build01: building 98yfqlwgw99s63ggi28829p4gab0apg7-vm-test-run-ncps.drv Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script additionally exposed symbols: alice, bob, clare, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh start all VMs alice: starting vm bob: starting vm clare: starting vm alice # Disk image does not exist, creating the virtualisation disk image... alice # Formatting '/build/vm-state-alice/tmp.mIb8Iv20xF', fmt=raw size=1073741824 alice # mke2fs 1.47.4 (6-Mar-2025) alice # Discarding device blocks: 0/262144 done alice # Creating filesystem with 262144 4k blocks and 65536 inodes alice # Filesystem UUID: 85c4d184-e71d-4416-a635-a78665477d19 alice # Superblock backups stored on blocks: alice: QEMU running (pid 48) alice # 32768, 98304, 163840, 229376 bob: QEMU running (pid 49) bob # Disk image does not exist, creating the virtualisation disk image... clare: QEMU running (pid 50) clare # Disk image does not exist, creating the virtualisation disk image... alice # clare # Formatting '/build/vm-state-clare/tmp.ed8bzjD2jk', fmt=raw size=1073741824 bob # Formatting '/build/vm-state-bob/tmp.yZi62EqhRa', fmt=raw size=1073741824 alice # Allocating group tables: 0/8 done (finished: start all VMs, in 0.57 seconds) clare # mke2fs 1.47.4 (6-Mar-2025) bob # mke2fs 1.47.4 (6-Mar-2025) alice # Writing inode tables: 0/8 done clare # Discarding device blocks: 0/262144 done bob: waiting for unit ncps alice # Creating journal (8192 blocks): done clare # Creating filesystem with 262144 4k blocks and 65536 inodes bob: waiting for the VM to finish booting alice # Writing superblocks and filesystem accounting information: 0/8 done bob # Discarding device blocks: 0/262144 done alice # bob # Creating filesystem with 262144 4k blocks and 65536 inodes alice # Virtualisation disk image created. bob # Filesystem UUID: 69bd0452-41c9-4514-a862-d6c3778b8fed alice # Starting virtiofs daemons... bob # Superblock backups stored on blocks: alice # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # 32768, 98304, 163840, 229376 alice # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # alice # [2026-09-27T17:41:37Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # Allocating group tables: 0/8 done alice # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # Writing inode tables: 0/8 done alice # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # Creating journal (8192 blocks): done alice # [2026-09-27T17:41:37Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # Writing superblocks and filesystem accounting information: 0/8 done alice # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # alice # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # Virtualisation disk image created. alice # [2026-09-27T17:41:37Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # Starting virtiofs daemons... alice # [2026-09-27T17:41:37Z INFO virtiofsd] Client connected, servicing requests bob # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-09-27T17:41:37Z INFO virtiofsd] Client connected, servicing requests bob # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-09-27T17:41:37Z INFO virtiofsd] Client connected, servicing requests bob # [2026-09-27T17:41:37Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # Filesystem UUID: 07b9ffc2-93c7-4939-8bcd-5eb6885e5e34 bob # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # Superblock backups stored on blocks: bob # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # 32768, 98304, 163840, 229376 bob # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # bob # [2026-09-27T17:41:37Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # Allocating group tables: 0/8 done bob # [2026-09-27T17:41:37Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # Writing inode tables: 0/8 done bob # [2026-09-27T17:41:37Z INFO virtiofsd] Client connected, servicing requests clare # Creating journal (8192 blocks): done bob # [2026-09-27T17:41:37Z INFO virtiofsd] Client connected, servicing requests clare # Writing superblocks and filesystem accounting information: 0/8 done bob # [2026-09-27T17:41:37Z INFO virtiofsd] Client connected, servicing requests clare # clare # Virtualisation disk image created. clare # Starting virtiofs daemons... clare # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-09-27T17:41:37Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-09-27T17:41:37Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-09-27T17:41:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-09-27T17:41:37Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-09-27T17:41:37Z INFO virtiofsd] Client connected, servicing requests clare # [2026-09-27T17:41:37Z INFO virtiofsd] Client connected, servicing requests clare # [2026-09-27T17:41:37Z INFO virtiofsd] Client connected, servicing requests alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] alice # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 2026 alice # [ 0.000000] KASLR enabled alice # [ 0.000000] random: crng init done alice # [ 0.000000] Machine model: linux,dummy-virt alice # [ 0.000000] efi: UEFI not found. alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] alice # [ 0.000000] Zone ranges: alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] DMA32 empty alice # [ 0.000000] Normal empty alice # [ 0.000000] Device empty alice # [ 0.000000] Movable zone start for each node alice # [ 0.000000] Early memory node ranges alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000000] psci: probing for conduit method from DT. alice # [ 0.000000] psci: PSCIv1.3 detected in firmware. alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs alice # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] psci: SMC Calling Convention v1.1 alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 alice # [ 0.000000] Detected PIPT I-cache on CPU0 alice # [ 0.000000] CPU features: detected: GICv3 CPU interface alice # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.000000] CPU features: detected: Spectre-BHB alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) alice # [ 0.000000] CPU features: detected: ARM erratum 1418040 alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing alice # [ 0.000000] alternatives: applying boot alternatives alice # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/5mrv0g4znr0sd45nr4sd8pdaz46s4fbl-nixos-system-alice-test/init regInfo=/nix/.ro-store/3wfslljc2qaaka5hfgp8x5r6s2fkm6ni-closure-info/registration console=ttyAMA0,115200n8 console=tty0 alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/3wfslljc2qaaka5hfgp8x5r6s2fkm6ni-closure-info/registration", will be passed to user space. alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB alice # [ 0.000000] software IO TLB: area num 1. alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) alice # [ 0.000000] Fallback order for Node 0: 0 alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 alice # [ 0.000000] Policy zone: DMA alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.000000] allocated 2097152 bytes of page_ext alice # [ 0.000000] ftrace: allocating 74963 entries in 294 pages alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups alice # [ 0.000000] rcu: Hierarchical RCU implementation. alice # [ 0.000000] rcu: RCU event tracing is enabled. alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. alice # [ 0.000000] Trampoline variant of Tasks RCU enabled. alice # [ 0.000000] Rude variant of Tasks RCU enabled. alice # [ 0.000000] Tracing variant of Tasks RCU enabled. alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 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] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 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] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 alice # [ 0.000000] GICv3: 256 SPIs implemented alice # [ 0.000000] GICv3: 0 Extended SPIs implemented alice # [ 0.000000] Root IRQ handler: gic_handle_irq alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) bob # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 2026 alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 bob # [ 0.000000] KASLR enabled clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] bob # [ 0.000000] random: crng init done alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000000] Machine model: linux,dummy-virt bob # [ 0.000000] efi: UEFI not found. alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. clare # [ 0.000000] Linux version 6.18.53 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 21 13:06:06 UTC 2026 bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT clare # [ 0.000000] KASLR enabled alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns clare # [ 0.000000] random: crng init done bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] Machine model: linux,dummy-virt alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 clare # [ 0.000000] efi: UEFI not found. bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] bob # [ 0.000000] Zone ranges: alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] DMA32 empty clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] Normal empty alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns bob # [ 0.000000] Device empty clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] clare # [ 0.000000] Zone ranges: bob # [ 0.000000] Movable zone start for each node alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.000000] Early memory node ranges alice # [ 0.000063] arm-pv: using stolen time PV clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] DMA32 empty bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] Normal empty clare # [ 0.000000] Device empty alice # [ 0.000371] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] Movable zone start for each node alice # [ 0.000664] Console: colour dummy device 80x25 clare # [ 0.000000] Early memory node ranges bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000683] printk: legacy console [tty0] enabled bob # [ 0.000000] psci: probing for conduit method from DT. clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] psci: PSCIv1.3 detected in firmware. clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000921] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs alice # [ 0.000926] pid_max: default: 32768 minimum: 301 clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 bob # [ 0.000000] psci: Trusted OS migration not required clare # [ 0.000000] psci: probing for conduit method from DT. alice # [ 0.001005] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.000000] psci: SMC Calling Convention v1.1 alice # [ 0.001137] landlock: Up and running. clare # [ 0.000000] psci: PSCIv1.3 detected in firmware. alice # [ 0.001140] Yama: becoming mindful. bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.001519] LSM support for eBPF active clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs clare # [ 0.000000] psci: Trusted OS migration not required bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 alice # [ 0.001690] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.000000] psci: SMC Calling Convention v1.1 bob # [ 0.000000] Detected PIPT I-cache on CPU0 bob # [ 0.000000] CPU features: detected: GICv3 CPU interface alice # [ 0.001709] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) bob # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.004484] rcu: Hierarchical SRCU implementation. clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 alice # [ 0.004490] rcu: Max phase no-delay instances is 1000. bob # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.000000] Detected PIPT I-cache on CPU0 bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR alice # [ 0.005408] fsl-mc MSI: its@8080000 domain created clare # [ 0.000000] CPU features: detected: GICv3 CPU interface alice # [ 0.005607] EFI services will not be available. bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.005693] smp: Bringing up secondary CPUs ... clare # [ 0.000000] CPU features: detected: Spectre-BHB bob # [ 0.000000] CPU features: detected: ARM erratum 1418040 alice # [ 0.005722] smp: Brought up 1 node, 1 CPU alice # [ 0.005724] SMP: Total of 1 processors activated. clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion alice # [ 0.005726] CPU: All CPU(s) started at EL1 bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) alice # [ 0.005738] CPU features: detected: 32-bit EL0 Support bob # [ 0.000000] alternatives: applying boot alternatives clare # [ 0.000000] CPU features: detected: ARM erratum 1418040 alice # [ 0.005741] CPU features: detected: Data cache clean to the PoU not required for I/D coherence clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion alice # [ 0.005744] CPU features: detected: Common not Private translations clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing alice # [ 0.005746] CPU features: detected: CRC32 instructions clare # [ 0.000000] alternatives: applying boot alternatives alice # [ 0.005748] CPU features: detected: Data cache clean to Point of Persistence alice # [ 0.005751] CPU features: detected: Enhanced Virtualization Traps 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/3x6i2g70yfi9bj03k2sr6girqyjnhfzm-nixos-system-bob-test/init regInfo=/nix/.ro-store/wdzzndsbqzfnh63fzrqqbllnakgdxmn3-closure-info/registration console=ttyAMA0,115200n8 console=tty0 alice # [ 0.005762] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.005764] CPU features: detected: LSE atomic instructions alice # [ 0.005766] CPU features: detected: Privileged Access Never bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/wdzzndsbqzfnh63fzrqqbllnakgdxmn3-closure-info/registration", will be passed to user space. 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/3rm7jwhi0dzl73bwshl17pwph88z8lgv-nixos-system-clare-test/init regInfo=/nix/.ro-store/459v1m8p3vmylm66g7rpqd68ysf5fgf5-closure-info/registration console=ttyAMA0,115200n8 console=tty0 alice # [ 0.005768] CPU features: detected: PMUv3 alice # [ 0.005770] CPU features: detected: RAS Extension Support bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.005775] CPU features: detected: Speculative Store Bypassing Safe (SSBS) bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/459v1m8p3vmylm66g7rpqd68ysf5fgf5-closure-info/registration", will be passed to user space. alice # [ 0.005796] alternatives: applying system-wide alternatives bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.007180] CPU features: detected: Hardware dirty bit management on CPU0 bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB bob # [ 0.000000] software IO TLB: area num 1. clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) alice # [ 0.007351] Memory: 891316K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 115948K reserved, 32768K cma-reserved) bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.007839] devtmpfs: initialized bob # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB alice # [ 0.009372] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 clare # [ 0.000000] software IO TLB: area num 1. bob # [ 0.000000] Policy zone: DMA alice # [ 0.009394] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off clare # [ 0.000000] Fallback order for Node 0: 0 alice # [ 0.009507] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.009510] 0 pages in range for non-PLT usage clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 clare # [ 0.000000] Policy zone: DMA bob # [ 0.000000] allocated 2097152 bytes of page_ext alice # [ 0.009511] 508224 pages in range for PLT usage bob # [ 0.000000] ftrace: allocating 74963 entries in 294 pages alice # [ 0.009614] pinctrl core: initialized pinctrl subsystem clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.010423] DMI not present or invalid. bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 bob # [ 0.000000] rcu: Hierarchical RCU implementation. alice # [ 0.013652] NET: Registered PF_NETLINK/PF_ROUTE protocol family clare # [ 0.000000] allocated 2097152 bytes of page_ext bob # [ 0.000000] rcu: RCU event tracing is enabled. alice # [ 0.016089] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations clare # [ 0.000000] ftrace: allocating 74963 entries in 294 pages bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups alice # [ 0.016244] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.000000] rcu: Hierarchical RCU implementation. bob # [ 0.000000] Rude variant of Tasks RCU enabled. alice # [ 0.016400] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.000000] rcu: RCU event tracing is enabled. bob # [ 0.000000] Tracing variant of Tasks RCU enabled. alice # [ 0.016416] audit: initializing netlink subsys (disabled) clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. alice # [ 0.017008] thermal_sys: Registered thermal governor 'fair_share' clare # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.000000] Rude variant of Tasks RCU enabled. alice # [ 0.017010] thermal_sys: Registered thermal governor 'bang_bang' bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 clare # [ 0.000000] Tracing variant of Tasks RCU enabled. alice # [ 0.017013] thermal_sys: Registered thermal governor 'step_wise' bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. alice # [ 0.017015] thermal_sys: Registered thermal governor 'user_space' 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.017016] thermal_sys: Registered thermal governor 'power_allocator' clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 0.017052] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 bob # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.017067] cpuidle: using governor ladder bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 alice # [ 0.017072] cpuidle: using governor menu bob # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.017197] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. bob # [ 0.000000] GICv3: 0 Extended SPIs implemented bob # [ 0.000000] Root IRQ handler: gic_handle_irq clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.017218] ASID allocator initialised with 32768 entries alice # [ 0.017860] Serial: AMBA PL011 UART driver bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.000000] GICv3: 256 SPIs implemented bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 alice # [ 0.021408] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 clare # [ 0.000000] GICv3: 0 Extended SPIs implemented bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 alice # [ 0.021555] printk: console [ttyAMA0] enabled clare # [ 0.000000] Root IRQ handler: gic_handle_irq bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). bob # [ 0.000044] arm-pv: using stolen time PV clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns bob # [ 0.000361] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.000603] Console: colour dummy device 80x25 clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.000619] printk: legacy console [tty0] enabled clare # [ 0.000050] arm-pv: using stolen time PV bob # [ 0.000848] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) clare # [ 0.000378] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.000853] pid_max: default: 32768 minimum: 301 clare # [ 0.000670] Console: colour dummy device 80x25 clare # [ 0.000691] printk: legacy console [tty0] enabled bob # [ 0.000927] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.001050] landlock: Up and running. bob # [ 0.001052] Yama: becoming mindful. clare # [ 0.000924] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) bob # [ 0.001393] LSM support for eBPF active clare # [ 0.000929] pid_max: default: 32768 minimum: 301 bob # [ 0.001561] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.001015] LSM: initializing lsm=capability,landlock,yama,bpf,ima clare # [ 0.001141] landlock: Up and running. bob # [ 0.001584] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.001143] Yama: becoming mindful. clare # [ 0.001525] LSM support for eBPF active bob # [ 0.004205] rcu: Hierarchical SRCU implementation. bob # [ 0.004210] rcu: Max phase no-delay instances is 1000. clare # [ 0.001698] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.005170] fsl-mc MSI: its@8080000 domain created alice # [ 0.096031] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.005348] EFI services will not be available. clare # [ 0.001721] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.096044] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 0.005431] smp: Bringing up secondary CPUs ... clare # [ 0.004231] rcu: Hierarchical SRCU implementation. bob # [ 0.005457] smp: Brought up 1 node, 1 CPU alice # [ 0.096046] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages clare # [ 0.004237] rcu: Max phase no-delay instances is 1000. bob # [ 0.005460] SMP: Total of 1 processors activated. clare # [ 0.005137] fsl-mc MSI: its@8080000 domain created alice # [ 0.096051] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page bob # [ 0.005461] CPU: All CPU(s) started at EL1 clare # [ 0.005320] EFI services will not be available. alice # [ 0.096053] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 0.005473] CPU features: detected: 32-bit EL0 Support clare # [ 0.005399] smp: Bringing up secondary CPUs ... alice # [ 0.096055] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page clare # [ 0.005424] smp: Brought up 1 node, 1 CPU bob # [ 0.005476] CPU features: detected: Data cache clean to the PoU not required for I/D coherence clare # [ 0.005426] SMP: Total of 1 processors activated. alice # [ 0.096057] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages clare # [ 0.005428] CPU: All CPU(s) started at EL1 bob # [ 0.005479] CPU features: detected: Common not Private translations alice # [ 0.096059] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page clare # [ 0.005439] CPU features: detected: 32-bit EL0 Support bob # [ 0.005481] CPU features: detected: CRC32 instructions alice # [ 0.097017] fbcon: Taking over console alice # [ 0.097032] ACPI: Interpreter disabled. bob # [ 0.005483] CPU features: detected: Data cache clean to Point of Persistence clare # [ 0.005441] CPU features: detected: Data cache clean to the PoU not required for I/D coherence alice # [ 0.097683] iommu: Default domain type: Translated bob # [ 0.005485] CPU features: detected: Enhanced Virtualization Traps clare # [ 0.005444] CPU features: detected: Common not Private translations alice # [ 0.097687] iommu: DMA domain TLB invalidation policy: strict mode bob # [ 0.005496] CPU features: detected: RCpc load-acquire (LDAPR) clare # [ 0.005447] CPU features: detected: CRC32 instructions alice # [ 0.097980] SCSI subsystem initialized bob # [ 0.005498] CPU features: detected: LSE atomic instructions alice # [ 0.098184] usbcore: registered new interface driver usbfs clare # [ 0.005449] CPU features: detected: Data cache clean to Point of Persistence bob # [ 0.005500] CPU features: detected: Privileged Access Never alice # [ 0.098217] usbcore: registered new interface driver hub clare # [ 0.005451] CPU features: detected: Enhanced Virtualization Traps bob # [ 0.005502] CPU features: detected: PMUv3 alice # [ 0.098227] usbcore: registered new device driver usb clare # [ 0.005461] CPU features: detected: RCpc load-acquire (LDAPR) bob # [ 0.005504] CPU features: detected: RAS Extension Support alice # [ 0.098442] pps_core: LinuxPPS API ver. 1 registered clare # [ 0.005463] CPU features: detected: LSE atomic instructions bob # [ 0.005508] CPU features: detected: Speculative Store Bypassing Safe (SSBS) clare # [ 0.005465] CPU features: detected: Privileged Access Never alice # [ 0.098445] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti bob # [ 0.005529] alternatives: applying system-wide alternatives clare # [ 0.005467] CPU features: detected: PMUv3 alice # [ 0.098449] PTP clock support registered alice # [ 0.098480] EDAC MC: Ver: 3.0.0 clare # [ 0.005469] CPU features: detected: RAS Extension Support clare # [ 0.005474] CPU features: detected: Speculative Store Bypassing Safe (SSBS) alice # [ 0.107908] scmi_core: SCMI protocol bus registered bob # [ 0.006986] CPU features: detected: Hardware dirty bit management on CPU0 clare # [ 0.005494] alternatives: applying system-wide alternatives bob # [ 0.007147] Memory: 891340K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 115940K reserved, 32768K cma-reserved) bob # [ 0.007558] devtmpfs: initialized clare # [ 0.006860] CPU features: detected: Hardware dirty bit management on CPU0 bob # [ 0.009029] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.009068] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). clare # [ 0.007048] Memory: 891284K/1048576K available (24448K kernel code, 7094K rwdata, 26592K rodata, 4736K init, 1107K bss, 115948K reserved, 32768K cma-reserved) clare # [ 0.007475] devtmpfs: initialized bob # [ 0.009193] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.009197] 0 pages in range for non-PLT usage clare # [ 0.008941] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.009198] 508224 pages in range for PLT usage alice # [ 0.108627] FPGA manager framework alice # [ 0.111873] vgaarb: loaded clare # [ 0.008963] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). bob # [ 0.009310] pinctrl core: initialized pinctrl subsystem bob # [ 0.010056] DMI not present or invalid. clare # [ 0.009090] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL clare # [ 0.009093] 0 pages in range for non-PLT usage bob # [ 0.013165] NET: Registered PF_NETLINK/PF_ROUTE protocol family clare # [ 0.009094] 508224 pages in range for PLT usage bob # [ 0.015424] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations clare # [ 0.009203] pinctrl core: initialized pinctrl subsystem clare # [ 0.009914] DMI not present or invalid. bob # [ 0.015556] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations clare # [ 0.013133] NET: Registered PF_NETLINK/PF_ROUTE protocol family bob # [ 0.015706] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.015341] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations bob # [ 0.015721] audit: initializing netlink subsys (disabled) clare # [ 0.015470] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 0.016166] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1 alice # [ 0.112553] clocksource: Switched to clocksource arch_sys_counter bob # [ 0.016313] thermal_sys: Registered thermal governor 'fair_share' clare # [ 0.015643] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.015658] audit: initializing netlink subsys (disabled) bob # [ 0.016314] thermal_sys: Registered thermal governor 'bang_bang' bob # [ 0.016317] thermal_sys: Registered thermal governor 'step_wise' clare # [ 0.016117] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1 bob # [ 0.016319] thermal_sys: Registered thermal governor 'user_space' clare # [ 0.016246] thermal_sys: Registered thermal governor 'fair_share' bob # [ 0.016320] thermal_sys: Registered thermal governor 'power_allocator' clare # [ 0.016248] thermal_sys: Registered thermal governor 'bang_bang' bob # [ 0.016347] cpuidle: using governor ladder clare # [ 0.016250] thermal_sys: Registered thermal governor 'step_wise' bob # [ 0.016353] cpuidle: using governor menu clare # [ 0.016252] thermal_sys: Registered thermal governor 'user_space' bob # [ 0.016456] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. clare # [ 0.016254] thermal_sys: Registered thermal governor 'power_allocator' bob # [ 0.016476] ASID allocator initialised with 32768 entries clare # [ 0.016280] cpuidle: using governor ladder bob # [ 0.017136] Serial: AMBA PL011 UART driver clare # [ 0.016286] cpuidle: using governor menu bob # [ 0.020500] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 clare # [ 0.016400] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. bob # [ 0.020638] printk: console [ttyAMA0] enabled clare # [ 0.016419] ASID allocator initialised with 32768 entries clare # [ 0.017067] Serial: AMBA PL011 UART driver clare # [ 0.020405] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 clare # [ 0.020538] printk: console [ttyAMA0] enabled alice # [ 0.120694] VFS: Disk quotas dquot_6.6.0 alice # [ 0.120721] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 0.122127] netfs: FS-Cache loaded alice # [ 0.122234] pnp: PnP ACPI: disabled alice # [ 0.124036] NET: Registered PF_INET protocol family alice # [ 0.124214] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) bob # [ 0.093516] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.093529] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 0.093531] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.093535] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page clare # [ 0.093528] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.093537] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages clare # [ 0.093541] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 0.093539] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page clare # [ 0.093543] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.093541] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages clare # [ 0.093547] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page bob # [ 0.093543] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page clare # [ 0.093549] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 0.094486] fbcon: Taking over console clare # [ 0.093551] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 0.094502] ACPI: Interpreter disabled. bob # [ 0.095124] iommu: Default domain type: Translated clare # [ 0.093554] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages bob # [ 0.095128] iommu: DMA domain TLB invalidation policy: strict mode clare # [ 0.093555] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page bob # [ 0.095422] SCSI subsystem initialized clare # [ 0.094516] fbcon: Taking over console clare # [ 0.094532] ACPI: Interpreter disabled. bob # [ 0.095640] usbcore: registered new interface driver usbfs clare # [ 0.095167] iommu: Default domain type: Translated bob # [ 0.095676] usbcore: registered new interface driver hub bob # [ 0.095690] usbcore: registered new device driver usb clare # [ 0.095171] iommu: DMA domain TLB invalidation policy: strict mode clare # [ 0.095447] SCSI subsystem initialized bob # [ 0.095892] pps_core: LinuxPPS API ver. 1 registered clare # [ 0.095699] usbcore: registered new interface driver usbfs bob # [ 0.095895] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti clare # [ 0.095729] usbcore: registered new interface driver hub bob # [ 0.095900] PTP clock support registered clare # [ 0.095738] usbcore: registered new device driver usb bob # [ 0.095931] EDAC MC: Ver: 3.0.0 clare # [ 0.095955] pps_core: LinuxPPS API ver. 1 registered bob # [ 0.105817] scmi_core: SCMI protocol bus registered clare # [ 0.095957] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti clare # [ 0.095962] PTP clock support registered clare # [ 0.095989] EDAC MC: Ver: 3.0.0 clare # [ 0.105856] scmi_core: SCMI protocol bus registered bob # [ 0.106534] FPGA manager framework bob # [ 0.109781] vgaarb: loaded clare # [ 0.106586] FPGA manager framework clare # [ 0.109792] vgaarb: loaded bob # [ 0.110463] clocksource: Switched to clocksource arch_sys_counter clare # [ 0.110500] clocksource: Switched to clocksource arch_sys_counter bob # [ 0.119315] VFS: Disk quotas dquot_6.6.0 bob # [ 0.119339] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.120649] netfs: FS-Cache loaded bob # [ 0.120755] pnp: PnP ACPI: disabled clare # [ 0.119722] VFS: Disk quotas dquot_6.6.0 clare # [ 0.119747] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) clare # [ 0.121128] netfs: FS-Cache loaded clare # [ 0.121235] pnp: PnP ACPI: disabled alice # [ 0.149429] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.149447] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.123984] NET: Registered PF_INET protocol family alice # [ 0.149457] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 0.124153] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.149492] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.149558] TCP: Hash tables configured (established 8192 bind 8192) clare # [ 0.124537] NET: Registered PF_INET protocol family alice # [ 0.149643] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) clare # [ 0.124703] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.149699] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.149721] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.149842] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 0.149873] NET: Registered PF_XDP protocol family alice # [ 0.149890] PCI: CLS 0 bytes, default 64 alice # [ 0.150177] Trying to unpack rootfs image as initramfs... alice # [ 0.152712] kvm [1]: HYP mode not available bob # [ 0.148068] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.148090] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.148102] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) clare # [ 0.148554] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.148138] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) clare # [ 0.148576] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.148205] TCP: Hash tables configured (established 8192 bind 8192) clare # [ 0.148587] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 0.148333] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) clare # [ 0.148624] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) bob # [ 0.148384] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.148690] TCP: Hash tables configured (established 8192 bind 8192) bob # [ 0.148406] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.148781] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) bob # [ 0.148529] NET: Registered PF_UNIX/PF_LOCAL protocol family bob # [ 0.148558] NET: Registered PF_XDP protocol family clare # [ 0.148808] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.148578] PCI: CLS 0 bytes, default 64 clare # [ 0.148829] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.148761] Trying to unpack rootfs image as initramfs... clare # [ 0.148943] NET: Registered PF_UNIX/PF_LOCAL protocol family clare # [ 0.148974] NET: Registered PF_XDP protocol family clare # [ 0.148994] PCI: CLS 0 bytes, default 64 clare # [ 0.149183] Trying to unpack rootfs image as initramfs... bob # [ 0.162484] kvm [1]: HYP mode not available clare # [ 0.162526] kvm [1]: HYP mode not available alice # [ 0.229778] Initialise system trusted keyrings alice # [ 0.229893] workingset: timestamp_bits=42 max_order=18 bucket_order=0 alice # [ 0.230200] squashfs: version 4.0 (2009/01/31) Phillip Lougher alice # [ 0.230233] 9p: Installing v9fs 9p2000 file system support alice # [ 0.250786] Key type asymmetric registered alice # [ 0.250806] Asymmetric key parser 'x509' registered alice # [ 0.250875] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) alice # [ 0.250982] io scheduler mq-deadline registered alice # [ 0.250986] io scheduler kyber registered alice # [ 0.261538] pl061_gpio 9030000.pl061: PL061 GPIO chip registered alice # [ 0.261871] ledtrig-cpu: registered to indicate activity on CPUs bob # [ 0.226862] Initialise system trusted keyrings alice # [ 0.262164] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: bob # [ 0.226979] workingset: timestamp_bits=42 max_order=18 bucket_order=0 alice # [ 0.262180] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 bob # [ 0.227285] squashfs: version 4.0 (2009/01/31) Phillip Lougher bob # [ 0.227358] 9p: Installing v9fs 9p2000 file system support alice # [ 0.262192] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 clare # [ 0.226891] Initialise system trusted keyrings clare # [ 0.226999] workingset: timestamp_bits=42 max_order=18 bucket_order=0 alice # [ 0.262197] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.227331] squashfs: version 4.0 (2009/01/31) Phillip Lougher alice # [ 0.262232] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits clare # [ 0.227369] 9p: Installing v9fs 9p2000 file system support alice # [ 0.262257] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] alice # [ 0.262328] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 alice # [ 0.262344] pci_bus 0000:00: root bus resource [bus 00-ff] alice # [ 0.262348] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] alice # [ 0.262350] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.262353] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.262431] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint alice # [ 0.262863] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.262989] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.262998] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.263017] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.263025] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.263340] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.263464] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.263473] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.263492] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.263802] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.263924] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.263933] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.263952] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.264278] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.264403] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.264412] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.264430] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.264439] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.246140] Key type asymmetric registered bob # [ 0.246158] Asymmetric key parser 'x509' registered bob # [ 0.246211] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) clare # [ 0.246174] Key type asymmetric registered clare # [ 0.246191] Asymmetric key parser 'x509' registered clare # [ 0.246244] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) alice # [ 0.288833] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint alice # [ 0.288963] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.254521] io scheduler mq-deadline registered bob # [ 0.254529] io scheduler kyber registered alice # [ 0.288981] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.256794] pl061_gpio 9030000.pl061: PL061 GPIO chip registered alice # [ 0.289295] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint bob # [ 0.257130] ledtrig-cpu: registered to indicate activity on CPUs alice # [ 0.289419] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.254562] io scheduler mq-deadline registered bob # [ 0.257429] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: clare # [ 0.254569] io scheduler kyber registered alice # [ 0.289436] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.257446] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 alice # [ 0.289713] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 0.289832] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] bob # [ 0.257458] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 alice # [ 0.290007] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.257463] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 alice # [ 0.290130] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.257502] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits alice # [ 0.290148] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.257526] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] alice # [ 0.290456] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.257591] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 clare # [ 0.259279] pl061_gpio 9030000.pl061: PL061 GPIO chip registered alice # [ 0.290580] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.257606] pci_bus 0000:00: root bus resource [bus 00-ff] clare # [ 0.259645] ledtrig-cpu: registered to indicate activity on CPUs alice # [ 0.290598] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.257609] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] clare # [ 0.259927] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: alice # [ 0.290900] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.257611] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.291023] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.259943] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 bob # [ 0.257614] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.291041] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.259956] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 bob # [ 0.257696] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint alice # [ 0.291355] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint clare # [ 0.259960] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 alice # [ 0.291551] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.258122] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.291561] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.258251] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.259996] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits bob # [ 0.258261] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.291579] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.260020] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] bob # [ 0.258280] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.291890] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.260084] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 bob # [ 0.258289] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.292022] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.260099] pci_bus 0000:00: root bus resource [bus 00-ff] alice # [ 0.292031] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.260102] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] alice # [ 0.292048] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.260105] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.292506] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned clare # [ 0.260107] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.292513] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned clare # [ 0.260182] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint alice # [ 0.292516] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned clare # [ 0.260610] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.292548] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned clare # [ 0.260738] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.260747] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.260765] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.307685] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned clare # [ 0.260773] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.307715] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned clare # [ 0.261094] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.307747] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned clare # [ 0.261219] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.261228] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.307776] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned clare # [ 0.261247] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.307805] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.261559] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.307834] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned clare # [ 0.261683] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.307863] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned clare # [ 0.261692] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.261711] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.307892] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned alice # [ 0.307948] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned bob # [ 0.274678] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.262029] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.262155] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.307977] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned bob # [ 0.274808] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.262163] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.274818] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.307990] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned bob # [ 0.274836] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.262182] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.308124] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned clare # [ 0.262190] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.275167] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.308137] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.275293] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.308149] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned bob # [ 0.275302] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.308161] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.275320] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.308173] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.275637] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.308185] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.275764] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.308198] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.275773] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.308209] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.275792] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.308221] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.275801] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.308233] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned bob # [ 0.276121] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint alice # [ 0.308246] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.276251] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.308257] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.276269] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.308269] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned alice # [ 0.308281] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned bob # [ 0.276594] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 0.308292] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned bob # [ 0.276721] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.308304] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.276739] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.308324] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.277014] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 0.308335] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] bob # [ 0.277150] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] alice # [ 0.308337] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] bob # [ 0.277354] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.277481] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.277499] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.277817] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.277944] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.277962] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.286563] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint bob # [ 0.278277] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.286697] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.278404] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.286716] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.278421] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.324577] pci 0000:00:07.0: enabling device (0000 -> 0002) clare # [ 0.287065] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint clare # [ 0.287193] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.294324] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint clare # [ 0.287212] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.287492] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.287630] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.287819] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.287946] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.287965] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.288280] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.288408] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.288426] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.288738] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.288866] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.288884] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.289326] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint clare # [ 0.289493] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] clare # [ 0.289503] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.334278] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) clare # [ 0.289521] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.336439] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) clare # [ 0.289911] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.290037] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.290046] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.290065] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.295167] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.295178] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.295196] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.295526] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.303687] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned bob # [ 0.295652] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.303694] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned bob # [ 0.295660] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.303697] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned bob # [ 0.295679] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.303732] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned bob # [ 0.296133] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned bob # [ 0.296140] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned clare # [ 0.303762] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned bob # [ 0.296143] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned clare # [ 0.303792] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.296179] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned clare # [ 0.303823] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.296209] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned clare # [ 0.303852] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned bob # [ 0.296240] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned clare # [ 0.303882] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned bob # [ 0.296273] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned clare # [ 0.303912] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.296305] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned clare # [ 0.303941] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.296337] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.303969] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned bob # [ 0.296368] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned clare # [ 0.304029] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned bob # [ 0.296399] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned clare # [ 0.304095] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned bob # [ 0.296429] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned clare # [ 0.304107] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.304119] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.296507] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned clare # [ 0.304130] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.296538] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned clare # [ 0.304142] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned bob # [ 0.296550] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.304154] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.296562] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned clare # [ 0.304166] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.296574] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned clare # [ 0.304178] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.296586] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned clare # [ 0.304190] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.296598] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned clare # [ 0.304202] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.296610] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned clare # [ 0.304213] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.296623] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned alice # [ 0.353641] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) clare # [ 0.304225] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned bob # [ 0.296635] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned alice # [ 0.355590] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.304237] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.296647] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned clare # [ 0.304248] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.296659] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned clare # [ 0.304260] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned bob # [ 0.296672] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned clare # [ 0.304272] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned bob # [ 0.296684] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned clare # [ 0.304283] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned bob # [ 0.296695] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned clare # [ 0.304295] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.296708] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned clare # [ 0.304313] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.296719] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned clare # [ 0.304324] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] bob # [ 0.296731] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.358519] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) clare # [ 0.304326] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] bob # [ 0.296743] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned clare # [ 0.304854] pci 0000:00:07.0: enabling device (0000 -> 0002) bob # [ 0.296760] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.296768] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] bob # [ 0.296771] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] bob # [ 0.297321] pci 0000:00:07.0: enabling device (0000 -> 0002) alice # [ 0.361259] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) alice # [ 0.363054] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.333782] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) clare # [ 0.335786] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) clare # [ 0.337620] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.373516] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.375426] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) bob # [ 0.343618] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) bob # [ 0.347480] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) bob # [ 0.349129] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.348164] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) clare # [ 0.349796] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) alice # [ 0.385045] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) alice # [ 0.387863] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) bob # [ 0.358890] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) bob # [ 0.361304] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) clare # [ 0.360326] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) clare # [ 0.362187] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) alice # [ 0.397290] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled alice # [ 0.398255] msm_serial: driver initialized alice # [ 0.398352] SuperH (H)SCI(F) driver initialized alice # [ 0.398392] STM32 USART driver initialized clare # [ 0.364723] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) clare # [ 0.366353] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) bob # [ 0.370585] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.372835] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) bob # [ 0.375514] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) bob # [ 0.377210] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) clare # [ 0.375678] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) clare # [ 0.377388] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) clare # [ 0.380368] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) alice # [ 0.422311] loop: module loaded alice # [ 0.422438] virtio_blk virtio2: 1/0/0 default/read/poll queues alice # [ 0.423030] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) bob # [ 0.387660] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) bob # [ 0.391659] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.392642] msm_serial: driver initialized bob # [ 0.392722] SuperH (H)SCI(F) driver initialized bob # [ 0.392760] STM32 USART driver initialized clare # [ 0.391899] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled clare # [ 0.392822] msm_serial: driver initialized clare # [ 0.392892] SuperH (H)SCI(F) driver initialized clare # [ 0.392932] STM32 USART driver initialized alice # [ 0.426236] megasas: 07.734.00.00-rc1 alice # [ 0.426694] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] alice # [ 0.442613] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.442637] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.444294] Using buffer write method alice # [ 0.444320] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] bob # [ 0.416678] loop: module loaded bob # [ 0.416811] virtio_blk virtio2: 1/0/0 default/read/poll queues bob # [ 0.417474] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) clare # [ 0.416084] loop: module loaded clare # [ 0.416215] virtio_blk virtio2: 1/0/0 default/read/poll queues clare # [ 0.416786] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 0.453442] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.453455] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.454923] Using buffer write method alice # [ 0.454936] Concatenating MTD devices: alice # [ 0.454938] (0): "0.flash" alice # [ 0.454940] (1): "0.flash" alice # [ 0.454941] into device "0.flash" clare # [ 0.420214] megasas: 07.734.00.00-rc1 clare # [ 0.420702] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] bob # [ 0.422848] megasas: 07.734.00.00-rc1 bob # [ 0.423281] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] clare # [ 0.431587] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.431631] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.433331] Using buffer write method clare # [ 0.433382] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] bob # [ 0.434493] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.434542] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.436173] Using buffer write method bob # [ 0.436224] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] clare # [ 0.437217] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.437230] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.440984] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.441025] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.443653] Using buffer write method bob # [ 0.443663] Concatenating MTD devices: bob # [ 0.443664] (0): "0.flash" bob # [ 0.443666] (1): "0.flash" bob # [ 0.443668] into device "0.flash" clare # [ 0.447057] Using buffer write method clare # [ 0.447070] Concatenating MTD devices: clare # [ 0.447072] (0): "0.flash" clare # [ 0.447074] (1): "0.flash" clare # [ 0.447075] into device "0.flash" alice # [ 0.651691] Freeing initrd memory: 26892K alice # [ 0.658440] tun: Universal TUN/TAP device driver, 1.6 alice # [ 0.661167] thunder_xcv, ver 1.0 alice # [ 0.661182] thunder_bgx, ver 1.0 alice # [ 0.661191] nicpf, ver 1.0 alice # [ 0.661494] e1000: Intel(R) PRO/1000 Network Driver alice # [ 0.661498] e1000: Copyright (c) 1999-2006 Intel Corporation. alice # [ 0.661510] e1000e: Intel(R) PRO/1000 Network Driver alice # [ 0.661515] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. alice # [ 0.661527] igb: Intel(R) Gigabit Ethernet Network Driver alice # [ 0.661529] igb: Copyright (c) 2007-2014 Intel Corporation. alice # [ 0.661541] igbvf: Intel(R) Gigabit Virtual Function Network Driver alice # [ 0.661543] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. alice # [ 0.661635] sky2: driver version 1.30 alice # [ 0.662470] usbcore: registered new interface driver usb-storage alice # [ 0.662609] usbcore: registered new interface driver usbserial_generic alice # [ 0.662615] usbserial: USB Serial support registered for generic alice # [ 0.662922] hv_vmbus: registering driver hyperv_keyboard alice # [ 0.663909] ehci-pci 0000:00:07.0: EHCI Host Controller alice # [ 0.663934] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 alice # [ 0.664222] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 alice # [ 0.664392] rtc-pl031 9010000.pl031: registered as rtc0 alice # [ 0.664418] rtc-pl031 9010000.pl031: setting system clock to 2026-09-27T17:41:38 UTC (1790530898) alice # [ 0.673427] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 alice # [ 0.673765] hub 1-0:1.0: USB hub found alice # [ 0.673788] hub 1-0:1.0: 6 ports detected alice # [ 0.674077] i2c_dev: i2c /dev entries driver alice # [ 0.675685] sdhci: Secure Digital Host Controller Interface driver alice # [ 0.675689] sdhci: Copyright(c) Pierre Ossman alice # [ 0.675845] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.676055] sdhci-pltfm: SDHCI platform and OF driver helper alice # [ 0.680216] hid: raw HID events driver (C) Jiri Kosina alice # [ 0.680352] usbcore: registered new interface driver usbhid alice # [ 0.680355] usbhid: USB HID core driver bob # [ 0.649213] Freeing initrd memory: 26884K clare # [ 0.646644] Freeing initrd memory: 26892K bob # [ 0.657264] tun: Universal TUN/TAP device driver, 1.6 clare # [ 0.654586] tun: Universal TUN/TAP device driver, 1.6 clare # [ 0.656800] thunder_xcv, ver 1.0 clare # [ 0.656819] thunder_bgx, ver 1.0 clare # [ 0.656828] nicpf, ver 1.0 clare # [ 0.657144] e1000: Intel(R) PRO/1000 Network Driver clare # [ 0.657147] e1000: Copyright (c) 1999-2006 Intel Corporation. clare # [ 0.657160] e1000e: Intel(R) PRO/1000 Network Driver clare # [ 0.657165] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.659879] thunder_xcv, ver 1.0 bob # [ 0.659894] thunder_bgx, ver 1.0 clare # [ 0.657176] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.659902] nicpf, ver 1.0 clare # [ 0.657178] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.660238] e1000: Intel(R) PRO/1000 Network Driver bob # [ 0.660242] e1000: Copyright (c) 1999-2006 Intel Corporation. clare # [ 0.657190] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.660254] e1000e: Intel(R) PRO/1000 Network Driver clare # [ 0.657192] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. clare # [ 0.657255] sky2: driver version 1.30 bob # [ 0.660259] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.660270] igb: Intel(R) Gigabit Ethernet Network Driver clare # [ 0.658058] usbcore: registered new interface driver usb-storage bob # [ 0.660272] igb: Copyright (c) 2007-2014 Intel Corporation. clare # [ 0.658097] usbcore: registered new interface driver usbserial_generic bob # [ 0.660284] igbvf: Intel(R) Gigabit Virtual Function Network Driver clare # [ 0.658102] usbserial: USB Serial support registered for generic bob # [ 0.660286] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. clare # [ 0.658399] hv_vmbus: registering driver hyperv_keyboard bob # [ 0.660350] sky2: driver version 1.30 bob # [ 0.661169] usbcore: registered new interface driver usb-storage bob # [ 0.661250] usbcore: registered new interface driver usbserial_generic bob # [ 0.661256] usbserial: USB Serial support registered for generic clare # [ 0.665798] ehci-pci 0000:00:07.0: EHCI Host Controller bob # [ 0.661553] hv_vmbus: registering driver hyperv_keyboard clare # [ 0.665823] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 clare # [ 0.666118] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 alice # [ 0.704257] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available alice # [ 0.705132] drop_monitor: Initializing network drop monitor service alice # [ 0.705302] NET: Registered PF_INET6 protocol family clare # [ 0.668119] rtc-pl031 9010000.pl031: registered as rtc0 clare # [ 0.668148] rtc-pl031 9010000.pl031: setting system clock to 2026-09-27T17:41:38 UTC (1790530898) clare # [ 0.668312] i2c_dev: i2c /dev entries driver bob # [ 0.668868] ehci-pci 0000:00:07.0: EHCI Host Controller alice # [ 0.707520] Segment Routing with IPv6 clare # [ 0.669898] sdhci: Secure Digital Host Controller Interface driver bob # [ 0.668922] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 alice # [ 0.707531] In-situ OAM (IOAM) with IPv6 clare # [ 0.669903] sdhci: Copyright(c) Pierre Ossman bob # [ 0.669174] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 alice # [ 0.707590] NET: Registered PF_PACKET protocol family clare # [ 0.670051] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.707652] 9pnet: Installing 9P2000 support bob # [ 0.669374] rtc-pl031 9010000.pl031: registered as rtc0 clare # [ 0.670231] sdhci-pltfm: SDHCI platform and OF driver helper alice # [ 0.707688] Key type dns_resolver registered bob # [ 0.669400] rtc-pl031 9010000.pl031: setting system clock to 2026-09-27T17:41:38 UTC (1790530898) bob # [ 0.669568] i2c_dev: i2c /dev entries driver bob # [ 0.671144] sdhci: Secure Digital Host Controller Interface driver clare # [ 0.674271] hid: raw HID events driver (C) Jiri Kosina bob # [ 0.671149] sdhci: Copyright(c) Pierre Ossman clare # [ 0.674400] usbcore: registered new interface driver usbhid bob # [ 0.671292] Synopsys Designware Multimedia Card Interface Driver clare # [ 0.674403] usbhid: USB HID core driver bob # [ 0.671469] sdhci-pltfm: SDHCI platform and OF driver helper clare # [ 0.674546] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 alice # [ 0.712160] registered taskstats version 1 bob # [ 0.672310] hid: raw HID events driver (C) Jiri Kosina alice # [ 0.712317] Loading compiled-in X.509 certificates bob # [ 0.672442] usbcore: registered new interface driver usbhid bob # [ 0.672445] usbhid: USB HID core driver clare # [ 0.676465] hub 1-0:1.0: USB hub found clare # [ 0.676502] hub 1-0:1.0: 6 ports detected clare # [ 0.677370] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available clare # [ 0.678144] drop_monitor: Initializing network drop monitor service clare # [ 0.678298] NET: Registered PF_INET6 protocol family bob # [ 0.678781] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 bob # [ 0.679124] hub 1-0:1.0: USB hub found bob # [ 0.679153] hub 1-0:1.0: 6 ports detected bob # [ 0.679481] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available clare # [ 0.681120] Segment Routing with IPv6 clare # [ 0.681141] In-situ OAM (IOAM) with IPv6 bob # [ 0.680282] drop_monitor: Initializing network drop monitor service clare # [ 0.681190] NET: Registered PF_PACKET protocol family bob # [ 0.680426] NET: Registered PF_INET6 protocol family clare # [ 0.681251] 9pnet: Installing 9P2000 support bob # [ 0.681004] Segment Routing with IPv6 bob # [ 0.681015] In-situ OAM (IOAM) with IPv6 clare # [ 0.681285] Key type dns_resolver registered bob # [ 0.681075] NET: Registered PF_PACKET protocol family alice # [ 0.717777] Demotion targets for Node 0: null bob # [ 0.681137] 9pnet: Installing 9P2000 support alice # [ 0.717846] Key type .fscrypt registered bob # [ 0.681176] Key type dns_resolver registered alice # [ 0.717849] Key type fscrypt-provisioning registered alice # [ 0.717945] ima: No TPM chip found, activating TPM-bypass! alice # [ 0.717955] ima: Allocated hash algorithm: sha1 alice # [ 0.717999] ima: No architecture policies found clare # [ 0.685749] registered taskstats version 1 alice # [ 0.718425] input: gpio-keys as /devices/platform/gpio-keys/input/input0 clare # [ 0.685891] Loading compiled-in X.509 certificates bob # [ 0.688256] registered taskstats version 1 bob # [ 0.688394] Loading compiled-in X.509 certificates bob # [ 0.693793] Demotion targets for Node 0: null bob # [ 0.693865] Key type .fscrypt registered bob # [ 0.693868] Key type fscrypt-provisioning registered bob # [ 0.693971] ima: No TPM chip found, activating TPM-bypass! bob # [ 0.693982] ima: Allocated hash algorithm: sha1 clare # [ 0.691365] Demotion targets for Node 0: null bob # [ 0.694026] ima: No architecture policies found clare # [ 0.691438] Key type .fscrypt registered clare # [ 0.691441] Key type fscrypt-provisioning registered bob # [ 0.694522] input: gpio-keys as /devices/platform/gpio-keys/input/input0 clare # [ 0.691551] ima: No TPM chip found, activating TPM-bypass! alice # [ 0.730828] clk: Disabling unused clocks clare # [ 0.691562] ima: Allocated hash algorithm: sha1 alice # [ 0.730838] PM: genpd: Disabling unused power domains clare # [ 0.691607] ima: No architecture policies found clare # [ 0.692076] input: gpio-keys as /devices/platform/gpio-keys/input/input0 alice # [ 0.733883] Freeing unused kernel memory: 4736K alice # [ 0.733958] Run /init as init process clare # [ 0.704630] clk: Disabling unused clocks clare # [ 0.704639] PM: genpd: Disabling unused power domains bob # [ 0.706944] clk: Disabling unused clocks bob # [ 0.706953] PM: genpd: Disabling unused power domains bob # [ 0.709276] Freeing unused kernel memory: 4736K bob # [ 0.709363] Run /init as init process clare # [ 0.707733] Freeing unused kernel memory: 4736K clare # [ 0.707822] Run /init as init process alice # [ 0.752085] systemd[1]: Successfully made /usr/ read-only. clare # [ 0.728926] systemd[1]: Successfully made /usr/ read-only. bob # [ 0.732802] systemd[1]: Successfully made /usr/ read-only. alice # [ 0.924634] usb 1-1: new high-speed USB device number 2 using ehci-pci clare # [ 0.926577] usb 1-1: new high-speed USB device number 2 using ehci-pci bob # [ 0.930559] usb 1-1: new high-speed USB device number 2 using ehci-pci alice # [ 1.076918] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 alice # [ 1.087098] 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.087126] systemd[1]: Detected virtualization qemu. alice # [ 1.087163] systemd[1]: Detected architecture arm64. alice # [ 1.087172] systemd[1]: Running in initrd. alice # [ 1.087964] systemd[1]: Initializing machine ID from random generator. alice # [ 1.088279] systemd[1]: Hostname set to . bob # [ 1.066700] 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.066717] systemd[1]: Detected virtualization qemu. bob # [ 1.066744] systemd[1]: Detected architecture arm64. bob # [ 1.066747] systemd[1]: Running in initrd. clare # [ 1.062864] 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.067275] systemd[1]: Initializing machine ID from random generator. bob # [ 1.067474] systemd[1]: Hostname set to . clare # [ 1.062894] systemd[1]: Detected virtualization qemu. clare # [ 1.062934] systemd[1]: Detected architecture arm64. clare # [ 1.062944] systemd[1]: Running in initrd. clare # [ 1.063778] systemd[1]: Initializing machine ID from random generator. clare # [ 1.064153] systemd[1]: Hostname set to . bob # [ 1.082833] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 clare # [ 1.086467] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 alice # [ 1.160689] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 bob # [ 1.162867] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 clare # [ 1.178658] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 alice # [ 1.280605] usb 1-2: new high-speed USB device number 3 using ehci-pci bob # [ 1.282493] usb 1-2: new high-speed USB device number 3 using ehci-pci alice # [ 1.325085] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 1.293849] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 1.298553] usb 1-2: new high-speed USB device number 3 using ehci-pci clare # [ 1.351782] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 1.392150] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 1.366127] systemd[1]: Queued start job for default target Initrd Default Target. alice # [ 1.402453] systemd[1]: Created slice Slice /system/modprobe. alice # [ 1.402626] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 1.402658] systemd[1]: Expecting device /dev/disk/by-label/nixos... alice # [ 1.402680] systemd[1]: Reached target Path Units. alice # [ 1.402695] systemd[1]: Reached target Slice Units. alice # [ 1.402708] systemd[1]: Reached target Swaps. alice # [ 1.402721] systemd[1]: Reached target Timer Units. alice # [ 1.402862] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 1.402983] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 1.403071] systemd[1]: Listening on Journal Sockets. alice # [ 1.403142] systemd[1]: Listening on udev Control Socket. alice # [ 1.403222] systemd[1]: Listening on udev Kernel Socket. alice # [ 1.403241] systemd[1]: Reached target Socket Units. alice # [ 1.404328] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.404380] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 1.376030] systemd[1]: Created slice Slice /system/modprobe. bob # [ 1.376191] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 1.376226] systemd[1]: Expecting device /dev/disk/by-label/nixos... bob # [ 1.376248] systemd[1]: Reached target Path Units. bob # [ 1.376265] systemd[1]: Reached target Slice Units. bob # [ 1.376278] systemd[1]: Reached target Swaps. bob # [ 1.376293] systemd[1]: Reached target Timer Units. bob # [ 1.376432] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 1.376561] systemd[1]: Listening on Journal Socket (/dev/log). bob # [ 1.376658] systemd[1]: Listening on Journal Sockets. bob # [ 1.376733] systemd[1]: Listening on udev Control Socket. bob # [ 1.376819] systemd[1]: Listening on udev Kernel Socket. bob # [ 1.376841] systemd[1]: Reached target Socket Units. bob # [ 1.377973] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 1.378028] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.420664] systemd[1]: Mounting Kernel Configuration File System... alice # [ 1.429773] systemd[1]: Starting Journal Service... bob # [ 1.395068] systemd[1]: Mounting Kernel Configuration File System... alice # [ 1.435239] systemd[1]: Starting Load Kernel Modules... alice # [ 1.435310] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 1.439624] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 bob # [ 1.402626] systemd[1]: Starting Journal Service... alice # [ 1.439708] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 alice # [ 1.443032] systemd[1]: Starting Coldplug All udev Devices... bob # [ 1.408383] systemd[1]: Starting Load Kernel Modules... bob # [ 1.408447] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 1.447094] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 1.457926] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.458296] systemd[1]: Mounted Kernel Configuration File System. bob # [ 1.419204] systemd[1]: Starting Coldplug All udev Devices... clare # [ 1.424681] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.432144] systemd[1]: Created slice Slice /system/modprobe. clare # [ 1.432315] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 1.432349] systemd[1]: Expecting device /dev/disk/by-label/nixos... clare # [ 1.432370] systemd[1]: Reached target Path Units. clare # [ 1.432383] systemd[1]: Reached target Slice Units. clare # [ 1.432396] systemd[1]: Reached target Swaps. clare # [ 1.432409] systemd[1]: Reached target Timer Units. clare # [ 1.432546] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 1.432670] systemd[1]: Listening on Journal Socket (/dev/log). clare # [ 1.432793] systemd[1]: Listening on Journal Sockets. clare # [ 1.432869] systemd[1]: Listening on udev Control Socket. clare # [ 1.432918] systemd[1]: Listening on udev Kernel Socket. clare # [ 1.432933] systemd[1]: Reached target Socket Units. bob # [ 1.442581] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 1.434028] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 1.434084] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 1.453847] systemd[1]: Mounting Kernel Configuration File System... bob # [ 1.456057] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 1.456772] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 bob # [ 1.456850] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 bob # [ 1.458095] systemd-journald[71]: Collecting audit messages is disabled. alice # [ 1.495575] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. clare # [ 1.460073] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 clare # [ 1.460195] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 alice # [ 1.503350] systemd-journald[71]: Collecting audit messages is disabled. clare # [ 1.466620] systemd[1]: Starting Journal Service... clare # [ 1.467489] systemd[1]: Starting Load Kernel Modules... clare # [ 1.467691] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 1.511102] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev alice # [ 1.512470] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 1.514828] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 1.470724] systemd[1]: Mounted Kernel Configuration File System. bob # [ 1.471453] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. clare # [ 1.478699] systemd[1]: Starting Coldplug All udev Devices... bob # [ 1.471585] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev bob # [ 1.479892] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 bob # [ 1.480094] [drm] features: -virgl +edid -resource_blob -host_visible bob # [ 1.480099] [drm] features: -context_init bob # [ 1.480638] [drm] number of scanouts: 1 bob # [ 1.480648] [drm] number of cap sets: 0 alice # [ 1.522126] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 alice # [ 1.522359] [drm] features: -virgl +edid -resource_blob -host_visible alice # [ 1.522361] [drm] features: -context_init alice # [ 1.522898] [drm] number of scanouts: 1 alice # [ 1.522907] [drm] number of cap sets: 0 clare # [ 1.494580] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 1.495300] systemd[1]: Mounted Kernel Configuration File System. bob # [ 1.499561] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 1.500487] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 1.499807] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 1.503774] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic bob # [ 1.503783] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 alice # [ 1.540853] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic alice # [ 1.540862] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 bob # [ 1.522861] Console: switching to colour frame buffer device 160x50 alice # [ 1.561435] Console: switching to colour frame buffer device 160x50 alice # [ 1.567552] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 1.567709] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 1.567729] systemd[1]: Reached target Local File Systems. alice # [ 1.570376] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 1.541675] systemd[1]: Started Journal Service. clare # [ 1.530673] systemd-journald[71]: Collecting audit messages is disabled. clare # [ 1.542714] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. bob # [ 1.541294] systemd-modules-load[72]: Inserted module 'dm_mod' bob # [ 1.547037] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device bob # [ 1.545381] systemd-modules-load[72]: Module 'virtio_balloon' is built in alice # [ 1.585123] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device clare # [ 1.550566] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev alice # [ 1.588394] systemd-modules-load[72]: Inserted module 'dm_mod' alice # [ 1.589166] systemd-modules-load[72]: Module 'virtio_balloon' is built in bob # [ 1.552490] systemd-modules-load[72]: Module 'virtio_console' is built in bob # [ 1.553228] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 1.555721] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 1.556850] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 1.556248] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 1.557021] systemd[1]: Reached target Local File Systems. alice # [ 1.593775] systemd-modules-load[72]: Module 'virtio_console' is built in bob # [ 1.557640] systemd[1]: Starting Create System Files and Directories... alice # [ 1.597250] systemd[1]: Started Journal Service. alice # [ 1.596622] systemd-modules-load[72]: Inserted module 'virtio_gpu' alice # [ 1.597276] systemd-modules-load[72]: Module 'virtio_rng' is built in bob # [ 1.561080] systemd-modules-load[72]: Inserted module 'virtio_gpu' alice # [ 1.597942] systemd[1]: Starting Create System Files and Directories... bob # [ 1.561685] systemd-modules-load[72]: Module 'virtio_rng' is built in bob # [ 1.563565] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 1.568205] systemd[1]: Finished Load Kernel Modules. clare # [ 1.572217] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 clare # [ 1.572436] [drm] features: -virgl +edid -resource_blob -host_visible clare # [ 1.572438] [drm] features: -context_init clare # [ 1.572892] [drm] number of scanouts: 1 clare # [ 1.572901] [drm] number of cap sets: 0 alice # [ 1.612075] systemd[1]: Finished Load Kernel Modules. clare # [ 1.583100] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic clare # [ 1.583109] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 alice # [ 1.625024] systemd[1]: Starting Apply Kernel Variables... bob # [ 1.588544] systemd[1]: Starting Apply Kernel Variables... bob # [ 1.589975] systemd[1]: Finished Create System Files and Directories. clare # [ 1.599931] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 1.600190] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 1.600211] systemd[1]: Reached target Local File Systems. clare # [ 1.601302] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 1.639572] systemd[1]: Finished Create System Files and Directories. clare # [ 1.605102] Console: switching to colour frame buffer device 160x50 alice # [ 1.645724] systemd-udevd[77]: Using default interface naming scheme 'v261'. bob # [ 1.613625] systemd[1]: Finished Apply Kernel Variables. clare # [ 1.614753] systemd-modules-load[72]: Inserted module 'dm_mod' clare # [ 1.615502] systemd-modules-load[72]: Module 'virtio_balloon' is built in alice # [ 1.660863] systemd[1]: Finished Apply Kernel Variables. clare # [ 1.626759] systemd[1]: Started Journal Service. clare # [ 1.625325] systemd-modules-load[72]: Module 'virtio_console' is built in clare # [ 1.628689] systemd[1]: Starting Create System Files and Directories... bob # [ 1.632073] systemd-udevd[79]: Using default interface naming scheme 'v261'. clare # [ 1.635040] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.672382] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 1.643274] systemd-modules-load[72]: Inserted module 'virtio_gpu' clare # [ 1.644161] systemd-modules-load[72]: Module 'virtio_rng' is built in bob # [ 1.654388] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 1.657775] systemd[1]: Finished Load Kernel Modules. clare # [ 1.672478] systemd[1]: Starting Apply Kernel Variables... clare # [ 1.683173] systemd[1]: Finished Create System Files and Directories. clare # [ 1.683988] systemd-udevd[77]: Using default interface naming scheme 'v261'. alice # [ 1.724090] systemd[1]: Starting Virtual Console Setup... clare # [ 1.691446] systemd[1]: Finished Apply Kernel Variables. bob # [ 1.704070] systemd[1]: Starting Virtual Console Setup... clare # [ 1.707692] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 1.768323] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 1.770599] systemd[1]: Finished Virtual Console Setup. bob # [ 1.748327] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 1.752135] systemd[1]: Finished Virtual Console Setup. clare # [ 1.765988] systemd[1]: Starting Virtual Console Setup... clare # [ 1.799111] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 1.801551] systemd[1]: Finished Virtual Console Setup. alice # [ 1.984832] systemd[1]: Finished Coldplug All udev Devices. alice # [ 1.985428] systemd[1]: Reached target System Initialization. alice # [ 1.985955] systemd[1]: Reached target Basic System. bob # [ 1.964932] systemd[1]: Finished Coldplug All udev Devices. bob # [ 1.965520] systemd[1]: Reached target System Initialization. bob # [ 1.966042] systemd[1]: Reached target Basic System. clare # [ 2.011167] systemd[1]: Finished Coldplug All udev Devices. clare # [ 2.016330] systemd[1]: Reached target System Initialization. clare # [ 2.016901] systemd[1]: Reached target Basic System. alice # [ 2.079540] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.053716] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.071361] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.109246] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.103582] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.156556] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.123606] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.168162] systemd[1]: Reached target Initrd Root Device. alice # [ 2.171824] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... bob # [ 2.135575] systemd[1]: Found device /dev/disk/by-label/nixos. bob # [ 2.143491] systemd[1]: Reached target Initrd Root Device. bob # [ 2.148511] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... alice # [ 2.203755] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 2.184810] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks alice # [ 2.225359] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. clare # [ 2.188350] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.191450] systemd[1]: Reached target Initrd Root Device. bob # [ 2.193024] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. clare # [ 2.194991] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... clare # [ 2.227539] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks clare # [ 2.234956] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 2.440103] systemd[1]: Mounting /sysroot... bob # [ 2.414998] systemd[1]: Mounting /sysroot... alice # [ 2.469158] EXT4-fs (vda): mounted filesystem 85c4d184-e71d-4416-a635-a78665477d19 r/w with ordered data mode. Quota mode: none. alice # [ 2.469624] systemd[1]: Mounted /sysroot. alice # [ 2.470845] systemd[1]: Reached target Initrd Root File System. alice # [ 2.473899] systemd[1]: Starting Mountpoints Configured in the Real Root... bob # [ 2.443022] systemd[1]: Mounted /sysroot. bob # [ 2.447220] EXT4-fs (vda): mounted filesystem 69bd0452-41c9-4514-a862-d6c3778b8fed r/w with ordered data mode. Quota mode: none. bob # [ 2.446626] systemd[1]: Reached target Initrd Root File System. bob # [ 2.454183] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.494664] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 2.500044] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 2.502727] systemd[1]: Reloading... bob # [ 2.466513] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 2.470889] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... bob # [ 2.474193] systemd[1]: Reloading... clare # [ 2.488119] systemd[1]: Mounting /sysroot... clare # [ 2.511322] systemd[1]: Mounted /sysroot. clare # [ 2.515037] EXT4-fs (vda): mounted filesystem 07b9ffc2-93c7-4939-8bcd-5eb6885e5e34 r/w with ordered data mode. Quota mode: none. clare # [ 2.514006] systemd[1]: Reached target Initrd Root File System. clare # [ 2.519601] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 2.533085] systemd[1]: Mounting /sysroot/nix/.rw-store... clare # [ 2.542710] systemd[1]: Mounting /sysroot/run... clare # [ 2.575383] systemd[1]: Mounting /sysroot/tmp/shared... clare # [ 2.580088] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 2.591749] systemd[1]: Starting Mountpoints Configured in the Real Root...[ 2.595047] fuse: init (API version 7.45) clare # clare # [ 2.593676] systemd[1]: Mounted /sysroot/nix/.rw-store. clare # [ 2.606312] virtiofs virtio6: discovered new tag: nix-store clare # [ 2.611901] virtiofs virtio6: virtio_fs_setup_dax: No cache capability clare # [ 2.618167] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 2.630100] virtiofs virtio7: discovered new tag: shared alice # [ 2.663770] systemd[1]: Reloading finished in 161 ms. clare # [ 2.632133] virtiofs virtio7: virtio_fs_setup_dax: No cache capability clare # [ 2.633878] virtiofs virtio8: discovered new tag: xchg clare # [ 2.634353] virtiofs virtio8: virtio_fs_setup_dax: No cache capability bob # [ 2.631202] systemd[1]: Reloading finished in 158 ms. clare # [ 2.638004] systemd[1]: Mounted /sysroot/run. clare # [ 2.639725] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 2.643260] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... bob # [ 2.645859] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... bob # [ 2.650162] systemd[1]: initrd-parse-etc.service: Deactivated successfully. alice # [ 2.688130] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace... bob # [ 2.651713] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 2.651249] systemd-sysroot-fstab-check[123]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 2.653096] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.690638] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace... alice # [ 2.693309] systemd[1]: initrd-parse-etc.service: Deactivated successfully. alice # [ 2.694730] systemd[1]: Finished Mountpoints Configured in the Real Root. alice # [ 2.696135] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. clare # [ 2.658195] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 2.658778] systemd[1]: Mounted /sysroot/nix/.ro-store. clare # [ 2.660624] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 2.661150] systemd[1]: Mounted /sysroot/tmp/xchg. clare # [ 2.667979] systemd[1]: Reload requested from client PID 123 ('systemd-sysroot') (unit initrd-parse-etc.service)... clare # [ 2.671165] systemd[1]: Reloading... clare # [ 2.828911] systemd[1]: Reloading finished in 157 ms. bob # [ 2.843341] fuse: init (API version 7.45) clare # [ 2.856692] systemd-sysroot-fstab-check[123]: Requesting initrd-fs.target/start/replace... clare # [ 2.859600] systemd-sysroot-fstab-check[123]: Requesting swap.target/start/replace... clare # [ 2.863916] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 2.868560] systemd[1]: initrd-parse-etc.service: Deactivated successfully. clare # [ 2.871840] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 2.872779] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.913554] fuse: init (API version 7.45) bob # [ 2.878965] virtiofs virtio6: discovered new tag: nix-store bob # [ 2.879475] virtiofs virtio6: virtio_fs_setup_dax: No cache capability clare # [ 2.881953] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 2.883025] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 2.926888] virtiofs virtio6: discovered new tag: nix-store alice # [ 2.927380] virtiofs virtio6: virtio_fs_setup_dax: No cache capability bob # [ 2.887177] (udev-worker)[90]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory bob # [ 2.891828] (udev-worker)[90]: mtd0ro: /etc/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 # [ 2.904197] virtiofs virtio7: discovered new tag: shared bob # [ 2.904698] virtiofs virtio7: virtio_fs_setup_dax: No cache capability alice # [ 2.939336] (udev-worker)[98]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory alice # [ 2.943362] (udev-worker)[98]: mtd0ro: /etc/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 # [ 2.916487] virtiofs virtio8: discovered new tag: xchg bob # [ 2.916983] virtiofs virtio8: virtio_fs_setup_dax: No cache capability alice # [ 2.955555] virtiofs virtio7: discovered new tag: shared alice # [ 2.956088] virtiofs virtio7: virtio_fs_setup_dax: No cache capability bob # [ 2.919141] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 2.920422] systemd[1]: Stopped Virtual Console Setup. alice # [ 2.958518] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 2.959663] systemd[1]: Stopped Virtual Console Setup.[ 2.961595] virtiofs virtio8: discovered new tag: xchg alice # [ 2.962050] virtiofs virtio8: virtio_fs_setup_dax: No cache capability bob # [ 2.924933] systemd[1]: Stopping Virtual Console Setup... bob # [ 2.925466] systemd[1]: Starting Virtual Console Setup... alice # alice # [ 2.965359] systemd[1]: Stopping Virtual Console Setup... alice # [ 2.966067] systemd[1]: Starting Virtual Console Setup... bob # [ 2.936475] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 2.937424] systemd[1]: Stopped Virtual Console Setup. bob # [ 2.938872] systemd[1]: Starting Virtual Console Setup... alice # [ 2.988110] systemd-vconsole-setup[136]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 2.990351] systemd[1]: Finished Virtual Console Setup. bob # [ 2.953103] systemd-vconsole-setup[139]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 2.955188] systemd[1]: Finished Virtual Console Setup. clare # [ 3.036965] (udev-worker)[91]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory clare # [ 3.040331] (udev-worker)[91]: mtd0ro: /etc/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.056947] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.058982] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.061134] systemd[1]: Stopping Virtual Console Setup... clare # [ 3.064423] systemd[1]: Starting Virtual Console Setup... clare # [ 3.086676] systemd-vconsole-setup[151]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 3.089065] systemd[1]: Finished Virtual Console Setup. alice # [ 3.442686] systemd[1]: Mounting /sysroot/nix/.ro-store... alice # [ 3.452259] systemd[1]: Mounting /sysroot/nix/.rw-store... bob # [ 3.418362] systemd[1]: Mounting /sysroot/nix/.ro-store... alice # [ 3.460265] systemd[1]: Mounting /sysroot/run... bob # [ 3.429659] systemd[1]: Mounting /sysroot/nix/.rw-store... bob # [ 3.431500] systemd[1]: Mounting /sysroot/run... alice # [ 3.469743] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 3.441781] systemd[1]: Mounting /sysroot/tmp/shared... alice # [ 3.492440] systemd[1]: Mounting /sysroot/tmp/xchg... bob # [ 3.460275] systemd[1]: Mounting /sysroot/tmp/xchg... alice # [ 3.514414] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 3.523298] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 3.524555] systemd[1]: Mounted /sysroot/run. clare # [ 3.487883] systemd[1]: Mounting /sysroot/nix/store... alice # [ 3.530405] systemd[1]: Mounted /sysroot/tmp/shared. bob # [ 3.500773] systemd[1]: Mounted /sysroot/nix/.ro-store. bob # [ 3.503088] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 3.540276] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 3.504967] systemd[1]: Mounted /sysroot/run. alice # [ 3.542536] systemd[1]: Mounted /sysroot/tmp/xchg. bob # [ 3.506898] systemd[1]: Mounted /sysroot/tmp/shared. bob # [ 3.510785] systemd[1]: Mounted /sysroot/tmp/xchg. bob # [ 3.513353] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.559993] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.561377] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 3.531653] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 3.533237] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.541781] systemd[1]: Mounted /sysroot/nix/store. clare # [ 3.544570] systemd[1]: Reached target Initrd File Systems. clare # [ 3.548212] systemd[1]: Starting Find NixOS closure... clare # [ 3.553407] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... clare # [ 3.586665] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. clare # [ 3.590283] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. clare # [ 3.603682] systemd[1]: Finished Find NixOS closure. clare # [ 3.606150] systemd[1]: Reached target Initrd Default Target. clare # [ 3.607451] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... clare # [ 3.637723] systemd[1]: Stopped target Initrd Default Target. clare # [ 3.639275] systemd[1]: Stopped target Basic System. clare # [ 3.643966] systemd[1]: Stopped target Initrd Root Device. clare # [ 3.644864] systemd[1]: Stopped target Path Units. clare # [ 3.645810] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. clare # [ 3.646892] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. clare # [ 3.649293] systemd[1]: Stopped target Slice Units. clare # [ 3.651020] systemd[1]: Stopped target Socket Units. clare # [ 3.652651] systemd[1]: Stopped target System Initialization. clare # [ 3.656213] systemd[1]: Stopped target Swaps. clare # [ 3.660515] systemd[1]: Stopped target Timer Units. clare # [ 3.662440] systemd[1]: dbus.socket: Deactivated successfully. clare # [ 3.663860] systemd[1]: Closed D-Bus System Message Bus Socket. clare # [ 3.666074] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. clare # [ 3.669916] systemd[1]: Stopped Find NixOS closure. clare # [ 3.671915] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.674692] systemd[1]: systemd-sysctl.service: Deactivated successfully. clare # [ 3.675584] systemd[1]: Stopped Apply Kernel Variables. clare # [ 3.678115] systemd[1]: systemd-modules-load.service: Deactivated successfully. clare # [ 3.678952] systemd[1]: Stopped Load Kernel Modules. clare # [ 3.679551] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. clare # [ 3.687529] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 3.688470] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. clare # [ 3.689354] systemd[1]: Stopped Create System Files and Directories. clare # [ 3.690015] systemd[1]: Stopped target Local File Systems. clare # [ 3.690562] systemd[1]: Stopped target Preparation for Local File Systems. clare # [ 3.691214] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. clare # [ 3.691890] systemd[1]: Stopped Coldplug All udev Devices. clare # [ 3.696826] systemd[1]: Stopping Rule-based Manager for Device Events and Files... clare # [ 3.700115] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.700825] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.701378] systemd[1]: initrd-cleanup.service: Deactivated successfully. clare # [ 3.702392] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. clare # [ 3.703261] systemd[1]: systemd-udevd.service: Deactivated successfully. clare # [ 3.703945] systemd[1]: Stopped Rule-based Manager for Device Events and Files. clare # [ 3.704811] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. clare # [ 3.705515] systemd[1]: Closed udev Control Socket. clare # [ 3.705984] systemd[1]: Starting Cleanup udev Database... clare # [ 3.706495] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. clare # [ 3.707197] systemd[1]: Stopped Create Static Device Nodes in /dev. clare # [ 3.707768] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. clare # [ 3.716139] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. clare # [ 3.716813] systemd[1]: kmod-static-nodes.service: Deactivated successfully. clare # [ 3.717630] systemd[1]: Stopped Create List of Static Device Nodes. clare # [ 3.718239] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.718894] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.731271] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. clare # [ 3.732083] systemd[1]: Finished Cleanup udev Database. clare # [ 3.733196] systemd[1]: Reached target Switch Root. clare # [ 3.733693] systemd[1]: Starting NixOS Activation... clare # [ 3.802297] initrd-nixos-activation-start[174]: booting system configuration /nix/store/3rm7jwhi0dzl73bwshl17pwph88z8lgv-nixos-system-clare-test clare # [ 3.828593] initrd-nixos-activation-start[174]: running activation script... clare # [ 4.038637] initrd-nixos-activation-start[198]: setting up /etc... clare # [ 4.126078] initrd-nixos-activation-start[198]: setting up age secrets... clare # [ 4.184572] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. clare # [ 4.186263] systemd[1]: Finished NixOS Activation. clare # [ 4.187012] systemd[1]: Starting Switch Root... clare # [ 4.203874] systemd[1]: Switching root. clare # [ 4.370932] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). alice # [ 4.441638] systemd[1]: Mounting /sysroot/nix/store... bob # [ 4.415737] systemd[1]: Mounting /sysroot/nix/store... alice # [ 4.491857] systemd[1]: Mounted /sysroot/nix/store. alice # [ 4.495032] systemd[1]: Reached target Initrd File Systems. alice # [ 4.498278] systemd[1]: Starting Find NixOS closure... bob # [ 4.465727] systemd[1]: Mounted /sysroot/nix/store. bob # [ 4.468069] systemd[1]: Reached target Initrd File Systems. alice # [ 4.508418] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 4.472120] systemd[1]: Starting Find NixOS closure... bob # [ 4.484279] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... alice # [ 4.541050] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. alice # [ 4.546495] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. alice # [ 4.559799] systemd[1]: Finished Find NixOS closure. alice # [ 4.560827] systemd[1]: Reached target Initrd Default Target. bob # [ 4.524408] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. alice # [ 4.564223] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... bob # [ 4.529999] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob # [ 4.542045] systemd[1]: Finished Find NixOS closure. bob # [ 4.544221] systemd[1]: Reached target Initrd Default Target. bob # [ 4.548275] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 4.591808] systemd[1]: initrd-cleanup.service: Deactivated successfully. alice # [ 4.595764] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. alice # [ 4.596817] systemd[1]: Stopped target Initrd Default Target. alice # [ 4.597644] systemd[1]: Stopped target Basic System. alice # [ 4.600163] systemd[1]: Stopped target Initrd Root Device. alice # [ 4.600940] systemd[1]: Stopped target Path Units. alice # [ 4.602133] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 4.608081] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. alice # [ 4.609059] systemd[1]: Stopped target Slice Units. alice # [ 4.609676] systemd[1]: Stopped target Socket Units. alice # [ 4.610295] systemd[1]: Stopped target System Initialization. alice # [ 4.611012] systemd[1]: Stopped target Swaps. alice # [ 4.611766] systemd[1]: Stopped target Timer Units. alice # [ 4.614454] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 4.577267] systemd[1]: initrd-cleanup.service: Deactivated successfully. alice # [ 4.615712] systemd[1]: Closed D-Bus System Message Bus Socket. bob # [ 4.579205] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. bob # [ 4.583032] systemd[1]: Stopped target Initrd Default Target. bob # [ 4.583853] systemd[1]: Stopped target Basic System. alice # [ 4.620821] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 4.585039] systemd[1]: Stopped target Initrd Root Device. alice # [ 4.624688] systemd[1]: Stopped Find NixOS closure. bob # [ 4.587816] systemd[1]: Stopped target Path Units. bob # [ 4.588727] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 4.625976] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 4.628270] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 4.591191] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. alice # [ 4.629715] systemd[1]: Stopped Apply Kernel Variables. alice # [ 4.630316] systemd[1]: systemd-modules-load.service: Deactivated successfully. alice # [ 4.631068] systemd[1]: Stopped Load Kernel Modules. alice # [ 4.631728] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 4.632829] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. alice # [ 4.633645] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 4.597139] systemd[1]: Stopped target Slice Units. alice # [ 4.634394] systemd[1]: Stopped Create System Files and Directories. bob # [ 4.597790] systemd[1]: Stopped target Socket Units. alice # [ 4.635029] systemd[1]: Stopped target Local File Systems. bob # [ 4.598420] systemd[1]: Stopped target System Initialization. alice # [ 4.635758] systemd[1]: Stopped target Preparation for Local File Systems. bob # [ 4.599468] systemd[1]: Stopped target Swaps. bob # [ 4.604112] systemd[1]: Stopped target Timer Units. bob # [ 4.605671] systemd[1]: dbus.socket: Deactivated successfully. alice # [ 4.643011] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 4.643827] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 4.644583] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 4.607929] systemd[1]: Closed D-Bus System Message Bus Socket. alice # [ 4.645315] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 4.646036] systemd[1]: Stopped Virtual Console Setup. bob # [ 4.608842] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 4.609765] systemd[1]: Stopped Find NixOS closure. alice # [ 4.646589] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 4.647300] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 4.610390] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 4.648285] systemd[1]: systemd-udevd.service: Deactivated successfully. bob # [ 4.611669] systemd[1]: systemd-sysctl.service: Deactivated successfully. alice # [ 4.649007] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 4.649693] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 4.650358] systemd[1]: Closed udev Control Socket. alice # [ 4.650834] systemd[1]: Starting Cleanup udev Database... alice # [ 4.651352] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 4.617443] systemd[1]: Stopped Apply Kernel Variables. alice # [ 4.656223] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 4.660104] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. alice # [ 4.661047] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. bob # [ 4.624260] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 4.625060] systemd[1]: Stopped Load Kernel Modules. alice # [ 4.661850] systemd[1]: kmod-static-nodes.service: Deactivated successfully. alice # [ 4.662530] systemd[1]: Stopped Create List of Static Device Nodes. bob # [ 4.625573] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. bob # [ 4.626321] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. bob # [ 4.627164] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 4.627858] systemd[1]: Stopped Create System Files and Directories. bob # [ 4.630844] systemd[1]: Stopped target Local File Systems. bob # [ 4.631430] systemd[1]: Stopped target Preparation for Local File Systems. bob # [ 4.636578] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 4.673906] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 4.637276] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 4.674669] systemd[1]: Finished Cleanup udev Database. alice # [ 4.675228] systemd[1]: Reached target Switch Root. bob # [ 4.640195] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 4.640972] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 4.641685] systemd[1]: Stopped Virtual Console Setup. alice # [ 4.679481] systemd[1]: Starting NixOS Activation... bob # [ 4.644212] systemd[1]: systemd-udevd.service: Deactivated successfully. bob # [ 4.644836] systemd[1]: Stopped Rule-based Manager for Device Events and Files. bob # [ 4.645473] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. bob # [ 4.648247] systemd[1]: Closed udev Control Socket. bob # [ 4.648736] systemd[1]: Starting Cleanup udev Database... bob # [ 4.649227] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 4.649906] systemd[1]: Stopped Create Static Device Nodes in /dev. bob # [ 4.652386] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. bob # [ 4.653171] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. bob # [ 4.656611] systemd[1]: kmod-static-nodes.service: Deactivated successfully. bob # [ 4.657262] systemd[1]: Stopped Create List of Static Device Nodes. bob # [ 4.657820] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 4.658443] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 4.664049] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 4.664765] systemd[1]: Finished Cleanup udev Database. bob # [ 4.665251] systemd[1]: Reached target Switch Root. bob # [ 4.665718] systemd[1]: Starting NixOS Activation... alice # [ 4.752612] initrd-nixos-activation-start[173]: booting system configuration /nix/store/5mrv0g4znr0sd45nr4sd8pdaz46s4fbl-nixos-system-alice-test bob # [ 4.734616] initrd-nixos-activation-start[174]: booting system configuration /nix/store/3x6i2g70yfi9bj03k2sr6girqyjnhfzm-nixos-system-bob-test alice # [ 4.778960] initrd-nixos-activation-start[173]: running activation script... bob # [ 4.761609] initrd-nixos-activation-start[174]: running activation script... clare # [ 4.907984] 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 # [ 4.908078] systemd[1]: Detected virtualization qemu. clare # [ 4.908137] systemd[1]: Detected architecture arm64. clare # [ 4.908250] systemd[1]: Detected first boot. clare # [ 4.910453] systemd[1]: Initializing machine ID from random generator. alice # [ 4.985685] initrd-nixos-activation-start[196]: setting up /etc... bob # [ 4.968790] initrd-nixos-activation-start[197]: setting up /etc... alice # [ 5.072903] initrd-nixos-activation-start[196]: setting up age secrets... bob # [ 5.061530] initrd-nixos-activation-start[197]: setting up age secrets... alice # [ 5.132511] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. alice # [ 5.134039] systemd[1]: Finished NixOS Activation. alice # [ 5.134742] systemd[1]: Starting Switch Root... alice # [ 5.151952] systemd[1]: Switching root. bob # [ 5.138979] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 5.140829] systemd[1]: Finished NixOS Activation. bob # [ 5.141586] systemd[1]: Starting Switch Root... bob # [ 5.160204] systemd[1]: Switching root. clare # [ 5.239405] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 5.317216] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). bob # [ 5.327599] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). clare # [ 5.365727] systemd[1]: Applying preset policy. clare # [ 5.517552] systemd[1]: Populated /etc with preset unit settings. clare # [ 5.725379] systemd[1]: initrd-switch-root.service: Deactivated successfully. clare # [ 5.725653] systemd[1]: Stopped initrd-switch-root.service. clare # [ 5.728613] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. clare # [ 5.729557] systemd[1]: Created slice Slice /system/getty. clare # [ 5.730474] systemd[1]: Created slice User and Session Slice. clare # [ 5.733351] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 5.733920] systemd[1]: Started Forward Password Requests to Wall Directory Watch. clare # [ 5.734489] systemd[1]: Expecting device /dev/hvc0... clare # [ 5.737291] systemd[1]: Expecting device /dev/ttyAMA0... clare # [ 5.737778] systemd[1]: Reached target Local Encrypted Volumes. clare # [ 5.738357] systemd[1]: Stopped target initrd-fs.target. clare # [ 5.740814] systemd[1]: Stopped target initrd-root-fs.target. clare # [ 5.741276] systemd[1]: Stopped target initrd-switch-root.target. clare # [ 5.741827] systemd[1]: Reached target Virtual Machines and Containers. clare # [ 5.742356] systemd[1]: Reached target Path Units. clare # [ 5.745262] systemd[1]: Reached target Remote File Systems. clare # [ 5.745732] systemd[1]: Reached target Slice Units. clare # [ 5.746283] systemd[1]: Reached target Swaps. clare # [ 5.749838] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 5.752832] systemd[1]: Listening on Process Core Dump Socket. clare # [ 5.754313] systemd[1]: Listening on Credential Encryption/Decryption. clare # [ 5.757001] systemd[1]: Listening on Factory Reset Management. clare # [ 5.757545] systemd[1]: Listening on Hostname Service Socket. clare # [ 5.760848] systemd[1]: Starting Journal Log Access Socket... clare # [ 5.762087] systemd[1]: Listening on Journal Audit Socket. clare # [ 5.765602] systemd[1]: Listening on Console Output Muting Service Socket. clare # [ 5.766241] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 5.768853] systemd[1]: Listening on Network Management Varlink Socket. clare # [ 5.770299] systemd[1]: Listening on Network Management Netlink Socket. clare # [ 5.771994] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. clare # [ 5.772495] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os clare # [ 5.772970] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki clare # [ 5.778529] systemd[1]: Listening on Disk Repartitioning Service Socket. clare # [ 5.779067] systemd[1]: Listening on Resolve Monitor Varlink Socket. clare # [ 5.779618] systemd[1]: Listening on Resolve Service Varlink Socket. clare # [ 5.780159] systemd[1]: Listening on udev Control Socket. clare # [ 5.780714] systemd[1]: Listening on udev Varlink Socket. clare # [ 5.782444] systemd[1]: Mounting Huge Pages File System... alice # [ 5.816519] 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.823482] systemd[1]: Detected virtualization qemu. alice # [ 5.824130] systemd[1]: Detected architecture arm64. clare # [ 5.787327] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 5.827384] systemd[1]: Detected first boot. alice # [ 5.830496] systemd[1]: Initializing machine ID from random generator. clare # [ 5.794671] systemd[1]: Mounting Kernel Debug File System... clare # [ 5.803134] systemd[1]: Mounting Kernel Trace File System... clare # [ 5.812442] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 5.815262] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 5.824542] systemd[1]: Mounting Kernel Configuration File System... clare # [ 5.824968] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm clare # [ 5.830603] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 5.831052] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 5.839421] systemd[1]: Mounting FUSE Control File System... clare # [ 5.842494] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 bob # [ 5.862689] 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 # [ 5.862785] systemd[1]: Detected virtualization qemu. clare # [ 5.871874] systemd[1]: Starting Journal Service... bob # [ 5.862843] systemd[1]: Detected architecture arm64. bob # [ 5.862957] systemd[1]: Detected first boot. bob # [ 5.865659] systemd[1]: Initializing machine ID from random generator. clare # [ 5.886667] systemd[1]: Starting Load Kernel Modules... clare # [ 5.901304] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 5.910750] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 5.911123] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 5.924353] systemd[1]: Starting Coldplug All udev Devices... clare # [ 5.928807] systemd[1]: Listening on Journal Log Access Socket. clare # [ 5.929248] systemd[1]: Mounted Huge Pages File System. clare # [ 5.929611] systemd[1]: Mounted POSIX Message Queue File System. clare # [ 5.929958] systemd[1]: Mounted Kernel Debug File System. clare # [ 5.930300] systemd[1]: Mounted Kernel Trace File System. clare # [ 5.944527] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 5.945167] systemd[1]: Finished Load Kernel Modules. clare # [ 5.959291] systemd[1]: Starting Firewall... clare # [ 5.968133] systemd[1]: Starting Apply Kernel Variables... clare # [ 5.982390] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 5.992079] systemd-journald[283]: Collecting audit messages is enabled. clare # [ 6.001210] systemd[1]: Mounted Kernel Configuration File System. clare # [ 6.008872] systemd[1]: Started Journal Service. clare # [ 6.010633] EXT4-fs (vda): re-mounted 07b9ffc2-93c7-4939-8bcd-5eb6885e5e34. clare # [ 6.007456] systemd[1]: Queued start job for default target Multi-User System. clare # [ 6.008565] systemd[1]: systemd-journald.service: Deactivated successfully. clare # [ 6.009384] systemd-modules-load[284]: Module 'atkbd' is built in clare # [ 6.010034] systemd-modules-load[284]: Module 'loop' is built in clare # [ 6.010661] systemd[1]: Mounted FUSE Control File System. clare # [ 6.022966] systemd[1]: Finished Remount Root and Kernel File Systems. clare # [ 6.030350] systemd-oomd[285]: No swap; memory pressure usage will be degraded clare # [ 6.047740] systemd[1]: Listening on Disk Image Download Service Socket. clare # [ 6.052251] systemd[1]: Starting Flush Journal to Persistent Storage... clare # [ 6.052878] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore clare # [ 6.063328] systemd[1]: Starting Load/Save OS Random Seed... clare # [ 6.063892] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 6.065247] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. alice # [ 6.137373] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 6.109036] systemd[1]: Finished Apply Kernel Variables. clare # [ 6.116748] systemd-journald[283]: Received client request to flush runtime journal. bob # [ 6.155316] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 6.166570] systemd[1]: Starting Network Name Resolution... clare # [ 6.176288] systemd[1]: Finished Load/Save OS Random Seed. clare # [ 6.176875] systemd[1]: Reached target First Boot Complete. clare # [ 6.177885] systemd[1]: Finished Flush Journal to Persistent Storage. alice # [ 6.253926] systemd[1]: Applying preset policy. bob # [ 6.261018] systemd[1]: Applying preset policy. clare # [ 6.292804] systemd-resolved[308]: Positive Trust Anchors: clare # [ 6.293394] systemd-resolved[308]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d clare # [ 6.294275] systemd-resolved[308]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 clare # [ 6.295142] systemd-resolved[308]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test clare # [ 6.345647] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 6.349253] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 6.412399] systemd[1]: Populated /etc with preset unit settings. bob # [ 6.423218] systemd[1]: Populated /etc with preset unit settings. clare # [ 6.484415] systemd-resolved[308]: Using system hostname 'clare'. clare # [ 6.487188] systemd[1]: Started Network Name Resolution. clare # [ 6.508469] systemd[1]: Finished Coldplug All udev Devices. clare # [ 6.519985] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 6.521800] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 6.523177] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 6.573181] systemd-udevd[328]: Using default interface naming scheme 'v261'. alice # [ 6.623631] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 6.624286] systemd[1]: Stopped initrd-switch-root.service. alice # [ 6.627575] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. alice # [ 6.630044] systemd[1]: Created slice Slice /system/getty. alice # [ 6.631049] systemd[1]: Created slice User and Session Slice. alice # [ 6.631612] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 6.632212] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 6.635367] systemd[1]: Expecting device /dev/hvc0... alice # [ 6.635840] systemd[1]: Expecting device /dev/ttyAMA0... alice # [ 6.636413] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 6.638767] systemd[1]: Stopped target initrd-fs.target. alice # [ 6.639234] systemd[1]: Stopped target initrd-root-fs.target. alice # [ 6.639775] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 6.640340] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 6.643231] systemd[1]: Reached target Path Units. alice # [ 6.643686] systemd[1]: Reached target Remote File Systems. alice # [ 6.644234] systemd[1]: Reached target Slice Units. alice # [ 6.646478] systemd[1]: Reached target Swaps. alice # [ 6.648292] systemd[1]: Listening on Query the User Interactively for a Password. alice # [ 6.651704] systemd[1]: Listening on Process Core Dump Socket. alice # [ 6.653831] systemd[1]: Listening on Credential Encryption/Decryption. alice # [ 6.655442] systemd[1]: Listening on Factory Reset Management. alice # [ 6.655989] systemd[1]: Listening on Hostname Service Socket. alice # [ 6.660097] systemd[1]: Starting Journal Log Access Socket... alice # [ 6.661665] systemd[1]: Listening on Journal Audit Socket. clare # [ 6.622598] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 6.663779] systemd[1]: Listening on Console Output Muting Service Socket. alice # [ 6.664436] systemd[1]: Listening on Network Management Metrics Varlink Socket. alice # [ 6.667154] systemd[1]: Listening on Network Management Varlink Socket. alice # [ 6.667785] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 6.633643] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 6.668491] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 6.633907] systemd[1]: Stopped initrd-switch-root.service. alice # [ 6.671341] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 6.671900] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki bob # [ 6.636998] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 6.638203] systemd[1]: Created slice Slice /system/getty. bob # [ 6.641007] systemd[1]: Created slice User and Session Slice. bob # [ 6.641582] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 6.642184] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 6.677898] systemd[1]: Listening on Disk Repartitioning Service Socket. alice # [ 6.678417] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 6.644930] systemd[1]: Expecting device /dev/hvc0... bob # [ 6.645416] systemd[1]: Expecting device /dev/ttyAMA0... bob # [ 6.645987] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 6.678957] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 6.679620] systemd[1]: Listening on udev Control Socket. alice # [ 6.680191] systemd[1]: Listening on udev Varlink Socket. bob # [ 6.648409] systemd[1]: Stopped target initrd-fs.target. bob # [ 6.648886] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 6.649470] systemd[1]: Stopped target initrd-switch-root.target. bob # [ 6.650049] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 6.685008] systemd[1]: Mounting Huge Pages File System... alice # [ 6.686580] systemd[1]: Mounting POSIX Message Queue File System... bob # [ 6.652997] systemd[1]: Reached target Path Units. bob # [ 6.653468] systemd[1]: Reached target Remote File Systems. bob # [ 6.654021] systemd[1]: Reached target Slice Units. bob # [ 6.656288] systemd[1]: Reached target Swaps. bob # [ 6.658182] systemd[1]: Listening on Query the User Interactively for a Password. bob # [ 6.661226] systemd[1]: Listening on Process Core Dump Socket. alice # [ 6.696638] systemd[1]: Mounting Kernel Debug File System... alice # [ 6.698323] systemd[1]: Mounting Kernel Trace File System... bob # [ 6.663263] systemd[1]: Listening on Credential Encryption/Decryption. bob # [ 6.664882] systemd[1]: Listening on Factory Reset Management. bob # [ 6.665437] systemd[1]: Listening on Hostname Service Socket. bob # [ 6.669295] systemd[1]: Starting Journal Log Access Socket... bob # [ 6.670380] systemd[1]: Listening on Journal Audit Socket. bob # [ 6.674081] systemd[1]: Listening on Console Output Muting Service Socket. alice # [ 6.709448] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 6.676013] systemd[1]: Listening on Network Management Metrics Varlink Socket. bob # [ 6.677427] systemd[1]: Listening on Network Management Varlink Socket. bob # [ 6.678000] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 6.713188] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 6.680707] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 6.681188] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os bob # [ 6.681702] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki alice # [ 6.719154] systemd[1]: Mounting Kernel Configuration File System... alice # [ 6.721804] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm bob # [ 6.687345] systemd[1]: Listening on Disk Repartitioning Service Socket. bob # [ 6.687871] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 6.688404] systemd[1]: Listening on Resolve Service Varlink Socket. bob # [ 6.688942] systemd[1]: Listening on udev Control Socket. bob # [ 6.689501] systemd[1]: Listening on udev Varlink Socket. bob # [ 6.693739] systemd[1]: Mounting Huge Pages File System... alice # [ 6.728995] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore bob # [ 6.696090] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 6.729357] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 6.740399] systemd[1]: Mounting FUSE Control File System... alice # [ 6.743794] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 bob # [ 6.706732] systemd[1]: Mounting Kernel Debug File System... bob # [ 6.712555] systemd[1]: Mounting Kernel Trace File System... bob # [ 6.721704] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 6.722117] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 6.723591] systemd[1]: Mounting /run/wrappers... bob # [ 6.731927] systemd[1]: Mounting Kernel Configuration File System... bob # [ 6.735187] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 6.774466] systemd[1]: Starting Journal Service... bob # [ 6.740079] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore bob # [ 6.741446] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 6.788060] systemd[1]: Starting Load Kernel Modules... clare # [ 6.756954] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 6.798689] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... bob # [ 6.768972] systemd[1]: Mounting FUSE Control File System... bob # [ 6.769359] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 alice # [ 6.812798] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 6.774129] systemd[1]: Mounted /run/wrappers. alice # [ 6.814063] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 6.774625] systemd[1]: Reached target Local File Systems. clare # [ 6.778895] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 6.783163] systemd[1]: Starting register-nix-paths.service... alice # [ 6.827717] systemd[1]: Starting Coldplug All udev Devices... clare # [ 6.786346] systemd[1]: Starting Create SUID/SGID Wrappers... bob # [ 6.793218] systemd[1]: Starting Journal Service... clare # [ 6.786925] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. clare # [ 6.792250] systemd[1]: Starting Save Transient machine-id to Disk... alice # [ 6.833954] systemd[1]: Listening on Journal Log Access Socket. alice # [ 6.834400] systemd[1]: Mounted Huge Pages File System. alice # [ 6.834770] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 6.801717] systemd[1]: Starting Load Kernel Modules... alice # [ 6.835101] systemd[1]: Mounted Kernel Debug File System. alice # [ 6.835424] systemd[1]: Mounted Kernel Trace File System. clare # [ 6.800495] systemd[1]: Starting Create System Files and Directories... alice # [ 6.843568] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 6.812028] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 6.809071] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 6.855846] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 6.859621] systemd[1]: Finished Load Kernel Modules. bob # [ 6.823207] systemd[1]: Starting Remount Root and Kernel File Systems... bob # [ 6.823576] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 6.862310] systemd-journald[281]: Collecting audit messages is enabled. alice # [ 6.868500] systemd[1]: Starting Firewall... alice # [ 6.869080] systemd[1]: Queued start job for default target Multi-User System. alice # [ 6.872834] systemd[1]: systemd-journald.service: Deactivated successfully. bob # [ 6.841616] systemd[1]: Starting Coldplug All udev Devices... alice # [ 6.873610] systemd-modules-load[282]: Module 'atkbd' is built in alice # [ 6.874257] systemd-modules-load[282]: Module 'loop' is built in alice # [ 6.881477] systemd[1]: Starting Apply Kernel Variables... alice # [ 6.881958] systemd[1]: Started Journal Service. bob # [ 6.847919] systemd[1]: Listening on Journal Log Access Socket. bob # [ 6.848372] systemd[1]: Mounted Huge Pages File System. bob # [ 6.848735] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 6.849104] systemd[1]: Mounted Kernel Debug File System. bob # [ 6.849444] systemd[1]: Mounted Kernel Trace File System. bob # [ 6.867746] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 6.868313] systemd[1]: Mounted Kernel Configuration File System. bob # [ 6.873148] systemd-journald[290]: Collecting audit messages is enabled. bob # [ 6.880976] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 6.886691] systemd[1]: Finished Load Kernel Modules. bob # [ 6.887183] systemd[1]: Started Journal Service. bob # [ 6.885397] systemd[1]: Queued start job for default target Multi-User System. bob # [ 6.886264] systemd[1]: systemd-journald.service: Deactivated successfully. bob # [ 6.887066] systemd-modules-load[291]: Module 'atkbd' is built in bob # [ 6.887726] systemd-modules-load[291]: Module 'loop' is built in alice # [ 6.930826] EXT4-fs (vda): re-mounted 85c4d184-e71d-4416-a635-a78665477d19. clare # [ 6.897373] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. alice # [ 6.937014] systemd[1]: Mounted Kernel Configuration File System. bob # [ 6.902343] systemd[1]: Starting Firewall... alice # [ 6.941086] systemd[1]: Mounted FUSE Control File System. alice # [ 6.944080] systemd[1]: Finished Remount Root and Kernel File Systems. clare # [ 6.906047] systemd[1]: Finished Save Transient machine-id to Disk. alice # [ 6.944705] systemd[1]: Listening on Disk Image Download Service Socket. bob # [ 6.912077] systemd[1]: Starting Apply Kernel Variables... alice # [ 6.956259] systemd[1]: Starting Flush Journal to Persistent Storage... alice # [ 6.956897] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore alice # [ 6.966699] systemd[1]: Starting Load/Save OS Random Seed... alice # [ 6.967253] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 6.934536] EXT4-fs (vda): re-mounted 69bd0452-41c9-4514-a862-d6c3778b8fed. alice # [ 6.973483] systemd-oomd[283]: No swap; memory pressure usage will be degraded bob # [ 6.945124] systemd[1]: Mounted FUSE Control File System. alice # [ 6.991553] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 6.956406] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 6.962531] systemd[1]: Listening on Disk Image Download Service Socket. clare # [ 6.963031] systemd[1]: Finished Create System Files and Directories. bob # [ 6.968379] systemd-oomd[292]: No swap; memory pressure usage will be degraded bob # [ 6.971145] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 6.971753] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore clare # [ 6.971361] systemd[1]: Starting Rebuild Journal Catalog... clare # [ 6.976400] systemd[1]: Starting Record System Boot/Shutdown in UTMP... bob # [ 6.984332] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 6.984882] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 6.985617] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. alice # [ 7.033719] systemd-journald[281]: Received client request to flush runtime journal. alice # [ 7.076381] systemd[1]: Finished Load/Save OS Random Seed. alice # [ 7.076966] systemd[1]: Reached target First Boot Complete. alice # [ 7.077500] systemd[1]: Finished Apply Kernel Variables. bob # [ 7.044395] systemd[1]: Finished Load/Save OS Random Seed. bob # [ 7.044976] systemd[1]: Reached target First Boot Complete. alice # [ 7.082131] systemd[1]: Starting Network Name Resolution... alice # [ 7.088158] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 7.059298] systemd-journald[290]: Received client request to flush runtime journal. clare # [ 7.062723] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 7.073137] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. alice # [ 7.121438] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 7.122955] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 7.098325] systemd[1]: Finished Apply Kernel Variables. bob # [ 7.098923] systemd[1]: Starting Network Name Resolution... bob # [ 7.105410] systemd[1]: Finished Flush Journal to Persistent Storage. clare # [ 7.110522] systemd[1]: Finished Rebuild Journal Catalog. clare # [ 7.116149] systemd[1]: Starting Update is Completed... bob # [ 7.124805] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 7.128143] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 7.146431] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. clare # [ 7.174239] systemd[1]: Finished Update is Completed. alice # [ 7.224757] systemd-resolved[309]: Positive Trust Anchors: alice # [ 7.225339] systemd-resolved[309]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d alice # [ 7.226226] systemd-resolved[309]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 alice # [ 7.227080] systemd-resolved[309]: 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 # [ 7.232814] systemd-resolved[317]: Positive Trust Anchors: bob # [ 7.233415] systemd-resolved[317]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d bob # [ 7.234265] systemd-resolved[317]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 bob # [ 7.235141] 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.397660] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 7.398282] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 7.400206] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 7.373919] (udev-worker)[352]: Network interface NamePolicy= disabled on kernel command line. alice # [ 7.416448] systemd-resolved[309]: Using system hostname 'alice'. clare # [ 7.381002] (udev-worker)[360]: Network interface NamePolicy= disabled on kernel command line. alice # [ 7.420837] systemd[1]: Started Network Name Resolution. bob # [ 7.397641] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 7.398289] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 7.399841] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 7.406440] systemd-resolved[317]: Using system hostname 'bob'. bob # [ 7.409349] systemd[1]: Started Network Name Resolution. alice # [ 7.453848] systemd[1]: Finished Coldplug All udev Devices. alice # [ 7.484487] systemd-udevd[320]: Using default interface naming scheme 'v261'. bob # [ 7.454598] systemd[1]: Finished Coldplug All udev Devices. bob # [ 7.488189] systemd-udevd[332]: Using default interface naming scheme 'v261'. alice # [ 7.536124] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 7.536217] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 7.622010] systemd[1]: Mounting /run/wrappers... alice # [ 7.659198] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 7.625423] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. clare # [ 7.627314] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 7.630787] systemd[1]: Mounting /run/wrappers... alice # [ 7.669311] systemd[1]: Mounted /run/wrappers. alice # [ 7.669811] systemd[1]: Reached target Local File Systems. clare # [ 7.633829] systemd[1]: Finished register-nix-paths.service. alice # [ 7.673998] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 7.636726] systemd[1]: Reached target System Initialization. clare # [ 7.639140] systemd[1]: Started Discard unused filesystem blocks once a week. alice # [ 7.677477] systemd[1]: Starting register-nix-paths.service... clare # [ 7.639817] systemd[1]: Started Daily Cleanup of Temporary Directories. clare # [ 7.642375] systemd[1]: Reached target Timer Units. alice # [ 7.681120] systemd[1]: Starting Create SUID/SGID Wrappers... clare # [ 7.642852] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 7.683010] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. alice # [ 7.686501] systemd[1]: Starting Save Transient machine-id to Disk... clare # [ 7.650238] systemd[1]: Listening on harmonia binary cache socket. clare # [ 7.650848] systemd[1]: Listening on Nix Daemon Socket. clare # [ 7.651328] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. clare # [ 7.658878] mousedev: PS/2 mouse device common for all mice clare # [ 7.656728] systemd[1]: Reached target Socket Units. clare # [ 7.657264] systemd[1]: Reached target Basic System. clare # [ 7.657733] systemd[1]: Started backdoor.service. clare # [ 7.658175] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 7.698740] systemd[1]: Starting Create System Files and Directories... alice # [ 7.699398] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 7.666459] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 7.666610] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 7.676879] systemd[1]: Mounted /run/wrappers. bob # [ 7.677376] systemd[1]: Reached target Local File Systems. clare # [ 7.677959] systemd[1]: Starting Post-Boot Actions... bob # [ 7.682394] systemd[1]: Listening on Boot Loader Control Service Socket. bob # [ 7.686570] systemd[1]: Starting register-nix-paths.service... bob # [ 7.689711] systemd[1]: Starting Create SUID/SGID Wrappers... bob # [ 7.690270] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. bob # [ 7.695185] systemd[1]: Starting Save Transient machine-id to Disk... clare # [ 7.705125] systemd[1]: Started Reset console on configuration changes. bob # [ 7.711195] systemd[1]: Starting Create System Files and Directories... bob # [ 7.711870] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 7.735952] systemd[1]: Starting D-Bus System Message Bus... alice # [ 7.810691] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. alice # [ 7.816546] systemd[1]: Finished Save Transient machine-id to Disk. clare # connecting to host... clare # [ 7.801371] systemd[1]: Finished Post-Boot Actions. bob # [ 7.805438] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. bob # [ 7.812441] systemd[1]: Finished Save Transient machine-id to Disk. clare # [ 7.815229] systemd[1]: Started Name Service Cache Daemon (nsncd). clare # [ 7.815870] systemd[1]: Reached target Host and Network Name Lookups. clare # [ 7.820930] nsncd[480]: Sep 27 17:41:45.655 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # [ 7.822161] systemd[1]: Reached target User and Group Name Lookups. alice # [ 7.862725] systemd[1]: Finished Create System Files and Directories. clare # [ 7.834269] systemd[1]: Starting User Login Management... alice # [ 7.873669] systemd[1]: Starting Rebuild Journal Catalog... alice # [ 7.880790] systemd[1]: Starting Record System Boot/Shutdown in UTMP... bob # [ 7.861500] systemd[1]: Finished Create System Files and Directories. clare # [ 7.868254] systemd[1]: Finished Import lastlog data into lastlog2 database. bob # [ 7.870616] systemd[1]: Starting Rebuild Journal Catalog... bob # [ 7.879121] systemd[1]: Starting Record System Boot/Shutdown in UTMP... clare # [ 7.934094] dbus-broker-launch[488]: Looking up NSS user entry for 'systemd-timesync'... alice # [ 7.976087] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 7.942496] dbus-broker-launch[488]: NSS returned no entry for 'systemd-timesync' clare # [ 7.943169] dbus-broker-launch[488]: Invalid user-name in /nix/store/6g1ahficw481g361kpzn9p34kp6lxn53-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 7.994435] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. clare # [ 7.956349] systemd-logind[502]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 7.961719] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 7.959523] systemd-logind[502]: Watching system buttons on /dev/input/event0 (gpio-keys) clare # [ 7.961214] systemd-logind[502]: New seat seat0. clare # [ 7.964844] systemd[1]: Started User Login Management. clare # [ 7.968158] systemd[1]: Starting linger-users.service... alice # [ 8.009623] systemd[1]: Finished Rebuild Journal Catalog. alice # [ 8.017838] systemd[1]: Starting Update is Completed... alice # [ 8.019303] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. clare # [ 7.985561] systemd[1]: Started D-Bus System Message Bus. bob # [ 8.001118] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. bob # [ 8.012503] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 8.019436] systemd[1]: Starting Update is Completed... clare # [ 8.020999] dbus-broker-launch[488]: Ready alice # [ 8.072369] systemd[1]: Finished Update is Completed. clare # [ 8.037893] systemd[1]: linger-users.service: Deactivated successfully. clare # [ 8.039784] systemd[1]: Finished linger-users.service. bob # [ 8.057067] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. clare # [ 8.083289] systemd[1]: Finished Firewall. bob # [ 8.086181] systemd[1]: Finished Update is Completed. clare # [ 8.085028] systemd[1]: Reached target Preparation for Network. clare # [ 8.088936] systemd[1]: Listening on Network Management Resolve Hook Socket. clare # [ 8.091989] systemd[1]: Starting Network Management... alice # [ 8.282730] (udev-worker)[347]: Network interface NamePolicy= disabled on kernel command line. alice # [ 8.291688] (udev-worker)[341]: Network interface NamePolicy= disabled on kernel command line. clare # [ 8.265373] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 8.297050] (udev-worker)[351]: Network interface NamePolicy= disabled on kernel command line. clare # [ 8.306413] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' bob # [ 8.303479] (udev-worker)[353]: Network interface NamePolicy= disabled on kernel command line. clare # [ 8.307495] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' clare # [ 8.309159] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 clare # [ 8.309528] cfg80211: failed to load regulatory.db clare # [ 8.334704] systemd-networkd[541]: lo: Link UP clare # [ 8.336436] systemd-networkd[541]: lo: Gained carrier clare # [ 8.343616] systemd[1]: Started Network Management. clare # [ 8.350409] systemd[1]: Reached target Network. clare # [ 8.350886] systemd-networkd[541]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. clare # [ 8.351717] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 8.359589] systemd-networkd[541]: eth1: Configuring with /etc/systemd/network/40-eth1.network. clare # [ 8.363281] systemd-networkd[541]: eth0: Link UP clare # [ 8.363723] systemd-networkd[541]: eth1: Link UP clare # [ 8.366556] systemd-networkd[541]: eth0: Gained carrier clare # [ 8.367038] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 8.367676] systemd-networkd[541]: eth1: Gained carrier clare # [ 8.373710] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 8.375046] systemd[1]: Starting Permit User Sessions... clare # [ 8.381695] systemd-networkd[541]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 clare # [ 8.437703] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 8.440202] systemd[1]: Finished Permit User Sessions. clare # [ 8.444357] systemd[1]: Started Getty on tty1. clare # [ 8.446013] systemd[1]: Reached target Login Prompts. clare # [ 8.448244] systemd[1]: Reached target Multi-User System. clare # [ 8.448815] systemd[1]: Startup finished in 707ms (kernel) + 3.764s (initrd) + 3.975s (userspace) = 8.447s. alice # [ 8.493134] mousedev: PS/2 mouse device common for all mice alice # [ 8.504782] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. alice # [ 8.505479] systemd[1]: Finished Create SUID/SGID Wrappers. alice # [ 8.526965] systemd[1]: Finished register-nix-paths.service. alice # [ 8.528745] systemd[1]: Reached target System Initialization. alice # [ 8.532146] systemd[1]: Started Discard unused filesystem blocks once a week. alice # [ 8.532829] systemd[1]: Started Daily Cleanup of Temporary Directories. alice # [ 8.533436] systemd[1]: Reached target Timer Units. alice # [ 8.533922] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 8.534511] systemd[1]: Listening on Nix Daemon Socket. alice # [ 8.534996] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 8.535748] systemd[1]: Reached target Socket Units. alice # [ 8.548757] systemd[1]: Reached target Basic System. alice # [ 8.549253] systemd[1]: Started backdoor.service. alice # [ 8.549699] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 8.550327] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 8.516655] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. bob # [ 8.520161] systemd[1]: Finished Create SUID/SGID Wrappers. alice # [ 8.559786] systemd[1]: Starting Post-Boot Actions... bob # [ 8.528019] mousedev: PS/2 mouse device common for all mice alice # [ 8.583771] systemd[1]: Started Reset console on configuration changes. alice # [ 8.609419] systemd[1]: Starting D-Bus System Message Bus... bob # [ 8.582023] systemd[1]: Finished register-nix-paths.service. bob # [ 8.583874] systemd[1]: Reached target System Initialization. bob # [ 8.586821] systemd[1]: Started Discard unused filesystem blocks once a week. bob # [ 8.587473] systemd[1]: Started Daily Cleanup of Temporary Directories. bob # [ 8.591770] systemd[1]: Reached target Timer Units. bob # [ 8.593280] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 8.593890] systemd[1]: Listening on Nix Daemon Socket. bob # [ 8.594382] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. bob # [ 8.595164] systemd[1]: Reached target Socket Units. bob # [ 8.595654] systemd[1]: Reached target Basic System. bob # [ 8.604614] systemd[1]: Started backdoor.service. bob # [ 8.605094] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 8.609603] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 8.614100] systemd[1]: Starting Post-Boot Actions... alice # connecting to host... bob # [ 8.635732] systemd[1]: Started Reset console on configuration changes. alice # [ 8.676879] systemd[1]: Finished Post-Boot Actions. clare # [ 8.643764] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 8.667343] systemd[1]: Starting D-Bus System Message Bus... alice # [ 8.708513] systemd[1]: Started Name Service Cache Daemon (nsncd). alice # [ 8.709319] nsncd[468]: Sep 27 17:41:46.548 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" alice # [ 8.710594] systemd[1]: Reached target Host and Network Name Lookups. alice # [ 8.711165] systemd[1]: Reached target User and Group Name Lookups. alice # [ 8.722370] systemd[1]: Starting User Login Management... alice # [ 8.737201] systemd[1]: Finished Import lastlog data into lastlog2 database. bob # connecting to host... bob # [ 8.742634] systemd[1]: Finished Post-Boot Actions. bob # [ 8.768194] systemd[1]: Started Name Service Cache Daemon (nsncd). bob # [ 8.768818] systemd[1]: Reached target Host and Network Name Lookups. bob # [ 8.769399] systemd[1]: Reached target User and Group Name Lookups. alice # [ 8.813789] dbus-broker-launch[473]: Looking up NSS user entry for 'systemd-timesync'... bob # [ 8.777937] nsncd[480]: Sep 27 17:41:46.609 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" bob # [ 8.784793] systemd[1]: Starting User Login Management... bob: Guest shell says: b'Spawning backdoor root shell...\n' alice # [ 8.822241] dbus-broker-launch[473]: NSS returned no entry for 'systemd-timesync' alice # [ 8.822881] dbus-broker-launch[473]: Invalid user-name in /nix/store/di4gbnzycnxil7gz63i2i532jvnffip4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 8.835492] systemd-logind[499]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) alice # [ 8.837825] systemd-logind[499]: Watching system buttons on /dev/input/event0 (gpio-keys) alice # [ 8.839367] systemd-logind[499]: New seat seat0. alice # [ 8.843263] systemd[1]: Started User Login Management. alice # [ 8.846894] systemd[1]: Starting linger-users.service... bob # [ 8.816174] systemd[1]: Finished Import lastlog data into lastlog2 database. alice # [ 8.865584] systemd[1]: Started D-Bus System Message Bus. bob # [ 8.837444] dbus-broker-launch[487]: Looking up NSS user entry for 'systemd-timesync'... bob # [ 8.853459] dbus-broker-launch[487]: NSS returned no entry for 'systemd-timesync' bob # [ 8.854081] dbus-broker-launch[487]: Invalid user-name in /nix/store/rhq6y0h3jpqv8wrzxv2gm05w8rslxbwf-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 8.899602] dbus-broker-launch[473]: Ready alice # [ 8.913245] systemd[1]: linger-users.service: Deactivated successfully. alice # [ 8.915704] systemd[1]: Finished linger-users.service. bob # [ 8.899004] systemd[1]: Started D-Bus System Message Bus. bob # [ 8.904219] systemd-logind[513]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 8.906591] systemd-logind[513]: Watching system buttons on /dev/input/event0 (gpio-keys) bob # [ 8.907974] systemd-logind[513]: New seat seat0. clare # [ 8.916556] systemd[1]: Starting Virtual Console Setup... bob # [ 8.912310] systemd[1]: Started User Login Management. bob: connected to guest root shell bob # [ 8.916180] systemd[1]: Starting linger-users.service... bob: (connecting took 9.19 seconds) bob: (finished: waiting for the VM to finish booting, in 9.19 seconds) bob # [ 8.951603] dbus-broker-launch[487]: Ready clare # [ 8.952189] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. clare # [ 8.962150] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 8.965372] systemd[1]: Stopped Virtual Console Setup. clare # [ 8.969317] systemd[1]: Starting Virtual Console Setup... clare # [ 8.974065] systemd-logind[502]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) alice # [ 9.016962] systemd[1]: Finished Firewall. alice # [ 9.019574] systemd[1]: Reached target Preparation for Network. alice # [ 9.021483] systemd[1]: Listening on Network Management Resolve Hook Socket. alice # [ 9.024834] systemd[1]: Starting Network Management... bob # [ 8.988403] systemd[1]: linger-users.service: Deactivated successfully. bob # [ 8.990610] systemd[1]: Finished linger-users.service. clare # [ 9.070110] systemd-vconsole-setup[561]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 9.073036] systemd[1]: Finished Virtual Console Setup. bob # [ 9.096768] systemd[1]: Finished Firewall. bob # [ 9.098375] systemd[1]: Reached target Preparation for Network. bob # [ 9.100584] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # [ 9.105383] systemd[1]: Starting Network Management... alice # [ 9.191974] cfg80211: Loading compiled-in X.509 certificates for regulatory database alice # [ 9.235683] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' alice # [ 9.236213] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' alice # [ 9.239440] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 alice # [ 9.239809] cfg80211: failed to load regulatory.db alice # [ 9.265200] systemd-networkd[536]: lo: Link UP alice # [ 9.268096] systemd-networkd[536]: lo: Gained carrier alice # [ 9.271542] systemd[1]: Started Network Management. alice # [ 9.273896] systemd-networkd[536]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. alice # [ 9.277216] systemd[1]: Reached target Network. alice # [ 9.283131] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 9.286778] systemd-networkd[536]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 9.287513] systemd-networkd[536]: eth0: Link UP alice # [ 9.287909] systemd-networkd[536]: eth0: Gained carrier alice # [ 9.296426] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... alice # [ 9.297074] systemd-networkd[536]: eth1: Link UP alice # [ 9.297476] systemd-networkd[536]: eth1: Gained carrier alice # [ 9.297935] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 9.299233] systemd[1]: Starting Permit User Sessions... bob # [ 9.273940] cfg80211: Loading compiled-in X.509 certificates for regulatory database alice # [ 9.306590] systemd-networkd[536]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 9.320596] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' bob # [ 9.321124] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' alice # [ 9.357169] systemd[1]: Finished Permit User Sessions. bob # [ 9.323856] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 bob # [ 9.324229] cfg80211: failed to load regulatory.db alice # [ 9.359293] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. alice # [ 9.364966] systemd[1]: Started Getty on tty1. alice # [ 9.366721] systemd[1]: Reached target Login Prompts. alice # [ 9.368384] systemd[1]: Reached target Multi-User System. alice # [ 9.376382] systemd[1]: Startup finished in 734ms (kernel) + 4.651s (initrd) + 3.989s (userspace) = 9.376s. bob # [ 9.346998] systemd-networkd[558]: lo: Link UP bob # [ 9.350607] systemd-networkd[558]: lo: Gained carrier bob # [ 9.355115] systemd[1]: Started Network Management. bob # [ 9.359255] systemd[1]: Reached target Network. bob # [ 9.359724] systemd[1]: Reached target Network is Online. bob # [ 9.366104] systemd-networkd[558]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 9.367448] systemd-networkd[558]: eth1: Configuring with /etc/systemd/network/40-eth1.network. bob # [ 9.374017] systemd-networkd[558]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. bob # [ 9.374899] systemd-networkd[558]: eth1: Link UP bob # [ 9.375329] systemd-networkd[558]: eth1: Gained carrier bob # [ 9.375777] systemd-networkd[558]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 9.389148] systemd[1]: Starting ncps binary cache proxy service... bob # [ 9.389736] systemd-networkd[558]: eth0: Link UP bob # [ 9.390143] systemd-networkd[558]: eth0: Gained carrier bob # [ 9.390605] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... bob # [ 9.393969] systemd-networkd[558]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 9.394857] systemd[1]: Starting Permit User Sessions... bob # [ 9.478094] systemd[1]: Finished Permit User Sessions. alice # [ 9.523380] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 9.486023] systemd[1]: Started Getty on tty1. bob # [ 9.489314] systemd[1]: Reached target Login Prompts. bob # [ 9.492856] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. bob # [ 9.516740] ncps-pre-start[565]: /nix/store/0m8la0b5a7q97yz1mc9c2x9xb5mc67vz-ncps-0.9.4/bin/dbmate-ncps up clare # [ 9.569657] systemd-networkd[541]: eth0: Gained IPv6LL bob # [ 9.704733] ncps-pre-start[580]: Creating: /var/lib/ncps/db/db.sqlite bob # [ 9.722409] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 9.727783] ncps-pre-start[580]: Applying: 20241210054814_create-narinfos-table.sql bob # [ 9.735293] ncps-pre-start[580]: Applied: 20241210054814_create-narinfos-table.sql in 7.48148ms bob # [ 9.736286] ncps-pre-start[580]: Applying: 20241210054829_create-nars-table.sql bob # [ 9.744314] ncps-pre-start[580]: Applied: 20241210054829_create-nars-table.sql in 8.90976ms bob # [ 9.745036] ncps-pre-start[580]: Applying: 20241213014846_add-query-to-nars-table.sql bob # [ 9.751808] ncps-pre-start[580]: Applied: 20241213014846_add-query-to-nars-table.sql in 7.57024ms bob # [ 9.752941] ncps-pre-start[580]: Applying: 20251230224159_add-cascade-to-nars-fk.sql bob # [ 9.765488] ncps-pre-start[580]: Applied: 20251230224159_add-cascade-to-nars-fk.sql in 13.6636ms alice # [ 9.803590] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 9.767231] ncps-pre-start[580]: Applying: 20260101000000_add-config-table.sql alice # [ 9.805691] systemd[1]: Starting Virtual Console Setup... bob # [ 9.776203] ncps-pre-start[580]: Applied: 20260101000000_add-config-table.sql in 10.6994ms bob # [ 9.777119] ncps-pre-start[580]: Applying: 20260105025735_remove-redundant-indexes.sql bob # [ 9.782286] ncps-pre-start[580]: Applied: 20260105025735_remove-redundant-indexes.sql in 6.07504ms bob # [ 9.784374] ncps-pre-start[580]: Applying: 20260105030513_support_shared_nars_between_narinfos.sql alice # [ 9.827413] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 9.830504] systemd[1]: Stopped Virtual Console Setup. bob # [ 9.795926] ncps-pre-start[580]: Applied: 20260105030513_support_shared_nars_between_narinfos.sql in 13.62516ms bob # [ 9.797042] ncps-pre-start[580]: Applying: 20260117195000_add_narinfo_de_normalized.sql alice # [ 9.834413] systemd[1]: Starting Virtual Console Setup... alice # [ 9.841317] systemd-logind[499]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 9.806432] ncps-pre-start[580]: Applied: 20260117195000_add_narinfo_de_normalized.sql in 10.45412ms bob # [ 9.807433] ncps-pre-start[580]: Applying: 20260127223000_allow_multiple_nar_representations.sql bob # [ 9.816216] ncps-pre-start[580]: Applied: 20260127223000_allow_multiple_nar_representations.sql in 9.81264ms bob # [ 9.817256] ncps-pre-start[580]: Applying: 20260131021850_add_chunks.sql bob # [ 9.828196] ncps-pre-start[580]: Applied: 20260131021850_add_chunks.sql in 11.96104ms bob # [ 9.830724] ncps-pre-start[580]: Applying: 20260205063651_add_total_chunks_to_nar_files.sql bob # [ 9.840202] ncps-pre-start[580]: Applied: 20260205063651_add_total_chunks_to_nar_files.sql in 11.99112ms bob # [ 9.844072] ncps-pre-start[580]: Applying: 20260217071237_add_chunking_started_at_to_nar_files.sql bob # [ 9.852196] ncps-pre-start[580]: Applied: 20260217071237_add_chunking_started_at_to_nar_files.sql in 11.98144ms bob # [ 9.854063] ncps-pre-start[580]: Applying: 20260301000000_add_verified_at_to_nar_files.sql bob # [ 9.864516] ncps-pre-start[580]: Applied: 20260301000000_add_verified_at_to_nar_files.sql in 11.97956ms alice # [ 9.929974] systemd-vconsole-setup[555]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 9.933025] systemd[1]: Finished Virtual Console Setup. bob # [ 10.009852] systemd[1]: Started ncps binary cache proxy service. bob # [ 10.010463] systemd[1]: Reached target Multi-User System. bob # [ 10.014147] systemd[1]: Startup finished in 709ms (kernel) + 4.682s (initrd) + 4.618s (userspace) = 10.011s. bob: (finished: waiting for unit ncps, in 10.44 seconds) bob: must succeed: systemctl status ncps bob # [ 10.172171] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 10.185515] systemd[1]: Starting Virtual Console Setup... clare # [ 10.210597] systemd-networkd[541]: eth1: Gained IPv6LL bob # [ 10.214258] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 10.218051] systemd[1]: Stopped Virtual Console Setup. bob # [ 10.225282] systemd[1]: Starting Virtual Console Setup... bob # [ 10.233651] systemd-logind[513]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob: (finished: must succeed: systemctl status ncps, in 0.08 seconds) clare: waiting for unit harmonia.socket clare: waiting for the VM to finish booting clare: Guest shell says: b'Spawning backdoor root shell...\n' clare: connected to guest root shell clare: (connecting took 0.00 seconds) clare: (finished: waiting for the VM to finish booting, in 0.00 seconds) clare: (finished: waiting for unit harmonia.socket, in 0.05 seconds) alice: waiting for success: curl bob:8502/nix-cache-info alice: waiting for the VM to finish booting alice: Guest shell says: b'Spawning backdoor root shell...\n' alice: connected to guest root shell alice: (connecting took 0.00 seconds) alice: (finished: waiting for the VM to finish booting, in 0.00 seconds) bob # [ 10.361087] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","log_level":"info","time":"2026-09-27T17:41:48Z","message":"logger created"} bob # [ 10.362757] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","xz-binary-path":"/nix/store/hrvsv5knsgy3kjqykgh75d0yfxm92j8g-xz-5.8.3-bin/bin/xz","time":"2026-09-27T17:41:48Z","message":"Using xz binary for xz decompression"} bob # [ 10.380908] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-09-27T17:41:48Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"} bob # [ 10.383309] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","time":"2026-09-27T17:41:48Z","message":"using local locks (single-instance mode)"} bob # [ 10.389716] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-09-27T17:41:48Z","message":"setting up tracer provider to discard traces"} bob # [ 10.393114] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-09-27T17:41:48Z","message":"setting up meter provider to discard metrics"} bob # [ 10.396073] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-09-27T17:41:48Z","message":"setting up logger provider to discard logs"} bob # [ 10.404034] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-09-27T17:41:48Z","message":"Reporting anonymous metrics to the project maintainers"} bob # [ 10.405579] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-09-27T17:41:48Z","message":"failed to parse netrc file, proceeding without netrc authentication"} bob # [ 10.407339] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-09-27T17:41:48Z","message":"using local storage"} clare # [ 10.433203] systemd[1]: Starting harmonia binary cache service... bob # [ 10.456226] systemd-vconsole-setup[605]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 10.458770] systemd[1]: Finished Virtual Console Setup. alice # [ 10.594793] systemd-networkd[536]: eth1: Gained IPv6LL clare # [ 10.573261] harmonia-cache[568]: 2026-09-27T17:41:48.410842Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000)) clare # [ 10.578060] harmonia-cache[568]: 2026-09-27T17:41:48.415082Z INFO actix_server::builder: starting 4 workers clare # [ 10.584450] harmonia-cache[568]: 2026-09-27T17:41:48.415891Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s clare # [ 10.588434] harmonia-cache[568]: 2026-09-27T17:41:48.415962Z INFO actix_server::server: Actix runtime found; starting in Actix runtime clare # [ 10.592674] harmonia-cache[568]: 2026-09-27T17:41:48.416000Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:5000 clare # [ 10.598586] systemd[1]: Started harmonia binary cache service. bob # [ 10.611876] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-09-27T17:41:48Z","message":"upstream became healthy and is now available for requests"} bob # [ 10.616441] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-09-27T17:41:48Z","message":"Server started"} bob # [ 10.945731] systemd-networkd[558]: eth1: Gained IPv6LL alice # [ 11.233390] systemd-networkd[536]: eth0: Gained IPv6LL bob # [ 11.298024] systemd-networkd[558]: eth0: Gained IPv6LL bob # [ 11.417265] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: 2026/09/27 17:41:49 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host alice # curl: (28) Failed to connect to bob:8502 after 133953 ms: Could not connect to server alice # % Total % Received % Xferd Average Speed Time Time Time Current alice # Dload Upload Total Spent Left Speed bob # [ 145.400377] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:33814","trace_id":"6e6308e146fcd6a1055183ce4b431f5f","span_id":"3ab1cf42a7a48f57","status":200,"elapsed":0.088,"bytes":50,"time":"2026-09-27T17:44:03Z","message":"handled request"} alice # 0 0 0 0 0 0 0 0 0100 50 100 50 0 0 2141 0 0100 50 100 50 0 0 1816 0 0100 50 100 50 0 0 1580 0 0 alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 135.12 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 4587 0 0100 51 100 51 0 0 3762 0 0100 51 100 51 0 0 3236 0 0 bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.06 seconds) clare: waiting for unit harmonia clare: (finished: waiting for unit harmonia, in 0.05 seconds) clare: must succeed: systemctl status harmonia clare: (finished: must succeed: systemctl status harmonia, in 0.02 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 # [ 145.622592] systemd-resolved[308]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. clare # [ 145.625444] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 145.627232] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 145.900409] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 145.904115] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 145.908284] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 145.912142] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 146.553150] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 146.556927] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 146.561384] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 146.565310] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 147.586743] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 147.591122] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 147.596274] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 147.600146] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 150.012132] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 150.015983] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 150.020415] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 150.024865] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare: (finished: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 , in 4.61 seconds) alice: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 bob # [ 150.283493] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:33828","trace_id":"aaee1509da7de6dda05ac85c09957c9a","span_id":"5d44fe0fb51daf27","status":200,"elapsed":0.02984,"bytes":50,"time":"2026-09-27T17:44:08Z","message":"handled request"} alice # [ 150.326348] systemd-resolved[309]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. alice # [ 150.331379] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 150.335063] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 150.694038] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 150.698849] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 150.702851] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 150.706598] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 151.392560] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 151.396511] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 151.401424] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 151.405083] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 152.495277] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 152.498995] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 152.503791] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 152.507347] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 154.873791] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 154.877320] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 154.882304] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 154.886065] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 154.855953] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33828","trace_id":"8bd0b16026d0a936be0e0e92aece0851","span_id":"0929a2ce524bc202","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-09-27T17:44:12Z","message":"download the narinfo from upstream"} bob # [ 154.886322] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33828","trace_id":"8bd0b16026d0a936be0e0e92aece0851","span_id":"0929a2ce524bc202","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-09-27T17:44:12Z","message":"downloading the nar from upstream"} bob # [ 154.898212] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33828","trace_id":"8bd0b16026d0a936be0e0e92aece0851","span_id":"0929a2ce524bc202","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-09-27T17:44:12Z","message":"download the nar from upstream"} bob # [ 154.936340] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33828","trace_id":"8bd0b16026d0a936be0e0e92aece0851","span_id":"0929a2ce524bc202","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-09-27T17:44:12Z","message":"storing narinfo and nar_file record in the database"} bob # [ 154.954654] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33828","trace_id":"8bd0b16026d0a936be0e0e92aece0851","span_id":"0929a2ce524bc202","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":63.71852,"time":"2026-09-27T17:44:12Z","message":"download of nar complete"} bob # [ 154.965984] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33828","trace_id":"8bd0b16026d0a936be0e0e92aece0851","span_id":"0929a2ce524bc202","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":97.37984,"time":"2026-09-27T17:44:12Z","message":"download of narinfo complete"} bob # [ 154.973789] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33828","trace_id":"8bd0b16026d0a936be0e0e92aece0851","span_id":"0929a2ce524bc202","status":200,"elapsed":100.16236,"bytes":532,"time":"2026-09-27T17:44:12Z","message":"handled request"} bob # [ 154.978226] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:33828","trace_id":"73730adcbf99f7b174e20f004643376b","span_id":"aa3bc0b8bbc639dd","status":200,"elapsed":0.93312,"bytes":95,"time":"2026-09-27T17:44:12Z","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.88 seconds) (finished: run the VM test script, in 155.88 seconds) test script finished in 156.03s cleanup kill QemuMachine (pid 48) alice # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) alice # [2026-09-27T17:44:13Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-09-27T17:44:13Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-09-27T17:44:13Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 49) bob # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) bob # [2026-09-27T17:44:13Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-09-27T17:44:13Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-09-27T17:44:13Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 50) clare # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) clare # [2026-09-27T17:44:13Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-09-27T17:44:13Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-09-27T17:44:13Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.35 seconds)