Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.01 seconds) Test will time out and terminate in 3600.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 alice: QEMU running (pid 47) alice # Disk image does not exist, creating the virtualisation disk image... alice # Formatting '/build/vm-state-alice/tmp.nibLKxXevd', 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 alice # Filesystem UUID: ebb0667e-e011-4bbb-beb5-5513edf13328 alice # Superblock backups stored on blocks: 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... bob # Disk image does not exist, creating the virtualisation disk image... bob: QEMU running (pid 48) bob # Formatting '/build/vm-state-bob/tmp.5Zo4Mg0rtP', fmt=raw size=1073741824 alice # [2026-10-17T17:00:20Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # mke2fs 1.47.4 (6-Mar-2025) (finished: start all VMs, in 0.59 seconds) bob # Discarding device blocks: 0/262144 done bob: waiting for success: curl bob:5000/nix-cache-info bob # Creating filesystem with 262144 4k blocks and 65536 inodes bob: waiting for the VM to finish booting bob # Filesystem UUID: 832f7df2-b5e7-4f5e-a126-f18ed73a4ba2 alice # [2026-10-17T17:00:20Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # Superblock backups stored on blocks: alice # [2026-10-17T17:00:20Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # 32768, 98304, 163840, 229376 alice # [2026-10-17T17:00:20Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # alice # [2026-10-17T17:00:20Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # Allocating group tables: 0/8 done alice # [2026-10-17T17:00:20Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # Writing inode tables: 0/8 done alice # [2026-10-17T17:00:20Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # Creating journal (8192 blocks): done alice # [2026-10-17T17:00:20Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # Writing superblocks and filesystem accounting information: 0/8 done alice # [2026-10-17T17:00:20Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # alice # [2026-10-17T17:00:20Z INFO virtiofsd] Client connected, servicing requests bob # Virtualisation disk image created. alice # [2026-10-17T17:00:20Z INFO virtiofsd] Client connected, servicing requests bob # Starting virtiofs daemons... alice # [2026-10-17T17:00:20Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-17T17:00:20Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # qemu-system-x86_64: Could not access KVM kernel module: No such file or directory bob # [2026-10-17T17:00:20Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # qemu-system-x86_64: failed to initialize kvm: No such file or directory bob # [2026-10-17T17:00:20Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # qemu-system-x86_64: falling back to tcg bob # [2026-10-17T17:00:20Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-17T17:00:20Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-17T17:00:20Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-17T17:00:20Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-17T17:00:20Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-17T17:00:20Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-17T17:00:20Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-17T17:00:20Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-17T17:00:20Z 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 # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) bob # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) bob # bob # alice # alice # bob # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC750+3EF2C750 CA00 alice # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC750+3EF2C750 CA00 bob # Press Ctrl-B to configure iPXE (PCI 00:02.0)... bob # bob # bob # bob # alice # Press Ctrl-B to configure iPXE (PCI 00:02.0)... alice # alice # alice # alice # bob # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC750 3EF2C750 CB00 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 # alice # Booting from ROM... bob # Booting from ROM... bob # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 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/slcgdwycnq2ijgwi3lrqwi05rqj4n7dw-nixos-system-bob-test/init regInfo=/nix/.ro-store/qik7gv13nl7z4cp68p4w3f0cvkni9n6n-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.236 MHz processor bob # [ 0.018035] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 bob # [ 0.018911] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs bob # [ 0.019155] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT bob # [ 0.049317] found SMP MP-table at [mem 0x000f5450-0x000f545f] bob # [ 0.052028] Using GB pages for direct mapping bob # [ 0.056357] RAMDISK: [mem 0x3e36b000-0x3ffcffff] bob # [ 0.057010] ACPI: Early table checksum verification disabled bob # [ 0.057824] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) bob # [ 0.058333] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.059498] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.060607] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.060796] ACPI: FACS 0x000000003FFE0000 000040 bob # [ 0.060889] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.060933] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.060973] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.061012] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) bob # [ 0.061172] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] bob # [ 0.061221] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] bob # [ 0.061234] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] bob # [ 0.061246] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] bob # [ 0.061256] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] bob # [ 0.061267] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] bob # [ 0.061277] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] bob # [ 0.072456] No NUMA configuration found bob # [ 0.072526] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] bob # [ 0.073614] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] bob # [ 0.080047] Zone ranges: bob # [ 0.080094] DMA [mem 0x0000000000001000-0x0000000000ffffff] bob # [ 0.080190] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] bob # [ 0.080204] Normal empty bob # [ 0.080222] Device empty bob # [ 0.080236] Movable zone start for each node bob # [ 0.080271] Early memory node ranges bob # [ 0.080304] node 0: [mem 0x0000000000001000-0x000000000009efff] bob # [ 0.080505] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] bob # [ 0.080662] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] bob # [ 0.081965] On node 0, zone DMA: 1 pages in unavailable ranges bob # [ 0.082402] On node 0, zone DMA: 97 pages in unavailable ranges bob # [ 0.101435] On node 0, zone DMA32: 40 pages in unavailable ranges bob # [ 0.102348] ACPI: PM-Timer IO Port: 0x608 bob # [ 0.103046] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) bob # [ 0.103593] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23 bob # [ 0.103800] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) bob # [ 0.104137] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) bob # [ 0.104201] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) bob # [ 0.104316] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) bob # [ 0.104329] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) bob # [ 0.104567] ACPI: Using ACPI (MADT) for SMP configuration information bob # [ 0.104629] ACPI: HPET id: 0x8086a201 base: 0xfed00000 bob # [ 0.105153] CPU topo: Max. logical packages: 1 bob # [ 0.105173] CPU topo: Max. logical dies: 1 bob # [ 0.105185] CPU topo: Max. dies per package: 1 bob # [ 0.105252] CPU topo: Max. threads per core: 1 bob # [ 0.105460] CPU topo: Num. cores per package: 1 bob # [ 0.105482] CPU topo: Num. threads per package: 1 bob # [ 0.105494] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs bob # [ 0.106669] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] bob # [ 0.106738] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] bob # [ 0.106899] [mem 0x40000000-0xafffffff] available for PCI devices bob # [ 0.106980] Booting paravirtualized kernel on bare hardware bob # [ 0.107624] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns bob # [ 0.124314] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 bob # [ 0.128389] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 bob # [ 0.130323] 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/slcgdwycnq2ijgwi3lrqwi05rqj4n7dw-nixos-system-bob-test/init regInfo=/nix/.ro-store/qik7gv13nl7z4cp68p4w3f0cvkni9n6n-closure-info/registration console=ttyS0,115200n8 console=tty0 bob # [ 0.133121] Unknown kernel command line parameters "regInfo=/nix/.ro-store/qik7gv13nl7z4cp68p4w3f0cvkni9n6n-closure-info/registration", will be passed to user space. bob # [ 0.134093] random: crng init done bob # [ 0.134261] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes bob # [ 0.135654] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) bob # [ 0.136016] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) bob # [ 0.140629] Fallback order for Node 0: 0 bob # [ 0.141078] Built 1 zonelists, mobility grouping on. Total pages: 262006 bob # [ 0.141108] Policy zone: DMA32 bob # [ 0.144483] mem auto-init: stack:all(zero), heap alloc:on, heap free:off bob # [ 0.183149] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 bob # [ 0.188945] allocated 2097152 bytes of page_ext bob # [ 0.260751] ftrace: allocating 48804 entries in 192 pages bob # [ 0.261060] ftrace: allocated 192 pages with 2 groups bob # [ 0.274975] Dynamic Preempt: lazy bob # [ 0.280893] rcu: Preemptible hierarchical RCU implementation. bob # [ 0.280988] rcu: RCU event tracing is enabled. bob # [ 0.281028] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. bob # [ 0.281335] Trampoline variant of Tasks RCU enabled. bob # [ 0.281355] Rude variant of Tasks RCU enabled. bob # [ 0.281364] Tracing variant of Tasks RCU enabled. bob # [ 0.281674] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. bob # [ 0.281730] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 bob # [ 0.284376] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.284471] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.284485] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.351856] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 bob # [ 0.545521] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.546435] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns bob # [ 0.552594] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.562026] Console: colour VGA+ 80x25 alice # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 UTC 2026 bob # [ 0.562805] printk: legacy console [tty0] enabled bob # [ 0.577139] printk: legacy console [ttyS0] enabled 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/gxn57vlvq5ihqqh0m09lqj84hadgp8sl-nixos-system-alice-test/init regInfo=/nix/.ro-store/x6f0f7kw4s1qabdcb324h5d6w8jkn8d3-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 bob # [ 0.599865] ACPI: Core revision 20250807 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.273 MHz processor alice # [ 0.023656] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 alice # [ 0.024890] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs alice # [ 0.025266] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT alice # [ 0.066248] found SMP MP-table at [mem 0x000f5450-0x000f545f] alice # [ 0.069100] Using GB pages for direct mapping alice # [ 0.074337] RAMDISK: [mem 0x3e36a000-0x3ffcffff] alice # [ 0.075045] ACPI: Early table checksum verification disabled alice # [ 0.075677] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) alice # [ 0.076198] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.077254] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.078143] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.078264] ACPI: FACS 0x000000003FFE0000 000040 alice # [ 0.078335] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.078368] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.078397] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.078426] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) alice # [ 0.078552] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d] alice # [ 0.078600] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219] alice # [ 0.078636] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] alice # [ 0.078644] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385] alice # [ 0.078651] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd] alice # [ 0.078659] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9] alice # [ 0.078666] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421] alice # [ 0.088291] No NUMA configuration found alice # [ 0.088361] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] alice # [ 0.089231] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] alice # [ 0.095186] Zone ranges: alice # [ 0.095246] DMA [mem 0x0000000000001000-0x0000000000ffffff] alice # [ 0.095345] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] alice # [ 0.095359] Normal empty alice # [ 0.095377] Device empty alice # [ 0.095392] Movable zone start for each node alice # [ 0.095426] Early memory node ranges alice # [ 0.095459] node 0: [mem 0x0000000000001000-0x000000000009efff] alice # [ 0.095696] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] alice # [ 0.095855] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] alice # [ 0.097192] On node 0, zone DMA: 1 pages in unavailable ranges alice # [ 0.097714] On node 0, zone DMA: 97 pages in unavailable ranges alice # [ 0.117686] On node 0, zone DMA32: 40 pages in unavailable ranges alice # [ 0.118698] ACPI: PM-Timer IO Port: 0x608 alice # [ 0.119458] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) alice # [ 0.120110] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23 alice # [ 0.120311] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) alice # [ 0.120677] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) alice # [ 0.120767] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) alice # [ 0.120909] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) alice # [ 0.120923] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) alice # [ 0.121176] ACPI: Using ACPI (MADT) for SMP configuration information alice # [ 0.121247] ACPI: HPET id: 0x8086a201 base: 0xfed00000 alice # [ 0.121942] CPU topo: Max. logical packages: 1 alice # [ 0.121975] CPU topo: Max. logical dies: 1 alice # [ 0.121988] CPU topo: Max. dies per package: 1 alice # [ 0.122056] CPU topo: Max. threads per core: 1 alice # [ 0.122274] CPU topo: Num. cores per package: 1 alice # [ 0.122297] CPU topo: Num. threads per package: 1 alice # [ 0.122309] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs alice # [ 0.123565] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] alice # [ 0.123712] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] alice # [ 0.123868] [mem 0x40000000-0xafffffff] available for PCI devices alice # [ 0.123973] Booting paravirtualized kernel on bare hardware alice # [ 0.124653] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns alice # [ 0.141926] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 alice # [ 0.146196] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 alice # [ 0.148281] 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/gxn57vlvq5ihqqh0m09lqj84hadgp8sl-nixos-system-alice-test/init regInfo=/nix/.ro-store/x6f0f7kw4s1qabdcb324h5d6w8jkn8d3-closure-info/registration console=ttyS0,115200n8 console=tty0 alice # [ 0.151147] Unknown kernel command line parameters "regInfo=/nix/.ro-store/x6f0f7kw4s1qabdcb324h5d6w8jkn8d3-closure-info/registration", will be passed to user space. alice # [ 0.152162] random: crng init done alice # [ 0.152294] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes alice # [ 0.153578] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) alice # [ 0.153959] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.158596] Fallback order for Node 0: 0 alice # [ 0.159058] Built 1 zonelists, mobility grouping on. Total pages: 262006 alice # [ 0.159099] Policy zone: DMA32 alice # [ 0.162339] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.310964] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.316537] allocated 2097152 bytes of page_ext alice # [ 0.379792] ftrace: allocating 48804 entries in 192 pages alice # [ 0.380089] ftrace: allocated 192 pages with 2 groups alice # [ 0.394280] Dynamic Preempt: lazy alice # [ 0.400706] rcu: Preemptible hierarchical RCU implementation. alice # [ 0.400761] rcu: RCU event tracing is enabled. alice # [ 0.400791] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. alice # [ 0.400972] Trampoline variant of Tasks RCU enabled. alice # [ 0.400982] Rude variant of Tasks RCU enabled. alice # [ 0.400991] Tracing variant of Tasks RCU enabled. alice # [ 0.401122] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. alice # [ 0.401159] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 0.403207] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.403260] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.403273] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.464999] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 alice # [ 0.481309] rcu: srcu_init: Setting srcu_struct sizes based on contention. alice # [ 0.482212] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns alice # [ 0.487860] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.495787] Console: colour VGA+ 80x25 alice # [ 0.496442] printk: legacy console [tty0] enabled alice # [ 0.509393] printk: legacy console [ttyS0] enabled alice # [ 0.533005] ACPI: Core revision 20250807 bob # [ 0.624047] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns alice # [ 0.542895] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns bob # [ 0.631692] APIC: Switch to symmetric I/O mode setup bob # [ 0.633024] x2apic: IRQ remapping doesn't support X2APIC mode alice # [ 0.549679] APIC: Switch to symmetric I/O mode setup alice # [ 0.551508] x2apic: IRQ remapping doesn't support X2APIC mode bob # [ 0.639891] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 alice # [ 0.558093] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 bob # [ 0.646397] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x33cb5d1ce9e, max_idle_ns: 440795242262 ns bob # [ 0.647662] Calibrating delay loop (skipped), value calculated using timer frequency.. 7186.47 BogoMIPS (lpj=3593236) alice # [ 0.564373] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x33cb8122a6a, max_idle_ns: 440795358851 ns alice # [ 0.566562] Calibrating delay loop (skipped), value calculated using timer frequency.. 7186.54 BogoMIPS (lpj=3593273) bob # [ 0.654083] x86/cpu: User Mode Instruction Prevention (UMIP) activated bob # [ 0.670054] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 alice # [ 0.575571] x86/cpu: User Mode Instruction Prevention (UMIP) activated bob # [ 0.670632] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 bob # [ 0.673835] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto bob # [ 0.675563] Speculative Store Bypass: Vulnerable bob # [ 0.676162] Spectre V2 : Mitigation: Retpolines bob # [ 0.676768] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization bob # [ 0.677441] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT alice # [ 0.596122] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 alice # [ 0.596588] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 bob # [ 0.686027] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' alice # [ 0.600170] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto bob # [ 0.690383] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' alice # [ 0.602491] Speculative Store Bypass: Vulnerable bob # [ 0.690723] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' bob # [ 0.690956] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' alice # [ 0.603301] Spectre V2 : Mitigation: Retpolines bob # [ 0.691225] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' bob # [ 0.691639] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' alice # [ 0.604245] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization bob # [ 0.692421] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 bob # [ 0.692904] x86/fpu: xstate_offset[3]: 960, xstate_sizes[3]: 64 alice # [ 0.605312] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT bob # [ 0.693224] x86/fpu: xstate_offset[4]: 1024, xstate_sizes[4]: 64 bob # [ 0.694548] x86/fpu: xstate_offset[9]: 2688, xstate_sizes[9]: 8 bob # [ 0.694849] x86/fpu: Enabled xstate features 0x21f, context size is 2696 bytes, using 'standard' format. alice # [ 0.616343] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' alice # [ 0.621859] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' alice # [ 0.622293] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' alice # [ 0.622600] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' alice # [ 0.622969] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' alice # [ 0.623458] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' alice # [ 0.624356] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 alice # [ 0.625127] x86/fpu: xstate_offset[3]: 960, xstate_sizes[3]: 64 alice # [ 0.625463] x86/fpu: xstate_offset[4]: 1024, xstate_sizes[4]: 64 alice # [ 0.626983] x86/fpu: xstate_offset[9]: 2688, xstate_sizes[9]: 8 alice # [ 0.627353] x86/fpu: Enabled xstate features 0x21f, context size is 2696 bytes, using 'standard' format. bob # [ 1.227725] Freeing SMP alternatives memory: 44K bob # [ 1.229627] pid_max: default: 32768 minimum: 301 bob # [ 1.242874] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 1.246798] landlock: Up and running. bob # [ 1.247022] Yama: becoming mindful. bob # [ 1.248857] LSM support for eBPF active bob # [ 1.254888] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 1.255518] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 1.192254] Freeing SMP alternatives memory: 44K alice # [ 1.194815] pid_max: default: 32768 minimum: 301 alice # [ 1.211474] LSM: initializing lsm=capability,landlock,yama,bpf,ima alice # [ 1.216858] landlock: Up and running. alice # [ 1.217149] Yama: becoming mindful. alice # [ 1.220130] LSM support for eBPF active alice # [ 1.228390] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 1.229130] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 1.424304] smpboot: CPU0: AMD QEMU TCG CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3) alice # [ 1.393691] smpboot: CPU0: AMD QEMU TCG CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3) bob # [ 1.447636] Performance Events: PMU not available due to virtualization, using software events only. bob # [ 1.450052] signal: max sigframe size: 3632 alice # [ 1.418981] Performance Events: PMU not available due to virtualization, using software events only. alice # [ 1.421428] signal: max sigframe size: 3632 bob # [ 1.455762] rcu: Hierarchical SRCU implementation. bob # [ 1.456072] rcu: Max phase no-delay instances is 400. alice # [ 1.425173] rcu: Hierarchical SRCU implementation. alice # [ 1.425469] rcu: Max phase no-delay instances is 400. bob # [ 1.469917] NMI watchdog: Perf NMI watchdog permanently disabled alice # [ 1.441985] NMI watchdog: Perf NMI watchdog permanently disabled bob # [ 1.475997] smp: Bringing up secondary CPUs ... alice # [ 1.446608] smp: Bringing up secondary CPUs ... bob # [ 1.479173] smp: Brought up 1 node, 1 CPU bob # [ 1.479774] smpboot: Total of 1 processors activated (7186.47 BogoMIPS) alice # [ 1.449369] smp: Brought up 1 node, 1 CPU alice # [ 1.450064] smpboot: Total of 1 processors activated (7186.54 BogoMIPS) bob # [ 1.494877] Memory: 941024K/1048024K available (17263K kernel code, 2728K rwdata, 13660K rodata, 3656K init, 2968K bss, 99600K reserved, 0K cma-reserved) alice # [ 1.464327] Memory: 941044K/1048024K available (17263K kernel code, 2728K rwdata, 13660K rodata, 3656K init, 2968K bss, 99604K reserved, 0K cma-reserved) bob # [ 1.508176] devtmpfs: initialized alice # [ 1.478229] devtmpfs: initialized bob # [ 1.516008] x86/mm: Memory block size: 128MB alice # [ 1.486362] x86/mm: Memory block size: 128MB bob # [ 1.536382] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 1.537227] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). bob # [ 1.544508] pinctrl core: initialized pinctrl subsystem alice # [ 1.513374] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 1.514837] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). alice # [ 1.523193] pinctrl core: initialized pinctrl subsystem bob # [ 1.558074] PM: RTC time: 17:00:24, date: 2026-10-17 alice # [ 1.540499] PM: RTC time: 17:00:24, date: 2026-10-17 bob # [ 1.587773] NET: Registered PF_NETLINK/PF_ROUTE protocol family bob # [ 1.595157] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations bob # [ 1.596757] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 1.598098] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations bob # [ 1.598880] audit: initializing netlink subsys (disabled) bob # [ 1.603807] audit: type=2000 audit(1792256422.971:1): state=initialized audit_enabled=0 res=1 alice # [ 1.574864] NET: Registered PF_NETLINK/PF_ROUTE protocol family alice # [ 1.583213] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations bob # [ 1.615621] thermal_sys: Registered thermal governor 'fair_share' alice # [ 1.585081] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 1.615722] thermal_sys: Registered thermal governor 'bang_bang' bob # [ 1.616037] thermal_sys: Registered thermal governor 'step_wise' bob # [ 1.616188] thermal_sys: Registered thermal governor 'user_space' alice # [ 1.586226] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations bob # [ 1.616436] thermal_sys: Registered thermal governor 'power_allocator' bob # [ 1.617606] cpuidle: using governor menu alice # [ 1.587211] audit: initializing netlink subsys (disabled) bob # [ 1.622969] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 alice # [ 1.592174] audit: type=2000 audit(1792256423.042:1): state=initialized audit_enabled=0 res=1 bob # [ 1.629784] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] bob # [ 1.631449] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry bob # [ 1.633935] PCI: Using configuration type 1 for base access alice # [ 1.602754] thermal_sys: Registered thermal governor 'fair_share' alice # [ 1.602846] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 1.603091] thermal_sys: Registered thermal governor 'step_wise' alice # [ 1.603297] thermal_sys: Registered thermal governor 'user_space' alice # [ 1.603471] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 1.605503] cpuidle: using governor menu bob # [ 1.639747] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. alice # [ 1.611531] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 alice # [ 1.618847] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] alice # [ 1.620295] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry alice # [ 1.623410] PCI: Using configuration type 1 for base access alice # [ 1.630791] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. bob # [ 1.665049] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 1.670484] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 1.670821] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 1.671041] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 1.658523] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages alice # [ 1.659040] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page alice # [ 1.659296] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages alice # [ 1.659495] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 1.758864] ACPI: Added _OSI(Module Device) bob # [ 1.761487] ACPI: Added _OSI(Processor Device) bob # [ 1.761831] ACPI: Added _OSI(Processor Aggregator Device) alice # [ 1.761316] ACPI: Added _OSI(Module Device) alice # [ 1.761810] ACPI: Added _OSI(Processor Device) alice # [ 1.761979] ACPI: Added _OSI(Processor Aggregator Device) bob # [ 1.904611] ACPI: 1 ACPI AML tables successfully acquired and loaded alice # [ 1.918081] ACPI: 1 ACPI AML tables successfully acquired and loaded bob # [ 1.966873] ACPI: Interpreter enabled bob # [ 1.971185] ACPI: PM: (supports S0 S3 S4 S5) alice # [ 1.979515] ACPI: Interpreter enabled bob # [ 1.973456] ACPI: Using IOAPIC for interrupt routing alice # [ 1.983631] ACPI: PM: (supports S0 S3 S4 S5) alice # [ 1.983954] ACPI: Using IOAPIC for interrupt routing bob # [ 1.982672] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug bob # [ 1.983133] PCI: Using E820 reservations for host bridge windows alice # [ 1.995308] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug alice # [ 1.995802] PCI: Using E820 reservations for host bridge windows bob # [ 1.990609] ACPI: Enabled 2 GPEs in block 00 to 3F alice # [ 2.003301] ACPI: Enabled 2 GPEs in block 00 to 3F bob # [ 2.083368] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) bob # [ 2.084635] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] bob # [ 2.087765] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] alice # [ 2.097105] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) bob # [ 2.090404] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] alice # [ 2.098355] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] alice # [ 2.101550] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] alice # [ 2.104824] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] bob # [ 2.102071] PCI host bridge to bus 0000:00 bob # [ 2.103467] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] bob # [ 2.103893] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] bob # [ 2.104170] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] bob # [ 2.104517] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] bob # [ 2.104806] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] bob # [ 2.105099] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] bob # [ 2.105869] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 2.111716] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint alice # [ 2.119847] PCI host bridge to bus 0000:00 alice # [ 2.121087] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] alice # [ 2.121864] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] alice # [ 2.122266] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] alice # [ 2.122814] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] alice # [ 2.123228] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] alice # [ 2.123797] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] alice # [ 2.124893] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 2.120421] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint bob # [ 2.123517] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] bob # [ 2.123919] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] bob # [ 2.124188] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] bob # [ 2.124860] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] bob # [ 2.127231] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 2.134840] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint bob # [ 2.130460] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] bob # [ 2.131463] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] bob # [ 2.133449] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] bob # [ 2.134628] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] bob # [ 2.136538] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 2.138460] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] bob # [ 2.139374] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] bob # [ 2.139595] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] alice # [ 2.148956] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint bob # [ 2.141625] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 2.152074] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] alice # [ 2.152654] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] bob # [ 2.144416] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] alice # [ 2.153328] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] bob # [ 2.144783] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] bob # [ 2.145008] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] alice # [ 2.154403] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] bob # [ 2.146546] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 2.158319] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 2.149431] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] bob # [ 2.149763] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] bob # [ 2.150018] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] bob # [ 2.150557] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] bob # [ 2.153044] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 2.161013] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] alice # [ 2.162089] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] alice # [ 2.162530] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] alice # [ 2.163079] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] bob # [ 2.156370] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] bob # [ 2.157390] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] bob # [ 2.159888] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 2.166935] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 2.161463] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] bob # [ 2.162367] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] bob # [ 2.164536] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 2.169911] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] alice # [ 2.171093] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] alice # [ 2.171407] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] bob # [ 2.168653] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] bob # [ 2.169050] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] alice # [ 2.175018] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint bob # [ 2.171417] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 2.173052] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] bob # [ 2.173646] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] alice # [ 2.176806] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] alice # [ 2.177889] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] alice # [ 2.178180] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] bob # [ 2.176720] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint alice # [ 2.183936] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 2.178463] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] bob # [ 2.178984] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] bob # [ 2.179428] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] bob # [ 2.181910] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 2.185854] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] alice # [ 2.186874] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] alice # [ 2.187248] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] alice # [ 2.187781] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] bob # [ 2.185440] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] bob # [ 2.185750] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] bob # [ 2.185964] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] alice # [ 2.190334] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 2.193749] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] alice # [ 2.194048] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] bob # [ 2.189933] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint bob # [ 2.191422] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] alice # [ 2.196517] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 2.193853] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint alice # [ 2.198809] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] alice # [ 2.199238] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] bob # [ 2.196463] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] alice # [ 2.203369] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 2.199613] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint bob # [ 2.201766] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] alice # [ 2.206816] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] alice # [ 2.207252] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] alice # [ 2.210462] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 2.205819] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint bob # [ 2.207476] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] alice # [ 2.213789] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] alice # [ 2.214236] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] bob # [ 2.210979] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint bob # [ 2.214956] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO alice # [ 2.218383] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint bob # [ 2.218926] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint alice # [ 2.221947] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] alice # [ 2.222999] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] bob # [ 2.223549] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] alice # [ 2.223890] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] bob # [ 2.224183] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] bob # [ 2.227730] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint alice # [ 2.230203] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 2.231426] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] alice # [ 2.233785] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] alice # [ 2.234263] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] alice # [ 2.234544] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] alice # [ 2.238448] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint alice # [ 2.242852] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] bob # [ 2.251678] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 alice # [ 2.249252] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint bob # [ 2.255546] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 alice # [ 2.251904] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] alice # [ 2.258426] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint bob # [ 2.261925] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 alice # [ 2.261854] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] bob # [ 2.270621] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 alice # [ 2.270335] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint bob # [ 2.277482] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 alice # [ 2.273800] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] bob # [ 2.283565] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 alice # [ 2.281473] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint alice # [ 2.284416] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO bob # [ 2.290604] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 bob # [ 2.294974] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 alice # [ 2.290836] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint bob # [ 2.297661] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 bob # [ 2.299535] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 alice # [ 2.292951] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] alice # [ 2.294807] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] bob # [ 2.301457] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 bob # [ 2.304609] ACPI: PCI: Interrupt link GSID configured for IRQ 19 bob # [ 2.305999] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 bob # [ 2.307528] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 bob # [ 2.308852] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 alice # [ 2.304038] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint bob # [ 2.311650] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 alice # [ 2.307909] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] bob # [ 2.331887] iommu: Default domain type: Translated bob # [ 2.332997] iommu: DMA domain TLB invalidation policy: lazy mode bob # [ 2.338885] ACPI: bus type USB registered bob # [ 2.341907] usbcore: registered new interface driver usbfs bob # [ 2.345666] usbcore: registered new interface driver hub bob # [ 2.347885] usbcore: registered new device driver usb alice # [ 2.346797] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 alice # [ 2.354188] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 alice # [ 2.359916] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 alice # [ 2.366993] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 alice # [ 2.372349] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 alice # [ 2.376280] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 alice # [ 2.380209] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 bob # [ 2.389904] NetLabel: Initializing bob # [ 2.390590] NetLabel: domain hash size = 128 alice # [ 2.386245] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 bob # [ 2.394225] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO alice # [ 2.389032] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 alice # [ 2.390976] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 bob # [ 2.397965] NetLabel: unlabeled traffic allowed by default bob # [ 2.398966] PCI: Using ACPI for IRQ routing alice # [ 2.394018] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 alice # [ 2.397273] ACPI: PCI: Interrupt link GSID configured for IRQ 19 alice # [ 2.398950] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 alice # [ 2.400825] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 alice # [ 2.402277] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 alice # [ 2.404306] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 bob # [ 2.420304] pci 0000:00:01.0: vgaarb: setting as boot VGA device bob # [ 2.420304] pci 0000:00:01.0: vgaarb: bridge control possible bob # [ 2.420304] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none bob # [ 2.420458] vgaarb: loaded bob # [ 2.422073] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 bob # [ 2.422519] hpet0: 3 comparators, 64-bit 100.000000 MHz counter alice # [ 2.419969] iommu: Default domain type: Translated alice # [ 2.420331] iommu: DMA domain TLB invalidation policy: lazy mode alice # [ 2.424889] ACPI: bus type USB registered alice # [ 2.427968] usbcore: registered new interface driver usbfs bob # [ 2.428304] clocksource: Switched to clocksource tsc-early alice # [ 2.429848] usbcore: registered new interface driver hub alice # [ 2.431047] usbcore: registered new device driver usb bob # [ 2.451860] VFS: Disk quotas dquot_6.6.0 bob # [ 2.454624] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 2.453976] NetLabel: Initializing alice # [ 2.454221] NetLabel: domain hash size = 128 alice # [ 2.454371] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO alice # [ 2.456459] NetLabel: unlabeled traffic allowed by default alice # [ 2.457149] PCI: Using ACPI for IRQ routing bob # [ 2.467495] pnp: PnP ACPI init bob # [ 2.474321] ACPI: IRQ 4 override to edge(!), high(!) alice # [ 2.471344] pci 0000:00:01.0: vgaarb: setting as boot VGA device alice # [ 2.471691] pci 0000:00:01.0: vgaarb: bridge control possible alice # [ 2.471691] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none alice # [ 2.472908] vgaarb: loaded bob # [ 2.478250] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved alice # [ 2.474770] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 alice # [ 2.475131] hpet0: 3 comparators, 64-bit 100.000000 MHz counter bob # [ 2.482608] pnp: PnP ACPI: found 5 devices alice # [ 2.480691] clocksource: Switched to clocksource tsc-early alice # [ 2.510715] VFS: Disk quotas dquot_6.6.0 alice # [ 2.511665] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 2.520367] pnp: PnP ACPI init alice # [ 2.526947] ACPI: IRQ 4 override to edge(!), high(!) alice # [ 2.530396] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved alice # [ 2.536286] pnp: PnP ACPI: found 5 devices bob # [ 2.558198] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns bob # [ 2.559156] clocksource: Switched to clocksource acpi_pm bob # [ 2.561437] NET: Registered PF_INET protocol family bob # [ 2.564238] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 2.607132] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns alice # [ 2.608090] clocksource: Switched to clocksource acpi_pm alice # [ 2.610324] NET: Registered PF_INET protocol family alice # [ 2.613572] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) bob # [ 2.685477] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 2.686874] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 2.687720] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 2.689252] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) bob # [ 2.689834] TCP: Hash tables configured (established 8192 bind 8192) bob # [ 2.693248] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) bob # [ 2.694396] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 2.695295] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 2.698669] NET: Registered PF_UNIX/PF_LOCAL protocol family bob # [ 2.700270] NET: Registered PF_XDP protocol family bob # [ 2.701949] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] bob # [ 2.702459] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] bob # [ 2.702671] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] bob # [ 2.702929] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] bob # [ 2.703284] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] bob # [ 2.703485] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] bob # [ 2.714422] ACPI: \_SB_.GSIA: Enabled at IRQ 16 bob # [ 2.727476] pci 0000:00:1d.0: quirk_usb_early_handoff+0x0/0x7c0 took 22134 usecs bob # [ 2.732340] ACPI: \_SB_.GSIB: Enabled at IRQ 17 bob # [ 2.742408] ACPI: \_SB_.GSIC: Enabled at IRQ 18 alice # [ 2.741945] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 2.743274] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 2.748540] pci 0000:00:1d.2: quirk_usb_early_handoff+0x0/0x7c0 took 10414 usecs alice # [ 2.744390] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) alice # [ 2.746939] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 2.747907] TCP: Hash tables configured (established 8192 bind 8192) bob # [ 2.755430] ACPI: \_SB_.GSID: Enabled at IRQ 19 alice # [ 2.751963] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 2.752989] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 2.753634] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 2.757022] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 2.758296] NET: Registered PF_XDP protocol family bob # [ 2.763574] pci 0000:00:1d.7: quirk_usb_early_handoff+0x0/0x7c0 took 13193 usecs bob # [ 2.764535] PCI: CLS 0 bytes, default 64 alice # [ 2.760239] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] alice # [ 2.760585] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] alice # [ 2.761065] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] alice # [ 2.761322] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] alice # [ 2.761527] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] alice # [ 2.761995] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] alice # [ 2.772020] ACPI: \_SB_.GSIA: Enabled at IRQ 16 bob # [ 2.777917] Trying to unpack rootfs image as initramfs... alice # [ 2.784003] pci 0000:00:1d.0: quirk_usb_early_handoff+0x0/0x7c0 took 20135 usecs alice # [ 2.789139] ACPI: \_SB_.GSIB: Enabled at IRQ 17 alice # [ 2.797452] ACPI: \_SB_.GSIC: Enabled at IRQ 18 alice # [ 2.806221] ACPI: \_SB_.GSID: Enabled at IRQ 19 alice # [ 2.813085] pci 0000:00:1d.7: quirk_usb_early_handoff+0x0/0x7c0 took 10207 usecs alice # [ 2.813564] PCI: CLS 0 bytes, default 64 alice # [ 2.825934] Trying to unpack rootfs image as initramfs... bob # [ 3.097941] Initialise system trusted keyrings bob # [ 3.106756] workingset: timestamp_bits=40 max_order=18 bucket_order=0 alice # [ 3.146712] Initialise system trusted keyrings alice # [ 3.154228] workingset: timestamp_bits=40 max_order=18 bucket_order=0 bob # [ 3.341446] Key type asymmetric registered bob # [ 3.341848] Asymmetric key parser 'x509' registered bob # [ 3.344892] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) bob # [ 3.349606] io scheduler mq-deadline registered bob # [ 3.349938] io scheduler kyber registered bob # [ 3.372573] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 3.383756] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A alice # [ 3.390450] Key type asymmetric registered alice # [ 3.391100] Asymmetric key parser 'x509' registered alice # [ 3.394235] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) alice # [ 3.398858] io scheduler mq-deadline registered alice # [ 3.399135] io scheduler kyber registered bob # [ 3.417809] Linux agpgart interface v0.103 bob # [ 3.421455] ACPI: bus type drm_connector registered alice # [ 3.420321] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled alice # [ 3.439396] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A bob # [ 3.453313] usbcore: registered new interface driver usbserial_generic bob # [ 3.456238] usbserial: USB Serial support registered for generic bob # [ 3.456739] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled bob # [ 3.465433] drop_monitor: Initializing network drop monitor service bob # [ 3.469814] NET: Registered PF_INET6 protocol family alice # [ 3.477029] Linux agpgart interface v0.103 alice # [ 3.480493] ACPI: bus type drm_connector registered bob # [ 3.496290] Segment Routing with IPv6 bob # [ 3.496910] In-situ OAM (IOAM) with IPv6 alice # [ 3.500298] usbcore: registered new interface driver usbserial_generic alice # [ 3.501223] usbserial: USB Serial support registered for generic alice # [ 3.504109] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled bob # [ 3.509516] IPI shorthand broadcast: enabled alice # [ 3.513437] drop_monitor: Initializing network drop monitor service alice # [ 3.522888] NET: Registered PF_INET6 protocol family alice # [ 3.565687] Segment Routing with IPv6 alice # [ 3.567324] In-situ OAM (IOAM) with IPv6 alice # [ 3.588490] IPI shorthand broadcast: enabled bob # [ 3.625453] sched_clock: Marking stable (3541088378, 84043810)->(3869206698, -244074510) bob # [ 3.636763] registered taskstats version 1 bob # [ 3.648250] Loading compiled-in X.509 certificates alice # [ 3.687346] sched_clock: Marking stable (3620101761, 66660439)->(3848538520, -161776320) alice # [ 3.696985] registered taskstats version 1 alice # [ 3.710922] Loading compiled-in X.509 certificates bob # [ 3.799530] tsc: Refined TSC clocksource calibration: 3593.243 MHz bob # [ 3.800477] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x33cb64513ed, max_idle_ns: 440795223150 ns bob # [ 3.823483] Demotion targets for Node 0: null bob # [ 3.829051] Key type .fscrypt registered bob # [ 3.829455] Key type fscrypt-provisioning registered bob # [ 3.841422] ima: No TPM chip found, activating TPM-bypass! bob # [ 3.841907] ima: Allocated hash algorithm: sha1 bob # [ 3.849824] ima: No architecture policies found alice # [ 3.846268] tsc: Refined TSC clocksource calibration: 3593.241 MHz alice # [ 3.847262] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x33cb625a3eb, max_idle_ns: 440795286221 ns bob # [ 3.865761] PM: Magic number: 14:68:37 bob # [ 3.886421] RAS: Correctable Errors collector initialized. alice # [ 3.901244] Demotion targets for Node 0: null alice # [ 3.906669] Key type .fscrypt registered alice # [ 3.907140] Key type fscrypt-provisioning registered alice # [ 3.919209] ima: No TPM chip found, activating TPM-bypass! alice # [ 3.919584] ima: Allocated hash algorithm: sha1 alice # [ 3.930369] ima: No architecture policies found alice # [ 3.946484] PM: Magic number: 14:68:37 alice # [ 3.969258] RAS: Correctable Errors collector initialized. bob # [ 3.990549] clk: Disabling unused clocks bob # [ 3.994337] PM: genpd: Disabling unused power domains alice # [ 4.082154] clk: Disabling unused clocks alice # [ 4.084987] PM: genpd: Disabling unused power domains bob # [ 8.148802] Freeing initrd memory: 29076K alice # [ 8.199063] Freeing initrd memory: 29080K bob # [ 8.282511] Freeing unused decrypted memory: 2028K alice # [ 8.320436] Freeing unused decrypted memory: 2028K bob # [ 8.356545] Freeing unused kernel image (initmem) memory: 3656K bob # [ 8.357381] Write protecting the kernel read-only data: 32768k bob # [ 8.361269] Freeing unused kernel image (text/rodata gap) memory: 1168K bob # [ 8.362884] Freeing unused kernel image (rodata/data gap) memory: 676K alice # [ 8.409521] Freeing unused kernel image (initmem) memory: 3656K alice # [ 8.410281] Write protecting the kernel read-only data: 32768k alice # [ 8.414026] Freeing unused kernel image (text/rodata gap) memory: 1168K alice # [ 8.415469] Freeing unused kernel image (rodata/data gap) memory: 676K bob # [ 8.631958] x86/mm: Checked W+X mappings: passed, no W+X pages found. bob # [ 8.632879] Run /init as init process alice # [ 8.708241] x86/mm: Checked W+X mappings: passed, no W+X pages found. alice # [ 8.709134] Run /init as init process bob # [ 8.958590] systemd[1]: Inserted module 'autofs4' alice # [ 9.109564] systemd[1]: Inserted module 'autofs4' bob # [ 9.162389] fuse: init (API version 7.45) bob # [ 9.230458] ACPI: \_SB_.GSIG: Enabled at IRQ 22 bob # [ 9.238534] ACPI: \_SB_.GSIH: Enabled at IRQ 23 bob # [ 9.257794] ACPI: \_SB_.GSIE: Enabled at IRQ 20 bob # [ 9.262591] ACPI: \_SB_.GSIF: Enabled at IRQ 21 bob # [ 9.274600] virtiofs virtio5: discovered new tag: nix-store bob # [ 9.280157] virtiofs virtio5: virtio_fs_setup_dax: No cache capability bob # [ 9.294267] virtiofs virtio6: discovered new tag: shared bob # [ 9.297208] virtiofs virtio6: virtio_fs_setup_dax: No cache capability alice # [ 9.293627] fuse: init (API version 7.45) bob # [ 9.306440] virtiofs virtio7: discovered new tag: xchg bob # [ 9.309824] virtiofs virtio7: virtio_fs_setup_dax: No cache capability alice # [ 9.361204] ACPI: \_SB_.GSIG: Enabled at IRQ 22 alice # [ 9.369432] ACPI: \_SB_.GSIH: Enabled at IRQ 23 alice # [ 9.388040] ACPI: \_SB_.GSIE: Enabled at IRQ 20 alice # [ 9.393006] ACPI: \_SB_.GSIF: Enabled at IRQ 21 alice # [ 9.405134] virtiofs virtio5: discovered new tag: nix-store alice # [ 9.412032] virtiofs virtio5: virtio_fs_setup_dax: No cache capability alice # [ 9.421639] virtiofs virtio6: discovered new tag: shared alice # [ 9.423680] virtiofs virtio6: virtio_fs_setup_dax: No cache capability alice # [ 9.430398] virtiofs virtio7: discovered new tag: xchg alice # [ 9.433241] virtiofs virtio7: virtio_fs_setup_dax: No cache capability bob # [ 9.444996] systemd[1]: Successfully made /usr/ read-only. alice # [ 9.557027] systemd[1]: Successfully made /usr/ read-only. bob # [ 9.804575] 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 # [ 9.806169] systemd[1]: Detected virtualization qemu. bob # [ 9.806999] systemd[1]: Detected architecture x86-64. bob # [ 9.807476] systemd[1]: Running in initrd. bob # [ 9.822052] systemd[1]: Initializing machine ID from random generator. bob # [ 9.828296] systemd[1]: Hostname set to . alice # [ 9.914552] 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 # [ 9.916296] systemd[1]: Detected virtualization qemu. alice # [ 9.917340] systemd[1]: Detected architecture x86-64. alice # [ 9.917689] systemd[1]: Running in initrd. alice # [ 9.933703] systemd[1]: Initializing machine ID from random generator. alice # [ 9.943085] systemd[1]: Hostname set to . bob # [ 11.212000] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 11.378585] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 12.769412] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 12.846062] systemd[1]: Created slice Slice /system/modprobe. bob # [ 12.854418] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 12.849516] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 12.856471] systemd[1]: Expecting device /dev/disk/by-label/nixos... bob # [ 12.857678] systemd[1]: Reached target Path Units. bob # [ 12.858600] systemd[1]: Reached target Slice Units. bob # [ 12.859401] systemd[1]: Reached target Swaps. bob # [ 12.860207] systemd[1]: Reached target Timer Units. bob # [ 12.866736] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 12.871374] systemd[1]: Listening on Journal Socket (/dev/log). bob # [ 12.874276] systemd[1]: Listening on Journal Sockets. bob # [ 12.877767] systemd[1]: Listening on udev Control Socket. bob # [ 12.879878] systemd[1]: Listening on udev Kernel Socket. bob # [ 12.880933] systemd[1]: Reached target Socket Units. bob # [ 12.928966] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 12.933982] systemd[1]: Created slice Slice /system/modprobe. alice # [ 12.943161] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 12.945548] systemd[1]: Expecting device /dev/disk/by-label/nixos... alice # [ 12.947317] systemd[1]: Reached target Path Units. alice # [ 12.948423] systemd[1]: Reached target Slice Units. alice # [ 12.949485] systemd[1]: Reached target Swaps. alice # [ 12.950594] systemd[1]: Reached target Timer Units. alice # [ 12.958473] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 12.963524] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 12.967206] systemd[1]: Listening on Journal Sockets. alice # [ 12.971312] systemd[1]: Listening on udev Control Socket. alice # [ 12.974407] systemd[1]: Listening on udev Kernel Socket. alice # [ 12.975558] systemd[1]: Reached target Socket Units. bob # [ 13.003975] systemd[1]: Starting Load Kernel Module configfs... alice # [ 13.191263] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 13.249962] systemd[1]: Starting Journal Service... alice # [ 13.262929] systemd[1]: Starting Load Kernel Module configfs... alice # [ 13.454626] systemd[1]: Starting Journal Service... bob # [ 13.482793] systemd[1]: Starting Load Kernel Modules... bob # [ 13.519035] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 13.679566] systemd[1]: Starting Load Kernel Modules... bob # [ 13.708649] systemd[1]: Starting Coldplug All udev Devices... alice # [ 13.720259] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 13.910202] systemd[1]: Starting Coldplug All udev Devices... bob # [ 13.940565] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 14.133731] systemd[1]: modprobe@configfs.service: Deactivated successfully. bob # [ 14.195902] systemd[1]: Finished Load Kernel Module configfs. alice # [ 14.241559] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 14.268843] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config bob # [ 14.379987] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 14.480579] systemd[1]: modprobe@configfs.service: Deactivated successfully. alice # [ 14.541318] systemd[1]: Finished Load Kernel Module configfs. bob # [ 14.638605] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. alice # [ 14.641536] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config bob # [ 14.654820] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev alice # [ 14.778420] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 15.035412] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. alice # [ 15.052437] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev bob # [ 15.057971] systemd[1]: Finished Load Kernel Modules. bob # [ 15.208819] systemd[1]: Starting Apply Kernel Variables... bob # [ 15.447850] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 15.513562] systemd[1]: Finished Load Kernel Modules. bob # [ 15.551510] systemd-journald[66]: Collecting audit messages is disabled. bob # [ 15.661905] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 15.673649] systemd[1]: Starting Apply Kernel Variables... alice # [ 15.985362] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 16.015150] systemd-journald[66]: Collecting audit messages is disabled. alice # [ 16.171418] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 16.334042] systemd[1]: Finished Apply Kernel Variables. bob # [ 16.665673] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 16.702791] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 16.707313] systemd[1]: Reached target Local File Systems. bob # [ 16.820462] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 16.898321] systemd[1]: Finished Apply Kernel Variables. alice # [ 17.179309] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 17.205225] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 17.208573] systemd[1]: Reached target Local File Systems. alice # [ 17.298290] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 18.861969] systemd[1]: Started Journal Service. bob # [ 18.807752] systemd-modules-load[67]: Inserted module 'dm_mod' bob # [ 18.907832] systemd-modules-load[67]: Inserted module 'virtio_balloon' bob # [ 18.956641] systemd-modules-load[67]: Inserted module 'virtio_gpu' bob # [ 19.045012] systemd-udevd[73]: Using default interface naming scheme 'v261'. bob # [ 19.140462] systemd[1]: Starting Create System Files and Directories... alice # [ 19.506859] systemd-modules-load[67]: Inserted module 'dm_mod' alice # [ 19.553846] systemd-modules-load[67]: Inserted module 'virtio_balloon' alice # [ 19.571785] systemd-modules-load[67]: Inserted module 'virtio_gpu' alice # [ 19.678060] systemd[1]: Started Journal Service. alice # [ 19.940639] systemd[1]: Starting Create System Files and Directories... bob # [ 20.153238] systemd[1]: Finished Create System Files and Directories. alice # [ 20.214720] systemd-udevd[73]: Using default interface naming scheme 'v261'. alice # [ 20.701849] systemd[1]: Finished Create System Files and Directories. bob # [ 20.721767] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 21.583268] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 23.142505] systemd[1]: Finished Coldplug All udev Devices. bob # [ 23.154632] systemd[1]: Reached target System Initialization. bob # [ 23.163533] systemd[1]: Reached target Basic System. alice # [ 24.146789] systemd[1]: Finished Coldplug All udev Devices. alice # [ 24.156722] systemd[1]: Reached target System Initialization. alice # [ 24.165051] 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'. bob # [ 32.854924] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 bob # [ 32.872728] serio: i8042 KBD port at 0x60,0x64 irq 1 bob # [ 32.877636] serio: i8042 AUX port at 0x60,0x64 irq 12 bob # [ 33.673698] virtio_blk virtio2: 1/0/0 default/read/poll queues alice # [ 33.737321] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 bob # [ 33.745440] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 33.778284] serio: i8042 KBD port at 0x60,0x64 irq 1 alice # [ 33.785092] serio: i8042 AUX port at 0x60,0x64 irq 12 bob # [ 34.208728] ehci-pci 0000:00:1d.7: EHCI Host Controller bob # [ 34.214979] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 bob # [ 34.227353] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 bob # [ 34.233448] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 bob # [ 34.251040] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 bob # [ 34.251535] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 bob # [ 34.251883] usb usb1: Product: EHCI Host Controller bob # [ 34.256390] usb usb1: Manufacturer: Linux 6.18.54 ehci_hcd bob # [ 34.256691] usb usb1: SerialNumber: 0000:00:1d.7 bob # [ 34.273303] hub 1-0:1.0: USB hub found bob # [ 34.278360] hub 1-0:1.0: 6 ports detected bob # [ 34.506943] uhci_hcd 0000:00:1d.0: UHCI Host Controller bob # [ 34.507557] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 bob # [ 34.515331] uhci_hcd 0000:00:1d.0: detected 2 ports bob # [ 34.516475] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 bob # [ 34.542749] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 bob # [ 34.543335] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 bob # [ 34.543618] usb usb2: Product: UHCI Host Controller bob # [ 34.543800] usb usb2: Manufacturer: Linux 6.18.54 uhci_hcd bob # [ 34.543992] usb usb2: SerialNumber: 0000:00:1d.0 bob # [ 34.557429] usb 1-1: new high-speed USB device number 2 using ehci-pci bob # [ 34.578527] hub 2-0:1.0: USB hub found bob # [ 34.582593] hub 2-0:1.0: 2 ports detected bob # [ 34.674997] uhci_hcd 0000:00:1d.1: UHCI Host Controller bob # [ 34.682583] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 bob # [ 34.683417] uhci_hcd 0000:00:1d.1: detected 2 ports bob # [ 34.697706] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 bob # [ 34.717760] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 bob # [ 34.718387] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 bob # [ 34.718764] usb usb3: Product: UHCI Host Controller bob # [ 34.719044] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd bob # [ 34.729918] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 bob # [ 34.730640] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 bob # [ 34.730976] usb 1-1: Product: QEMU USB Tablet bob # [ 34.731322] usb 1-1: Manufacturer: QEMU bob # [ 34.731516] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 bob # [ 34.733426] usb usb3: SerialNumber: 0000:00:1d.1 bob # [ 34.752897] hub 3-0:1.0: USB hub found bob # [ 34.759310] hub 3-0:1.0: 2 ports detected bob # [ 34.813423] uhci_hcd 0000:00:1d.2: UHCI Host Controller bob # [ 34.813896] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 bob # [ 34.825425] uhci_hcd 0000:00:1d.2: detected 2 ports bob # [ 34.826451] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 bob # [ 34.849772] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 bob # [ 34.850327] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 bob # [ 34.850562] usb usb4: Product: UHCI Host Controller bob # [ 34.850753] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd bob # [ 34.850990] usb usb4: SerialNumber: 0000:00:1d.2 bob # [ 34.866768] hub 4-0:1.0: USB hub found bob # [ 34.875949] hub 4-0:1.0: 2 ports detected bob # [ 35.025419] SCSI subsystem initialized alice # [ 35.056440] virtio_blk virtio2: 1/0/0 default/read/poll queues alice # [ 35.202699] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 35.599907] ehci-pci 0000:00:1d.7: EHCI Host Controller alice # [ 35.607509] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 alice # [ 35.629361] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 alice # [ 35.638292] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 alice # [ 35.673439] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 alice # [ 35.674213] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 alice # [ 35.674665] usb usb1: Product: EHCI Host Controller alice # [ 35.682118] usb usb1: Manufacturer: Linux 6.18.54 ehci_hcd alice # [ 35.682445] usb usb1: SerialNumber: 0000:00:1d.7 alice # [ 35.709539] hub 1-0:1.0: USB hub found alice # [ 35.726055] hub 1-0:1.0: 6 ports detected alice # [ 35.797612] uhci_hcd 0000:00:1d.0: UHCI Host Controller alice # [ 35.799128] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2 alice # [ 35.800014] uhci_hcd 0000:00:1d.0: detected 2 ports alice # [ 35.828209] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 alice # [ 35.867589] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 alice # [ 35.868265] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 alice # [ 35.868501] usb usb2: Product: UHCI Host Controller alice # [ 35.868668] usb usb2: Manufacturer: Linux 6.18.54 uhci_hcd alice # [ 35.869057] usb usb2: SerialNumber: 0000:00:1d.0 alice # [ 35.914311] hub 2-0:1.0: USB hub found alice # [ 35.935198] hub 2-0:1.0: 2 ports detected alice # [ 36.020353] usb 1-1: new high-speed USB device number 2 using ehci-pci alice # [ 36.056138] uhci_hcd 0000:00:1d.1: UHCI Host Controller alice # [ 36.056517] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 alice # [ 36.057211] uhci_hcd 0000:00:1d.1: detected 2 ports alice # [ 36.066303] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 alice # [ 36.083148] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 alice # [ 36.083495] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 alice # [ 36.083670] usb usb3: Product: UHCI Host Controller alice # [ 36.084171] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd alice # [ 36.084339] usb usb3: SerialNumber: 0000:00:1d.1 alice # [ 36.107091] hub 3-0:1.0: USB hub found alice # [ 36.115177] hub 3-0:1.0: 2 ports detected alice # [ 36.180536] uhci_hcd 0000:00:1d.2: UHCI Host Controller alice # [ 36.181138] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 alice # [ 36.181462] uhci_hcd 0000:00:1d.2: detected 2 ports alice # [ 36.186040] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 alice # [ 36.186376] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 alice # [ 36.186573] usb 1-1: Product: QEMU USB Tablet alice # [ 36.186996] usb 1-1: Manufacturer: QEMU alice # [ 36.187141] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1 alice # [ 36.193622] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 alice # [ 36.216021] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 alice # [ 36.216331] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 alice # [ 36.216526] usb usb4: Product: UHCI Host Controller alice # [ 36.216665] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd alice # [ 36.217266] usb usb4: SerialNumber: 0000:00:1d.2 alice # [ 36.237497] hub 4-0:1.0: USB hub found alice # [ 36.245119] hub 4-0:1.0: 2 ports detected bob # [ 36.284895] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode bob # [ 36.287564] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) bob # [ 36.291565] ahci 0000:00:1f.2: flags: 64bit ncq only bob # [ 36.364414] scsi host0: ahci bob # [ 36.383579] scsi host1: ahci bob # [ 36.394896] scsi host2: ahci bob # [ 36.411394] scsi host3: ahci bob # [ 36.426948] scsi host4: ahci bob # [ 36.440945] scsi host5: ahci bob # [ 36.451011] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1 bob # [ 36.451509] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1 bob # [ 36.451783] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1 bob # [ 36.461316] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1 bob # [ 36.461712] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1 bob # [ 36.462016] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1 alice # [ 36.522595] SCSI subsystem initialized bob # [ 36.797921] ata5: SATA link down (SStatus 0 SControl 300) bob # [ 36.801294] ata1: SATA link down (SStatus 0 SControl 300) bob # [ 36.802568] ata2: SATA link down (SStatus 0 SControl 300) bob # [ 36.803222] ata6: SATA link down (SStatus 0 SControl 300) bob # [ 36.804179] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) bob # [ 36.812920] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 bob # [ 36.815500] ata3.00: applying bridge limits bob # [ 36.819222] ata4: SATA link down (SStatus 0 SControl 300) bob # [ 36.822203] ata3.00: configured for UDMA/100 bob # [ 36.851905] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 alice # [ 37.149703] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode alice # [ 37.152636] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) alice # [ 37.158650] ahci 0000:00:1f.2: flags: 64bit ncq only alice # [ 37.251529] scsi host0: ahci alice # [ 37.281609] scsi host1: ahci alice # [ 37.301638] scsi host2: ahci alice # [ 37.323563] scsi host3: ahci alice # [ 37.340649] scsi host4: ahci alice # [ 37.364370] scsi host5: ahci alice # [ 37.373522] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1 alice # [ 37.381153] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1 alice # [ 37.381505] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1 alice # [ 37.382090] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1 alice # [ 37.382349] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1 alice # [ 37.382537] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1 alice # [ 37.735021] ata4: SATA link down (SStatus 0 SControl 300) alice # [ 37.738903] ata2: SATA link down (SStatus 0 SControl 300) alice # [ 37.740262] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) alice # [ 37.748924] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 alice # [ 37.749370] ata3.00: applying bridge limits alice # [ 37.754338] ata5: SATA link down (SStatus 0 SControl 300) alice # [ 37.754865] ata1: SATA link down (SStatus 0 SControl 300) alice # [ 37.755468] ata6: SATA link down (SStatus 0 SControl 300) alice # [ 37.757618] ata3.00: configured for UDMA/100 alice # [ 37.778943] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 bob # [ 38.338003] systemd[1]: Starting Virtual Console Setup... bob # [ 38.555905] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 bob # [ 38.884873] (udev-worker)[151]: Network interface NamePolicy= disabled on kernel command line. bob # [ 38.919832] (udev-worker)[160]: Network interface NamePolicy= disabled on kernel command line. bob # [ 39.280472] systemd-vconsole-setup[162]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 39.322555] systemd[1]: Finished Virtual Console Setup. alice # [ 39.967056] systemd[1]: Starting Virtual Console Setup... alice # [ 40.193169] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 bob # [ 40.257816] systemd[1]: Found device /dev/disk/by-label/nixos. bob # [ 40.264889] systemd[1]: Reached target Initrd Root Device. bob # [ 40.305004] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... alice # [ 40.660720] (udev-worker)[155]: Network interface NamePolicy= disabled on kernel command line. alice # [ 40.692836] (udev-worker)[157]: Network interface NamePolicy= disabled on kernel command line. alice # [ 40.974870] systemd-vconsole-setup[161]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 41.038900] systemd[1]: Finished Virtual Console Setup. bob # [ 41.086775] systemd-fsck[179]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 41.171634] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. bob # [ 41.280001] systemd[1]: Mounting /sysroot... alice # [ 41.917444] systemd[1]: Found device /dev/disk/by-label/nixos. alice # [ 41.926975] systemd[1]: Reached target Initrd Root Device. alice # [ 41.970916] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... alice # [ 42.762426] systemd-fsck[178]: nixos: clean, 12/65536 files, 13019/262144 blocks alice # [ 42.861735] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 42.925660] systemd[1]: Mounting /sysroot... bob # [ 43.100377] EXT4-fs (vda): mounted filesystem 832f7df2-b5e7-4f5e-a126-f18ed73a4ba2 r/w with ordered data mode. Quota mode: none. bob # [ 43.123497] systemd[1]: Mounted /sysroot. bob # [ 43.131576] systemd[1]: Reached target Initrd Root File System. bob # [ 43.227465] systemd[1]: Mounting /sysroot/nix/.ro-store... bob # [ 43.294679] systemd[1]: Mounting /sysroot/nix/.rw-store... bob # [ 43.405552] systemd[1]: Mounting /sysroot/run... bob # [ 43.557833] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 43.731514] systemd[1]: Mounting /sysroot/tmp/xchg... bob # [ 43.920608] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 44.788376] EXT4-fs (vda): mounted filesystem ebb0667e-e011-4bbb-beb5-5513edf13328 r/w with ordered data mode. Quota mode: none. alice # [ 44.845404] systemd[1]: Mounted /sysroot. alice # [ 44.858787] systemd[1]: Reached target Initrd Root File System. bob # [ 44.886851] systemd-sysroot-fstab-check[203]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 44.967549] systemd[1]: Mounting /sysroot/nix/.ro-store... alice # [ 45.056283] systemd[1]: Mounting /sysroot/nix/.rw-store... alice # [ 45.193720] systemd[1]: Mounting /sysroot/run... bob # [ 45.305935] systemd[1]: Mounted /sysroot/nix/.ro-store. bob # [ 45.311918] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 45.318922] systemd[1]: Mounted /sysroot/run. bob # [ 45.328529] systemd[1]: Mounted /sysroot/tmp/shared. bob # [ 45.338523] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 45.367885] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 45.462564] systemd[1]: Reload requested from client PID 203 ('systemd-sysroot') (unit initrd-parse-etc.service)... bob # [ 45.466993] systemd[1]: Reloading... alice # [ 45.565569] systemd[1]: Mounting /sysroot/tmp/xchg... alice # [ 45.788501] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 46.700621] systemd-sysroot-fstab-check[202]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 46.904567] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 46.909514] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 46.915560] systemd[1]: Mounted /sysroot/run. alice # [ 46.922794] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 46.930760] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 47.019553] systemd[1]: Reload requested from client PID 202 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 47.028717] systemd[1]: Reloading... bob # [ 47.037671] systemd[1]: Reloading finished in 1554 ms. bob # [ 47.162442] systemd-sysroot-fstab-check[203]: Requesting initrd-fs.target/start/replace... bob # [ 47.196524] systemd-sysroot-fstab-check[203]: Requesting swap.target/start/replace... bob # [ 47.243568] systemd[1]: initrd-parse-etc.service: Deactivated successfully. bob # [ 47.250662] systemd[1]: Finished Mountpoints Configured in the Real Root. bob # [ 47.255873] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. bob # [ 47.297624] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 47.640465] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 47.656405] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 47.706574] systemd[1]: Mounting /sysroot/nix/store... bob # [ 48.233547] systemd[1]: Mounted /sysroot/nix/store. bob # [ 48.252278] systemd[1]: Reached target Initrd File Systems. bob # [ 48.285525] systemd[1]: Starting Find NixOS closure... bob # [ 48.353606] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 48.720567] hid: raw HID events driver (C) Jiri Kosina alice # [ 48.941927] systemd[1]: Reloading finished in 1897 ms. bob # [ 48.939697] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 49.068870] usbcore: registered new interface driver usbhid bob # [ 48.985643] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob # [ 49.078286] usbhid: USB HID core driver alice # [ 49.097442] systemd-sysroot-fstab-check[202]: Requesting initrd-fs.target/start/replace... alice # [ 49.125447] systemd-sysroot-fstab-check[202]: Requesting swap.target/start/replace... alice # [ 49.164569] systemd[1]: initrd-parse-etc.service: Deactivated successfully. alice # [ 49.172788] systemd[1]: Finished Mountpoints Configured in the Real Root. alice # [ 49.177933] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 49.226538] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 49.397360] systemd[1]: Finished Find NixOS closure. bob # [ 49.408573] systemd[1]: Reached target Initrd Default Target. bob # [ 49.442455] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 49.614293] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 49.634813] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 49.699608] systemd[1]: Mounting /sysroot/nix/store... bob # [ 49.935936] systemd[1]: Stopped target Initrd Default Target. bob # [ 49.946703] systemd[1]: Stopped target Basic System. bob # [ 49.956987] systemd[1]: Stopped target Initrd Root Device. bob # [ 49.966273] systemd[1]: Stopped target Path Units. bob # [ 49.973495] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. bob # [ 49.992546] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. bob # [ 50.007656] systemd[1]: Stopped target Slice Units. bob # [ 50.020507] systemd[1]: Stopped target Socket Units. bob # [ 50.031513] systemd[1]: Stopped target System Initialization. bob # [ 50.041436] systemd[1]: Stopped target Swaps. bob # [ 50.051621] systemd[1]: Stopped target Timer Units. bob # [ 50.060571] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 50.072546] systemd[1]: Closed D-Bus System Message Bus Socket. bob # [ 50.084641] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 50.095606] systemd[1]: Stopped Find NixOS closure. bob # [ 50.150495] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 50.155819] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 50.176591] systemd[1]: Stopped Apply Kernel Variables. bob # [ 50.199929] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 50.217571] systemd[1]: Stopped Load Kernel Modules. bob # [ 50.237008] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. bob # [ 50.257569] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. bob # [ 50.278728] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 50.298980] systemd[1]: Stopped Create System Files and Directories. bob # [ 50.329468] systemd[1]: Stopped target Local File Systems. bob # [ 50.350620] systemd[1]: Stopped target Preparation for Local File Systems. bob # [ 50.364624] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 50.387430] systemd[1]: Mounted /sysroot/nix/store. alice # [ 50.400232] systemd[1]: Reached target Initrd File Systems. bob # [ 50.412519] systemd[1]: Stopped Coldplug All udev Devices. bob # [ 50.421202] systemd[1]: systemd-udev-trigger.service: Consumed 3.104s CPU time over 36.758s wall clock time, 3M memory peak. alice # [ 50.446514] systemd[1]: Starting Find NixOS closure... bob # [ 50.432657] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 50.442525] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 50.451946] systemd[1]: Stopped Virtual Console Setup. alice # [ 50.514000] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 50.729567] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 50.743935] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 50.768455] systemd[1]: initrd-cleanup.service: Deactivated successfully. bob # [ 50.782015] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. bob # [ 51.021253] systemd[1]: systemd-udevd.service: Deactivated successfully. bob # [ 51.028767] systemd[1]: Stopped Rule-based Manager for Device Events and Files. bob # [ 51.033657] systemd[1]: systemd-udevd.service: Consumed 17.440s CPU time over 34.296s wall clock time, 17.5M memory peak. bob # [ 51.044612] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. bob # [ 51.049937] systemd[1]: Closed udev Control Socket. bob # [ 51.065609] systemd[1]: Starting Cleanup udev Database... bob # [ 51.068543] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 51.074643] systemd[1]: Stopped Create Static Device Nodes in /dev. bob # [ 51.082603] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. bob # [ 51.087371] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. bob # [ 51.095641] systemd[1]: kmod-static-nodes.service: Deactivated successfully. bob # [ 51.099867] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 51.221612] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. alice # [ 51.274533] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob # [ 51.283376] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 51.290962] systemd[1]: Finished Cleanup udev Database. bob # [ 51.299626] systemd[1]: Reached target Switch Root. bob # [ 51.317610] systemd[1]: Starting NixOS Activation... alice # [ 51.679906] systemd[1]: Finished Find NixOS closure. alice # [ 51.687592] systemd[1]: Reached target Initrd Default Target. alice # [ 51.726294] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... bob # [ 52.253545] initrd-nixos-activation-start[266]: booting system configuration /nix/store/slcgdwycnq2ijgwi3lrqwi05rqj4n7dw-nixos-system-bob-test alice # [ 52.299305] systemd[1]: Stopped target Initrd Default Target. alice # [ 52.315631] systemd[1]: Stopped target Basic System. alice # [ 52.327410] systemd[1]: Stopped target Initrd Root Device. alice # [ 52.341595] systemd[1]: Stopped target Path Units. alice # [ 52.349536] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 52.369551] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. alice # [ 52.389509] systemd[1]: Stopped target Slice Units. alice # [ 52.399001] systemd[1]: Stopped target Socket Units. alice # [ 52.413518] systemd[1]: Stopped target System Initialization. alice # [ 52.427729] systemd[1]: Stopped target Swaps. alice # [ 52.441203] systemd[1]: Stopped target Timer Units. alice # [ 52.452539] systemd[1]: dbus.socket: Deactivated successfully. alice # [ 52.463649] systemd[1]: Closed D-Bus System Message Bus Socket. alice # [ 52.479672] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. alice # [ 52.492732] systemd[1]: Stopped Find NixOS closure. alice # [ 52.563874] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 52.570316] systemd[1]: systemd-sysctl.service: Deactivated successfully. alice # [ 52.592729] systemd[1]: Stopped Apply Kernel Variables. alice # [ 52.618756] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 52.611273] initrd-nixos-activation-start[266]: running activation script... alice # [ 52.644657] systemd[1]: Stopped Load Kernel Modules. alice # [ 52.684908] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 52.699692] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. alice # [ 52.728628] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. alice # [ 52.749719] systemd[1]: Stopped Create System Files and Directories. alice # [ 52.787298] systemd[1]: Stopped target Local File Systems. alice # [ 52.812651] systemd[1]: Stopped target Preparation for Local File Systems. alice # [ 52.827935] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 52.856741] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 52.874740] systemd[1]: systemd-udev-trigger.service: Consumed 3.260s CPU time over 39.009s wall clock time, 3M memory peak. alice # [ 52.899612] systemd[1]: Stopping Rule-based Manager for Device Events and Files... alice # [ 52.910647] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 52.919638] systemd[1]: Stopped Virtual Console Setup. alice # [ 53.231646] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 53.250588] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 53.282523] systemd[1]: initrd-cleanup.service: Deactivated successfully. alice # [ 53.299591] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. alice # [ 53.702941] systemd[1]: systemd-udevd.service: Deactivated successfully. alice # [ 53.713479] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 53.717763] systemd[1]: systemd-udevd.service: Consumed 17.673s CPU time over 36.477s wall clock time, 17.6M memory peak. alice # [ 53.728933] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 53.734607] systemd[1]: Closed udev Control Socket. alice # [ 53.752684] systemd[1]: Starting Cleanup udev Database... alice # [ 53.756936] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. alice # [ 53.762519] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 53.769619] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. alice # [ 53.774563] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. alice # [ 53.781759] systemd[1]: kmod-static-nodes.service: Deactivated successfully. alice # [ 53.786956] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 53.997536] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. alice # [ 54.006854] systemd[1]: Finished Cleanup udev Database. alice # [ 54.014988] systemd[1]: Reached target Switch Root. alice # [ 54.035800] systemd[1]: Starting NixOS Activation... alice # [ 55.149710] initrd-nixos-activation-start[255]: booting system configuration /nix/store/gxn57vlvq5ihqqh0m09lqj84hadgp8sl-nixos-system-alice-test bob # [ 55.509556] initrd-nixos-activation-start[289]: setting up /etc... alice # [ 55.548384] initrd-nixos-activation-start[255]: running activation script... bob # [ 57.052864] initrd-nixos-activation-start[289]: setting up age secrets... bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. bob # [ 58.161767] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 58.168606] systemd[1]: Finished NixOS Activation. bob # [ 58.174758] systemd[1]: initrd-nixos-activation.service: Consumed 5.326s CPU time over 6.835s wall clock time, 26.5M memory peak. bob # [ 58.198726] systemd[1]: Starting Switch Root... bob # [ 58.387477] systemd[1]: Switching root. bob # [ 58.771843] systemd-journald[66]: Received SIGTERM from PID 1 (systemd). alice # [ 58.800825] initrd-nixos-activation-start[278]: setting up /etc... bob # [ 59.670925] NET: Registered PF_VSOCK protocol family alice # [ 60.154739] initrd-nixos-activation-start[278]: setting up age secrets... bob # [ 60.336276] 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 # [ 60.337862] systemd[1]: Detected virtualization qemu. bob # [ 60.338877] systemd[1]: Detected architecture x86-64. bob # [ 60.340469] systemd[1]: Detected first boot. bob # [ 60.360954] systemd[1]: Initializing machine ID from random generator. alice # [ 61.006809] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. alice # [ 61.014775] systemd[1]: Finished NixOS Activation. alice # [ 61.018193] systemd[1]: initrd-nixos-activation.service: Consumed 5.385s CPU time over 6.970s wall clock time, 26.5M memory peak. alice # [ 61.038613] systemd[1]: Starting Switch Root... alice # [ 61.197467] systemd[1]: Switching root. alice # [ 61.512810] systemd-journald[66]: Received SIGTERM from PID 1 (systemd). bob # [ 61.622897] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 62.239752] NET: Registered PF_VSOCK protocol family alice # [ 62.842956] 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 # [ 62.844195] systemd[1]: Detected virtualization qemu. alice # [ 62.844989] systemd[1]: Detected architecture x86-64. alice # [ 62.846180] systemd[1]: Detected first boot. alice # [ 62.863508] systemd[1]: Initializing machine ID from random generator. bob # [ 62.976849] systemd[1]: Applying preset policy. alice # [ 63.992863] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 65.146984] systemd[1]: Applying preset policy. bob # [ 65.276972] systemd[1]: Populated /etc with preset unit settings. alice # [ 66.879204] systemd[1]: Populated /etc with preset unit settings. bob # [ 68.930222] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 68.936244] systemd[1]: Stopped initrd-switch-root.service. bob # [ 68.966754] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 68.984304] systemd[1]: Created slice Slice /system/getty. bob # [ 68.994300] systemd[1]: Created slice User and Session Slice. bob # [ 68.998235] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 69.000155] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 69.001703] systemd[1]: Expecting device /dev/hvc0... bob # [ 69.002307] systemd[1]: Expecting device /dev/ttyS0... bob # [ 69.002924] systemd[1]: Reached target Local Encrypted Volumes. bob # [ 69.004110] systemd[1]: Stopped target initrd-fs.target. bob # [ 69.004899] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 69.005748] systemd[1]: Stopped target initrd-switch-root.target. bob # [ 69.006681] systemd[1]: Reached target Virtual Machines and Containers. bob # [ 69.007665] systemd[1]: Reached target Path Units. bob # [ 69.008389] systemd[1]: Reached target Remote File Systems. bob # [ 69.008885] systemd[1]: Reached target Slice Units. bob # [ 69.009572] systemd[1]: Reached target Swaps. bob # [ 69.036909] systemd[1]: Listening on Query the User Interactively for a Password. bob # [ 69.063924] systemd[1]: Listening on Process Core Dump Socket. bob # [ 69.084939] systemd[1]: Listening on Credential Encryption/Decryption. bob # [ 69.110938] systemd[1]: Listening on Factory Reset Management. bob # [ 69.113287] systemd[1]: Listening on Hostname Service Socket. bob # [ 69.150815] systemd[1]: Starting Journal Log Access Socket... bob # [ 69.171958] systemd[1]: Listening on Journal Audit Socket. bob # [ 69.202934] systemd[1]: Listening on Console Output Muting Service Socket. bob # [ 69.206447] systemd[1]: Listening on Network Management Metrics Varlink Socket. bob # [ 69.210564] systemd[1]: Listening on Network Management Varlink Socket. bob # [ 69.214413] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 69.218399] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 69.220697] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os bob # [ 69.221712] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki bob # [ 69.266348] systemd[1]: Listening on Disk Repartitioning Service Socket. bob # [ 69.268810] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 69.270994] systemd[1]: Listening on Resolve Service Varlink Socket. bob # [ 69.273156] systemd[1]: Listening on udev Control Socket. bob # [ 69.275577] systemd[1]: Listening on udev Varlink Socket. bob # [ 69.310244] systemd[1]: Mounting Huge Pages File System... bob # [ 69.365393] systemd[1]: Mounting POSIX Message Queue File System... bob # [ 69.445343] systemd[1]: Mounting Kernel Debug File System... bob # [ 69.550384] systemd[1]: Mounting Kernel Trace File System... bob # [ 69.694780] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 69.879782] systemd[1]: Starting Load Kernel Module configfs... bob # [ 69.893909] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm bob # [ 69.908736] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore bob # [ 69.922736] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 69.980489] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 69.985363] systemd[1]: Stopped initrd-switch-root.service. alice # [ 70.015056] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. alice # [ 70.028725] systemd[1]: Created slice Slice /system/getty. alice # [ 70.037632] systemd[1]: Created slice User and Session Slice. alice # [ 70.041386] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 70.043180] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 70.044478] systemd[1]: Expecting device /dev/hvc0... alice # [ 70.045084] systemd[1]: Expecting device /dev/ttyS0... alice # [ 70.045504] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 70.046355] systemd[1]: Stopped target initrd-fs.target. alice # [ 70.047023] systemd[1]: Stopped target initrd-root-fs.target. alice # [ 70.047473] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 70.048178] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 70.048892] systemd[1]: Reached target Path Units. alice # [ 70.049333] systemd[1]: Reached target Remote File Systems. alice # [ 70.049857] systemd[1]: Reached target Slice Units. alice # [ 70.050334] systemd[1]: Reached target Swaps. alice # [ 70.077223] systemd[1]: Listening on Query the User Interactively for a Password. alice # [ 70.107205] systemd[1]: Listening on Process Core Dump Socket. bob # [ 70.126527] systemd[1]: Mounting FUSE Control File System... alice # [ 70.130641] systemd[1]: Listening on Credential Encryption/Decryption. bob # [ 70.152516] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 alice # [ 70.157537] systemd[1]: Listening on Factory Reset Management. alice # [ 70.159829] systemd[1]: Listening on Hostname Service Socket. alice # [ 70.195565] systemd[1]: Starting Journal Log Access Socket... alice # [ 70.215587] systemd[1]: Listening on Journal Audit Socket. alice # [ 70.250818] systemd[1]: Listening on Console Output Muting Service Socket. alice # [ 70.254148] systemd[1]: Listening on Network Management Metrics Varlink Socket. alice # [ 70.258051] systemd[1]: Listening on Network Management Varlink Socket. alice # [ 70.261535] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 70.265208] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. alice # [ 70.267593] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 70.268644] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki alice # [ 70.313058] systemd[1]: Listening on Disk Repartitioning Service Socket. alice # [ 70.315475] systemd[1]: Listening on Resolve Monitor Varlink Socket. alice # [ 70.318064] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 70.320210] systemd[1]: Listening on udev Control Socket. alice # [ 70.322572] systemd[1]: Listening on udev Varlink Socket. alice # [ 70.364129] systemd[1]: Mounting Huge Pages File System... alice # [ 70.425237] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 70.519259] systemd[1]: Mounting Kernel Debug File System... alice # [ 70.646388] systemd[1]: Mounting Kernel Trace File System... bob # [ 70.708405] systemd[1]: Starting Journal Service... alice # [ 70.819563] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 70.991630] systemd[1]: Starting Load Kernel Module configfs... alice # [ 71.008468] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 71.028500] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore alice # [ 71.040459] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 71.291586] systemd[1]: Mounting FUSE Control File System... alice # [ 71.309642] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 alice # [ 71.805624] systemd[1]: Starting Journal Service... bob # [ 71.831947] systemd[1]: Starting Load Kernel Modules... alice # [ 71.999655] systemd[1]: Starting Load Kernel Modules... bob # [ 72.055823] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... bob # [ 72.221771] systemd[1]: Starting Remount Root and Kernel File Systems... bob # [ 72.231953] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 72.322280] systemd-journald[382]: Collecting audit messages is enabled. bob # [ 72.468140] systemd[1]: Starting Coldplug All udev Devices... bob # [ 72.863532] systemd[1]: Queued start job for default target Multi-User System. bob # [ 72.881745] systemd[1]: systemd-journald.service: Deactivated successfully. bob # [ 72.892974] systemd[1]: systemd-journald.service: Consumed 2.213s CPU time over 55.640s wall clock time, 3.6M memory peak. bob # [ 73.085880] systemd[1]: Started Journal Service. alice # [ 73.093379] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... bob # [ 73.044737] systemd[1]: Listening on Journal Log Access Socket. bob # [ 73.051209] systemd[1]: Mounted Huge Pages File System. bob # [ 73.053000] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 73.057572] systemd[1]: Mounted Kernel Debug File System. bob # [ 73.071970] systemd[1]: Mounted Kernel Trace File System. bob # [ 73.083809] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 73.108393] systemd[1]: modprobe@configfs.service: Deactivated successfully. bob # [ 73.121546] systemd[1]: Finished Load Kernel Module configfs. bob # [ 73.130671] systemd[1]: Mounted FUSE Control File System. alice # [ 73.350351] systemd[1]: Starting Remount Root and Kernel File Systems... alice # [ 73.552048] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 73.846175] systemd[1]: Starting Coldplug All udev Devices... bob # [ 73.904758] systemd[1]: Mounting Kernel Configuration File System... alice # [ 74.152461] systemd[1]: Listening on Journal Log Access Socket. alice # [ 74.167657] systemd[1]: Mounted Huge Pages File System. alice # [ 74.187800] systemd[1]: Mounted POSIX Message Queue File System. alice # [ 74.210532] systemd[1]: Mounted Kernel Debug File System. bob # [ 74.125752] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 74.216255] systemd[1]: Mounted Kernel Trace File System. alice # [ 74.240462] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 74.254761] loop: module loaded bob # [ 74.229610] systemd-modules-load[384]: Inserted module 'loop' bob # [ 74.386747] systemd[1]: Finished Load Kernel Modules. alice # [ 74.481997] systemd[1]: modprobe@configfs.service: Deactivated successfully. alice # [ 74.499589] systemd[1]: Finished Load Kernel Module configfs. alice # [ 74.511528] systemd[1]: Mounted FUSE Control File System. bob # [ 74.542462] EXT4-fs (vda): re-mounted 832f7df2-b5e7-4f5e-a126-f18ed73a4ba2. alice # [ 74.736253] systemd[1]: Mounting Kernel Configuration File System... alice # [ 74.808578] systemd-journald[364]: Collecting audit messages is enabled. bob # [ 74.741758] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 75.048694] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 75.148010] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 75.304095] loop: module loaded bob # [ 75.276753] systemd[1]: Starting Firewall... alice # [ 75.568116] systemd[1]: Finished Load Kernel Modules. bob # [ 75.583839] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 75.627622] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore alice # [ 75.817694] systemd[1]: Queued start job for default target Multi-User System. alice # [ 75.862535] systemd[1]: systemd-journald.service: Deactivated successfully. alice # [ 75.951499] systemd[1]: Starting Firewall... alice # [ 75.895688] systemd[1]: systemd-journald.service: Consumed 2.273s CPU time over 56.474s wall clock time, 3.6M memory peak. alice # [ 75.970927] EXT4-fs (vda): re-mounted ebb0667e-e011-4bbb-beb5-5513edf13328. alice # [ 75.971488] systemd-modules-load[365]: Inserted module 'loop' bob # [ 76.096481] systemd[1]: Starting Load/Save OS Random Seed... alice # [ 76.406273] systemd[1]: Starting Apply Kernel Variables... bob # [ 76.415786] systemd[1]: Starting Apply Kernel Variables... bob # [ 76.429880] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 76.789101] systemd[1]: Started Journal Service. bob # [ 76.915735] systemd[1]: Mounted Kernel Configuration File System. bob # [ 77.153760] systemd-oomd[385]: No swap; memory pressure usage will be degraded bob # [ 77.225961] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 77.400108] systemd-journald[382]: Received client request to flush runtime journal. alice # [ 77.457960] systemd[1]: Finished Remount Root and Kernel File Systems. alice # [ 77.878680] systemd[1]: Mounted Kernel Configuration File System. alice # [ 77.994802] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 78.770001] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 78.864377] systemd[1]: Starting Flush Journal to Persistent Storage... alice # [ 78.874441] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore alice # [ 79.117677] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 79.210619] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. alice # [ 79.228644] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 79.244353] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 79.272626] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 79.304892] systemd[1]: Finished Load/Save OS Random Seed. bob # [ 79.323906] systemd[1]: Reached target First Boot Complete. bob # [ 79.340556] systemd[1]: Finished Flush Journal to Persistent Storage. alice # [ 79.447855] systemd-oomd[367]: No swap; memory pressure usage will be degraded bob # [ 79.459596] systemd[1]: Finished Apply Kernel Variables. bob # [ 79.686327] systemd[1]: Starting Network Name Resolution... alice # [ 79.865841] systemd[1]: Finished Apply Kernel Variables. bob # [ 80.026567] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 80.066850] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 80.152488] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 80.338975] systemd[1]: Mounting /run/wrappers... alice # [ 80.561250] systemd-journald[364]: Received client request to flush runtime journal. bob # [ 80.555486] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 81.877568] systemd[1]: Mounted /run/wrappers. bob # [ 81.906430] systemd[1]: Reached target Local File Systems. bob # [ 82.045463] systemd[1]: Listening on Boot Loader Control Service Socket. bob # [ 82.177032] systemd[1]: Starting register-nix-paths.service... bob # [ 82.420707] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 82.448705] systemd[1]: Starting Network Name Resolution... bob # [ 82.443890] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. alice # [ 82.503798] systemd[1]: Finished Load/Save OS Random Seed. alice # [ 82.545571] systemd[1]: Reached target First Boot Complete. alice # [ 82.561409] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 82.571871] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 82.585638] systemd[1]: Mounting /run/wrappers... alice # [ 82.600358] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 82.692499] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 82.819670] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 83.305405] systemd[1]: Starting Create System Files and Directories... alice # [ 84.004589] systemd[1]: Mounted /run/wrappers. alice # [ 84.039678] systemd[1]: Reached target Local File Systems. alice # [ 84.184579] systemd[1]: Listening on Boot Loader Control Service Socket. bob # [ 84.181372] systemd-udevd[426]: Using default interface naming scheme 'v261'. alice # [ 84.329995] systemd[1]: Starting register-nix-paths.service... alice # [ 84.597678] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 84.639554] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. bob # [ 84.880700] systemd-resolved[416]: Positive Trust Anchors: bob # [ 84.933701] systemd-resolved[416]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d bob # [ 84.967393] systemd-resolved[416]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 bob # [ 85.021550] systemd-resolved[416]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 85.138352] systemd[1]: Starting Save Transient machine-id to Disk... alice # [ 85.545960] systemd[1]: Starting Create System Files and Directories... bob # [ 85.590850] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. bob # [ 85.630876] systemd[1]: Finished Save Transient machine-id to Disk. alice # [ 86.020794] systemd-resolved[395]: Positive Trust Anchors: alice # [ 86.059458] systemd-resolved[395]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d alice # [ 86.087786] systemd-resolved[395]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 alice # [ 86.107466] systemd-resolved[395]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 86.802597] systemd-udevd[409]: Using default interface naming scheme 'v261'. alice # [ 87.578671] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. alice # [ 87.659671] systemd[1]: Finished Save Transient machine-id to Disk. bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. bob # [ 88.495376] systemd[1]: Finished Create System Files and Directories. bob # [ 88.894756] systemd[1]: Starting Rebuild Journal Catalog... bob # [ 89.348601] systemd[1]: Starting Record System Boot/Shutdown in UTMP... bob # [ 89.575906] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 90.225841] systemd[1]: Finished Create System Files and Directories. alice # [ 90.558543] systemd[1]: Starting Rebuild Journal Catalog... alice # [ 90.892807] systemd[1]: Starting Record System Boot/Shutdown in UTMP... alice # [ 91.066714] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 92.403533] systemd[1]: Finished Record System Boot/Shutdown in UTMP. alice # [ 93.389706] systemd[1]: Finished Record System Boot/Shutdown in UTMP. bob # [ 94.620825] systemd[1]: Finished Rebuild Journal Catalog. alice # [ 94.804649] systemd-resolved[395]: Using system hostname 'alice'. bob # [ 94.856439] systemd[1]: Starting Update is Completed... alice # [ 95.272929] systemd[1]: Started Network Name Resolution. alice # [ 95.340784] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 95.380873] systemd-resolved[416]: Using system hostname 'bob'. alice # [ 95.561870] systemd[1]: Starting Update is Completed... bob # [ 95.957868] systemd[1]: Started Network Name Resolution. bob # [ 96.423797] systemd[1]: Finished Update is Completed. alice # [ 96.888981] systemd[1]: Finished Update is Completed. bob # [ 98.874964] systemd[1]: Finished Coldplug All udev Devices. bob # [ 99.179654] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2 bob # [ 99.641171] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0 alice # [ 100.382736] systemd[1]: Finished Coldplug All udev Devices. bob # [ 100.585459] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray bob # [ 100.620426] cdrom: Uniform CD-ROM driver Revision: 3.20 bob # [ 100.925377] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. bob # [ 100.944534] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 100.959908] systemd[1]: suid-sgid-wrappers.service: Consumed 2.054s CPU time over 18.485s wall clock time, 3M memory peak. alice # [ 101.164971] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. alice # [ 101.202575] systemd[1]: Finished Create SUID/SGID Wrappers. alice # [ 101.221029] systemd[1]: suid-sgid-wrappers.service: Consumed 1.859s CPU time over 16.582s wall clock time, 2.9M memory peak. alice # [ 103.074686] systemd[1]: Finished register-nix-paths.service. alice # [ 103.085768] systemd[1]: Reached target System Initialization. alice # [ 103.118730] systemd[1]: Started Discard unused filesystem blocks once a week. alice # [ 103.129569] systemd[1]: Started Daily Cleanup of Temporary Directories. alice # [ 103.142731] systemd[1]: Reached target Timer Units. alice # [ 103.159901] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 103.173007] systemd[1]: Listening on Nix Daemon Socket. alice # [ 103.189341] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 103.206944] systemd[1]: Reached target Socket Units. alice # [ 103.215724] systemd[1]: Reached target Basic System. alice # [ 103.277718] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 103.458660] systemd[1]: Starting Name Service Cache Daemon (nsncd)... alice # [ 103.718421] systemd[1]: Starting Post-Boot Actions... bob # [ 103.853592] systemd[1]: Finished register-nix-paths.service. bob # [ 103.868681] systemd[1]: Reached target System Initialization. bob # [ 103.912602] systemd[1]: Started Discard unused filesystem blocks once a week. bob # [ 103.929462] systemd[1]: Started Daily Cleanup of Temporary Directories. bob # [ 103.946006] systemd[1]: Reached target Timer Units. bob # [ 103.962884] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 104.044890] systemd[1]: Started Reset console on configuration changes. bob # [ 104.093387] systemd[1]: Listening on harmonia binary cache socket. bob # [ 104.110222] systemd[1]: Listening on Nix Daemon Socket. bob # [ 104.130400] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. bob # [ 104.141737] systemd[1]: Reached target Socket Units. bob # [ 104.156820] systemd[1]: Reached target Basic System. bob # [ 104.242640] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 104.464527] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 104.731463] systemd[1]: Starting Post-Boot Actions... alice # [ 105.115212] systemd[1]: Starting D-Bus System Message Bus... bob # [ 105.147730] systemd[1]: Started Reset console on configuration changes. alice # [ 105.486715] systemd[1]: Finished Post-Boot Actions. alice # [ 105.647685] systemd[1]: Started Name Service Cache Daemon (nsncd). alice # [ 105.709631] nsncd[564]: Oct 17 17:02:07.062 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" alice # [ 105.829609] systemd[1]: Finished Import lastlog data into lastlog2 database. alice # [ 105.873493] systemd[1]: Reached target Host and Network Name Lookups. alice # [ 105.889898] systemd[1]: Reached target User and Group Name Lookups. alice # [ 106.056718] systemd[1]: Starting User Login Management... bob # [ 106.417872] systemd[1]: Starting D-Bus System Message Bus... bob # [ 106.975791] systemd[1]: Finished Post-Boot Actions. alice # [ 107.155488] systemd[1]: Finished Firewall. alice # [ 107.169686] systemd[1]: Reached target Preparation for Network. alice # [ 107.190718] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # [ 107.181913] systemd[1]: Started Name Service Cache Daemon (nsncd). bob # [ 107.253556] nsncd[589]: Oct 17 17:02:08.498 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" alice # [ 107.303609] dbus-broker-launch[579]: Looking up NSS user entry for 'systemd-timesync'... alice # [ 107.367660] systemd[1]: Starting Network Management... bob # [ 107.495983] systemd[1]: Finished Import lastlog data into lastlog2 database. bob # [ 107.527458] systemd[1]: Reached target Host and Network Name Lookups. alice # [ 107.549688] dbus-broker-launch[579]: NSS returned no entry for 'systemd-timesync' bob # [ 107.546363] systemd[1]: Reached target User and Group Name Lookups. alice # [ 107.563588] dbus-broker-launch[579]: Invalid user-name in /nix/store/1kbqa9c6s0kj2n8kzdx9vg2scyfjfsvf-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" bob # [ 107.753877] systemd[1]: Starting User Login Management... alice # [ 107.772418] systemd[1]: Started D-Bus System Message Bus. bob # [ 108.498973] systemd[1]: Finished Firewall. bob # [ 108.537733] systemd[1]: Reached target Preparation for Network. bob # [ 108.573579] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # [ 108.801822] systemd[1]: Starting Network Management... alice # [ 108.940907] dbus-broker-launch[579]: Ready bob # [ 109.070947] dbus-broker-launch[603]: Looking up NSS user entry for 'systemd-timesync'... bob # [ 109.351572] dbus-broker-launch[603]: NSS returned no entry for 'systemd-timesync' bob # [ 109.367499] dbus-broker-launch[603]: Invalid user-name in /nix/store/kv2gwcd73wcsja59kawg0rarkyfbp69x-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 109.589969] systemd-logind[598]: New seat seat0. alice # [ 109.669820] systemd[1]: Started User Login Management. bob # [ 109.698759] systemd[1]: Started D-Bus System Message Bus. alice # [ 109.785304] systemd[1]: Starting linger-users.service... alice # [ 110.845188] hid: raw HID events driver (C) Jiri Kosina alice # [ 110.978703] systemd[1]: linger-users.service: Deactivated successfully. alice # [ 110.992538] systemd[1]: Finished linger-users.service. bob # [ 110.982487] dbus-broker-launch[603]: Ready alice # [ 111.195306] usbcore: registered new interface driver usbhid alice # [ 111.195550] usbhid: USB HID core driver bob # [ 111.921895] systemd-logind[623]: New seat seat0. bob # [ 112.049334] systemd[1]: Started User Login Management. bob # [ 112.212955] systemd[1]: Starting linger-users.service... alice # [ 112.374492] cfg80211: Loading compiled-in X.509 certificates for regulatory database alice # [ 112.579169] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' alice # [ 112.583439] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' alice # [ 112.598449] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 alice # [ 112.599411] cfg80211: failed to load regulatory.db alice # [ 112.943944] systemd-networkd[611]: lo: Link UP alice # [ 112.955460] systemd-networkd[611]: lo: Gained carrier alice # [ 113.118466] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray alice # [ 113.095932] systemd[1]: Started Network Management. alice # [ 113.219036] cdrom: Uniform CD-ROM driver Revision: 3.20 alice # [ 113.166430] systemd[1]: Reached target Network. alice # [ 113.244876] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... bob # [ 113.296016] systemd[1]: linger-users.service: Deactivated successfully. bob # [ 113.315690] systemd[1]: Finished linger-users.service. alice # [ 113.363635] systemd[1]: Starting Permit User Sessions... alice # [ 114.064224] systemd[1]: Finished Permit User Sessions. alice # [ 114.130407] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. alice # [ 114.247615] systemd[1]: Started Getty on tty1. alice # [ 114.271427] systemd[1]: Reached target Login Prompts. bob # [ 114.654310] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 114.929625] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' bob # [ 114.934220] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 114.953820] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 bob # [ 114.955938] cfg80211: failed to load regulatory.db bob # [ 115.462953] systemd-networkd[630]: lo: Link UP bob # [ 115.478388] systemd-networkd[630]: lo: Gained carrier bob # [ 115.661563] systemd[1]: Started Network Management. bob # [ 115.739312] systemd[1]: Reached target Network. alice # [ 115.799867] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 115.834563] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... bob # [ 116.008586] systemd[1]: Starting Permit User Sessions... bob # [ 116.985739] systemd[1]: Finished Permit User Sessions. bob # [ 117.163587] systemd[1]: Started Getty on tty1. bob # [ 117.211997] systemd[1]: Reached target Login Prompts. bob # [ 117.283721] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. bob: Guest root shell did not produce any data yet... bob: To debug, enter the VM and run 'systemctl status backdoor.service'. alice # [ 117.999742] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. alice # [ 118.656967] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. bob # [ 118.720436] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 118.746006] systemd[1]: Started backdoor.service. alice # [ 118.782512] systemd[1]: Reached target Multi-User System. alice # [ 118.813577] systemd[1]: Startup finished in 8.789s (kernel) + 53.069s (initrd) + 56.946s (userspace) = 1min 58.804s. alice # [ 119.840935] (udev-worker)[664]: Network interface NamePolicy= disabled on kernel command line. alice # [ 119.917582] (udev-worker)[659]: Network interface NamePolicy= disabled on kernel command line. alice # connecting to host... alice # [ 120.553813] systemd-networkd[611]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. alice # [ 120.803551] systemd-networkd[611]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 120.822724] systemd-networkd[611]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 120.938545] systemd-networkd[611]: eth0: Link UP alice # [ 120.977520] systemd-networkd[611]: eth0: Gained carrier alice # [ 121.239875] NET: Registered PF_PACKET protocol family alice # [ 121.226868] systemd-networkd[611]: eth1: Link UP alice # [ 121.392617] systemd-networkd[611]: eth1: Gained carrier alice # [ 121.397990] systemd-networkd[611]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 121.418595] systemd-networkd[611]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 121.470392] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. bob # [ 122.239570] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. alice # [ 122.270638] systemd-networkd[611]: eth0: Gained IPv6LL bob # [ 122.341931] systemd[1]: Started backdoor.service. bob # [ 122.388502] systemd[1]: Reached target Multi-User System. bob # [ 122.422861] systemd[1]: Startup finished in 8.695s (kernel) + 50.517s (initrd) + 1min 3.202s (userspace) = 2min 2.414s. alice # [ 122.506899] systemd-networkd[611]: eth1: Gained IPv6LL bob # [ 123.372014] (udev-worker)[689]: Network interface NamePolicy= disabled on kernel command line. bob # [ 123.407838] (udev-worker)[684]: Network interface NamePolicy= disabled on kernel command line. bob # connecting to host... bob: Guest shell says: b'Spawning backdoor root shell...\n' bob: connected to guest root shell bob: (connecting took 126.41 seconds) bob: (finished: waiting for the VM to finish booting, in 126.41 seconds) bob # [ 124.342790] systemd-networkd[630]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. bob # [ 124.619697] systemd-networkd[630]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 124.638781] systemd-networkd[630]: eth1: Configuring with /etc/systemd/network/40-eth1.network. bob # [ 124.766583] systemd-networkd[630]: eth0: Link UP bob # [ 124.829920] systemd-networkd[630]: eth0: Gained carrier bob # [ 125.246274] NET: Registered PF_PACKET protocol family bob # [ 125.248537] systemd-networkd[630]: eth1: Link UP bob # [ 125.294684] systemd-networkd[630]: eth1: Gained carrier bob # [ 125.307015] systemd-networkd[630]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 125.550948] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 alice # [ 125.580481] ACPI: button: Power Button [PWRF] alice # [ 125.686401] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console alice # [ 125.703627] Console: switching to colour dummy device 80x25 bob # [ 125.641232] systemd-networkd[630]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 alice # [ 125.723141] [drm] Found bochs VGA, ID 0xb0c5. alice # [ 125.723380] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. alice # [ 125.752501] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic alice # [ 125.752942] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 bob # [ 125.863496] systemd-networkd[630]: eth0: Gained IPv6LL alice # [ 125.970513] Console: switching to colour frame buffer device 160x50 alice # [ 125.996466] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device bob # [ 126.709378] systemd-networkd[630]: eth1: Gained IPv6LL bob # % Total % Received % Xferd Average Speed Time Time Time Current bob # Dload Upload Total Spent Left Speed bob # 0 0 0 0 0 0 0 0 0[ 127.320745] systemd[1]: Starting harmonia binary cache service... alice # [ 127.956614] rtc_cmos PNP0B00:00: RTC can wake from S4 alice # [ 128.020837] rtc_cmos PNP0B00:00: registered as rtc0 alice # [ 128.036246] rtc_cmos PNP0B00:00: setting system clock to 2026-10-17T17:02:30 UTC (1792256550) alice # [ 127.986499] systemd-resolved[395]: Clock change detected. Flushing caches. alice # [ 128.075175] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs alice # [ 128.088348] parport_pc 00:02: reported by Plug and Play ACPI alice # [ 128.099415] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] alice # [ 128.510176] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input3 alice # [ 128.604309] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized alice # [ 129.042281] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt alice # [ 129.062150] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD bob # 0 0 0 0 0 0 0 0 00:01 0 0 0 0 0 0 0 0 0 00:02 0[ 130.030523] harmonia-cache[704]: 2026-10-17T17:02:31.449609Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000)) bob # [ 130.049470] harmonia-cache[704]: 2026-10-17T17:02:31.468456Z INFO actix_server::builder: starting 4 workers bob # [ 130.080452] harmonia-cache[704]: 2026-10-17T17:02:31.488449Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s bob # [ 130.095615] systemd[1]: Started harmonia binary cache service. bob # [ 130.102657] harmonia-cache[704]: 2026-10-17T17:02:31.506121Z INFO actix_server::server: Actix runtime found; starting in Actix runtime bob # [ 130.108537] harmonia-cache[704]: 2026-10-17T17:02:31.506648Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:5000 bob # 0 0 0 0 0 0 0 0 00:03 0100 51 100 51 0 0 11 0 00:04 00:04 14100 51 100 51 0 0 11 0 00:04 00:04 14100 51 100 51 0 0 11 0 00:04 00:04 14 bob: (finished: waiting for success: curl bob:5000/nix-cache-info, in 133.19 seconds) bob: waiting for unit harmonia alice # [ 131.711502] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 bob: (finished: waiting for unit harmonia, in 1.54 seconds) bob: must succeed: systemctl status harmonia bob: (finished: must succeed: systemctl status harmonia, in 0.84 seconds) alice: waiting for success: curl bob:5000/nix-cache-info alice: waiting for the VM to finish booting alice: Guest shell says: b'Spawning backdoor root shell...\n' alice: connected to guest root shell alice: (connecting took 0.00 seconds) alice: (finished: waiting for the VM to finish booting, in 0.00 seconds) alice # % Total % Received % Xferd Average Speed Time Time Time Current alice # Dload Upload Total Spent Left Speed alice # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 140 0 0100 51 100 51 0 0 138 0 0100 51 100 51 0 0 136 0 0 alice: (finished: waiting for success: curl bob:5000/nix-cache-info, in 1.32 seconds) bob: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "x86_64-linux"; } ' 2>&1 alice # [ 134.932678] systemd[1]: Starting Virtual Console Setup... alice # [ 135.417171] systemd-logind[598]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) alice # [ 135.970195] kvm_amd: Nested Virtualization enabled alice # [ 135.980301] kvm_amd: Nested Paging enabled alice # [ 135.984150] kvm_amd: Virtual GIF supported alice # [ 135.984472] kvm_amd: PMU virtualization is disabled bob # [ 137.276556] systemd-resolved[416]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. bob # [ 137.336239] systemd-resolved[416]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. bob # [ 137.372990] systemd-resolved[416]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 137.808696] systemd-resolved[416]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. bob # [ 137.831724] systemd-resolved[416]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 137.883662] systemd-resolved[416]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. bob # [ 137.909244] systemd-resolved[416]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 137.958898] systemd-vconsole-setup[735]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 138.217758] systemd[1]: Finished Virtual Console Setup. bob # [ 138.664894] systemd-resolved[416]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. bob # [ 138.681696] systemd-resolved[416]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 138.736718] systemd-resolved[416]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. bob # [ 138.760731] systemd-resolved[416]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 139.711022] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 140.081658] systemd-resolved[416]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. bob # [ 140.097695] systemd-resolved[416]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 140.148727] systemd-resolved[416]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. bob # [ 140.174513] systemd-resolved[416]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 140.711530] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input5 bob # [ 140.771316] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 bob # [ 140.803340] ACPI: button: Power Button [PWRF] alice # [ 140.804892] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0 bob # [ 140.900616] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console bob # [ 140.923255] Console: switching to colour dummy device 80x25 bob # [ 140.940635] [drm] Found bochs VGA, ID 0xb0c5. bob # [ 140.940884] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. bob # [ 140.980241] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic bob # [ 140.980514] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 alice # [ 140.910761] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 140.929544] systemd[1]: Stopped Virtual Console Setup. alice # [ 140.948816] systemd[1]: Stopping Virtual Console Setup... alice # [ 141.057784] systemd-logind[598]: Watching system buttons on /dev/input/event1 (Power Button) alice # [ 141.219844] systemd[1]: Starting Virtual Console Setup... bob # [ 141.849401] Console: switching to colour frame buffer device 160x50 bob # [ 141.934692] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device bob # [ 142.588615] systemd-resolved[416]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. bob # [ 142.603699] systemd-resolved[416]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 142.667568] systemd-resolved[416]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. bob # [ 142.698748] systemd-resolved[416]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 143.808677] systemd-vconsole-setup[751]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 143.865893] systemd[1]: Finished Virtual Console Setup. alice # [ 144.348526] ppdev: user-space parallel port driver bob # [ 144.831396] rtc_cmos PNP0B00:00: RTC can wake from S4 bob # [ 144.980535] rtc_cmos PNP0B00:00: registered as rtc0 bob # [ 144.945793] systemd-resolved[416]: Clock change detected. Flushing caches. bob # [ 145.041447] rtc_cmos PNP0B00:00: setting system clock to 2026-10-17T17:02:47 UTC (1792256567) bob # [ 145.127328] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs bob # [ 145.159244] parport_pc 00:02: reported by Plug and Play ACPI bob # [ 145.191227] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] bob: (finished: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "x86_64-linux"; } ' 2>&1 , in 11.26 seconds) alice: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "x86_64-linux"; } ' 2>&1 alice # [ 147.029049] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) alice # [ 147.101821] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) bob # [ 147.169502] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4 bob # [ 147.320502] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized alice # [ 147.498031] mousedev: PS/2 mouse device common for all mice alice # [ 147.782185] systemd-logind[598]: Watching system buttons on /dev/input/event2 (QEMU Virtio Keyboard) bob # [ 148.718325] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt bob # [ 148.729387] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD alice # [ 150.007817] systemd-resolved[395]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. alice # [ 150.343785] systemd-resolved[395]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 150.382539] systemd-resolved[395]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 150.426764] systemd-resolved[395]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 150.447592] systemd-resolved[395]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 150.937231] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input5 alice # [ 151.145905] systemd-resolved[395]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 151.165207] systemd-resolved[395]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 151.215633] systemd-resolved[395]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 151.238325] systemd-resolved[395]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 152.409785] systemd-resolved[395]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 152.418611] systemd-resolved[395]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 152.446980] systemd-resolved[395]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 152.461269] systemd-resolved[395]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 153.580332] systemd[1]: Starting Virtual Console Setup... alice # [ 155.304937] systemd-resolved[395]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 155.312911] systemd-resolved[395]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 155.341715] systemd-resolved[395]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 155.352925] systemd-resolved[395]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 155.445450] mousedev: PS/2 mouse device common for all mice bob # [ 155.932812] kvm_amd: Nested Virtualization enabled bob # [ 155.933433] kvm_amd: Nested Paging enabled bob # [ 156.013608] kvm_amd: Virtual GIF supported bob # [ 156.013888] kvm_amd: PMU virtualization is disabled bob # [ 156.175750] systemd-vconsole-setup[828]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 156.238833] systemd[1]: Finished Virtual Console Setup. alice: (finished: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "x86_64-linux"; } ' 2>&1 , in 10.74 seconds) (finished: run the VM test script, in 159.47 seconds) test script finished in 161.19s 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-10-17T17:03:01Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-10-17T17:03:01Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-10-17T17:03:01Z 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-10-17T17:03:01Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-10-17T17:03:01Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-10-17T17:03:01Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.32 seconds)