Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script additionally exposed symbols: alice, bob, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh start all VMs alice: starting vm bob: starting vm bob # Disk image does not exist, creating the virtualisation disk image... bob: QEMU running (pid 48) bob # Formatting '/build/vm-state-bob/tmp.z5s3d6JSKI', fmt=raw size=1073741824 bob # mke2fs 1.47.4 (6-Mar-2025) bob # Discarding device blocks: 0/262144 done bob # Creating filesystem with 262144 4k blocks and 65536 inodes bob # Filesystem UUID: bf4151ed-ddcc-484b-a344-da467ef50d4f bob # Superblock backups stored on blocks: bob # 32768, 98304, 163840, 229376 bob # bob # Allocating group tables: 0/8 done bob # Writing inode tables: 0/8 done bob # Creating journal (8192 blocks): done bob # Writing superblocks and filesystem accounting information: 0/8 done bob # bob # Virtualisation disk image created. bob # Starting virtiofs daemons... bob # [2026-09-22T17:55:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-09-22T17:55:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-09-22T17:55:34Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-09-22T17:55:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-09-22T17:55:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-09-22T17:55:34Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-09-22T17:55:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-09-22T17:55:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-09-22T17:55:34Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-09-22T17:55:34Z INFO virtiofsd] Client connected, servicing requests bob # [2026-09-22T17:55:34Z INFO virtiofsd] Client connected, servicing requests bob # [2026-09-22T17:55:34Z INFO virtiofsd] Client connected, servicing requests bob # qemu-system-x86_64: Could not access KVM kernel module: No such file or directory bob # qemu-system-x86_64: failed to initialize kvm: No such file or directory bob # qemu-system-x86_64: falling back to tcg alice # Disk image does not exist, creating the virtualisation disk image... alice: QEMU running (pid 47) alice # Formatting '/build/vm-state-alice/tmp.yDxuKJqje0', fmt=raw size=1073741824 alice # mke2fs 1.47.4 (6-Mar-2025) alice # Discarding device blocks: 0/262144 done alice # Creating filesystem with 262144 4k blocks and 65536 inodes (finished: start all VMs, in 0.22 seconds) alice # Filesystem UUID: 97b8a4f6-291d-4747-878d-587587bb26a7 bob: waiting for success: curl bob:5000/nix-cache-info alice # Superblock backups stored on blocks: bob: waiting for the VM to finish booting alice # 32768, 98304, 163840, 229376 alice # alice # Allocating group tables: 0/8 done alice # Writing inode tables: 0/8 done alice # Creating journal (8192 blocks): done alice # Writing superblocks and filesystem accounting information: 0/8 done alice # alice # Virtualisation disk image created. alice # Starting virtiofs daemons... alice # [2026-09-22T17:55:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-09-22T17:55:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-09-22T17:55:34Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-09-22T17:55:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-09-22T17:55:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-09-22T17:55:34Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-09-22T17:55:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-09-22T17:55:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-09-22T17:55:34Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-09-22T17:55:34Z INFO virtiofsd] Client connected, servicing requests alice # [2026-09-22T17:55:34Z INFO virtiofsd] Client connected, servicing requests alice # [2026-09-22T17:55:34Z INFO virtiofsd] Client connected, servicing requests alice # qemu-system-x86_64: Could not access KVM kernel module: No such file or directory alice # qemu-system-x86_64: failed to initialize kvm: No such file or directory alice # qemu-system-x86_64: falling back to tcg bob # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) alice # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) bob # bob # bob # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC750+3EF2C750 CA00 alice # alice # bob # Press Ctrl-B to configure iPXE (PCI 00:02.0)... bob # bob # bob # bob # alice # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC750+3EF2C750 CA00 bob # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC750 3EF2C750 CB00 alice # Press Ctrl-B to configure iPXE (PCI 00:02.0)... alice # alice # alice # alice # alice # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC750 3EF2C750 CB00 bob # Press Ctrl-B to configure iPXE (PCI 00:05.0)... bob # bob # alice # Press Ctrl-B to configure iPXE (PCI 00:05.0)... alice # alice # bob # Booting from ROM... alice # Booting from ROM... bob # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Mon Sep 14 11:36:19 UTC 2026 bob # [ 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/p8hwydl4ilpb66vfs3pdc12192v1qv90-nixos-system-bob-test/init regInfo=/nix/.ro-store/j7hg3r4lmk2jfr15d55rq2jc2sjizg7d-closure-info/registration console=ttyS0,115200n8 console=tty0 bob # [ 0.000000] BIOS-provided physical RAM map: bob # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable bob # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved bob # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved bob # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable bob # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved bob # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved bob # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved bob # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved bob # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved bob # [ 0.000000] NX (Execute Disable) protection: active bob # [ 0.000000] APIC: Static calls initialized bob # [ 0.000000] SMBIOS 2.8 present. bob # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 bob # [ 0.000000] DMI: Memory slots populated: 1/1 bob # [ 0.000000] tsc: Fast TSC calibration using PIT bob # [ 0.000000] tsc: Detected 3593.156 MHz processor bob # [ 0.017492] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 bob # [ 0.018316] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs bob # [ 0.018522] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT bob # [ 0.044165] found SMP MP-table at [mem 0x000f5450-0x000f545f] bob # [ 0.046627] Using GB pages for direct mapping bob # [ 0.051210] RAMDISK: [mem 0x3e36d000-0x3ffcffff] bob # [ 0.051787] ACPI: Early table checksum verification disabled bob # [ 0.052344] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) bob # [ 0.052726] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.053580] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.054364] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.054488] ACPI: FACS 0x000000003FFE0000 000040 bob # [ 0.054558] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.054589] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.054618] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.054646] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.054764] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] bob # [ 0.054802] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] bob # [ 0.054812] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] bob # [ 0.054819] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] bob # [ 0.054826] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] bob # [ 0.054833] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] bob # [ 0.054840] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] bob # [ 0.060733] No NUMA configuration found bob # [ 0.060783] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] bob # [ 0.061571] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] bob # [ 0.066359] Zone ranges: bob # [ 0.066408] DMA [mem 0x0000000000001000-0x0000000000ffffff] bob # [ 0.066507] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] bob # [ 0.066520] Normal empty bob # [ 0.066538] Device empty bob # [ 0.066551] Movable zone start for each node bob # [ 0.066586] Early memory node ranges bob # [ 0.066619] node 0: [mem 0x0000000000001000-0x000000000009efff] bob # [ 0.066834] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] bob # [ 0.066991] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] bob # [ 0.068302] On node 0, zone DMA: 1 pages in unavailable ranges bob # [ 0.068726] On node 0, zone DMA: 97 pages in unavailable ranges bob # [ 0.084595] On node 0, zone DMA32: 40 pages in unavailable ranges bob # [ 0.085397] ACPI: PM-Timer IO Port: 0x608 bob # [ 0.086029] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) bob # [ 0.086576] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23 bob # [ 0.086740] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) bob # [ 0.087062] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) bob # [ 0.087123] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) bob # [ 0.087239] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) bob # [ 0.087251] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) bob # [ 0.087483] ACPI: Using ACPI (MADT) for SMP configuration information bob # [ 0.087541] ACPI: HPET id: 0x8086a201 base: 0xfed00000 bob # [ 0.088027] CPU topo: Max. logical packages: 1 bob # [ 0.088048] CPU topo: Max. logical dies: 1 bob # [ 0.088059] CPU topo: Max. dies per package: 1 bob # [ 0.088125] CPU topo: Max. threads per core: 1 bob # [ 0.088322] CPU topo: Num. cores per package: 1 bob # [ 0.088385] CPU topo: Num. threads per package: 1 bob # [ 0.088406] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs bob # [ 0.089479] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] bob # [ 0.089523] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] bob # [ 0.089649] [mem 0x40000000-0xafffffff] available for PCI devices bob # [ 0.089724] Booting paravirtualized kernel on bare hardware bob # [ 0.090380] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns bob # [ 0.106052] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 bob # [ 0.109815] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 bob # [ 0.111534] 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/p8hwydl4ilpb66vfs3pdc12192v1qv90-nixos-system-bob-test/init regInfo=/nix/.ro-store/j7hg3r4lmk2jfr15d55rq2jc2sjizg7d-closure-info/registration console=ttyS0,115200n8 console=tty0 bob # [ 0.113959] Unknown kernel command line parameters "regInfo=/nix/.ro-store/j7hg3r4lmk2jfr15d55rq2jc2sjizg7d-closure-info/registration", will be passed to user space. bob # [ 0.114817] random: crng init done bob # [ 0.114932] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes bob # [ 0.116104] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) bob # [ 0.116372] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) bob # [ 0.120423] Fallback order for Node 0: 0 bob # [ 0.120810] Built 1 zonelists, mobility grouping on. Total pages: 262006 bob # [ 0.120836] Policy zone: DMA32 bob # [ 0.123666] mem auto-init: stack:all(zero), heap alloc:on, heap free:off bob # [ 0.153005] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 bob # [ 0.157605] allocated 2097152 bytes of page_ext bob # [ 0.214410] ftrace: allocating 48787 entries in 192 pages bob # [ 0.214684] ftrace: allocated 192 pages with 2 groups bob # [ 0.227127] Dynamic Preempt: lazy bob # [ 0.232119] rcu: Preemptible hierarchical RCU implementation. bob # [ 0.232150] rcu: RCU event tracing is enabled. bob # [ 0.232177] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. bob # [ 0.232345] Trampoline variant of Tasks RCU enabled. bob # [ 0.232356] Rude variant of Tasks RCU enabled. bob # [ 0.232364] Tracing variant of Tasks RCU enabled. bob # [ 0.232492] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. bob # [ 0.232527] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 bob # [ 0.234231] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.234271] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.234291] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.292288] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 bob # [ 0.305460] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.306338] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns bob # [ 0.311166] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.318171] Console: colour VGA+ 80x25 bob # [ 0.318730] printk: legacy console [tty0] enabled bob # [ 0.329740] printk: legacy console [ttyS0] enabled bob # [ 0.349712] ACPI: Core revision 20250807 alice # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Mon Sep 14 11:36:19 UTC 2026 alice # [ 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/dm25ifzai99pkp763r7wv9b38z3i6w0n-nixos-system-alice-test/init regInfo=/nix/.ro-store/f14fnyp3hll108i4nmqldhzm1qlpc32a-closure-info/registration console=ttyS0,115200n8 console=tty0 alice # [ 0.000000] BIOS-provided physical RAM map: alice # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable alice # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved alice # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved alice # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable alice # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved alice # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved alice # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved alice # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved alice # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved alice # [ 0.000000] NX (Execute Disable) protection: active alice # [ 0.000000] APIC: Static calls initialized alice # [ 0.000000] SMBIOS 2.8 present. alice # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 alice # [ 0.000000] DMI: Memory slots populated: 1/1 alice # [ 0.000000] tsc: Fast TSC calibration using PIT alice # [ 0.000000] tsc: Detected 3593.258 MHz processor alice # [ 0.017672] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 alice # [ 0.018476] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs alice # [ 0.018683] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT alice # [ 0.044940] found SMP MP-table at [mem 0x000f5450-0x000f545f] alice # [ 0.047370] Using GB pages for direct mapping alice # [ 0.052080] RAMDISK: [mem 0x3e36a000-0x3ffcffff] alice # [ 0.052663] ACPI: Early table checksum verification disabled alice # [ 0.053203] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) alice # [ 0.053591] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.054436] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.055224] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.055323] ACPI: FACS 0x000000003FFE0000 000040 alice # [ 0.055390] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.055421] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.055449] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.055476] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.055613] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] alice # [ 0.055653] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] alice # [ 0.055663] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] bob # [ 0.358489] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns alice # [ 0.055671] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] alice # [ 0.055678] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] alice # [ 0.055685] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] alice # [ 0.055692] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] alice # [ 0.061584] No NUMA configuration found alice # [ 0.061639] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] alice # [ 0.062407] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] alice # [ 0.067266] Zone ranges: alice # [ 0.067316] DMA [mem 0x0000000000001000-0x0000000000ffffff] alice # [ 0.067417] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] alice # [ 0.067430] Normal empty alice # [ 0.067447] Device empty alice # [ 0.067461] Movable zone start for each node alice # [ 0.067495] Early memory node ranges alice # [ 0.067527] node 0: [mem 0x0000000000001000-0x000000000009efff] alice # [ 0.067744] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] alice # [ 0.067903] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] alice # [ 0.069188] On node 0, zone DMA: 1 pages in unavailable ranges alice # [ 0.069623] On node 0, zone DMA: 97 pages in unavailable ranges alice # [ 0.085582] On node 0, zone DMA32: 40 pages in unavailable ranges alice # [ 0.086386] ACPI: PM-Timer IO Port: 0x608 alice # [ 0.087007] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) alice # [ 0.087528] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23 alice # [ 0.087700] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) alice # [ 0.088023] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) alice # [ 0.088085] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) alice # [ 0.088209] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) alice # [ 0.088224] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) alice # [ 0.088476] ACPI: Using ACPI (MADT) for SMP configuration information alice # [ 0.088534] ACPI: HPET id: 0x8086a201 base: 0xfed00000 alice # [ 0.089065] CPU topo: Max. logical packages: 1 alice # [ 0.089083] CPU topo: Max. logical dies: 1 alice # [ 0.089094] CPU topo: Max. dies per package: 1 alice # [ 0.089161] CPU topo: Max. threads per core: 1 alice # [ 0.089345] CPU topo: Num. cores per package: 1 alice # [ 0.089365] CPU topo: Num. threads per package: 1 alice # [ 0.089375] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs alice # [ 0.090407] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] alice # [ 0.090442] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] alice # [ 0.090577] [mem 0x40000000-0xafffffff] available for PCI devices alice # [ 0.090643] Booting paravirtualized kernel on bare hardware alice # [ 0.091271] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns alice # [ 0.107064] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 alice # [ 0.110841] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 alice # [ 0.112568] 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/dm25ifzai99pkp763r7wv9b38z3i6w0n-nixos-system-alice-test/init regInfo=/nix/.ro-store/f14fnyp3hll108i4nmqldhzm1qlpc32a-closure-info/registration console=ttyS0,115200n8 console=tty0 alice # [ 0.114933] Unknown kernel command line parameters "regInfo=/nix/.ro-store/f14fnyp3hll108i4nmqldhzm1qlpc32a-closure-info/registration", will be passed to user space. alice # [ 0.115747] random: crng init done alice # [ 0.115849] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes alice # [ 0.116994] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) alice # [ 0.117206] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.121284] Fallback order for Node 0: 0 alice # [ 0.121678] Built 1 zonelists, mobility grouping on. Total pages: 262006 alice # [ 0.121702] Policy zone: DMA32 alice # [ 0.124519] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.153944] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.158440] allocated 2097152 bytes of page_ext bob # [ 0.366322] APIC: Switch to symmetric I/O mode setup alice # [ 0.215451] ftrace: allocating 48787 entries in 192 pages alice # [ 0.215733] ftrace: allocated 192 pages with 2 groups alice # [ 0.228144] Dynamic Preempt: lazy alice # [ 0.233232] rcu: Preemptible hierarchical RCU implementation. alice # [ 0.233259] rcu: RCU event tracing is enabled. alice # [ 0.233286] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. alice # [ 0.233440] Trampoline variant of Tasks RCU enabled. alice # [ 0.233450] Rude variant of Tasks RCU enabled. alice # [ 0.233458] Tracing variant of Tasks RCU enabled. alice # [ 0.233596] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. alice # [ 0.233634] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 bob # [ 0.367658] x2apic: IRQ remapping doesn't support X2APIC mode alice # [ 0.235356] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.235401] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.235414] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.293931] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 alice # [ 0.307163] rcu: srcu_init: Setting srcu_struct sizes based on contention. alice # [ 0.308049] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns alice # [ 0.312701] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.319871] Console: colour VGA+ 80x25 alice # [ 0.320452] printk: legacy console [tty0] enabled alice # [ 0.331643] printk: legacy console [ttyS0] enabled alice # [ 0.350264] ACPI: Core revision 20250807 bob # [ 0.374269] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 alice # [ 0.358672] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns bob # [ 0.380776] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x33cb127363e, max_idle_ns: 440795305332 ns bob # [ 0.381983] Calibrating delay loop (skipped), value calculated using timer frequency.. 7186.31 BogoMIPS (lpj=3593156) alice # [ 0.364974] APIC: Switch to symmetric I/O mode setup alice # [ 0.366631] x2apic: IRQ remapping doesn't support X2APIC mode alice # [ 0.372755] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 bob # [ 0.385571] x86/cpu: User Mode Instruction Prevention (UMIP) activated alice # [ 0.378707] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x33cb721243a, max_idle_ns: 440795205948 ns alice # [ 0.380132] Calibrating delay loop (skipped), value calculated using timer frequency.. 7186.51 BogoMIPS (lpj=3593258) bob # [ 0.401015] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 bob # [ 0.401456] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 bob # [ 0.404051] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto bob # [ 0.405548] Speculative Store Bypass: Vulnerable bob # [ 0.406078] Spectre V2 : Mitigation: Retpolines bob # [ 0.406762] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization bob # [ 0.407267] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT alice # [ 0.386704] x86/cpu: User Mode Instruction Prevention (UMIP) activated alice # [ 0.401001] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 alice # [ 0.401382] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 alice # [ 0.403894] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto alice # [ 0.405524] Speculative Store Bypass: Vulnerable alice # [ 0.406045] Spectre V2 : Mitigation: Retpolines bob # [ 0.414460] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' alice # [ 0.406611] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization alice # [ 0.407067] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT bob # [ 0.419085] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' bob # [ 0.419710] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' bob # [ 0.419948] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' bob # [ 0.420179] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' bob # [ 0.420716] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' bob # [ 0.421209] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 bob # [ 0.421756] x86/fpu: xstate_offset[3]: 960, xstate_sizes[3]: 64 bob # [ 0.421983] x86/fpu: xstate_offset[4]: 1024, xstate_sizes[4]: 64 bob # [ 0.422838] x86/fpu: xstate_offset[9]: 2688, xstate_sizes[9]: 8 bob # [ 0.423117] x86/fpu: Enabled xstate features 0x21f, context size is 2696 bytes, using 'standard' format. alice # [ 0.414227] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' alice # [ 0.418704] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' alice # [ 0.419347] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' alice # [ 0.419596] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' alice # [ 0.419822] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' alice # [ 0.420107] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' alice # [ 0.420767] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 alice # [ 0.421089] x86/fpu: xstate_offset[3]: 960, xstate_sizes[3]: 64 alice # [ 0.421357] x86/fpu: xstate_offset[4]: 1024, xstate_sizes[4]: 64 alice # [ 0.422121] x86/fpu: xstate_offset[9]: 2688, xstate_sizes[9]: 8 alice # [ 0.422606] x86/fpu: Enabled xstate features 0x21f, context size is 2696 bytes, using 'standard' format. bob # [ 0.904231] Freeing SMP alternatives memory: 44K bob # [ 0.906022] pid_max: default: 32768 minimum: 301 alice # [ 0.899873] Freeing SMP alternatives memory: 44K alice # [ 0.901617] pid_max: default: 32768 minimum: 301 bob # [ 0.918530] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.922203] landlock: Up and running. bob # [ 0.922422] Yama: becoming mindful. bob # [ 0.924009] LSM support for eBPF active bob # [ 0.930154] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.930737] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.914018] LSM: initializing lsm=capability,landlock,yama,bpf,ima alice # [ 0.917396] landlock: Up and running. alice # [ 0.917647] Yama: becoming mindful. alice # [ 0.919081] LSM support for eBPF active alice # [ 0.924578] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.925033] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 1.081647] smpboot: CPU0: AMD QEMU TCG CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3) alice # [ 1.078311] smpboot: CPU0: AMD QEMU TCG CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3) bob # [ 1.103353] Performance Events: PMU not available due to virtualization, using software events only. bob # [ 1.105401] signal: max sigframe size: 3632 bob # [ 1.108513] rcu: Hierarchical SRCU implementation. bob # [ 1.109179] rcu: Max phase no-delay instances is 400. alice # [ 1.097159] Performance Events: PMU not available due to virtualization, using software events only. alice # [ 1.099190] signal: max sigframe size: 3632 alice # [ 1.102004] rcu: Hierarchical SRCU implementation. alice # [ 1.102272] rcu: Max phase no-delay instances is 400. bob # [ 1.122315] NMI watchdog: Perf NMI watchdog permanently disabled alice # [ 1.112976] NMI watchdog: Perf NMI watchdog permanently disabled bob # [ 1.129836] smp: Bringing up secondary CPUs ... bob # [ 1.133326] smp: Brought up 1 node, 1 CPU bob # [ 1.133946] smpboot: Total of 1 processors activated (7186.31 BogoMIPS) alice # [ 1.118662] smp: Bringing up secondary CPUs ... alice # [ 1.121053] smp: Brought up 1 node, 1 CPU alice # [ 1.121487] smpboot: Total of 1 processors activated (7186.51 BogoMIPS) alice # [ 1.132817] Memory: 941032K/1048024K available (17247K kernel code, 2727K rwdata, 13616K rodata, 3652K init, 2976K bss, 99604K reserved, 0K cma-reserved) bob # [ 1.150438] Memory: 941052K/1048024K available (17247K kernel code, 2727K rwdata, 13616K rodata, 3652K init, 2976K bss, 99592K reserved, 0K cma-reserved) alice # [ 1.143527] devtmpfs: initialized bob # [ 1.164527] devtmpfs: initialized alice # [ 1.150073] x86/mm: Memory block size: 128MB bob # [ 1.171906] x86/mm: Memory block size: 128MB alice # [ 1.166349] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 1.167139] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). alice # [ 1.172012] pinctrl core: initialized pinctrl subsystem bob # [ 1.190817] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 1.191805] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). bob # [ 1.197058] pinctrl core: initialized pinctrl subsystem alice # [ 1.182686] PM: RTC time: 17:55:36, date: 2026-09-22 bob # [ 1.208554] PM: RTC time: 17:55:36, date: 2026-09-22 alice # [ 1.207831] NET: Registered PF_NETLINK/PF_ROUTE protocol family alice # [ 1.214112] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations alice # [ 1.215703] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations alice # [ 1.216562] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations alice # [ 1.217117] audit: initializing netlink subsys (disabled) bob # [ 1.234462] NET: Registered PF_NETLINK/PF_ROUTE protocol family alice # [ 1.220734] audit: type=2000 audit(1790099736.855:1): state=initialized audit_enabled=0 res=1 bob # [ 1.241248] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations bob # [ 1.242835] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 1.243793] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations bob # [ 1.244401] audit: initializing netlink subsys (disabled) alice # [ 1.227927] thermal_sys: Registered thermal governor 'fair_share' alice # [ 1.228008] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 1.228253] thermal_sys: Registered thermal governor 'step_wise' alice # [ 1.228446] thermal_sys: Registered thermal governor 'user_space' alice # [ 1.228618] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 1.229552] cpuidle: using governor menu bob # [ 1.248207] audit: type=2000 audit(1790099735.882:1): state=initialized audit_enabled=0 res=1 alice # [ 1.232662] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 alice # [ 1.237844] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] alice # [ 1.238981] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry bob # [ 1.256714] thermal_sys: Registered thermal governor 'fair_share' bob # [ 1.256787] thermal_sys: Registered thermal governor 'bang_bang' bob # [ 1.257035] thermal_sys: Registered thermal governor 'step_wise' bob # [ 1.257206] thermal_sys: Registered thermal governor 'user_space' alice # [ 1.241210] PCI: Using configuration type 1 for base access bob # [ 1.257375] thermal_sys: Registered thermal governor 'power_allocator' bob # [ 1.259000] cpuidle: using governor menu bob # [ 1.262268] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 alice # [ 1.245717] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. bob # [ 1.268172] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] bob # [ 1.269485] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry bob # [ 1.272062] PCI: Using configuration type 1 for base access bob # [ 1.277063] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. alice # [ 1.269486] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages alice # [ 1.269790] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page alice # [ 1.269999] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages alice # [ 1.270178] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 1.298058] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 1.298361] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 1.298579] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 1.298823] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 1.333191] ACPI: Added _OSI(Module Device) alice # [ 1.337374] ACPI: Added _OSI(Processor Device) alice # [ 1.337573] ACPI: Added _OSI(Processor Aggregator Device) bob # [ 1.376542] ACPI: Added _OSI(Module Device) bob # [ 1.380968] ACPI: Added _OSI(Processor Device) bob # [ 1.381161] ACPI: Added _OSI(Processor Aggregator Device) alice # [ 1.446617] ACPI: 1 ACPI AML tables successfully acquired and loaded bob # [ 1.498579] ACPI: 1 ACPI AML tables successfully acquired and loaded alice # [ 1.503034] ACPI: Interpreter enabled alice # [ 1.506812] ACPI: PM: (supports S0 S3 S4 S5) alice # [ 1.507081] ACPI: Using IOAPIC for interrupt routing alice # [ 1.517468] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug alice # [ 1.517889] PCI: Using E820 reservations for host bridge windows alice # [ 1.522965] ACPI: Enabled 2 GPEs in block 00 to 3F bob # [ 1.555419] ACPI: Interpreter enabled bob # [ 1.559469] ACPI: PM: (supports S0 S3 S4 S5) bob # [ 1.559763] ACPI: Using IOAPIC for interrupt routing bob # [ 1.570784] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug bob # [ 1.571171] PCI: Using E820 reservations for host bridge windows bob # [ 1.578259] ACPI: Enabled 2 GPEs in block 00 to 3F alice # [ 1.617576] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) alice # [ 1.618680] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] alice # [ 1.621785] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] alice # [ 1.624082] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] alice # [ 1.632151] PCI host bridge to bus 0000:00 alice # [ 1.632892] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] alice # [ 1.633395] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] alice # [ 1.633604] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] alice # [ 1.633823] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] alice # [ 1.634046] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] alice # [ 1.634360] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] alice # [ 1.634912] pci_bus 0000:00: root bus resource [bus 00-ff] alice # [ 1.640079] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint alice # [ 1.646971] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint alice # [ 1.649595] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] alice # [ 1.650380] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] alice # [ 1.650590] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] alice # [ 1.651493] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] alice # [ 1.653458] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 1.669478] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) bob # [ 1.670942] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] alice # [ 1.655461] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] alice # [ 1.656533] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] alice # [ 1.656756] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] bob # [ 1.673942] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] alice # [ 1.657014] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] alice # [ 1.659041] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 1.676180] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] alice # [ 1.661395] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] alice # [ 1.661686] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] alice # [ 1.661906] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] alice # [ 1.663646] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 1.665401] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] alice # [ 1.665697] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] alice # [ 1.665884] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] bob # [ 1.684990] PCI host bridge to bus 0000:00 bob # [ 1.685795] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] bob # [ 1.686172] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] bob # [ 1.686390] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] bob # [ 1.686736] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] bob # [ 1.687007] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] bob # [ 1.687246] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] alice # [ 1.670090] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 1.688027] pci_bus 0000:00: root bus resource [bus 00-ff] alice # [ 1.673411] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] alice # [ 1.673727] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] alice # [ 1.673906] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] alice # [ 1.674342] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] alice # [ 1.675887] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint bob # [ 1.693474] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint alice # [ 1.678374] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] alice # [ 1.678714] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] alice # [ 1.680603] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 1.683432] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] alice # [ 1.683782] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] bob # [ 1.701298] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint alice # [ 1.685756] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 1.703980] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] bob # [ 1.704969] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] bob # [ 1.705185] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] alice # [ 1.687441] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] alice # [ 1.688348] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] bob # [ 1.705949] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] alice # [ 1.690718] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 1.708454] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 1.693444] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] alice # [ 1.693748] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] bob # [ 1.711837] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] bob # [ 1.712752] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] alice # [ 1.695954] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint bob # [ 1.713702] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] bob # [ 1.713995] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] bob # [ 1.715917] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 1.698398] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] alice # [ 1.698712] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] alice # [ 1.698900] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] alice # [ 1.700579] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 1.717725] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] bob # [ 1.718922] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] bob # [ 1.719122] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] bob # [ 1.720903] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 1.702405] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] alice # [ 1.703563] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] alice # [ 1.704544] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] bob # [ 1.724733] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] bob # [ 1.725041] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] bob # [ 1.725236] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] alice # [ 1.708810] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint bob # [ 1.727174] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 1.710809] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] bob # [ 1.728738] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] bob # [ 1.729042] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] bob # [ 1.729243] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] alice # [ 1.712627] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint bob # [ 1.729706] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] alice # [ 1.713873] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] bob # [ 1.731311] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 1.715845] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint alice # [ 1.717372] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] bob # [ 1.732797] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] bob # [ 1.734895] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] alice # [ 1.719351] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 1.720722] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] bob # [ 1.738313] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 1.722866] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint alice # [ 1.723892] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO bob # [ 1.741726] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] bob # [ 1.742040] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] alice # [ 1.725728] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint bob # [ 1.743953] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 1.727421] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] alice # [ 1.727715] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] alice # [ 1.729485] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint bob # [ 1.746723] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] bob # [ 1.747045] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] alice # [ 1.731364] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] bob # [ 1.748771] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 1.751275] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] bob # [ 1.751567] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] bob # [ 1.753251] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint bob # [ 1.755747] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] bob # [ 1.756030] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] bob # [ 1.756809] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] alice # [ 1.741227] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 bob # [ 1.758457] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 1.743774] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 alice # [ 1.744906] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 bob # [ 1.762741] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] bob # [ 1.763031] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] alice # [ 1.746238] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 bob # [ 1.763222] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] alice # [ 1.747583] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 bob # [ 1.764900] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint alice # [ 1.748820] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 bob # [ 1.766083] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] alice # [ 1.750071] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 alice # [ 1.751127] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 bob # [ 1.768129] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint alice # [ 1.751788] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 alice # [ 1.752158] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 bob # [ 1.769119] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] alice # [ 1.752594] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 alice # [ 1.753628] ACPI: PCI: Interrupt link GSID configured for IRQ 19 alice # [ 1.753966] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 alice # [ 1.754463] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 alice # [ 1.754773] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 alice # [ 1.755073] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 bob # [ 1.771872] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint bob # [ 1.775175] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] bob # [ 1.777274] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 1.761782] iommu: Default domain type: Translated alice # [ 1.762079] iommu: DMA domain TLB invalidation policy: lazy mode bob # [ 1.778786] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] bob # [ 1.780758] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint alice # [ 1.764213] ACPI: bus type USB registered bob # [ 1.781709] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO alice # [ 1.765428] usbcore: registered new interface driver usbfs alice # [ 1.766151] usbcore: registered new interface driver hub alice # [ 1.766561] usbcore: registered new device driver usb bob # [ 1.783396] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint bob # [ 1.785741] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] bob # [ 1.786027] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] bob # [ 1.787809] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint bob # [ 1.789162] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] alice # [ 1.782966] NetLabel: Initializing alice # [ 1.783184] NetLabel: domain hash size = 128 alice # [ 1.783363] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO alice # [ 1.784876] NetLabel: unlabeled traffic allowed by default alice # [ 1.785263] PCI: Using ACPI for IRQ routing bob # [ 1.800918] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 bob # [ 1.802783] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 bob # [ 1.804145] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 bob # [ 1.805878] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 bob # [ 1.807926] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 bob # [ 1.810491] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 alice # [ 1.796024] pci 0000:00:01.0: vgaarb: setting as boot VGA device alice # [ 1.796311] pci 0000:00:01.0: vgaarb: bridge control possible bob # [ 1.812021] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 alice # [ 1.796311] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none alice # [ 1.796423] vgaarb: loaded bob # [ 1.813502] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 alice # [ 1.798166] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 alice # [ 1.798543] hpet0: 3 comparators, 64-bit 100.000000 MHz counter bob # [ 1.814184] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 bob # [ 1.814879] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 bob # [ 1.815261] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 bob # [ 1.816033] ACPI: PCI: Interrupt link GSID configured for IRQ 19 bob # [ 1.817155] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 bob # [ 1.817546] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 bob # [ 1.817995] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 bob # [ 1.818356] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 alice # [ 1.804311] clocksource: Switched to clocksource tsc-early bob # [ 1.825189] iommu: Default domain type: Translated bob # [ 1.825447] iommu: DMA domain TLB invalidation policy: lazy mode bob # [ 1.827808] ACPI: bus type USB registered bob # [ 1.828998] usbcore: registered new interface driver usbfs bob # [ 1.829889] usbcore: registered new interface driver hub bob # [ 1.830351] usbcore: registered new device driver usb alice # [ 1.820704] VFS: Disk quotas dquot_6.6.0 alice # [ 1.821300] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 1.828168] pnp: PnP ACPI init bob # [ 1.847919] NetLabel: Initializing bob # [ 1.848136] NetLabel: domain hash size = 128 bob # [ 1.848279] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO alice # [ 1.832806] ACPI: IRQ 4 override to edge(!), high(!) bob # [ 1.849881] NetLabel: unlabeled traffic allowed by default bob # [ 1.850300] PCI: Using ACPI for IRQ routing alice # [ 1.835052] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved alice # [ 1.839176] pnp: PnP ACPI: found 5 devices bob # [ 1.861231] pci 0000:00:01.0: vgaarb: setting as boot VGA device bob # [ 1.861637] pci 0000:00:01.0: vgaarb: bridge control possible bob # [ 1.861647] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none bob # [ 1.861775] vgaarb: loaded bob # [ 1.863725] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 bob # [ 1.864040] hpet0: 3 comparators, 64-bit 100.000000 MHz counter bob # [ 1.869369] clocksource: Switched to clocksource tsc-early bob # [ 1.890782] VFS: Disk quotas dquot_6.6.0 bob # [ 1.891664] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 1.896357] pnp: PnP ACPI init alice # [ 1.883818] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns bob # [ 1.900935] ACPI: IRQ 4 override to edge(!), high(!) alice # [ 1.884645] clocksource: Switched to clocksource acpi_pm alice # [ 1.886344] NET: Registered PF_INET protocol family bob # [ 1.903435] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved alice # [ 1.888902] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) bob # [ 1.907678] pnp: PnP ACPI: found 5 devices bob # [ 1.947722] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns bob # [ 1.948494] clocksource: Switched to clocksource acpi_pm bob # [ 1.950282] NET: Registered PF_INET protocol family bob # [ 1.952694] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 1.989998] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 1.991081] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) alice # [ 1.991677] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) alice # [ 1.992756] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 1.993222] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 1.995756] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 1.996635] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 1.997259] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 2.000357] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 2.001343] NET: Registered PF_XDP protocol family alice # [ 2.002919] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] alice # [ 2.003191] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] alice # [ 2.003373] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] alice # [ 2.003687] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] alice # [ 2.003876] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] alice # [ 2.004066] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] alice # [ 2.011356] ACPI: \_SB_.GSIA: Enabled at IRQ 16 alice # [ 2.021429] pci 0000:00:1d.0: quirk_usb_early_handoff+0x0/0x7c0 took 15968 usecs alice # [ 2.025935] ACPI: \_SB_.GSIB: Enabled at IRQ 17 alice # [ 2.033652] ACPI: \_SB_.GSIC: Enabled at IRQ 18 bob # [ 2.050319] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 2.051421] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 2.052085] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 2.053345] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) bob # [ 2.053959] TCP: Hash tables configured (established 8192 bind 8192) bob # [ 2.057158] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 2.041866] ACPI: \_SB_.GSID: Enabled at IRQ 19 bob # [ 2.058217] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 2.059132] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 2.061771] NET: Registered PF_UNIX/PF_LOCAL protocol family bob # [ 2.062903] NET: Registered PF_XDP protocol family bob # [ 2.064431] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] bob # [ 2.064822] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] alice # [ 2.048412] PCI: CLS 0 bytes, default 64 bob # [ 2.065025] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] bob # [ 2.065221] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] bob # [ 2.065481] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] bob # [ 2.065863] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] bob # [ 2.073441] ACPI: \_SB_.GSIA: Enabled at IRQ 16 alice # [ 2.056427] Trying to unpack rootfs image as initramfs... bob # [ 2.083488] pci 0000:00:1d.0: quirk_usb_early_handoff+0x0/0x7c0 took 16099 usecs bob # [ 2.087417] ACPI: \_SB_.GSIB: Enabled at IRQ 17 bob # [ 2.095247] ACPI: \_SB_.GSIC: Enabled at IRQ 18 bob # [ 2.101954] ACPI: \_SB_.GSID: Enabled at IRQ 19 bob # [ 2.107827] PCI: CLS 0 bytes, default 64 bob # [ 2.119219] Trying to unpack rootfs image as initramfs... alice # [ 2.343093] Initialise system trusted keyrings alice # [ 2.347952] workingset: timestamp_bits=40 max_order=18 bucket_order=0 bob # [ 2.409210] Initialise system trusted keyrings bob # [ 2.413997] workingset: timestamp_bits=40 max_order=18 bucket_order=0 alice # [ 2.531192] Key type asymmetric registered alice # [ 2.531793] Asymmetric key parser 'x509' registered alice # [ 2.535092] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) alice # [ 2.540270] io scheduler mq-deadline registered alice # [ 2.540701] io scheduler kyber registered alice # [ 2.565083] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled alice # [ 2.580145] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A bob # [ 2.603093] Key type asymmetric registered bob # [ 2.603719] Asymmetric key parser 'x509' registered bob # [ 2.606969] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) bob # [ 2.609923] io scheduler mq-deadline registered bob # [ 2.610370] io scheduler kyber registered bob # [ 2.631197] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled alice # [ 2.615933] Linux agpgart interface v0.103 alice # [ 2.617008] ACPI: bus type drm_connector registered bob # [ 2.649062] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A alice # [ 2.644065] usbcore: registered new interface driver usbserial_generic alice # [ 2.644995] usbserial: USB Serial support registered for generic alice # [ 2.645408] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled alice # [ 2.652436] drop_monitor: Initializing network drop monitor service alice # [ 2.656396] NET: Registered PF_INET6 protocol family bob # [ 2.681771] Linux agpgart interface v0.103 bob # [ 2.685072] ACPI: bus type drm_connector registered alice # [ 2.686973] Segment Routing with IPv6 bob # [ 2.703386] usbcore: registered new interface driver usbserial_generic alice # [ 2.689608] In-situ OAM (IOAM) with IPv6 bob # [ 2.706717] usbserial: USB Serial support registered for generic bob # [ 2.707240] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled alice # [ 2.697217] IPI shorthand broadcast: enabled bob # [ 2.712494] drop_monitor: Initializing network drop monitor service bob # [ 2.718805] NET: Registered PF_INET6 protocol family bob # [ 2.748385] Segment Routing with IPv6 bob # [ 2.749211] In-situ OAM (IOAM) with IPv6 bob # [ 2.761417] IPI shorthand broadcast: enabled alice # [ 2.770823] sched_clock: Marking stable (2714041786, 56428349)->(2787103314, -16633179) alice # [ 2.777161] registered taskstats version 1 alice # [ 2.786907] Loading compiled-in X.509 certificates bob # [ 2.838783] sched_clock: Marking stable (2780094128, 58482912)->(2859648096, -21071056) bob # [ 2.847877] registered taskstats version 1 bob # [ 2.855826] Loading compiled-in X.509 certificates alice # [ 2.915862] Demotion targets for Node 0: null alice # [ 2.922866] Key type .fscrypt registered alice # [ 2.923104] Key type fscrypt-provisioning registered alice # [ 2.932161] ima: No TPM chip found, activating TPM-bypass! alice # [ 2.932941] ima: Allocated hash algorithm: sha1 alice # [ 2.940197] ima: No architecture policies found alice # [ 2.951815] PM: Magic number: 10:367:947 alice # [ 2.952301] tty tty4: hash matches alice # [ 2.952734] memory memory0: hash matches alice # [ 2.969087] RAS: Correctable Errors collector initialized. bob # [ 2.986838] Demotion targets for Node 0: null bob # [ 2.989176] Key type .fscrypt registered bob # [ 2.989423] Key type fscrypt-provisioning registered bob # [ 3.003159] ima: No TPM chip found, activating TPM-bypass! bob # [ 3.003529] ima: Allocated hash algorithm: sha1 bob # [ 3.011135] ima: No architecture policies found bob # [ 3.024348] PM: Magic number: 10:367:947 bob # [ 3.024945] tty tty4: hash matches bob # [ 3.025181] memory memory0: hash matches bob # [ 3.041963] RAS: Correctable Errors collector initialized. alice # [ 3.045303] clk: Disabling unused clocks alice # [ 3.045976] PM: genpd: Disabling unused power domains alice # [ 3.084771] tsc: Refined TSC clocksource calibration: 3593.237 MHz alice # [ 3.085423] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x33cb5f13e99, max_idle_ns: 440795391341 ns bob # [ 3.120462] clk: Disabling unused clocks bob # [ 3.123208] PM: genpd: Disabling unused power domains bob # [ 3.150917] tsc: Refined TSC clocksource calibration: 3593.235 MHz bob # [ 3.151716] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x33cb5c753f5, max_idle_ns: 440795263286 ns alice # [ 5.060214] Freeing initrd memory: 29080K bob # [ 5.188574] Freeing initrd memory: 29068K alice # [ 5.188664] Freeing unused decrypted memory: 2028K alice # [ 5.267467] Freeing unused kernel image (initmem) memory: 3652K alice # [ 5.268418] Write protecting the kernel read-only data: 32768k alice # [ 5.273917] Freeing unused kernel image (text/rodata gap) memory: 1184K alice # [ 5.275721] Freeing unused kernel image (rodata/data gap) memory: 720K bob # [ 5.302361] Freeing unused decrypted memory: 2028K bob # [ 5.379513] Freeing unused kernel image (initmem) memory: 3652K bob # [ 5.380410] Write protecting the kernel read-only data: 32768k bob # [ 5.385005] Freeing unused kernel image (text/rodata gap) memory: 1184K bob # [ 5.386745] Freeing unused kernel image (rodata/data gap) memory: 720K alice # [ 5.487329] x86/mm: Checked W+X mappings: passed, no W+X pages found. alice # [ 5.488239] Run /init as init process bob # [ 5.607627] x86/mm: Checked W+X mappings: passed, no W+X pages found. bob # [ 5.608592] Run /init as init process alice # [ 5.811096] systemd[1]: Inserted module 'autofs4' bob # [ 5.887862] systemd[1]: Inserted module 'autofs4' alice # [ 5.987809] fuse: init (API version 7.45) bob # [ 6.048016] fuse: init (API version 7.45) alice # [ 6.052596] ACPI: \_SB_.GSIG: Enabled at IRQ 22 alice # [ 6.060088] ACPI: \_SB_.GSIH: Enabled at IRQ 23 alice # [ 6.077829] ACPI: \_SB_.GSIE: Enabled at IRQ 20 alice # [ 6.082959] ACPI: \_SB_.GSIF: Enabled at IRQ 21 bob # [ 6.106503] ACPI: \_SB_.GSIG: Enabled at IRQ 22 bob # [ 6.113952] ACPI: \_SB_.GSIH: Enabled at IRQ 23 alice # [ 6.097870] virtiofs virtio5: discovered new tag: nix-store alice # [ 6.106715] virtiofs virtio5: virtio_fs_setup_dax: No cache capability bob # [ 6.131070] ACPI: \_SB_.GSIE: Enabled at IRQ 20 alice # [ 6.117926] virtiofs virtio6: discovered new tag: shared bob # [ 6.135398] ACPI: \_SB_.GSIF: Enabled at IRQ 21 alice # [ 6.120232] virtiofs virtio6: virtio_fs_setup_dax: No cache capability alice # [ 6.127645] virtiofs virtio7: discovered new tag: xchg bob # [ 6.146683] virtiofs virtio5: discovered new tag: nix-store alice # [ 6.130925] virtiofs virtio7: virtio_fs_setup_dax: No cache capability bob # [ 6.153948] virtiofs virtio5: virtio_fs_setup_dax: No cache capability bob # [ 6.162892] virtiofs virtio6: discovered new tag: shared bob # [ 6.164731] virtiofs virtio6: virtio_fs_setup_dax: No cache capability bob # [ 6.170700] virtiofs virtio7: discovered new tag: xchg bob # [ 6.172504] virtiofs virtio7: virtio_fs_setup_dax: No cache capability alice # [ 6.268830] systemd[1]: Successfully made /usr/ read-only. bob # [ 6.285685] systemd[1]: Successfully made /usr/ read-only. bob # [ 6.644055] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 6.645190] systemd[1]: Detected virtualization qemu. bob # [ 6.646068] systemd[1]: Detected architecture x86-64. bob # [ 6.646351] systemd[1]: Running in initrd. alice # [ 6.629743] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) alice # [ 6.631266] systemd[1]: Detected virtualization qemu. alice # [ 6.632306] systemd[1]: Detected architecture x86-64. alice # [ 6.632812] systemd[1]: Running in initrd. bob # [ 6.658874] systemd[1]: Initializing machine ID from random generator. alice # [ 6.647873] systemd[1]: Initializing machine ID from random generator. bob # [ 6.664389] systemd[1]: Hostname set to . alice # [ 6.654356] systemd[1]: Hostname set to . bob # [ 7.775046] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 7.998926] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 8.892051] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 8.966186] systemd[1]: Created slice Slice /system/modprobe. bob # [ 8.973854] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 8.975896] systemd[1]: Expecting device /dev/disk/by-label/nixos... bob # [ 8.977191] systemd[1]: Reached target Path Units. bob # [ 8.978230] systemd[1]: Reached target Slice Units. bob # [ 8.979253] systemd[1]: Reached target Swaps. bob # [ 8.980216] systemd[1]: Reached target Timer Units. bob # [ 8.987135] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 8.991046] systemd[1]: Listening on Journal Socket (/dev/log). bob # [ 8.993492] systemd[1]: Listening on Journal Sockets. bob # [ 8.996412] systemd[1]: Listening on udev Control Socket. bob # [ 8.998778] systemd[1]: Listening on udev Kernel Socket. bob # [ 8.999765] systemd[1]: Reached target Socket Units. bob # [ 9.041383] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 9.090707] systemd[1]: Starting Load Kernel Module configfs... bob # [ 9.239027] systemd[1]: Starting Journal Service... alice # [ 9.284116] systemd[1]: Queued start job for default target Initrd Default Target. alice # [ 9.353770] systemd[1]: Created slice Slice /system/modprobe. alice # [ 9.361094] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 9.363109] systemd[1]: Expecting device /dev/disk/by-label/nixos... alice # [ 9.364364] systemd[1]: Reached target Path Units. alice # [ 9.365284] systemd[1]: Reached target Slice Units. alice # [ 9.366065] systemd[1]: Reached target Swaps. alice # [ 9.367009] systemd[1]: Reached target Timer Units. alice # [ 9.374247] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 9.391178] systemd[1]: Starting Load Kernel Modules... alice # [ 9.378378] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 9.381187] systemd[1]: Listening on Journal Sockets. alice # [ 9.383976] systemd[1]: Listening on udev Control Socket. alice # [ 9.386213] systemd[1]: Listening on udev Kernel Socket. alice # [ 9.387061] systemd[1]: Reached target Socket Units. bob # [ 9.429157] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 9.427046] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 9.476210] systemd[1]: Starting Load Kernel Module configfs... bob # [ 9.603675] systemd[1]: Starting Coldplug All udev Devices... alice # [ 9.619911] systemd[1]: Starting Journal Service... alice # [ 9.785414] systemd[1]: Starting Load Kernel Modules... bob # [ 9.815000] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 9.819298] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 9.999295] systemd[1]: modprobe@configfs.service: Deactivated successfully. alice # [ 10.006428] systemd[1]: Starting Coldplug All udev Devices... bob # [ 10.057206] systemd[1]: Finished Load Kernel Module configfs. bob # [ 10.131162] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config bob # [ 10.215736] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 10.215073] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 10.394685] systemd[1]: modprobe@configfs.service: Deactivated successfully. alice # [ 10.454955] systemd[1]: Finished Load Kernel Module configfs. bob # [ 10.492395] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. bob # [ 10.509088] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev alice # [ 10.533423] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config alice # [ 10.647029] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 10.870992] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. bob # [ 10.902112] systemd[1]: Finished Load Kernel Modules. alice # [ 10.888915] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev bob # [ 10.987997] systemd[1]: Starting Apply Kernel Variables... bob # [ 11.153009] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 11.277452] systemd-journald[66]: Collecting audit messages is disabled. alice # [ 11.269215] systemd[1]: Finished Load Kernel Modules. bob # [ 11.306909] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 11.341331] systemd[1]: Starting Apply Kernel Variables... alice # [ 11.567370] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 11.713250] systemd-journald[66]: Collecting audit messages is disabled. alice # [ 11.744133] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 12.018227] systemd[1]: Finished Apply Kernel Variables. bob # [ 12.254156] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 12.284385] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 12.287478] systemd[1]: Reached target Local File Systems. alice # [ 12.310842] systemd[1]: Finished Apply Kernel Variables. bob # [ 12.397392] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 12.619183] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 12.645128] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 12.647462] systemd[1]: Reached target Local File Systems. alice # [ 12.735015] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 16.455969] systemd[1]: Started Journal Service. bob # [ 16.437424] systemd-modules-load[67]: Inserted module 'dm_mod' bob # [ 16.611920] systemd-modules-load[67]: Inserted module 'virtio_balloon' bob # [ 16.662469] systemd-modules-load[67]: Inserted module 'virtio_gpu' bob # [ 16.748919] systemd-udevd[73]: Using default interface naming scheme 'v261'. bob # [ 16.802668] systemd[1]: Starting Create System Files and Directories... alice # [ 17.022346] systemd[1]: Started Journal Service. alice # [ 17.007994] systemd-modules-load[67]: Inserted module 'dm_mod' alice # [ 17.119803] systemd-modules-load[67]: Inserted module 'virtio_balloon' alice # [ 17.195896] systemd-modules-load[67]: Inserted module 'virtio_gpu' alice # [ 17.288008] systemd-udevd[73]: Using default interface naming scheme 'v261'. alice # [ 17.377982] systemd[1]: Starting Create System Files and Directories... bob # [ 18.081447] systemd[1]: Finished Create System Files and Directories. alice # [ 18.870846] systemd[1]: Finished Create System Files and Directories. bob # [ 18.936878] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 19.564307] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 25.257562] systemd[1]: Finished Coldplug All udev Devices. bob # [ 25.267718] systemd[1]: Reached target System Initialization. bob # [ 25.281434] systemd[1]: Reached target Basic System. alice # [ 28.252327] systemd[1]: Finished Coldplug All udev Devices. alice # [ 28.264616] systemd[1]: Reached target System Initialization. alice # [ 28.273745] systemd[1]: Reached target Basic System. bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. alice # [ 40.241839] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 alice # [ 40.296305] serio: i8042 KBD port at 0x60,0x64 irq 1 alice # [ 40.297285] serio: i8042 AUX port at 0x60,0x64 irq 12 bob # [ 40.461196] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 bob # [ 40.522203] serio: i8042 KBD port at 0x60,0x64 irq 1 bob # [ 40.538175] serio: i8042 AUX port at 0x60,0x64 irq 12 bob # [ 40.655163] virtio_blk virtio2: 1/0/0 default/read/poll queues bob # [ 40.806942] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 40.960034] virtio_blk virtio2: 1/0/0 default/read/poll queues alice # [ 41.016246] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 41.088121] uhci_hcd 0000:00:1d.0: UHCI Host Controller alice # [ 41.094598] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 1 alice # [ 41.107018] uhci_hcd 0000:00:1d.0: detected 2 ports alice # [ 41.123851] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 alice # [ 41.203121] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 alice # [ 41.203971] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 alice # [ 41.204339] usb usb1: Product: UHCI Host Controller alice # [ 41.214859] usb usb1: Manufacturer: Linux 6.18.52 uhci_hcd alice # [ 41.215377] usb usb1: SerialNumber: 0000:00:1d.0 alice # [ 41.259971] hub 1-0:1.0: USB hub found bob # [ 41.288374] uhci_hcd 0000:00:1d.0: UHCI Host Controller alice # [ 41.272326] hub 1-0:1.0: 2 ports detected bob # [ 41.298191] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 1 bob # [ 41.306005] uhci_hcd 0000:00:1d.0: detected 2 ports bob # [ 41.313497] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 bob # [ 41.370706] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 bob # [ 41.371125] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 bob # [ 41.371503] usb usb1: Product: UHCI Host Controller bob # [ 41.371945] usb usb1: Manufacturer: Linux 6.18.52 uhci_hcd bob # [ 41.372159] usb usb1: SerialNumber: 0000:00:1d.0 alice # [ 41.357163] uhci_hcd 0000:00:1d.1: UHCI Host Controller alice # [ 41.363785] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 2 alice # [ 41.364173] uhci_hcd 0000:00:1d.1: detected 2 ports alice # [ 41.365151] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 alice # [ 41.376348] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 alice # [ 41.376946] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 alice # [ 41.377345] usb usb2: Product: UHCI Host Controller alice # [ 41.383993] usb usb2: Manufacturer: Linux 6.18.52 uhci_hcd alice # [ 41.384278] usb usb2: SerialNumber: 0000:00:1d.1 alice # [ 41.398313] hub 2-0:1.0: USB hub found alice # [ 41.402067] hub 2-0:1.0: 2 ports detected alice # [ 41.440338] uhci_hcd 0000:00:1d.2: UHCI Host Controller alice # [ 41.446739] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 3 alice # [ 41.447163] uhci_hcd 0000:00:1d.2: detected 2 ports alice # [ 41.448025] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 bob # [ 41.464459] hub 1-0:1.0: USB hub found bob # [ 41.475395] hub 1-0:1.0: 2 ports detected alice # [ 41.462009] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 alice # [ 41.462315] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 alice # [ 41.462743] usb usb3: Product: UHCI Host Controller alice # [ 41.463030] usb usb3: Manufacturer: Linux 6.18.52 uhci_hcd alice # [ 41.463228] usb usb3: SerialNumber: 0000:00:1d.2 alice # [ 41.477253] hub 3-0:1.0: USB hub found alice # [ 41.481745] hub 3-0:1.0: 2 ports detected bob # [ 41.515714] uhci_hcd 0000:00:1d.1: UHCI Host Controller bob # [ 41.516146] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 2 bob # [ 41.519809] uhci_hcd 0000:00:1d.1: detected 2 ports bob # [ 41.520722] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 bob # [ 41.530373] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 bob # [ 41.530906] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 bob # [ 41.531146] usb usb2: Product: UHCI Host Controller bob # [ 41.531341] usb usb2: Manufacturer: Linux 6.18.52 uhci_hcd bob # [ 41.531492] usb usb2: SerialNumber: 0000:00:1d.1 bob # [ 41.543468] hub 2-0:1.0: USB hub found bob # [ 41.549024] hub 2-0:1.0: 2 ports detected bob # [ 41.598908] uhci_hcd 0000:00:1d.2: UHCI Host Controller bob # [ 41.599401] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 3 bob # [ 41.599983] uhci_hcd 0000:00:1d.2: detected 2 ports bob # [ 41.607228] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 bob # [ 41.619242] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 bob # [ 41.619882] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 bob # [ 41.620094] usb usb3: Product: UHCI Host Controller bob # [ 41.620247] usb usb3: Manufacturer: Linux 6.18.52 uhci_hcd bob # [ 41.620492] usb usb3: SerialNumber: 0000:00:1d.2 bob # [ 41.633875] hub 3-0:1.0: USB hub found bob # [ 41.641211] hub 3-0:1.0: 2 ports detected alice # [ 42.559659] ehci-pci 0000:00:1d.7: EHCI Host Controller alice # [ 42.560314] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 4 alice # [ 42.580397] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 alice # [ 42.593180] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 alice # [ 42.604254] usb usb4: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 alice # [ 42.605289] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 alice # [ 42.608937] usb usb4: Product: EHCI Host Controller alice # [ 42.609213] usb usb4: Manufacturer: Linux 6.18.52 ehci_hcd alice # [ 42.609357] usb usb4: SerialNumber: 0000:00:1d.7 alice # [ 42.622099] hub 4-0:1.0: USB hub found alice # [ 42.627914] hub 4-0:1.0: 6 ports detected alice # [ 42.693430] hub 1-0:1.0: USB hub found alice # [ 42.707251] hub 1-0:1.0: 2 ports detected alice # [ 42.777380] hub 2-0:1.0: USB hub found alice # [ 42.795464] hub 2-0:1.0: 2 ports detected alice # [ 42.842407] hub 3-0:1.0: USB hub found alice # [ 42.848228] hub 3-0:1.0: 2 ports detected alice # [ 42.934463] usb 4-1: new high-speed USB device number 2 using ehci-pci bob # [ 43.075255] ehci-pci 0000:00:1d.7: EHCI Host Controller bob # [ 43.091490] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 4 bob # [ 43.136962] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 bob # [ 43.163865] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 alice # [ 43.155947] usb 4-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 alice # [ 43.157024] usb 4-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 alice # [ 43.157458] usb 4-1: Product: QEMU USB Tablet alice # [ 43.162121] usb 4-1: Manufacturer: QEMU alice # [ 43.162996] usb 4-1: SerialNumber: 28754-0000:00:1d.7-1 bob # [ 43.196048] usb usb4: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 bob # [ 43.209043] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 bob # [ 43.209742] usb usb4: Product: EHCI Host Controller bob # [ 43.210093] usb usb4: Manufacturer: Linux 6.18.52 ehci_hcd bob # [ 43.211164] usb usb4: SerialNumber: 0000:00:1d.7 bob # [ 43.257025] hub 4-0:1.0: USB hub found bob # [ 43.272504] hub 4-0:1.0: 6 ports detected bob # [ 43.380878] hrtimer: interrupt took 2348343 ns bob # [ 43.436114] hub 1-0:1.0: USB hub found bob # [ 43.446454] hub 1-0:1.0: 2 ports detected bob # [ 43.537250] hub 2-0:1.0: USB hub found bob # [ 43.553603] hub 2-0:1.0: 2 ports detected bob # [ 43.667132] hub 3-0:1.0: USB hub found bob # [ 43.673413] hub 3-0:1.0: 2 ports detected bob # [ 43.701517] usb 4-1: new high-speed USB device number 2 using ehci-pci bob # [ 43.943185] usb 4-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 bob # [ 43.949011] usb 4-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 bob # [ 43.956315] usb 4-1: Product: QEMU USB Tablet bob # [ 43.962459] usb 4-1: Manufacturer: QEMU bob # [ 43.971035] usb 4-1: SerialNumber: 28754-0000:00:1d.7-1 alice # [ 44.257107] SCSI subsystem initialized bob # [ 45.858250] SCSI subsystem initialized alice # [ 46.007269] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode alice # [ 46.008421] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) alice # [ 46.027140] ahci 0000:00:1f.2: flags: 64bit ncq only alice # [ 46.279424] scsi host0: ahci alice # [ 46.306048] scsi host1: ahci alice # [ 46.323407] scsi host2: ahci alice # [ 46.340391] scsi host3: ahci alice # [ 46.355434] scsi host4: ahci alice # [ 46.376090] scsi host5: ahci alice # [ 46.386303] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1 alice # [ 46.389901] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1 alice # [ 46.390321] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1 alice # [ 46.394285] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1 alice # [ 46.395027] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1 alice # [ 46.395346] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1 alice # [ 46.750399] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) alice # [ 46.784415] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 alice # [ 46.797746] ata3.00: applying bridge limits alice # [ 46.807081] ata5: SATA link down (SStatus 0 SControl 300) alice # [ 46.816948] ata4: SATA link down (SStatus 0 SControl 300) alice # [ 46.817858] ata6: SATA link down (SStatus 0 SControl 300) alice # [ 46.819260] ata1: SATA link down (SStatus 0 SControl 300) alice # [ 46.820240] ata2: SATA link down (SStatus 0 SControl 300) alice # [ 46.830317] ata3.00: configured for UDMA/100 alice # [ 46.883974] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 bob # [ 47.025883] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode bob # [ 47.026431] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) bob # [ 47.033262] ahci 0000:00:1f.2: flags: 64bit ncq only bob # [ 47.216168] scsi host0: ahci bob # [ 47.252884] scsi host1: ahci bob # [ 47.278921] scsi host2: ahci bob # [ 47.317136] scsi host3: ahci bob # [ 47.344034] scsi host4: ahci bob # [ 47.382399] scsi host5: ahci bob # [ 47.411032] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1 bob # [ 47.412148] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1 bob # [ 47.419127] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1 bob # [ 47.420302] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1 bob # [ 47.429036] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1 bob # [ 47.430158] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1 bob # [ 47.808624] ata2: SATA link down (SStatus 0 SControl 300) bob # [ 47.839408] ata5: SATA link down (SStatus 0 SControl 300) bob # [ 47.843492] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) bob # [ 47.877159] ata6: SATA link down (SStatus 0 SControl 300) bob # [ 47.885092] ata4: SATA link down (SStatus 0 SControl 300) bob # [ 47.887454] ata1: SATA link down (SStatus 0 SControl 300) bob # [ 47.912943] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 bob # [ 47.924315] ata3.00: applying bridge limits bob # [ 47.948439] ata3.00: configured for UDMA/100 bob # [ 48.065521] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. alice # [ 58.966872] systemd[1]: Starting Virtual Console Setup... bob # [ 59.151729] systemd[1]: Starting Virtual Console Setup... bob # [ 60.672341] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 alice # [ 60.733849] systemd-vconsole-setup[205]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 60.784637] systemd-vconsole-setup[210]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 60.805907] systemd[1]: Finished Virtual Console Setup. bob # [ 60.840912] systemd[1]: Finished Virtual Console Setup. bob # [ 61.042763] (udev-worker)[209]: Network interface NamePolicy= disabled on kernel command line. bob # [ 61.076907] (udev-worker)[203]: Network interface NamePolicy= disabled on kernel command line. alice # [ 61.536109] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 alice # [ 61.908881] (udev-worker)[212]: Network interface NamePolicy= disabled on kernel command line. alice # [ 61.946885] (udev-worker)[213]: Network interface NamePolicy= disabled on kernel command line. bob # [ 62.623902] systemd[1]: Found device /dev/disk/by-label/nixos. bob # [ 62.633669] systemd[1]: Reached target Initrd Root Device. bob # [ 62.698740] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... bob # [ 63.686634] systemd-fsck[226]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 63.782868] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. bob # [ 63.840692] systemd[1]: Mounting /sysroot... bob # [ 66.062904] EXT4-fs (vda): mounted filesystem bf4151ed-ddcc-484b-a344-da467ef50d4f r/w with ordered data mode. Quota mode: none. alice # [ 66.007723] systemd[1]: Found device /dev/disk/by-label/nixos. alice # [ 66.017815] systemd[1]: Reached target Initrd Root Device. alice # [ 66.046438] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... bob # [ 66.155707] systemd[1]: Mounted /sysroot. bob # [ 66.176923] systemd[1]: Reached target Initrd Root File System. bob # [ 66.313833] systemd[1]: Mounting /sysroot/nix/.ro-store... bob # [ 66.443018] systemd[1]: Mounting /sysroot/nix/.rw-store... bob # [ 66.689772] systemd[1]: Mounting /sysroot/run... bob # [ 66.998753] systemd[1]: Mounting /sysroot/tmp/shared... alice # [ 67.066589] systemd-fsck[242]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 67.215948] systemd[1]: Mounting /sysroot/tmp/xchg... alice # [ 67.222575] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 67.306559] systemd[1]: Mounting /sysroot... bob # [ 67.456425] systemd[1]: Starting Mountpoints Configured in the Real Root... bob # [ 68.408763] systemd-sysroot-fstab-check[250]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 68.551623] systemd[1]: Mounted /sysroot/nix/.ro-store. bob # [ 68.556410] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 68.562835] systemd[1]: Mounted /sysroot/run. bob # [ 68.570854] systemd[1]: Mounted /sysroot/tmp/shared. bob # [ 68.577927] systemd[1]: Mounted /sysroot/tmp/xchg. bob # [ 68.656774] systemd[1]: Reload requested from client PID 250 ('systemd-sysroot') (unit initrd-parse-etc.service)... bob # [ 68.665457] systemd[1]: Reloading... alice # [ 69.356938] EXT4-fs (vda): mounted filesystem 97b8a4f6-291d-4747-878d-587587bb26a7 r/w with ordered data mode. Quota mode: none. alice # [ 69.457593] systemd[1]: Mounted /sysroot. alice # [ 69.470628] systemd[1]: Reached target Initrd Root File System. alice # [ 69.567439] systemd[1]: Mounting /sysroot/nix/.ro-store... alice # [ 69.661203] systemd[1]: Mounting /sysroot/nix/.rw-store... alice # [ 69.818923] systemd[1]: Mounting /sysroot/run... alice # [ 70.076063] systemd[1]: Mounting /sysroot/tmp/shared... alice # [ 70.563991] systemd[1]: Mounting /sysroot/tmp/xchg... bob # [ 70.628623] systemd[1]: Reloading finished in 1934 ms. bob # [ 70.811866] systemd-sysroot-fstab-check[250]: Requesting initrd-fs.target/start/replace... bob # [ 70.881847] systemd-sysroot-fstab-check[250]: Requesting swap.target/start/replace... alice # [ 70.875429] systemd[1]: Starting Mountpoints Configured in the Real Root... bob # [ 70.940328] systemd[1]: initrd-parse-etc.service: Deactivated successfully. bob # [ 70.953867] systemd[1]: Finished Mountpoints Configured in the Real Root. bob # [ 70.960681] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. bob # [ 71.040477] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 73.561330] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 73.648727] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 74.224643] systemd[1]: Mounting /sysroot/nix/store... alice # [ 78.206393] systemd-sysroot-fstab-check[266]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 79.131232] systemd[1]: Mounted /sysroot/nix/store. bob # [ 79.347513] systemd[1]: Reached target Initrd File Systems. bob # [ 79.719426] systemd[1]: Starting Find NixOS closure... bob # [ 80.534930] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... alice # [ 82.138875] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 82.261652] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 82.302993] systemd[1]: Mounted /sysroot/run. alice # [ 82.362754] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 82.429442] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 82.954124] systemd[1]: Reload requested from client PID 266 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 82.993066] systemd[1]: Reloading... bob # [ 86.111078] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 86.812402] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. bob # [ 89.696747] systemd[1]: Finished Find NixOS closure. bob # [ 89.762394] systemd[1]: Reached target Initrd Default Target. bob # [ 90.032900] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 92.699020] systemd[1]: Reloading finished in 9587 ms. bob # [ 93.939916] systemd[1]: Stopped target Initrd Default Target. alice # [ 93.936383] systemd-sysroot-fstab-check[266]: Requesting initrd-fs.target/start/replace... bob # [ 94.018014] systemd[1]: Stopped target Basic System. bob # [ 94.126557] systemd[1]: Stopped target Initrd Root Device. bob # [ 94.176275] systemd[1]: Stopped target Path Units. bob # [ 94.231714] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 94.248189] systemd-sysroot-fstab-check[266]: Requesting swap.target/start/replace... bob # [ 94.317715] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. bob # [ 94.408373] systemd[1]: Stopped target Slice Units. bob # [ 94.470053] systemd[1]: Stopped target Socket Units. bob # [ 94.559537] systemd[1]: Stopped target System Initialization. alice # [ 94.563510] systemd[1]: initrd-parse-etc.service: Deactivated successfully. alice # [ 94.620442] systemd[1]: Finished Mountpoints Configured in the Real Root. bob # [ 94.692170] systemd[1]: Stopped target Swaps. alice # [ 94.753915] systemd[1]: initrd-parse-etc.service: Consumed 1.562s CPU time over 23.574s wall clock time, 2.1M memory peak. bob # [ 94.842732] systemd[1]: Stopped target Timer Units. alice # [ 94.863604] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. bob # [ 94.887764] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 94.974220] systemd[1]: Closed D-Bus System Message Bus Socket. bob # [ 95.043262] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 95.136684] systemd[1]: Stopped Find NixOS closure. alice # [ 95.165986] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 95.283941] systemd[1]: initrd-find-nixos-closure.service: Consumed 2.817s CPU time over 15.456s wall clock time, 1.9M memory peak. bob # [ 96.018302] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 96.132072] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 96.190208] systemd[1]: Stopped Apply Kernel Variables. bob # [ 96.379030] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 96.546894] systemd[1]: Stopped Load Kernel Modules. bob # [ 96.809068] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. bob # [ 97.103502] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. bob # [ 97.180618] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Consumed 1.189s CPU time over 16.563s wall clock time, 2M memory peak. bob # [ 97.306575] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 97.490498] systemd[1]: Stopped Create System Files and Directories. bob # [ 97.772862] systemd[1]: Stopped target Local File Systems. bob # [ 97.884509] systemd[1]: Stopped target Preparation for Local File Systems. alice # [ 97.877549] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 97.931972] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 97.910053] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 98.139749] systemd[1]: Stopped Coldplug All udev Devices. bob # [ 98.196490] systemd[1]: systemd-udev-trigger.service: Consumed 5.263s CPU time over 1min 28.471s wall clock time, 3M memory peak. bob # [ 98.250301] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 98.325755] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 98.381356] systemd[1]: Stopped Virtual Console Setup. alice # [ 98.609758] systemd[1]: Mounting /sysroot/nix/store... bob # [ 100.254337] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 100.288751] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 100.315453] systemd[1]: rw-sysroot-nix-store.service: Consumed 1.002s CPU time over 4.343s wall clock time, 1.9M memory peak. bob # [ 100.606771] systemd[1]: initrd-cleanup.service: Deactivated successfully. bob # [ 100.666387] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. bob # [ 100.704074] systemd[1]: initrd-cleanup.service: Consumed 1.531s CPU time over 10.591s wall clock time, 1.9M memory peak. bob # [ 104.243836] systemd[1]: systemd-udevd.service: Deactivated successfully. bob # [ 104.385225] systemd[1]: Stopped Rule-based Manager for Device Events and Files. bob # [ 104.486648] systemd[1]: systemd-udevd.service: Consumed 39.401s CPU time over 1min 31.922s wall clock time, 19.9M memory peak. bob # [ 104.526546] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. bob # [ 104.555569] systemd[1]: Closed udev Control Socket. bob # [ 104.678399] systemd[1]: Starting Cleanup udev Database... bob # [ 104.697950] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 104.735246] systemd[1]: Stopped Create Static Device Nodes in /dev. bob # [ 104.811414] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. bob # [ 104.882412] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. bob # [ 104.997221] systemd[1]: kmod-static-nodes.service: Deactivated successfully. bob # [ 105.062992] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 105.751282] systemd[1]: Mounted /sysroot/nix/store. alice # [ 105.832244] systemd[1]: Reached target Initrd File Systems. alice # [ 105.989332] systemd[1]: Starting Find NixOS closure... alice # [ 106.212115] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 108.055494] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 108.087846] systemd[1]: Finished Cleanup udev Database. bob # [ 108.114395] systemd[1]: initrd-udevadm-cleanup-db.service: Consumed 2.135s CPU time over 3.334s wall clock time, 2M memory peak. bob # [ 108.166714] systemd[1]: Reached target Switch Root. bob # [ 108.277036] systemd[1]: Starting NixOS Activation... alice # [ 112.612065] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. alice # [ 113.003936] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. alice # [ 115.182147] systemd[1]: Finished Find NixOS closure. alice # [ 115.246824] systemd[1]: Reached target Initrd Default Target. alice # [ 115.613790] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... bob # [ 117.293448] initrd-nixos-activation-start[303]: booting system configuration /nix/store/p8hwydl4ilpb66vfs3pdc12192v1qv90-nixos-system-bob-test alice # [ 118.433441] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. alice # [ 118.685589] cdrom: Uniform CD-ROM driver Revision: 3.20 alice # [ 120.110183] systemd[1]: Stopped target Initrd Default Target. alice # [ 120.164744] systemd[1]: Stopped target Basic System. alice # [ 120.208104] systemd[1]: Stopped target Initrd Root Device. alice # [ 120.292811] systemd[1]: Stopped target Path Units. alice # [ 120.352565] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 120.621890] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. alice # [ 121.126812] systemd[1]: Stopped target Slice Units. alice # [ 121.169458] systemd[1]: Stopped target Socket Units. alice # [ 121.213726] systemd[1]: Stopped target System Initialization. alice # [ 121.260114] systemd[1]: Stopped target Swaps. alice # [ 121.322424] systemd[1]: Stopped target Timer Units. alice # [ 121.375911] systemd[1]: dbus.socket: Deactivated successfully. alice # [ 121.475352] systemd[1]: Closed D-Bus System Message Bus Socket. alice # [ 121.550313] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. alice # [ 121.604830] systemd[1]: Stopped Find NixOS closure. alice # [ 121.664066] systemd[1]: initrd-find-nixos-closure.service: Consumed 2.576s CPU time over 15.617s wall clock time, 2M memory peak. bob # [ 121.866537] initrd-nixos-activation-start[303]: running activation script... alice # [ 122.259098] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 122.322457] systemd[1]: systemd-sysctl.service: Deactivated successfully. alice # [ 122.376273] systemd[1]: Stopped Apply Kernel Variables. alice # [ 122.436729] systemd[1]: systemd-modules-load.service: Deactivated successfully. alice # [ 122.484457] systemd[1]: Stopped Load Kernel Modules. alice # [ 122.555259] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 122.607863] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. alice # [ 122.654254] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Consumed 1.370s CPU time over 16.390s wall clock time, 1.9M memory peak. alice # [ 122.684249] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. alice # [ 122.759048] systemd[1]: Stopped Create System Files and Directories. alice # [ 122.852900] systemd[1]: Stopped target Local File Systems. alice # [ 122.929897] systemd[1]: Stopped target Preparation for Local File Systems. alice # [ 123.015138] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 123.217102] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 123.269092] systemd[1]: systemd-udev-trigger.service: Consumed 5.811s CPU time over 1min 53.148s wall clock time, 3.1M memory peak. alice # [ 123.353943] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 123.386883] systemd[1]: Stopped Virtual Console Setup. alice # [ 125.409449] systemd[1]: initrd-cleanup.service: Deactivated successfully. alice # [ 125.446416] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. alice # [ 125.471392] systemd[1]: initrd-cleanup.service: Consumed 1.676s CPU time over 9.763s wall clock time, 1.9M memory peak. alice # [ 125.527808] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 125.576998] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 125.601999] systemd[1]: rw-sysroot-nix-store.service: Consumed 1.021s CPU time over 3.523s wall clock time, 2M memory peak. alice # [ 129.217966] systemd[1]: systemd-udevd.service: Deactivated successfully. alice # [ 129.316190] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 129.345353] systemd[1]: systemd-udevd.service: Consumed 44.391s CPU time over 1min 56.512s wall clock time, 21M memory peak. alice # [ 129.417275] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 129.522108] systemd[1]: Closed udev Control Socket. alice # [ 129.768417] systemd[1]: Starting Cleanup udev Database... alice # [ 129.788921] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. alice # [ 129.865024] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 129.904577] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. alice # [ 129.932296] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. alice # [ 129.987283] systemd[1]: kmod-static-nodes.service: Deactivated successfully. alice # [ 130.029839] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 132.576419] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. alice # [ 132.603694] systemd[1]: Finished Cleanup udev Database. alice # [ 132.648434] systemd[1]: initrd-udevadm-cleanup-db.service: Consumed 1.668s CPU time over 2.770s wall clock time, 2M memory peak. alice # [ 132.746776] systemd[1]: Reached target Switch Root. alice # [ 132.895403] systemd[1]: Starting NixOS Activation... alice # [ 144.000085] initrd-nixos-activation-start[317]: booting system configuration /nix/store/dm25ifzai99pkp763r7wv9b38z3i6w0n-nixos-system-alice-test alice # [ 148.290398] initrd-nixos-activation-start[317]: running activation script... bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. alice # [ 153.259691] hrtimer: interrupt took 3373054 ns bob # [ 161.704381] initrd-nixos-activation-start[326]: setting up /etc... bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. bob # [ 183.436168] initrd-nixos-activation-start[326]: setting up age secrets... alice # [ 190.352673] initrd-nixos-activation-start[340]: setting up /etc... bob # [ 191.605925] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 191.617644] systemd[1]: Finished NixOS Activation. bob # [ 191.636890] systemd[1]: initrd-nixos-activation.service: Consumed 56.227s CPU time over 1min 23.314s wall clock time, 26.5M memory peak. bob # [ 191.679821] systemd[1]: Starting Switch Root... bob # [ 192.054735] systemd[1]: Switching root. bob # [ 192.612223] systemd-journald[66]: Received SIGTERM from PID 1 (systemd). alice # [ 194.505615] initrd-nixos-activation-start[340]: setting up age secrets... bob # [ 194.598893] NET: Registered PF_VSOCK protocol family bob # [ 195.418992] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 195.421038] systemd[1]: Detected virtualization qemu. bob # [ 195.422337] systemd[1]: Detected architecture x86-64. bob # [ 195.424871] systemd[1]: Detected first boot. bob # [ 195.459148] systemd[1]: Initializing machine ID from random generator. alice # [ 196.653380] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. alice # [ 196.692072] systemd[1]: Finished NixOS Activation. alice # [ 196.716095] systemd[1]: initrd-nixos-activation.service: Consumed 42.949s CPU time over 1min 3.733s wall clock time, 26.5M memory peak. alice # [ 196.873648] systemd[1]: Starting Switch Root... alice # [ 198.835597] systemd[1]: Switching root. bob # [ 199.789737] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 200.093677] systemd-journald[66]: Received SIGTERM from PID 1 (systemd). alice # [ 208.131007] NET: Registered PF_VSOCK protocol family bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. bob # [ 208.830986] systemd[1]: Applying preset policy. alice # [ 209.660471] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) alice # [ 209.662962] systemd[1]: Detected virtualization qemu. alice # [ 209.664763] systemd[1]: Detected architecture x86-64. alice # [ 209.670733] systemd[1]: Detected first boot. alice # [ 209.744494] systemd[1]: Initializing machine ID from random generator. alice # [ 213.121226] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 214.320008] systemd[1]: Populated /etc with preset unit settings. alice # [ 214.805318] systemd[1]: Applying preset policy. alice # [ 217.763747] systemd[1]: Populated /etc with preset unit settings. bob # [ 218.655301] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 218.666513] systemd[1]: Stopped initrd-switch-root.service. bob # [ 218.743321] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 218.765398] systemd[1]: Created slice Slice /system/getty. bob # [ 218.785304] systemd[1]: Created slice User and Session Slice. bob # [ 218.792837] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 218.795462] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 218.797413] systemd[1]: Expecting device /dev/hvc0... bob # [ 218.798456] systemd[1]: Expecting device /dev/ttyS0... bob # [ 218.799739] systemd[1]: Reached target Local Encrypted Volumes. bob # [ 218.801143] systemd[1]: Stopped target initrd-fs.target. bob # [ 218.802722] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 218.803757] systemd[1]: Stopped target initrd-switch-root.target. bob # [ 218.804917] systemd[1]: Reached target Virtual Machines and Containers. bob # [ 218.806338] systemd[1]: Reached target Path Units. bob # [ 218.807814] systemd[1]: Reached target Remote File Systems. bob # [ 218.809006] systemd[1]: Reached target Slice Units. bob # [ 218.810132] systemd[1]: Reached target Swaps. bob # [ 218.847392] systemd[1]: Listening on Query the User Interactively for a Password. bob # [ 218.889874] systemd[1]: Listening on Process Core Dump Socket. bob # [ 218.923794] systemd[1]: Listening on Credential Encryption/Decryption. bob # [ 218.970160] systemd[1]: Listening on Factory Reset Management. bob # [ 218.975452] systemd[1]: Listening on Hostname Service Socket. bob # [ 219.048702] systemd[1]: Starting Journal Log Access Socket... bob # [ 219.086485] systemd[1]: Listening on Journal Audit Socket. bob # [ 219.132715] systemd[1]: Listening on Console Output Muting Service Socket. bob # [ 219.139025] systemd[1]: Listening on Network Management Metrics Varlink Socket. bob # [ 219.147468] systemd[1]: Listening on Network Management Varlink Socket. bob # [ 219.153342] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 219.160992] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 219.164929] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os bob # [ 219.166741] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki bob # [ 219.243861] systemd[1]: Listening on Disk Repartitioning Service Socket. bob # [ 219.250748] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 219.256074] systemd[1]: Listening on Resolve Service Varlink Socket. bob # [ 219.261166] systemd[1]: Listening on udev Control Socket. bob # [ 219.267405] systemd[1]: Listening on udev Varlink Socket. bob # [ 219.346425] systemd[1]: Mounting Huge Pages File System... bob # [ 219.473184] systemd[1]: Mounting POSIX Message Queue File System... bob # [ 219.682136] systemd[1]: Mounting Kernel Debug File System... bob # [ 219.969163] systemd[1]: Mounting Kernel Trace File System... bob # [ 220.407354] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 220.891104] systemd[1]: Starting Load Kernel Module configfs... bob # [ 220.919248] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm bob # [ 220.951395] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore bob # [ 220.990431] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 221.603188] systemd[1]: Mounting FUSE Control File System... bob # [ 221.653157] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 bob # [ 222.363100] systemd[1]: Starting Journal Service... bob # [ 222.642325] systemd[1]: Starting Load Kernel Modules... alice # [ 223.067809] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 223.074946] systemd[1]: Stopped initrd-switch-root.service. alice # [ 223.079077] systemd[1]: initrd-switch-root.service: Consumed 1.099s CPU time over 26.148s wall clock time, 2M memory peak. alice # [ 223.127844] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. alice # [ 223.147021] systemd[1]: Created slice Slice /system/getty. alice # [ 223.162698] systemd[1]: Created slice User and Session Slice. alice # [ 223.168595] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 223.171151] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 223.173899] systemd[1]: Expecting device /dev/hvc0... alice # [ 223.174921] systemd[1]: Expecting device /dev/ttyS0... alice # [ 223.176026] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 223.177261] systemd[1]: Stopped target initrd-fs.target. alice # [ 223.178829] systemd[1]: Stopped target initrd-root-fs.target. alice # [ 223.179922] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 223.180994] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 223.182314] systemd[1]: Reached target Path Units. alice # [ 223.183729] systemd[1]: Reached target Remote File Systems. alice # [ 223.184631] systemd[1]: Reached target Slice Units. alice # [ 223.185705] systemd[1]: Reached target Swaps. alice # [ 223.217914] systemd[1]: Listening on Query the User Interactively for a Password. alice # [ 223.254916] systemd[1]: Listening on Process Core Dump Socket. alice # [ 223.284295] systemd[1]: Listening on Credential Encryption/Decryption. alice # [ 223.314521] systemd[1]: Listening on Factory Reset Management. alice # [ 223.317614] systemd[1]: Listening on Hostname Service Socket. alice # [ 223.367246] systemd[1]: Starting Journal Log Access Socket... alice # [ 223.394308] systemd[1]: Listening on Journal Audit Socket. alice # [ 223.435503] systemd[1]: Listening on Console Output Muting Service Socket. alice # [ 223.439917] systemd[1]: Listening on Network Management Metrics Varlink Socket. alice # [ 223.444113] systemd[1]: Listening on Network Management Varlink Socket. alice # [ 223.448698] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 223.453740] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. alice # [ 223.456522] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 223.457720] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki alice # [ 223.511605] systemd[1]: Listening on Disk Repartitioning Service Socket. alice # [ 223.514802] systemd[1]: Listening on Resolve Monitor Varlink Socket. alice # [ 223.516986] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 223.519614] systemd[1]: Listening on udev Control Socket. alice # [ 223.523757] systemd[1]: Listening on udev Varlink Socket. alice # [ 223.564165] systemd[1]: Mounting Huge Pages File System... alice # [ 223.652198] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 223.779230] systemd[1]: Mounting Kernel Debug File System... alice # [ 223.957993] systemd[1]: Mounting Kernel Trace File System... bob # [ 224.062746] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... alice # [ 224.249916] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 224.530221] systemd[1]: Starting Remount Root and Kernel File Systems... bob # [ 224.579070] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 224.568858] systemd[1]: Starting Load Kernel Module configfs... alice # [ 224.584502] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 224.611049] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore alice # [ 224.634741] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 225.042913] systemd[1]: Mounting FUSE Control File System... alice # [ 225.060297] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 bob # [ 225.118456] systemd[1]: Starting Coldplug All udev Devices... bob # [ 225.801955] systemd[1]: Listening on Journal Log Access Socket. bob # [ 225.815472] systemd[1]: Mounted Huge Pages File System. bob # [ 225.828075] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 225.839876] systemd[1]: Mounted Kernel Debug File System. bob # [ 225.850925] systemd[1]: Mounted Kernel Trace File System. bob # [ 225.899309] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 225.933430] systemd[1]: Starting Journal Service... bob # [ 226.106134] systemd[1]: modprobe@configfs.service: Deactivated successfully. bob # [ 226.170308] systemd[1]: Finished Load Kernel Module configfs. alice # [ 226.192715] systemd[1]: Starting Load Kernel Modules... bob # [ 226.231878] systemd[1]: Mounted FUSE Control File System. bob # [ 227.035029] loop: module loaded bob # [ 227.047098] systemd[1]: Mounting Kernel Configuration File System... bob # [ 227.049481] systemd-journald[420]: Collecting audit messages is enabled. bob # [ 227.865216] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 228.356531] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... bob # [ 228.631524] systemd[1]: Finished Load Kernel Modules. bob # [ 229.186115] EXT4-fs (vda): re-mounted bf4151ed-ddcc-484b-a344-da467ef50d4f. alice # [ 229.301874] systemd[1]: Starting Remount Root and Kernel File Systems... alice # [ 229.339508] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 229.430743] systemd[1]: Queued start job for default target Multi-User System. bob # [ 229.551613] systemd[1]: systemd-journald.service: Deactivated successfully. bob # [ 229.642231] systemd[1]: systemd-journald.service: Consumed 5.779s CPU time over 3min 29.323s wall clock time, 3.6M memory peak. bob # [ 229.756488] systemd-modules-load[421]: Inserted module 'loop' bob # [ 229.999206] systemd[1]: Starting Firewall... alice # [ 230.700412] systemd[1]: Starting Coldplug All udev Devices... bob # [ 231.177081] systemd[1]: Starting Apply Kernel Variables... alice # [ 231.282658] systemd[1]: Listening on Journal Log Access Socket. alice # [ 231.307512] systemd[1]: Mounted Huge Pages File System. alice # [ 231.318268] systemd[1]: Mounted POSIX Message Queue File System. alice # [ 231.342336] systemd[1]: Mounted Kernel Debug File System. alice # [ 231.352666] systemd[1]: Mounted Kernel Trace File System. alice # [ 231.433291] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 231.467013] systemd[1]: modprobe@configfs.service: Deactivated successfully. alice # [ 231.580910] systemd[1]: Finished Load Kernel Module configfs. alice # [ 232.023268] systemd[1]: Mounted FUSE Control File System. bob # [ 232.243333] systemd[1]: Started Journal Service. alice # [ 232.527721] systemd[1]: Mounting Kernel Configuration File System... alice # [ 232.551520] systemd-journald[426]: Collecting audit messages is enabled. alice # [ 233.596304] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 233.600508] loop: module loaded alice # [ 234.410751] systemd[1]: Finished Load Kernel Modules. bob # [ 234.872611] systemd[1]: Finished Remount Root and Kernel File Systems. alice # [ 235.488596] systemd[1]: Queued start job for default target Multi-User System. alice # [ 235.628599] systemd[1]: systemd-journald.service: Deactivated successfully. alice # [ 235.835180] EXT4-fs (vda): re-mounted 97b8a4f6-291d-4747-878d-587587bb26a7. alice # [ 235.767986] systemd[1]: systemd-journald.service: Consumed 5.811s CPU time over 3min 33.374s wall clock time, 3.6M memory peak. alice # [ 235.962496] systemd-modules-load[427]: Inserted module 'loop' bob # [ 236.031684] systemd[1]: Mounted Kernel Configuration File System. alice # [ 236.611250] systemd[1]: Starting Firewall... alice # [ 238.148212] systemd[1]: Starting Apply Kernel Variables... bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. bob # [ 238.536036] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 239.095808] systemd[1]: Started Journal Service. bob # [ 239.224625] systemd-oomd[423]: No swap; memory pressure usage will be degraded bob # [ 239.316987] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 239.449719] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 241.065822] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 241.092257] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 241.357880] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 241.503346] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 241.712282] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 242.707909] systemd[1]: Finished Apply Kernel Variables. bob # [ 244.068477] systemd-journald[420]: Received client request to flush runtime journal. alice # [ 244.423098] systemd[1]: Mounted Kernel Configuration File System. alice # [ 247.719272] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 248.497288] systemd-oomd[429]: No swap; memory pressure usage will be degraded alice # [ 248.928768] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 249.936581] systemd[1]: Starting Flush Journal to Persistent Storage... alice # [ 250.316118] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore alice # [ 251.611705] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 252.447409] systemd[1]: Starting Network Name Resolution... bob # [ 252.743334] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 252.949384] systemd[1]: Finished Load/Save OS Random Seed. bob # [ 253.035521] systemd[1]: Reached target First Boot Complete. bob # [ 253.115541] systemd[1]: Finished Flush Journal to Persistent Storage. alice # [ 253.304520] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 253.357771] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 253.633891] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. alice # [ 254.870537] systemd[1]: Finished Apply Kernel Variables. alice # [ 255.570454] systemd-journald[426]: Received client request to flush runtime journal. bob # [ 257.995190] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 258.321503] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 258.840737] systemd[1]: Mounting /run/wrappers... bob # [ 259.969909] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 268.056009] systemd[1]: Mounted /run/wrappers. bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. alice # [ 310.733325] watchdog: BUG: soft lockup - CPU#0 stuck for 43s! [systemd:1] alice # [ 310.744305] Modules linked in: nf_tables sch_fq_codel nfnetlink loop configfs dmi_sysfs qemu_fw_cfg vsock_loopback vmw_vsock_virtio_transport vmw_vsock_virtio_transport_common vsock sr_mod cdrom overlay ext4 crc16 mbcache jbd2 atkbd libps2 vivaldi_fmap ahci libahci libata scsi_mod scsi_common ehci_pci uhci_hcd virtio_blk ehci_hcd virtio_net net_failover failover i8042 serio virtio_gpu virtio_dma_buf virtio_balloon dm_mod virtio_pci virtio_pci_legacy_dev virtio_pci_modern_dev virtiofs fuse virtio_console virtio_rng virtio virtio_ring autofs4 alice # [ 310.755996] CPU: 0 UID: 0 PID: 1 Comm: systemd Not tainted 6.18.52 #1-NixOS PREEMPT(lazy) alice # [ 310.756489] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 alice # [ 310.756800] RIP: 0033:0x7f7b100f2b28 alice # [ 310.759050] Code: 48 8d 75 b8 e8 f9 70 03 00 85 c0 75 55 48 39 5d c0 75 df 48 8b 45 b8 48 8b 75 98 48 89 da 48 89 c7 48 89 45 90 e8 88 26 ff ff <85> c0 75 c4 48 8b 7d 88 48 85 ff 74 2b 48 8b 55 90 31 c9 48 89 17 alice # [ 310.759613] RSP: 002b:00007ffeaee09280 EFLAGS: 00010246 alice # [ 310.760128] RAX: 0000000000000000 RBX: 0000000000000002 RCX: 0000000000000002 alice # [ 310.760182] RDX: 0000000000000002 RSI: 00007f7b1013c225 RDI: 000055908ba88f83 alice # [ 310.760196] RBP: 00007ffeaee09300 R08: 0000000000000000 R09: 0000000000000000 alice # [ 310.760210] R10: 0000000000000000 R11: 0000000000000000 R12: 00007ffeaee09338 alice # [ 310.760238] R13: 00007ffeaee09330 R14: 00007ffeaee093a0 R15: 000055908bad7dd0 alice # [ 310.760323] FS: 00007f7b1027e840 GS: 0000000000000000 alice # [ 310.792115] clocksource: Long readout interval, skipping watchdog check: cs_nsec: 42906215952 wd_nsec: 0 bob # [ 310.751818] watchdog: BUG: soft lockup - CPU#0 stuck for 43s! [systemd:1] bob # [ 310.766807] Modules linked in: nf_tables nfnetlink sch_fq_codel loop configfs dmi_sysfs qemu_fw_cfg vsock_loopback vmw_vsock_virtio_transport vmw_vsock_virtio_transport_common vsock overlay ext4 crc16 mbcache jbd2 atkbd libps2 vivaldi_fmap ahci libahci libata scsi_mod scsi_common ehci_pci uhci_hcd ehci_hcd virtio_net net_failover virtio_blk failover i8042 serio virtio_gpu virtio_dma_buf virtio_balloon dm_mod virtio_pci virtio_pci_legacy_dev virtio_pci_modern_dev virtiofs fuse virtio_console virtio_rng virtio virtio_ring autofs4 bob # [ 310.778074] CPU: 0 UID: 0 PID: 1 Comm: systemd Not tainted 6.18.52 #1-NixOS PREEMPT(lazy) bob # [ 310.780632] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 bob # [ 310.780976] RIP: 0033:0x7f538cf64092 bob # [ 310.785595] Code: 84 0a 8a d8 ff 0f b6 47 2c 41 89 f4 89 c2 c0 ea 03 83 e2 01 40 38 f2 0f 84 db 00 00 00 89 f2 83 e0 f7 83 e2 01 c1 e2 03 09 d0 <88> 47 2c 48 8b 47 08 40 84 f6 75 42 48 8d 57 38 48 8b 78 10 48 89 bob # [ 310.786779] RSP: 002b:00007ffd783c4610 EFLAGS: 00010202 bob # [ 310.786907] RAX: 0000000000000001 RBX: 00005600e076e240 RCX: 0000000000000000 bob # [ 310.786933] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00005600e076e240 bob # [ 310.786952] RBP: 00007ffd783c4620 R08: 0000000000000000 R09: 000000000004234a bob # [ 310.786971] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 bob # [ 310.786988] R13: 000000000000000e R14: 00005600e076e240 R15: 00005600e076fbf0 bob # [ 310.787072] FS: 00007f538c8fc840 GS: 0000000000000000 bob # [ 310.813055] clocksource: Long readout interval, skipping watchdog check: cs_nsec: 42927001909 wd_nsec: 0 alice # [ 273.351428] systemd[1]: Starting Network Name Resolution... alice # [ 273.428614] systemd[1]: Finished Load/Save OS Random Seed. bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. cleanup kill QemuMachine (pid 47) alice # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14) alice # [2026-09-22T18:01:57Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-09-22T18:01:57Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-09-22T18:01:57Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 48) bob # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14) bob # [2026-09-22T18:01:58Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-09-22T18:01:58Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-09-22T18:01:58Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 1.45 seconds) Traceback (most recent call last): File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in sys.exit(main()) ~~~~^^ File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/__init__.py", line 194, in main driver.run_tests() ~~~~~~~~~~~~~~~~^^ File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 437, in run_tests self.test_script() ~~~~~~~~~~~~~~~~^^ File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 395, in test_script exec(self.tests, symbols, None) ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "", line 5, in File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 541, in wait_until_succeeds retry(check_success, as_timedelta(timeout)) ~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 141, in retry if fn(False): ~~^^^^^^^ File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 537, in check_success status, output = self.execute(command, timeout=timeout) ~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 722, in execute return self._execute( ~~~~~~~~~~~~~^ command=command, ^^^^^^^^^^^^^^^^ ...<2 lines>... timeout=as_timedelta(timeout) if timeout is not None else None, ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ ) ^ File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 983, in _execute self.connect() ~~~~~~~~~~~~^^ File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 1172, in connect raise RuntimeError("Shell did not start in time") RuntimeError: Shell did not start in time