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 bob # Disk image does not exist, creating the virtualisation disk image... clare # Disk image does not exist, creating the virtualisation disk image... bob # Formatting '/build/vm-state-bob/tmp.nnrOWki90r', fmt=raw size=1073741824 clare: QEMU running (pid 50) alice # Disk image does not exist, creating the virtualisation disk image... bob # mke2fs 1.47.4 (6-Mar-2025) clare # Formatting '/build/vm-state-clare/tmp.yZcx7Ng67S', fmt=raw size=1073741824 alice # Formatting '/build/vm-state-alice/tmp.E7ojsJaxXB', fmt=raw size=1073741824 bob # Discarding device blocks: 0/262144 done clare # mke2fs 1.47.4 (6-Mar-2025) alice # mke2fs 1.47.4 (6-Mar-2025) bob: QEMU running (pid 48) clare # Discarding device blocks: 0/262144 done alice # Discarding device blocks: 0/262144 done bob # Creating filesystem with 262144 4k blocks and 65536 inodes alice: QEMU running (pid 49) bob # Filesystem UUID: f43137ba-975d-4769-a2ec-01d6c731ce9d clare # Creating filesystem with 262144 4k blocks and 65536 inodes bob # Superblock backups stored on blocks: clare # Filesystem UUID: dfed0fd1-e6d8-49c4-bec4-ad693342b33c bob # 32768, 98304, 163840, 229376 clare # Superblock backups stored on blocks: bob # alice # Creating filesystem with 262144 4k blocks and 65536 inodes (finished: start all VMs, in 0.51 seconds) clare # 32768, 98304, 163840, 229376 bob # Allocating group tables: 0/8 done alice # Filesystem UUID: c3105474-4727-4398-a7b0-79d3f73c17bd bob: waiting for unit ncps bob # Writing inode tables: 0/8 done alice # Superblock backups stored on blocks: bob: waiting for the VM to finish booting bob # Creating journal (8192 blocks): done alice # 32768, 98304, 163840, 229376 bob # Writing superblocks and filesystem accounting information: 0/8 done alice # bob # alice # Allocating group tables: 0/8 done bob # Virtualisation disk image created. alice # Writing inode tables: 0/8 done clare # alice # Creating journal (8192 blocks): done clare # Allocating group tables: 0/8 done alice # Writing superblocks and filesystem accounting information: 0/8 done clare # Writing inode tables: 0/8 done alice # clare # Creating journal (8192 blocks): done alice # Virtualisation disk image created. clare # Writing superblocks and filesystem accounting information: 0/8 done clare # clare # Virtualisation disk image created. clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] clare # [ 0.000000] Linux version 6.18.47 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Thu Aug 27 12:32:55 UTC 2026 clare # [ 0.000000] KASLR enabled clare # [ 0.000000] random: crng init done clare # [ 0.000000] Machine model: linux,dummy-virt clare # [ 0.000000] efi: UEFI not found. clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f] clare # [ 0.000000] Zone ranges: clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] DMA32 empty clare # [ 0.000000] Normal empty clare # [ 0.000000] Device empty clare # [ 0.000000] Movable zone start for each node clare # [ 0.000000] Early memory node ranges clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 clare # [ 0.000000] psci: probing for conduit method from DT. clare # [ 0.000000] psci: PSCIv1.3 detected in firmware. clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs clare # [ 0.000000] psci: Trusted OS migration not required clare # [ 0.000000] psci: SMC Calling Convention v1.1 clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296 clare # [ 0.000000] Detected PIPT I-cache on CPU0 clare # [ 0.000000] CPU features: detected: GICv3 CPU interface clare # [ 0.000000] CPU features: detected: Spectre-v4 clare # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.000000] CPU features: detected: ARM erratum 1418040 clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.000000] alternatives: applying boot alternatives 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/lpcdx8i5xciyq3d1r44g9d3hb3wfndlv-nixos-system-clare-test/init regInfo=/nix/store/989bbx97zh8mrqmih6nw20ms7cvswh8m-closure-info/registration console=ttyAMA0,115200n8 console=tty0 clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/989bbx97zh8mrqmih6nw20ms7cvswh8m-closure-info/registration", will be passed to user space. clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB clare # [ 0.000000] software IO TLB: area num 1. clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 clare # [ 0.000000] Policy zone: DMA clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 clare # [ 0.000000] allocated 2097152 bytes of page_ext clare # [ 0.000000] ftrace: allocating 74886 entries in 294 pages clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups clare # [ 0.000000] rcu: Hierarchical RCU implementation. clare # [ 0.000000] rcu: RCU event tracing is enabled. clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. clare # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.000000] Rude variant of Tasks RCU enabled. clare # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_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. clare # [ 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 Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.000000] GICv3: 0 Extended SPIs implemented bob # [ 0.000000] Linux version 6.18.47 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Thu Aug 27 12:32:55 UTC 2026 bob # [ 0.000000] KASLR enabled clare # [ 0.000000] Root IRQ handler: gic_handle_irq bob # [ 0.000000] random: crng init done clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI bob # [ 0.000000] Machine model: linux,dummy-virt bob # [ 0.000000] efi: UEFI not found. clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f] bob # [ 0.000000] Zone ranges: bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) bob # [ 0.000000] DMA32 empty bob # [ 0.000000] Normal empty clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 bob # [ 0.000000] Device empty bob # [ 0.000000] Movable zone start for each node clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000000] Early memory node ranges clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.000000] psci: probing for conduit method from DT. clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). bob # [ 0.000000] psci: PSCIv1.3 detected in firmware. bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns bob # [ 0.000000] psci: Trusted OS migration not required bob # [ 0.000000] psci: SMC Calling Convention v1.1 clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns clare # [ 0.000062] arm-pv: using stolen time PV bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296 clare # [ 0.000487] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.000000] Detected PIPT I-cache on CPU0 clare # [ 0.000766] Console: colour dummy device 80x25 bob # [ 0.000000] CPU features: detected: GICv3 CPU interface clare # [ 0.000779] printk: legacy console [tty0] enabled bob # [ 0.000000] CPU features: detected: Spectre-v4 bob # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.000995] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) clare # [ 0.001000] pid_max: default: 32768 minimum: 301 bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.001084] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.001233] landlock: Up and running. bob # [ 0.000000] CPU features: detected: ARM erratum 1418040 clare # [ 0.001236] Yama: becoming mindful. clare # [ 0.001748] LSM support for eBPF active bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion clare # [ 0.001936] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing bob # [ 0.000000] alternatives: applying boot alternatives clare # [ 0.001953] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.003844] cacheinfo: Unable to detect cache hierarchy for CPU 0 clare # [ 0.004684] rcu: Hierarchical SRCU implementation. clare # [ 0.004689] rcu: Max phase no-delay instances is 1000. clare # [ 0.005614] fsl-mc MSI: its@8080000 domain created clare # [ 0.005795] EFI services will not be available. clare # [ 0.005887] smp: Bringing up secondary CPUs ... 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/qxdv7pdjrqaakcbi512las48qjrf5msl-nixos-system-bob-test/init regInfo=/nix/store/zv7rjf5p0ljc47mvyixywwrd1jxjnkvp-closure-info/registration console=ttyAMA0,115200n8 console=tty0 clare # [ 0.005923] smp: Brought up 1 node, 1 CPU clare # [ 0.005926] SMP: Total of 1 processors activated. clare # [ 0.005928] CPU: All CPU(s) started at EL1 bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/zv7rjf5p0ljc47mvyixywwrd1jxjnkvp-closure-info/registration", will be passed to user space. clare # [ 0.005940] CPU features: detected: 32-bit EL0 Support bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes clare # [ 0.005943] CPU features: detected: Data cache clean to the PoU not required for I/D coherence bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) clare # [ 0.005946] CPU features: detected: Common not Private translations clare # [ 0.005948] CPU features: detected: CRC32 instructions bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.005949] CPU features: detected: Data cache clean to Point of Persistence bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB bob # [ 0.000000] software IO TLB: area num 1. clare # [ 0.005952] CPU features: detected: Enhanced Virtualization Traps clare # [ 0.005962] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.005964] CPU features: detected: LSE atomic instructions bob # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.005966] CPU features: detected: Privileged Access Never bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 alice # [ 0.000000] Linux version 6.18.47 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Thu Aug 27 12:32:55 UTC 2026 alice # [ 0.000000] KASLR enabled clare # [ 0.005968] CPU features: detected: PMUv3 bob # [ 0.000000] Policy zone: DMA alice # [ 0.000000] random: crng init done bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off clare # [ 0.005970] CPU features: detected: RAS Extension Support alice # [ 0.000000] Machine model: linux,dummy-virt alice # [ 0.000000] efi: UEFI not found. bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 clare # [ 0.005974] CPU features: detected: Speculative Store Bypassing Safe (SSBS) bob # [ 0.000000] allocated 2097152 bytes of page_ext alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT clare # [ 0.006001] alternatives: applying system-wide alternatives bob # [ 0.000000] ftrace: allocating 74886 entries in 294 pages clare # [ 0.007449] CPU features: detected: Hardware dirty bit management on CPU0 alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups bob # [ 0.000000] rcu: Hierarchical RCU implementation. alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f] alice # [ 0.000000] Zone ranges: bob # [ 0.000000] rcu: RCU event tracing is enabled. clare # [ 0.007676] Memory: 892380K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 114888K reserved, 32768K cma-reserved) alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.008184] devtmpfs: initialized bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. alice # [ 0.000000] DMA32 empty alice # [ 0.000000] Normal empty bob # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.009741] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.000000] Device empty bob # [ 0.000000] Rude variant of Tasks RCU enabled. alice # [ 0.000000] Movable zone start for each node alice # [ 0.000000] Early memory node ranges clare # [ 0.009761] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). bob # [ 0.000000] Tracing variant of Tasks RCU enabled. alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. clare # [ 0.009908] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL clare # [ 0.009911] 0 pages in range for non-PLT usage alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 clare # [ 0.009912] 508288 pages in range for PLT usage alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 clare # [ 0.010052] pinctrl core: initialized pinctrl subsystem bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.000000] psci: probing for conduit method from DT. clare # [ 0.010894] DMI not present or invalid. alice # [ 0.000000] psci: PSCIv1.3 detected in firmware. clare # [ 0.014432] NET: Registered PF_NETLINK/PF_ROUTE protocol family bob # [ 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.000000] psci: Using standard PSCI v0.2 function IDs clare # [ 0.016684] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations alice # [ 0.000000] psci: Trusted OS migration not required bob # [ 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.000000] psci: SMC Calling Convention v1.1 bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.016817] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 0.000000] GICv3: 256 SPIs implemented alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) clare # [ 0.016966] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations bob # [ 0.000000] GICv3: 0 Extended SPIs implemented alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296 bob # [ 0.000000] Root IRQ handler: gic_handle_irq clare # [ 0.016987] audit: initializing netlink subsys (disabled) alice # [ 0.000000] Detected PIPT I-cache on CPU0 bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI clare # [ 0.017605] thermal_sys: Registered thermal governor 'fair_share' bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 alice # [ 0.000000] CPU features: detected: GICv3 CPU interface clare # [ 0.017607] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 0.000000] CPU features: detected: Spectre-v4 bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 clare # [ 0.017611] thermal_sys: Registered thermal governor 'step_wise' bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] alice # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.017613] thermal_sys: Registered thermal governor 'user_space' alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) clare # [ 0.017615] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.017644] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 alice # [ 0.000000] CPU features: detected: ARM erratum 1418040 bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.017660] cpuidle: using governor ladder alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion clare # [ 0.017665] cpuidle: using governor menu bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.017785] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 alice # [ 0.000000] alternatives: applying boot alternatives clare # [ 0.017806] ASID allocator initialised with 32768 entries bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. clare # [ 0.018463] Serial: AMBA PL011 UART driver bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns clare # [ 0.021633] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 clare # [ 0.021816] printk: console [ttyAMA0] enabled bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). 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/27w2nnrs3k2hcqiqp5iagqb8c9cvnjnc-nixos-system-alice-test/init regInfo=/nix/store/r8257y4hiz40kj57dpncgc6m0gfg7rg8-closure-info/registration console=ttyAMA0,115200n8 console=tty0 bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/r8257y4hiz40kj57dpncgc6m0gfg7rg8-closure-info/registration", will be passed to user space. bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.000056] arm-pv: using stolen time PV alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes bob # [ 0.000395] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) bob # [ 0.000669] Console: colour dummy device 80x25 alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) bob # [ 0.000681] printk: legacy console [tty0] enabled alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB alice # [ 0.000000] software IO TLB: area num 1. bob # [ 0.000886] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) bob # [ 0.000891] pid_max: default: 32768 minimum: 301 alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) bob # [ 0.000968] LSM: initializing lsm=capability,landlock,yama,bpf,ima alice # [ 0.000000] Fallback order for Node 0: 0 bob # [ 0.001102] landlock: Up and running. bob # [ 0.001104] Yama: becoming mindful. alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 alice # [ 0.000000] Policy zone: DMA bob # [ 0.001522] LSM support for eBPF active alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off bob # [ 0.001701] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 bob # [ 0.001720] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.000000] allocated 2097152 bytes of page_ext bob # [ 0.003507] cacheinfo: Unable to detect cache hierarchy for CPU 0 clare # [ 0.095296] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages alice # [ 0.000000] ftrace: allocating 74886 entries in 294 pages bob # [ 0.004419] rcu: Hierarchical SRCU implementation. clare # [ 0.095313] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups bob # [ 0.004424] rcu: Max phase no-delay instances is 1000. alice # [ 0.000000] rcu: Hierarchical RCU implementation. clare # [ 0.095320] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.005329] fsl-mc MSI: its@8080000 domain created alice # [ 0.000000] rcu: RCU event tracing is enabled. bob # [ 0.005489] EFI services will not be available. clare # [ 0.095323] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. bob # [ 0.005571] smp: Bringing up secondary CPUs ... clare # [ 0.095326] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 0.005605] smp: Brought up 1 node, 1 CPU alice # [ 0.000000] Trampoline variant of Tasks RCU enabled. bob # [ 0.005608] SMP: Total of 1 processors activated. clare # [ 0.095329] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 0.000000] Rude variant of Tasks RCU enabled. bob # [ 0.005610] CPU: All CPU(s) started at EL1 alice # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.095332] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages bob # [ 0.005622] CPU features: detected: 32-bit EL0 Support alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. clare # [ 0.095335] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page bob # [ 0.005625] CPU features: detected: Data cache clean to the PoU not required for I/D coherence alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 bob # [ 0.005628] CPU features: detected: Common not Private translations 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.005630] CPU features: detected: CRC32 instructions bob # [ 0.005632] CPU features: detected: Data cache clean to Point of Persistence alice # [ 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.100348] fbcon: Taking over console bob # [ 0.005635] CPU features: detected: Enhanced Virtualization Traps clare # [ 0.100369] ACPI: Interpreter disabled. alice # [ 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.101586] iommu: Default domain type: Translated bob # [ 0.005644] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.101597] iommu: DMA domain TLB invalidation policy: strict mode bob # [ 0.005647] CPU features: detected: LSE atomic instructions alice # [ 0.000000] GICv3: 256 SPIs implemented bob # [ 0.005648] CPU features: detected: Privileged Access Never alice # [ 0.000000] GICv3: 0 Extended SPIs implemented clare # [ 0.102751] SCSI subsystem initialized bob # [ 0.005650] CPU features: detected: PMUv3 alice # [ 0.000000] Root IRQ handler: gic_handle_irq bob # [ 0.005652] CPU features: detected: RAS Extension Support alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 bob # [ 0.005657] CPU features: detected: Speculative Store Bypassing Safe (SSBS) bob # [ 0.005683] alternatives: applying system-wide alternatives alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] bob # [ 0.007066] CPU features: detected: Hardware dirty bit management on CPU0 alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) bob # [ 0.007281] Memory: 892364K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 114892K reserved, 32768K cma-reserved) bob # [ 0.007744] devtmpfs: initialized alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) bob # [ 0.009224] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.009244] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.009367] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.009370] 0 pages in range for non-PLT usage clare # [ 0.107666] usbcore: registered new interface driver usbfs bob # [ 0.009371] 508288 pages in range for PLT usage alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns clare # [ 0.107705] usbcore: registered new interface driver hub bob # [ 0.009500] pinctrl core: initialized pinctrl subsystem alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 clare # [ 0.107716] usbcore: registered new device driver usb bob # [ 0.010212] DMI not present or invalid. clare # [ 0.107929] pps_core: LinuxPPS API ver. 1 registered alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). bob # [ 0.013435] NET: Registered PF_NETLINK/PF_ROUTE protocol family clare # [ 0.107936] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti bob # [ 0.015788] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns clare # [ 0.107942] PTP clock support registered clare # [ 0.107970] EDAC MC: Ver: 3.0.0 bob # [ 0.015921] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns alice # [ 0.000064] arm-pv: using stolen time PV clare # [ 0.110918] scmi_core: SCMI protocol bus registered bob # [ 0.016072] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations alice # [ 0.000432] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.000708] Console: colour dummy device 80x25 bob # [ 0.016092] audit: initializing netlink subsys (disabled) clare # [ 0.111661] FPGA manager framework alice # [ 0.000720] printk: legacy console [tty0] enabled clare # [ 0.112621] vgaarb: loaded bob # [ 0.016708] thermal_sys: Registered thermal governor 'fair_share' bob # [ 0.016710] thermal_sys: Registered thermal governor 'bang_bang' clare # [ 0.113048] clocksource: Switched to clocksource arch_sys_counter alice # [ 0.000926] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) bob # [ 0.016713] thermal_sys: Registered thermal governor 'step_wise' alice # [ 0.000932] pid_max: default: 32768 minimum: 301 bob # [ 0.016715] thermal_sys: Registered thermal governor 'user_space' alice # [ 0.001019] LSM: initializing lsm=capability,landlock,yama,bpf,ima alice # [ 0.001182] landlock: Up and running. bob # [ 0.016717] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 0.001184] Yama: becoming mindful. alice # [ 0.001609] LSM support for eBPF active bob # [ 0.016740] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 bob # [ 0.016755] cpuidle: using governor ladder alice # [ 0.001775] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.016760] cpuidle: using governor menu alice # [ 0.001793] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.016895] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. alice # [ 0.003575] cacheinfo: Unable to detect cache hierarchy for CPU 0 bob # [ 0.016915] ASID allocator initialised with 32768 entries alice # [ 0.004398] rcu: Hierarchical SRCU implementation. bob # [ 0.017603] Serial: AMBA PL011 UART driver alice # [ 0.004403] rcu: Max phase no-delay instances is 1000. bob # [ 0.020881] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 alice # [ 0.005330] fsl-mc MSI: its@8080000 domain created bob # [ 0.021032] printk: console [ttyAMA0] enabled alice # [ 0.005483] EFI services will not be available. alice # [ 0.005580] smp: Bringing up secondary CPUs ... alice # [ 0.005614] smp: Brought up 1 node, 1 CPU alice # [ 0.005617] SMP: Total of 1 processors activated. alice # [ 0.005619] CPU: All CPU(s) started at EL1 alice # [ 0.005631] CPU features: detected: 32-bit EL0 Support alice # [ 0.005633] CPU features: detected: Data cache clean to the PoU not required for I/D coherence alice # [ 0.005636] CPU features: detected: Common not Private translations alice # [ 0.005638] CPU features: detected: CRC32 instructions alice # [ 0.005640] CPU features: detected: Data cache clean to Point of Persistence clare # [ 0.122281] VFS: Disk quotas dquot_6.6.0 alice # [ 0.005643] CPU features: detected: Enhanced Virtualization Traps clare # [ 0.122326] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 0.005652] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.005655] CPU features: detected: LSE atomic instructions alice # [ 0.005656] CPU features: detected: Privileged Access Never alice # [ 0.005658] CPU features: detected: PMUv3 bob # [ 0.093636] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages alice # [ 0.005660] CPU features: detected: RAS Extension Support clare # [ 0.124546] netfs: FS-Cache loaded bob # [ 0.093654] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page clare # [ 0.124677] pnp: PnP ACPI: disabled alice # [ 0.005664] CPU features: detected: Speculative Store Bypassing Safe (SSBS) bob # [ 0.093662] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages alice # [ 0.005691] alternatives: applying system-wide alternatives bob # [ 0.093665] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page alice # [ 0.007149] CPU features: detected: Hardware dirty bit management on CPU0 bob # [ 0.093668] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 0.093671] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 0.007343] Memory: 892364K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 114888K reserved, 32768K cma-reserved) alice # [ 0.007796] devtmpfs: initialized bob # [ 0.093674] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages alice # [ 0.009274] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.093677] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page alice # [ 0.009295] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). alice # [ 0.009433] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL clare # [ 0.127087] NET: Registered PF_INET protocol family alice # [ 0.009437] 0 pages in range for non-PLT usage clare # [ 0.127280] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.009438] 508288 pages in range for PLT usage bob # [ 0.098818] fbcon: Taking over console alice # [ 0.009564] pinctrl core: initialized pinctrl subsystem bob # [ 0.098841] ACPI: Interpreter disabled. alice # [ 0.010406] DMI not present or invalid. bob # [ 0.100068] iommu: Default domain type: Translated alice # [ 0.013649] NET: Registered PF_NETLINK/PF_ROUTE protocol family bob # [ 0.100079] iommu: DMA domain TLB invalidation policy: strict mode alice # [ 0.015876] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations bob # [ 0.101235] SCSI subsystem initialized alice # [ 0.016010] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations alice # [ 0.016188] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations alice # [ 0.016207] audit: initializing netlink subsys (disabled) alice # [ 0.016755] thermal_sys: Registered thermal governor 'fair_share' alice # [ 0.016757] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 0.016760] thermal_sys: Registered thermal governor 'step_wise' alice # [ 0.016762] thermal_sys: Registered thermal governor 'user_space' alice # [ 0.016764] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 0.016788] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 alice # [ 0.016802] cpuidle: using governor ladder alice # [ 0.016807] cpuidle: using governor menu alice # [ 0.016941] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. alice # [ 0.016964] ASID allocator initialised with 32768 entries alice # [ 0.017630] Serial: AMBA PL011 UART driver alice # [ 0.020777] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 alice # [ 0.020924] printk: console [ttyAMA0] enabled bob # [ 0.109911] usbcore: registered new interface driver usbfs bob # [ 0.109952] usbcore: registered new interface driver hub bob # [ 0.109967] usbcore: registered new device driver usb bob # [ 0.110196] pps_core: LinuxPPS API ver. 1 registered bob # [ 0.110202] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti bob # [ 0.110208] PTP clock support registered bob # [ 0.110248] EDAC MC: Ver: 3.0.0 bob # [ 0.113384] scmi_core: SCMI protocol bus registered alice # [ 0.093616] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.114220] FPGA manager framework bob # [ 0.115296] vgaarb: loaded alice # [ 0.093633] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 0.115748] clocksource: Switched to clocksource arch_sys_counter alice # [ 0.093640] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages alice # [ 0.093644] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page alice # [ 0.093647] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages alice # [ 0.093650] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 0.093653] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages alice # [ 0.093656] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page alice # [ 0.099228] fbcon: Taking over console alice # [ 0.099250] ACPI: Interpreter disabled. bob # [ 0.122238] VFS: Disk quotas dquot_6.6.0 bob # [ 0.122278] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.124710] netfs: FS-Cache loaded bob # [ 0.124888] pnp: PnP ACPI: disabled alice # [ 0.105515] iommu: Default domain type: Translated clare # [ 0.151413] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.105530] iommu: DMA domain TLB invalidation policy: strict mode clare # [ 0.151445] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) alice # [ 0.105885] SCSI subsystem initialized bob # [ 0.127324] NET: Registered PF_INET protocol family clare # [ 0.151459] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 0.127509] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) clare # [ 0.151502] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.107350] usbcore: registered new interface driver usbfs alice # [ 0.107391] usbcore: registered new interface driver hub clare # [ 0.151570] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.107407] usbcore: registered new device driver usb clare # [ 0.151663] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 0.107631] pps_core: LinuxPPS API ver. 1 registered clare # [ 0.151723] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.107637] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti clare # [ 0.151751] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.107650] PTP clock support registered alice # [ 0.107677] EDAC MC: Ver: 3.0.0 clare # [ 0.151862] NET: Registered PF_UNIX/PF_LOCAL protocol family clare # [ 0.151902] NET: Registered PF_XDP protocol family clare # [ 0.151924] PCI: CLS 0 bytes, default 64 alice # [ 0.110879] scmi_core: SCMI protocol bus registered clare # [ 0.152127] Trying to unpack rootfs image as initramfs... alice # [ 0.111617] FPGA manager framework alice # [ 0.112628] vgaarb: loaded alice # [ 0.113125] clocksource: Switched to clocksource arch_sys_counter clare # [ 0.163151] kvm [1]: HYP mode not available alice # [ 0.123690] VFS: Disk quotas dquot_6.6.0 alice # [ 0.123732] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 0.126078] netfs: FS-Cache loaded alice # [ 0.126201] pnp: PnP ACPI: disabled alice # [ 0.128602] NET: Registered PF_INET protocol family alice # [ 0.128798] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) bob # [ 0.151141] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.151176] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.151191] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 0.151242] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) bob # [ 0.151310] TCP: Hash tables configured (established 8192 bind 8192) bob # [ 0.151406] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) bob # [ 0.151467] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.151526] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.151663] NET: Registered PF_UNIX/PF_LOCAL protocol family bob # [ 0.151700] NET: Registered PF_XDP protocol family bob # [ 0.151723] PCI: CLS 0 bytes, default 64 bob # [ 0.151876] Trying to unpack rootfs image as initramfs... bob # [ 0.166083] kvm [1]: HYP mode not available alice # [ 0.153261] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.153294] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) alice # [ 0.153309] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) alice # [ 0.153355] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.153423] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.153557] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 0.153614] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.153644] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.153749] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 0.153794] NET: Registered PF_XDP protocol family alice # [ 0.153818] PCI: CLS 0 bytes, default 64 alice # [ 0.154013] Trying to unpack rootfs image as initramfs... alice # [ 0.167479] kvm [1]: HYP mode not available clare # [ 0.229465] Initialise system trusted keyrings clare # [ 0.229961] workingset: timestamp_bits=42 max_order=18 bucket_order=0 clare # [ 0.230884] squashfs: version 4.0 (2009/01/31) Phillip Lougher clare # [ 0.231389] 9p: Installing v9fs 9p2000 file system support clare # [ 0.254304] Key type asymmetric registered clare # [ 0.254333] Asymmetric key parser 'x509' registered clare # [ 0.254397] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) clare # [ 0.255763] io scheduler mq-deadline registered clare # [ 0.255779] io scheduler kyber registered bob # [ 0.233137] Initialise system trusted keyrings bob # [ 0.233675] workingset: timestamp_bits=42 max_order=18 bucket_order=0 bob # [ 0.234630] squashfs: version 4.0 (2009/01/31) Phillip Lougher bob # [ 0.235154] 9p: Installing v9fs 9p2000 file system support clare # [ 0.265217] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.265591] ledtrig-cpu: registered to indicate activity on CPUs clare # [ 0.265895] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: clare # [ 0.265916] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 clare # [ 0.265930] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 clare # [ 0.265936] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.265971] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits clare # [ 0.265996] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.266085] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 clare # [ 0.266102] pci_bus 0000:00: root bus resource [bus 00-ff] clare # [ 0.266107] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] clare # [ 0.266111] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] clare # [ 0.266114] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] clare # [ 0.266205] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint clare # [ 0.266658] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.266790] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.266802] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.266823] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.266835] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.267159] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.267291] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.267303] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.267324] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.267637] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint clare # [ 0.267762] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.234953] Initialise system trusted keyrings clare # [ 0.267774] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.235476] workingset: timestamp_bits=42 max_order=18 bucket_order=0 clare # [ 0.267795] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.268116] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint alice # [ 0.236449] squashfs: version 4.0 (2009/01/31) Phillip Lougher clare # [ 0.268243] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.236978] 9p: Installing v9fs 9p2000 file system support clare # [ 0.268254] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.258087] Key type asymmetric registered clare # [ 0.268275] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.258120] Asymmetric key parser 'x509' registered clare # [ 0.268593] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint bob # [ 0.258181] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) clare # [ 0.268722] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.259542] io scheduler mq-deadline registered bob # [ 0.259554] io scheduler kyber registered clare # [ 0.268734] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.268755] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.269099] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint clare # [ 0.269224] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] clare # [ 0.269236] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.269265] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.269587] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.269715] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.269726] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.269752] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.269763] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.270134] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.270264] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.270284] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.270604] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint clare # [ 0.270735] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.270755] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.271025] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.271148] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.271315] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint clare # [ 0.271548] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] clare # [ 0.271562] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.271581] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.271895] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.272021] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.272033] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.272053] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.272472] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned clare # [ 0.272483] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned clare # [ 0.272488] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned clare # [ 0.272523] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned bob # [ 0.267870] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.272555] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned bob # [ 0.268234] ledtrig-cpu: registered to indicate activity on CPUs clare # [ 0.272588] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.268532] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: alice # [ 0.256390] Key type asymmetric registered clare # [ 0.272619] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.268553] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 alice # [ 0.256418] Asymmetric key parser 'x509' registered clare # [ 0.272652] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned bob # [ 0.268567] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 alice # [ 0.256483] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) clare # [ 0.272684] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned bob # [ 0.268573] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.272717] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.268605] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits clare # [ 0.272749] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.268628] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.272782] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned bob # [ 0.268693] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 clare # [ 0.272868] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned bob # [ 0.268711] pci_bus 0000:00: root bus resource [bus 00-ff] clare # [ 0.272907] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned bob # [ 0.268716] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] clare # [ 0.272921] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned alice # [ 0.261215] io scheduler mq-deadline registered bob # [ 0.268720] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.261236] io scheduler kyber registered clare # [ 0.272935] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.268724] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] clare # [ 0.272948] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.268809] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint clare # [ 0.272962] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned bob # [ 0.269265] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.272976] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.269399] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.272990] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.269411] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.273003] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.269431] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.273017] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.269443] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.273031] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.269768] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.269900] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.269912] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.269933] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.270266] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint bob # [ 0.270397] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.270408] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.270429] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.270747] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint bob # [ 0.270884] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.270896] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.270917] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.271271] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint bob # [ 0.271400] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.321086] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.271412] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.321152] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned bob # [ 0.271432] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.321169] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned clare # [ 0.321184] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.271744] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint clare # [ 0.321200] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned bob # [ 0.271897] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] clare # [ 0.321214] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned bob # [ 0.271909] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.321233] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.271930] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.321256] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned bob # [ 0.272254] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.321271] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned bob # [ 0.272384] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.321286] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned bob # [ 0.272395] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.321302] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned bob # [ 0.272419] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.269306] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.321326] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.272430] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.321332] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] alice # [ 0.269675] ledtrig-cpu: registered to indicate activity on CPUs bob # [ 0.272749] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.321336] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.269993] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: bob # [ 0.272889] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.322132] pci 0000:00:0a.0: enabling device (0000 -> 0002) alice # [ 0.270015] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 bob # [ 0.272911] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.270031] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 bob # [ 0.273237] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 0.270049] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 bob # [ 0.273370] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.270084] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits bob # [ 0.273390] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.273668] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 0.270112] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] bob # [ 0.273795] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] alice # [ 0.270210] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 bob # [ 0.273963] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint alice # [ 0.270229] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 0.274191] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.270233] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] bob # [ 0.274205] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.270237] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] bob # [ 0.274226] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.270241] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] bob # [ 0.274551] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.270320] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint bob # [ 0.274681] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.274692] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.270771] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.270903] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.274713] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.270915] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.275157] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned clare # [ 0.335064] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.270936] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.275168] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned clare # [ 0.336951] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.270947] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.275173] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned alice # [ 0.271273] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.275207] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned alice # [ 0.271406] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.275240] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned alice # [ 0.271419] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.275272] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned alice # [ 0.271438] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.275305] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned alice # [ 0.271765] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint alice # [ 0.271891] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.275339] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned alice # [ 0.271902] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.275372] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned alice # [ 0.271922] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.275405] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned alice # [ 0.272233] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint bob # [ 0.275439] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned alice # [ 0.272359] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.272370] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.275471] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned alice # [ 0.272391] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.275544] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned bob # [ 0.275576] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned alice # [ 0.272716] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint bob # [ 0.275590] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned alice # [ 0.272845] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.272857] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.275604] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned alice # [ 0.272879] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.275618] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned alice # [ 0.273221] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint bob # [ 0.275631] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned alice # [ 0.273348] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] bob # [ 0.275645] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned alice # [ 0.273359] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.275659] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned alice # [ 0.273379] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.275673] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned alice # [ 0.273705] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.275686] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned alice # [ 0.273832] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.275700] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned alice # [ 0.273844] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.275715] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned alice # [ 0.273870] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.275728] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned alice # [ 0.273881] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.275742] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned alice # [ 0.274244] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint alice # [ 0.274376] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.274397] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.274739] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 0.274872] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.274893] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.275174] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 0.275299] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] alice # [ 0.275467] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint alice # [ 0.275701] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.275714] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.275735] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.354249] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) alice # [ 0.276064] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.355947] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) alice # [ 0.276193] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.276203] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.276224] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.276656] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned alice # [ 0.276668] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned bob # [ 0.327840] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.327864] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned alice # [ 0.276673] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned bob # [ 0.327881] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.276707] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned clare # [ 0.358874] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) bob # [ 0.327901] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned alice # [ 0.276740] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned clare # [ 0.360752] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) bob # [ 0.327916] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned alice # [ 0.276771] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.327931] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned bob # [ 0.327946] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned alice # [ 0.276803] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.327962] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned alice # [ 0.276834] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned bob # [ 0.327988] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] alice # [ 0.276866] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned bob # [ 0.327994] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] bob # [ 0.327998] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.276898] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.328811] pci 0000:00:0a.0: enabling device (0000 -> 0002) alice # [ 0.276930] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned alice # [ 0.276962] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned alice # [ 0.277058] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned alice # [ 0.277090] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned alice # [ 0.277104] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned alice # [ 0.277118] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned clare # [ 0.367819] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) clare # [ 0.374041] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.344728] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.325225] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned clare # [ 0.376023] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) bob # [ 0.346564] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.325257] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned alice # [ 0.325274] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned alice # [ 0.325290] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned alice # [ 0.325306] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned alice # [ 0.325324] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned alice # [ 0.325340] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned alice # [ 0.325357] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned alice # [ 0.325373] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned alice # [ 0.325389] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned alice # [ 0.325403] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned alice # [ 0.325417] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned alice # [ 0.325432] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.325452] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned alice # [ 0.325468] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned alice # [ 0.325482] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned alice # [ 0.325496] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned alice # [ 0.325510] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned alice # [ 0.325532] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] alice # [ 0.325538] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] alice # [ 0.325543] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.326364] pci 0000:00:0a.0: enabling device (0000 -> 0002) clare # [ 0.385530] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) clare # [ 0.387590] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) bob # [ 0.356255] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) bob # [ 0.358148] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) bob # [ 0.359907] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) bob # [ 0.361865] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) bob # [ 0.363733] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) alice # [ 0.343437] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) clare # [ 0.398958] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled clare # [ 0.400499] msm_serial: driver initialized clare # [ 0.400575] SuperH (H)SCI(F) driver initialized clare # [ 0.400610] STM32 USART driver initialized bob # [ 0.373704] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.375555] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.353367] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.356442] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) bob # [ 0.385173] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) bob # [ 0.387212] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) alice # [ 0.366324] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.420501] loop: module loaded clare # [ 0.420648] virtio_blk virtio5: 1/0/0 default/read/poll queues clare # [ 0.421997] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 0.370103] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) alice # [ 0.371884] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) bob # [ 0.399796] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled clare # [ 0.429532] megasas: 07.734.00.00-rc1 bob # [ 0.401345] msm_serial: driver initialized clare # [ 0.430116] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] bob # [ 0.401436] SuperH (H)SCI(F) driver initialized bob # [ 0.401472] STM32 USART driver initialized alice # [ 0.381650] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) alice # [ 0.384189] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) clare # [ 0.442087] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.442129] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.444120] Using buffer write method clare # [ 0.444191] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.394254] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.396017] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) clare # [ 0.447927] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.447952] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.450655] Using buffer write method clare # [ 0.450682] Concatenating MTD devices: clare # [ 0.450685] (0): "0.flash" clare # [ 0.450687] (1): "0.flash" clare # [ 0.450690] into device "0.flash" alice # [ 0.399247] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) bob # [ 0.427329] loop: module loaded bob # [ 0.427490] virtio_blk virtio5: 1/0/0 default/read/poll queues bob # [ 0.428828] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 0.410741] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled alice # [ 0.412282] msm_serial: driver initialized alice # [ 0.412388] SuperH (H)SCI(F) driver initialized alice # [ 0.412423] STM32 USART driver initialized bob # [ 0.432208] megasas: 07.734.00.00-rc1 bob # [ 0.432707] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] bob # [ 0.448817] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.448870] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.451262] Using buffer write method bob # [ 0.451342] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.434923] loop: module loaded alice # [ 0.435102] virtio_blk virtio5: 1/0/0 default/read/poll queues alice # [ 0.435684] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) bob # [ 0.460740] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.460774] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.463424] Using buffer write method bob # [ 0.463456] Concatenating MTD devices: bob # [ 0.463459] (0): "0.flash" bob # [ 0.463462] (1): "0.flash" bob # [ 0.463464] into device "0.flash" alice # [ 0.445687] megasas: 07.734.00.00-rc1 alice # [ 0.446295] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] alice # [ 0.448212] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.448249] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.450057] Using buffer write method alice # [ 0.450102] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.451981] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.452005] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.461657] Using buffer write method alice # [ 0.461689] Concatenating MTD devices: alice # [ 0.461692] (0): "0.flash" alice # [ 0.461695] (1): "0.flash" alice # [ 0.461697] into device "0.flash" clare # [ 0.672757] Freeing initrd memory: 26084K clare # [ 0.680406] tun: Universal TUN/TAP device driver, 1.6 clare # [ 0.683165] thunder_xcv, ver 1.0 clare # [ 0.683200] thunder_bgx, ver 1.0 clare # [ 0.683212] nicpf, ver 1.0 clare # [ 0.683516] e1000: Intel(R) PRO/1000 Network Driver clare # [ 0.683525] e1000: Copyright (c) 1999-2006 Intel Corporation. clare # [ 0.683540] e1000e: Intel(R) PRO/1000 Network Driver clare # [ 0.683547] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. clare # [ 0.683560] igb: Intel(R) Gigabit Ethernet Network Driver clare # [ 0.683564] igb: Copyright (c) 2007-2014 Intel Corporation. clare # [ 0.683574] igbvf: Intel(R) Gigabit Virtual Function Network Driver clare # [ 0.683578] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. clare # [ 0.683640] sky2: driver version 1.30 clare # [ 0.684407] usbcore: registered new interface driver usb-storage clare # [ 0.684467] usbcore: registered new interface driver usbserial_generic clare # [ 0.684474] usbserial: USB Serial support registered for generic clare # [ 0.684748] hv_vmbus: registering driver hyperv_keyboard clare # [ 0.692043] ehci-pci 0000:00:0a.0: EHCI Host Controller clare # [ 0.692085] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 clare # [ 0.692287] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 clare # [ 0.694287] rtc-pl031 9010000.pl031: registered as rtc0 clare # [ 0.694333] rtc-pl031 9010000.pl031: setting system clock to 2026-08-29T20:08:48 UTC (1788034128) clare # [ 0.694542] i2c_dev: i2c /dev entries driver clare # [ 0.697438] sdhci: Secure Digital Host Controller Interface driver clare # [ 0.697454] sdhci: Copyright(c) Pierre Ossman clare # [ 0.697584] Synopsys Designware Multimedia Card Interface Driver clare # [ 0.697747] sdhci-pltfm: SDHCI platform and OF driver helper clare # [ 0.698643] hid: raw HID events driver (C) Jiri Kosina clare # [ 0.698837] usbcore: registered new interface driver usbhid clare # [ 0.698843] usbhid: USB HID core driver clare # [ 0.702480] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 clare # [ 0.702954] hub 1-0:1.0: USB hub found clare # [ 0.702985] hub 1-0:1.0: 6 ports detected clare # [ 0.704388] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available clare # [ 0.705992] drop_monitor: Initializing network drop monitor service clare # [ 0.706128] NET: Registered PF_INET6 protocol family clare # [ 0.707744] Segment Routing with IPv6 clare # [ 0.707772] In-situ OAM (IOAM) with IPv6 clare # [ 0.707828] NET: Registered PF_PACKET protocol family clare # [ 0.708964] 9pnet: Installing 9P2000 support clare # [ 0.710991] Key type dns_resolver registered clare # [ 0.714313] registered taskstats version 1 clare # [ 0.714479] Loading compiled-in X.509 certificates bob # [ 0.683311] Freeing initrd memory: 26088K clare # [ 0.719922] Demotion targets for Node 0: null clare # [ 0.720006] Key type .fscrypt registered clare # [ 0.720011] Key type fscrypt-provisioning registered bob # [ 0.690729] tun: Universal TUN/TAP device driver, 1.6 clare # [ 0.720129] ima: No TPM chip found, activating TPM-bypass! clare # [ 0.720141] ima: Allocated hash algorithm: sha1 clare # [ 0.720194] ima: No architecture policies found clare # [ 0.723302] input: gpio-keys as /devices/platform/gpio-keys/input/input0 bob # [ 0.693617] thunder_xcv, ver 1.0 bob # [ 0.693652] thunder_bgx, ver 1.0 bob # [ 0.693665] nicpf, ver 1.0 bob # [ 0.693970] e1000: Intel(R) PRO/1000 Network Driver bob # [ 0.693979] e1000: Copyright (c) 1999-2006 Intel Corporation. bob # [ 0.693995] e1000e: Intel(R) PRO/1000 Network Driver bob # [ 0.694002] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.694014] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.694018] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.694031] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.694035] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. bob # [ 0.694099] sky2: driver version 1.30 bob # [ 0.694954] usbcore: registered new interface driver usb-storage bob # [ 0.695046] usbcore: registered new interface driver usbserial_generic bob # [ 0.695055] usbserial: USB Serial support registered for generic bob # [ 0.695349] hv_vmbus: registering driver hyperv_keyboard bob # [ 0.702729] ehci-pci 0000:00:0a.0: EHCI Host Controller bob # [ 0.702788] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 bob # [ 0.703016] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 clare # [ 0.734017] clk: Disabling unused clocks clare # [ 0.734042] PM: genpd: Disabling unused power domains bob # [ 0.704868] rtc-pl031 9010000.pl031: registered as rtc0 bob # [ 0.704919] rtc-pl031 9010000.pl031: setting system clock to 2026-08-29T20:08:48 UTC (1788034128) bob # [ 0.705143] i2c_dev: i2c /dev entries driver clare # [ 0.737320] Freeing unused kernel memory: 4736K clare # [ 0.737419] Run /init as init process alice # [ 0.688926] Freeing initrd memory: 26084K bob # [ 0.708039] sdhci: Secure Digital Host Controller Interface driver bob # [ 0.708056] sdhci: Copyright(c) Pierre Ossman bob # [ 0.708195] Synopsys Designware Multimedia Card Interface Driver bob # [ 0.708412] sdhci-pltfm: SDHCI platform and OF driver helper bob # [ 0.709393] hid: raw HID events driver (C) Jiri Kosina bob # [ 0.709576] usbcore: registered new interface driver usbhid bob # [ 0.709581] usbhid: USB HID core driver bob # [ 0.713244] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 bob # [ 0.713783] hub 1-0:1.0: USB hub found bob # [ 0.713813] hub 1-0:1.0: 6 ports detected bob # [ 0.715282] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available bob # [ 0.716943] drop_monitor: Initializing network drop monitor service bob # [ 0.717100] NET: Registered PF_INET6 protocol family alice # [ 0.697580] tun: Universal TUN/TAP device driver, 1.6 bob # [ 0.718790] Segment Routing with IPv6 bob # [ 0.718817] In-situ OAM (IOAM) with IPv6 bob # [ 0.718874] NET: Registered PF_PACKET protocol family alice # [ 0.700464] thunder_xcv, ver 1.0 alice # [ 0.700496] thunder_bgx, ver 1.0 alice # [ 0.700509] nicpf, ver 1.0 alice # [ 0.700803] e1000: Intel(R) PRO/1000 Network Driver alice # [ 0.700811] e1000: Copyright (c) 1999-2006 Intel Corporation. alice # [ 0.700826] e1000e: Intel(R) PRO/1000 Network Driver bob # [ 0.720073] 9pnet: Installing 9P2000 support alice # [ 0.700832] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.721780] Key type dns_resolver registered alice # [ 0.700845] igb: Intel(R) Gigabit Ethernet Network Driver alice # [ 0.700848] igb: Copyright (c) 2007-2014 Intel Corporation. alice # [ 0.700862] igbvf: Intel(R) Gigabit Virtual Function Network Driver alice # [ 0.700866] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. alice # [ 0.700935] sky2: driver version 1.30 bob # [ 0.725370] registered taskstats version 1 bob # [ 0.725540] Loading compiled-in X.509 certificates alice # [ 0.706430] usbcore: registered new interface driver usb-storage clare # [ 0.759735] systemd[1]: Successfully made /usr/ read-only. alice # [ 0.706532] usbcore: registered new interface driver usbserial_generic alice # [ 0.706540] usbserial: USB Serial support registered for generic alice # [ 0.706815] hv_vmbus: registering driver hyperv_keyboard alice # [ 0.707869] ehci-pci 0000:00:0a.0: EHCI Host Controller alice # [ 0.707910] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 bob # [ 0.731006] Demotion targets for Node 0: null alice # [ 0.708142] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 bob # [ 0.731096] Key type .fscrypt registered bob # [ 0.731102] Key type fscrypt-provisioning registered bob # [ 0.731213] ima: No TPM chip found, activating TPM-bypass! bob # [ 0.731226] ima: Allocated hash algorithm: sha1 alice # [ 0.711655] rtc-pl031 9010000.pl031: registered as rtc0 bob # [ 0.731279] ima: No architecture policies found alice # [ 0.711709] rtc-pl031 9010000.pl031: setting system clock to 2026-08-29T20:08:48 UTC (1788034128) alice # [ 0.711912] i2c_dev: i2c /dev entries driver bob # [ 0.734357] input: gpio-keys as /devices/platform/gpio-keys/input/input0 alice # [ 0.714768] sdhci: Secure Digital Host Controller Interface driver alice # [ 0.714786] sdhci: Copyright(c) Pierre Ossman alice # [ 0.714916] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.715074] sdhci-pltfm: SDHCI platform and OF driver helper alice # [ 0.716004] hid: raw HID events driver (C) Jiri Kosina alice # [ 0.716182] usbcore: registered new interface driver usbhid alice # [ 0.716188] usbhid: USB HID core driver alice # [ 0.719747] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 alice # [ 0.720251] hub 1-0:1.0: USB hub found alice # [ 0.721073] hub 1-0:1.0: 6 ports detected alice # [ 0.721822] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available alice # [ 0.722700] drop_monitor: Initializing network drop monitor service alice # [ 0.722871] NET: Registered PF_INET6 protocol family alice # [ 0.725194] Segment Routing with IPv6 alice # [ 0.725217] In-situ OAM (IOAM) with IPv6 bob # [ 0.745060] clk: Disabling unused clocks alice # [ 0.725292] NET: Registered PF_PACKET protocol family bob # [ 0.745087] PM: genpd: Disabling unused power domains bob # [ 0.748292] Freeing unused kernel memory: 4736K bob # [ 0.748417] Run /init as init process alice # [ 0.726398] 9pnet: Installing 9P2000 support alice # [ 0.728235] Key type dns_resolver registered alice # [ 0.731934] registered taskstats version 1 alice # [ 0.732097] Loading compiled-in X.509 certificates alice # [ 0.737639] Demotion targets for Node 0: null alice # [ 0.737721] Key type .fscrypt registered alice # [ 0.737727] Key type fscrypt-provisioning registered alice # [ 0.737841] ima: No TPM chip found, activating TPM-bypass! alice # [ 0.737854] ima: Allocated hash algorithm: sha1 alice # [ 0.737908] ima: No architecture policies found alice # [ 0.740828] input: gpio-keys as /devices/platform/gpio-keys/input/input0 bob # [ 0.766529] systemd[1]: Successfully made /usr/ read-only. alice # [ 0.751678] clk: Disabling unused clocks alice # [ 0.751702] PM: genpd: Disabling unused power domains alice # [ 0.754884] Freeing unused kernel memory: 4736K alice # [ 0.755016] Run /init as init process alice # [ 0.775770] systemd[1]: Successfully made /usr/ read-only. clare # [ 0.949148] usb 1-1: new high-speed USB device number 2 using ehci-pci bob # [ 0.959929] usb 1-1: new high-speed USB device number 2 using ehci-pci alice # [ 0.969186] usb 1-1: new high-speed USB device number 2 using ehci-pci clare # [ 1.094583] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) clare # [ 1.102346] systemd[1]: Detected virtualization qemu. clare # [ 1.103527] systemd[1]: Detected architecture arm64. clare # [ 1.105141] systemd[1]: Running in initrd. clare # [ 1.107590] systemd[1]: Initializing machine ID from random generator. clare # [ 1.110424] 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.114075] systemd[1]: Hostname set to . bob # [ 1.101197] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 1.108579] systemd[1]: Detected virtualization qemu. bob # [ 1.110232] systemd[1]: Detected architecture arm64. bob # [ 1.111632] systemd[1]: Running in initrd. bob # [ 1.113570] systemd[1]: Initializing machine ID from random generator. bob # [ 1.115962] systemd[1]: Hostname set to . bob # [ 1.122928] 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.110459] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) alice # [ 1.118918] systemd[1]: Detected virtualization qemu. alice # [ 1.120497] systemd[1]: Detected architecture arm64. alice # [ 1.122003] systemd[1]: Running in initrd. alice # [ 1.123042] systemd[1]: Initializing machine ID from random generator. alice # [ 1.123393] systemd[1]: Hostname set to . alice # [ 1.135609] 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.190719] 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.199996] 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.213351] 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.309119] usb 1-2: new high-speed USB device number 3 using ehci-pci bob # [ 1.319811] usb 1-2: new high-speed USB device number 3 using ehci-pci alice # [ 1.333162] usb 1-2: new high-speed USB device number 3 using ehci-pci clare # [ 1.390963] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 1.397980] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 1.407580] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 1.465850] 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.467543] 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.472708] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.482985] systemd[1]: Created slice Slice /system/modprobe. clare # [ 1.484039] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 1.485142] systemd[1]: Expecting device /dev/disk/by-label/nixos... clare # [ 1.485980] systemd[1]: Reached target Path Units. clare # [ 1.486597] systemd[1]: Reached target Slice Units. clare # [ 1.487227] systemd[1]: Reached target Swaps. clare # [ 1.487894] systemd[1]: Reached target Timer Units. clare # [ 1.488737] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 1.489815] systemd[1]: Listening on Journal Socket (/dev/log). clare # [ 1.490685] systemd[1]: Listening on Journal Sockets. clare # [ 1.491492] systemd[1]: Listening on udev Control Socket. clare # [ 1.492319] systemd[1]: Listening on udev Kernel Socket. clare # [ 1.493104] systemd[1]: Reached target Socket Units. clare # [ 1.495266] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 1.505377] systemd[1]: Starting Load Kernel Module 9pnet_virtio... bob # [ 1.473694] 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.506202] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 1.476786] 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.514054] systemd[1]: Mounting Kernel Configuration File System... clare # [ 1.533209] systemd[1]: Starting Journal Service... bob # [ 1.508366] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.540958] systemd[1]: Starting Load Kernel Modules... alice # [ 1.489681] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.541875] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 1.493083] 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.518227] systemd[1]: Created slice Slice /system/modprobe. alice # [ 1.497471] systemd[1]: Created slice Slice /system/modprobe. alice # [ 1.498964] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 1.519494] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 1.500225] systemd[1]: Expecting device /dev/disk/by-label/nixos... bob # [ 1.521280] systemd[1]: Expecting device /dev/disk/by-label/nixos... alice # [ 1.501197] systemd[1]: Reached target Path Units. bob # [ 1.522278] systemd[1]: Reached target Path Units. alice # [ 1.501928] systemd[1]: Reached target Slice Units. bob # [ 1.523075] systemd[1]: Reached target Slice Units. alice # [ 1.502926] systemd[1]: Reached target Swaps. bob # [ 1.523893] systemd[1]: Reached target Swaps. alice # [ 1.503631] systemd[1]: Reached target Timer Units. bob # [ 1.524590] systemd[1]: Reached target Timer Units. clare # [ 1.554459] systemd[1]: Starting Coldplug All udev Devices... alice # [ 1.504521] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 1.525646] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 1.505729] 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.527033] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 1.507604] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 1.508826] systemd[1]: Listening on Journal Sockets. bob # [ 1.528455] systemd[1]: Listening on Journal Sockets. bob # [ 1.528614] systemd[1]: Listening on udev Control Socket. bob # [ 1.528781] systemd[1]: Listening on udev Kernel Socket. alice # [ 1.509774] systemd[1]: Listening on udev Control Socket. bob # [ 1.528829] systemd[1]: Reached target Socket Units. alice # [ 1.509942] systemd[1]: Listening on udev Kernel Socket. alice # [ 1.509990] systemd[1]: Reached target Socket Units. bob # [ 1.534051] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.514636] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 1.543915] systemd[1]: Starting Load Kernel Module 9pnet_virtio... clare # [ 1.573329] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 1.544893] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.524981] systemd[1]: Starting Load Kernel Module 9pnet_virtio... clare # [ 1.574776] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. clare # [ 1.574976] systemd[1]: Finished Load Kernel Module 9pnet_virtio. clare # [ 1.575171] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.526008] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 1.552048] systemd[1]: Mounting Kernel Configuration File System... alice # [ 1.538267] systemd[1]: Mounting Kernel Configuration File System... clare # [ 1.589519] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 1.600419] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. bob # [ 1.576012] systemd[1]: Starting Journal Service... bob # [ 1.577768] systemd[1]: Starting Load Kernel Modules... alice # [ 1.557266] systemd[1]: Starting Journal Service... bob # [ 1.578429] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 1.561366] systemd[1]: Starting Load Kernel Modules... clare # [ 1.610976] systemd-journald[73]: Collecting audit messages is disabled. alice # [ 1.562196] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 1.566104] systemd[1]: Starting Coldplug All udev Devices... alice # [ 1.571060] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 1.617123] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev clare # [ 1.621941] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 alice # [ 1.572569] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. clare # [ 1.622159] [drm] features: -virgl +edid -resource_blob -host_visible bob # [ 1.593743] systemd[1]: Starting Coldplug All udev Devices... clare # [ 1.622164] [drm] features: -context_init clare # [ 1.622641] [drm] number of scanouts: 1 clare # [ 1.622656] [drm] number of cap sets: 0 clare # [ 1.633607] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 1.635350] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 1.604930] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 1.605785] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. bob # [ 1.606021] systemd[1]: Finished Load Kernel Module 9pnet_virtio. bob # [ 1.606242] systemd[1]: Mounted Kernel Configuration File System. bob # [ 1.616028] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 1.643788] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic clare # [ 1.643808] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 alice # [ 1.597367] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 1.597953] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.601506] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.614665] systemd-journald[73]: Collecting audit messages is disabled. clare # [ 1.665876] Console: switching to colour frame buffer device 160x50 bob # [ 1.647460] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. clare # [ 1.681550] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 1.681733] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 1.681768] systemd[1]: Reached target Local File Systems. clare # [ 1.684493] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 1.633557] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. alice # [ 1.635195] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 1.636943] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 1.659048] systemd-journald[73]: Collecting audit messages is disabled. clare # [ 1.693775] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.649415] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev clare # [ 1.694461] systemd-modules-load[74]: Inserted module 'dm_mod' bob # [ 1.672321] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 1.695233] systemd-modules-load[74]: Module 'virtio_balloon' is built in clare # [ 1.705016] systemd[1]: Started Journal Service. bob # [ 1.674090] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 1.698530] systemd-modules-load[74]: Module 'virtio_console' is built in clare # [ 1.700293] systemd-modules-load[74]: Inserted module 'virtio_gpu' clare # [ 1.703773] systemd-modules-load[74]: Module 'virtio_rng' is built in bob # [ 1.676380] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev bob # [ 1.681306] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 bob # [ 1.681548] [drm] features: -virgl +edid -resource_blob -host_visible bob # [ 1.681555] [drm] features: -context_init bob # [ 1.682037] [drm] number of scanouts: 1 bob # [ 1.682053] [drm] number of cap sets: 0 alice # [ 1.667177] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 alice # [ 1.667402] [drm] features: -virgl +edid -resource_blob -host_visible alice # [ 1.667409] [drm] features: -context_init alice # [ 1.667873] [drm] number of scanouts: 1 alice # [ 1.667888] [drm] number of cap sets: 0 clare # [ 1.712372] systemd[1]: Finished Load Kernel Modules. clare # [ 1.712994] systemd[1]: Starting Apply Kernel Variables... bob # [ 1.696190] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic bob # [ 1.696209] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 alice # [ 1.677605] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 1.677823] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 1.677856] systemd[1]: Reached target Local File Systems. alice # [ 1.679024] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 1.730085] systemd[1]: Starting Create System Files and Directories... alice # [ 1.685319] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic alice # [ 1.685334] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 alice # [ 1.701295] systemd[1]: Started Journal Service. bob # [ 1.720101] Console: switching to colour frame buffer device 160x50 bob # [ 1.726227] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 1.726397] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 1.726425] systemd[1]: Reached target Local File Systems. alice # [ 1.704276] Console: switching to colour frame buffer device 160x50 bob # [ 1.729181] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 1.756787] systemd[1]: Finished Apply Kernel Variables. clare # [ 1.758247] systemd-udevd[80]: Using default interface naming scheme 'v261'. alice # [ 1.708568] systemd-modules-load[74]: Inserted module 'dm_mod' alice # [ 1.709518] systemd-modules-load[74]: Module 'virtio_balloon' is built in alice # [ 1.710316] systemd-modules-load[74]: Module 'virtio_console' is built in bob # [ 1.744323] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device clare # [ 1.769010] systemd[1]: Finished Create System Files and Directories. alice # [ 1.725671] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.720082] systemd[1]: Starting Create System Files and Directories... bob # [ 1.748573] systemd-modules-load[74]: Inserted module 'dm_mod' bob # [ 1.749398] systemd-modules-load[74]: Module 'virtio_balloon' is built in alice # [ 1.728070] systemd-modules-load[74]: Inserted module 'virtio_gpu' bob # [ 1.757903] systemd[1]: Started Journal Service. alice # [ 1.728742] systemd-modules-load[74]: Module 'virtio_rng' is built in bob # [ 1.753006] systemd-modules-load[74]: Module 'virtio_console' is built in alice # [ 1.731671] systemd[1]: Finished Load Kernel Modules. clare # [ 1.785281] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 1.756282] systemd-modules-load[74]: Inserted module 'virtio_gpu' bob # [ 1.763544] systemd-modules-load[74]: Module 'virtio_rng' is built in bob # [ 1.768277] systemd[1]: Finished Load Kernel Modules. bob # [ 1.769039] systemd[1]: Starting Apply Kernel Variables... alice # [ 1.748129] systemd[1]: Starting Apply Kernel Variables... alice # [ 1.759778] systemd[1]: Finished Create System Files and Directories. bob # [ 1.785869] systemd[1]: Starting Create System Files and Directories... alice # [ 1.772539] systemd-udevd[79]: Using default interface naming scheme 'v261'. clare # [ 1.828090] systemd[1]: Starting Virtual Console Setup... bob # [ 1.800439] systemd-udevd[79]: Using default interface naming scheme 'v261'. alice # [ 1.779380] systemd[1]: Finished Apply Kernel Variables. alice # [ 1.800886] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 1.825186] systemd[1]: Finished Apply Kernel Variables. bob # [ 1.827796] systemd[1]: Finished Create System Files and Directories. clare # [ 1.860601] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 1.864799] systemd[1]: Finished Virtual Console Setup. bob # [ 1.840549] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 1.843447] systemd[1]: Starting Virtual Console Setup... bob # [ 1.884128] systemd[1]: Starting Virtual Console Setup... alice # [ 1.876503] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 1.879079] systemd[1]: Finished Virtual Console Setup. bob # [ 1.925013] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 1.926939] systemd[1]: Finished Virtual Console Setup. clare # [ 2.088916] systemd[1]: Finished Coldplug All udev Devices. clare # [ 2.089501] systemd[1]: Reached target System Initialization. clare # [ 2.090026] systemd[1]: Reached target Basic System. alice # [ 2.105307] systemd[1]: Finished Coldplug All udev Devices. alice # [ 2.105937] systemd[1]: Reached target System Initialization. alice # [ 2.106992] systemd[1]: Reached target Basic System. bob # [ 2.141087] systemd[1]: Finished Coldplug All udev Devices. bob # [ 2.141714] systemd[1]: Reached target System Initialization. bob # [ 2.142253] systemd[1]: Reached target Basic System. clare # [ 2.196990] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.201868] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.208133] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.222278] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.276946] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.279820] systemd[1]: Reached target Initrd Root Device. bob # [ 2.251691] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.286944] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... bob # [ 2.262513] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.318904] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks clare # [ 2.324878] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 2.297986] systemd[1]: Found device /dev/disk/by-label/nixos. alice # [ 2.299922] systemd[1]: Reached target Initrd Root Device. alice # [ 2.302545] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... bob # [ 2.340088] systemd[1]: Found device /dev/disk/by-label/nixos. bob # [ 2.343085] systemd[1]: Reached target Initrd Root Device. bob # [ 2.346545] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... alice # [ 2.339969] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks alice # [ 2.347661] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. bob # [ 2.381478] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 2.387311] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. clare # [ 2.548203] systemd[1]: Mounting /sysroot... clare # [ 2.579151] EXT4-fs (vda): mounted filesystem dfed0fd1-e6d8-49c4-bec4-ad693342b33c r/w with ordered data mode. Quota mode: none. clare # [ 2.573488] systemd[1]: Mounted /sysroot. clare # [ 2.574422] systemd[1]: Reached target Initrd Root File System. clare # [ 2.579641] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 2.593675] systemd[1]: Mounting /sysroot/nix/.rw-store... clare # [ 2.608699] systemd[1]: Mounting /sysroot/run... alice # [ 2.564119] systemd[1]: Mounting /sysroot... clare # [ 2.618291] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 2.600124] systemd[1]: Mounting /sysroot... clare # [ 2.633343] systemd[1]: Mounting /sysroot/tmp/xchg... alice # [ 2.590726] EXT4-fs (vda): mounted filesystem c3105474-4727-4398-a7b0-79d3f73c17bd r/w with ordered data mode. Quota mode: none. clare # [ 2.636080] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.586373] systemd[1]: Mounted /sysroot. clare # [ 2.637809] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 2.588189] systemd[1]: Reached target Initrd Root File System. alice # [ 2.593474] systemd[1]: Mounting /sysroot/nix/.ro-store... bob # [ 2.631325] EXT4-fs (vda): mounted filesystem f43137ba-975d-4769-a2ec-01d6c731ce9d r/w with ordered data mode. Quota mode: none. clare # [ 2.654891] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 2.603550] systemd[1]: Mounting /sysroot/nix/.rw-store... bob # [ 2.628832] systemd[1]: Mounted /sysroot. bob # [ 2.630422] systemd[1]: Reached target Initrd Root File System. bob # [ 2.634430] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.613673] systemd[1]: Mounting /sysroot/run... clare # [ 2.669594] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 2.631051] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 2.654198] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload. clare # [ 2.685508] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 2.687329] systemd[1]: Mounted /sysroot/run. bob # [ 2.658971] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)... bob # [ 2.662460] systemd[1]: Reloading... alice # [ 2.650029] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 2.702197] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 2.706266] systemd[1]: Mounted /sysroot/tmp/xchg. clare # [ 2.709900] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 2.666221] systemd[1]: Starting Mountpoints Configured in the Real Root... clare # [ 2.717844] systemd[1]: Reloading... alice # [ 2.668726] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 2.674273] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 2.693514] systemd[1]: Mounted /sysroot/run. alice # [ 2.710452] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 2.711095] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 2.711558] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 2.720258] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 2.728938] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 2.732147] systemd[1]: Reloading... bob # [ 2.856569] systemd[1]: Reloading finished in 195 ms. clare # [ 2.895664] systemd[1]: Reloading finished in 179 ms. bob # [ 2.885504] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace... bob # [ 2.886787] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace... bob # [ 2.895485] systemd[1]: Starting Load Kernel Module 9pnet_virtio... clare # [ 2.924731] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace... clare # [ 2.927319] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 2.930140] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 2.931803] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace... bob # [ 2.904905] systemd[1]: initrd-parse-etc.service: Deactivated successfully. bob # [ 2.908756] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 2.938456] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 2.909483] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. bob # [ 2.911258] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. clare # [ 2.941422] systemd[1]: initrd-parse-etc.service: Deactivated successfully. bob # [ 2.912741] systemd[1]: Finished Load Kernel Module 9pnet_virtio. clare # [ 2.942582] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 2.947044] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. clare # [ 2.958063] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 2.958976] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 2.911386] systemd[1]: Reloading finished in 180 ms. alice # [ 2.932602] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace... alice # [ 2.934120] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 2.936209] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 2.936800] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace... alice # [ 2.941026] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 2.947125] systemd[1]: initrd-parse-etc.service: Deactivated successfully. alice # [ 2.948680] systemd[1]: Finished Mountpoints Configured in the Real Root. alice # [ 2.951140] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.966122] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 2.967088] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.143004] (udev-worker)[92]: mtd0ro: Failed to find and pin callout binary "/nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/mtd_probe": No such file or directory clare # [ 3.146038] (udev-worker)[92]: mtd0ro: /nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/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.156721] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.158793] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.159365] systemd[1]: Stopping Virtual Console Setup... clare # [ 3.162010] systemd[1]: Starting Virtual Console Setup... clare # [ 3.170348] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.172332] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.174959] systemd[1]: Starting Virtual Console Setup... bob # [ 3.164939] (udev-worker)[95]: mtd0ro: Failed to find and pin callout binary "/nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/mtd_probe": No such file or directory clare # [ 3.195074] systemd-vconsole-setup[154]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 3.197253] systemd[1]: Finished Virtual Console Setup. bob # [ 3.168567] (udev-worker)[95]: mtd0ro: /nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/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.174778] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 3.175643] systemd[1]: Stopped Virtual Console Setup. bob # [ 3.177997] systemd[1]: Stopping Virtual Console Setup... alice # [ 3.155509] (udev-worker)[96]: mtd0ro: Failed to find and pin callout binary "/nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/mtd_probe": No such file or directory bob # [ 3.180157] systemd[1]: Starting Virtual Console Setup... alice # [ 3.159462] (udev-worker)[96]: mtd0ro: /nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/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.182394] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.184281] systemd[1]: Stopped Virtual Console Setup. bob # [ 3.207389] systemd-vconsole-setup[140]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 3.186154] systemd[1]: Stopping Virtual Console Setup... bob # [ 3.209992] systemd[1]: Finished Virtual Console Setup. alice # [ 3.188091] systemd[1]: Starting Virtual Console Setup... alice # [ 3.211144] systemd-vconsole-setup[153]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 3.213651] systemd[1]: Finished Virtual Console Setup. clare # [ 3.549600] systemd[1]: Mounting /sysroot/nix/store... clare # [ 3.599205] systemd[1]: Mounted /sysroot/nix/store. clare # [ 3.601490] systemd[1]: Reached target Initrd File Systems. clare # [ 3.605248] systemd[1]: Starting Find NixOS closure... alice # [ 3.560548] systemd[1]: Mounting /sysroot/nix/store... clare # [ 3.616927] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 3.600874] systemd[1]: Mounting /sysroot/nix/.ro-store... bob # [ 3.612293] systemd[1]: Mounting /sysroot/nix/.rw-store... alice # [ 3.592619] systemd[1]: Mounted /sysroot/nix/store. alice # [ 3.593971] systemd[1]: Reached target Initrd File Systems. bob # [ 3.615810] systemd[1]: Mounting /sysroot/run... alice # [ 3.596179] systemd[1]: Starting Find NixOS closure... clare # [ 3.654846] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. alice # [ 3.604173] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 3.627381] systemd[1]: Mounting /sysroot/tmp/shared... clare # [ 3.660376] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. clare # [ 3.675863] systemd[1]: Finished Find NixOS closure. clare # [ 3.678812] systemd[1]: Reached target Initrd Default Target. clare # [ 3.684440] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 3.633327] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 3.656444] systemd[1]: Mounting /sysroot/tmp/xchg... alice # [ 3.637743] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. alice # [ 3.651563] systemd[1]: Finished Find NixOS closure. alice # [ 3.653976] systemd[1]: Reached target Initrd Default Target. clare # [ 3.706305] systemd[1]: Stopped target Initrd Default Target. bob # [ 3.677337] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 3.656240] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... clare # [ 3.708280] systemd[1]: Stopped target Basic System. clare # [ 3.709372] systemd[1]: Stopped target Initrd Root Device. bob # [ 3.681572] systemd[1]: Mounted /sysroot/nix/.rw-store. clare # [ 3.712448] systemd[1]: Stopped target Path Units. clare # [ 3.712951] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. clare # [ 3.714111] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. clare # [ 3.715209] systemd[1]: Stopped target Slice Units. clare # [ 3.719243] systemd[1]: Stopped target Socket Units. clare # [ 3.719741] systemd[1]: Stopped target System Initialization. clare # [ 3.720472] systemd[1]: Stopped target Swaps. clare # [ 3.720898] systemd[1]: Stopped target Timer Units. clare # [ 3.721329] systemd[1]: dbus.socket: Deactivated successfully. clare # [ 3.721843] systemd[1]: Closed D-Bus System Message Bus Socket. clare # [ 3.722349] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. clare # [ 3.723012] systemd[1]: Stopped Find NixOS closure. clare # [ 3.723460] systemd[1]: Starting Load Kernel Module 9pnet_virtio... bob # [ 3.697799] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.679528] systemd[1]: initrd-cleanup.service: Deactivated successfully. bob # [ 3.702574] systemd[1]: Mounted /sysroot/run. alice # [ 3.681129] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. bob # [ 3.703985] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 3.683900] systemd[1]: Stopped target Initrd Default Target. clare # [ 3.734280] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.734940] systemd[1]: systemd-sysctl.service: Deactivated successfully. clare # [ 3.735528] systemd[1]: Stopped Apply Kernel Variables. clare # [ 3.735982] systemd[1]: systemd-modules-load.service: Deactivated successfully. alice # [ 3.687310] systemd[1]: Stopped target Basic System. bob # [ 3.709872] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 3.687972] systemd[1]: Stopped target Initrd Root Device. alice # [ 3.688845] systemd[1]: Stopped target Path Units. clare # [ 3.741067] systemd[1]: Stopped Load Kernel Modules. alice # [ 3.690056] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. clare # [ 3.743182] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 3.691945] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. clare # [ 3.744903] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 3.746417] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. alice # [ 3.696563] systemd[1]: Stopped target Slice Units. clare # [ 3.748346] systemd[1]: Stopped Create System Files and Directories. alice # [ 3.697241] systemd[1]: Stopped target Socket Units. alice # [ 3.697774] systemd[1]: Stopped target System Initialization. alice # [ 3.698388] systemd[1]: Stopped target Swaps. bob # [ 3.720377] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.698873] systemd[1]: Stopped target Timer Units. alice # [ 3.699403] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 3.721813] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.749324] systemd[1]: Stopped target Local File Systems. clare # [ 3.750457] systemd[1]: Stopped target Preparation for Local File Systems. clare # [ 3.751990] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. clare # [ 3.755451] systemd[1]: Stopped Coldplug All udev Devices. clare # [ 3.756085] systemd[1]: Stopping Rule-based Manager for Device Events and Files... clare # [ 3.757074] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.757745] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.758191] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. clare # [ 3.758819] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 3.707442] systemd[1]: Closed D-Bus System Message Bus Socket. clare # [ 3.759348] systemd[1]: systemd-udevd.service: Deactivated successfully. alice # [ 3.710954] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. alice # [ 3.711713] systemd[1]: Stopped Find NixOS closure. alice # [ 3.713604] systemd[1]: Starting Load Kernel Module 9pnet_virtio... alice # [ 3.714295] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.765649] systemd[1]: Stopped Rule-based Manager for Device Events and Files. clare # [ 3.766383] systemd[1]: initrd-cleanup.service: Deactivated successfully. alice # [ 3.715285] systemd[1]: systemd-sysctl.service: Deactivated successfully. clare # [ 3.766981] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. alice # [ 3.716657] systemd[1]: Stopped Apply Kernel Variables. alice # [ 3.717542] systemd[1]: systemd-modules-load.service: Deactivated successfully. alice # [ 3.718235] systemd[1]: Stopped Load Kernel Modules. alice # [ 3.718717] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. clare # [ 3.770517] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.719474] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 3.772191] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.773089] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 3.728226] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. clare # [ 3.776370] systemd[1]: Closed udev Control Socket. clare # [ 3.776999] systemd[1]: Starting Cleanup udev Database... clare # [ 3.777466] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. clare # [ 3.778072] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 3.729042] systemd[1]: Stopped Create System Files and Directories. clare # [ 3.780290] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. alice # [ 3.729696] systemd[1]: Stopped target Local File Systems. clare # [ 3.780988] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. alice # [ 3.730265] systemd[1]: Stopped target Preparation for Local File Systems. clare # [ 3.781685] systemd[1]: kmod-static-nodes.service: Deactivated successfully. alice # [ 3.730871] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. clare # [ 3.782366] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 3.731484] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 3.731946] systemd[1]: Stopping Rule-based Manager for Device Events and Files... alice # [ 3.740190] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.741732] systemd[1]: Stopped Virtual Console Setup. alice # [ 3.743100] systemd[1]: systemd-udevd.service: Deactivated successfully. alice # [ 3.743975] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 3.744703] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 3.745350] systemd[1]: Closed udev Control Socket. alice # [ 3.745803] systemd[1]: Starting Cleanup udev Database... alice # [ 3.746295] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. alice # [ 3.746961] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 3.747520] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. clare # [ 3.805165] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. clare # [ 3.806800] systemd[1]: Finished Cleanup udev Database. clare # [ 3.807325] systemd[1]: Reached target Switch Root. alice # [ 3.756171] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. alice # [ 3.756827] systemd[1]: kmod-static-nodes.service: Deactivated successfully. alice # [ 3.757681] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 3.758269] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. alice # [ 3.758900] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 3.759449] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.811912] systemd[1]: Starting NixOS Activation... alice # [ 3.764571] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 3.771007] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. alice # [ 3.771725] systemd[1]: Finished Cleanup udev Database. alice # [ 3.776776] systemd[1]: Reached target Switch Root. alice # [ 3.777267] systemd[1]: Starting NixOS Activation... clare # [ 3.998448] initrd-nixos-activation-start[179]: booting system configuration /nix/store/lpcdx8i5xciyq3d1r44g9d3hb3wfndlv-nixos-system-clare-test alice # [ 3.953994] initrd-nixos-activation-start[179]: booting system configuration /nix/store/27w2nnrs3k2hcqiqp5iagqb8c9cvnjnc-nixos-system-alice-test clare # [ 4.066900] initrd-nixos-activation-start[179]: running activation script... alice # [ 4.019475] initrd-nixos-activation-start[179]: running activation script... alice # [ 4.467403] initrd-nixos-activation-start[202]: setting up /etc... clare # [ 4.534819] initrd-nixos-activation-start[202]: setting up /etc... bob # [ 4.599252] systemd[1]: Mounting /sysroot/nix/store... bob # [ 4.646003] systemd[1]: Mounted /sysroot/nix/store. bob # [ 4.648622] systemd[1]: Reached target Initrd File Systems. bob # [ 4.652119] systemd[1]: Starting Find NixOS closure... bob # [ 4.660291] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 4.702439] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 4.707241] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. alice # [ 4.697982] initrd-nixos-activation-start[202]: setting up age secrets... bob # [ 4.724275] systemd[1]: Finished Find NixOS closure. bob # [ 4.726596] systemd[1]: Reached target Initrd Default Target. bob # [ 4.728203] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... clare # [ 4.780076] initrd-nixos-activation-start[202]: setting up age secrets... bob # [ 4.760496] systemd[1]: Stopped target Initrd Default Target. bob # [ 4.762123] systemd[1]: Stopped target Basic System. bob # [ 4.764178] systemd[1]: Stopped target Initrd Root Device. bob # [ 4.768389] systemd[1]: Stopped target Path Units. bob # [ 4.769162] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. bob # [ 4.770238] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. bob # [ 4.772514] systemd[1]: Stopped target Slice Units. bob # [ 4.776200] systemd[1]: Stopped target Socket Units. bob # [ 4.776952] systemd[1]: Stopped target System Initialization. bob # [ 4.777765] systemd[1]: Stopped target Swaps. bob # [ 4.778415] systemd[1]: Stopped target Timer Units. bob # [ 4.779152] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 4.779976] systemd[1]: Closed D-Bus System Message Bus Socket. bob # [ 4.786289] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 4.792897] systemd[1]: Stopped Find NixOS closure. bob # [ 4.794724] systemd[1]: Starting Load Kernel Module 9pnet_virtio... bob # [ 4.798114] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 4.800133] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 4.802634] systemd[1]: Stopped Apply Kernel Variables. bob # [ 4.803446] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 4.805981] systemd[1]: Stopped Load Kernel Modules. bob # [ 4.807743] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. bob # [ 4.810300] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. bob # [ 4.817643] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 4.818506] systemd[1]: Stopped Create System Files and Directories. bob # [ 4.820325] systemd[1]: Stopped target Local File Systems. bob # [ 4.827933] systemd[1]: Stopped target Preparation for Local File Systems. bob # [ 4.829066] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. bob # [ 4.829887] systemd[1]: Stopped Coldplug All udev Devices. bob # [ 4.830449] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 4.831451] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 4.837674] systemd[1]: Stopped Virtual Console Setup. bob # [ 4.838231] systemd[1]: systemd-udevd.service: Deactivated successfully. bob # [ 4.838849] systemd[1]: Stopped Rule-based Manager for Device Events and Files. bob # [ 4.839609] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. bob # [ 4.841530] systemd[1]: Closed udev Control Socket. bob # [ 4.843166] systemd[1]: Starting Cleanup udev Database... bob # [ 4.844128] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 4.845501] systemd[1]: Stopped Create Static Device Nodes in /dev. bob # [ 4.847877] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. bob # [ 4.848922] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. bob # [ 4.849915] systemd[1]: kmod-static-nodes.service: Deactivated successfully. bob # [ 4.850980] systemd[1]: Stopped Create List of Static Device Nodes. bob # [ 4.851574] systemd[1]: initrd-cleanup.service: Deactivated successfully. bob # [ 4.860187] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. bob # [ 4.860809] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. bob # [ 4.861683] systemd[1]: Finished Load Kernel Module 9pnet_virtio. bob # [ 4.862273] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 4.862936] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 4.874729] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 4.875473] systemd[1]: Finished Cleanup udev Database. bob # [ 4.875982] systemd[1]: Reached target Switch Root. alice # [ 4.854514] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. alice # [ 4.856130] systemd[1]: Finished NixOS Activation. alice # [ 4.857107] systemd[1]: Starting Switch Root... bob # [ 4.879908] systemd[1]: Starting NixOS Activation... clare # [ 4.919371] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. clare # [ 4.921991] systemd[1]: Finished NixOS Activation. clare # [ 4.924160] systemd[1]: Starting Switch Root... alice # [ 4.873022] systemd[1]: Switching root. clare # [ 4.936965] systemd[1]: Switching root. bob # [ 5.011986] initrd-nixos-activation-start[177]: booting system configuration /nix/store/qxdv7pdjrqaakcbi512las48qjrf5msl-nixos-system-bob-test bob # [ 5.062229] initrd-nixos-activation-start[177]: running activation script... alice # [ 5.050438] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). clare # [ 5.113921] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). bob # [ 5.463565] initrd-nixos-activation-start[200]: setting up /etc... clare # [ 5.665111] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) clare # [ 5.672057] systemd[1]: Detected virtualization qemu. clare # [ 5.673861] systemd[1]: Detected architecture arm64. clare # [ 5.676081] systemd[1]: Detected first boot. clare # [ 5.681523] systemd[1]: Initializing machine ID from random generator. alice # [ 5.650735] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) alice # [ 5.657599] systemd[1]: Detected virtualization qemu. alice # [ 5.659349] systemd[1]: Detected architecture arm64. alice # [ 5.661649] systemd[1]: Detected first boot. alice # [ 5.667464] systemd[1]: Initializing machine ID from random generator. bob # [ 5.719513] initrd-nixos-activation-start[200]: setting up age secrets... bob # [ 5.905820] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 5.907613] systemd[1]: Finished NixOS Activation. bob # [ 5.908468] systemd[1]: Starting Switch Root... clare # [ 5.958572] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 5.925214] systemd[1]: Switching root. alice # [ 5.969746] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 6.084179] systemd[1]: Applying preset policy. bob # [ 6.102951] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). alice # [ 6.113397] systemd[1]: Applying preset policy. clare # [ 6.501095] systemd[1]: Populated /etc with preset unit settings. alice # [ 6.609178] systemd[1]: Populated /etc with preset unit settings. bob # [ 6.661612] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 6.665597] systemd[1]: Detected virtualization qemu. bob # [ 6.666655] systemd[1]: Detected architecture arm64. bob # [ 6.668055] systemd[1]: Detected first boot. bob # [ 6.671564] systemd[1]: Initializing machine ID from random generator. bob # [ 6.936229] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 7.009869] systemd[1]: initrd-switch-root.service: Deactivated successfully. clare # [ 7.010757] systemd[1]: Stopped initrd-switch-root.service. clare # [ 7.012725] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. clare # [ 7.015692] systemd[1]: Created slice Slice /system/getty. clare # [ 7.017311] systemd[1]: Created slice User and Session Slice. clare # [ 7.017440] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 7.017515] systemd[1]: Started Forward Password Requests to Wall Directory Watch. clare # [ 7.017947] systemd[1]: Expecting device /dev/hvc0... clare # [ 7.018223] systemd[1]: Expecting device /dev/ttyAMA0... clare # [ 7.018497] systemd[1]: Reached target Local Encrypted Volumes. clare # [ 7.018770] systemd[1]: Stopped target initrd-fs.target. clare # [ 7.019029] systemd[1]: Stopped target initrd-root-fs.target. clare # [ 7.019288] systemd[1]: Stopped target initrd-switch-root.target. clare # [ 7.019565] systemd[1]: Reached target Virtual Machines and Containers. clare # [ 7.019839] systemd[1]: Reached target Path Units. clare # [ 7.020095] systemd[1]: Reached target Remote File Systems. clare # [ 7.020348] systemd[1]: Reached target Slice Units. clare # [ 7.020604] systemd[1]: Reached target Swaps. clare # [ 7.030175] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 7.034250] systemd[1]: Listening on Process Core Dump Socket. clare # [ 7.037640] systemd[1]: Listening on Credential Encryption/Decryption. clare # [ 7.040951] systemd[1]: Listening on Factory Reset Management. clare # [ 7.042031] systemd[1]: Listening on Hostname Service Socket. clare # [ 7.047214] systemd[1]: Starting Journal Log Access Socket... clare # [ 7.049579] systemd[1]: Listening on Journal Audit Socket. clare # [ 7.054538] systemd[1]: Listening on Console Output Muting Service Socket. clare # [ 7.056360] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 7.058352] systemd[1]: Listening on Network Management Varlink Socket. clare # [ 7.060142] systemd[1]: Listening on Network Management Netlink Socket. clare # [ 7.062443] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. clare # [ 7.064104] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os clare # [ 7.065945] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki clare # [ 7.076884] systemd[1]: Listening on Disk Repartitioning Service Socket. clare # [ 7.078608] systemd[1]: Listening on Resolve Monitor Varlink Socket. clare # [ 7.080164] systemd[1]: Listening on Resolve Service Varlink Socket. clare # [ 7.081804] systemd[1]: Listening on udev Control Socket. clare # [ 7.083234] systemd[1]: Listening on udev Varlink Socket. clare # [ 7.087045] systemd[1]: Mounting Huge Pages File System... clare # [ 7.090937] systemd[1]: Mounting POSIX Message Queue File System... clare # [ 7.100687] systemd[1]: Mounting Kernel Debug File System... clare # [ 7.105392] systemd[1]: Mounting Kernel Trace File System... clare # [ 7.117437] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 7.128647] systemd[1]: Starting Load Kernel Module 9pnet_virtio... alice # [ 7.078395] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 7.079256] systemd[1]: Stopped initrd-switch-root.service. bob # [ 7.100732] systemd[1]: Applying preset policy. clare # [ 7.131137] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 7.081436] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. alice # [ 7.084294] systemd[1]: Created slice Slice /system/getty. alice # [ 7.085474] systemd[1]: Created slice User and Session Slice. alice # [ 7.086699] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 7.088042] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 7.089418] systemd[1]: Expecting device /dev/hvc0... alice # [ 7.090526] systemd[1]: Expecting device /dev/ttyAMA0... alice # [ 7.091590] systemd[1]: Reached target Local Encrypted Volumes. clare # [ 7.142791] systemd[1]: Mounting Kernel Configuration File System... alice # [ 7.092703] systemd[1]: Stopped target initrd-fs.target. alice # [ 7.093250] systemd[1]: Stopped target initrd-root-fs.target. alice # [ 7.094331] systemd[1]: Stopped target initrd-switch-root.target. clare # [ 7.145015] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 7.095375] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 7.096385] systemd[1]: Reached target Path Units. clare # [ 7.147651] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore alice # [ 7.097901] systemd[1]: Reached target Remote File Systems. alice # [ 7.099344] systemd[1]: Reached target Slice Units. alice # [ 7.100764] systemd[1]: Reached target Swaps. alice # [ 7.104696] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 7.158049] systemd[1]: Starting Load Kernel Module fuse... alice # [ 7.109279] systemd[1]: Listening on Process Core Dump Socket. clare # [ 7.160002] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 alice # [ 7.112940] systemd[1]: Listening on Credential Encryption/Decryption. alice # [ 7.116399] systemd[1]: Listening on Factory Reset Management. alice # [ 7.117442] systemd[1]: Listening on Hostname Service Socket. alice # [ 7.122164] systemd[1]: Starting Journal Log Access Socket... alice # [ 7.123712] systemd[1]: Listening on Journal Audit Socket. alice # [ 7.127219] systemd[1]: Listening on Console Output Muting Service Socket. alice # [ 7.127750] systemd[1]: Listening on Network Management Metrics Varlink Socket. alice # [ 7.128200] systemd[1]: Listening on Network Management Varlink Socket. alice # [ 7.128598] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 7.129097] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. alice # [ 7.132369] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 7.133720] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki alice # [ 7.144280] systemd[1]: Listening on Disk Repartitioning Service Socket. alice # [ 7.145958] systemd[1]: Listening on Resolve Monitor Varlink Socket. alice # [ 7.147486] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 7.149026] systemd[1]: Listening on udev Control Socket. alice # [ 7.150601] systemd[1]: Listening on udev Varlink Socket. alice # [ 7.154515] systemd[1]: Mounting Huge Pages File System... alice # [ 7.158443] systemd[1]: Mounting POSIX Message Queue File System... clare # [ 7.213713] systemd[1]: Starting Journal Service... alice # [ 7.163957] systemd[1]: Mounting Kernel Debug File System... alice # [ 7.171467] systemd[1]: Mounting Kernel Trace File System... clare # [ 7.234667] systemd[1]: Starting Load Kernel Modules... alice # [ 7.184529] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 7.196030] systemd[1]: Starting Load Kernel Module 9pnet_virtio... alice # [ 7.197906] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 7.258108] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... alice # [ 7.208616] systemd[1]: Mounting Kernel Configuration File System... alice # [ 7.212518] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm clare # [ 7.264313] fuse: init (API version 7.45) alice # [ 7.215185] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 7.280430] systemd[1]: Starting Remount Root and Kernel File Systems... alice # [ 7.228625] systemd[1]: Starting Load Kernel Module fuse... alice # [ 7.230672] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 clare # [ 7.282956] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 7.311935] systemd[1]: Starting Coldplug All udev Devices... clare # [ 7.330143] systemd[1]: Listening on Journal Log Access Socket. clare # [ 7.332836] systemd[1]: Mounted Huge Pages File System. clare # [ 7.337665] systemd-journald[288]: Collecting audit messages is enabled. clare # [ 7.331949] systemd[1]: Queued start job for default target Multi-User System. clare # [ 7.343389] systemd[1]: Mounted POSIX Message Queue File System. clare # [ 7.344777] systemd[1]: Started Journal Service. alice # [ 7.302616] systemd[1]: Starting Journal Service... clare # [ 7.346539] systemd[1]: systemd-journald.service: Deactivated successfully. clare # [ 7.351337] systemd-modules-load[289]: Module 'atkbd' is built in alice # [ 7.314442] systemd[1]: Starting Load Kernel Modules... clare # [ 7.360213] systemd-modules-load[289]: Module 'loop' is built in clare # [ 7.361743] systemd[1]: Mounted Kernel Debug File System. clare # [ 7.364392] systemd[1]: Mounted Kernel Trace File System. clare # [ 7.370200] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 7.374385] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. clare # [ 7.380771] systemd[1]: Finished Load Kernel Module 9pnet_virtio. clare # [ 7.384307] systemd[1]: Mounted Kernel Configuration File System. clare # [ 7.385028] systemd[1]: modprobe@fuse.service: Deactivated successfully. clare # [ 7.386661] systemd[1]: Finished Load Kernel Module fuse. clare # [ 7.388557] systemd[1]: Finished Load Kernel Modules. alice # [ 7.348839] fuse: init (API version 7.45) clare # [ 7.401157] EXT4-fs (vda): re-mounted dfed0fd1-e6d8-49c4-bec4-ad693342b33c. alice # [ 7.354323] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 7.397846] systemd[1]: Mounting FUSE Control File System... clare # [ 7.398424] systemd[1]: Starting Firewall... clare # [ 7.398838] systemd[1]: Starting Apply Kernel Variables... clare # [ 7.412068] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 7.373321] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 7.418263] systemd[1]: Finished Remount Root and Kernel File Systems. alice # [ 7.375471] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 7.383364] systemd[1]: Starting Coldplug All udev Devices... alice # [ 7.388796] systemd[1]: Listening on Journal Log Access Socket. clare # [ 7.442719] systemd-oomd[290]: No swap; memory pressure usage will be degraded alice # [ 7.401995] systemd[1]: Mounted Huge Pages File System. alice # [ 7.410524] systemd[1]: Mounted POSIX Message Queue File System. alice # [ 7.411021] systemd[1]: Mounted Kernel Debug File System. alice # [ 7.411414] systemd[1]: Mounted Kernel Trace File System. alice # [ 7.421416] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 7.467116] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. alice # [ 7.425283] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. alice # [ 7.429546] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 7.432327] systemd[1]: Mounted Kernel Configuration File System. alice # [ 7.432905] systemd[1]: modprobe@fuse.service: Deactivated successfully. alice # [ 7.438688] systemd[1]: Finished Load Kernel Module fuse. clare # [ 7.481397] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 7.440544] systemd[1]: Finished Load Kernel Modules. clare # [ 7.487594] systemd[1]: Starting Flush Journal to Persistent Storage... alice # [ 7.445663] systemd[1]: Mounting FUSE Control File System... clare # [ 7.492203] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore alice # [ 7.451545] systemd[1]: Starting Firewall... clare # [ 7.500482] systemd[1]: Starting Load/Save OS Random Seed... clare # [ 7.506604] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 7.476787] systemd-journald[288]: Collecting audit messages is enabled. alice # [ 7.481966] systemd[1]: Starting Apply Kernel Variables... clare # [ 7.533557] systemd[1]: Mounted FUSE Control File System. alice # [ 7.485310] systemd[1]: Queued start job for default target Multi-User System. alice # [ 7.486987] systemd[1]: systemd-journald.service: Deactivated successfully. clare # [ 7.538917] systemd[1]: Finished Apply Kernel Variables. alice # [ 7.490416] systemd-modules-load[289]: Module 'atkbd' is built in alice # [ 7.501281] EXT4-fs (vda): re-mounted c3105474-4727-4398-a7b0-79d3f73c17bd. alice # [ 7.494669] systemd-modules-load[289]: Module 'loop' is built in alice # [ 7.510837] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 7.505440] systemd-oomd[290]: No swap; memory pressure usage will be degraded clare # [ 7.565236] systemd[1]: Starting Network Name Resolution... alice # [ 7.525883] systemd[1]: Started Journal Service. alice # [ 7.518779] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. clare # [ 7.572871] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 7.555667] systemd[1]: Populated /etc with preset unit settings. clare # [ 7.589742] systemd-journald[288]: Received client request to flush runtime journal. alice # [ 7.533953] systemd[1]: Finished Remount Root and Kernel File Systems. alice # [ 7.582129] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 7.592142] systemd[1]: Starting Flush Journal to Persistent Storage... alice # [ 7.593210] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore alice # [ 7.596626] systemd[1]: Starting Load/Save OS Random Seed... alice # [ 7.597288] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 7.613101] systemd[1]: Mounted FUSE Control File System. alice # [ 7.643218] systemd[1]: Finished Apply Kernel Variables. alice # [ 7.650489] systemd[1]: Starting Network Name Resolution... clare # [ 7.707934] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 7.710360] systemd[1]: Finished Load/Save OS Random Seed. clare # [ 7.711001] systemd[1]: Reached target First Boot Complete. clare # [ 7.713141] systemd[1]: Finished Flush Journal to Persistent Storage. clare # [ 7.729531] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 7.730672] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 7.738277] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 7.692266] systemd[1]: Finished Load/Save OS Random Seed. clare # [ 7.746300] systemd-resolved[317]: Positive Trust Anchors: alice # [ 7.696154] systemd[1]: Reached target First Boot Complete. alice # [ 7.707683] systemd-journald[288]: Received client request to flush runtime journal. clare # [ 7.751803] systemd-resolved[317]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d clare # [ 7.754626] systemd-resolved[317]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 clare # [ 7.758449] 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 alice # [ 7.761246] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 7.772590] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 7.773202] systemd[1]: Finished Flush Journal to Persistent Storage. clare # [ 7.873180] systemd-udevd[330]: Using default interface naming scheme 'v261'. alice # [ 7.835655] systemd-resolved[317]: Positive Trust Anchors: alice # [ 7.836934] systemd-resolved[317]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d alice # [ 7.843130] systemd-resolved[317]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 alice # [ 7.848726] 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 alice # [ 7.878283] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 7.879030] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 7.880528] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 7.993252] systemd-resolved[317]: Using system hostname 'clare'. clare # [ 8.006037] systemd[1]: Mounting /run/wrappers... clare # [ 8.007682] systemd[1]: Started Network Name Resolution. alice # [ 7.969136] systemd-udevd[331]: Using default interface naming scheme 'v261'. clare # [ 8.053980] systemd[1]: Mounted /run/wrappers. clare # [ 8.054626] systemd[1]: Reached target Local File Systems. clare # [ 8.058467] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 8.069198] systemd[1]: Starting register-nix-paths.service... clare # [ 8.072987] systemd[1]: Starting Create SUID/SGID Wrappers... clare # [ 8.073729] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. clare # [ 8.110507] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 8.090733] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 8.092245] systemd[1]: Stopped initrd-switch-root.service. bob # [ 8.094196] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 8.097083] systemd[1]: Created slice Slice /system/getty. bob # [ 8.097549] systemd[1]: Created slice User and Session Slice. bob # [ 8.097672] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 8.097751] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 8.098167] systemd[1]: Expecting device /dev/hvc0... bob # [ 8.098452] systemd[1]: Expecting device /dev/ttyAMA0... bob # [ 8.098762] systemd[1]: Reached target Local Encrypted Volumes. bob # [ 8.099050] systemd[1]: Stopped target initrd-fs.target. bob # [ 8.099322] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 8.099595] systemd[1]: Stopped target initrd-switch-root.target. bob # [ 8.104670] systemd[1]: Reached target Virtual Machines and Containers. bob # [ 8.106045] systemd[1]: Reached target Path Units. alice # [ 8.077195] systemd[1]: Mounting /run/wrappers... bob # [ 8.107258] systemd[1]: Reached target Remote File Systems. bob # [ 8.108751] systemd[1]: Reached target Slice Units. bob # [ 8.109936] systemd[1]: Reached target Swaps. bob # [ 8.113427] systemd[1]: Listening on Query the User Interactively for a Password. bob # [ 8.117444] systemd[1]: Listening on Process Core Dump Socket. bob # [ 8.120509] systemd[1]: Listening on Credential Encryption/Decryption. clare # [ 8.144940] systemd[1]: Starting Create System Files and Directories... bob # [ 8.123543] systemd[1]: Listening on Factory Reset Management. bob # [ 8.124650] systemd[1]: Listening on Hostname Service Socket. bob # [ 8.129070] systemd[1]: Starting Journal Log Access Socket... bob # [ 8.131031] systemd[1]: Listening on Journal Audit Socket. clare # [ 8.147792] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 8.135006] systemd[1]: Listening on Console Output Muting Service Socket. bob # [ 8.136205] systemd[1]: Listening on Network Management Metrics Varlink Socket. bob # [ 8.137730] systemd[1]: Listening on Network Management Varlink Socket. alice # [ 8.109828] systemd-resolved[317]: Using system hostname 'alice'. bob # [ 8.139273] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 8.140875] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 8.141921] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os bob # [ 8.143138] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki alice # [ 8.123035] systemd[1]: Started Network Name Resolution. bob # [ 8.153038] systemd[1]: Listening on Disk Repartitioning Service Socket. bob # [ 8.154695] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 8.156353] systemd[1]: Listening on Resolve Service Varlink Socket. bob # [ 8.157396] systemd[1]: Listening on udev Control Socket. bob # [ 8.158382] systemd[1]: Listening on udev Varlink Socket. bob # [ 8.162255] systemd[1]: Mounting Huge Pages File System... bob # [ 8.166197] systemd[1]: Mounting POSIX Message Queue File System... bob # [ 8.173286] systemd[1]: Mounting Kernel Debug File System... alice # [ 8.149472] systemd[1]: Mounted /run/wrappers. alice # [ 8.150857] systemd[1]: Reached target Local File Systems. bob # [ 8.180757] systemd[1]: Mounting Kernel Trace File System... alice # [ 8.156602] systemd[1]: Listening on Boot Loader Control Service Socket. alice # [ 8.164191] systemd[1]: Starting register-nix-paths.service... bob # [ 8.193934] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 8.201194] systemd[1]: Starting Load Kernel Module 9pnet_virtio... alice # [ 8.167716] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 8.172544] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. bob # [ 8.202322] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 8.175555] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 8.214679] systemd[1]: Mounting Kernel Configuration File System... bob # [ 8.216141] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm bob # [ 8.219154] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 8.248606] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. bob # [ 8.232258] systemd[1]: Starting Load Kernel Module fuse... bob # [ 8.233476] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 clare # [ 8.264994] systemd[1]: Finished Save Transient machine-id to Disk. alice # [ 8.215170] systemd[1]: Starting Create System Files and Directories... alice # [ 8.224415] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 8.304162] systemd[1]: Finished Create System Files and Directories. clare # [ 8.318749] systemd[1]: Starting Rebuild Journal Catalog... clare # [ 8.323256] systemd[1]: Starting Record System Boot/Shutdown in UTMP... bob # [ 8.303353] systemd[1]: Starting Journal Service... alice # [ 8.291304] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. alice # [ 8.297087] systemd[1]: Finished Save Transient machine-id to Disk. bob # [ 8.332006] systemd[1]: Starting Load Kernel Modules... bob # [ 8.350041] fuse: init (API version 7.45) bob # [ 8.358428] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... bob # [ 8.375259] systemd[1]: Starting Remount Root and Kernel File Systems... bob # [ 8.377174] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 8.402171] systemd[1]: Starting Coldplug All udev Devices... bob # [ 8.415533] systemd[1]: Listening on Journal Log Access Socket. clare # [ 8.441156] systemd[1]: Finished Record System Boot/Shutdown in UTMP. bob # [ 8.424454] systemd[1]: Mounted Huge Pages File System. bob # [ 8.426082] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 8.433816] systemd[1]: Mounted Kernel Debug File System. bob # [ 8.438024] systemd[1]: Mounted Kernel Trace File System. bob # [ 8.449815] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 8.423331] systemd[1]: Finished Create System Files and Directories. bob # [ 8.453082] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. bob # [ 8.456807] systemd-journald[294]: Collecting audit messages is enabled. bob # [ 8.458325] systemd[1]: Finished Load Kernel Module 9pnet_virtio. bob # [ 8.463940] EXT4-fs (vda): re-mounted f43137ba-975d-4769-a2ec-01d6c731ce9d. bob # [ 8.466828] systemd[1]: Mounted Kernel Configuration File System. bob # [ 8.468660] systemd[1]: modprobe@fuse.service: Deactivated successfully. alice # [ 8.439595] systemd[1]: Starting Rebuild Journal Catalog... clare # [ 8.493367] systemd[1]: Finished Coldplug All udev Devices. bob # [ 8.472276] systemd[1]: Finished Load Kernel Module fuse. bob # [ 8.476221] systemd[1]: Finished Load Kernel Modules. alice # [ 8.450353] systemd[1]: Starting Record System Boot/Shutdown in UTMP... bob # [ 8.480050] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 8.473751] systemd[1]: Queued start job for default target Multi-User System. bob # [ 8.476417] systemd[1]: systemd-journald.service: Deactivated successfully. bob # [ 8.477191] systemd-modules-load[295]: Module 'atkbd' is built in bob # [ 8.477820] systemd-modules-load[295]: Module 'loop' is built in bob # [ 8.487065] systemd[1]: Started Journal Service. bob # [ 8.496183] systemd[1]: Listening on Disk Image Download Service Socket. clare # [ 8.526964] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 8.501863] systemd[1]: Mounting FUSE Control File System... clare # [ 8.532072] systemd[1]: Starting Update is Completed... bob # [ 8.509457] systemd[1]: Starting Firewall... bob # [ 8.514208] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 8.515918] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 8.526309] systemd-oomd[296]: No swap; memory pressure usage will be degraded bob # [ 8.542581] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 8.561737] systemd[1]: Starting Apply Kernel Variables... clare # [ 8.609536] systemd[1]: Finished Update is Completed. bob # [ 8.592390] systemd-journald[294]: Received client request to flush runtime journal. alice # [ 8.587576] systemd[1]: Finished Record System Boot/Shutdown in UTMP. alice # [ 8.623617] systemd[1]: Finished Coldplug All udev Devices. clare # [ 8.697028] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 8.665916] systemd[1]: Finished Rebuild Journal Catalog. alice # [ 8.669535] systemd[1]: Starting Update is Completed... bob # [ 8.698896] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 8.704870] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 8.711818] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 8.716932] systemd[1]: Mounted FUSE Control File System. bob # [ 8.722004] systemd[1]: Finished Load/Save OS Random Seed. bob # [ 8.722539] systemd[1]: Reached target First Boot Complete. bob # [ 8.723069] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 8.723639] systemd[1]: Finished Apply Kernel Variables. bob # [ 8.727147] systemd[1]: Starting Network Name Resolution... alice # [ 8.733236] systemd[1]: Finished Update is Completed. bob # [ 8.763261] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 8.770373] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 8.810478] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 8.874649] systemd-resolved[329]: Positive Trust Anchors: bob # [ 8.876468] systemd-resolved[329]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d bob # [ 8.881399] systemd-resolved[329]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 bob # [ 8.885507] systemd-resolved[329]: 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 bob # [ 8.915015] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 8.916103] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 8.921069] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 8.972748] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. clare # [ 8.992277] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. clare # [ 8.996129] systemd[1]: Finished Create SUID/SGID Wrappers. clare # [ 9.022327] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. bob # [ 9.017947] systemd-udevd[339]: Using default interface naming scheme 'v261'. bob # [ 9.091233] systemd[1]: Mounting /run/wrappers... alice # [ 9.092384] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. bob # [ 9.146373] systemd-resolved[329]: Using system hostname 'bob'. alice # [ 9.131703] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. alice # [ 9.134893] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 9.157522] systemd[1]: Started Network Name Resolution. bob # [ 9.160478] systemd[1]: Mounted /run/wrappers. bob # [ 9.162218] systemd[1]: Reached target Local File Systems. bob # [ 9.167426] systemd[1]: Listening on Boot Loader Control Service Socket. bob # [ 9.175100] systemd[1]: Starting register-nix-paths.service... alice # [ 9.154901] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. bob # [ 9.177888] systemd[1]: Starting Create SUID/SGID Wrappers... bob # [ 9.178479] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. bob # [ 9.188765] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 9.225209] systemd[1]: Starting Create System Files and Directories... clare # [ 9.325374] systemd[1]: Finished register-nix-paths.service. clare # [ 9.327602] systemd[1]: Reached target System Initialization. clare # [ 9.330161] systemd[1]: Started Discard unused filesystem blocks once a week. clare # [ 9.333899] systemd[1]: Started Daily Cleanup of Temporary Directories. clare # [ 9.335435] systemd[1]: Reached target Timer Units. clare # [ 9.337779] (udev-worker)[370]: Network interface NamePolicy= disabled on kernel command line. bob # [ 9.309855] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. clare # [ 9.339760] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 9.344653] (udev-worker)[365]: Network interface NamePolicy= disabled on kernel command line. clare # [ 9.346519] systemd[1]: Listening on harmonia binary cache socket. clare # [ 9.350325] systemd[1]: Listening on Nix Daemon Socket. bob # [ 9.321253] systemd[1]: Finished Save Transient machine-id to Disk. clare # [ 9.354078] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. clare # [ 9.356567] systemd[1]: Reached target Socket Units. clare # [ 9.361157] systemd[1]: Reached target Basic System. clare # [ 9.361744] systemd[1]: Started backdoor.service. clare # [ 9.362201] systemd[1]: Starting Import lastlog data into lastlog2 database... clare # [ 9.370988] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 9.345942] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 9.383426] systemd[1]: Starting Post-Boot Actions... clare # [ 9.395924] systemd[1]: Started Reset console on configuration changes. bob # [ 9.382602] systemd[1]: Finished Create System Files and Directories. clare # [ 9.412104] systemd[1]: Finished Firewall. clare # [ 9.428216] systemd[1]: Reached target Preparation for Network. bob # [ 9.399305] systemd[1]: Starting Rebuild Journal Catalog... clare # [ 9.431489] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # [ 9.413007] systemd[1]: Starting Record System Boot/Shutdown in UTMP... clare # [ 9.449137] systemd[1]: Starting D-Bus System Message Bus... alice # [ 9.419512] (udev-worker)[367]: Network interface NamePolicy= disabled on kernel command line. clare # [ 9.480191] systemd[1]: Starting Network Management... alice # [ 9.435547] (udev-worker)[366]: Network interface NamePolicy= disabled on kernel command line. alice # [ 9.458660] systemd[1]: Finished register-nix-paths.service. alice # [ 9.461144] systemd[1]: Reached target System Initialization. clare # [ 9.511004] systemd[1]: Finished Post-Boot Actions. alice # [ 9.465501] systemd[1]: Started Discard unused filesystem blocks once a week. alice # [ 9.466505] systemd[1]: Started Daily Cleanup of Temporary Directories. clare # [ 9.515858] systemd[1]: Started Name Service Cache Daemon (nsncd). clare # connecting to host... alice # [ 9.470169] systemd[1]: Reached target Timer Units. clare # [ 9.521436] nsncd[514]: Aug 29 20:08:57.330 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" alice # [ 9.472636] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 9.476493] systemd[1]: Listening on Nix Daemon Socket. alice # [ 9.480293] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 9.482102] systemd[1]: Reached target Socket Units. clare # [ 9.535146] systemd[1]: Reached target Host and Network Name Lookups. clare # [ 9.536806] systemd[1]: Reached target User and Group Name Lookups. alice # [ 9.488109] systemd[1]: Reached target Basic System. alice # [ 9.488676] systemd[1]: Started backdoor.service. alice # [ 9.489157] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 9.489791] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 9.526377] systemd[1]: Finished Record System Boot/Shutdown in UTMP. alice # [ 9.513404] systemd[1]: Starting Post-Boot Actions... clare # [ 9.569555] systemd[1]: Starting User Login Management... alice # [ 9.531045] systemd[1]: Started Reset console on configuration changes. alice # [ 9.535454] systemd[1]: Finished Firewall. clare # [ 9.624023] mousedev: PS/2 mouse device common for all mice alice # [ 9.565430] systemd[1]: Reached target Preparation for Network. clare # [ 9.617550] systemd[1]: Finished Import lastlog data into lastlog2 database. alice # [ 9.573974] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # [ 9.620608] systemd[1]: Finished Rebuild Journal Catalog. alice # [ 9.604668] systemd[1]: Starting D-Bus System Message Bus... bob # [ 9.640744] systemd[1]: Starting Update is Completed... alice # [ 9.628236] systemd[1]: Starting Network Management... alice # [ 9.645345] systemd[1]: Finished Post-Boot Actions. clare # [ 9.706937] dbus-broker-launch[519]: Looking up NSS user entry for 'systemd-timesync'... clare # [ 9.715420] dbus-broker-launch[519]: NSS returned no entry for 'systemd-timesync' clare # [ 9.720428] dbus-broker-launch[519]: Invalid user-name in /nix/store/cbcb9dk4199xmzl6mba843fhymwv3rrg-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # connecting to host... alice # [ 9.688574] systemd[1]: Finished Import lastlog data into lastlog2 database. clare # [ 9.748699] systemd-logind[539]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) clare # [ 9.754560] systemd-logind[539]: Watching system buttons on /dev/input/event0 (gpio-keys) clare # [ 9.755327] systemd-logind[539]: New seat seat0. clare # [ 9.755754] systemd[1]: Started User Login Management. alice # [ 9.709401] nsncd[511]: Aug 29 20:08:57.501 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" alice # [ 9.710770] systemd[1]: Started Name Service Cache Daemon (nsncd). clare # [ 9.762045] systemd[1]: Starting linger-users.service... bob # [ 9.741883] systemd[1]: Finished Update is Completed. alice # [ 9.721437] systemd[1]: Reached target Host and Network Name Lookups. alice # [ 9.722098] systemd[1]: Reached target User and Group Name Lookups. clare # [ 9.779219] systemd[1]: Started D-Bus System Message Bus. alice # [ 9.738565] systemd[1]: Starting User Login Management... bob # [ 9.775464] systemd[1]: Finished Coldplug All udev Devices. clare # [ 9.841013] dbus-broker-launch[519]: Ready alice # [ 9.805543] mousedev: PS/2 mouse device common for all mice clare # [ 9.853653] systemd[1]: linger-users.service: Deactivated successfully. clare # [ 9.854295] systemd[1]: Finished linger-users.service. clare # [ 9.892911] cfg80211: Loading compiled-in X.509 certificates for regulatory database clare # [ 9.930768] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' clare # [ 9.931314] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' clare # [ 9.936475] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 clare # [ 9.936864] cfg80211: failed to load regulatory.db alice # [ 9.882367] dbus-broker-launch[518]: Looking up NSS user entry for 'systemd-timesync'... alice # [ 9.902032] systemd-logind[538]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) alice # [ 9.905574] systemd-logind[538]: Watching system buttons on /dev/input/event0 (gpio-keys) alice # [ 9.911135] systemd-logind[538]: New seat seat0. bob # [ 9.933192] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 9.911663] systemd[1]: Started User Login Management. alice # [ 9.917964] dbus-broker-launch[518]: NSS returned no entry for 'systemd-timesync' alice # [ 9.925485] dbus-broker-launch[518]: Invalid user-name in /nix/store/fx7clk11h89p78dzlr5303sfnqrd56pn-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 9.928089] systemd[1]: Starting linger-users.service... clare # [ 9.989905] systemd-networkd[522]: lo: Link UP clare # [ 9.990483] systemd-networkd[522]: lo: Gained carrier clare # [ 9.998919] systemd[1]: Started Network Management. clare # [ 10.001395] systemd[1]: Reached target Network. clare # [ 10.007929] systemd-networkd[522]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. clare # [ 10.013483] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 10.014182] systemd-networkd[522]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 10.015640] systemd-networkd[522]: eth1: Configuring with /etc/systemd/network/40-eth1.network. clare # [ 10.024279] systemd-networkd[522]: eth0: Link UP clare # [ 10.029697] systemd-networkd[522]: eth0: Gained carrier clare # [ 10.030245] systemd[1]: Starting Permit User Sessions... clare # [ 10.030772] systemd-networkd[522]: eth1: Link UP alice # [ 9.988508] cfg80211: Loading compiled-in X.509 certificates for regulatory database clare # [ 10.036926] systemd-networkd[522]: eth1: Gained carrier clare # [ 10.037486] systemd-networkd[522]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 9.994826] systemd[1]: Started D-Bus System Message Bus. clare # [ 10.051115] systemd-networkd[522]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 alice # [ 10.028551] systemd[1]: linger-users.service: Deactivated successfully. alice # [ 10.031483] systemd[1]: Finished linger-users.service. alice # [ 10.046043] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' alice # [ 10.046579] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' alice # [ 10.050971] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 alice # [ 10.051362] cfg80211: failed to load regulatory.db clare # [ 10.096581] systemd[1]: Finished Permit User Sessions. alice # [ 10.051808] dbus-broker-launch[518]: Ready clare # [ 10.104221] systemd[1]: Started Getty on tty1. clare # [ 10.106640] systemd[1]: Reached target Login Prompts. clare # [ 10.108576] systemd[1]: Reached target Multi-User System. clare # [ 10.112617] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 10.115501] systemd[1]: Startup finished in 734ms (kernel) + 4.451s (initrd) + 4.929s (userspace) = 10.115s. alice # [ 10.106593] systemd-networkd[521]: lo: Link UP alice # [ 10.107111] systemd-networkd[521]: lo: Gained carrier alice # [ 10.114427] systemd-networkd[521]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 10.115843] systemd-networkd[521]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 10.121722] systemd[1]: Started Network Management. alice # [ 10.125809] systemd[1]: Reached target Network. alice # [ 10.126258] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... alice # [ 10.131876] systemd-networkd[521]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. alice # [ 10.140603] systemd-networkd[521]: eth1: Link UP alice # [ 10.141072] systemd-networkd[521]: eth1: Gained carrier alice # [ 10.141526] systemd-networkd[521]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 10.142767] systemd[1]: Starting Permit User Sessions... alice # [ 10.143238] systemd-networkd[521]: eth0: Link UP alice # [ 10.143645] systemd-networkd[521]: eth0: Gained carrier alice # [ 10.152551] systemd-networkd[521]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 10.182713] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. bob # [ 10.184411] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 10.207319] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. alice # [ 10.204819] systemd[1]: Finished Permit User Sessions. alice # [ 10.209914] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. alice # [ 10.216590] systemd[1]: Started Getty on tty1. alice # [ 10.218840] systemd[1]: Reached target Login Prompts. alice # [ 10.219349] systemd[1]: Reached target Multi-User System. alice # [ 10.224913] systemd[1]: Startup finished in 751ms (kernel) + 4.393s (initrd) + 5.075s (userspace) = 10.219s. bob # [ 10.264263] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. clare # [ 10.474062] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 bob # [ 10.481618] systemd[1]: Finished register-nix-paths.service. bob # [ 10.483617] systemd[1]: Reached target System Initialization. bob # [ 10.488468] systemd[1]: Started Discard unused filesystem blocks once a week. bob # [ 10.490326] systemd[1]: Started Daily Cleanup of Temporary Directories. bob # [ 10.491643] systemd[1]: Reached target Timer Units. bob # [ 10.495294] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 10.499787] systemd[1]: Listening on Nix Daemon Socket. bob # [ 10.501807] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. bob # [ 10.504412] systemd[1]: Reached target Socket Units. bob # [ 10.507051] systemd[1]: Reached target Basic System. bob # [ 10.509329] systemd[1]: Started backdoor.service. bob # [ 10.511245] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 10.514665] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 10.527016] systemd[1]: Starting Post-Boot Actions... bob # [ 10.546856] systemd[1]: Started Reset console on configuration changes. bob # [ 10.598780] systemd[1]: Starting D-Bus System Message Bus... bob # [ 10.625977] systemd[1]: Finished Firewall. bob # [ 10.629424] systemd[1]: Finished Post-Boot Actions. alice # [ 10.626881] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 bob # connecting to host... bob # [ 10.645224] (udev-worker)[380]: Network interface NamePolicy= disabled on kernel command line. bob # [ 10.652401] (udev-worker)[389]: Network interface NamePolicy= disabled on kernel command line. bob # [ 10.655092] systemd[1]: Reached target Preparation for Network. bob # [ 10.661986] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # [ 10.667365] systemd[1]: Starting Network Management... bob: Guest shell says: b'Spawning backdoor root shell...\n' clare # [ 10.754396] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 10.736630] systemd[1]: Started Name Service Cache Daemon (nsncd). bob # [ 10.740136] systemd[1]: Reached target Host and Network Name Lookups. bob # [ 10.745202] nsncd[512]: Aug 29 20:08:58.541 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" bob # [ 10.746392] systemd[1]: Reached target User and Group Name Lookups. bob # [ 10.755061] systemd[1]: Starting User Login Management... bob: connected to guest root shell clare # [ 10.777759] systemd-logind[539]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob: (connecting took 11.06 seconds) clare # [ 10.784546] systemd[1]: Starting Virtual Console Setup... bob: (finished: waiting for the VM to finish booting, in 11.06 seconds) bob # [ 10.796182] systemd[1]: Finished Import lastlog data into lastlog2 database. bob # [ 10.851998] dbus-broker-launch[522]: Looking up NSS user entry for 'systemd-timesync'... alice # [ 10.882275] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 10.918311] dbus-broker-launch[522]: NSS returned no entry for 'systemd-timesync' bob # [ 10.922050] dbus-broker-launch[522]: Invalid user-name in /nix/store/g2ws3wg0a2nig0hwf8gxia0qwazn1hhv-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" bob # [ 10.947195] systemd-logind[546]: Watching system buttons on /dev/input/event0 (gpio-keys) bob # [ 10.949553] systemd-logind[546]: New seat seat0. alice # [ 10.934112] systemd[1]: Starting Virtual Console Setup... bob # [ 10.956306] systemd[1]: Started User Login Management. alice # [ 10.941244] systemd-logind[538]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 10.963800] systemd[1]: Starting linger-users.service... bob # [ 10.976064] systemd[1]: Started D-Bus System Message Bus. bob # [ 11.045933] dbus-broker-launch[522]: Ready bob # [ 11.056224] mousedev: PS/2 mouse device common for all mice bob # [ 11.060650] systemd[1]: linger-users.service: Deactivated successfully. bob # [ 11.062529] systemd[1]: Finished linger-users.service. bob # [ 11.071138] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 11.089455] systemd-logind[546]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 11.105529] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' bob # [ 11.106054] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 11.109897] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 bob # [ 11.110280] cfg80211: failed to load regulatory.db clare # [ 11.168500] systemd-networkd[522]: eth1: Gained IPv6LL bob # [ 11.149143] systemd-networkd[528]: lo: Link UP bob # [ 11.149762] systemd-networkd[528]: lo: Gained carrier clare # [ 11.184812] systemd-vconsole-setup[568]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 11.157224] systemd-networkd[528]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 11.187362] systemd[1]: Finished Virtual Console Setup. bob # [ 11.158595] systemd-networkd[528]: eth1: Configuring with /etc/systemd/network/40-eth1.network. bob # [ 11.159339] systemd[1]: Started Network Management. bob # [ 11.169389] systemd[1]: Reached target Network. bob # [ 11.171894] systemd[1]: Reached target Network is Online. bob # [ 11.173495] systemd-networkd[528]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. bob # [ 11.174326] systemd-networkd[528]: eth1: Link UP bob # [ 11.174749] systemd-networkd[528]: eth0: Link UP bob # [ 11.175157] systemd[1]: Starting ncps binary cache proxy service... bob # [ 11.175685] systemd-networkd[528]: eth1: Gained carrier bob # [ 11.181982] systemd-networkd[528]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 11.183308] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... bob # [ 11.189923] systemd-networkd[528]: eth0: Gained carrier bob # [ 11.190420] systemd[1]: Starting Permit User Sessions... bob # [ 11.200539] systemd-networkd[528]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 11.271594] systemd[1]: Finished Permit User Sessions. bob # [ 11.279113] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. bob # [ 11.283950] systemd[1]: Started Getty on tty1. bob # [ 11.284580] systemd[1]: Reached target Login Prompts. alice # [ 11.271231] systemd-vconsole-setup[565]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 11.274045] systemd[1]: Finished Virtual Console Setup. bob # [ 11.329422] ncps-pre-start[572]: /nix/store/dgy2kbj0kb9qfkxqfwksgn92fw05yn0v-ncps-0.9.4/bin/dbmate-ncps up alice # [ 11.521980] systemd-networkd[521]: eth1: Gained IPv6LL clare # [ 11.585203] systemd-networkd[522]: eth0: Gained IPv6LL bob # [ 11.721730] ncps-pre-start[586]: Creating: /var/lib/ncps/db/db.sqlite bob # [ 11.766512] ncps-pre-start[586]: Applying: 20241210054814_create-narinfos-table.sql bob # [ 11.773525] ncps-pre-start[586]: Applied: 20241210054814_create-narinfos-table.sql in 7.00388ms bob # [ 11.774688] ncps-pre-start[586]: Applying: 20241210054829_create-nars-table.sql bob # [ 11.779595] ncps-pre-start[586]: Applied: 20241210054829_create-nars-table.sql in 6.04228ms bob # [ 11.781266] ncps-pre-start[586]: Applying: 20241213014846_add-query-to-nars-table.sql bob # [ 11.787215] ncps-pre-start[586]: Applied: 20241213014846_add-query-to-nars-table.sql in 7.59652ms bob # [ 11.789488] ncps-pre-start[586]: Applying: 20251230224159_add-cascade-to-nars-fk.sql alice # [ 11.777421] systemd-networkd[521]: eth0: Gained IPv6LL bob # [ 11.799906] ncps-pre-start[586]: Applied: 20251230224159_add-cascade-to-nars-fk.sql in 12.69404ms bob # [ 11.801635] ncps-pre-start[586]: Applying: 20260101000000_add-config-table.sql bob # [ 11.810813] ncps-pre-start[586]: Applied: 20260101000000_add-config-table.sql in 10.87712ms bob # [ 11.812595] ncps-pre-start[586]: Applying: 20260105025735_remove-redundant-indexes.sql bob # [ 11.821782] ncps-pre-start[586]: Applied: 20260105025735_remove-redundant-indexes.sql in 10.96404ms bob # [ 11.823523] ncps-pre-start[586]: Applying: 20260105030513_support_shared_nars_between_narinfos.sql bob # [ 11.836226] ncps-pre-start[586]: Applied: 20260105030513_support_shared_nars_between_narinfos.sql in 14.40968ms bob # [ 11.838383] ncps-pre-start[586]: Applying: 20260117195000_add_narinfo_de_normalized.sql bob # [ 11.849785] ncps-pre-start[586]: Applied: 20260117195000_add_narinfo_de_normalized.sql in 13.5538ms bob # [ 11.851669] ncps-pre-start[586]: Applying: 20260127223000_allow_multiple_nar_representations.sql bob # [ 11.859861] ncps-pre-start[586]: Applied: 20260127223000_allow_multiple_nar_representations.sql in 10.1174ms bob # [ 11.861373] ncps-pre-start[586]: Applying: 20260131021850_add_chunks.sql bob # [ 11.869120] ncps-pre-start[586]: Applied: 20260131021850_add_chunks.sql in 7.6778ms bob # [ 11.870939] ncps-pre-start[586]: Applying: 20260205063651_add_total_chunks_to_nar_files.sql bob # [ 11.876786] ncps-pre-start[586]: Applied: 20260205063651_add_total_chunks_to_nar_files.sql in 7.8782ms bob # [ 11.878608] ncps-pre-start[586]: Applying: 20260217071237_add_chunking_started_at_to_nar_files.sql bob # [ 11.884674] ncps-pre-start[586]: Applied: 20260217071237_add_chunking_started_at_to_nar_files.sql in 7.87568ms bob # [ 11.886481] ncps-pre-start[586]: Applying: 20260301000000_add_verified_at_to_nar_files.sql bob # [ 11.893417] ncps-pre-start[586]: Applied: 20260301000000_add_verified_at_to_nar_files.sql in 8.27768ms bob # [ 11.990038] systemd[1]: Started ncps binary cache proxy service. bob # [ 11.992338] systemd[1]: Reached target Multi-User System. bob # [ 11.995516] systemd[1]: Startup finished in 746ms (kernel) + 5.421s (initrd) + 5.825s (userspace) = 11.993s. bob # [ 12.021503] 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.39 seconds) bob: must succeed: systemctl status ncps bob: (finished: must succeed: systemctl status ncps, in 0.04 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) bob # [ 12.194001] systemd-networkd[528]: eth0: Gained IPv6LL 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) alice # curl: (7) Failed to connect to bob:8502 after 39 ms: Could not connect to server bob # [ 12.520316] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 12.561491] systemd[1]: Starting Virtual Console Setup... bob # [ 12.590256] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 12.592368] systemd[1]: Stopped Virtual Console Setup. bob # [ 12.605079] systemd[1]: Starting Virtual Console Setup... bob # [ 12.625723] systemd-logind[546]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 12.640260] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","log_level":"info","time":"2026-08-29T20:09:00Z","message":"logger created"} bob # [ 12.641369] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","xz-binary-path":"/nix/store/fklavd7qagzs74a6jp2c6rcj7zj2hfa2-xz-5.8.3-bin/bin/xz","time":"2026-08-29T20:09:00Z","message":"Using xz binary for xz decompression"} bob # [ 12.651939] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-08-29T20:09:00Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"} bob # [ 12.670399] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","time":"2026-08-29T20:09:00Z","message":"using local locks (single-instance mode)"} bob # [ 12.680308] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-08-29T20:09:00Z","message":"setting up tracer provider to discard traces"} bob # [ 12.686429] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-08-29T20:09:00Z","message":"setting up meter provider to discard metrics"} bob # [ 12.691295] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-08-29T20:09:00Z","message":"setting up logger provider to discard logs"} bob # [ 12.700539] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-08-29T20:09:00Z","message":"Reporting anonymous metrics to the project maintainers"} bob # [ 12.705232] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-08-29T20:09:00Z","message":"failed to parse netrc file, proceeding without netrc authentication"} bob # [ 12.708562] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-08-29T20:09:00Z","message":"using local storage"} clare # [ 12.798745] systemd[1]: Starting harmonia binary cache service... bob # [ 12.801224] systemd-networkd[528]: eth1: Gained IPv6LL clare # [ 12.975036] harmonia-cache[575]: 2026-08-29T20:09:00.788579Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000)) clare # [ 12.978290] harmonia-cache[575]: 2026-08-29T20:09:00.791346Z INFO actix_server::builder: starting 4 workers clare # [ 12.979451] systemd[1]: Started harmonia binary cache service. clare # [ 12.980369] harmonia-cache[575]: 2026-08-29T20:09:00.794922Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s clare # [ 12.983089] harmonia-cache[575]: 2026-08-29T20:09:00.797539Z INFO actix_server::server: Actix runtime found; starting in Actix runtime clare # [ 12.985042] harmonia-cache[575]: 2026-08-29T20:09:00.797565Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:5000 bob # [ 12.970524] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-08-29T20:09:00Z","message":"upstream became healthy and is now available for requests"} bob # [ 12.972199] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-08-29T20:09:00Z","message":"Server started"} bob # [ 13.105646] systemd-vconsole-setup[622]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 13.107984] systemd[1]: Finished Virtual Console Setup. alice # % Total % Received % Xferd Average Speed Time Time Time Current alice # Dload Upload Total Spent Left Speed alice # 0 0 0 0 0 0 0 0 0100 50 100 50 0 0 4498 0 0100 50 100 50 0 0 3836 0 0100 50 100 50 0 0 3383 0 0 bob # [ 13.449236] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:57982","trace_id":"41253a2de9ebe94fb2ea6317f248b21d","span_id":"d1ab7da1ba92d633","status":200,"elapsed":0.06932,"bytes":50,"time":"2026-08-29T20:09:01Z","message":"handled request"} alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 1.24 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 3182 0 0100 51 100 51 0 0 2676 0 0100 51 100 51 0 0 2297 0 0 bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.13 seconds) clare: waiting for unit harmonia bob # [ 13.710456] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: 2026/08/29 20:09:01 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.923649] systemd-resolved[317]: Using degraded feature set UDP instead of UDP+EDNS0 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 5.02 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.924709] systemd-resolved[317]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. bob # [ 18.942204] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:57990","trace_id":"6ba1441d639905cfc33296d5c049014e","span_id":"822d9767b2ea6f5f","status":200,"elapsed":0.0304,"bytes":50,"time":"2026-08-29T20:09:06Z","message":"handled request"} bob # [ 23.515438] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:57990","trace_id":"a144162247dbb4173296ca6d77de7434","span_id":"af862392b2444a46","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-29T20:09:11Z","message":"download the narinfo from upstream"} bob # [ 23.561417] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:57990","trace_id":"a144162247dbb4173296ca6d77de7434","span_id":"af862392b2444a46","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-08-29T20:09:11Z","message":"downloading the nar from upstream"} bob # [ 23.572528] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:57990","trace_id":"a144162247dbb4173296ca6d77de7434","span_id":"af862392b2444a46","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-29T20:09:11Z","message":"download the nar from upstream"} bob # [ 23.601812] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:57990","trace_id":"a144162247dbb4173296ca6d77de7434","span_id":"af862392b2444a46","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":39.31172,"time":"2026-08-29T20:09:11Z","message":"download of nar complete"} bob # [ 23.620225] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:57990","trace_id":"a144162247dbb4173296ca6d77de7434","span_id":"af862392b2444a46","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-08-29T20:09:11Z","message":"storing narinfo and nar_file record in the database"} bob # [ 23.629160] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:57990","trace_id":"a144162247dbb4173296ca6d77de7434","span_id":"af862392b2444a46","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":114.22952,"time":"2026-08-29T20:09:11Z","message":"download of narinfo complete"} bob # [ 23.637683] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:57990","trace_id":"a144162247dbb4173296ca6d77de7434","span_id":"af862392b2444a46","status":200,"elapsed":125.14084,"bytes":532,"time":"2026-08-29T20:09:11Z","message":"handled request"} bob # [ 23.645880] z79zizd8xibvxpwcnnd1i299ywk3qwvz-ncps-wrapper[591]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:57990","trace_id":"31c3a6d2ed3a9aa97c1608cc82f6fcc6","span_id":"323b322025230f70","status":200,"elapsed":0.6054,"bytes":95,"time":"2026-08-29T20:09:11Z","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.89 seconds) (finished: run the VM test script, in 24.50 seconds) test script finished in 24.69s cleanup kill QemuMachine (pid 49) alice # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14) kill QemuMachine (pid 48) bob # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14) kill QemuMachine (pid 50) clare # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14) (finished: cleanup, in 0.36 seconds)