Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script additionally exposed symbols: alice, bob, clare, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh start all VMs alice: starting vm bob: starting vm clare: starting vm alice # Disk image does not exist, creating the virtualisation disk image... alice # Formatting '/build/vm-state-alice/tmp.wzT0ge55KA', fmt=raw size=1073741824 alice # mke2fs 1.47.4 (6-Mar-2025) alice # Discarding device blocks: 0/262144 done alice # Creating filesystem with 262144 4k blocks and 65536 inodes alice # Filesystem UUID: 03b46383-2d13-42d1-9003-bde9f86a1886 alice # Superblock backups stored on blocks: alice # 32768, 98304, 163840, 229376 alice # alice # Allocating group tables: 0/8 done alice # Writing inode tables: 0/8 done alice # Creating journal (8192 blocks): done alice # Writing superblocks and filesystem accounting information: 0/8 done alice # alice # Virtualisation disk image created. alice: QEMU running (pid 48) bob # Disk image does not exist, creating the virtualisation disk image... bob: QEMU running (pid 49) bob # Formatting '/build/vm-state-bob/tmp.GmVmhYH5GC', fmt=raw size=1073741824 bob # mke2fs 1.47.4 (6-Mar-2025) bob # Discarding device blocks: 0/262144 done bob # Creating filesystem with 262144 4k blocks and 65536 inodes bob # Filesystem UUID: 1c69ab3b-2ed6-4a10-94f1-2f1b6e71a6b2 bob # Superblock backups stored on blocks: bob # 32768, 98304, 163840, 229376 bob # bob # Allocating group tables: 0/8 done bob # Writing inode tables: 0/8 done bob # Creating journal (8192 blocks): done bob # Writing superblocks and filesystem accounting information: 0/8 done bob # bob # Virtualisation disk image created. clare # Disk image does not exist, creating the virtualisation disk image... clare: QEMU running (pid 50) clare # Formatting '/build/vm-state-clare/tmp.EC0Y4Gr6vn', fmt=raw size=1073741824 clare # mke2fs 1.47.4 (6-Mar-2025) clare # Discarding device blocks: 0/262144 done clare # Creating filesystem with 262144 4k blocks and 65536 inodes clare # Filesystem UUID: 7af232b8-c67b-4195-aaa7-eb9743caf149 clare # Superblock backups stored on blocks: clare # 32768, 98304, 163840, 229376 clare # clare # Allocating group tables: 0/8 done clare # Writing inode tables: 0/8 done clare # Creating journal (8192 blocks): done clare # Writing superblocks and filesystem accounting information: 0/8 done clare # clare # Virtualisation disk image created. (finished: start all VMs, in 0.63 seconds) bob: waiting for unit ncps bob: waiting for the VM to finish booting alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] alice # [ 0.000000] Linux version 6.18.44 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sun Aug 9 18:25:30 UTC 2026 alice # [ 0.000000] KASLR enabled alice # [ 0.000000] random: crng init done alice # [ 0.000000] Machine model: linux,dummy-virt alice # [ 0.000000] efi: UEFI not found. alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf] alice # [ 0.000000] Zone ranges: alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] DMA32 empty alice # [ 0.000000] Normal empty alice # [ 0.000000] Device empty alice # [ 0.000000] Movable zone start for each node alice # [ 0.000000] Early memory node ranges alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000000] psci: probing for conduit method from DT. alice # [ 0.000000] psci: PSCIv1.3 detected in firmware. alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs alice # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] psci: SMC Calling Convention v1.1 alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296 alice # [ 0.000000] Detected PIPT I-cache on CPU0 alice # [ 0.000000] CPU features: detected: GICv3 CPU interface alice # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.000000] CPU features: detected: Spectre-BHB alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) alice # [ 0.000000] CPU features: detected: ARM erratum 1418040 alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing alice # [ 0.000000] alternatives: applying boot alternatives alice # [ 0.000000] Kernel command line: console=ttyAMA0 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/i8b7i3j8l63r0j9j8k6k25zvriwfnlry-nixos-system-alice-test/init regInfo=/nix/store/kq4fj1pk42sb99v42bv07xn1lm54cbzs-closure-info/registration console=ttyAMA0,115200n8 console=tty0 alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/kq4fj1pk42sb99v42bv07xn1lm54cbzs-closure-info/registration", will be passed to user space. alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB alice # [ 0.000000] software IO TLB: area num 1. alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) alice # [ 0.000000] Fallback order for Node 0: 0 alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 alice # [ 0.000000] Policy zone: DMA alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.000000] allocated 2097152 bytes of page_ext alice # [ 0.000000] ftrace: allocating 74867 entries in 294 pages alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups alice # [ 0.000000] rcu: Hierarchical RCU implementation. alice # [ 0.000000] rcu: RCU event tracing is enabled. alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. alice # [ 0.000000] Trampoline variant of Tasks RCU enabled. alice # [ 0.000000] Rude variant of Tasks RCU enabled. alice # [ 0.000000] Tracing variant of Tasks RCU enabled. bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 bob # [ 0.000000] Linux version 6.18.44 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sun Aug 9 18:25:30 UTC 2026 alice # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] KASLR enabled clare # [ 0.000000] Linux version 6.18.44 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sun Aug 9 18:25:30 UTC 2026 bob # [ 0.000000] random: crng init done clare # [ 0.000000] KASLR enabled bob # [ 0.000000] Machine model: linux,dummy-virt clare # [ 0.000000] random: crng init done alice # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] efi: UEFI not found. clare # [ 0.000000] Machine model: linux,dummy-virt clare # [ 0.000000] efi: UEFI not found. alice # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] GICv3: 0 Extended SPIs implemented bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf] bob # [ 0.000000] Zone ranges: alice # [ 0.000000] Root IRQ handler: gic_handle_irq clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf] clare # [ 0.000000] Zone ranges: bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI bob # [ 0.000000] DMA32 empty alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] Normal empty clare # [ 0.000000] DMA32 empty bob # [ 0.000000] Device empty clare # [ 0.000000] Normal empty alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 bob # [ 0.000000] Movable zone start for each node clare # [ 0.000000] Device empty alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] bob # [ 0.000000] Early memory node ranges clare # [ 0.000000] Movable zone start for each node clare # [ 0.000000] Early memory node ranges bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] psci: probing for conduit method from DT. clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 bob # [ 0.000000] psci: PSCIv1.3 detected in firmware. clare # [ 0.000000] psci: probing for conduit method from DT. alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs clare # [ 0.000000] psci: PSCIv1.3 detected in firmware. bob # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs bob # [ 0.000000] psci: SMC Calling Convention v1.1 clare # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns clare # [ 0.000000] psci: SMC Calling Convention v1.1 bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296 alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). bob # [ 0.000000] Detected PIPT I-cache on CPU0 clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296 clare # [ 0.000000] Detected PIPT I-cache on CPU0 bob # [ 0.000000] CPU features: detected: GICv3 CPU interface alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns clare # [ 0.000000] CPU features: detected: GICv3 CPU interface bob # [ 0.000000] CPU features: detected: Spectre-v4 clare # [ 0.000000] CPU features: detected: Spectre-v4 bob # [ 0.000000] CPU features: detected: Spectre-BHB alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns clare # [ 0.000000] CPU features: detected: Spectre-BHB alice # [ 0.000076] arm-pv: using stolen time PV bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) alice # [ 0.000485] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) bob # [ 0.000000] CPU features: detected: ARM erratum 1418040 alice # [ 0.000801] Console: colour dummy device 80x25 clare # [ 0.000000] CPU features: detected: ARM erratum 1418040 bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion alice # [ 0.000820] printk: legacy console [tty0] enabled clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing alice # [ 0.001048] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) bob # [ 0.000000] alternatives: applying boot alternatives alice # [ 0.001053] pid_max: default: 32768 minimum: 301 clare # [ 0.000000] alternatives: applying boot alternatives alice # [ 0.001139] LSM: initializing lsm=capability,landlock,yama,bpf,ima alice # [ 0.001296] landlock: Up and running. alice # [ 0.001299] Yama: becoming mindful. alice # [ 0.001847] LSM support for eBPF active alice # [ 0.002087] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.000000] Kernel command line: console=ttyAMA0 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/zsrzanr1i20v2f6jlpwbcckidbhsjbnm-nixos-system-bob-test/init regInfo=/nix/store/rq4vwnjfhln2pcy2l0c1cvg1x8j19wqk-closure-info/registration console=ttyAMA0,115200n8 console=tty0 alice # [ 0.002107] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.000000] Kernel command line: console=ttyAMA0 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/m2g6h78kg79q67m793jjs8209zg2wd2j-nixos-system-clare-test/init regInfo=/nix/store/whz5vnfq14zsvww347jy025pkscvr75s-closure-info/registration console=ttyAMA0,115200n8 console=tty0 alice # [ 0.004198] cacheinfo: Unable to detect cache hierarchy for CPU 0 alice # [ 0.004985] rcu: Hierarchical SRCU implementation. clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/whz5vnfq14zsvww347jy025pkscvr75s-closure-info/registration", will be passed to user space. bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/rq4vwnjfhln2pcy2l0c1cvg1x8j19wqk-closure-info/registration", will be passed to user space. alice # [ 0.004990] rcu: Max phase no-delay instances is 1000. bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.005958] fsl-mc MSI: its@8080000 domain created clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.006121] EFI services will not be available. bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) alice # [ 0.006254] smp: Bringing up secondary CPUs ... alice # [ 0.006283] smp: Brought up 1 node, 1 CPU bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.006287] SMP: Total of 1 processors activated. bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB alice # [ 0.006289] CPU: All CPU(s) started at EL1 clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB bob # [ 0.000000] software IO TLB: area num 1. clare # [ 0.000000] software IO TLB: area num 1. alice # [ 0.006302] CPU features: detected: 32-bit EL0 Support bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) alice # [ 0.006304] CPU features: detected: Data cache clean to the PoU not required for I/D coherence bob # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.000000] Fallback order for Node 0: 0 alice # [ 0.006307] CPU features: detected: Common not Private translations bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 alice # [ 0.006309] CPU features: detected: CRC32 instructions bob # [ 0.000000] Policy zone: DMA clare # [ 0.000000] Policy zone: DMA bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.006311] CPU features: detected: Data cache clean to Point of Persistence clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.006314] CPU features: detected: Enhanced Virtualization Traps clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 bob # [ 0.000000] allocated 2097152 bytes of page_ext clare # [ 0.000000] allocated 2097152 bytes of page_ext alice # [ 0.006326] CPU features: detected: RCpc load-acquire (LDAPR) bob # [ 0.000000] ftrace: allocating 74867 entries in 294 pages clare # [ 0.000000] ftrace: allocating 74867 entries in 294 pages alice # [ 0.006328] CPU features: detected: LSE atomic instructions bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups alice # [ 0.006330] CPU features: detected: Privileged Access Never bob # [ 0.000000] rcu: Hierarchical RCU implementation. clare # [ 0.000000] rcu: Hierarchical RCU implementation. alice # [ 0.006331] CPU features: detected: PMUv3 bob # [ 0.000000] rcu: RCU event tracing is enabled. clare # [ 0.000000] rcu: RCU event tracing is enabled. alice # [ 0.006333] CPU features: detected: RAS Extension Support bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. alice # [ 0.006337] CPU features: detected: Speculative Store Bypassing Safe (SSBS) bob # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.000000] Trampoline variant of Tasks RCU enabled. alice # [ 0.006361] alternatives: applying system-wide alternatives bob # [ 0.000000] Rude variant of Tasks RCU enabled. clare # [ 0.000000] Rude variant of Tasks RCU enabled. alice # [ 0.007889] CPU features: detected: Hardware dirty bit management on CPU0 bob # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.000000] Tracing variant of Tasks RCU enabled. bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. alice # [ 0.008097] Memory: 891480K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 115796K reserved, 32768K cma-reserved) bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 0.008711] devtmpfs: initialized bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.010299] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.010323] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). alice # [ 0.010452] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.010455] 0 pages in range for non-PLT usage bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 alice # [ 0.010456] 508288 pages in range for PLT usage bob # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.000000] GICv3: 256 SPIs implemented alice # [ 0.010603] pinctrl core: initialized pinctrl subsystem bob # [ 0.000000] GICv3: 0 Extended SPIs implemented clare # [ 0.000000] GICv3: 0 Extended SPIs implemented alice # [ 0.011442] DMI not present or invalid. bob # [ 0.000000] Root IRQ handler: gic_handle_irq clare # [ 0.000000] Root IRQ handler: gic_handle_irq alice # [ 0.015281] NET: Registered PF_NETLINK/PF_ROUTE protocol family bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 alice # [ 0.017772] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 alice # [ 0.017916] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] alice # [ 0.018069] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) alice # [ 0.018090] audit: initializing netlink subsys (disabled) alice # [ 0.018750] thermal_sys: Registered thermal governor 'fair_share' clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) alice # [ 0.018752] thermal_sys: Registered thermal governor 'bang_bang' clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 alice # [ 0.018756] thermal_sys: Registered thermal governor 'step_wise' clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 alice # [ 0.018757] thermal_sys: Registered thermal governor 'user_space' clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. alice # [ 0.018759] thermal_sys: Registered thermal governor 'power_allocator' clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns alice # [ 0.018800] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 alice # [ 0.018819] cpuidle: using governor ladder clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 alice # [ 0.018824] cpuidle: using governor menu clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). alice # [ 0.018947] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. alice # [ 0.018971] ASID allocator initialised with 32768 entries clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns alice # [ 0.019671] Serial: AMBA PL011 UART driver clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns clare # [ 0.000067] arm-pv: using stolen time PV alice # [ 0.022899] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 bob # [ 0.000077] arm-pv: using stolen time PV alice # [ 0.023076] printk: console [ttyAMA0] enabled clare # [ 0.000436] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.000465] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) clare # [ 0.000693] Console: colour dummy device 80x25 bob # [ 0.000763] Console: colour dummy device 80x25 clare # [ 0.000712] printk: legacy console [tty0] enabled bob # [ 0.000780] printk: legacy console [tty0] enabled clare # [ 0.000939] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) bob # [ 0.001001] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) clare # [ 0.000945] pid_max: default: 32768 minimum: 301 bob # [ 0.001007] pid_max: default: 32768 minimum: 301 clare # [ 0.001016] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.001090] LSM: initializing lsm=capability,landlock,yama,bpf,ima clare # [ 0.001158] landlock: Up and running. bob # [ 0.001259] landlock: Up and running. clare # [ 0.001161] Yama: becoming mindful. bob # [ 0.001261] Yama: becoming mindful. clare # [ 0.001601] LSM support for eBPF active bob # [ 0.001753] LSM support for eBPF active clare # [ 0.001783] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.001935] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.001803] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.001960] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.003722] cacheinfo: Unable to detect cache hierarchy for CPU 0 bob # [ 0.003854] cacheinfo: Unable to detect cache hierarchy for CPU 0 clare # [ 0.004553] rcu: Hierarchical SRCU implementation. bob # [ 0.004774] rcu: Hierarchical SRCU implementation. clare # [ 0.004558] rcu: Max phase no-delay instances is 1000. bob # [ 0.004779] rcu: Max phase no-delay instances is 1000. clare # [ 0.005545] fsl-mc MSI: its@8080000 domain created alice # [ 0.093524] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.005762] fsl-mc MSI: its@8080000 domain created clare # [ 0.005692] EFI services will not be available. bob # [ 0.005933] EFI services will not be available. alice # [ 0.093548] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page clare # [ 0.005788] smp: Bringing up secondary CPUs ... bob # [ 0.006036] smp: Bringing up secondary CPUs ... clare # [ 0.005815] smp: Brought up 1 node, 1 CPU bob # [ 0.006061] smp: Brought up 1 node, 1 CPU alice # [ 0.093552] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages clare # [ 0.005819] SMP: Total of 1 processors activated. bob # [ 0.006065] SMP: Total of 1 processors activated. alice # [ 0.093559] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page clare # [ 0.005821] CPU: All CPU(s) started at EL1 bob # [ 0.006067] CPU: All CPU(s) started at EL1 clare # [ 0.005833] CPU features: detected: 32-bit EL0 Support alice # [ 0.093562] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 0.006080] CPU features: detected: 32-bit EL0 Support alice # [ 0.093565] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page clare # [ 0.005836] CPU features: detected: Data cache clean to the PoU not required for I/D coherence bob # [ 0.006082] CPU features: detected: Data cache clean to the PoU not required for I/D coherence alice # [ 0.093568] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages clare # [ 0.005839] CPU features: detected: Common not Private translations bob # [ 0.006086] CPU features: detected: Common not Private translations clare # [ 0.005841] CPU features: detected: CRC32 instructions alice # [ 0.093571] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page bob # [ 0.006088] CPU features: detected: CRC32 instructions clare # [ 0.005843] CPU features: detected: Data cache clean to Point of Persistence bob # [ 0.006090] CPU features: detected: Data cache clean to Point of Persistence clare # [ 0.005846] CPU features: detected: Enhanced Virtualization Traps bob # [ 0.006092] CPU features: detected: Enhanced Virtualization Traps clare # [ 0.005855] CPU features: detected: RCpc load-acquire (LDAPR) bob # [ 0.006103] CPU features: detected: RCpc load-acquire (LDAPR) clare # [ 0.005858] CPU features: detected: LSE atomic instructions alice # [ 0.098497] fbcon: Taking over console bob # [ 0.006105] CPU features: detected: LSE atomic instructions clare # [ 0.005860] CPU features: detected: Privileged Access Never alice # [ 0.098516] ACPI: Interpreter disabled. bob # [ 0.006107] CPU features: detected: Privileged Access Never clare # [ 0.005861] CPU features: detected: PMUv3 bob # [ 0.006109] CPU features: detected: PMUv3 clare # [ 0.005863] CPU features: detected: RAS Extension Support bob # [ 0.006111] CPU features: detected: RAS Extension Support clare # [ 0.005867] CPU features: detected: Speculative Store Bypassing Safe (SSBS) bob # [ 0.006115] CPU features: detected: Speculative Store Bypassing Safe (SSBS) clare # [ 0.005892] alternatives: applying system-wide alternatives bob # [ 0.006136] alternatives: applying system-wide alternatives clare # [ 0.007366] CPU features: detected: Hardware dirty bit management on CPU0 bob # [ 0.007656] CPU features: detected: Hardware dirty bit management on CPU0 clare # [ 0.007601] Memory: 891516K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 115792K reserved, 32768K cma-reserved) clare # [ 0.008056] devtmpfs: initialized bob # [ 0.007869] Memory: 891440K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 115796K reserved, 32768K cma-reserved) bob # [ 0.008384] devtmpfs: initialized clare # [ 0.009693] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.009994] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) clare # [ 0.009716] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). bob # [ 0.010019] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). clare # [ 0.009856] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.010175] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL clare # [ 0.009860] 0 pages in range for non-PLT usage bob # [ 0.010179] 0 pages in range for non-PLT usage clare # [ 0.009860] 508288 pages in range for PLT usage bob # [ 0.010179] 508288 pages in range for PLT usage clare # [ 0.009991] pinctrl core: initialized pinctrl subsystem clare # [ 0.010766] DMI not present or invalid. bob # [ 0.010310] pinctrl core: initialized pinctrl subsystem alice # [ 0.105582] iommu: Default domain type: Translated bob # [ 0.011142] DMI not present or invalid. clare # [ 0.014291] NET: Registered PF_NETLINK/PF_ROUTE protocol family alice # [ 0.105595] iommu: DMA domain TLB invalidation policy: strict mode bob # [ 0.014701] NET: Registered PF_NETLINK/PF_ROUTE protocol family clare # [ 0.016808] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations alice # [ 0.105950] SCSI subsystem initialized bob # [ 0.017343] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations clare # [ 0.016953] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 0.017494] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations alice # [ 0.107280] usbcore: registered new interface driver usbfs clare # [ 0.017105] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations alice # [ 0.107319] usbcore: registered new interface driver hub bob # [ 0.017658] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.017128] audit: initializing netlink subsys (disabled) alice # [ 0.107330] usbcore: registered new device driver usb bob # [ 0.017684] audit: initializing netlink subsys (disabled) clare # [ 0.017716] thermal_sys: Registered thermal governor 'fair_share' alice # [ 0.107540] pps_core: LinuxPPS API ver. 1 registered bob # [ 0.018324] thermal_sys: Registered thermal governor 'fair_share' clare # [ 0.017718] thermal_sys: Registered thermal governor 'bang_bang' bob # [ 0.018326] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 0.107545] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti clare # [ 0.017721] thermal_sys: Registered thermal governor 'step_wise' alice # [ 0.107551] PTP clock support registered bob # [ 0.018329] thermal_sys: Registered thermal governor 'step_wise' clare # [ 0.017723] thermal_sys: Registered thermal governor 'user_space' alice # [ 0.107585] EDAC MC: Ver: 3.0.0 bob # [ 0.018331] thermal_sys: Registered thermal governor 'user_space' clare # [ 0.017725] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 0.110582] scmi_core: SCMI protocol bus registered bob # [ 0.018333] thermal_sys: Registered thermal governor 'power_allocator' clare # [ 0.017759] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 bob # [ 0.018373] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 clare # [ 0.017773] cpuidle: using governor ladder clare # [ 0.017778] cpuidle: using governor menu bob # [ 0.018390] cpuidle: using governor ladder alice # [ 0.111260] FPGA manager framework bob # [ 0.018395] cpuidle: using governor menu alice # [ 0.112249] vgaarb: loaded clare # [ 0.017903] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. bob # [ 0.018536] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. clare # [ 0.017927] ASID allocator initialised with 32768 entries clare # [ 0.018649] Serial: AMBA PL011 UART driver bob # [ 0.018559] ASID allocator initialised with 32768 entries bob # [ 0.019274] Serial: AMBA PL011 UART driver alice # [ 0.112678] clocksource: Switched to clocksource arch_sys_counter clare # [ 0.021965] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 alice # [ 0.113253] VFS: Disk quotas dquot_6.6.0 clare # [ 0.022123] printk: console [ttyAMA0] enabled bob # [ 0.022647] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 bob # [ 0.022845] printk: console [ttyAMA0] enabled alice # [ 0.113283] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 0.115005] netfs: FS-Cache loaded alice # [ 0.115126] pnp: PnP ACPI: disabled clare # [ 0.094021] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.095191] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages clare # [ 0.094050] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 0.095217] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page clare # [ 0.094055] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.095221] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages clare # [ 0.094060] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page bob # [ 0.095227] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page clare # [ 0.094064] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 0.095231] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages clare # [ 0.094067] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 0.095234] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page clare # [ 0.094070] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages bob # [ 0.095237] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages clare # [ 0.094073] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page bob # [ 0.095240] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page clare # [ 0.099131] fbcon: Taking over console bob # [ 0.100350] fbcon: Taking over console clare # [ 0.099153] ACPI: Interpreter disabled. bob # [ 0.100371] ACPI: Interpreter disabled. clare # [ 0.100398] iommu: Default domain type: Translated bob # [ 0.101625] iommu: Default domain type: Translated clare # [ 0.100408] iommu: DMA domain TLB invalidation policy: strict mode alice # [ 0.122589] NET: Registered PF_INET protocol family bob # [ 0.101644] iommu: DMA domain TLB invalidation policy: strict mode alice # [ 0.122804] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) clare # [ 0.101541] SCSI subsystem initialized bob # [ 0.102807] SCSI subsystem initialized clare # [ 0.102146] usbcore: registered new interface driver usbfs clare # [ 0.102191] usbcore: registered new interface driver hub clare # [ 0.102201] usbcore: registered new device driver usb clare # [ 0.102398] pps_core: LinuxPPS API ver. 1 registered clare # [ 0.102403] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti clare # [ 0.102409] PTP clock support registered clare # [ 0.102442] EDAC MC: Ver: 3.0.0 clare # [ 0.110053] scmi_core: SCMI protocol bus registered bob # [ 0.111469] usbcore: registered new interface driver usbfs bob # [ 0.111511] usbcore: registered new interface driver hub clare # [ 0.110803] FPGA manager framework bob # [ 0.111524] usbcore: registered new device driver usb clare # [ 0.111858] vgaarb: loaded bob # [ 0.111764] pps_core: LinuxPPS API ver. 1 registered bob # [ 0.111770] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti bob # [ 0.111776] PTP clock support registered clare # [ 0.112281] clocksource: Switched to clocksource arch_sys_counter bob # [ 0.111805] EDAC MC: Ver: 3.0.0 clare # [ 0.112863] VFS: Disk quotas dquot_6.6.0 clare # [ 0.112896] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.114788] scmi_core: SCMI protocol bus registered clare # [ 0.114677] netfs: FS-Cache loaded clare # [ 0.114803] pnp: PnP ACPI: disabled bob # [ 0.115585] FPGA manager framework bob # [ 0.116695] vgaarb: loaded bob # [ 0.117155] clocksource: Switched to clocksource arch_sys_counter bob # [ 0.117762] VFS: Disk quotas dquot_6.6.0 bob # [ 0.117798] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.119476] netfs: FS-Cache loaded bob # [ 0.119596] pnp: PnP ACPI: disabled bob # [ 0.122330] NET: Registered PF_INET protocol family bob # [ 0.122528] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.151821] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.151855] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) alice # [ 0.151870] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) alice # [ 0.151912] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.151980] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.152090] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 0.152120] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.122028] NET: Registered PF_INET protocol family alice # [ 0.152144] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.122237] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.152271] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 0.152342] NET: Registered PF_XDP protocol family alice # [ 0.152361] PCI: CLS 0 bytes, default 64 alice # [ 0.158235] Trying to unpack rootfs image as initramfs... alice # [ 0.161155] kvm [1]: HYP mode not available clare # [ 0.151840] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) clare # [ 0.151876] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) clare # [ 0.151890] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) clare # [ 0.151931] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) clare # [ 0.151999] TCP: Hash tables configured (established 8192 bind 8192) clare # [ 0.152102] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) clare # [ 0.152133] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.152157] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.152275] NET: Registered PF_UNIX/PF_LOCAL protocol family clare # [ 0.157180] NET: Registered PF_XDP protocol family clare # [ 0.157212] PCI: CLS 0 bytes, default 64 bob # [ 0.153863] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) clare # [ 0.158109] Trying to unpack rootfs image as initramfs... bob # [ 0.153899] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.153915] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 0.153959] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) bob # [ 0.154027] TCP: Hash tables configured (established 8192 bind 8192) bob # [ 0.154131] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) bob # [ 0.154198] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.154252] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.154375] NET: Registered PF_UNIX/PF_LOCAL protocol family bob # [ 0.154418] NET: Registered PF_XDP protocol family bob # [ 0.154437] PCI: CLS 0 bytes, default 64 bob # [ 0.154662] Trying to unpack rootfs image as initramfs... bob # [ 0.167226] kvm [1]: HYP mode not available clare # [ 0.168357] kvm [1]: HYP mode not available alice # [ 0.237083] Initialise system trusted keyrings alice # [ 0.237607] workingset: timestamp_bits=42 max_order=18 bucket_order=0 alice # [ 0.238618] squashfs: version 4.0 (2009/01/31) Phillip Lougher alice # [ 0.239125] 9p: Installing v9fs 9p2000 file system support alice # [ 0.258526] Key type asymmetric registered alice # [ 0.258553] Asymmetric key parser 'x509' registered alice # [ 0.258626] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) clare # [ 0.237240] Initialise system trusted keyrings alice # [ 0.264957] io scheduler mq-deadline registered alice # [ 0.264981] io scheduler kyber registered clare # [ 0.237776] workingset: timestamp_bits=42 max_order=18 bucket_order=0 clare # [ 0.238755] squashfs: version 4.0 (2009/01/31) Phillip Lougher clare # [ 0.239271] 9p: Installing v9fs 9p2000 file system support bob # [ 0.241564] Initialise system trusted keyrings alice # [ 0.267964] pl061_gpio 9030000.pl061: PL061 GPIO chip registered bob # [ 0.242119] workingset: timestamp_bits=42 max_order=18 bucket_order=0 bob # [ 0.243158] squashfs: version 4.0 (2009/01/31) Phillip Lougher bob # [ 0.243689] 9p: Installing v9fs 9p2000 file system support alice # [ 0.268816] ledtrig-cpu: registered to indicate activity on CPUs alice # [ 0.269155] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: alice # [ 0.269179] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 alice # [ 0.269198] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 alice # [ 0.269204] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 alice # [ 0.269233] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits alice # [ 0.269267] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] alice # [ 0.269358] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 alice # [ 0.269383] pci_bus 0000:00: root bus resource [bus 00-ff] alice # [ 0.269388] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] alice # [ 0.269392] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.269396] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.269487] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint alice # [ 0.269922] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.270050] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.270062] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.270084] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.270096] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.270414] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.270537] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.270548] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.270572] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.270869] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint alice # [ 0.270989] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.271000] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.271022] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.271320] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint clare # [ 0.258737] Key type asymmetric registered alice # [ 0.271440] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.258765] Asymmetric key parser 'x509' registered alice # [ 0.271452] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.258840] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) alice # [ 0.271472] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.271781] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint alice # [ 0.271905] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.271917] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.271938] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.272252] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.272371] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.272382] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.272402] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.264368] io scheduler mq-deadline registered clare # [ 0.264391] io scheduler kyber registered bob # [ 0.266827] Key type asymmetric registered bob # [ 0.266859] Asymmetric key parser 'x509' registered bob # [ 0.266931] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) bob # [ 0.268325] io scheduler mq-deadline registered bob # [ 0.268337] io scheduler kyber registered alice # [ 0.296879] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.297027] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.297038] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.297060] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.297078] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.297438] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint alice # [ 0.297560] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.297580] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.297888] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 0.298013] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.298033] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.298304] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 0.298422] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] alice # [ 0.298597] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint alice # [ 0.298811] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] clare # [ 0.272617] pl061_gpio 9030000.pl061: PL061 GPIO chip registered alice # [ 0.298825] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.272989] ledtrig-cpu: registered to indicate activity on CPUs alice # [ 0.298846] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.273324] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: alice # [ 0.299151] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.273348] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 alice # [ 0.299272] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.273371] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 alice # [ 0.299284] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.299305] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.273378] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 alice # [ 0.299709] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned clare # [ 0.273419] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits bob # [ 0.277420] pl061_gpio 9030000.pl061: PL061 GPIO chip registered alice # [ 0.299721] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned bob # [ 0.277829] ledtrig-cpu: registered to indicate activity on CPUs clare # [ 0.273452] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] alice # [ 0.299726] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned bob # [ 0.278157] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: clare # [ 0.273541] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 alice # [ 0.299760] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned clare # [ 0.273560] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 0.278180] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 alice # [ 0.299792] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned clare # [ 0.273565] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] bob # [ 0.278200] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 clare # [ 0.273569] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.299823] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.278206] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.273573] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.299853] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.278240] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits clare # [ 0.273673] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint alice # [ 0.299883] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned bob # [ 0.278277] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.274104] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.299915] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.274232] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.278356] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 alice # [ 0.299947] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned clare # [ 0.274243] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.278376] pci_bus 0000:00: root bus resource [bus 00-ff] alice # [ 0.299978] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned clare # [ 0.274265] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.278381] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] clare # [ 0.274277] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.300009] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned bob # [ 0.278385] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] clare # [ 0.274587] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.278389] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.300072] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned clare # [ 0.274710] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.300102] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned bob # [ 0.278490] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint clare # [ 0.274721] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.300115] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned bob # [ 0.278930] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.274742] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.300129] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.279058] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.275040] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint alice # [ 0.300143] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.279070] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.275163] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.300156] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned bob # [ 0.279092] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.275174] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.300170] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.279105] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.275195] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.300183] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.279418] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.275502] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint alice # [ 0.300197] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.279543] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.275625] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.279555] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.300210] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned clare # [ 0.275637] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.279576] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.300238] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned clare # [ 0.275657] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.300253] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.279879] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint clare # [ 0.275961] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint bob # [ 0.280002] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.300267] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned clare # [ 0.276084] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.300280] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.280014] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.276095] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.280035] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.276115] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.300294] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned alice # [ 0.300307] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned bob # [ 0.280339] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint clare # [ 0.276450] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.300321] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned bob # [ 0.280462] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.276587] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.300334] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.280473] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.276598] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.300354] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned clare # [ 0.276619] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.280495] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.300367] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned bob # [ 0.280805] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint clare # [ 0.276929] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.300381] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned bob # [ 0.280930] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.277051] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.300395] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned bob # [ 0.280942] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.277063] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.300415] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.280963] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.277084] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.300421] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] clare # [ 0.277099] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.300425] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] bob # [ 0.281308] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint bob # [ 0.281434] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] clare # [ 0.277410] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint bob # [ 0.281446] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.277536] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.281467] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.277556] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.281801] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.277871] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint bob # [ 0.281930] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.277999] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.281941] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.278020] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.281962] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.278291] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint bob # [ 0.281977] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.278411] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] bob # [ 0.282295] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.278577] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint bob # [ 0.282423] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.278810] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.282443] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.278824] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.278844] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.282753] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint bob # [ 0.282880] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.279158] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.279284] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.282901] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.279295] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.283165] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.279317] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.283290] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.279736] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned alice # [ 0.337286] pci 0000:00:0a.0: enabling device (0000 -> 0002) bob # [ 0.283467] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint clare # [ 0.279748] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned bob # [ 0.283630] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.283643] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.279753] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned bob # [ 0.283664] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.279790] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned bob # [ 0.283976] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.279823] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned bob # [ 0.284100] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.284113] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.279855] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.284134] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.279886] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.284565] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned clare # [ 0.279917] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned bob # [ 0.284579] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned clare # [ 0.279950] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned bob # [ 0.284584] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned clare # [ 0.279982] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.284619] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned clare # [ 0.280013] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.284651] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned clare # [ 0.280044] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned alice # [ 0.343907] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) bob # [ 0.284683] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned clare # [ 0.280103] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned bob # [ 0.284715] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned clare # [ 0.280134] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned bob # [ 0.284747] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned clare # [ 0.280148] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.280162] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.284779] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.280176] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.284812] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned clare # [ 0.280190] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned bob # [ 0.284844] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned clare # [ 0.280204] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.284875] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned clare # [ 0.280218] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned clare # [ 0.280231] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.284931] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned clare # [ 0.280245] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.284962] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned clare # [ 0.280259] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.284976] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.280274] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.284989] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.285003] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.285017] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned bob # [ 0.285031] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.285045] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.285059] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.285073] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.285087] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.285101] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.285115] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned bob # [ 0.285128] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.285142] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned alice # [ 0.353950] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.355832] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) clare # [ 0.328380] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned clare # [ 0.328412] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned clare # [ 0.328431] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned clare # [ 0.328448] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned clare # [ 0.328463] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned clare # [ 0.328479] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned clare # [ 0.328511] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned clare # [ 0.328527] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned clare # [ 0.328542] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned clare # [ 0.328558] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned clare # [ 0.328587] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] clare # [ 0.328593] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] clare # [ 0.328597] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] clare # [ 0.329381] pci 0000:00:0a.0: enabling device (0000 -> 0002) bob # [ 0.337256] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned bob # [ 0.337299] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned bob # [ 0.337317] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.337339] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned bob # [ 0.337355] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned bob # [ 0.337371] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned alice # [ 0.365885] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) bob # [ 0.337388] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned bob # [ 0.337414] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] alice # [ 0.367795] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) bob # [ 0.337420] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] bob # [ 0.337424] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] bob # [ 0.338202] pci 0000:00:0a.0: enabling device (0000 -> 0002) alice # [ 0.370854] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) clare # [ 0.349454] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) clare # [ 0.351431] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.376847] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) alice # [ 0.379122] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.353870] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) bob # [ 0.355865] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) bob # [ 0.358068] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) bob # [ 0.360050] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.362226] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) clare # [ 0.364107] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.367330] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) alice # [ 0.388907] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.391018] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) alice # [ 0.393224] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) clare # [ 0.374039] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) alice # [ 0.405502] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.378301] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) bob # [ 0.380426] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) alice # [ 0.407079] msm_serial: driver initialized alice # [ 0.407167] SuperH (H)SCI(F) driver initialized alice # [ 0.407202] STM32 USART driver initialized bob # [ 0.383411] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) clare # [ 0.380782] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) clare # [ 0.382881] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.385937] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.387885] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) clare # [ 0.392481] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) clare # [ 0.394257] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) bob # [ 0.397857] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) bob # [ 0.399815] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) clare # [ 0.400487] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) alice # [ 0.433704] loop: module loaded alice # [ 0.433856] virtio_blk virtio5: 1/0/0 default/read/poll queues alice # [ 0.434497] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) clare # [ 0.409312] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.412005] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled clare # [ 0.410902] msm_serial: driver initialized clare # [ 0.411010] SuperH (H)SCI(F) driver initialized clare # [ 0.411046] STM32 USART driver initialized bob # [ 0.413623] msm_serial: driver initialized bob # [ 0.413734] SuperH (H)SCI(F) driver initialized bob # [ 0.413772] STM32 USART driver initialized alice # [ 0.441156] megasas: 07.734.00.00-rc1 alice # [ 0.441670] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] alice # [ 0.453884] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.453929] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.455713] Using buffer write method alice # [ 0.455760] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.459653] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.459679] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.462650] Using buffer write method alice # [ 0.462678] Concatenating MTD devices: alice # [ 0.462681] (0): "0.flash" alice # [ 0.462685] (1): "0.flash" alice # [ 0.462687] into device "0.flash" clare # [ 0.437372] loop: module loaded bob # [ 0.438485] loop: module loaded clare # [ 0.437516] virtio_blk virtio5: 1/0/0 default/read/poll queues bob # [ 0.438637] virtio_blk virtio5: 1/0/0 default/read/poll queues clare # [ 0.438159] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) bob # [ 0.439268] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) clare # [ 0.444793] megasas: 07.734.00.00-rc1 clare # [ 0.445338] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] clare # [ 0.451017] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.445673] megasas: 07.734.00.00-rc1 clare # [ 0.451059] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.446205] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] bob # [ 0.451236] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.451296] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.453046] Using buffer write method bob # [ 0.453091] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] clare # [ 0.456874] Using buffer write method clare # [ 0.456926] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] clare # [ 0.458861] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.462075] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.458887] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.462110] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.463663] Using buffer write method bob # [ 0.463690] Concatenating MTD devices: bob # [ 0.463693] (0): "0.flash" bob # [ 0.463696] (1): "0.flash" bob # [ 0.463698] into device "0.flash" clare # [ 0.468780] Using buffer write method clare # [ 0.468811] Concatenating MTD devices: clare # [ 0.468814] (0): "0.flash" clare # [ 0.468817] (1): "0.flash" clare # [ 0.468820] into device "0.flash" alice # [ 0.705954] Freeing initrd memory: 26996K alice # [ 0.713141] tun: Universal TUN/TAP device driver, 1.6 alice # [ 0.715868] thunder_xcv, ver 1.0 alice # [ 0.715905] thunder_bgx, ver 1.0 alice # [ 0.715917] nicpf, ver 1.0 alice # [ 0.716212] e1000: Intel(R) PRO/1000 Network Driver alice # [ 0.716241] e1000: Copyright (c) 1999-2006 Intel Corporation. alice # [ 0.716258] e1000e: Intel(R) PRO/1000 Network Driver alice # [ 0.716266] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. alice # [ 0.716279] igb: Intel(R) Gigabit Ethernet Network Driver alice # [ 0.716283] igb: Copyright (c) 2007-2014 Intel Corporation. alice # [ 0.716294] igbvf: Intel(R) Gigabit Virtual Function Network Driver alice # [ 0.716297] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. alice # [ 0.716362] sky2: driver version 1.30 alice # [ 0.721659] usbcore: registered new interface driver usb-storage alice # [ 0.721763] usbcore: registered new interface driver usbserial_generic alice # [ 0.721772] usbserial: USB Serial support registered for generic alice # [ 0.722059] hv_vmbus: registering driver hyperv_keyboard alice # [ 0.723167] ehci-pci 0000:00:0a.0: EHCI Host Controller alice # [ 0.723203] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 alice # [ 0.723447] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 alice # [ 0.726893] rtc-pl031 9010000.pl031: registered as rtc0 alice # [ 0.726935] rtc-pl031 9010000.pl031: setting system clock to 2026-08-18T08:23:30 UTC (1787041410) alice # [ 0.727132] i2c_dev: i2c /dev entries driver clare # [ 0.707054] Freeing initrd memory: 26992K clare # [ 0.714213] tun: Universal TUN/TAP device driver, 1.6 bob # [ 0.713547] Freeing initrd memory: 26996K bob # [ 0.720776] tun: Universal TUN/TAP device driver, 1.6 clare # [ 0.717073] thunder_xcv, ver 1.0 clare # [ 0.717106] thunder_bgx, ver 1.0 clare # [ 0.717118] nicpf, ver 1.0 clare # [ 0.717412] e1000: Intel(R) PRO/1000 Network Driver clare # [ 0.717421] e1000: Copyright (c) 1999-2006 Intel Corporation. clare # [ 0.717437] e1000e: Intel(R) PRO/1000 Network Driver clare # [ 0.717443] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. clare # [ 0.717457] igb: Intel(R) Gigabit Ethernet Network Driver clare # [ 0.717460] igb: Copyright (c) 2007-2014 Intel Corporation. clare # [ 0.717471] igbvf: Intel(R) Gigabit Virtual Function Network Driver clare # [ 0.717475] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. clare # [ 0.717537] sky2: driver version 1.30 clare # [ 0.718333] usbcore: registered new interface driver usb-storage bob # [ 0.723496] thunder_xcv, ver 1.0 clare # [ 0.718382] usbcore: registered new interface driver usbserial_generic bob # [ 0.723529] thunder_bgx, ver 1.0 bob # [ 0.723541] nicpf, ver 1.0 clare # [ 0.718390] usbserial: USB Serial support registered for generic bob # [ 0.723845] e1000: Intel(R) PRO/1000 Network Driver clare # [ 0.718674] hv_vmbus: registering driver hyperv_keyboard bob # [ 0.723854] e1000: Copyright (c) 1999-2006 Intel Corporation. clare # [ 0.719697] ehci-pci 0000:00:0a.0: EHCI Host Controller bob # [ 0.723870] e1000e: Intel(R) PRO/1000 Network Driver clare # [ 0.719731] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 bob # [ 0.723876] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. clare # [ 0.720028] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 bob # [ 0.723889] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.723892] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.723903] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.723906] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. bob # [ 0.723970] sky2: driver version 1.30 clare # [ 0.727647] rtc-pl031 9010000.pl031: registered as rtc0 bob # [ 0.724779] usbcore: registered new interface driver usb-storage alice # [ 0.729974] sdhci: Secure Digital Host Controller Interface driver clare # [ 0.727690] rtc-pl031 9010000.pl031: setting system clock to 2026-08-18T08:23:31 UTC (1787041411) alice # [ 0.729990] sdhci: Copyright(c) Pierre Ossman bob # [ 0.724856] usbcore: registered new interface driver usbserial_generic alice # [ 0.730120] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.730283] sdhci-pltfm: SDHCI platform and OF driver helper clare # [ 0.727904] i2c_dev: i2c /dev entries driver alice # [ 0.731229] hid: raw HID events driver (C) Jiri Kosina bob # [ 0.724865] usbserial: USB Serial support registered for generic clare # [ 0.728428] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 bob # [ 0.725833] ehci-pci 0000:00:0a.0: EHCI Host Controller alice # [ 0.731402] usbcore: registered new interface driver usbhid clare # [ 0.728779] hub 1-0:1.0: USB hub found bob # [ 0.725871] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 bob # [ 0.726045] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 clare # [ 0.728804] hub 1-0:1.0: 6 ports detected alice # [ 0.731407] usbhid: USB HID core driver clare # [ 0.732259] sdhci: Secure Digital Host Controller Interface driver bob # [ 0.732942] hv_vmbus: registering driver hyperv_keyboard clare # [ 0.732274] sdhci: Copyright(c) Pierre Ossman alice # [ 0.758971] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 alice # [ 0.759425] hub 1-0:1.0: USB hub found bob # [ 0.734116] rtc-pl031 9010000.pl031: registered as rtc0 alice # [ 0.759451] hub 1-0:1.0: 6 ports detected alice # [ 0.759760] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available alice # [ 0.760575] drop_monitor: Initializing network drop monitor service clare # [ 0.733186] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.760784] NET: Registered PF_INET6 protocol family bob # [ 0.734155] rtc-pl031 9010000.pl031: setting system clock to 2026-08-18T08:23:31 UTC (1787041411) clare # [ 0.733363] sdhci-pltfm: SDHCI platform and OF driver helper bob # [ 0.734336] i2c_dev: i2c /dev entries driver clare # [ 0.734312] hid: raw HID events driver (C) Jiri Kosina clare # [ 0.734454] usbcore: registered new interface driver usbhid alice # [ 0.763971] Segment Routing with IPv6 alice # [ 0.764000] In-situ OAM (IOAM) with IPv6 clare # [ 0.734458] usbhid: USB HID core driver alice # [ 0.764048] NET: Registered PF_PACKET protocol family bob # [ 0.737332] sdhci: Secure Digital Host Controller Interface driver clare # [ 0.737071] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available bob # [ 0.737346] sdhci: Copyright(c) Pierre Ossman clare # [ 0.737892] drop_monitor: Initializing network drop monitor service bob # [ 0.737488] Synopsys Designware Multimedia Card Interface Driver clare # [ 0.738048] NET: Registered PF_INET6 protocol family bob # [ 0.737673] sdhci-pltfm: SDHCI platform and OF driver helper bob # [ 0.738619] hid: raw HID events driver (C) Jiri Kosina clare # [ 0.740188] Segment Routing with IPv6 bob # [ 0.738784] usbcore: registered new interface driver usbhid clare # [ 0.740215] In-situ OAM (IOAM) with IPv6 bob # [ 0.738790] usbhid: USB HID core driver alice # [ 0.765144] 9pnet: Installing 9P2000 support clare # [ 0.740907] NET: Registered PF_PACKET protocol family alice # [ 0.766812] Key type dns_resolver registered bob # [ 0.742153] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 bob # [ 0.742536] hub 1-0:1.0: USB hub found bob # [ 0.743282] hub 1-0:1.0: 6 ports detected clare # [ 0.741408] 9pnet: Installing 9P2000 support bob # [ 0.743917] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available clare # [ 0.743049] Key type dns_resolver registered bob # [ 0.744734] drop_monitor: Initializing network drop monitor service alice # [ 0.770505] registered taskstats version 1 bob # [ 0.744857] NET: Registered PF_INET6 protocol family alice # [ 0.770696] Loading compiled-in X.509 certificates bob # [ 0.747264] Segment Routing with IPv6 bob # [ 0.747304] In-situ OAM (IOAM) with IPv6 bob # [ 0.747353] NET: Registered PF_PACKET protocol family bob # [ 0.748429] 9pnet: Installing 9P2000 support clare # [ 0.746706] registered taskstats version 1 clare # [ 0.746858] Loading compiled-in X.509 certificates alice # [ 0.776034] Demotion targets for Node 0: null alice # [ 0.776127] Key type .fscrypt registered alice # [ 0.776132] Key type fscrypt-provisioning registered alice # [ 0.776290] ima: No TPM chip found, activating TPM-bypass! alice # [ 0.776305] ima: Allocated hash algorithm: sha1 alice # [ 0.776353] ima: No architecture policies found alice # [ 0.779369] input: gpio-keys as /devices/platform/gpio-keys/input/input0 bob # [ 0.750390] Key type dns_resolver registered clare # [ 0.752238] Demotion targets for Node 0: null clare # [ 0.752997] Key type .fscrypt registered clare # [ 0.753010] Key type fscrypt-provisioning registered clare # [ 0.753127] ima: No TPM chip found, activating TPM-bypass! clare # [ 0.753141] ima: Allocated hash algorithm: sha1 clare # [ 0.753190] ima: No architecture policies found clare # [ 0.755506] input: gpio-keys as /devices/platform/gpio-keys/input/input0 bob # [ 0.753735] registered taskstats version 1 bob # [ 0.753935] Loading compiled-in X.509 certificates bob # [ 0.759492] Demotion targets for Node 0: null bob # [ 0.759594] Key type .fscrypt registered bob # [ 0.759600] Key type fscrypt-provisioning registered bob # [ 0.759711] ima: No TPM chip found, activating TPM-bypass! bob # [ 0.759724] ima: Allocated hash algorithm: sha1 bob # [ 0.759774] ima: No architecture policies found bob # [ 0.762833] input: gpio-keys as /devices/platform/gpio-keys/input/input0 alice # [ 0.790432] clk: Disabling unused clocks alice # [ 0.790467] PM: genpd: Disabling unused power domains alice # [ 0.793729] Freeing unused kernel memory: 4736K alice # [ 0.793845] Run /init as init process clare # [ 0.766474] clk: Disabling unused clocks clare # [ 0.766506] PM: genpd: Disabling unused power domains clare # [ 0.769790] Freeing unused kernel memory: 4736K clare # [ 0.769904] Run /init as init process bob # [ 0.774128] clk: Disabling unused clocks bob # [ 0.774162] PM: genpd: Disabling unused power domains bob # [ 0.777486] Freeing unused kernel memory: 4736K bob # [ 0.777606] Run /init as init process alice # [ 0.815680] systemd[1]: Successfully made /usr/ read-only. clare # [ 0.790947] systemd[1]: Successfully made /usr/ read-only. bob # [ 0.799888] systemd[1]: Successfully made /usr/ read-only. clare # [ 0.976364] usb 1-1: new high-speed USB device number 2 using ehci-pci alice # [ 1.004715] usb 1-1: new high-speed USB device number 2 using ehci-pci bob # [ 0.989206] usb 1-1: new high-speed USB device number 2 using ehci-pci clare # [ 1.123673] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 alice # [ 1.150433] systemd[1]: systemd 261.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) alice # [ 1.154648] systemd[1]: Detected virtualization qemu. alice # [ 1.155300] systemd[1]: Detected architecture arm64. alice # [ 1.155905] systemd[1]: Running in initrd. clare # [ 1.125645] systemd[1]: systemd 261.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) alice # [ 1.157302] systemd[1]: Initializing machine ID from random generator. clare # [ 1.129270] systemd[1]: Detected virtualization qemu. alice # [ 1.158350] systemd[1]: Hostname set to . clare # [ 1.129317] systemd[1]: Detected architecture arm64. clare # [ 1.129327] systemd[1]: Running in initrd. clare # [ 1.129918] systemd[1]: Initializing machine ID from random generator. clare # [ 1.130124] systemd[1]: Hostname set to . alice # [ 1.162123] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 bob # [ 1.134507] systemd[1]: systemd 261.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 1.137643] systemd[1]: Detected virtualization qemu. bob # [ 1.138175] systemd[1]: Detected architecture arm64. bob # [ 1.138660] systemd[1]: Running in initrd. bob # [ 1.139582] systemd[1]: Initializing machine ID from random generator. bob # [ 1.140658] systemd[1]: Hostname set to . bob # [ 1.145855] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 clare # [ 1.200545] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 alice # [ 1.240878] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 bob # [ 1.225544] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 clare # [ 1.320307] usb 1-2: new high-speed USB device number 3 using ehci-pci alice # [ 1.360719] usb 1-2: new high-speed USB device number 3 using ehci-pci bob # [ 1.345222] usb 1-2: new high-speed USB device number 3 using ehci-pci clare # [ 1.357707] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 1.394340] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 1.376516] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 1.417599] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.426037] systemd[1]: Created slice Slice /system/modprobe. alice # [ 1.454006] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.426734] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 1.427395] systemd[1]: Expecting device /dev/disk/by-label/nixos... clare # [ 1.427933] systemd[1]: Reached target Path Units. clare # [ 1.428367] systemd[1]: Reached target Slice Units. clare # [ 1.428811] systemd[1]: Reached target Swaps. clare # [ 1.429184] systemd[1]: Reached target Timer Units. clare # [ 1.429751] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 1.430436] systemd[1]: Listening on Journal Socket (/dev/log). clare # [ 1.431041] systemd[1]: Listening on Journal Sockets. clare # [ 1.431552] systemd[1]: Listening on udev Control Socket. clare # [ 1.432101] systemd[1]: Listening on udev Kernel Socket. clare # [ 1.432667] systemd[1]: Reached target Socket Units. clare # [ 1.434286] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.462323] systemd[1]: Created slice Slice /system/modprobe. alice # [ 1.463003] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 1.463667] systemd[1]: Expecting device /dev/disk/by-label/nixos... alice # [ 1.464216] systemd[1]: Reached target Path Units. alice # [ 1.464627] systemd[1]: Reached target Slice Units. alice # [ 1.465128] systemd[1]: Reached target Swaps. alice # [ 1.465500] systemd[1]: Reached target Timer Units. alice # [ 1.466102] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 1.466803] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 1.467412] systemd[1]: Listening on Journal Sockets. alice # [ 1.467933] systemd[1]: Listening on udev Control Socket. clare # [ 1.440196] systemd[1]: Starting Load Kernel Module 9pnet_virtio... alice # [ 1.468484] systemd[1]: Listening on udev Kernel Socket. alice # [ 1.469002] systemd[1]: Reached target Socket Units. alice # [ 1.470640] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 1.447095] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.476448] systemd[1]: Starting Load Kernel Module 9pnet_virtio... bob # [ 1.456242] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.454438] systemd[1]: Mounting Kernel Configuration File System... alice # [ 1.485001] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.488636] systemd[1]: Mounting Kernel Configuration File System... clare # [ 1.464690] systemd[1]: Starting Journal Service... bob # [ 1.467462] systemd[1]: Created slice Slice /system/modprobe. bob # [ 1.468224] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 1.466572] systemd[1]: Starting Load Kernel Modules... bob # [ 1.468989] systemd[1]: Expecting device /dev/disk/by-label/nixos... clare # [ 1.466676] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 1.469711] systemd[1]: Reached target Path Units. bob # [ 1.470182] systemd[1]: Reached target Slice Units. bob # [ 1.470644] systemd[1]: Reached target Swaps. bob # [ 1.471052] systemd[1]: Reached target Timer Units. bob # [ 1.471754] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 1.472548] systemd[1]: Listening on Journal Socket (/dev/log). bob # [ 1.473301] systemd[1]: Listening on Journal Sockets. bob # [ 1.473913] systemd[1]: Listening on udev Control Socket. bob # [ 1.474536] systemd[1]: Listening on udev Kernel Socket. bob # [ 1.475052] systemd[1]: Reached target Socket Units. alice # [ 1.500867] systemd[1]: Starting Journal Service... clare # [ 1.474126] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 clare # [ 1.475192] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 bob # [ 1.476809] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 1.476277] systemd[1]: Starting Coldplug All udev Devices... alice # [ 1.504847] systemd[1]: Starting Load Kernel Modules... alice # [ 1.505411] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 1.485337] systemd[1]: Starting Load Kernel Module 9pnet_virtio... alice # [ 1.512387] systemd[1]: Starting Coldplug All udev Devices... bob # [ 1.486030] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 1.487947] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 1.490064] systemd[1]: Mounting Kernel Configuration File System... clare # [ 1.493204] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. bob # [ 1.495398] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 bob # [ 1.497119] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 clare # [ 1.496671] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 1.525051] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 alice # [ 1.525155] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 bob # [ 1.505537] systemd[1]: Starting Journal Service... alice # [ 1.536908] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 1.537908] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. alice # [ 1.538111] systemd[1]: Finished Load Kernel Module 9pnet_virtio. clare # [ 1.520538] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 1.521009] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.550848] systemd-journald[73]: Collecting audit messages is disabled. bob # [ 1.524883] systemd[1]: Starting Load Kernel Modules... bob # [ 1.525752] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 1.535472] systemd-journald[73]: Collecting audit messages is disabled. clare # [ 1.536588] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 1.538184] systemd[1]: Starting Coldplug All udev Devices... clare # [ 1.542151] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. clare # [ 1.543560] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 1.579144] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 1.553429] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 1.554732] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. clare # [ 1.556493] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev alice # [ 1.584965] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.586007] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 1.589034] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 1.562063] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 clare # [ 1.562299] [drm] features: -virgl +edid -resource_blob -host_visible clare # [ 1.562307] [drm] features: -context_init clare # [ 1.562795] [drm] number of scanouts: 1 clare # [ 1.562810] [drm] number of cap sets: 0 bob # [ 1.569381] systemd[1]: Finished Load Kernel Module 9pnet_virtio. bob # [ 1.569887] systemd[1]: Mounted Kernel Configuration File System. bob # [ 1.571934] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.603661] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. clare # [ 1.579822] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 1.579977] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 1.580007] systemd[1]: Reached target Local File Systems. clare # [ 1.582744] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 1.585604] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. alice # [ 1.616897] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev bob # [ 1.597829] systemd-journald[73]: Collecting audit messages is disabled. bob # [ 1.601779] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev alice # [ 1.624815] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 alice # [ 1.625056] [drm] features: -virgl +edid -resource_blob -host_visible clare # [ 1.596534] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic alice # [ 1.625063] [drm] features: -context_init alice # [ 1.625524] [drm] number of scanouts: 1 clare # [ 1.596554] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 alice # [ 1.625540] [drm] number of cap sets: 0 alice # [ 1.641897] systemd[1]: Started Journal Service. bob # [ 1.615538] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 bob # [ 1.615765] [drm] features: -virgl +edid -resource_blob -host_visible bob # [ 1.615771] [drm] features: -context_init bob # [ 1.616250] [drm] number of scanouts: 1 bob # [ 1.616266] [drm] number of cap sets: 0 alice # [ 1.644061] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic clare # [ 1.612693] Console: switching to colour frame buffer device 160x50 alice # [ 1.644079] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 bob # [ 1.618960] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 1.638602] systemd-modules-load[74]: Inserted module 'dm_mod' alice # [ 1.639517] systemd-modules-load[74]: Module 'virtio_balloon' is built in bob # [ 1.625476] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 1.651973] systemd-modules-load[74]: Module 'virtio_console' is built in alice # [ 1.652750] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 1.632844] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.653442] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 1.654113] systemd[1]: Reached target Local File Systems. alice # [ 1.654625] systemd[1]: Starting Create System Files and Directories... clare # [ 1.628708] systemd-modules-load[74]: Inserted module 'dm_mod' clare # [ 1.637975] systemd[1]: Started Journal Service. bob # [ 1.633556] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic bob # [ 1.633576] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 clare # [ 1.636550] systemd-modules-load[74]: Module 'virtio_balloon' is built in clare # [ 1.637223] systemd-modules-load[74]: Module 'virtio_console' is built in clare # [ 1.637867] systemd[1]: Starting Create System Files and Directories... clare # [ 1.638507] systemd-modules-load[74]: Inserted module 'virtio_gpu' clare # [ 1.639114] systemd-modules-load[74]: Module 'virtio_rng' is built in alice # [ 1.673138] Console: switching to colour frame buffer device 160x50 alice # [ 1.675923] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.669229] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 1.672665] systemd-modules-load[74]: Inserted module 'virtio_gpu' alice # [ 1.673258] systemd-modules-load[74]: Module 'virtio_rng' is built in alice # [ 1.675178] systemd[1]: Finished Load Kernel Modules. bob # [ 1.655363] Console: switching to colour frame buffer device 160x50 bob # [ 1.658283] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device bob # [ 1.661684] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 1.661886] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 1.661927] systemd[1]: Reached target Local File Systems. bob # [ 1.665070] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 1.657146] systemd[1]: Finished Load Kernel Modules. alice # [ 1.696164] systemd[1]: Starting Apply Kernel Variables... bob # [ 1.674697] systemd-modules-load[74]: Inserted module 'dm_mod' clare # [ 1.676194] systemd[1]: Starting Apply Kernel Variables... bob # [ 1.684880] systemd[1]: Started Journal Service. clare # [ 1.680395] systemd-udevd[80]: Using default interface naming scheme 'v261'. alice # [ 1.712886] systemd[1]: Finished Create System Files and Directories. bob # [ 1.688310] systemd-modules-load[74]: Module 'virtio_balloon' is built in bob # [ 1.689096] systemd-modules-load[74]: Module 'virtio_console' is built in clare # [ 1.689135] systemd[1]: Finished Create System Files and Directories. bob # [ 1.689793] systemd-modules-load[74]: Inserted module 'virtio_gpu' bob # [ 1.690433] systemd-modules-load[74]: Module 'virtio_rng' is built in bob # [ 1.691132] systemd[1]: Finished Load Kernel Modules. bob # [ 1.691769] systemd[1]: Starting Apply Kernel Variables... alice # [ 1.731909] systemd[1]: Finished Apply Kernel Variables. clare # [ 1.708741] systemd[1]: Finished Apply Kernel Variables. alice # [ 1.739155] systemd-udevd[81]: Using default interface naming scheme 'v261'. clare # [ 1.713018] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 1.714019] systemd[1]: Starting Create System Files and Directories... alice # [ 1.760253] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 1.738383] systemd-udevd[80]: Using default interface naming scheme 'v261'. bob # [ 1.746457] systemd[1]: Finished Apply Kernel Variables. bob # [ 1.752883] systemd[1]: Finished Create System Files and Directories. bob # [ 1.769163] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 1.772927] systemd[1]: Starting Virtual Console Setup... alice # [ 1.815893] systemd[1]: Starting Virtual Console Setup... clare # [ 1.812390] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 1.814725] systemd[1]: Finished Virtual Console Setup. bob # [ 1.824085] systemd[1]: Starting Virtual Console Setup... alice # [ 1.860376] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 1.862528] systemd[1]: Finished Virtual Console Setup. bob # [ 1.872430] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 1.874750] systemd[1]: Finished Virtual Console Setup. clare # [ 2.034013] systemd[1]: Finished Coldplug All udev Devices. clare # [ 2.034566] systemd[1]: Reached target System Initialization. clare # [ 2.035174] systemd[1]: Reached target Basic System. alice # [ 2.081133] systemd[1]: Finished Coldplug All udev Devices. alice # [ 2.081712] systemd[1]: Reached target System Initialization. alice # [ 2.082358] systemd[1]: Reached target Basic System. bob # [ 2.092995] systemd[1]: Finished Coldplug All udev Devices. bob # [ 2.093599] systemd[1]: Reached target System Initialization. bob # [ 2.096146] systemd[1]: Reached target Basic System. clare # [ 2.131325] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.149717] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.179435] (udev-worker)[101]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.201529] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.203113] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.220949] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.223702] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.225591] systemd[1]: Reached target Initrd Root Device. alice # [ 2.253233] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.229290] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... alice # [ 2.265769] systemd[1]: Reached target Initrd Root Device. alice # [ 2.270256] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... clare # [ 2.264786] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks alice # [ 2.308794] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 2.291679] systemd[1]: Found device /dev/disk/by-label/nixos. bob # [ 2.296111] systemd[1]: Reached target Initrd Root Device. bob # [ 2.298948] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... clare # [ 2.298821] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 2.326972] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. bob # [ 2.332701] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 2.340089] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. clare # [ 2.472127] systemd[1]: Mounting /sysroot... alice # [ 2.508137] systemd[1]: Mounting /sysroot... clare # [ 2.500385] EXT4-fs (vda): mounted filesystem 7af232b8-c67b-4195-aaa7-eb9743caf149 r/w with ordered data mode. Quota mode: none. clare # [ 2.497654] systemd[1]: Mounted /sysroot. clare # [ 2.498107] systemd[1]: Reached target Initrd Root File System. clare # [ 2.500094] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.543830] EXT4-fs (vda): mounted filesystem 03b46383-2d13-42d1-9003-bde9f86a1886 r/w with ordered data mode. Quota mode: none. alice # [ 2.540628] systemd[1]: Mounted /sysroot. alice # [ 2.541983] systemd[1]: Reached target Initrd Root File System. alice # [ 2.546648] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 2.520114] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload. clare # [ 2.526131] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)... clare # [ 2.528168] systemd[1]: Reloading... bob # [ 2.531789] systemd[1]: Mounting /sysroot... alice # [ 2.563002] systemd[1]: Mounting /sysroot/nix/.rw-store... alice # [ 2.567479] systemd[1]: Mounting /sysroot/run... alice # [ 2.586012] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 2.567374] EXT4-fs (vda): mounted filesystem 1c69ab3b-2ed6-4a10-94f1-2f1b6e71a6b2 r/w with ordered data mode. Quota mode: none. bob # [ 2.563372] systemd[1]: Mounted /sysroot. bob # [ 2.564978] systemd[1]: Reached target Initrd Root File System. bob # [ 2.568837] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.605200] systemd[1]: Mounting /sysroot/tmp/xchg... bob # [ 2.587612] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 2.594106] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 2.622428] systemd[1]: Starting Mountpoints Configured in the Real Root... bob # [ 2.596101] systemd[1]: Reloading... alice # [ 2.626204] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 2.635647] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 2.647785] systemd-sysroot-fstab-check[126]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 2.658116] systemd[1]: Mounted /sysroot/run. alice # [ 2.670710] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 2.672224] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 2.676747] systemd[1]: Reload requested from client PID 126 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 2.679293] systemd[1]: Reloading... clare # [ 2.701500] systemd[1]: Reloading finished in 171 ms. clare # [ 2.720567] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace... clare # [ 2.722310] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace... clare # [ 2.724239] systemd[1]: Starting Load Kernel Module 9pnet_virtio... clare # [ 2.732521] systemd[1]: initrd-parse-etc.service: Deactivated successfully. clare # [ 2.733940] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 2.734667] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. clare # [ 2.750147] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. clare # [ 2.751328] systemd[1]: Finished Load Kernel Module 9pnet_virtio. bob # [ 2.772662] systemd[1]: Reloading finished in 175 ms. bob # [ 2.800120] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace... bob # [ 2.803463] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace... bob # [ 2.806320] systemd[1]: Starting Load Kernel Module 9pnet_virtio... bob # [ 2.811959] systemd[1]: initrd-parse-etc.service: Deactivated successfully. bob # [ 2.818595] systemd[1]: Finished Mountpoints Configured in the Real Root. bob # [ 2.819342] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.855911] systemd[1]: Reloading finished in 179 ms. bob # [ 2.829847] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. bob # [ 2.831097] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 2.886842] systemd-sysroot-fstab-check[126]: Requesting initrd-fs.target/start/replace... alice # [ 2.889608] systemd-sysroot-fstab-check[126]: Requesting swap.target/start/replace... alice # [ 2.893649] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 2.894741] systemd[1]: initrd-parse-etc.service: Deactivated successfully. alice # [ 2.897568] systemd[1]: Finished Mountpoints Configured in the Real Root. alice # [ 2.900141] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.921046] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 2.921947] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.012919] (udev-worker)[100]: mtd0ro: Failed to find and pin callout binary "/nix/store/pyrvwmigi6qdpz9lbbslj5771pfl7r69-systemd-261.1/lib/udev/mtd_probe": No such file or directory clare # [ 3.016624] (udev-worker)[100]: mtd0ro: /nix/store/pyrvwmigi6qdpz9lbbslj5771pfl7r69-systemd-261.1/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory clare # [ 3.027643] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.029430] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.032266] systemd[1]: Stopping Virtual Console Setup... clare # [ 3.036530] systemd[1]: Starting Virtual Console Setup... clare # [ 3.055295] systemd-vconsole-setup[141]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 3.057565] systemd[1]: Finished Virtual Console Setup. alice # [ 3.124547] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/pyrvwmigi6qdpz9lbbslj5771pfl7r69-systemd-261.1/lib/udev/mtd_probe": No such file or directory alice # [ 3.128415] (udev-worker)[99]: mtd0ro: /nix/store/pyrvwmigi6qdpz9lbbslj5771pfl7r69-systemd-261.1/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory bob # [ 3.108853] (udev-worker)[98]: mtd0ro: Failed to find and pin callout binary "/nix/store/pyrvwmigi6qdpz9lbbslj5771pfl7r69-systemd-261.1/lib/udev/mtd_probe": No such file or directory alice # [ 3.140568] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.141600] systemd[1]: Stopped Virtual Console Setup. bob # [ 3.113814] (udev-worker)[98]: mtd0ro: /nix/store/pyrvwmigi6qdpz9lbbslj5771pfl7r69-systemd-261.1/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory alice # [ 3.142783] systemd[1]: Stopping Virtual Console Setup... alice # [ 3.144165] systemd[1]: Starting Virtual Console Setup... bob # [ 3.123429] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 3.124601] systemd[1]: Stopped Virtual Console Setup. bob # [ 3.125405] systemd[1]: Stopping Virtual Console Setup... bob # [ 3.127933] systemd[1]: Starting Virtual Console Setup... bob # [ 3.140522] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.167543] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 3.141485] systemd[1]: Stopped Virtual Console Setup. alice # [ 3.169927] systemd[1]: Finished Virtual Console Setup. bob # [ 3.142949] systemd[1]: Starting Virtual Console Setup... bob # [ 3.157995] systemd-vconsole-setup[143]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 3.159952] systemd[1]: Finished Virtual Console Setup. clare # [ 3.471761] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 3.478755] systemd[1]: Mounting /sysroot/nix/.rw-store... alice # [ 3.506452] systemd[1]: Mounting /sysroot/nix/store... clare # [ 3.488845] systemd[1]: Mounting /sysroot/run... clare # [ 3.494922] systemd[1]: Mounting /sysroot/tmp/shared... clare # [ 3.497993] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 3.499355] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 3.527081] systemd[1]: Mounted /sysroot/nix/store. alice # [ 3.528325] systemd[1]: Reached target Initrd File Systems. alice # [ 3.529785] systemd[1]: Starting Find NixOS closure... clare # [ 3.503972] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 3.535079] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... clare # [ 3.523307] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.529917] systemd[1]: Mounted /sysroot/run. clare # [ 3.531151] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 3.531620] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 3.558514] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 3.532533] systemd[1]: Mounting /sysroot/nix/.ro-store... alice # [ 3.561914] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob # [ 3.539352] systemd[1]: Mounting /sysroot/nix/.rw-store... clare # [ 3.542369] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.570631] systemd[1]: Finished Find NixOS closure. clare # [ 3.543303] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 3.571693] systemd[1]: Reached target Initrd Default Target. clare # [ 3.545101] systemd[1]: Mounting /sysroot/nix/store... alice # [ 3.573417] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... bob # [ 3.552841] systemd[1]: Mounting /sysroot/run... bob # [ 3.564296] systemd[1]: Mounting /sysroot/tmp/shared... alice # [ 3.594865] systemd[1]: Stopped target Initrd Default Target. alice # [ 3.595772] systemd[1]: Stopped target Basic System. alice # [ 3.598801] systemd[1]: Stopped target Initrd Root Device. alice # [ 3.599280] systemd[1]: Stopped target Path Units. clare # [ 3.571928] systemd[1]: Mounted /sysroot/nix/store. alice # [ 3.599715] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. clare # [ 3.572807] systemd[1]: Reached target Initrd File Systems. alice # [ 3.600575] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. bob # [ 3.573703] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 3.574125] systemd[1]: Starting Find NixOS closure... alice # [ 3.601799] systemd[1]: Stopped target Slice Units. alice # [ 3.603031] systemd[1]: Stopped target Socket Units. alice # [ 3.603683] systemd[1]: Stopped target System Initialization. clare # [ 3.579386] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... alice # [ 3.607859] systemd[1]: Stopped target Swaps. alice # [ 3.608321] systemd[1]: Stopped target Timer Units. alice # [ 3.608754] systemd[1]: dbus.socket: Deactivated successfully. alice # [ 3.609236] systemd[1]: Closed D-Bus System Message Bus Socket. alice # [ 3.609711] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. alice # [ 3.610305] systemd[1]: Stopped Find NixOS closure. alice # [ 3.610708] systemd[1]: Starting Load Kernel Module 9pnet_virtio... alice # [ 3.611246] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.611764] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 3.586957] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 3.616331] systemd[1]: Stopped Apply Kernel Variables. alice # [ 3.617803] systemd[1]: systemd-modules-load.service: Deactivated successfully. clare # [ 3.595567] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 3.597861] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 3.625187] systemd[1]: Stopped Load Kernel Modules. alice # [ 3.625702] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 3.631077] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 3.606909] systemd[1]: Finished Find NixOS closure. clare # [ 3.607911] systemd[1]: Reached target Initrd Default Target. alice # [ 3.636414] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. alice # [ 3.637161] systemd[1]: Stopped Create System Files and Directories. clare # [ 3.609755] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... bob # [ 3.610333] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.637711] systemd[1]: Stopped target Local File Systems. alice # [ 3.638177] systemd[1]: Stopped target Preparation for Local File Systems. alice # [ 3.638723] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 3.639302] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 3.639782] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 3.615074] systemd[1]: Mounted /sysroot/run. alice # [ 3.644404] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.645759] systemd[1]: Stopped Virtual Console Setup. bob # [ 3.619163] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 3.646965] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. alice # [ 3.648105] systemd[1]: Finished Load Kernel Module 9pnet_virtio. bob # [ 3.623692] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 3.651150] systemd[1]: systemd-udevd.service: Deactivated successfully. alice # [ 3.651753] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 3.652861] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.653463] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 3.656255] systemd[1]: initrd-cleanup.service: Deactivated successfully. alice # [ 3.656891] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. alice # [ 3.657469] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 3.658059] systemd[1]: Closed udev Control Socket. clare # [ 3.631386] systemd[1]: Stopped target Initrd Default Target. clare # [ 3.632425] systemd[1]: Stopped target Basic System. alice # [ 3.660122] systemd[1]: Starting Cleanup udev Database... alice # [ 3.660681] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 3.634530] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.662101] systemd[1]: Stopped Create Static Device Nodes in /dev. bob # [ 3.635545] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.635402] systemd[1]: Stopped target Initrd Root Device. alice # [ 3.662725] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. clare # [ 3.635897] systemd[1]: Stopped target Path Units. alice # [ 3.663382] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. clare # [ 3.636622] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 3.663958] systemd[1]: kmod-static-nodes.service: Deactivated successfully. clare # [ 3.637248] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. alice # [ 3.664700] systemd[1]: Stopped Create List of Static Device Nodes. clare # [ 3.637856] systemd[1]: Stopped target Slice Units. clare # [ 3.638257] systemd[1]: Stopped target Socket Units. clare # [ 3.638656] systemd[1]: Stopped target System Initialization. clare # [ 3.639113] systemd[1]: Stopped target Swaps. clare # [ 3.639472] systemd[1]: Stopped target Timer Units. clare # [ 3.639874] systemd[1]: dbus.socket: Deactivated successfully. clare # [ 3.648765] systemd[1]: Closed D-Bus System Message Bus Socket. clare # [ 3.649283] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. clare # [ 3.649878] systemd[1]: Stopped Find NixOS closure. clare # [ 3.650281] systemd[1]: Starting Load Kernel Module 9pnet_virtio... clare # [ 3.650790] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.651270] systemd[1]: systemd-sysctl.service: Deactivated successfully. clare # [ 3.651812] systemd[1]: Stopped Apply Kernel Variables. alice # [ 3.685227] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. clare # [ 3.655990] systemd[1]: systemd-modules-load.service: Deactivated successfully. alice # [ 3.686958] systemd[1]: Finished Cleanup udev Database. clare # [ 3.659988] systemd[1]: Stopped Load Kernel Modules. alice # [ 3.687630] systemd[1]: Reached target Switch Root. clare # [ 3.660502] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 3.689197] systemd[1]: Starting NixOS Activation... clare # [ 3.662093] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 3.662770] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. clare # [ 3.663425] systemd[1]: Stopped Create System Files and Directories. clare # [ 3.668225] systemd[1]: Stopped target Local File Systems. clare # [ 3.670238] systemd[1]: Stopped target Preparation for Local File Systems. clare # [ 3.670832] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. clare # [ 3.672595] systemd[1]: Stopped Coldplug All udev Devices. clare # [ 3.674752] systemd[1]: Stopping Rule-based Manager for Device Events and Files... clare # [ 3.679749] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.681686] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.682166] systemd[1]: systemd-udevd.service: Deactivated successfully. clare # [ 3.682707] systemd[1]: Stopped Rule-based Manager for Device Events and Files. clare # [ 3.683285] systemd[1]: initrd-cleanup.service: Deactivated successfully. clare # [ 3.688064] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. clare # [ 3.688667] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. clare # [ 3.689250] systemd[1]: Finished Load Kernel Module 9pnet_virtio. clare # [ 3.689748] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.692359] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.692905] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. clare # [ 3.693481] systemd[1]: Closed udev Control Socket. clare # [ 3.694452] systemd[1]: Starting Cleanup udev Database... clare # [ 3.696145] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. clare # [ 3.696825] systemd[1]: Stopped Create Static Device Nodes in /dev. clare # [ 3.698100] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. clare # [ 3.698855] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. clare # [ 3.699444] systemd[1]: kmod-static-nodes.service: Deactivated successfully. clare # [ 3.700050] systemd[1]: Stopped Create List of Static Device Nodes. clare # [ 3.721115] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. clare # [ 3.722712] systemd[1]: Finished Cleanup udev Database. clare # [ 3.723211] systemd[1]: Reached target Switch Root. clare # [ 3.724928] systemd[1]: Starting NixOS Activation... alice # [ 3.853099] initrd-nixos-activation-start[177]: booting system configuration /nix/store/i8b7i3j8l63r0j9j8k6k25zvriwfnlry-nixos-system-alice-test alice # [ 3.910896] initrd-nixos-activation-start[177]: running activation script... clare # [ 3.908397] initrd-nixos-activation-start[179]: booting system configuration /nix/store/m2g6h78kg79q67m793jjs8209zg2wd2j-nixos-system-clare-test clare # [ 3.979226] initrd-nixos-activation-start[179]: running activation script... alice # [ 4.320268] initrd-nixos-activation-start[200]: setting up /etc... clare # [ 4.383256] initrd-nixos-activation-start[202]: setting up /etc... alice # [ 4.548632] initrd-nixos-activation-start[200]: setting up age secrets... bob # [ 4.532166] systemd[1]: Mounting /sysroot/nix/store... bob # [ 4.578099] systemd[1]: Mounted /sysroot/nix/store. bob # [ 4.580664] systemd[1]: Reached target Initrd File Systems. clare # [ 4.584364] initrd-nixos-activation-start[202]: setting up age secrets... bob # [ 4.584132] systemd[1]: Starting Find NixOS closure... bob # [ 4.596364] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 4.641643] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 4.644298] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. alice # [ 4.674096] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. alice # [ 4.676097] systemd[1]: Finished NixOS Activation. alice # [ 4.676883] systemd[1]: Starting Switch Root... bob # [ 4.659532] systemd[1]: Finished Find NixOS closure. bob # [ 4.661741] systemd[1]: Reached target Initrd Default Target. bob # [ 4.662981] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 4.697892] systemd[1]: Switching root. bob # [ 4.694261] systemd[1]: Stopped target Initrd Default Target. bob # [ 4.696235] systemd[1]: Stopped target Basic System. bob # [ 4.697595] systemd[1]: Stopped target Initrd Root Device. bob # [ 4.700348] systemd[1]: Stopped target Path Units. clare # [ 4.701479] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 4.702124] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. clare # [ 4.703374] systemd[1]: Finished NixOS Activation. clare # [ 4.704227] systemd[1]: Starting Switch Root... bob # [ 4.704753] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. bob # [ 4.706751] systemd[1]: Stopped target Slice Units. bob # [ 4.708626] systemd[1]: Stopped target Socket Units. bob # [ 4.712166] systemd[1]: Stopped target System Initialization. bob # [ 4.713082] systemd[1]: Stopped target Swaps. bob # [ 4.713625] systemd[1]: Stopped target Timer Units. bob # [ 4.715315] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 4.716119] systemd[1]: Closed D-Bus System Message Bus Socket. bob # [ 4.720152] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 4.721831] systemd[1]: Stopped Find NixOS closure. clare # [ 4.722367] systemd[1]: Switching root. bob # [ 4.724125] systemd[1]: Starting Load Kernel Module 9pnet_virtio... bob # [ 4.725955] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 4.728668] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 4.732189] systemd[1]: Stopped Apply Kernel Variables. bob # [ 4.732855] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 4.736211] systemd[1]: Stopped Load Kernel Modules. bob # [ 4.737085] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. bob # [ 4.739035] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. bob # [ 4.741383] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 4.742197] systemd[1]: Stopped Create System Files and Directories. bob # [ 4.743652] systemd[1]: Stopped target Local File Systems. bob # [ 4.745634] systemd[1]: Stopped target Preparation for Local File Systems. bob # [ 4.746858] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. bob # [ 4.747610] systemd[1]: Stopped Coldplug All udev Devices. bob # [ 4.755931] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 4.761105] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 4.761885] systemd[1]: Stopped Virtual Console Setup. bob # [ 4.762427] systemd[1]: systemd-udevd.service: Deactivated successfully. bob # [ 4.762987] systemd[1]: Stopped Rule-based Manager for Device Events and Files. bob # [ 4.763576] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. bob # [ 4.769114] systemd[1]: Closed udev Control Socket. bob # [ 4.770029] systemd[1]: Starting Cleanup udev Database... bob # [ 4.771615] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 4.772995] systemd[1]: Stopped Create Static Device Nodes in /dev. bob # [ 4.775063] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. bob # [ 4.775857] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. bob # [ 4.777201] systemd[1]: kmod-static-nodes.service: Deactivated successfully. bob # [ 4.777798] systemd[1]: Stopped Create List of Static Device Nodes. bob # [ 4.778338] systemd[1]: initrd-cleanup.service: Deactivated successfully. bob # [ 4.778922] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. bob # [ 4.779491] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. bob # [ 4.786158] systemd[1]: Finished Load Kernel Module 9pnet_virtio. bob # [ 4.786732] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 4.787413] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 4.806790] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 4.807557] systemd[1]: Finished Cleanup udev Database. bob # [ 4.808627] systemd[1]: Reached target Switch Root. bob # [ 4.812142] systemd[1]: Starting NixOS Activation... alice # [ 4.883653] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). clare # [ 4.897330] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). bob # [ 5.003297] initrd-nixos-activation-start[180]: booting system configuration /nix/store/zsrzanr1i20v2f6jlpwbcckidbhsjbnm-nixos-system-bob-test bob # [ 5.074023] initrd-nixos-activation-start[180]: running activation script... alice # [ 5.425220] systemd[1]: systemd 261.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) alice # [ 5.428829] systemd[1]: Detected virtualization qemu. alice # [ 5.430017] systemd[1]: Detected architecture arm64. alice # [ 5.431698] systemd[1]: Detected first boot. alice # [ 5.435777] systemd[1]: Initializing machine ID from random generator. clare # [ 5.420541] systemd[1]: systemd 261.1 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) clare # [ 5.422916] systemd[1]: Detected virtualization qemu. clare # [ 5.423725] systemd[1]: Detected architecture arm64. clare # [ 5.424953] systemd[1]: Detected first boot. clare # [ 5.427793] systemd[1]: Initializing machine ID from random generator. bob # [ 5.457970] initrd-nixos-activation-start[203]: setting up /etc... alice # [ 5.606088] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 5.642054] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 5.648285] initrd-nixos-activation-start[203]: setting up age secrets... alice # [ 5.729066] systemd[1]: Applying preset policy. clare # [ 5.755396] systemd[1]: Applying preset policy. bob # [ 5.792754] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 5.794563] systemd[1]: Finished NixOS Activation. bob # [ 5.795362] systemd[1]: Starting Switch Root... bob # [ 5.809087] systemd[1]: Switching root. bob # [ 5.988379] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). alice # [ 6.030452] systemd[1]: Populated /etc with preset unit settings. clare # [ 6.087332] systemd[1]: Populated /etc with preset unit settings. alice # [ 6.416342] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 6.417319] systemd[1]: Stopped initrd-switch-root.service. alice # [ 6.418987] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. alice # [ 6.421376] systemd[1]: Created slice Slice /system/getty. alice # [ 6.422358] systemd[1]: Created slice User and Session Slice. alice # [ 6.423373] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 6.424086] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 6.425179] systemd[1]: Expecting device /dev/hvc0... alice # [ 6.425953] systemd[1]: Expecting device /dev/ttyAMA0... alice # [ 6.425995] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 6.426025] systemd[1]: Stopped target initrd-fs.target. alice # [ 6.426052] systemd[1]: Stopped target initrd-root-fs.target. alice # [ 6.426492] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 6.426787] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 6.427067] systemd[1]: Reached target Path Units. alice # [ 6.427326] systemd[1]: Reached target Remote File Systems. alice # [ 6.427576] systemd[1]: Reached target Slice Units. alice # [ 6.427830] systemd[1]: Reached target Swaps. alice # [ 6.434534] systemd[1]: Listening on Query the User Interactively for a Password. alice # [ 6.438353] systemd[1]: Listening on Process Core Dump Socket. alice # [ 6.441442] systemd[1]: Listening on Credential Encryption/Decryption. alice # [ 6.444449] systemd[1]: Listening on Factory Reset Management. alice # [ 6.445467] systemd[1]: Listening on Hostname Service Socket. alice # [ 6.449652] systemd[1]: Starting Journal Log Access Socket... alice # [ 6.451482] systemd[1]: Listening on Journal Audit Socket. alice # [ 6.454868] systemd[1]: Listening on Console Output Muting Service Socket. alice # [ 6.456341] systemd[1]: Listening on Network Management Metrics Varlink Socket. alice # [ 6.458120] systemd[1]: Listening on Network Management Varlink Socket. alice # [ 6.459567] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 6.461448] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. alice # [ 6.462903] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 6.464451] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki alice # [ 6.473698] systemd[1]: Listening on Disk Repartitioning Service Socket. alice # [ 6.474188] systemd[1]: Listening on Resolve Monitor Varlink Socket. alice # [ 6.474522] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 6.474849] systemd[1]: Listening on udev Control Socket. alice # [ 6.475176] systemd[1]: Listening on udev Varlink Socket. alice # [ 6.480237] systemd[1]: Mounting Huge Pages File System... alice # [ 6.483615] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 6.489407] systemd[1]: Mounting Kernel Debug File System... alice # [ 6.495280] systemd[1]: Mounting Kernel Trace File System... alice # [ 6.503991] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 6.515297] systemd[1]: Starting Load Kernel Module 9pnet_virtio... alice # [ 6.517050] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 6.535899] systemd[1]: Mounting Kernel Configuration File System... alice # [ 6.538136] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 6.541143] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 6.519700] systemd[1]: initrd-switch-root.service: Deactivated successfully. clare # [ 6.520644] systemd[1]: Stopped initrd-switch-root.service. clare # [ 6.522958] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 6.523093] systemd[1]: systemd 261.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 6.526850] systemd[1]: Detected virtualization qemu. bob # [ 6.527975] systemd[1]: Detected architecture arm64. bob # [ 6.529136] systemd[1]: Detected first boot. clare # [ 6.529301] systemd[1]: Created slice Slice /system/getty. clare # [ 6.531334] systemd[1]: Created slice User and Session Slice. clare # [ 6.532839] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 6.535081] systemd[1]: Initializing machine ID from random generator. clare # [ 6.534144] systemd[1]: Started Forward Password Requests to Wall Directory Watch. clare # [ 6.535388] systemd[1]: Expecting device /dev/hvc0... clare # [ 6.537044] systemd[1]: Expecting device /dev/ttyAMA0... clare # [ 6.538089] systemd[1]: Reached target Local Encrypted Volumes. clare # [ 6.539167] systemd[1]: Stopped target initrd-fs.target. clare # [ 6.540109] systemd[1]: Stopped target initrd-root-fs.target. clare # [ 6.541200] systemd[1]: Stopped target initrd-switch-root.target. clare # [ 6.542217] systemd[1]: Reached target Virtual Machines and Containers. clare # [ 6.543290] systemd[1]: Reached target Path Units. clare # [ 6.544194] systemd[1]: Reached target Remote File Systems. clare # [ 6.545626] systemd[1]: Reached target Slice Units. clare # [ 6.546939] systemd[1]: Reached target Swaps. clare # [ 6.550837] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 6.555097] systemd[1]: Listening on Process Core Dump Socket. clare # [ 6.558667] systemd[1]: Listening on Credential Encryption/Decryption. clare # [ 6.562589] systemd[1]: Listening on Factory Reset Management. clare # [ 6.563724] systemd[1]: Listening on Hostname Service Socket. clare # [ 6.568898] systemd[1]: Starting Journal Log Access Socket... clare # [ 6.570352] systemd[1]: Listening on Journal Audit Socket. clare # [ 6.573710] systemd[1]: Listening on Console Output Muting Service Socket. clare # [ 6.574951] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 6.576214] systemd[1]: Listening on Network Management Varlink Socket. alice # [ 6.604304] systemd[1]: Starting Load Kernel Module fuse... clare # [ 6.577828] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 6.606222] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 clare # [ 6.579848] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. clare # [ 6.582002] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os clare # [ 6.583721] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki clare # [ 6.593058] systemd[1]: Listening on Disk Repartitioning Service Socket. clare # [ 6.594663] systemd[1]: Listening on Resolve Monitor Varlink Socket. clare # [ 6.596173] systemd[1]: Listening on Resolve Service Varlink Socket. clare # [ 6.597793] systemd[1]: Listening on udev Control Socket. clare # [ 6.599220] systemd[1]: Listening on udev Varlink Socket. clare # [ 6.602913] systemd[1]: Mounting Huge Pages File System... alice # [ 6.632278] systemd[1]: Starting Journal Service... clare # [ 6.606313] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 6.643633] systemd[1]: Starting Load Kernel Modules... clare # [ 6.615809] systemd[1]: Mounting Kernel Debug File System... clare # [ 6.623871] systemd[1]: Mounting Kernel Trace File System... clare # [ 6.632956] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 6.666182] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 6.643165] systemd[1]: Starting Load Kernel Module 9pnet_virtio... clare # [ 6.644747] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 6.657615] systemd[1]: Mounting Kernel Configuration File System... clare # [ 6.658809] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 6.689822] systemd[1]: Starting Remount Root and Kernel File Systems... alice # [ 6.692185] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 6.664947] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore alice # [ 6.699316] fuse: init (API version 7.45) clare # [ 6.675021] systemd[1]: Starting Load Kernel Module fuse... clare # [ 6.680449] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 alice # [ 6.715258] systemd[1]: Starting Coldplug All udev Devices... alice # [ 6.739284] systemd[1]: Listening on Journal Log Access Socket. alice # [ 6.746018] systemd[1]: Mounted Huge Pages File System. alice # [ 6.750224] systemd[1]: Mounted POSIX Message Queue File System. alice # [ 6.756304] systemd[1]: Mounted Kernel Debug File System. alice # [ 6.758877] systemd[1]: Mounted Kernel Trace File System. clare # [ 6.736042] systemd[1]: Starting Journal Service... alice # [ 6.767736] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 6.768423] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. alice # [ 6.773548] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 6.778074] systemd[1]: Mounted Kernel Configuration File System. alice # [ 6.783096] systemd[1]: modprobe@fuse.service: Deactivated successfully. alice # [ 6.787553] systemd-journald[286]: Collecting audit messages is enabled. alice # [ 6.789615] systemd[1]: Finished Load Kernel Module fuse. clare # [ 6.761407] systemd[1]: Starting Load Kernel Modules... alice # [ 6.793416] systemd[1]: Finished Load Kernel Modules. alice # [ 6.793627] systemd[1]: Queued start job for default target Multi-User System. alice # [ 6.803131] EXT4-fs (vda): re-mounted 03b46383-2d13-42d1-9003-bde9f86a1886. alice # [ 6.806873] systemd[1]: Mounting FUSE Control File System... alice # [ 6.804309] systemd[1]: systemd-journald.service: Deactivated successfully. alice # [ 6.805768] systemd-modules-load[287]: Module 'atkbd' is built in alice # [ 6.808688] systemd-modules-load[287]: Module 'loop' is built in clare # [ 6.791041] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... alice # [ 6.819271] systemd[1]: Starting Firewall... clare # [ 6.798839] fuse: init (API version 7.45) bob # [ 6.803745] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 6.808936] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 6.811479] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 6.845175] systemd[1]: Starting Apply Kernel Variables... clare # [ 6.831862] systemd[1]: Starting Coldplug All udev Devices... alice # [ 6.858611] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 6.859708] systemd[1]: Started Journal Service. alice # [ 6.860832] systemd-oomd[288]: No swap; memory pressure usage will be degraded clare # [ 6.860211] systemd[1]: Listening on Journal Log Access Socket. clare # [ 6.863248] systemd[1]: Mounted Huge Pages File System. clare # [ 6.865554] systemd[1]: Mounted POSIX Message Queue File System. clare # [ 6.869108] systemd[1]: Mounted Kernel Debug File System. clare # [ 6.872144] systemd[1]: Mounted Kernel Trace File System. alice # [ 6.894812] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. clare # [ 6.875843] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 6.879861] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. clare # [ 6.883714] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 6.906635] systemd[1]: Finished Remount Root and Kernel File Systems. clare # [ 6.887251] systemd[1]: Mounted Kernel Configuration File System. clare # [ 6.891789] systemd[1]: modprobe@fuse.service: Deactivated successfully. clare # [ 6.895863] systemd[1]: Finished Load Kernel Module fuse. clare # [ 6.905101] systemd-journald[288]: Collecting audit messages is enabled. clare # [ 6.910767] systemd[1]: Finished Load Kernel Modules. clare # [ 6.922952] systemd[1]: Mounting FUSE Control File System... clare # [ 6.917592] systemd[1]: Queued start job for default target Multi-User System. clare # [ 6.919928] systemd[1]: systemd-journald.service: Deactivated successfully. clare # [ 6.928488] systemd-modules-load[289]: Module 'atkbd' is built in clare # [ 6.929133] systemd-modules-load[289]: Module 'loop' is built in alice # [ 6.962007] systemd[1]: Mounted FUSE Control File System. clare # [ 6.945937] systemd[1]: Starting Firewall... alice # [ 6.969728] systemd[1]: Listening on Disk Image Download Service Socket. clare # [ 6.952438] EXT4-fs (vda): re-mounted 7af232b8-c67b-4195-aaa7-eb9743caf149. alice # [ 6.979114] systemd[1]: Starting Flush Journal to Persistent Storage... alice # [ 6.979884] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore clare # [ 6.972603] systemd[1]: Starting Apply Kernel Variables... alice # [ 6.987426] systemd[1]: Starting Load/Save OS Random Seed... alice # [ 6.995353] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 6.983652] systemd[1]: Applying preset policy. clare # [ 6.983134] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 6.999322] systemd[1]: Started Journal Service. alice # [ 7.031135] systemd[1]: Finished Apply Kernel Variables. clare # [ 7.004668] systemd-oomd[290]: No swap; memory pressure usage will be degraded alice # [ 7.031764] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 7.042259] systemd[1]: Starting Network Name Resolution... alice # [ 7.049561] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 7.028925] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. clare # [ 7.036124] systemd[1]: Finished Remount Root and Kernel File Systems. alice # [ 7.073892] systemd-journald[286]: Received client request to flush runtime journal. clare # [ 7.070869] systemd[1]: Listening on Disk Image Download Service Socket. clare # [ 7.085133] systemd[1]: Starting Flush Journal to Persistent Storage... clare # [ 7.087199] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore clare # [ 7.095599] systemd[1]: Starting Load/Save OS Random Seed... clare # [ 7.097082] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 7.132734] systemd[1]: Finished Load/Save OS Random Seed. alice # [ 7.137292] systemd[1]: Reached target First Boot Complete. clare # [ 7.119038] systemd[1]: Mounted FUSE Control File System. alice # [ 7.139047] systemd[1]: Finished Flush Journal to Persistent Storage. clare # [ 7.130838] systemd[1]: Finished Apply Kernel Variables. clare # [ 7.153062] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 7.190241] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 7.190894] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 7.194298] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 7.167398] systemd[1]: Starting Network Name Resolution... clare # [ 7.173931] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 7.211490] systemd-journald[288]: Received client request to flush runtime journal. alice # [ 7.234625] systemd-resolved[317]: Positive Trust Anchors: alice # [ 7.235525] systemd-resolved[317]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d alice # [ 7.240630] systemd-resolved[317]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 alice # [ 7.243446] systemd-resolved[317]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test clare # [ 7.293239] systemd[1]: Finished Load/Save OS Random Seed. clare # [ 7.301201] systemd[1]: Reached target First Boot Complete. clare # [ 7.302103] systemd[1]: Finished Flush Journal to Persistent Storage. alice # [ 7.335592] systemd-udevd[329]: Using default interface naming scheme 'v261'. clare # [ 7.310611] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 7.311213] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 7.320631] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 7.358117] systemd-resolved[320]: Positive Trust Anchors: clare # [ 7.359107] systemd-resolved[320]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d clare # [ 7.366661] systemd-resolved[320]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 clare # [ 7.372222] systemd-resolved[320]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 7.416913] systemd[1]: Mounting /run/wrappers... bob # [ 7.404736] systemd[1]: Populated /etc with preset unit settings. clare # [ 7.429832] systemd-udevd[333]: Using default interface naming scheme 'v261'. alice # [ 7.489887] systemd[1]: Mounted /run/wrappers. alice # [ 7.500623] systemd[1]: Reached target Local File Systems. alice # [ 7.504088] systemd[1]: Listening on Boot Loader Control Service Socket. alice # [ 7.507110] systemd[1]: Starting register-nix-paths.service... alice # [ 7.510360] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 7.513167] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. alice # [ 7.518308] systemd-resolved[317]: Using system hostname 'alice'. alice # [ 7.530816] systemd[1]: Starting Save Transient machine-id to Disk... clare # [ 7.521757] systemd[1]: Mounting /run/wrappers... alice # [ 7.566805] systemd[1]: Starting Create System Files and Directories... alice # [ 7.568603] systemd[1]: Started Network Name Resolution. clare # [ 7.604552] systemd[1]: Mounted /run/wrappers. clare # [ 7.609748] systemd-resolved[320]: Using system hostname 'clare'. clare # [ 7.615341] systemd[1]: Reached target Local File Systems. alice # [ 7.649433] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. clare # [ 7.622885] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 7.627023] systemd[1]: Starting register-nix-paths.service... clare # [ 7.630319] systemd[1]: Starting Create SUID/SGID Wrappers... clare # [ 7.631004] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. alice # [ 7.660919] systemd[1]: Finished Save Transient machine-id to Disk. clare # [ 7.643055] systemd[1]: Starting Save Transient machine-id to Disk... alice # [ 7.679489] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 7.681851] systemd[1]: Starting Create System Files and Directories... clare # [ 7.682519] systemd[1]: Started Network Name Resolution. clare # [ 7.687690] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 7.735281] systemd[1]: Finished Create System Files and Directories. alice # [ 7.747464] systemd[1]: Starting Rebuild Journal Catalog... alice # [ 7.760229] systemd[1]: Starting Record System Boot/Shutdown in UTMP... clare # [ 7.786561] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. clare # [ 7.794376] systemd[1]: Finished Save Transient machine-id to Disk. bob # [ 7.893035] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 7.894205] systemd[1]: Stopped initrd-switch-root.service. alice # [ 7.914080] systemd[1]: Finished Record System Boot/Shutdown in UTMP. bob # [ 7.896567] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 7.899430] systemd[1]: Created slice Slice /system/getty. bob # [ 7.901093] systemd[1]: Created slice User and Session Slice. bob # [ 7.902484] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 7.903296] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 7.904529] systemd[1]: Expecting device /dev/hvc0... bob # [ 7.905653] systemd[1]: Expecting device /dev/ttyAMA0... clare # [ 7.896707] systemd[1]: Finished Create System Files and Directories. bob # [ 7.906627] systemd[1]: Reached target Local Encrypted Volumes. bob # [ 7.907651] systemd[1]: Stopped target initrd-fs.target. bob # [ 7.909037] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 7.910492] systemd[1]: Stopped target initrd-switch-root.target. bob # [ 7.911861] systemd[1]: Reached target Virtual Machines and Containers. bob # [ 7.913340] systemd[1]: Reached target Path Units. bob # [ 7.914581] systemd[1]: Reached target Remote File Systems. bob # [ 7.915926] systemd[1]: Reached target Slice Units. bob # [ 7.917137] systemd[1]: Reached target Swaps. clare # [ 7.911857] systemd[1]: Starting Rebuild Journal Catalog... bob # [ 7.920688] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 7.915515] systemd[1]: Starting Record System Boot/Shutdown in UTMP... bob # [ 7.924799] systemd[1]: Listening on Process Core Dump Socket. alice # [ 7.945844] systemd[1]: Finished Coldplug All udev Devices. bob # [ 7.928043] systemd[1]: Listening on Credential Encryption/Decryption. bob # [ 7.931364] systemd[1]: Listening on Factory Reset Management. bob # [ 7.932838] systemd[1]: Listening on Hostname Service Socket. bob # [ 7.937015] systemd[1]: Starting Journal Log Access Socket... bob # [ 7.939156] systemd[1]: Listening on Journal Audit Socket. bob # [ 7.942785] systemd[1]: Listening on Console Output Muting Service Socket. bob # [ 7.944584] systemd[1]: Listening on Network Management Metrics Varlink Socket. bob # [ 7.946448] systemd[1]: Listening on Network Management Varlink Socket. bob # [ 7.947854] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 7.949942] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 7.951467] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 7.970645] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 7.952696] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki bob # [ 7.960035] systemd[1]: Listening on Disk Repartitioning Service Socket. bob # [ 7.960600] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 7.960972] systemd[1]: Listening on Resolve Service Varlink Socket. bob # [ 7.963013] systemd[1]: Listening on udev Control Socket. alice # [ 7.982060] systemd[1]: Starting Update is Completed... bob # [ 7.964102] systemd[1]: Listening on udev Varlink Socket. bob # [ 7.967665] systemd[1]: Mounting Huge Pages File System... bob # [ 7.971340] systemd[1]: Mounting POSIX Message Queue File System... bob # [ 7.981662] systemd[1]: Mounting Kernel Debug File System... bob # [ 7.985700] systemd[1]: Mounting Kernel Trace File System... bob # [ 8.000217] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 8.015966] systemd[1]: Starting Load Kernel Module 9pnet_virtio... bob # [ 8.019782] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 8.053206] systemd[1]: Finished Update is Completed. bob # [ 8.040407] systemd[1]: Mounting Kernel Configuration File System... bob # [ 8.042203] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm bob # [ 8.045673] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 8.061314] systemd[1]: Finished Record System Boot/Shutdown in UTMP. bob # [ 8.101083] systemd[1]: Starting Load Kernel Module fuse... bob # [ 8.108102] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 clare # [ 8.124313] systemd[1]: Finished Rebuild Journal Catalog. clare # [ 8.131037] systemd[1]: Starting Update is Completed... bob # [ 8.140550] systemd[1]: Starting Journal Service... bob # [ 8.152704] systemd[1]: Starting Load Kernel Modules... clare # [ 8.154654] systemd[1]: Finished Coldplug All udev Devices. bob # [ 8.173053] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... bob # [ 8.183598] systemd[1]: Starting Remount Root and Kernel File Systems... bob # [ 8.187019] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 8.198245] systemd[1]: Finished Update is Completed. bob # [ 8.226054] fuse: init (API version 7.45) alice # [ 8.248076] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 8.230408] systemd[1]: Starting Coldplug All udev Devices... bob # [ 8.232544] systemd[1]: Listening on Journal Log Access Socket. bob # [ 8.233074] systemd[1]: Mounted Huge Pages File System. bob # [ 8.235380] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 8.264556] systemd-journald[297]: Collecting audit messages is enabled. bob # [ 8.270235] systemd[1]: Mounted Kernel Debug File System. bob # [ 8.273704] systemd[1]: Mounted Kernel Trace File System. bob # [ 8.277837] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 8.281884] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. bob # [ 8.285333] EXT4-fs (vda): re-mounted 1c69ab3b-2ed6-4a10-94f1-2f1b6e71a6b2. bob # [ 8.289912] systemd[1]: Finished Load Kernel Module 9pnet_virtio. bob # [ 8.280597] systemd[1]: Queued start job for default target Multi-User System. bob # [ 8.293095] systemd[1]: Started Journal Service. bob # [ 8.285294] systemd[1]: systemd-journald.service: Deactivated successfully. bob # [ 8.286221] systemd-modules-load[298]: Module 'atkbd' is built in bob # [ 8.286908] systemd-modules-load[298]: Module 'loop' is built in bob # [ 8.294634] systemd[1]: Mounted Kernel Configuration File System. bob # [ 8.297959] systemd[1]: modprobe@fuse.service: Deactivated successfully. bob # [ 8.301034] systemd[1]: Finished Load Kernel Module fuse. bob # [ 8.304489] systemd[1]: Finished Load Kernel Modules. bob # [ 8.306115] systemd[1]: Finished Remount Root and Kernel File Systems. clare # [ 8.310763] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 8.318194] systemd[1]: Listening on Disk Image Download Service Socket. bob # [ 8.324315] systemd[1]: Mounting FUSE Control File System... bob # [ 8.342687] systemd[1]: Starting Firewall... bob # [ 8.356197] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 8.356882] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 8.358688] systemd-oomd[300]: No swap; memory pressure usage will be degraded bob # [ 8.378603] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 8.392836] systemd[1]: Starting Apply Kernel Variables... bob # [ 8.437378] systemd-journald[297]: Received client request to flush runtime journal. alice # [ 8.467410] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. alice # [ 8.471397] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 8.520895] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 8.525098] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 8.531577] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. alice # [ 8.560212] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. bob # [ 8.538470] systemd[1]: Finished Load/Save OS Random Seed. bob # [ 8.539053] systemd[1]: Reached target First Boot Complete. bob # [ 8.539534] systemd[1]: Mounted FUSE Control File System. bob # [ 8.539994] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 8.557641] systemd[1]: Finished Apply Kernel Variables. alice # [ 8.594183] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. bob # [ 8.569590] systemd[1]: Starting Network Name Resolution... clare # [ 8.611138] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. clare # [ 8.614589] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 8.614916] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 8.628706] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 8.647807] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. alice # [ 8.709430] systemd[1]: Finished register-nix-paths.service. alice # [ 8.710611] systemd[1]: Reached target System Initialization. alice # [ 8.712906] systemd[1]: Started Discard unused filesystem blocks once a week. alice # [ 8.716388] systemd[1]: Started Daily Cleanup of Temporary Directories. alice # [ 8.721506] systemd[1]: Reached target Timer Units. alice # [ 8.725051] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 8.695678] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. alice # [ 8.728988] systemd[1]: Listening on Nix Daemon Socket. alice # [ 8.729452] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 8.730110] systemd[1]: Reached target Socket Units. alice # [ 8.730524] systemd[1]: Reached target Basic System. alice # [ 8.730933] systemd[1]: Started backdoor.service. alice # [ 8.731337] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 8.705238] systemd-resolved[330]: Positive Trust Anchors: bob # [ 8.706209] systemd-resolved[330]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d bob # [ 8.710819] systemd-resolved[330]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 alice # [ 8.739042] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 8.716943] systemd-resolved[330]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 8.754131] systemd[1]: Starting Post-Boot Actions... bob # [ 8.742931] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 8.770461] systemd[1]: Started Reset console on configuration changes. bob # [ 8.744624] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 8.750368] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 8.782250] systemd[1]: Finished Firewall. alice # [ 8.836228] systemd[1]: Reached target Preparation for Network. alice # [ 8.845420] systemd[1]: Listening on Network Management Resolve Hook Socket. alice # [ 8.863130] systemd[1]: Starting D-Bus System Message Bus... alice # [ 8.874101] systemd[1]: Starting Network Management... alice # connecting to host... alice # [ 8.906006] systemd[1]: Finished Post-Boot Actions. clare # [ 8.887966] systemd[1]: Finished register-nix-paths.service. clare # [ 8.888741] systemd[1]: Reached target System Initialization. clare # [ 8.892708] systemd[1]: Started Discard unused filesystem blocks once a week. bob # [ 8.894706] systemd[1]: Mounting /run/wrappers... clare # [ 8.894448] systemd[1]: Started Daily Cleanup of Temporary Directories. bob # [ 8.896994] systemd-udevd[343]: Using default interface naming scheme 'v261'. clare # [ 8.897667] systemd[1]: Reached target Timer Units. alice # [ 8.926746] nsncd[506]: Aug 18 08:23:38.706 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # [ 8.899580] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 8.932512] systemd[1]: Started Name Service Cache Daemon (nsncd). clare # [ 8.907624] systemd[1]: Listening on harmonia binary cache socket. clare # [ 8.908635] systemd[1]: Listening on Nix Daemon Socket. clare # [ 8.909820] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. clare # [ 8.914732] systemd[1]: Reached target Socket Units. clare # [ 8.916433] systemd[1]: Reached target Basic System. clare # [ 8.917680] systemd[1]: Started backdoor.service. clare # [ 8.919953] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 8.957822] systemd[1]: Reached target Host and Network Name Lookups. alice # [ 8.958426] systemd[1]: Reached target User and Group Name Lookups. clare # [ 8.935012] systemd[1]: Starting Name Service Cache Daemon (nsncd)... alice # [ 8.975397] systemd[1]: Starting User Login Management... bob # [ 8.972453] systemd[1]: Mounted /run/wrappers. clare # [ 8.972987] systemd[1]: Starting Post-Boot Actions... bob # [ 8.975024] systemd[1]: Reached target Local File Systems. bob # [ 8.986654] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 8.991775] systemd[1]: Started Reset console on configuration changes. alice # [ 9.021571] systemd[1]: Finished Import lastlog data into lastlog2 database. clare # [ 9.000299] systemd[1]: Finished Firewall. bob # [ 8.999794] systemd[1]: Starting register-nix-paths.service... bob # [ 9.004086] systemd[1]: Starting Create SUID/SGID Wrappers... bob # [ 9.012839] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. bob # [ 9.019776] systemd-resolved[330]: Using system hostname 'bob'. bob # [ 9.035000] systemd[1]: Starting Save Transient machine-id to Disk... clare # [ 9.042407] systemd[1]: Reached target Preparation for Network. clare # [ 9.047694] systemd[1]: Listening on Network Management Resolve Hook Socket. clare # connecting to host... clare # [ 9.061535] systemd[1]: Starting D-Bus System Message Bus... alice # [ 9.094685] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'... alice # [ 9.103214] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync' bob # [ 9.076131] systemd[1]: Starting Create System Files and Directories... bob # [ 9.077407] systemd[1]: Started Network Name Resolution. alice # [ 9.103993] dbus-broker-launch[513]: Invalid user-name in /nix/store/h11xk030fm7ccawk61ka41z1iq3bjxkl-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" clare # [ 9.087789] systemd[1]: Starting Network Management... alice # [ 9.130097] (udev-worker)[428]: Network interface NamePolicy= disabled on kernel command line. clare # [ 9.109810] systemd[1]: Finished Post-Boot Actions. alice # [ 9.140838] systemd[1]: Started D-Bus System Message Bus. alice # [ 9.148318] systemd-logind[531]: New seat seat0. clare # [ 9.124729] nsncd[509]: Aug 18 08:23:39.900 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" alice # [ 9.153051] (udev-worker)[434]: Network interface NamePolicy= disabled on kernel command line. clare # [ 9.128305] (udev-worker)[369]: Network interface NamePolicy= disabled on kernel command line. clare # [ 9.136224] systemd[1]: Started Name Service Cache Daemon (nsncd). clare # [ 9.141749] systemd[1]: Reached target Host and Network Name Lookups. clare # [ 9.144706] systemd[1]: Reached target User and Group Name Lookups. bob # [ 9.145760] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. alice # [ 9.176481] systemd-logind[531]: Watching system buttons on /dev/input/event0 (gpio-keys) clare # [ 9.149552] (udev-worker)[373]: Network interface NamePolicy= disabled on kernel command line. bob # [ 9.160913] systemd[1]: Finished Save Transient machine-id to Disk. clare # [ 9.160753] systemd[1]: Starting User Login Management... alice # [ 9.196410] dbus-broker-launch[513]: Ready alice # [ 9.196834] systemd[1]: Started User Login Management. alice # [ 9.205864] systemd[1]: Starting linger-users.service... clare # [ 9.195381] systemd[1]: Finished Import lastlog data into lastlog2 database. alice # [ 9.268673] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 9.252314] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 9.282781] systemd[1]: linger-users.service: Deactivated successfully. alice # [ 9.283439] systemd[1]: Finished linger-users.service. bob # [ 9.267662] systemd[1]: Finished Create System Files and Directories. alice # [ 9.317183] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' clare # [ 9.282586] dbus-broker-launch[519]: Looking up NSS user entry for 'systemd-timesync'... alice # [ 9.317702] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 9.284346] systemd[1]: Starting Rebuild Journal Catalog... alice # [ 9.322334] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 alice # [ 9.322698] cfg80211: failed to load regulatory.db clare # [ 9.290854] dbus-broker-launch[519]: NSS returned no entry for 'systemd-timesync' bob # [ 9.294632] systemd[1]: Starting Record System Boot/Shutdown in UTMP... clare # [ 9.295567] dbus-broker-launch[519]: Invalid user-name in /nix/store/pbvwh6wwh6qqsa3d5lwy900fgkykr9nv-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 9.347938] mousedev: PS/2 mouse device common for all mice clare # [ 9.321146] systemd-logind[538]: Watching system buttons on /dev/input/event0 (gpio-keys) clare # [ 9.327491] systemd-logind[538]: New seat seat0. clare # [ 9.330709] systemd[1]: Started User Login Management. clare # [ 9.333416] systemd[1]: Starting linger-users.service... clare # [ 9.344630] systemd[1]: Started D-Bus System Message Bus. alice # [ 9.371832] systemd-logind[531]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) alice # [ 9.383201] systemd-networkd[514]: lo: Link UP alice # [ 9.383716] systemd-networkd[514]: lo: Gained carrier alice # [ 9.388456] systemd-networkd[514]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. alice # [ 9.392210] systemd[1]: Started Network Management. alice # [ 9.392756] systemd[1]: Reached target Network. alice # [ 9.396153] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... alice # [ 9.403450] systemd[1]: Starting Permit User Sessions... alice # [ 9.403968] systemd-networkd[514]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 9.409743] systemd-networkd[514]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 9.411298] systemd-networkd[514]: eth0: Link UP alice # [ 9.411741] systemd-networkd[514]: eth0: Gained carrier alice # [ 9.418692] systemd-networkd[514]: eth1: Link UP alice # [ 9.419107] systemd-networkd[514]: eth1: Gained carrier alice # [ 9.419561] systemd-networkd[514]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 9.402033] dbus-broker-launch[519]: Ready alice # [ 9.433610] systemd-networkd[514]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 clare # [ 9.423220] systemd[1]: linger-users.service: Deactivated successfully. clare # [ 9.424581] systemd[1]: Finished linger-users.service. bob # [ 9.429695] systemd[1]: Finished Record System Boot/Shutdown in UTMP. alice # [ 9.471538] systemd[1]: Finished Permit User Sessions. clare # [ 9.460049] cfg80211: Loading compiled-in X.509 certificates for regulatory database alice # [ 9.480498] systemd[1]: Started Getty on tty1. alice # [ 9.482640] systemd[1]: Reached target Login Prompts. alice # [ 9.485800] systemd[1]: Reached target Multi-User System. alice # [ 9.490177] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. alice # [ 9.493266] systemd[1]: Startup finished in 790ms (kernel) + 4.170s (initrd) + 4.531s (userspace) = 9.492s. clare # [ 9.489449] mousedev: PS/2 mouse device common for all mice clare # [ 9.501220] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' clare # [ 9.501750] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' clare # [ 9.506397] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 clare # [ 9.506796] cfg80211: failed to load regulatory.db bob # [ 9.501034] systemd[1]: Finished Coldplug All udev Devices. clare # [ 9.505017] systemd-logind[538]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 9.511289] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 9.523235] systemd[1]: Starting Update is Completed... clare # [ 9.556682] systemd-networkd[521]: lo: Link UP clare # [ 9.557149] systemd-networkd[521]: lo: Gained carrier clare # [ 9.560801] systemd[1]: Started Network Management. clare # [ 9.561268] systemd[1]: Reached target Network. clare # [ 9.566307] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 9.571096] systemd-networkd[521]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 9.580987] systemd-networkd[521]: eth1: Configuring with /etc/systemd/network/40-eth1.network. clare # [ 9.581683] systemd-networkd[521]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. clare # [ 9.582405] systemd-networkd[521]: eth1: Link UP clare # [ 9.582773] systemd-networkd[521]: eth1: Gained carrier clare # [ 9.583173] systemd-networkd[521]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 9.589751] systemd[1]: Starting Permit User Sessions... bob # [ 9.590499] systemd[1]: Finished Update is Completed. clare # [ 9.593438] systemd-networkd[521]: eth0: Link UP clare # [ 9.593871] systemd-networkd[521]: eth0: Gained carrier clare # [ 9.608831] systemd-networkd[521]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 clare # [ 9.653260] systemd[1]: Finished Permit User Sessions. clare # [ 9.662531] systemd[1]: Started Getty on tty1. clare # [ 9.664900] systemd[1]: Reached target Login Prompts. clare # [ 9.666511] systemd[1]: Reached target Multi-User System. clare # [ 9.671729] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 9.675583] systemd[1]: Startup finished in 768ms (kernel) + 4.185s (initrd) + 4.722s (userspace) = 9.675s. bob # [ 9.772449] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 9.961949] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 bob # [ 9.971061] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. bob # [ 9.974654] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 10.055853] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. clare # [ 10.097028] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 bob # [ 10.117956] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. alice # [ 10.226119] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. alice # [ 10.262481] systemd[1]: Starting Virtual Console Setup... bob # [ 10.237910] systemd[1]: Finished register-nix-paths.service. alice # [ 10.266383] systemd-logind[531]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 10.240165] systemd[1]: Reached target System Initialization. bob # [ 10.242298] systemd[1]: Started Discard unused filesystem blocks once a week. bob # [ 10.245590] systemd[1]: Started Daily Cleanup of Temporary Directories. bob # [ 10.247533] systemd[1]: Reached target Timer Units. alice # [ 10.275562] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 10.249816] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 10.277313] systemd[1]: Stopped Virtual Console Setup. bob # [ 10.253065] systemd[1]: Listening on Nix Daemon Socket. bob # [ 10.254498] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 10.283693] systemd[1]: Starting Virtual Console Setup... bob # [ 10.257765] systemd[1]: Reached target Socket Units. bob # [ 10.261716] systemd[1]: Reached target Basic System. bob # [ 10.262581] systemd[1]: Started backdoor.service. bob # [ 10.264310] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 10.278377] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 10.300663] systemd[1]: Starting Post-Boot Actions... bob # [ 10.316623] systemd[1]: Started Reset console on configuration changes. clare # [ 10.370270] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 10.396972] systemd[1]: Finished Firewall. clare # [ 10.406067] systemd-logind[538]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 10.411108] systemd[1]: Reached target Preparation for Network. clare # [ 10.416764] systemd[1]: Starting Virtual Console Setup... bob # [ 10.417145] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # connecting to host... bob # [ 10.426270] systemd[1]: Starting D-Bus System Message Bus... bob # [ 10.434599] nsncd[512]: Aug 18 08:23:41.205 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" bob # [ 10.448767] systemd[1]: Starting Network Management... bob # [ 10.450351] systemd[1]: Started Name Service Cache Daemon (nsncd). bob # [ 10.454294] systemd[1]: Finished Post-Boot Actions. bob # [ 10.476172] systemd[1]: Finished Import lastlog data into lastlog2 database. bob: Guest shell says: b'Spawning backdoor root shell...\n' bob # [ 10.488147] systemd[1]: Reached target Host and Network Name Lookups. bob # [ 10.490369] systemd[1]: Reached target User and Group Name Lookups. bob # [ 10.504327] systemd[1]: Starting User Login Management... bob: connected to guest root shell bob: (connecting took 10.86 seconds) bob: (finished: waiting for the VM to finish booting, in 10.86 seconds) alice # [ 10.647320] systemd-vconsole-setup[563]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 10.650226] systemd[1]: Finished Virtual Console Setup. bob # [ 10.641727] (udev-worker)[442]: Network interface NamePolicy= disabled on kernel command line. alice # [ 10.689291] systemd-networkd[514]: eth1: Gained IPv6LL bob # [ 10.669430] (udev-worker)[438]: Network interface NamePolicy= disabled on kernel command line. bob # [ 10.677515] systemd-logind[545]: New seat seat0. bob # [ 10.684914] dbus-broker-launch[529]: Looking up NSS user entry for 'systemd-timesync'... clare # [ 10.690182] systemd-networkd[521]: eth1: Gained IPv6LL clare # [ 10.690672] systemd-networkd[521]: eth0: Gained IPv6LL bob # [ 10.690977] systemd-logind[545]: Watching system buttons on /dev/input/event0 (gpio-keys) bob # [ 10.709767] systemd[1]: Started User Login Management. bob # [ 10.713145] dbus-broker-launch[529]: NSS returned no entry for 'systemd-timesync' bob # [ 10.717134] dbus-broker-launch[529]: Invalid user-name in /nix/store/aaj4m73z84ck12jxy5i8zhj0r23k4pm1-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" bob # [ 10.722321] systemd[1]: Starting linger-users.service... bob # [ 10.771773] systemd[1]: Started D-Bus System Message Bus. bob # [ 10.827896] cfg80211: Loading compiled-in X.509 certificates for regulatory database alice # [ 10.850714] systemd-networkd[514]: eth0: Gained IPv6LL bob # [ 10.834297] dbus-broker-launch[529]: Ready bob # [ 10.836475] systemd[1]: linger-users.service: Deactivated successfully. bob # [ 10.837988] systemd[1]: Finished linger-users.service. bob # [ 10.875601] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' bob # [ 10.876104] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 10.880785] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 bob # [ 10.881217] cfg80211: failed to load regulatory.db clare # [ 10.874223] systemd-vconsole-setup[568]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 10.876947] systemd[1]: Finished Virtual Console Setup. bob # [ 10.899255] mousedev: PS/2 mouse device common for all mice bob # [ 10.911362] systemd-logind[545]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 10.940500] systemd-networkd[541]: lo: Link UP bob # [ 10.940966] systemd-networkd[541]: lo: Gained carrier bob # [ 10.948575] systemd[1]: Started Network Management. bob # [ 10.949226] systemd[1]: Reached target Network. bob # [ 10.949621] systemd[1]: Reached target Network is Online. bob # [ 10.950050] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 10.951262] systemd-networkd[541]: eth1: Configuring with /etc/systemd/network/40-eth1.network. bob # [ 10.955260] systemd[1]: Starting ncps binary cache proxy service... bob # [ 10.961196] systemd-networkd[541]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. bob # [ 10.965762] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... bob # [ 10.966477] systemd-networkd[541]: eth1: Link UP bob # [ 10.966893] systemd-networkd[541]: eth1: Gained carrier bob # [ 10.967323] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 10.974103] systemd[1]: Starting Permit User Sessions... bob # [ 10.981978] systemd-networkd[541]: eth0: Link UP bob # [ 10.982411] systemd-networkd[541]: eth0: Gained carrier bob # [ 10.996584] systemd-networkd[541]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 11.055600] systemd[1]: Finished Permit User Sessions. bob # [ 11.068801] systemd[1]: Started Getty on tty1. bob # [ 11.069465] systemd[1]: Reached target Login Prompts. bob # [ 11.083697] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. bob # [ 11.116226] ncps-pre-start[575]: /nix/store/jfdayyyhg1p4vdm02yiph4kwrwh1fyqy-ncps-0.9.4/bin/dbmate-ncps up bob # [ 11.493227] ncps-pre-start[590]: Creating: /var/lib/ncps/db/db.sqlite bob # [ 11.529725] ncps-pre-start[590]: Applying: 20241210054814_create-narinfos-table.sql bob # [ 11.538450] ncps-pre-start[590]: Applied: 20241210054814_create-narinfos-table.sql in 8.703ms bob # [ 11.540508] ncps-pre-start[590]: Applying: 20241210054829_create-nars-table.sql bob # [ 11.547199] ncps-pre-start[590]: Applied: 20241210054829_create-nars-table.sql in 8.727ms bob # [ 11.548900] ncps-pre-start[590]: Applying: 20241213014846_add-query-to-nars-table.sql bob # [ 11.554918] ncps-pre-start[590]: Applied: 20241213014846_add-query-to-nars-table.sql in 7.76244ms bob # [ 11.556548] ncps-pre-start[590]: Applying: 20251230224159_add-cascade-to-nars-fk.sql bob # [ 11.567351] ncps-pre-start[590]: Applied: 20251230224159_add-cascade-to-nars-fk.sql in 10.92188ms bob # [ 11.568518] ncps-pre-start[590]: Applying: 20260101000000_add-config-table.sql bob # [ 11.575565] ncps-pre-start[590]: Applied: 20260101000000_add-config-table.sql in 8.56752ms bob # [ 11.577740] ncps-pre-start[590]: Applying: 20260105025735_remove-redundant-indexes.sql bob # [ 11.583739] ncps-pre-start[590]: Applied: 20260105025735_remove-redundant-indexes.sql in 7.83344ms bob # [ 11.585206] ncps-pre-start[590]: Applying: 20260105030513_support_shared_nars_between_narinfos.sql bob # [ 11.593228] ncps-pre-start[590]: Applied: 20260105030513_support_shared_nars_between_narinfos.sql in 9.86204ms bob # [ 11.594857] ncps-pre-start[590]: Applying: 20260117195000_add_narinfo_de_normalized.sql bob # [ 11.604513] ncps-pre-start[590]: Applied: 20260117195000_add_narinfo_de_normalized.sql in 9.87724ms bob # [ 11.606326] ncps-pre-start[590]: Applying: 20260127223000_allow_multiple_nar_representations.sql bob # [ 11.613907] ncps-pre-start[590]: Applied: 20260127223000_allow_multiple_nar_representations.sql in 9.5776ms bob # [ 11.616067] ncps-pre-start[590]: Applying: 20260131021850_add_chunks.sql bob # [ 11.622331] ncps-pre-start[590]: Applied: 20260131021850_add_chunks.sql in 8.4072ms bob # [ 11.624071] ncps-pre-start[590]: Applying: 20260205063651_add_total_chunks_to_nar_files.sql bob # [ 11.630430] ncps-pre-start[590]: Applied: 20260205063651_add_total_chunks_to_nar_files.sql in 7.8566ms bob # [ 11.632500] ncps-pre-start[590]: Applying: 20260217071237_add_chunking_started_at_to_nar_files.sql bob # [ 11.639793] ncps-pre-start[590]: Applied: 20260217071237_add_chunking_started_at_to_nar_files.sql in 9.5666ms bob # [ 11.641935] ncps-pre-start[590]: Applying: 20260301000000_add_verified_at_to_nar_files.sql bob # [ 11.650833] ncps-pre-start[590]: Applied: 20260301000000_add_verified_at_to_nar_files.sql in 10.27228ms bob # [ 11.754259] systemd[1]: Started ncps binary cache proxy service. bob # [ 11.757049] systemd[1]: Reached target Multi-User System. bob # [ 11.757641] systemd[1]: Startup finished in 774ms (kernel) + 5.282s (initrd) + 5.698s (userspace) = 11.756s. bob # [ 11.863889] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 bob: (finished: waiting for unit ncps, in 12.21 seconds) bob: must succeed: systemctl status ncps bob: (finished: must succeed: systemctl status ncps, in 0.05 seconds) clare: waiting for unit harmonia.socket clare: waiting for the VM to finish booting clare: Guest shell says: b'Spawning backdoor root shell...\n' clare: connected to guest root shell clare: (connecting took 0.00 seconds) clare: (finished: waiting for the VM to finish booting, in 0.00 seconds) clare: (finished: waiting for unit harmonia.socket, in 0.09 seconds) alice: waiting for success: curl bob:8502/nix-cache-info alice: waiting for the VM to finish booting alice: Guest shell says: b'Spawning backdoor root shell...\n' alice: connected to guest root shell alice: (connecting took 0.00 seconds) alice: (finished: waiting for the VM to finish booting, in 0.00 seconds) bob # [ 12.322935] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","log_level":"info","time":"2026-08-18T08:23:43Z","message":"logger created"} bob # [ 12.324250] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","xz-binary-path":"/nix/store/7i4c31hkr3f5859qkwkd1q9avmsa6s74-xz-5.8.3-bin/bin/xz","time":"2026-08-18T08:23:43Z","message":"Using xz binary for xz decompression"} bob # [ 12.338757] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-08-18T08:23:43Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"} bob # [ 12.368358] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","time":"2026-08-18T08:23:43Z","message":"using local locks (single-instance mode)"} bob # [ 12.380165] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-08-18T08:23:43Z","message":"setting up tracer provider to discard traces"} bob # [ 12.387262] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-08-18T08:23:43Z","message":"setting up meter provider to discard metrics"} bob # [ 12.395661] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-08-18T08:23:43Z","message":"setting up logger provider to discard logs"} bob # [ 12.417094] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-08-18T08:23:43Z","message":"Reporting anonymous metrics to the project maintainers"} bob # [ 12.420256] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 12.420940] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-08-18T08:23:43Z","message":"failed to parse netrc file, proceeding without netrc authentication"} bob # [ 12.428811] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-08-18T08:23:43Z","message":"using local storage"} bob # [ 12.439682] systemd[1]: Starting Virtual Console Setup... bob # [ 12.466228] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 12.467488] systemd[1]: Stopped Virtual Console Setup. bob # [ 12.472575] systemd[1]: Starting Virtual Console Setup... bob # [ 12.497724] systemd-logind[545]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) clare # [ 12.514066] systemd[1]: Starting harmonia binary cache service... bob # [ 12.577353] systemd-networkd[541]: eth1: Gained IPv6LL clare # [ 12.681220] harmonia-cache[575]: 2026-08-18T08:23:43.460310Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000)) clare # [ 12.683823] harmonia-cache[575]: 2026-08-18T08:23:43.461384Z INFO actix_server::builder: starting 4 workers clare # [ 12.685189] systemd[1]: Started harmonia binary cache service. clare # [ 12.685781] harmonia-cache[575]: 2026-08-18T08:23:43.465541Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s clare # [ 12.687815] harmonia-cache[575]: 2026-08-18T08:23:43.468142Z INFO actix_server::server: Actix runtime found; starting in Actix runtime clare # [ 12.689493] harmonia-cache[575]: 2026-08-18T08:23:43.468168Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:5000 bob # [ 12.703049] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-08-18T08:23:43Z","message":"upstream became healthy and is now available for requests"} bob # [ 12.705110] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-08-18T08:23:43Z","message":"Server started"} bob # [ 12.832785] systemd-networkd[541]: eth0: Gained IPv6LL bob # [ 12.955548] systemd-vconsole-setup[626]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 12.958462] systemd[1]: Finished Virtual Console Setup. alice # % Total % Received % Xferd Average Speed Time Time Time Current alice # Dload Upload Total Spent Left Speed bob # [ 13.226720] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:35824","trace_id":"87c38e1806927fed4a5bb3961eeec414","span_id":"d1b6c825d7769ec8","status":200,"elapsed":0.12768,"bytes":50,"time":"2026-08-18T08:23:44Z","message":"handled request"} alice # 0 0 0 0 0 0 0 0 00:01 0100 50 100 50 0 0 47 0 00:01 00:01 0100 50 100 50 0 0 46 0 00:01 00:01 0100 50 100 50 0 0 46 0 00:01 00:01 0 alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 1.19 seconds) bob: waiting for success: curl clare:5000/nix-cache-info bob # % Total % Received % Xferd Average Speed Time Time Time Current bob # Dload Upload Total Spent Left Speed bob # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 3263 0 0100 51 100 51 0 0 2785 0 0100 51 100 51 0 0 2392 0 0 bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.13 seconds) clare: waiting for unit harmonia bob # [ 13.423685] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: 2026/08/18 08:23:44 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host clare: (finished: waiting for unit harmonia, in 0.13 seconds) clare: must succeed: systemctl status harmonia clare: (finished: must succeed: systemctl status harmonia, in 0.04 seconds) clare: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 clare # [ 13.694509] systemd-resolved[320]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. clare # [ 13.697050] systemd-resolved[320]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 13.698767] systemd-resolved[320]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare: (finished: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 , in 4.89 seconds) alice: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 alice # [ 18.631620] systemd-resolved[317]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. bob # [ 18.600181] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:35836","trace_id":"c6dfc7bce22449c3bec6f6b15a4b4ef6","span_id":"6f5bcc5b364b7572","status":200,"elapsed":0.0312,"bytes":50,"time":"2026-08-18T08:23:49Z","message":"handled request"} bob # [ 23.249185] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:35836","trace_id":"833bc0dc9fd176ad7eabba5a4c4e5228","span_id":"75ab3b50bbd0de5d","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_url":"http://clare.clan:5000/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","upstream_url":"http://clare.clan:5000","time":"2026-08-18T08:23:54Z","message":"download the narinfo from upstream"} bob # [ 23.292270] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:35836","trace_id":"833bc0dc9fd176ad7eabba5a4c4e5228","span_id":"75ab3b50bbd0de5d","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-08-18T08:23:54Z","message":"downloading the nar from upstream"} bob # [ 23.302503] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:35836","trace_id":"833bc0dc9fd176ad7eabba5a4c4e5228","span_id":"75ab3b50bbd0de5d","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_hash":"1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h","nar_compression":"none","nar_query":"hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"http://clare.clan:5000/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","upstream_url":"http://clare.clan:5000","nar_hash":"1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h","nar_compression":"none","nar_query":"hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-08-18T08:23:54Z","message":"download the nar from upstream"} bob # [ 23.333604] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:35836","trace_id":"833bc0dc9fd176ad7eabba5a4c4e5228","span_id":"75ab3b50bbd0de5d","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-08-18T08:23:54Z","message":"storing narinfo and nar_file record in the database"} bob # [ 23.347814] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:35836","trace_id":"833bc0dc9fd176ad7eabba5a4c4e5228","span_id":"75ab3b50bbd0de5d","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":100.0476,"time":"2026-08-18T08:23:54Z","message":"download of narinfo complete"} bob # [ 23.361883] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:35836","trace_id":"833bc0dc9fd176ad7eabba5a4c4e5228","span_id":"75ab3b50bbd0de5d","status":200,"elapsed":118.97948,"bytes":532,"time":"2026-08-18T08:23:54Z","message":"handled request"} bob # [ 23.369223] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:35836","trace_id":"833bc0dc9fd176ad7eabba5a4c4e5228","span_id":"75ab3b50bbd0de5d","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":68.6084,"time":"2026-08-18T08:23:54Z","message":"download of nar complete"} bob # [ 23.378507] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:35836","trace_id":"6391e0f8d489de876b959d44023aa388","span_id":"46cc14086f389f87","status":200,"elapsed":0.69252,"bytes":95,"time":"2026-08-18T08:23:54Z","message":"handled request"} alice: (finished: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 , in 4.96 seconds) (finished: run the VM test script, in 24.32 seconds) test script finished in 24.50s cleanup kill QemuMachine (pid 48) alice # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3inr3nxzqarhb9rh1z7s2s20j8c0395h-python3-3.14.7/bin/python3.14) kill QemuMachine (pid 49) bob # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3inr3nxzqarhb9rh1z7s2s20j8c0395h-python3-3.14.7/bin/python3.14) kill QemuMachine (pid 50) clare # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3inr3nxzqarhb9rh1z7s2s20j8c0395h-python3-3.14.7/bin/python3.14) (finished: cleanup, in 0.43 seconds)