vm-test-run-harmonia
checks.x86_64-linux.harmonia
· build #2
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.06 seconds)4Test will time out and terminate in 3600.0 seconds5run the VM test script6additionally exposed symbols:7 alice, bob,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10start all VMs11alice: starting vm12bob: starting vm13bob # Disk image does not exist, creating the virtualisation disk image...14bob # Formatting '/build/vm-state-bob/tmp.WEcoEdA9aj', fmt=raw size=107374182415bob # mke2fs 1.47.4 (6-Mar-2025)16bob # Discarding device blocks: 0/262144 done17bob # Creating filesystem with 262144 4k blocks and 65536 inodes18bob # Filesystem UUID: efbdb221-7984-49e3-aca8-0c197f53f61a19bob # Superblock backups stored on blocks:20bob # 32768, 98304, 163840, 22937621bob # 22bob # Allocating group tables: 0/8 done23bob # Writing inode tables: 0/8 done24bob # Creating journal (8192 blocks): done25bob # Writing superblocks and filesystem accounting information: 0/8 done26bob # 27bob # Virtualisation disk image created.28bob # Starting virtiofs daemons...29bob # [2026-09-22T17:35:53Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)30bob # [2026-09-22T17:35:53Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether31bob # [2026-09-22T17:35:53Z INFO virtiofsd] Waiting for vhost-user socket connection...32bob # [2026-09-22T17:35:53Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)33bob # [2026-09-22T17:35:53Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether34bob # [2026-09-22T17:35:53Z INFO virtiofsd] Waiting for vhost-user socket connection...35bob # [2026-09-22T17:35:53Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)36bob # [2026-09-22T17:35:53Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether37bob # [2026-09-22T17:35:53Z INFO virtiofsd] Waiting for vhost-user socket connection...38bob # [2026-09-22T17:35:53Z INFO virtiofsd] Client connected, servicing requests39bob # [2026-09-22T17:35:53Z INFO virtiofsd] Client connected, servicing requests40bob # [2026-09-22T17:35:53Z INFO virtiofsd] Client connected, servicing requests41bob # qemu-system-x86_64: Could not access KVM kernel module: No such file or directory42bob # qemu-system-x86_64: failed to initialize kvm: No such file or directory43bob # qemu-system-x86_64: falling back to tcg44bob: QEMU running (pid 51)45alice # Disk image does not exist, creating the virtualisation disk image...46alice # Formatting '/build/vm-state-alice/tmp.WUiYCsm7jg', fmt=raw size=107374182447alice # mke2fs 1.47.4 (6-Mar-2025)48alice # Discarding device blocks: 0/262144 done49alice # Creating filesystem with 262144 4k blocks and 65536 inodes50alice # Filesystem UUID: 5c4819f4-710d-4f30-88fd-e0e492c9ffc251alice # Superblock backups stored on blocks:52alice # 32768, 98304, 163840, 22937653alice # 54alice # Allocating group tables: 0/8 done55alice # Writing inode tables: 0/8 done56alice # Creating journal (8192 blocks): done57alice # Writing superblocks and filesystem accounting information: 0/8 done58alice # 59alice # Virtualisation disk image created.60alice # Starting virtiofs daemons...61alice # [2026-09-22T17:35:53Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)62alice # [2026-09-22T17:35:53Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether63alice # [2026-09-22T17:35:53Z INFO virtiofsd] Waiting for vhost-user socket connection...64alice # [2026-09-22T17:35:53Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)65alice # [2026-09-22T17:35:53Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether66alice # [2026-09-22T17:35:53Z INFO virtiofsd] Waiting for vhost-user socket connection...67alice # [2026-09-22T17:35:53Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)68alice # [2026-09-22T17:35:53Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether69alice # [2026-09-22T17:35:53Z INFO virtiofsd] Waiting for vhost-user socket connection...70alice # [2026-09-22T17:35:53Z INFO virtiofsd] Client connected, servicing requests71alice # [2026-09-22T17:35:53Z INFO virtiofsd] Client connected, servicing requests72alice # [2026-09-22T17:35:53Z INFO virtiofsd] Client connected, servicing requests73alice # qemu-system-x86_64: Could not access KVM kernel module: No such file or directory74alice # qemu-system-x86_64: failed to initialize kvm: No such file or directory75alice # qemu-system-x86_64: falling back to tcg76alice: QEMU running (pid 46)77(finished: start all VMs, in 4.19 seconds)78bob: waiting for success: curl bob:5000/nix-cache-info79bob: waiting for the VM to finish booting80bob # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)81alice # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)82bob # 83bob # 84bob # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC750+3EF2C750 CA0085bob # Press Ctrl-B to configure iPXE (PCI 00:02.0)...86bob # 87bob # 88bob # 89bob # 90bob # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC750 3EF2C750 CB0091alice # 92alice # 93bob # Press Ctrl-B to configure iPXE (PCI 00:05.0)...94bob # 95bob # 96alice # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFC8600+3EF28600 CA0097alice # Press Ctrl-B to configure iPXE (PCI 00:02.0)...98alice # 99alice # 100alice # 101alice # 102alice # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFC8600 3EF28600 CB00Press Ctrl-B to configure iPXE (PCI 00:05.0)...103alice # 104alice # 105bob # Booting from ROM...106bob # Probing EDD (edd=off to disable)... ok107alice # Booting from ROM...108bob # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Mon Sep 14 11:36:19 UTC 2026109bob # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/p8hwydl4ilpb66vfs3pdc12192v1qv90-nixos-system-bob-test/init regInfo=/nix/.ro-store/j7hg3r4lmk2jfr15d55rq2jc2sjizg7d-closure-info/registration console=ttyS0,115200n8 console=tty0110bob # [ 0.000000] BIOS-provided physical RAM map:111bob # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable112bob # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved113bob # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved114bob # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable115bob # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved116bob # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved117bob # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved118bob # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved119bob # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved120bob # [ 0.000000] NX (Execute Disable) protection: active121bob # [ 0.000000] APIC: Static calls initialized122bob # [ 0.000000] SMBIOS 2.8 present.123bob # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014124bob # [ 0.000000] DMI: Memory slots populated: 1/1125bob # [ 0.000000] tsc: Fast TSC calibration failed126bob # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000127bob # [ 0.000000] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs128bob # [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT129bob # [ 0.000000] found SMP MP-table at [mem 0x000f5450-0x000f545f]130bob # [ 0.000000] Using GB pages for direct mapping131bob # [ 0.000000] RAMDISK: [mem 0x3e36d000-0x3ffcffff]132bob # [ 0.000000] ACPI: Early table checksum verification disabled133bob # [ 0.000000] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )134bob # [ 0.000000] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)135bob # [ 0.000000] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)136bob # [ 0.000000] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)137bob # [ 0.000000] ACPI: FACS 0x000000003FFE0000 000040138bob # [ 0.000000] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)139bob # [ 0.000000] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)140bob # [ 0.000000] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)141bob # [ 0.000000] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)142bob # [ 0.000000] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]143bob # [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]144bob # [ 0.000000] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]145bob # [ 0.000000] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]146bob # [ 0.000000] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]147bob # [ 0.000000] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]148bob # [ 0.000000] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]149bob # [ 0.000000] No NUMA configuration found150bob # [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]151bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]152bob # [ 0.000000] Zone ranges:153bob # [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff]154bob # [ 0.000000] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]155bob # [ 0.000000] Normal empty156bob # [ 0.000000] Device empty157bob # [ 0.000000] Movable zone start for each node158bob # [ 0.000000] Early memory node ranges159bob # [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff]160bob # [ 0.000000] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]161bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]162bob # [ 0.000000] On node 0, zone DMA: 1 pages in unavailable ranges163bob # [ 0.000000] On node 0, zone DMA: 97 pages in unavailable ranges164bob # [ 0.000000] On node 0, zone DMA32: 40 pages in unavailable ranges165bob # [ 0.000000] ACPI: PM-Timer IO Port: 0x608166bob # [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])167bob # [ 0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23168bob # [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)169bob # [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)170bob # [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)171bob # [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)172bob # [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)173bob # [ 0.000000] ACPI: Using ACPI (MADT) for SMP configuration information174bob # [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000175bob # [ 0.000000] CPU topo: Max. logical packages: 1176bob # [ 0.000000] CPU topo: Max. logical dies: 1177bob # [ 0.000000] CPU topo: Max. dies per package: 1178bob # [ 0.000000] CPU topo: Max. threads per core: 1179bob # [ 0.000000] CPU topo: Num. cores per package: 1180bob # [ 0.000000] CPU topo: Num. threads per package: 1181bob # [ 0.000000] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs182bob # [ 0.000000] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]183bob # [ 0.000000] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]184bob # [ 0.000000] [mem 0x40000000-0xafffffff] available for PCI devices185bob # [ 0.000000] Booting paravirtualized kernel on bare hardware186bob # [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns187bob # [ 0.000000] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1188bob # [ 0.000000] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152189bob # [ 0.000000] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/p8hwydl4ilpb66vfs3pdc12192v1qv90-nixos-system-bob-test/init regInfo=/nix/.ro-store/j7hg3r4lmk2jfr15d55rq2jc2sjizg7d-closure-info/registration console=ttyS0,115200n8 console=tty0190bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/j7hg3r4lmk2jfr15d55rq2jc2sjizg7d-closure-info/registration", will be passed to user space.191bob # [ 0.000000] random: crng init done192bob # [ 0.000000] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes193bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)194bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)195bob # [ 0.000000] Fallback order for Node 0: 0196alice # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Mon Sep 14 11:36:19 UTC 2026197alice # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/dm25ifzai99pkp763r7wv9b38z3i6w0n-nixos-system-alice-test/init regInfo=/nix/.ro-store/f14fnyp3hll108i4nmqldhzm1qlpc32a-closure-info/registration console=ttyS0,115200n8 console=tty0198alice # [ 0.000000] BIOS-provided physical RAM map:199alice # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable200alice # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved201bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262006202bob # [ 0.000000] Policy zone: DMA32203bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off204bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1205bob # [ 0.000000] allocated 2097152 bytes of page_ext206bob # [ 0.000000] ftrace: allocating 48787 entries in 192 pages207bob # [ 0.000000] ftrace: allocated 192 pages with 2 groups208bob # [ 0.000000] Dynamic Preempt: lazy209bob # [ 0.000000] rcu: Preemptible hierarchical RCU implementation.210bob # [ 0.000000] rcu: RCU event tracing is enabled.211bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.212bob # [ 0.000000] Trampoline variant of Tasks RCU enabled.213bob # [ 0.000000] Rude variant of Tasks RCU enabled.214bob # [ 0.000000] Tracing variant of Tasks RCU enabled.215bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.216alice # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved217alice # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable218alice # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved219alice # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved220alice # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved221alice # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved222alice # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved223alice # [ 0.000000] NX (Execute Disable) protection: active224alice # [ 0.000000] APIC: Static calls initialized225alice # [ 0.000000] SMBIOS 2.8 present.226alice # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014227alice # [ 0.000000] DMI: Memory slots populated: 1/1228alice # [ 0.000000] tsc: Fast TSC calibration failed229alice # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000230alice # [ 0.000000] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs231alice # [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT232alice # [ 0.000000] found SMP MP-table at [mem 0x000f5450-0x000f545f]233alice # [ 0.000000] Using GB pages for direct mapping234alice # [ 0.000000] RAMDISK: [mem 0x3e36a000-0x3ffcffff]235alice # [ 0.000000] ACPI: Early table checksum verification disabled236alice # [ 0.000000] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )237alice # [ 0.000000] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)238bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1239bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.240bob # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.241bob # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.242bob # [ 0.000000] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16243bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.244bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns245bob # [ 0.000000] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)246bob # [ 0.000000] Console: colour VGA+ 80x25247bob # [ 0.000000] printk: legacy console [tty0] enabled248bob # [ 0.000000] printk: legacy console [ttyS0] enabled249alice # [ 0.000000] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)250alice # [ 0.000000] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)251alice # [ 0.000000] ACPI: FACS 0x000000003FFE0000 000040252alice # [ 0.000000] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)253alice # [ 0.000000] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)254alice # [ 0.000000] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)255alice # [ 0.000000] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)256alice # [ 0.000000] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]257alice # [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]258alice # [ 0.000000] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]259alice # [ 0.000000] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]260alice # [ 0.000000] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]261alice # [ 0.000000] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]262alice # [ 0.000000] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]263alice # [ 0.000000] No NUMA configuration found264alice # [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]265alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]266alice # [ 0.000000] Zone ranges:267alice # [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff]268alice # [ 0.000000] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]269alice # [ 0.000000] Normal empty270alice # [ 0.000000] Device empty271alice # [ 0.000000] Movable zone start for each node272bob # [ 0.000000] ACPI: Core revision 20250807273alice # [ 0.000000] Early memory node ranges274alice # [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff]275alice # [ 0.000000] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]276alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]277alice # [ 0.000000] On node 0, zone DMA: 1 pages in unavailable ranges278alice # [ 0.000000] On node 0, zone DMA: 97 pages in unavailable ranges279alice # [ 0.000000] On node 0, zone DMA32: 40 pages in unavailable ranges280alice # [ 0.000000] ACPI: PM-Timer IO Port: 0x608281alice # [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])282alice # [ 0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23283alice # [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)284alice # [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)285alice # [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)286alice # [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)287alice # [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)288alice # [ 0.000000] ACPI: Using ACPI (MADT) for SMP configuration information289alice # [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000290alice # [ 0.000000] CPU topo: Max. logical packages: 1291alice # [ 0.000000] CPU topo: Max. logical dies: 1292alice # [ 0.000000] CPU topo: Max. dies per package: 1293alice # [ 0.000000] CPU topo: Max. threads per core: 1294alice # [ 0.000000] CPU topo: Num. cores per package: 1295alice # [ 0.000000] CPU topo: Num. threads per package: 1296alice # [ 0.000000] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs297alice # [ 0.000000] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]298alice # [ 0.000000] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]299alice # [ 0.000000] [mem 0x40000000-0xafffffff] available for PCI devices300alice # [ 0.000000] Booting paravirtualized kernel on bare hardware301alice # [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns302alice # [ 0.000000] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1303alice # [ 0.000000] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152304alice # [ 0.000000] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/dm25ifzai99pkp763r7wv9b38z3i6w0n-nixos-system-alice-test/init regInfo=/nix/.ro-store/f14fnyp3hll108i4nmqldhzm1qlpc32a-closure-info/registration console=ttyS0,115200n8 console=tty0305alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/f14fnyp3hll108i4nmqldhzm1qlpc32a-closure-info/registration", will be passed to user space.306alice # [ 0.000000] random: crng init done307alice # [ 0.000000] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes308alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)309alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)310alice # [ 0.000000] Fallback order for Node 0: 0311alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262006312alice # [ 0.000000] Policy zone: DMA32313alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off314alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1315alice # [ 0.000000] allocated 2097152 bytes of page_ext316alice # [ 0.000000] ftrace: allocating 48787 entries in 192 pages317alice # [ 0.000000] ftrace: allocated 192 pages with 2 groups318alice # [ 0.000000] Dynamic Preempt: lazy319alice # [ 0.000000] rcu: Preemptible hierarchical RCU implementation.320alice # [ 0.000000] rcu: RCU event tracing is enabled.321alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.322alice # [ 0.000000] Trampoline variant of Tasks RCU enabled.323alice # [ 0.000000] Rude variant of Tasks RCU enabled.324alice # [ 0.000000] Tracing variant of Tasks RCU enabled.325alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.326alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1327alice # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.328alice # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.329alice # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.330alice # [ 0.000000] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16331alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.332alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns333alice # [ 0.000000] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)334alice # [ 0.000000] Console: colour VGA+ 80x25335alice # [ 0.000000] printk: legacy console [tty0] enabled336alice # [ 0.000000] printk: legacy console [ttyS0] enabled337alice # [ 0.000000] ACPI: Core revision 20250807338bob # [ 0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns339alice # [ 0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns340bob # [ 0.007000] APIC: Switch to symmetric I/O mode setup341bob # [ 0.010000] x2apic: IRQ remapping doesn't support X2APIC mode342alice # [ 0.003000] APIC: Switch to symmetric I/O mode setup343alice # [ 0.003000] x2apic: IRQ remapping doesn't support X2APIC mode344bob # [ 0.025000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1345alice # [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1346bob # [ 0.035000] tsc: Unable to calibrate against PIT347bob # [ 0.038000] tsc: using HPET reference calibration348bob # [ 0.040000] tsc: Detected 3593.306 MHz processor349bob # [ 0.001670] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x33cb9f4386f, max_idle_ns: 440795240352 ns350alice # [ 0.015000] tsc: Unable to calibrate against PIT351alice # [ 0.017000] tsc: using HPET reference calibration352bob # [ 0.009557] Calibrating delay loop (skipped), value calculated using timer frequency.. 7186.61 BogoMIPS (lpj=3593306)353alice # [ 0.017000] tsc: Detected 3593.343 MHz processor354alice # [ 0.005275] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x33cbc3499fc, max_idle_ns: 440795356943 ns355alice # [ 0.012569] Calibrating delay loop (skipped), value calculated using timer frequency.. 7186.68 BogoMIPS (lpj=3593343)356bob # [ 0.022881] x86/cpu: User Mode Instruction Prevention (UMIP) activated357alice # [ 0.023531] x86/cpu: User Mode Instruction Prevention (UMIP) activated358alice # [ 0.052083] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0359alice # [ 0.052083] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0360alice # [ 0.054685] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto361alice # [ 0.055083] Speculative Store Bypass: Vulnerable362alice # [ 0.056083] Spectre V2 : Mitigation: Retpolines363alice # [ 0.058331] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization364alice # [ 0.060555] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT365alice # [ 0.075410] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'366alice # [ 0.084764] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'367alice # [ 0.086299] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'368alice # [ 0.089258] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers'369bob # [ 0.061890] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0370bob # [ 0.062101] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0371bob # [ 0.073916] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto372bob # [ 0.078101] Speculative Store Bypass: Vulnerable373bob # [ 0.079509] Spectre V2 : Mitigation: Retpolines374bob # [ 0.080101] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization375bob # [ 0.080101] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT376alice # [ 0.091468] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR'377alice # [ 0.093269] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'378alice # [ 0.097606] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256379alice # [ 0.099595] x86/fpu: xstate_offset[3]: 960, xstate_sizes[3]: 64380alice # [ 0.101263] x86/fpu: xstate_offset[4]: 1024, xstate_sizes[4]: 64381alice # [ 0.103292] x86/fpu: xstate_offset[9]: 2688, xstate_sizes[9]: 8382alice # [ 0.105374] x86/fpu: Enabled xstate features 0x21f, context size is 2696 bytes, using 'standard' format.383bob # [ 0.095654] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'384bob # [ 0.103919] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'385bob # [ 0.106284] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'386bob # [ 0.106972] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers'387bob # [ 0.108616] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR'388bob # [ 0.109789] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'389bob # [ 0.112692] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256390bob # [ 0.117352] x86/fpu: xstate_offset[3]: 960, xstate_sizes[3]: 64391bob # [ 0.119270] x86/fpu: xstate_offset[4]: 1024, xstate_sizes[4]: 64392bob # [ 0.121264] x86/fpu: xstate_offset[9]: 2688, xstate_sizes[9]: 8393bob # [ 0.122637] x86/fpu: Enabled xstate features 0x21f, context size is 2696 bytes, using 'standard' format.394bob # [ 0.830101] Freeing SMP alternatives memory: 44K395bob # [ 0.833101] pid_max: default: 32768 minimum: 301396bob # [ 0.844431] LSM: initializing lsm=capability,landlock,yama,bpf,ima397bob # [ 0.850858] landlock: Up and running.398bob # [ 0.851623] Yama: becoming mindful.399bob # [ 0.855101] LSM support for eBPF active400bob # [ 0.861313] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)401bob # [ 0.862101] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)402alice # [ 0.840766] Freeing SMP alternatives memory: 44K403alice # [ 0.843083] pid_max: default: 32768 minimum: 301404alice # [ 0.852479] LSM: initializing lsm=capability,landlock,yama,bpf,ima405alice # [ 0.859326] landlock: Up and running.406alice # [ 0.860083] Yama: becoming mindful.407alice # [ 0.865799] LSM support for eBPF active408alice # [ 0.881083] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)409alice # [ 0.881630] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)410alice # [ 0.925446] APIC calibration not consistent with PM-Timer: 102ms instead of 100ms411alice # [ 0.926083] APIC delta adjusted to PM-Timer: 6250156 (6403724)412alice # [ 0.928414] smpboot: CPU0: AMD QEMU TCG CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)413bob # [ 1.029675] APIC calibration not consistent with PM-Timer: 91ms instead of 100ms414bob # [ 1.030101] APIC delta adjusted to PM-Timer: 6249499 (5736433)415bob # [ 1.031101] smpboot: CPU0: AMD QEMU TCG CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)416bob # [ 1.073101] Performance Events: PMU not available due to virtualization, using software events only.417bob # [ 1.073382] signal: max sigframe size: 3632418alice # [ 1.054338] Performance Events: PMU not available due to virtualization, using software events only.419alice # [ 1.065582] signal: max sigframe size: 3632420bob # [ 1.080101] rcu: Hierarchical SRCU implementation.421bob # [ 1.081713] rcu: Max phase no-delay instances is 400.422alice # [ 1.081743] rcu: Hierarchical SRCU implementation.423alice # [ 1.084397] rcu: Max phase no-delay instances is 400.424bob # [ 1.109101] NMI watchdog: Perf NMI watchdog permanently disabled425bob # [ 1.111101] smp: Bringing up secondary CPUs ...426bob # [ 1.111956] smp: Brought up 1 node, 1 CPU427alice # [ 1.137496] NMI watchdog: Perf NMI watchdog permanently disabled428bob # [ 1.112101] smpboot: Total of 1 processors activated (7186.61 BogoMIPS)429alice # [ 1.153574] smp: Bringing up secondary CPUs ...430alice # [ 1.161840] smp: Brought up 1 node, 1 CPU431alice # [ 1.164664] smpboot: Total of 1 processors activated (7186.68 BogoMIPS)432bob # [ 1.112101] Memory: 941040K/1048024K available (17247K kernel code, 2727K rwdata, 13616K rodata, 3652K init, 2976K bss, 99592K reserved, 0K cma-reserved)433alice # [ 1.207289] Memory: 941076K/1048024K available (17247K kernel code, 2727K rwdata, 13616K rodata, 3652K init, 2976K bss, 99604K reserved, 0K cma-reserved)434bob # [ 1.112101] devtmpfs: initialized435bob # [ 1.112101] x86/mm: Memory block size: 128MB436alice # [ 1.245083] devtmpfs: initialized437alice # [ 1.255083] x86/mm: Memory block size: 128MB438bob # [ 1.174101] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)439bob # [ 1.176101] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).440bob # [ 1.183575] pinctrl core: initialized pinctrl subsystem441bob # [ 1.200745] PM: RTC time: 17:35:59, date: 2026-09-22442alice # [ 1.326083] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)443alice # [ 1.326083] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).444alice # [ 1.337726] pinctrl core: initialized pinctrl subsystem445alice # [ 1.359842] PM: RTC time: 17:36:00, date: 2026-09-22446bob # [ 1.260101] NET: Registered PF_NETLINK/PF_ROUTE protocol family447alice # [ 1.473413] NET: Registered PF_NETLINK/PF_ROUTE protocol family448bob # [ 1.285101] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations449bob # [ 1.287713] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations450bob # [ 1.290101] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations451bob # [ 1.292101] audit: initializing netlink subsys (disabled)452alice # [ 1.510078] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations453alice # [ 1.515458] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations454alice # [ 1.521636] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations455alice # [ 1.527372] audit: initializing netlink subsys (disabled)456bob # [ 1.301101] audit: type=2000 audit(1790098558.340:1): state=initialized audit_enabled=0 res=1457alice # [ 1.555551] audit: type=2000 audit(1790098558.558:1): state=initialized audit_enabled=0 res=1458alice # [ 1.612838] thermal_sys: Registered thermal governor 'fair_share'459alice # [ 1.613352] thermal_sys: Registered thermal governor 'bang_bang'460alice # [ 1.614380] thermal_sys: Registered thermal governor 'step_wise'461alice # [ 1.615661] thermal_sys: Registered thermal governor 'user_space'462alice # [ 1.616300] thermal_sys: Registered thermal governor 'power_allocator'463alice # [ 1.622549] cpuidle: using governor menu464bob # [ 1.332101] thermal_sys: Registered thermal governor 'fair_share'465bob # [ 1.332101] thermal_sys: Registered thermal governor 'bang_bang'466bob # [ 1.332101] thermal_sys: Registered thermal governor 'step_wise'467bob # [ 1.332101] thermal_sys: Registered thermal governor 'user_space'468bob # [ 1.332101] thermal_sys: Registered thermal governor 'power_allocator'469bob # [ 1.333833] cpuidle: using governor menu470alice # [ 1.636499] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5471bob # [ 1.342983] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5472alice # [ 1.650531] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]473alice # [ 1.654364] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry474bob # [ 1.356840] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]475bob # [ 1.359101] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry476bob # [ 1.362608] PCI: Using configuration type 1 for base access477alice # [ 1.662896] PCI: Using configuration type 1 for base access478alice # [ 1.678405] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.479bob # [ 1.380101] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.480bob # [ 1.436405] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages481bob # [ 1.443177] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page482bob # [ 1.446369] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages483alice # [ 1.749083] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages484alice # [ 1.749083] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page485alice # [ 1.749346] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages486bob # [ 1.447337] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page487alice # [ 1.756386] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page488alice # [ 1.980372] ACPI: Added _OSI(Module Device)489alice # [ 1.981471] ACPI: Added _OSI(Processor Device)490alice # [ 1.987409] ACPI: Added _OSI(Processor Aggregator Device)491bob # [ 1.699810] ACPI: Added _OSI(Module Device)492bob # [ 1.703523] ACPI: Added _OSI(Processor Device)493bob # [ 1.712503] ACPI: Added _OSI(Processor Aggregator Device)494bob # [ 1.830101] ACPI: 1 ACPI AML tables successfully acquired and loaded495bob # [ 1.854883] ACPI: Interpreter enabled496bob # [ 1.861056] ACPI: PM: (supports S0 S3 S4 S5)497bob # [ 1.862538] ACPI: Using IOAPIC for interrupt routing498alice # [ 2.348083] ACPI: 1 ACPI AML tables successfully acquired and loaded499bob # [ 1.872038] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug500bob # [ 1.874649] PCI: Using E820 reservations for host bridge windows501bob # [ 1.881670] ACPI: Enabled 2 GPEs in block 00 to 3F502alice # [ 2.449339] ACPI: Interpreter enabled503alice # [ 2.458264] ACPI: PM: (supports S0 S3 S4 S5)504alice # [ 2.460335] ACPI: Using IOAPIC for interrupt routing505alice # [ 2.477624] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug506alice # [ 2.479336] PCI: Using E820 reservations for host bridge windows507alice # [ 2.492741] ACPI: Enabled 2 GPEs in block 00 to 3F508bob # [ 2.133101] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])509bob # [ 2.133101] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]510bob # [ 2.139101] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]511bob # [ 2.152101] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]512alice # [ 2.822083] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])513alice # [ 2.825083] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]514alice # [ 2.827083] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]515bob # [ 2.153101] PCI host bridge to bus 0000:00516bob # [ 2.153101] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]517bob # [ 2.153101] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]518bob # [ 2.153101] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]519bob # [ 2.153101] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]520bob # [ 2.153101] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]521bob # [ 2.153101] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]522bob # [ 2.153879] pci_bus 0000:00: root bus resource [bus 00-ff]523bob # [ 2.154101] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint524bob # [ 2.169993] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint525bob # [ 2.171101] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]526bob # [ 2.172101] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]527bob # [ 2.173491] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]528alice # [ 2.829083] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]529bob # [ 2.175076] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]530bob # [ 2.180101] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint531bob # [ 2.181101] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]532bob # [ 2.181674] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]533bob # [ 2.183633] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]534bob # [ 2.184101] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]535bob # [ 2.189347] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint536alice # [ 2.841083] PCI host bridge to bus 0000:00537bob # [ 2.191101] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]538alice # [ 2.844083] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]539bob # [ 2.191485] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]540alice # [ 2.844526] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]541alice # [ 2.845083] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]542alice # [ 2.845083] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]543alice # [ 2.845083] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]544alice # [ 2.845083] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]545bob # [ 2.193633] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]546alice # [ 2.846606] pci_bus 0000:00: root bus resource [bus 00-ff]547bob # [ 2.197101] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint548bob # [ 2.200429] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]549bob # [ 2.202101] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]550bob # [ 2.203101] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]551alice # [ 2.858799] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint552bob # [ 2.211452] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint553bob # [ 2.214101] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]554bob # [ 2.214528] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]555bob # [ 2.215515] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]556bob # [ 2.218101] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]557bob # [ 2.222504] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint558bob # [ 2.230325] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]559bob # [ 2.232393] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]560bob # [ 2.251269] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint561bob # [ 2.256469] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]562alice # [ 2.918586] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint563bob # [ 2.260498] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]564alice # [ 2.925464] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]565alice # [ 2.927853] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]566alice # [ 2.929563] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]567alice # [ 2.937413] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]568bob # [ 2.279578] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint569bob # [ 2.285503] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]570bob # [ 2.289341] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]571alice # [ 2.960858] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint572bob # [ 2.307252] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint573alice # [ 2.964738] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]574bob # [ 2.311404] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]575alice # [ 2.967754] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]576bob # [ 2.313696] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]577alice # [ 2.969782] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]578alice # [ 2.971300] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]579bob # [ 2.329101] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint580alice # [ 2.984342] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint581alice # [ 2.988483] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]582bob # [ 2.334283] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]583alice # [ 2.989650] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]584bob # [ 2.336359] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]585alice # [ 2.992498] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]586bob # [ 2.339481] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]587alice # [ 3.007684] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint588bob # [ 2.358585] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint589alice # [ 3.011445] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]590alice # [ 3.013862] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]591alice # [ 3.015083] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]592bob # [ 2.365288] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]593bob # [ 2.367365] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]594bob # [ 2.368746] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]595alice # [ 3.030740] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint596alice # [ 3.036417] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]597bob # [ 2.388101] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint598bob # [ 2.394259] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]599alice # [ 3.039323] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]600alice # [ 3.040763] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]601alice # [ 3.043295] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]602bob # [ 2.407662] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint603bob # [ 2.411313] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]604alice # [ 3.058944] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint605alice # [ 3.062472] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]606alice # [ 3.066396] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]607bob # [ 2.425101] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint608bob # [ 2.430479] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]609alice # [ 3.083606] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint610alice # [ 3.089433] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]611alice # [ 3.090083] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]612bob # [ 2.452528] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint613bob # [ 2.458341] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]614bob # [ 2.476714] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint615bob # [ 2.484338] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO616alice # [ 3.108475] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint617bob # [ 2.501101] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint618alice # [ 3.111489] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]619alice # [ 3.116440] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]620bob # [ 2.506476] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]621alice # [ 3.130083] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint622bob # [ 2.508774] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]623alice # [ 3.130083] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]624alice # [ 3.132325] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]625bob # [ 2.529454] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint626bob # [ 2.535296] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]627alice # [ 3.138356] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint628alice # [ 3.139083] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]629alice # [ 3.140375] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]630alice # [ 3.143506] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]631alice # [ 3.164583] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint632alice # [ 3.171419] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]633bob # [ 2.595101] ACPI: PCI: Interrupt link LNKA configured for IRQ 10634alice # [ 3.183179] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]635bob # [ 2.610729] ACPI: PCI: Interrupt link LNKB configured for IRQ 10636alice # [ 3.185470] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]637bob # [ 2.618101] ACPI: PCI: Interrupt link LNKC configured for IRQ 11638bob # [ 2.627277] ACPI: PCI: Interrupt link LNKD configured for IRQ 11639alice # [ 3.204356] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint640alice # [ 3.207083] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]641bob # [ 2.651433] ACPI: PCI: Interrupt link LNKE configured for IRQ 10642alice # [ 3.229677] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint643bob # [ 2.676024] ACPI: PCI: Interrupt link LNKF configured for IRQ 10644alice # [ 3.234502] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]645bob # [ 2.703902] ACPI: PCI: Interrupt link LNKG configured for IRQ 11646alice # [ 3.250264] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint647alice # [ 3.253731] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]648bob # [ 2.723727] ACPI: PCI: Interrupt link LNKH configured for IRQ 11649alice # [ 3.261601] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint650alice # [ 3.263083] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]651bob # [ 2.734585] ACPI: PCI: Interrupt link GSIA configured for IRQ 16652bob # [ 2.738751] ACPI: PCI: Interrupt link GSIB configured for IRQ 17653alice # [ 3.269649] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint654bob # [ 2.741409] ACPI: PCI: Interrupt link GSIC configured for IRQ 18655bob # [ 2.746704] ACPI: PCI: Interrupt link GSID configured for IRQ 19656alice # [ 3.272712] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO657bob # [ 2.750536] ACPI: PCI: Interrupt link GSIE configured for IRQ 20658bob # [ 2.753926] ACPI: PCI: Interrupt link GSIF configured for IRQ 21659bob # [ 2.755373] ACPI: PCI: Interrupt link GSIG configured for IRQ 22660alice # [ 3.284551] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint661bob # [ 2.757053] ACPI: PCI: Interrupt link GSIH configured for IRQ 23662alice # [ 3.288488] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]663alice # [ 3.290643] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]664alice # [ 3.314854] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint665alice # [ 3.321294] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]666bob # [ 2.857002] iommu: Default domain type: Translated667bob # [ 2.860101] iommu: DMA domain TLB invalidation policy: lazy mode668bob # [ 2.887408] ACPI: bus type USB registered669bob # [ 2.907223] usbcore: registered new interface driver usbfs670bob # [ 2.914798] usbcore: registered new interface driver hub671bob # [ 2.922465] usbcore: registered new device driver usb672alice # [ 3.412647] ACPI: PCI: Interrupt link LNKA configured for IRQ 10673alice # [ 3.426505] ACPI: PCI: Interrupt link LNKB configured for IRQ 10674alice # [ 3.443376] ACPI: PCI: Interrupt link LNKC configured for IRQ 11675alice # [ 3.450378] ACPI: PCI: Interrupt link LNKD configured for IRQ 11676alice # [ 3.452083] ACPI: PCI: Interrupt link LNKE configured for IRQ 10677alice # [ 3.452083] ACPI: PCI: Interrupt link LNKF configured for IRQ 10678bob # [ 3.106378] NetLabel: Initializing679bob # [ 3.108406] NetLabel: domain hash size = 128680bob # [ 3.114101] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO681alice # [ 3.454772] ACPI: PCI: Interrupt link LNKG configured for IRQ 11682bob # [ 3.132101] NetLabel: unlabeled traffic allowed by default683bob # [ 3.138464] PCI: Using ACPI for IRQ routing684alice # [ 3.461083] ACPI: PCI: Interrupt link LNKH configured for IRQ 11685alice # [ 3.466991] ACPI: PCI: Interrupt link GSIA configured for IRQ 16686alice # [ 3.469083] ACPI: PCI: Interrupt link GSIB configured for IRQ 17687alice # [ 3.469083] ACPI: PCI: Interrupt link GSIC configured for IRQ 18688alice # [ 3.470083] ACPI: PCI: Interrupt link GSID configured for IRQ 19689alice # [ 3.470832] ACPI: PCI: Interrupt link GSIE configured for IRQ 20690alice # [ 3.471083] ACPI: PCI: Interrupt link GSIF configured for IRQ 21691alice # [ 3.473083] ACPI: PCI: Interrupt link GSIG configured for IRQ 22692alice # [ 3.473083] ACPI: PCI: Interrupt link GSIH configured for IRQ 23693bob # [ 3.201101] pci 0000:00:01.0: vgaarb: setting as boot VGA device694bob # [ 3.201101] pci 0000:00:01.0: vgaarb: bridge control possible695bob # [ 3.201101] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none696bob # [ 3.202452] vgaarb: loaded697bob # [ 3.210587] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0698bob # [ 3.216468] hpet0: 3 comparators, 64-bit 100.000000 MHz counter699bob # [ 3.254081] clocksource: Switched to clocksource tsc-early700alice # [ 3.523083] iommu: Default domain type: Translated701alice # [ 3.527560] iommu: DMA domain TLB invalidation policy: lazy mode702alice # [ 3.532083] ACPI: bus type USB registered703alice # [ 3.532083] usbcore: registered new interface driver usbfs704alice # [ 3.532083] usbcore: registered new interface driver hub705alice # [ 3.534083] usbcore: registered new device driver usb706bob # [ 3.350008] VFS: Disk quotas dquot_6.6.0707bob # [ 3.357438] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)708alice # [ 3.558083] NetLabel: Initializing709alice # [ 3.559028] NetLabel: domain hash size = 128710alice # [ 3.559083] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO711alice # [ 3.561551] NetLabel: unlabeled traffic allowed by default712alice # [ 3.562492] PCI: Using ACPI for IRQ routing713bob # [ 3.423894] pnp: PnP ACPI init714alice # [ 3.580083] pci 0000:00:01.0: vgaarb: setting as boot VGA device715alice # [ 3.580083] pci 0000:00:01.0: vgaarb: bridge control possible716alice # [ 3.580083] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none717alice # [ 3.581269] vgaarb: loaded718alice # [ 3.583083] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0719alice # [ 3.583267] hpet0: 3 comparators, 64-bit 100.000000 MHz counter720alice # [ 3.592083] clocksource: Switched to clocksource tsc-early721bob # [ 3.492121] ACPI: IRQ 4 override to edge(!), high(!)722bob # [ 3.512747] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved723alice # [ 3.640779] VFS: Disk quotas dquot_6.6.0724alice # [ 3.644155] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)725alice # [ 3.657104] pnp: PnP ACPI init726bob # [ 3.570935] pnp: PnP ACPI: found 5 devices727alice # [ 3.722745] ACPI: IRQ 4 override to edge(!), high(!)728alice # [ 3.738747] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved729alice # [ 3.816055] pnp: PnP ACPI: found 5 devices730bob # [ 3.887841] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns731bob # [ 3.887841] clocksource: Switched to clocksource acpi_pm732bob # [ 3.901794] NET: Registered PF_INET protocol family733bob # [ 3.924804] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)734alice # [ 4.207852] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns735alice # [ 4.211955] clocksource: Switched to clocksource acpi_pm736alice # [ 4.225624] NET: Registered PF_INET protocol family737alice # [ 4.245330] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)738bob # [ 4.150844] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)739bob # [ 4.154840] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)740bob # [ 4.154840] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)741bob # [ 4.158810] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)742bob # [ 4.158810] TCP: Hash tables configured (established 8192 bind 8192)743bob # [ 4.169888] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)744bob # [ 4.169888] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)745bob # [ 4.175736] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)746bob # [ 4.186550] NET: Registered PF_UNIX/PF_LOCAL protocol family747bob # [ 4.191182] NET: Registered PF_XDP protocol family748bob # [ 4.196316] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]749bob # [ 4.196976] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]750bob # [ 4.196976] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]751bob # [ 4.199089] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]752bob # [ 4.199520] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]753bob # [ 4.199520] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]754bob # [ 4.216823] ACPI: \_SB_.GSIA: Enabled at IRQ 16755bob # [ 4.237902] pci 0000:00:1d.0: quirk_usb_early_handoff+0x0/0x7c0 took 33631 usecs756bob # [ 4.250832] ACPI: \_SB_.GSIB: Enabled at IRQ 17757bob # [ 4.268236] pci 0000:00:1d.1: quirk_usb_early_handoff+0x0/0x7c0 took 27169 usecs758bob # [ 4.313266] ACPI: \_SB_.GSIC: Enabled at IRQ 18759bob # [ 4.334738] pci 0000:00:1d.2: quirk_usb_early_handoff+0x0/0x7c0 took 47748 usecs760bob # [ 4.356320] ACPI: \_SB_.GSID: Enabled at IRQ 19761bob # [ 4.395790] pci 0000:00:1d.7: quirk_usb_early_handoff+0x0/0x7c0 took 55046 usecs762bob # [ 4.403925] PCI: CLS 0 bytes, default 64763alice # [ 4.562051] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)764alice # [ 4.567496] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)765alice # [ 4.571702] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)766alice # [ 4.578220] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)767alice # [ 4.585784] TCP: Hash tables configured (established 8192 bind 8192)768bob # [ 4.470097] Trying to unpack rootfs image as initramfs...769alice # [ 4.607589] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)770alice # [ 4.611248] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)771alice # [ 4.612553] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)772alice # [ 4.631029] NET: Registered PF_UNIX/PF_LOCAL protocol family773alice # [ 4.635215] NET: Registered PF_XDP protocol family774alice # [ 4.639419] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]775alice # [ 4.639419] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]776alice # [ 4.641292] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]777alice # [ 4.641699] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]778alice # [ 4.641699] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]779alice # [ 4.641699] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]780alice # [ 4.662489] ACPI: \_SB_.GSIA: Enabled at IRQ 16781alice # [ 4.684630] pci 0000:00:1d.0: quirk_usb_early_handoff+0x0/0x7c0 took 39448 usecs782alice # [ 4.697609] ACPI: \_SB_.GSIB: Enabled at IRQ 17783alice # [ 4.726666] pci 0000:00:1d.1: quirk_usb_early_handoff+0x0/0x7c0 took 38564 usecs784alice # [ 4.745313] ACPI: \_SB_.GSIC: Enabled at IRQ 18785alice # [ 4.766460] pci 0000:00:1d.2: quirk_usb_early_handoff+0x0/0x7c0 took 35723 usecs786alice # [ 4.780585] ACPI: \_SB_.GSID: Enabled at IRQ 19787alice # [ 4.835562] pci 0000:00:1d.7: quirk_usb_early_handoff+0x0/0x7c0 took 64191 usecs788alice # [ 4.835795] PCI: CLS 0 bytes, default 64789alice # [ 4.865605] Trying to unpack rootfs image as initramfs...790bob # [ 5.261179] Initialise system trusted keyrings791bob # [ 5.272228] workingset: timestamp_bits=40 max_order=18 bucket_order=0792bob # [ 5.539155] tsc: Refined TSC clocksource calibration: 3593.238 MHz793bob # [ 5.541331] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x33cb5fbb942, max_idle_ns: 440795370317 ns794alice # [ 6.218106] tsc: Refined TSC clocksource calibration: 3593.237 MHz795alice # [ 6.231942] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x33cb5f13e99, max_idle_ns: 440795391341 ns796alice # [ 6.369406] Initialise system trusted keyrings797bob # [ 6.286115] Key type asymmetric registered798bob # [ 6.291889] Asymmetric key parser 'x509' registered799alice # [ 6.418073] workingset: timestamp_bits=40 max_order=18 bucket_order=0800bob # [ 6.313925] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)801bob # [ 6.342355] io scheduler mq-deadline registered802bob # [ 6.348049] io scheduler kyber registered803bob # [ 6.607262] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled804bob # [ 6.644783] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A805bob # [ 6.783436] Linux agpgart interface v0.103806bob # [ 6.783436] ACPI: bus type drm_connector registered807alice # [ 6.962574] Key type asymmetric registered808alice # [ 6.971810] Asymmetric key parser 'x509' registered809alice # [ 6.982661] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)810alice # [ 6.989375] io scheduler mq-deadline registered811alice # [ 6.991084] io scheduler kyber registered812bob # [ 6.872859] usbcore: registered new interface driver usbserial_generic813bob # [ 6.877608] usbserial: USB Serial support registered for generic814bob # [ 6.879231] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled815bob # [ 6.883827] drop_monitor: Initializing network drop monitor service816bob # [ 6.915114] NET: Registered PF_INET6 protocol family817alice # [ 7.077618] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled818alice # [ 7.109599] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A819alice # [ 7.366583] Linux agpgart interface v0.103820alice # [ 7.471943] ACPI: bus type drm_connector registered821alice # [ 7.715091] usbcore: registered new interface driver usbserial_generic822alice # [ 7.726934] usbserial: USB Serial support registered for generic823alice # [ 7.732755] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled824alice # [ 7.756820] drop_monitor: Initializing network drop monitor service825alice # [ 7.788829] NET: Registered PF_INET6 protocol family826alice # [ 7.992707] Segment Routing with IPv6827alice # [ 7.993163] In-situ OAM (IOAM) with IPv6828alice # [ 8.043669] IPI shorthand broadcast: enabled829bob # [ 8.160273] Segment Routing with IPv6830bob # [ 8.175062] In-situ OAM (IOAM) with IPv6831bob # [ 8.246851] IPI shorthand broadcast: enabled832alice # [ 8.403602] sched_clock: Marking stable (8414519156, -11916654)->(11235330503, -2832728001)833alice # [ 8.433905] registered taskstats version 1834alice # [ 8.468589] Loading compiled-in X.509 certificates835bob # [ 9.112213] sched_clock: Marking stable (9154112104, -42898595)->(12082466628, -2971253119)836bob # [ 9.149105] registered taskstats version 1837bob # [ 9.171814] Loading compiled-in X.509 certificates838alice # [ 9.439037] Demotion targets for Node 0: null839alice # [ 9.528266] Key type .fscrypt registered840alice # [ 9.547270] Key type fscrypt-provisioning registered841alice # [ 9.722841] ima: No TPM chip found, activating TPM-bypass!842alice # [ 9.735177] ima: Allocated hash algorithm: sha1843alice # [ 9.778959] ima: No architecture policies found844alice # [ 9.913899] PM: Magic number: 10:58:644845alice # [ 9.976389] RAS: Correctable Errors collector initialized.846bob # [ 10.008045] Demotion targets for Node 0: null847bob # [ 10.064253] Key type .fscrypt registered848bob # [ 10.072054] Key type fscrypt-provisioning registered849bob # [ 10.153136] ima: No TPM chip found, activating TPM-bypass!850bob # [ 10.163436] ima: Allocated hash algorithm: sha1851bob # [ 10.192444] ima: No architecture policies found852bob # [ 10.253006] PM: Magic number: 10:505:593853bob # [ 10.309695] RAS: Correctable Errors collector initialized.854alice # [ 10.442074] clk: Disabling unused clocks855alice # [ 10.449080] PM: genpd: Disabling unused power domains856bob # [ 10.906640] clk: Disabling unused clocks857bob # [ 10.915020] PM: genpd: Disabling unused power domains858bob # [ 20.112704] Freeing initrd memory: 29068K859bob # [ 20.326337] Freeing unused decrypted memory: 2028K860bob # [ 20.471426] Freeing unused kernel image (initmem) memory: 3652K861bob # [ 20.473051] Write protecting the kernel read-only data: 32768k862bob # [ 20.483643] Freeing unused kernel image (text/rodata gap) memory: 1184K863bob # [ 20.487964] Freeing unused kernel image (rodata/data gap) memory: 720K864bob # [ 20.819190] x86/mm: Checked W+X mappings: passed, no W+X pages found.865bob # [ 20.821291] Run /init as init process866bob # [ 20.963192] hrtimer: interrupt took 7230249 ns867alice # [ 21.936346] Freeing initrd memory: 29080K868alice # [ 22.215169] Freeing unused decrypted memory: 2028K869bob # [ 22.197802] systemd[1]: Inserted module 'autofs4'870alice # [ 22.379307] Freeing unused kernel image (initmem) memory: 3652K871alice # [ 22.380927] Write protecting the kernel read-only data: 32768k872alice # [ 22.391571] Freeing unused kernel image (text/rodata gap) memory: 1184K873alice # [ 22.395989] Freeing unused kernel image (rodata/data gap) memory: 720K874alice # [ 22.900852] x86/mm: Checked W+X mappings: passed, no W+X pages found.875alice # [ 22.902619] Run /init as init process876bob: Guest root shell did not produce any data yet...877bob: To debug, enter the VM and run 'systemctl status backdoor.service'.878bob # [ 23.253636] fuse: init (API version 7.45)879bob # [ 23.601858] ACPI: \_SB_.GSIG: Enabled at IRQ 22880bob # [ 23.676299] ACPI: \_SB_.GSIH: Enabled at IRQ 23881alice # [ 24.041928] hrtimer: interrupt took 1021126428 ns882alice # [ 24.052755] clocksource: Long readout interval, skipping watchdog check: cs_nsec: 1518507023 wd_nsec: 1518449240883bob # [ 23.976736] ACPI: \_SB_.GSIE: Enabled at IRQ 20884bob # [ 24.060242] ACPI: \_SB_.GSIF: Enabled at IRQ 21885bob # [ 24.148270] virtiofs virtio5: discovered new tag: nix-store886bob # [ 24.211196] virtiofs virtio5: virtio_fs_setup_dax: No cache capability887bob # [ 24.346604] virtiofs virtio6: discovered new tag: shared888bob # [ 24.356713] virtiofs virtio6: virtio_fs_setup_dax: No cache capability889bob # [ 24.448148] virtiofs virtio7: discovered new tag: xchg890bob # [ 24.494280] virtiofs virtio7: virtio_fs_setup_dax: No cache capability891alice # [ 25.932914] systemd[1]: Inserted module 'autofs4'892bob # [ 25.877048] systemd[1]: Successfully made /usr/ read-only.893bob # [ 26.337534] 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)894bob # [ 26.344798] systemd[1]: Detected virtualization qemu.895bob # [ 26.349683] systemd[1]: Detected architecture x86-64.896bob # [ 26.352842] systemd[1]: Running in initrd.897bob # [ 26.425409] systemd[1]: Initializing machine ID from random generator.898bob # [ 26.459472] systemd[1]: Hostname set to <bob>.899alice # [ 26.689581] fuse: init (API version 7.45)900alice # [ 27.052078] ACPI: \_SB_.GSIG: Enabled at IRQ 22901alice # [ 27.105969] ACPI: \_SB_.GSIH: Enabled at IRQ 23902alice # [ 27.168837] ACPI: \_SB_.GSIE: Enabled at IRQ 20903alice # [ 27.183982] ACPI: \_SB_.GSIF: Enabled at IRQ 21904alice # [ 27.300677] virtiofs virtio5: discovered new tag: nix-store905alice # [ 27.372219] virtiofs virtio5: virtio_fs_setup_dax: No cache capability906alice # [ 27.473779] virtiofs virtio6: discovered new tag: shared907alice # [ 27.502908] virtiofs virtio6: virtio_fs_setup_dax: No cache capability908alice # [ 27.545065] virtiofs virtio7: discovered new tag: xchg909alice # [ 27.575076] virtiofs virtio7: virtio_fs_setup_dax: No cache capability910alice # [ 28.276916] systemd[1]: Successfully made /usr/ read-only.911alice # [ 28.782872] 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)912alice # [ 28.785424] systemd[1]: Detected virtualization qemu.913alice # [ 28.788640] systemd[1]: Detected architecture x86-64.914alice # [ 28.789846] systemd[1]: Running in initrd.915alice # [ 28.861312] systemd[1]: Initializing machine ID from random generator.916alice # [ 28.887073] systemd[1]: Hostname set to <alice>.917bob # [ 34.152962] systemd[1]: bpf-restrict-fs: LSM BPF program attached918alice # [ 39.828632] systemd[1]: bpf-restrict-fs: LSM BPF program attached919bob # [ 42.047215] systemd[1]: Queued start job for default target Initrd Default Target.920bob # [ 42.437075] systemd[1]: Created slice Slice /system/modprobe.921bob # [ 42.467007] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.922bob # [ 42.476836] systemd[1]: Expecting device /dev/disk/by-label/nixos...923bob # [ 42.481324] systemd[1]: Reached target Path Units.924bob # [ 42.484839] systemd[1]: Reached target Slice Units.925bob # [ 42.487112] systemd[1]: Reached target Swaps.926bob # [ 42.492235] systemd[1]: Reached target Timer Units.927bob # [ 42.518807] systemd[1]: Listening on D-Bus System Message Bus Socket.928bob # [ 42.546768] systemd[1]: Listening on Journal Socket (/dev/log).929bob # [ 42.570648] systemd[1]: Listening on Journal Sockets.930bob # [ 42.596145] systemd[1]: Listening on udev Control Socket.931bob # [ 42.611075] systemd[1]: Listening on udev Kernel Socket.932bob # [ 42.617845] systemd[1]: Reached target Socket Units.933bob # [ 42.918890] systemd[1]: Starting Create List of Static Device Nodes...934bob # [ 43.770068] systemd[1]: Starting Load Kernel Module configfs...935bob # [ 45.505142] systemd[1]: Starting Journal Service...936bob # [ 48.059769] systemd[1]: Starting Load Kernel Modules...937bob # [ 48.317428] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os938alice # [ 49.029534] systemd[1]: Queued start job for default target Initrd Default Target.939alice # [ 49.518428] systemd[1]: Created slice Slice /system/modprobe.940alice # [ 49.548657] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.941alice # [ 49.555919] systemd[1]: Expecting device /dev/disk/by-label/nixos...942alice # [ 49.561014] systemd[1]: Reached target Path Units.943alice # [ 49.566573] systemd[1]: Reached target Slice Units.944alice # [ 49.569931] systemd[1]: Reached target Swaps.945alice # [ 49.574519] systemd[1]: Reached target Timer Units.946alice # [ 49.607833] systemd[1]: Listening on D-Bus System Message Bus Socket.947alice # [ 49.624670] systemd[1]: Listening on Journal Socket (/dev/log).948alice # [ 49.639689] systemd[1]: Listening on Journal Sockets.949alice # [ 49.657073] systemd[1]: Listening on udev Control Socket.950alice # [ 49.676457] systemd[1]: Listening on udev Kernel Socket.951alice # [ 49.681323] systemd[1]: Reached target Socket Units.952alice # [ 50.028890] systemd[1]: Starting Create List of Static Device Nodes...953bob # [ 50.292326] systemd[1]: Starting Coldplug All udev Devices...954alice # [ 50.555055] systemd[1]: Starting Load Kernel Module configfs...955alice # [ 51.496069] systemd[1]: Starting Journal Service...956bob # [ 51.995369] systemd[1]: Finished Create List of Static Device Nodes.957bob # [ 52.613699] systemd[1]: modprobe@configfs.service: Deactivated successfully.958alice # [ 52.869995] systemd[1]: Starting Load Kernel Modules...959bob # [ 52.776901] systemd[1]: Finished Load Kernel Module configfs.960bob # [ 52.847066] systemd[1]: modprobe@configfs.service: Consumed 1.402s CPU time over 8.880s wall clock time, 1.9M memory peak.961alice # [ 52.982586] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os962bob: Guest root shell did not produce any data yet...963bob: To debug, enter the VM and run 'systemctl status backdoor.service'.964bob # [ 53.061047] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config965bob # [ 53.543954] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...966alice # [ 54.064920] systemd[1]: Starting Coldplug All udev Devices...967bob # [ 55.120165] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.968bob # [ 55.187757] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev969alice # [ 55.548428] systemd[1]: Finished Create List of Static Device Nodes.970alice # [ 56.780244] systemd[1]: modprobe@configfs.service: Deactivated successfully.971bob # [ 56.821696] systemd[1]: Finished Load Kernel Modules.972alice # [ 57.006014] systemd[1]: Finished Load Kernel Module configfs.973alice # [ 57.334304] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config974bob # [ 57.671968] systemd[1]: Starting Apply Kernel Variables...975alice # [ 58.173981] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...976alice # [ 59.075047] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.977bob # [ 58.966951] systemd-journald[66]: Collecting audit messages is disabled.978alice # [ 59.192845] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev979bob # [ 59.273906] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.980bob # [ 60.292533] systemd[1]: Starting Create Static Device Nodes in /dev...981alice # [ 61.146984] systemd[1]: Finished Load Kernel Modules.982alice # [ 61.811588] systemd[1]: Starting Apply Kernel Variables...983bob # [ 62.531462] systemd[1]: Finished Apply Kernel Variables.984bob # [ 64.366000] systemd[1]: Finished Create Static Device Nodes in /dev.985bob # [ 64.554010] systemd[1]: Reached target Preparation for Local File Systems.986bob # [ 64.581120] systemd[1]: Reached target Local File Systems.987alice # [ 65.009761] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.988bob # [ 65.158306] systemd[1]: Starting Rule-based Manager for Device Events and Files...989alice # [ 65.932928] systemd[1]: Starting Create Static Device Nodes in /dev...990alice # [ 66.101843] systemd-journald[66]: Collecting audit messages is disabled.991alice # [ 68.283775] systemd[1]: Finished Apply Kernel Variables.992alice # [ 71.070920] systemd[1]: Finished Create Static Device Nodes in /dev.993alice # [ 71.126514] systemd[1]: Reached target Preparation for Local File Systems.994alice # [ 71.127945] systemd[1]: Reached target Local File Systems.995alice # [ 71.242035] systemd[1]: Starting Rule-based Manager for Device Events and Files...996bob # [ 75.582183] systemd[1]: Started Journal Service.997bob # [ 75.727287] systemd-modules-load[67]: Inserted module 'dm_mod'998bob # [ 76.006688] systemd-modules-load[67]: Inserted module 'virtio_balloon'999bob # [ 76.198732] systemd-modules-load[67]: Inserted module 'virtio_gpu'1000bob # [ 76.696114] systemd-udevd[73]: Using default interface naming scheme 'v261'.1001bob # [ 77.049184] systemd[1]: Starting Create System Files and Directories...1002bob # [ 78.420919] systemd[1]: Started Rule-based Manager for Device Events and Files.1003bob # [ 79.562869] systemd[1]: Finished Create System Files and Directories.1004alice # [ 80.086703] systemd[1]: Started Journal Service.1005alice # [ 80.125725] systemd-modules-load[67]: Inserted module 'dm_mod'1006alice # [ 80.189663] systemd-modules-load[67]: Inserted module 'virtio_balloon'1007alice # [ 80.230394] systemd-modules-load[67]: Inserted module 'virtio_gpu'1008alice # [ 80.284709] systemd-udevd[73]: Using default interface naming scheme 'v261'.1009alice # [ 80.392590] systemd[1]: Starting Create System Files and Directories...1010alice # [ 80.543609] systemd[1]: Started Rule-based Manager for Device Events and Files.1011alice # [ 82.424008] systemd[1]: Finished Create System Files and Directories.1012bob: Guest root shell did not produce any data yet...1013bob: To debug, enter the VM and run 'systemctl status backdoor.service'.1014bob # [ 84.923731] systemd[1]: Finished Coldplug All udev Devices.1015bob # [ 84.937648] systemd[1]: Reached target System Initialization.1016bob # [ 84.947502] systemd[1]: Reached target Basic System.1017alice # [ 94.281003] systemd[1]: Finished Coldplug All udev Devices.1018alice # [ 94.366584] systemd[1]: Reached target System Initialization.1019alice # [ 94.391772] systemd[1]: Reached target Basic System.1020bob: Guest root shell did not produce any data yet...1021bob: To debug, enter the VM and run 'systemctl status backdoor.service'.1022bob # [ 138.708730] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121023bob # [ 138.814328] serio: i8042 KBD port at 0x60,0x64 irq 11024bob # [ 138.829362] serio: i8042 AUX port at 0x60,0x64 irq 121025bob: Guest root shell did not produce any data yet...1026bob: To debug, enter the VM and run 'systemctl status backdoor.service'.1027bob # [ 157.361217] virtio_blk virtio2: 1/0/0 default/read/poll queues1028bob # [ 157.588919] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1029bob # [ 158.648148] uhci_hcd 0000:00:1d.0: UHCI Host Controller1030bob # [ 158.675834] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 11031bob # [ 158.706822] uhci_hcd 0000:00:1d.0: detected 2 ports1032bob # [ 158.737386] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801033bob # [ 158.864067] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181034bob # [ 158.872200] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11035bob # [ 158.873815] usb usb1: Product: UHCI Host Controller1036bob # [ 158.874761] usb usb1: Manufacturer: Linux 6.18.52 uhci_hcd1037bob # [ 158.892015] usb usb1: SerialNumber: 0000:00:1d.01038bob # [ 158.985158] hub 1-0:1.0: USB hub found1039bob # [ 159.001731] hub 1-0:1.0: 2 ports detected1040bob # [ 159.251458] uhci_hcd 0000:00:1d.1: UHCI Host Controller1041bob # [ 159.252716] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 21042bob # [ 159.265710] uhci_hcd 0000:00:1d.1: detected 2 ports1043bob # [ 159.266804] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01044bob # [ 159.311890] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181045bob # [ 159.312529] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11046bob # [ 159.312902] usb usb2: Product: UHCI Host Controller1047bob # [ 159.313096] usb usb2: Manufacturer: Linux 6.18.52 uhci_hcd1048bob # [ 159.313262] usb usb2: SerialNumber: 0000:00:1d.11049bob # [ 159.363454] hub 2-0:1.0: USB hub found1050bob # [ 159.376884] hub 2-0:1.0: 2 ports detected1051bob # [ 159.505474] uhci_hcd 0000:00:1d.2: UHCI Host Controller1052bob # [ 159.506416] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 31053bob # [ 159.507003] uhci_hcd 0000:00:1d.2: detected 2 ports1054bob # [ 159.570401] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01055bob # [ 159.652414] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181056bob # [ 159.653001] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11057bob # [ 159.653271] usb usb3: Product: UHCI Host Controller1058bob # [ 159.653471] usb usb3: Manufacturer: Linux 6.18.52 uhci_hcd1059bob # [ 159.653649] usb usb3: SerialNumber: 0000:00:1d.21060bob # [ 159.677241] hub 3-0:1.0: USB hub found1061bob # [ 159.691593] hub 3-0:1.0: 2 ports detected1062alice # [ 161.704682] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121063alice # [ 161.726380] serio: i8042 KBD port at 0x60,0x64 irq 11064alice # [ 161.732500] serio: i8042 AUX port at 0x60,0x64 irq 121065bob # [ 162.305907] ehci-pci 0000:00:1d.7: EHCI Host Controller1066bob # [ 162.306868] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 41067bob # [ 162.336625] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001068bob # [ 162.353569] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001069bob # [ 162.365667] usb usb4: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181070bob # [ 162.366479] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11071bob # [ 162.366871] usb usb4: Product: EHCI Host Controller1072bob # [ 162.370432] usb usb4: Manufacturer: Linux 6.18.52 ehci_hcd1073bob # [ 162.370845] usb usb4: SerialNumber: 0000:00:1d.71074bob # [ 162.389786] hub 4-0:1.0: USB hub found1075bob # [ 162.397496] hub 4-0:1.0: 6 ports detected1076bob # [ 162.455186] hub 1-0:1.0: USB hub found1077bob # [ 162.457344] hub 1-0:1.0: 2 ports detected1078bob # [ 162.511717] hub 2-0:1.0: USB hub found1079bob # [ 162.516876] hub 2-0:1.0: 2 ports detected1080bob # [ 162.552906] hub 3-0:1.0: USB hub found1081bob # [ 162.560655] hub 3-0:1.0: 2 ports detected1082bob # [ 162.684589] usb 4-1: new high-speed USB device number 2 using ehci-pci1083bob # [ 162.865460] usb 4-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001084bob # [ 162.866414] usb 4-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101085bob # [ 162.866838] usb 4-1: Product: QEMU USB Tablet1086bob # [ 162.871416] usb 4-1: Manufacturer: QEMU1087bob # [ 162.871762] usb 4-1: SerialNumber: 28754-0000:00:1d.7-11088alice # [ 164.677490] virtio_blk virtio2: 1/0/0 default/read/poll queues1089alice # [ 164.787647] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1090bob # [ 165.548773] SCSI subsystem initialized1091bob # [ 166.599779] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1092bob # [ 166.600749] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1093bob # [ 166.608627] ahci 0000:00:1f.2: flags: 64bit ncq only1094bob # [ 166.720552] scsi host0: ahci1095bob # [ 166.776895] scsi host1: ahci1096bob # [ 166.796653] scsi host2: ahci1097bob # [ 166.816909] scsi host3: ahci1098bob # [ 166.836515] scsi host4: ahci1099bob # [ 166.853923] scsi host5: ahci1100bob # [ 166.863890] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11101bob # [ 166.864576] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11102bob # [ 166.864904] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11103bob # [ 166.877345] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11104bob # [ 166.877783] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11105bob # [ 166.878411] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11106bob # [ 167.207590] ata1: SATA link down (SStatus 0 SControl 300)1107bob # [ 167.220218] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1108bob # [ 167.228524] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001109bob # [ 167.231515] ata3.00: applying bridge limits1110bob # [ 167.237808] ata4: SATA link down (SStatus 0 SControl 300)1111bob # [ 167.239224] ata6: SATA link down (SStatus 0 SControl 300)1112bob # [ 167.239840] ata2: SATA link down (SStatus 0 SControl 300)1113bob # [ 167.240788] ata5: SATA link down (SStatus 0 SControl 300)1114bob # [ 167.244576] ata3.00: configured for UDMA/1001115bob # [ 167.279663] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51116alice # [ 167.548945] ehci-pci 0000:00:1d.7: EHCI Host Controller1117alice # [ 167.549944] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11118alice # [ 167.568595] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001119alice # [ 167.580478] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001120alice # [ 167.603280] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181121alice # [ 167.603649] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11122alice # [ 167.603889] usb usb1: Product: EHCI Host Controller1123alice # [ 167.604296] usb usb1: Manufacturer: Linux 6.18.52 ehci_hcd1124alice # [ 167.604487] usb usb1: SerialNumber: 0000:00:1d.71125alice # [ 167.631458] hub 1-0:1.0: USB hub found1126alice # [ 167.635557] hub 1-0:1.0: 6 ports detected1127alice # [ 167.818375] uhci_hcd 0000:00:1d.0: UHCI Host Controller1128alice # [ 167.818785] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21129alice # [ 167.819549] uhci_hcd 0000:00:1d.0: detected 2 ports1130alice # [ 167.830778] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801131alice # [ 167.900984] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181132alice # [ 167.908985] usb 1-1: new high-speed USB device number 2 using ehci-pci1133alice # [ 167.919894] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11134alice # [ 167.921143] usb usb2: Product: UHCI Host Controller1135alice # [ 167.947845] usb usb2: Manufacturer: Linux 6.18.52 uhci_hcd1136alice # [ 167.953391] usb usb2: SerialNumber: 0000:00:1d.01137alice # [ 168.022313] hub 2-0:1.0: USB hub found1138alice # [ 168.052610] hub 2-0:1.0: 2 ports detected1139alice # [ 168.116667] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001140alice # [ 168.117915] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101141alice # [ 168.128601] usb 1-1: Product: QEMU USB Tablet1142alice # [ 168.128939] usb 1-1: Manufacturer: QEMU1143alice # [ 168.129501] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11144alice # [ 168.255902] uhci_hcd 0000:00:1d.1: UHCI Host Controller1145alice # [ 168.271637] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31146alice # [ 168.272488] uhci_hcd 0000:00:1d.1: detected 2 ports1147alice # [ 168.281757] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01148alice # [ 168.308801] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181149alice # [ 168.309452] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11150alice # [ 168.309705] usb usb3: Product: UHCI Host Controller1151alice # [ 168.309938] usb usb3: Manufacturer: Linux 6.18.52 uhci_hcd1152alice # [ 168.318504] usb usb3: SerialNumber: 0000:00:1d.11153alice # [ 168.339267] hub 3-0:1.0: USB hub found1154alice # [ 168.344976] hub 3-0:1.0: 2 ports detected1155alice # [ 168.548618] uhci_hcd 0000:00:1d.2: UHCI Host Controller1156alice # [ 168.549635] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41157alice # [ 168.550315] uhci_hcd 0000:00:1d.2: detected 2 ports1158alice # [ 168.603425] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01159alice # [ 168.619820] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181160alice # [ 168.620388] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11161alice # [ 168.620628] usb usb4: Product: UHCI Host Controller1162alice # [ 168.620838] usb usb4: Manufacturer: Linux 6.18.52 uhci_hcd1163alice # [ 168.629172] usb usb4: SerialNumber: 0000:00:1d.21164alice # [ 168.644199] hub 4-0:1.0: USB hub found1165alice # [ 168.651500] hub 4-0:1.0: 2 ports detected1166alice # [ 168.661402] SCSI subsystem initialized1167alice # [ 169.740748] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1168alice # [ 169.743566] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1169alice # [ 169.747796] ahci 0000:00:1f.2: flags: 64bit ncq only1170alice # [ 169.840540] scsi host0: ahci1171alice # [ 169.860435] scsi host1: ahci1172alice # [ 169.874289] scsi host2: ahci1173alice # [ 169.887938] scsi host3: ahci1174alice # [ 169.902376] scsi host4: ahci1175alice # [ 169.915897] scsi host5: ahci1176alice # [ 169.918750] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11177alice # [ 169.928573] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11178alice # [ 169.929555] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11179alice # [ 169.937530] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11180alice # [ 169.937912] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11181alice # [ 169.938751] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11182alice # [ 170.286641] ata2: SATA link down (SStatus 0 SControl 300)1183alice # [ 170.299193] ata6: SATA link down (SStatus 0 SControl 300)1184alice # [ 170.300169] ata4: SATA link down (SStatus 0 SControl 300)1185alice # [ 170.301378] ata1: SATA link down (SStatus 0 SControl 300)1186alice # [ 170.301942] ata5: SATA link down (SStatus 0 SControl 300)1187alice # [ 170.303563] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1188alice # [ 170.316494] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001189alice # [ 170.317375] ata3.00: applying bridge limits1190alice # [ 170.325205] ata3.00: configured for UDMA/1001191alice # [ 170.364842] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51192bob # [ 172.800024] systemd[1]: Starting Virtual Console Setup...1193bob: Guest root shell did not produce any data yet...1194bob: To debug, enter the VM and run 'systemctl status backdoor.service'.1195bob # [ 173.750490] (udev-worker)[308]: Network interface NamePolicy= disabled on kernel command line.1196bob # [ 173.830963] (udev-worker)[307]: Network interface NamePolicy= disabled on kernel command line.1197bob # [ 175.785112] systemd-vconsole-setup[315]: Configuration of first virtual console was skipped, ignoring remaining ones.1198bob # [ 175.958050] systemd[1]: Finished Virtual Console Setup.1199bob # [ 182.047513] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01200bob # [ 184.687264] systemd[1]: Found device /dev/disk/by-label/nixos.1201bob # [ 184.729126] systemd[1]: Reached target Initrd Root Device.1202bob # [ 185.006092] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1203bob # [ 191.390994] systemd-fsck[331]: nixos: clean, 12/65536 files, 13019/262144 blocks1204bob # [ 191.789945] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1205bob # [ 192.184633] systemd[1]: Mounting /sysroot...1206bob # [ 200.076534] EXT4-fs (vda): mounted filesystem efbdb221-7984-49e3-aca8-0c197f53f61a r/w with ordered data mode. Quota mode: none.1207bob # [ 200.708966] systemd[1]: Mounted /sysroot.1208bob # [ 200.791738] systemd[1]: Reached target Initrd Root File System.1209bob # [ 201.580544] systemd[1]: Mounting /sysroot/nix/.ro-store...1210bob # [ 202.758804] systemd[1]: Mounting /sysroot/nix/.rw-store...1211bob: Guest root shell did not produce any data yet...1212bob: To debug, enter the VM and run 'systemctl status backdoor.service'.1213bob # [ 203.600246] systemd[1]: Mounting /sysroot/run...1214alice # [ 204.730846] systemd[1]: Starting Virtual Console Setup...1215bob # [ 204.762676] systemd[1]: Mounting /sysroot/tmp/shared...1216bob # [ 205.803930] systemd[1]: Mounting /sysroot/tmp/xchg...1217bob # [ 207.165839] systemd[1]: Starting Mountpoints Configured in the Real Root...1218alice # [ 209.160825] systemd-vconsole-setup[322]: Configuration of first virtual console was skipped, ignoring remaining ones.1219alice # [ 209.399557] systemd[1]: Finished Virtual Console Setup.1220bob # [ 210.830804] systemd-sysroot-fstab-check[355]: /sysroot should be mounted in the initrd, will request daemon-reload.1221bob # [ 211.847134] systemd[1]: Mounted /sysroot/nix/.ro-store.1222bob # [ 212.021048] systemd[1]: Mounted /sysroot/nix/.rw-store.1223bob # [ 212.115397] systemd[1]: Mounted /sysroot/run.1224bob # [ 212.194521] systemd[1]: Mounted /sysroot/tmp/shared.1225bob # [ 212.259841] systemd[1]: Mounted /sysroot/tmp/xchg.1226bob # [ 213.583662] systemd[1]: Reload requested from client PID 355 ('systemd-sysroot') (unit initrd-parse-etc.service)...1227bob # [ 213.631901] systemd[1]: Reloading...1228alice # [ 216.892830] (udev-worker)[334]: Network interface NamePolicy= disabled on kernel command line.1229alice # [ 217.031352] (udev-worker)[333]: Network interface NamePolicy= disabled on kernel command line.1230alice # [ 220.760820] systemd[1]: Found device /dev/disk/by-label/nixos.1231alice # [ 220.793720] systemd[1]: Reached target Initrd Root Device.1232alice # [ 220.855638] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1233bob # [ 221.385965] systemd[1]: Reloading finished in 7671 ms.1234bob # [ 222.285442] systemd-sysroot-fstab-check[355]: Requesting initrd-fs.target/start/replace...1235bob # [ 222.510091] systemd-sysroot-fstab-check[355]: Requesting swap.target/start/replace...1236bob # [ 222.706582] systemd[1]: Starting rw-sysroot-nix-store.service...1237bob # [ 222.788726] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1238bob # [ 222.839714] systemd[1]: Finished Mountpoints Configured in the Real Root.1239bob # [ 222.861647] systemd[1]: initrd-parse-etc.service: Consumed 1.088s CPU time over 15.733s wall clock time, 2M memory peak.1240bob # [ 222.882847] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1241alice # [ 224.514955] systemd-fsck[339]: nixos: clean, 12/65536 files, 13019/262144 blocks1242bob # [ 224.489894] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1243bob # [ 224.514968] systemd[1]: Finished rw-sysroot-nix-store.service.1244alice # [ 224.800629] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1245bob # [ 224.736015] systemd[1]: Mounting /sysroot/nix/store...1246alice # [ 224.955578] systemd[1]: Mounting /sysroot...1247bob # [ 225.938508] systemd[1]: Mounted /sysroot/nix/store.1248bob # [ 225.965855] systemd[1]: Reached target Initrd File Systems.1249bob # [ 226.005835] systemd[1]: Starting Find NixOS closure...1250bob # [ 226.097275] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1251alice # [ 226.201711] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01252bob # [ 227.168532] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1253bob # [ 227.220560] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1254alice # [ 227.455438] EXT4-fs (vda): mounted filesystem 5c4819f4-710d-4f30-88fd-e0e492c9ffc2 r/w with ordered data mode. Quota mode: none.1255alice # [ 227.592941] systemd[1]: Mounted /sysroot.1256alice # [ 227.613716] systemd[1]: Reached target Initrd Root File System.1257alice # [ 227.741986] systemd[1]: Mounting /sysroot/nix/.ro-store...1258bob # [ 227.685941] systemd[1]: Finished Find NixOS closure.1259bob # [ 227.715659] systemd[1]: Reached target Initrd Default Target.1260alice # [ 227.840517] systemd[1]: Mounting /sysroot/nix/.rw-store...1261bob # [ 227.759795] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1262alice # [ 227.987697] systemd[1]: Mounting /sysroot/run...1263alice # [ 228.167524] systemd[1]: Mounting /sysroot/tmp/shared...1264alice # [ 228.419917] systemd[1]: Mounting /sysroot/tmp/xchg...1265bob # [ 228.514867] systemd[1]: Stopped target Initrd Default Target.1266bob # [ 228.525635] systemd[1]: Stopped target Basic System.1267bob # [ 228.537954] systemd[1]: Stopped target Initrd Root Device.1268bob # [ 228.552643] systemd[1]: Stopped target Path Units.1269bob # [ 228.567781] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1270alice # [ 228.674701] systemd[1]: Starting Mountpoints Configured in the Real Root...1271bob # [ 228.604762] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1272bob # [ 228.630921] systemd[1]: Stopped target Slice Units.1273bob # [ 228.651805] systemd[1]: Stopped target Socket Units.1274bob # [ 228.676751] systemd[1]: Stopped target System Initialization.1275bob # [ 228.693609] systemd[1]: Stopped target Swaps.1276bob # [ 228.705674] systemd[1]: Stopped target Timer Units.1277bob # [ 228.715849] systemd[1]: dbus.socket: Deactivated successfully.1278bob # [ 228.727653] systemd[1]: Closed D-Bus System Message Bus Socket.1279bob # [ 228.741590] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1280bob # [ 228.754880] systemd[1]: Stopped Find NixOS closure.1281bob # [ 228.821693] systemd[1]: Starting rw-sysroot-nix-store.service...1282bob # [ 228.830553] systemd[1]: systemd-sysctl.service: Deactivated successfully.1283bob # [ 228.846725] systemd[1]: Stopped Apply Kernel Variables.1284bob # [ 228.878670] systemd[1]: systemd-modules-load.service: Deactivated successfully.1285bob # [ 228.905680] systemd[1]: Stopped Load Kernel Modules.1286bob # [ 228.929706] systemd[1]: systemd-modules-load.service: Consumed 1.487s CPU time over 3min 801ms wall clock time, 2.1M memory peak.1287bob # [ 228.939453] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1288bob # [ 228.963573] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1289bob # [ 228.992688] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1290bob # [ 229.011869] systemd[1]: Stopped Create System Files and Directories.1291bob # [ 229.049591] systemd[1]: Stopped target Local File Systems.1292bob # [ 229.069389] systemd[1]: Stopped target Preparation for Local File Systems.1293bob # [ 229.087671] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1294bob # [ 229.113545] systemd[1]: Stopped Coldplug All udev Devices.1295bob # [ 229.127842] systemd[1]: systemd-udev-trigger.service: Consumed 8.793s CPU time over 2min 58.765s wall clock time, 3.1M memory peak.1296bob # [ 229.144565] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1297bob # [ 229.153998] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1298bob # [ 229.166504] systemd[1]: Stopped Virtual Console Setup.1299bob # [ 229.456722] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1300bob # [ 229.464994] systemd[1]: Finished rw-sysroot-nix-store.service.1301bob # [ 229.479663] systemd[1]: initrd-cleanup.service: Deactivated successfully.1302bob # [ 229.489971] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1303alice # [ 229.757794] systemd-sysroot-fstab-check[373]: /sysroot should be mounted in the initrd, will request daemon-reload.1304bob # [ 229.860649] systemd[1]: systemd-udevd.service: Deactivated successfully.1305bob # [ 229.868748] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1306bob # [ 229.875608] systemd[1]: systemd-udevd.service: Consumed 1min 16.571s CPU time over 2min 44.660s wall clock time, 21M memory peak.1307bob # [ 229.884563] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1308bob # [ 229.891421] systemd[1]: Closed udev Control Socket.1309bob # [ 229.908958] systemd[1]: Starting Cleanup udev Database...1310bob # [ 229.913691] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1311bob # [ 229.919585] systemd[1]: Stopped Create Static Device Nodes in /dev.1312bob # [ 229.926772] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1313bob # [ 229.932897] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1314bob # [ 229.941636] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1315bob # [ 229.948958] systemd[1]: Stopped Create List of Static Device Nodes.1316bob # [ 229.953927] systemd[1]: kmod-static-nodes.service: Consumed 1.750s CPU time over 3min 6.993s wall clock time, 1.9M memory peak.1317alice # [ 230.108838] systemd[1]: Mounted /sysroot/nix/.ro-store.1318alice # [ 230.114481] systemd[1]: Mounted /sysroot/nix/.rw-store.1319alice # [ 230.119682] systemd[1]: Mounted /sysroot/run.1320alice # [ 230.125608] systemd[1]: Mounted /sysroot/tmp/shared.1321alice # [ 230.133720] systemd[1]: Mounted /sysroot/tmp/xchg.1322alice # [ 230.217584] systemd[1]: Reload requested from client PID 373 ('systemd-sysroot') (unit initrd-parse-etc.service)...1323alice # [ 230.224506] systemd[1]: Reloading...1324bob # [ 230.171700] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1325bob # [ 230.180985] systemd[1]: Finished Cleanup udev Database.1326bob # [ 230.191650] systemd[1]: Reached target Switch Root.1327bob # [ 230.211758] systemd[1]: Starting NixOS Activation...1328bob # [ 231.347863] initrd-nixos-activation-start[409]: booting system configuration /nix/store/p8hwydl4ilpb66vfs3pdc12192v1qv90-nixos-system-bob-test1329bob # [ 231.858706] initrd-nixos-activation-start[409]: running activation script...1330alice # [ 231.966739] systemd[1]: Reloading finished in 1725 ms.1331alice # [ 232.091669] systemd-sysroot-fstab-check[373]: Requesting initrd-fs.target/start/replace...1332alice # [ 232.124457] systemd-sysroot-fstab-check[373]: Requesting swap.target/start/replace...1333alice # [ 232.165634] systemd[1]: Starting rw-sysroot-nix-store.service...1334alice # [ 232.189507] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1335alice # [ 232.204583] systemd[1]: Finished Mountpoints Configured in the Real Root.1336alice # [ 232.210941] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1337alice # [ 232.676698] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1338alice # [ 232.703271] systemd[1]: Finished rw-sysroot-nix-store.service.1339alice # [ 232.795587] systemd[1]: Mounting /sysroot/nix/store...1340bob: Guest root shell did not produce any data yet...1341bob: To debug, enter the VM and run 'systemctl status backdoor.service'.1342alice # [ 233.550906] systemd[1]: Mounted /sysroot/nix/store.1343alice # [ 233.570799] systemd[1]: Reached target Initrd File Systems.1344alice # [ 233.613714] systemd[1]: Starting Find NixOS closure...1345alice # [ 233.661641] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1346alice # [ 234.477633] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1347alice # [ 234.568894] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1348alice # [ 235.023641] systemd[1]: Finished Find NixOS closure.1349alice # [ 235.055693] systemd[1]: Reached target Initrd Default Target.1350alice # [ 235.108645] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1351alice # [ 235.870796] systemd[1]: Stopped target Initrd Default Target.1352alice # [ 235.878751] systemd[1]: Stopped target Basic System.1353alice # [ 235.890596] systemd[1]: Stopped target Initrd Root Device.1354alice # [ 235.907609] systemd[1]: Stopped target Path Units.1355alice # [ 235.917573] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1356alice # [ 235.944195] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1357alice # [ 235.959338] systemd[1]: Stopped target Slice Units.1358alice # [ 235.974716] systemd[1]: Stopped target Socket Units.1359alice # [ 235.987666] systemd[1]: Stopped target System Initialization.1360bob # [ 235.905560] initrd-nixos-activation-start[432]: setting up /etc...1361alice # [ 236.001714] systemd[1]: Stopped target Swaps.1362alice # [ 236.014400] systemd[1]: Stopped target Timer Units.1363alice # [ 236.031608] systemd[1]: dbus.socket: Deactivated successfully.1364alice # [ 236.044614] systemd[1]: Closed D-Bus System Message Bus Socket.1365alice # [ 236.057670] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1366alice # [ 236.070774] systemd[1]: Stopped Find NixOS closure.1367alice # [ 236.141281] systemd[1]: Starting rw-sysroot-nix-store.service...1368alice # [ 236.150654] systemd[1]: systemd-sysctl.service: Deactivated successfully.1369alice # [ 236.175718] systemd[1]: Stopped Apply Kernel Variables.1370alice # [ 236.201704] systemd[1]: systemd-modules-load.service: Deactivated successfully.1371alice # [ 236.225675] systemd[1]: Stopped Load Kernel Modules.1372alice # [ 236.250756] systemd[1]: systemd-modules-load.service: Consumed 1.518s CPU time over 3min 3.356s wall clock time, 2M memory peak.1373alice # [ 236.264562] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1374alice # [ 236.287813] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1375alice # [ 236.316762] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1376alice # [ 236.344710] systemd[1]: Stopped Create System Files and Directories.1377alice # [ 236.378544] systemd[1]: Stopped target Local File Systems.1378alice # [ 236.404817] systemd[1]: Stopped target Preparation for Local File Systems.1379alice # [ 236.430405] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1380alice # [ 236.443616] systemd[1]: Stopped Coldplug All udev Devices.1381alice # [ 236.464805] systemd[1]: systemd-udev-trigger.service: Consumed 10.744s CPU time over 3min 2.395s wall clock time, 3.2M memory peak.1382alice # [ 236.492680] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1383alice # [ 236.509819] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1384alice # [ 236.535938] systemd[1]: Stopped Virtual Console Setup.1385alice # [ 236.563853] systemd[1]: systemd-vconsole-setup.service: Consumed 1.207s CPU time over 31.968s wall clock time, 1.9M memory peak.1386alice # [ 236.833616] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1387alice # [ 236.873995] systemd[1]: Finished rw-sysroot-nix-store.service.1388alice # [ 236.991693] systemd[1]: initrd-cleanup.service: Deactivated successfully.1389alice # [ 237.008734] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1390alice # [ 237.389352] systemd[1]: systemd-udevd.service: Deactivated successfully.1391alice # [ 237.401728] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1392alice # [ 237.406837] systemd[1]: systemd-udevd.service: Consumed 1min 28.867s CPU time over 2min 46.140s wall clock time, 20.4M memory peak.1393alice # [ 237.419570] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1394alice # [ 237.424785] systemd[1]: Closed udev Control Socket.1395alice # [ 237.443596] systemd[1]: Starting Cleanup udev Database...1396alice # [ 237.449593] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1397alice # [ 237.455534] systemd[1]: Stopped Create Static Device Nodes in /dev.1398alice # [ 237.461941] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1399alice # [ 237.467755] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1400alice # [ 237.474669] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Consumed 1.052s CPU time over 2min 59.309s wall clock time, 2.1M memory peak.1401alice # [ 237.480376] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1402alice # [ 237.485677] systemd[1]: Stopped Create List of Static Device Nodes.1403alice # [ 237.491245] systemd[1]: kmod-static-nodes.service: Consumed 1.070s CPU time over 3min 7.443s wall clock time, 2M memory peak.1404alice # [ 237.724401] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1405alice # [ 237.736842] systemd[1]: Finished Cleanup udev Database.1406alice # [ 237.745862] systemd[1]: Reached target Switch Root.1407alice # [ 237.767290] systemd[1]: Starting NixOS Activation...1408bob # [ 237.737946] initrd-nixos-activation-start[432]: setting up age secrets...1409alice # [ 238.915790] initrd-nixos-activation-start[426]: booting system configuration /nix/store/dm25ifzai99pkp763r7wv9b38z3i6w0n-nixos-system-alice-test1410bob # [ 239.101954] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1411bob # [ 239.110581] systemd[1]: Finished NixOS Activation.1412bob # [ 239.116693] systemd[1]: initrd-nixos-activation.service: Consumed 6.635s CPU time over 8.883s wall clock time, 26.4M memory peak.1413bob # [ 239.138007] systemd[1]: Starting Switch Root...1414alice # [ 239.364630] initrd-nixos-activation-start[426]: running activation script...1415bob # [ 239.334561] systemd[1]: Switching root.1416bob # [ 239.614812] systemd-journald[66]: Received SIGTERM from PID 1 (systemd).1417bob # [ 240.928436] NET: Registered PF_VSOCK protocol family1418bob # [ 241.593135] 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)1419bob # [ 241.594619] systemd[1]: Detected virtualization qemu.1420bob # [ 241.595616] systemd[1]: Detected architecture x86-64.1421bob # [ 241.597241] systemd[1]: Detected first boot.1422bob # [ 241.618319] systemd[1]: Initializing machine ID from random generator.1423alice # [ 242.804677] initrd-nixos-activation-start[449]: setting up /etc...1424bob # [ 243.004090] systemd[1]: bpf-restrict-fs: LSM BPF program attached1425alice # [ 244.447571] initrd-nixos-activation-start[449]: setting up age secrets...1426bob # [ 244.464708] systemd[1]: Applying preset policy.1427alice # [ 245.453905] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1428alice # [ 245.460812] systemd[1]: Finished NixOS Activation.1429alice # [ 245.466600] systemd[1]: initrd-nixos-activation.service: Consumed 5.693s CPU time over 7.682s wall clock time, 26.4M memory peak.1430alice # [ 245.485710] systemd[1]: Starting Switch Root...1431alice # [ 245.650603] systemd[1]: Switching root.1432alice # [ 245.919684] systemd-journald[66]: Received SIGTERM from PID 1 (systemd).1433bob # [ 246.410746] systemd[1]: Populated /etc with preset unit settings.1434alice # [ 247.566076] NET: Registered PF_VSOCK protocol family1435alice # [ 248.246873] 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)1436alice # [ 248.248842] systemd[1]: Detected virtualization qemu.1437alice # [ 248.250054] systemd[1]: Detected architecture x86-64.1438alice # [ 248.251556] systemd[1]: Detected first boot.1439alice # [ 248.271291] systemd[1]: Initializing machine ID from random generator.1440alice # [ 249.645413] systemd[1]: bpf-restrict-fs: LSM BPF program attached1441bob # [ 249.573579] systemd[1]: initrd-switch-root.service: Deactivated successfully.1442bob # [ 249.578662] systemd[1]: Stopped initrd-switch-root.service.1443bob # [ 249.609460] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1444bob # [ 249.623469] systemd[1]: Created slice Slice /system/getty.1445bob # [ 249.633190] systemd[1]: Created slice User and Session Slice.1446bob # [ 249.637028] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1447bob # [ 249.638779] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1448bob # [ 249.640362] systemd[1]: Expecting device /dev/hvc0...1449bob # [ 249.640848] systemd[1]: Expecting device /dev/ttyS0...1450bob # [ 249.641632] systemd[1]: Reached target Local Encrypted Volumes.1451bob # [ 249.642648] systemd[1]: Stopped target initrd-fs.target.1452bob # [ 249.643507] systemd[1]: Stopped target initrd-root-fs.target.1453bob # [ 249.644193] systemd[1]: Stopped target initrd-switch-root.target.1454bob # [ 249.645074] systemd[1]: Reached target Virtual Machines and Containers.1455bob # [ 249.646030] systemd[1]: Reached target Path Units.1456bob # [ 249.646623] systemd[1]: Reached target Remote File Systems.1457bob # [ 249.647288] systemd[1]: Reached target Slice Units.1458bob # [ 249.647832] systemd[1]: Reached target Swaps.1459bob # [ 249.675370] systemd[1]: Listening on Query the User Interactively for a Password.1460bob # [ 249.702273] systemd[1]: Listening on Process Core Dump Socket.1461bob # [ 249.722015] systemd[1]: Listening on Credential Encryption/Decryption.1462bob # [ 249.746765] systemd[1]: Listening on Factory Reset Management.1463bob # [ 249.749113] systemd[1]: Listening on Hostname Service Socket.1464bob # [ 249.786590] systemd[1]: Starting Journal Log Access Socket...1465bob # [ 249.807656] systemd[1]: Listening on Journal Audit Socket.1466bob # [ 249.838802] systemd[1]: Listening on Console Output Muting Service Socket.1467bob # [ 249.842402] systemd[1]: Listening on Network Management Metrics Varlink Socket.1468bob # [ 249.846515] systemd[1]: Listening on Network Management Varlink Socket.1469bob # [ 249.850776] systemd[1]: Listening on Network Management Netlink Socket.1470bob # [ 249.854482] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1471bob # [ 249.857090] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1472bob # [ 249.858066] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1473bob # [ 249.904046] systemd[1]: Listening on Disk Repartitioning Service Socket.1474bob # [ 249.906770] systemd[1]: Listening on Resolve Monitor Varlink Socket.1475bob # [ 249.909253] systemd[1]: Listening on Resolve Service Varlink Socket.1476bob # [ 249.911433] systemd[1]: Listening on udev Control Socket.1477bob # [ 249.913842] systemd[1]: Listening on udev Varlink Socket.1478bob # [ 249.949828] systemd[1]: Mounting Huge Pages File System...1479bob # [ 250.000857] systemd[1]: Mounting POSIX Message Queue File System...1480bob # [ 250.073060] systemd[1]: Mounting Kernel Debug File System...1481bob # [ 250.165752] systemd[1]: Mounting Kernel Trace File System...1482bob # [ 250.316552] systemd[1]: Starting Create List of Static Device Nodes...1483bob # [ 250.485738] systemd[1]: Starting Load Kernel Module configfs...1484bob # [ 250.502438] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1485bob # [ 250.509811] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1486bob # [ 250.535287] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1487bob # [ 250.720474] systemd[1]: Mounting FUSE Control File System...1488bob # [ 250.740796] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671489alice # [ 251.028553] systemd[1]: Applying preset policy.1490bob # [ 251.411719] systemd[1]: Starting Journal Service...1491bob # [ 251.543596] systemd[1]: Starting Load Kernel Modules...1492bob # [ 252.627393] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1493bob # [ 252.771039] systemd[1]: Starting Remount Root and Kernel File Systems...1494bob # [ 252.791282] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1495alice # [ 252.993597] systemd[1]: Populated /etc with preset unit settings.1496bob # [ 253.433968] systemd[1]: Starting Coldplug All udev Devices...1497bob # [ 253.523815] systemd[1]: Listening on Journal Log Access Socket.1498bob # [ 253.531479] systemd[1]: Mounted Huge Pages File System.1499bob # [ 253.543419] systemd[1]: Mounted POSIX Message Queue File System.1500bob # [ 253.548689] systemd[1]: Mounted Kernel Debug File System.1501bob # [ 253.572624] systemd[1]: Mounted Kernel Trace File System.1502bob # [ 253.804734] systemd[1]: Finished Create List of Static Device Nodes.1503bob # [ 253.836603] systemd[1]: modprobe@configfs.service: Deactivated successfully.1504bob # [ 253.847826] systemd[1]: Finished Load Kernel Module configfs.1505bob # [ 253.860616] systemd[1]: Mounted FUSE Control File System.1506bob # [ 254.067349] systemd[1]: Mounting Kernel Configuration File System...1507bob # [ 254.267159] systemd-journald[525]: Collecting audit messages is enabled.1508bob # [ 254.466370] systemd[1]: Queued start job for default target Multi-User System.1509bob # [ 254.473670] systemd[1]: systemd-journald.service: Deactivated successfully.1510bob # [ 254.483854] systemd[1]: systemd-journald.service: Consumed 8.495s CPU time over 3min 24.018s wall clock time, 3.6M memory peak.1511bob # [ 254.470174] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1512bob # [ 254.478412] systemd[1]: Started Journal Service.1513bob # [ 254.856331] loop: module loaded1514bob # [ 254.947666] systemd-modules-load[526]: Inserted module 'loop'1515bob # [ 255.108951] systemd[1]: Finished Load Kernel Modules.1516bob # [ 255.096629] EXT4-fs (vda): re-mounted efbdb221-7984-49e3-aca8-0c197f53f61a.1517bob # [ 255.537901] systemd[1]: Finished Remount Root and Kernel File Systems.1518bob # [ 256.074291] systemd[1]: Listening on Disk Image Download Service Socket.1519alice # [ 256.299479] systemd[1]: initrd-switch-root.service: Deactivated successfully.1520alice # [ 256.304823] systemd[1]: Stopped initrd-switch-root.service.1521alice # [ 256.336603] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1522alice # [ 256.351236] systemd[1]: Created slice Slice /system/getty.1523alice # [ 256.360787] systemd[1]: Created slice User and Session Slice.1524alice # [ 256.364675] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1525alice # [ 256.366496] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1526alice # [ 256.368041] systemd[1]: Expecting device /dev/hvc0...1527alice # [ 256.368499] systemd[1]: Expecting device /dev/ttyS0...1528alice # [ 256.369119] systemd[1]: Reached target Local Encrypted Volumes.1529alice # [ 256.369861] systemd[1]: Stopped target initrd-fs.target.1530alice # [ 256.370596] systemd[1]: Stopped target initrd-root-fs.target.1531alice # [ 256.371170] systemd[1]: Stopped target initrd-switch-root.target.1532alice # [ 256.371766] systemd[1]: Reached target Virtual Machines and Containers.1533alice # [ 256.372630] systemd[1]: Reached target Path Units.1534alice # [ 256.373253] systemd[1]: Reached target Remote File Systems.1535alice # [ 256.373650] systemd[1]: Reached target Slice Units.1536alice # [ 256.374202] systemd[1]: Reached target Swaps.1537bob # [ 256.308661] systemd[1]: Starting Firewall...1538alice # [ 256.401400] systemd[1]: Listening on Query the User Interactively for a Password.1539alice # [ 256.428567] systemd[1]: Listening on Process Core Dump Socket.1540alice # [ 256.449184] systemd[1]: Listening on Credential Encryption/Decryption.1541alice # [ 256.474423] systemd[1]: Listening on Factory Reset Management.1542alice # [ 256.476562] systemd[1]: Listening on Hostname Service Socket.1543alice # [ 256.513681] systemd[1]: Starting Journal Log Access Socket...1544alice # [ 256.534536] systemd[1]: Listening on Journal Audit Socket.1545alice # [ 256.574583] systemd[1]: Listening on Console Output Muting Service Socket.1546alice # [ 256.578223] systemd[1]: Listening on Network Management Metrics Varlink Socket.1547alice # [ 256.581806] systemd[1]: Listening on Network Management Varlink Socket.1548alice # [ 256.585606] systemd[1]: Listening on Network Management Netlink Socket.1549alice # [ 256.589266] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1550alice # [ 256.591685] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1551alice # [ 256.592753] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1552alice # [ 256.641150] systemd[1]: Listening on Disk Repartitioning Service Socket.1553alice # [ 256.643629] systemd[1]: Listening on Resolve Monitor Varlink Socket.1554alice # [ 256.645834] systemd[1]: Listening on Resolve Service Varlink Socket.1555bob # [ 256.558695] systemd[1]: Starting Flush Journal to Persistent Storage...1556alice # [ 256.648216] systemd[1]: Listening on udev Control Socket.1557alice # [ 256.650862] systemd[1]: Listening on udev Varlink Socket.1558bob # [ 256.574922] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1559alice # [ 256.683848] systemd[1]: Mounting Huge Pages File System...1560alice # [ 256.737728] systemd[1]: Mounting POSIX Message Queue File System...1561alice # [ 256.812186] systemd[1]: Mounting Kernel Debug File System...1562bob # [ 256.804827] systemd[1]: Starting Load/Save OS Random Seed...1563alice # [ 256.908114] systemd[1]: Mounting Kernel Trace File System...1564alice # [ 257.068141] systemd[1]: Starting Create List of Static Device Nodes...1565bob # [ 257.149460] systemd[1]: Starting Apply Kernel Variables...1566alice # [ 257.249366] systemd[1]: Starting Load Kernel Module configfs...1567bob # [ 257.165578] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1568alice # [ 257.261674] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1569alice # [ 257.285383] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1570alice # [ 257.289745] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1571alice # [ 257.547756] systemd[1]: Mounting FUSE Control File System...1572alice # [ 257.580339] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671573bob # [ 257.728725] systemd[1]: Mounted Kernel Configuration File System.1574bob # [ 258.054708] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1575bob # [ 258.273580] systemd-oomd[528]: No swap; memory pressure usage will be degraded1576alice # [ 258.452242] systemd[1]: Starting Journal Service...1577bob # [ 258.678652] systemd-journald[525]: Received client request to flush runtime journal.1578alice # [ 258.910444] systemd[1]: Starting Load Kernel Modules...1579alice # [ 260.669124] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1580alice # [ 261.503688] systemd[1]: Starting Remount Root and Kernel File Systems...1581alice # [ 261.542300] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1582alice # [ 262.763418] systemd[1]: Starting Coldplug All udev Devices...1583alice # [ 263.313749] systemd[1]: Listening on Journal Log Access Socket.1584alice # [ 263.332171] systemd[1]: Mounted Huge Pages File System.1585bob # [ 263.262474] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1586alice # [ 263.360812] systemd[1]: Mounted POSIX Message Queue File System.1587alice # [ 263.380861] systemd[1]: Mounted Kernel Debug File System.1588bob: Guest root shell did not produce any data yet...1589bob: To debug, enter the VM and run 'systemctl status backdoor.service'.1590alice # [ 263.421592] systemd[1]: Mounted Kernel Trace File System.1591bob # [ 263.425032] systemd[1]: Starting Create Static Device Nodes in /dev...1592alice # [ 263.580581] systemd[1]: Finished Create List of Static Device Nodes.1593bob # [ 263.522544] systemd[1]: Finished Load/Save OS Random Seed.1594bob # [ 263.586046] systemd[1]: Reached target First Boot Complete.1595bob # [ 263.639892] systemd[1]: Finished Flush Journal to Persistent Storage.1596bob # [ 263.727945] systemd[1]: Finished Apply Kernel Variables.1597alice # [ 263.938230] systemd[1]: modprobe@configfs.service: Deactivated successfully.1598alice # [ 263.977554] systemd[1]: Finished Load Kernel Module configfs.1599alice # [ 263.987503] systemd[1]: Mounted FUSE Control File System.1600alice # [ 264.316125] systemd[1]: Mounting Kernel Configuration File System...1601alice # [ 264.476686] systemd-journald[535]: Collecting audit messages is enabled.1602bob # [ 264.495695] systemd[1]: Starting Network Name Resolution...1603alice # [ 264.796860] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1604alice # [ 264.836758] loop: module loaded1605alice # [ 265.220324] systemd[1]: Finished Load Kernel Modules.1606bob # [ 265.448685] systemd[1]: Finished Create Static Device Nodes in /dev.1607alice # [ 265.916799] EXT4-fs (vda): re-mounted 5c4819f4-710d-4f30-88fd-e0e492c9ffc2.1608bob # [ 265.859667] systemd[1]: Reached target Preparation for Local File Systems.1609alice # [ 266.206687] systemd[1]: Queued start job for default target Multi-User System.1610bob # [ 266.143937] systemd[1]: Mounting /run/wrappers...1611alice # [ 266.297911] systemd[1]: systemd-journald.service: Deactivated successfully.1612alice # [ 266.386326] systemd[1]: systemd-journald.service: Consumed 7.087s CPU time over 3min 24.768s wall clock time, 3.6M memory peak.1613alice # [ 266.409750] systemd[1]: Starting Firewall...1614alice # [ 266.482871] systemd-modules-load[536]: Inserted module 'loop'1615bob # [ 267.203582] systemd[1]: Starting Rule-based Manager for Device Events and Files...1616alice # [ 267.857668] systemd[1]: Starting Apply Kernel Variables...1617alice # [ 268.739655] systemd[1]: Started Journal Service.1618alice # [ 269.914176] systemd[1]: Finished Remount Root and Kernel File Systems.1619bob # [ 269.935980] systemd[1]: Mounted /run/wrappers.1620bob # [ 269.991755] systemd[1]: Reached target Local File Systems.1621bob # [ 270.217446] systemd[1]: Listening on Boot Loader Control Service Socket.1622alice # [ 270.471934] systemd[1]: Mounted Kernel Configuration File System.1623bob # [ 270.434232] systemd[1]: Starting register-nix-paths.service...1624alice # [ 270.760678] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1625bob # [ 271.188488] systemd[1]: Starting Create SUID/SGID Wrappers...1626bob # [ 271.245585] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1627alice # [ 273.283794] systemd[1]: Listening on Disk Image Download Service Socket.1628bob # [ 273.252783] systemd[1]: Starting Save Transient machine-id to Disk...1629alice # [ 274.769591] systemd[1]: Starting Flush Journal to Persistent Storage...1630alice # [ 275.194908] systemd-oomd[538]: No swap; memory pressure usage will be degraded1631alice # [ 275.330438] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1632alice # [ 275.989521] systemd[1]: Starting Load/Save OS Random Seed...1633bob # [ 275.953042] systemd[1]: Starting Create System Files and Directories...1634alice # [ 277.098896] systemd[1]: Starting Create Static Device Nodes in /dev...1635alice # [ 277.135001] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1636alice # [ 277.483731] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1637alice # [ 278.498682] systemd[1]: Finished Apply Kernel Variables.1638alice # [ 279.409666] systemd-journald[535]: Received client request to flush runtime journal.1639bob # [ 279.818026] systemd-resolved[561]: Positive Trust Anchors:1640bob # [ 279.968910] systemd-resolved[561]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1641bob # [ 280.047724] systemd-resolved[561]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161642bob # [ 280.165031] systemd-resolved[561]: 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 test1643bob # [ 289.945702] systemd-udevd[571]: Using default interface naming scheme 'v261'.1644alice # [ 290.168690] systemd[1]: Starting Network Name Resolution...1645alice # [ 290.240006] systemd[1]: Finished Load/Save OS Random Seed.1646bob # [ 290.157419] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1647alice # [ 290.350820] systemd[1]: Reached target First Boot Complete.1648bob # [ 290.293979] systemd[1]: Finished Save Transient machine-id to Disk.1649alice # [ 290.456724] systemd[1]: Finished Create Static Device Nodes in /dev.1650alice # [ 290.490665] systemd[1]: Finished Flush Journal to Persistent Storage.1651alice # [ 290.663796] systemd[1]: Reached target Preparation for Local File Systems.1652alice # [ 290.951810] systemd[1]: Mounting /run/wrappers...1653alice # [ 291.725269] systemd[1]: Starting Rule-based Manager for Device Events and Files...1654bob: Guest root shell did not produce any data yet...1655bob: To debug, enter the VM and run 'systemctl status backdoor.service'.1656cleanup1657kill QemuMachine (pid 46)1658alice # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14)1659alice # [2026-09-22T17:40:54Z INFO virtiofsd] Client disconnected, shutting down1660alice # [2026-09-22T17:40:54Z INFO virtiofsd] Client disconnected, shutting down1661alice # [2026-09-22T17:40:54Z INFO virtiofsd] Client disconnected, shutting down1662kill QemuMachine (pid 51)1663bob # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14)1664bob # [2026-09-22T17:40:54Z INFO virtiofsd] Client disconnected, shutting down1665bob # [2026-09-22T17:40:54Z INFO virtiofsd] Client disconnected, shutting down1666bob # [2026-09-22T17:40:54Z INFO virtiofsd] Client disconnected, shutting down1667(finished: cleanup, in 0.30 seconds)1668Traceback (most recent call last):1669 File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in <module>1670 sys.exit(main())1671 ~~~~^^1672 File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/__init__.py", line 194, in main1673 driver.run_tests()1674 ~~~~~~~~~~~~~~~~^^1675 File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 437, in run_tests1676 self.test_script()1677 ~~~~~~~~~~~~~~~~^^1678 File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 395, in test_script1679 exec(self.tests, symbols, None)1680 ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^1681 File "<string>", line 5, in <module>1682 File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 541, in wait_until_succeeds1683 retry(check_success, as_timedelta(timeout))1684 ~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^1685 File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 141, in retry1686 if fn(False):1687 ~~^^^^^^^1688 File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 537, in check_success1689 status, output = self.execute(command, timeout=timeout)1690 ~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^1691 File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 722, in execute1692 return self._execute(1693 ~~~~~~~~~~~~~^1694 command=command,1695 ^^^^^^^^^^^^^^^^1696 ...<2 lines>...1697 timeout=as_timedelta(timeout) if timeout is not None else None,1698 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^1699 )1700 ^1701 File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 983, in _execute1702 self.connect()1703 ~~~~~~~~~~~~^^1704 File "/nix/store/dfqkqnafwd5cfzvqwzq1sfwyyx0lhrr4-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 1172, in connect1705 raise RuntimeError("Shell did not start in time")1706RuntimeError: Shell did not start in time