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.Y6gktiUXXF', fmt=raw size=1073741824 alice: QEMU running (pid 48) alice # mke2fs 1.47.4 (6-Mar-2025) bob: QEMU running (pid 49) alice # Discarding device blocks: 0/262144 done bob # Disk image does not exist, creating the virtualisation disk image... alice # Creating filesystem with 262144 4k blocks and 65536 inodes bob # Formatting '/build/vm-state-bob/tmp.J2ZueMwQE2', fmt=raw size=1073741824 alice # Filesystem UUID: 58a30e0a-f96c-42d0-ae38-0862f6802fe9 bob # mke2fs 1.47.4 (6-Mar-2025) alice # Superblock backups stored on blocks: bob # Discarding device blocks: 0/262144 done alice # 32768, 98304, 163840, 229376 bob # Creating filesystem with 262144 4k blocks and 65536 inodes alice # bob # Filesystem UUID: 8e51f2ba-7b6a-436e-89fa-572b3da529ec alice # Allocating group tables: 0/8 done bob # Superblock backups stored on blocks: alice # Writing inode tables: 0/8 done bob # 32768, 98304, 163840, 229376 alice # Creating journal (8192 blocks): done bob # alice # Writing superblocks and filesystem accounting information: 0/8 done bob # Allocating group tables: 0/8 done alice # bob # Writing inode tables: 0/8 done alice # Virtualisation disk image created. clare # Disk image does not exist, creating the virtualisation disk image... clare: QEMU running (pid 50) bob # Creating journal (8192 blocks): done alice # Starting virtiofs daemons... bob # Writing superblocks and filesystem accounting information: 0/8 done clare # Formatting '/build/vm-state-clare/tmp.eV1iHRfMK6', fmt=raw size=1073741824 (finished: start all VMs, in 0.64 seconds) alice # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # clare # mke2fs 1.47.4 (6-Mar-2025) bob: waiting for unit ncps alice # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # Virtualisation disk image created. clare # Discarding device blocks: 0/262144 done alice # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection... bob: waiting for the VM to finish booting bob # Starting virtiofs daemons... clare # Creating filesystem with 262144 4k blocks and 65536 inodes bob # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # Filesystem UUID: eec86074-08cc-4b2f-b215-04f44adf23c8 alice # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # Superblock backups stored on blocks: clare # 32768, 98304, 163840, 229376 alice # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # bob # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests bob # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests bob # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests bob # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # Allocating group tables: 0/8 done clare # Writing inode tables: 0/8 done bob # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests clare # Creating journal (8192 blocks): done clare # Writing superblocks and filesystem accounting information: 0/8 done bob # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests clare # bob # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests clare # Virtualisation disk image created. clare # Starting virtiofs daemons... clare # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests clare # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests clare # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] clare # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 2026 clare # [ 0.000000] KASLR enabled clare # [ 0.000000] random: crng init done clare # [ 0.000000] Machine model: linux,dummy-virt clare # [ 0.000000] efi: UEFI not found. clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f] clare # [ 0.000000] Zone ranges: clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] DMA32 empty clare # [ 0.000000] Normal empty clare # [ 0.000000] Device empty clare # [ 0.000000] Movable zone start for each node clare # [ 0.000000] Early memory node ranges clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 clare # [ 0.000000] psci: probing for conduit method from DT. clare # [ 0.000000] psci: PSCIv1.3 detected in firmware. clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs clare # [ 0.000000] psci: Trusted OS migration not required clare # [ 0.000000] psci: SMC Calling Convention v1.1 clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296 clare # [ 0.000000] Detected PIPT I-cache on CPU0 clare # [ 0.000000] CPU features: detected: GICv3 CPU interface clare # [ 0.000000] CPU features: detected: Spectre-v4 clare # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.000000] CPU features: detected: ARM erratum 1418040 clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.000000] alternatives: applying boot alternatives clare # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/48d43zfi2vli8nvl465idklqygs42ym9-nixos-system-clare-test/init regInfo=/nix/store/bwi1h48qy42jgjwb9y277m5jr7ppf76g-closure-info/registration console=ttyAMA0,115200n8 console=tty0 clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/bwi1h48qy42jgjwb9y277m5jr7ppf76g-closure-info/registration", will be passed to user space. clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB clare # [ 0.000000] software IO TLB: area num 1. clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 clare # [ 0.000000] Policy zone: DMA clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 clare # [ 0.000000] allocated 2097152 bytes of page_ext clare # [ 0.000000] ftrace: allocating 74950 entries in 294 pages clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups clare # [ 0.000000] rcu: Hierarchical RCU implementation. clare # [ 0.000000] rcu: RCU event tracing is enabled. clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. clare # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.000000] Rude variant of Tasks RCU enabled. clare # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 clare # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.000000] GICv3: 0 Extended SPIs implemented clare # [ 0.000000] Root IRQ handler: gic_handle_irq clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) bob # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 2026 clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 bob # [ 0.000000] KASLR enabled bob # [ 0.000000] random: crng init done clare # [ 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. clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f] bob # [ 0.000000] Zone ranges: clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] DMA32 empty bob # [ 0.000000] Normal empty clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns bob # [ 0.000000] Device empty bob # [ 0.000000] Movable zone start for each node clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.000000] Early memory node ranges clare # [ 0.000062] arm-pv: using stolen time PV alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000433] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 2026 clare # [ 0.000720] Console: colour dummy device 80x25 bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000000] KASLR enabled clare # [ 0.000741] printk: legacy console [tty0] enabled alice # [ 0.000000] random: crng init done bob # [ 0.000000] psci: probing for conduit method from DT. alice # [ 0.000000] Machine model: linux,dummy-virt bob # [ 0.000000] psci: PSCIv1.3 detected in firmware. alice # [ 0.000000] efi: UEFI not found. clare # [ 0.000945] 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 clare # [ 0.000951] pid_max: default: 32768 minimum: 301 alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT bob # [ 0.000000] psci: Trusted OS migration not required clare # [ 0.001035] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.000000] psci: SMC Calling Convention v1.1 alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.001189] landlock: Up and running. clare # [ 0.001191] Yama: becoming mindful. alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f] bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.000000] Zone ranges: clare # [ 0.001669] LSM support for eBPF active bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296 alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.001873] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.000000] Detected PIPT I-cache on CPU0 alice # [ 0.000000] DMA32 empty alice # [ 0.000000] Normal empty bob # [ 0.000000] CPU features: detected: GICv3 CPU interface alice # [ 0.000000] Device empty clare # [ 0.001894] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.000000] Movable zone start for each node clare # [ 0.004980] rcu: Hierarchical SRCU implementation. alice # [ 0.000000] Early memory node ranges bob # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.004990] rcu: Max phase no-delay instances is 1000. alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.006087] fsl-mc MSI: its@8080000 domain created bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.006259] EFI services will not be available. bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.006368] smp: Bringing up secondary CPUs ... bob # [ 0.000000] CPU features: detected: ARM erratum 1418040 alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 clare # [ 0.006401] smp: Brought up 1 node, 1 CPU alice # [ 0.000000] psci: probing for conduit method from DT. bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion clare # [ 0.006404] SMP: Total of 1 processors activated. alice # [ 0.000000] psci: PSCIv1.3 detected in firmware. clare # [ 0.006405] CPU: All CPU(s) started at EL1 bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs bob # [ 0.000000] alternatives: applying boot alternatives clare # [ 0.006419] CPU features: detected: 32-bit EL0 Support alice # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] psci: SMC Calling Convention v1.1 clare # [ 0.006422] CPU features: detected: Data cache clean to the PoU not required for I/D coherence clare # [ 0.006425] CPU features: detected: Common not Private translations alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) clare # [ 0.006427] CPU features: detected: CRC32 instructions alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296 clare # [ 0.006429] CPU features: detected: Data cache clean to Point of Persistence alice # [ 0.000000] Detected PIPT I-cache on CPU0 clare # [ 0.006431] CPU features: detected: Enhanced Virtualization Traps alice # [ 0.000000] CPU features: detected: GICv3 CPU interface 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/d8kcs93jcill7b2299xbjmwnzczl6sbz-nixos-system-bob-test/init regInfo=/nix/store/0xnfd3i8ajvb831sc6z38byc3242fa29-closure-info/registration console=ttyAMA0,115200n8 console=tty0 clare # [ 0.006442] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.006445] CPU features: detected: LSE atomic instructions bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/0xnfd3i8ajvb831sc6z38byc3242fa29-closure-info/registration", will be passed to user space. alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.006447] CPU features: detected: Privileged Access Never clare # [ 0.006449] CPU features: detected: PMUv3 bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.006451] CPU features: detected: RAS Extension Support alice # [ 0.000000] CPU features: detected: ARM erratum 1418040 bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) clare # [ 0.006455] CPU features: detected: Speculative Store Bypassing Safe (SSBS) alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.006481] alternatives: applying system-wide alternatives alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB clare # [ 0.008041] CPU features: detected: Hardware dirty bit management on CPU0 alice # [ 0.000000] alternatives: applying boot alternatives bob # [ 0.000000] software IO TLB: area num 1. bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.008298] Memory: 891436K/1048576K available (24448K kernel code, 7094K rwdata, 26372K rodata, 4736K init, 1107K bss, 115836K reserved, 32768K cma-reserved) bob # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.008834] devtmpfs: initialized bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 clare # [ 0.010517] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.000000] Policy zone: DMA bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off 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/mkf3xhkpx4y16h0rmmhhsxkxj82z03ph-nixos-system-alice-test/init regInfo=/nix/store/31y0dxlvvbsip4jp7qnl3a271bngvbvr-closure-info/registration console=ttyAMA0,115200n8 console=tty0 clare # [ 0.010543] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 clare # [ 0.010683] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.000000] allocated 2097152 bytes of page_ext clare # [ 0.010688] 0 pages in range for non-PLT usage alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/31y0dxlvvbsip4jp7qnl3a271bngvbvr-closure-info/registration", will be passed to user space. bob # [ 0.000000] ftrace: allocating 74950 entries in 294 pages clare # [ 0.010688] 508272 pages in range for PLT usage bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups clare # [ 0.010809] pinctrl core: initialized pinctrl subsystem alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes clare # [ 0.011704] DMI not present or invalid. bob # [ 0.000000] rcu: Hierarchical RCU implementation. bob # [ 0.000000] rcu: RCU event tracing is enabled. clare # [ 0.015696] NET: Registered PF_NETLINK/PF_ROUTE protocol family alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. clare # [ 0.018669] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) bob # [ 0.000000] Trampoline variant of Tasks RCU enabled. alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB clare # [ 0.018817] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 0.000000] Rude variant of Tasks RCU enabled. alice # [ 0.000000] software IO TLB: area num 1. bob # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.018970] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.018998] audit: initializing netlink subsys (disabled) bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. alice # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.019756] thermal_sys: Registered thermal governor 'fair_share' bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 alice # [ 0.000000] Policy zone: DMA clare # [ 0.019758] thermal_sys: Registered thermal governor 'bang_bang' bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off clare # [ 0.019762] thermal_sys: Registered thermal governor 'step_wise' bob # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.019763] thermal_sys: Registered thermal governor 'user_space' alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.000000] allocated 2097152 bytes of page_ext clare # [ 0.019765] thermal_sys: Registered thermal governor 'power_allocator' bob # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.000000] ftrace: allocating 74950 entries in 294 pages bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.019798] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups bob # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.019817] cpuidle: using governor ladder alice # [ 0.000000] rcu: Hierarchical RCU implementation. clare # [ 0.019823] cpuidle: using governor menu bob # [ 0.000000] GICv3: 0 Extended SPIs implemented alice # [ 0.000000] rcu: RCU event tracing is enabled. bob # [ 0.000000] Root IRQ handler: gic_handle_irq clare # [ 0.019959] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI clare # [ 0.019984] ASID allocator initialised with 32768 entries bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 alice # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.020724] Serial: AMBA PL011 UART driver alice # [ 0.000000] Rude variant of Tasks RCU enabled. bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 alice # [ 0.000000] Tracing variant of Tasks RCU enabled. bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.024217] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 clare # [ 0.024436] printk: console [ttyAMA0] enabled alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) alice # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 alice # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. 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 bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns alice # [ 0.000000] GICv3: 256 SPIs implemented alice # [ 0.000000] GICv3: 0 Extended SPIs implemented bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 alice # [ 0.000000] Root IRQ handler: gic_handle_irq bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] bob # [ 0.000053] arm-pv: using stolen time PV alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) bob # [ 0.000387] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.000676] Console: colour dummy device 80x25 alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) bob # [ 0.000693] printk: legacy console [tty0] enabled alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 bob # [ 0.000884] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) clare # [ 0.098624] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000889] pid_max: default: 32768 minimum: 301 clare # [ 0.098651] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.000960] LSM: initializing lsm=capability,landlock,yama,bpf,ima clare # [ 0.098656] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.001126] landlock: Up and running. bob # [ 0.001128] Yama: becoming mindful. alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns clare # [ 0.098662] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page bob # [ 0.001541] LSM support for eBPF active alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 clare # [ 0.098665] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 0.001708] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). clare # [ 0.098668] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 0.001727] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.098671] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages bob # [ 0.004348] rcu: Hierarchical SRCU implementation. alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns clare # [ 0.098674] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page bob # [ 0.004357] rcu: Max phase no-delay instances is 1000. alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.005375] fsl-mc MSI: its@8080000 domain created alice # [ 0.000065] arm-pv: using stolen time PV bob # [ 0.005554] EFI services will not be available. bob # [ 0.005658] smp: Bringing up secondary CPUs ... bob # [ 0.005688] smp: Brought up 1 node, 1 CPU alice # [ 0.000485] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.005691] SMP: Total of 1 processors activated. alice # [ 0.000757] Console: colour dummy device 80x25 bob # [ 0.005693] CPU: All CPU(s) started at EL1 alice # [ 0.000773] printk: legacy console [tty0] enabled clare # [ 0.103863] fbcon: Taking over console clare # [ 0.103887] ACPI: Interpreter disabled. bob # [ 0.005706] CPU features: detected: 32-bit EL0 Support clare # [ 0.105101] iommu: Default domain type: Translated alice # [ 0.000975] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) bob # [ 0.005708] CPU features: detected: Data cache clean to the PoU not required for I/D coherence clare # [ 0.105112] iommu: DMA domain TLB invalidation policy: strict mode alice # [ 0.000980] pid_max: default: 32768 minimum: 301 bob # [ 0.005711] CPU features: detected: Common not Private translations alice # [ 0.001057] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.005714] CPU features: detected: CRC32 instructions clare # [ 0.106273] SCSI subsystem initialized alice # [ 0.001225] landlock: Up and running. alice # [ 0.001227] Yama: becoming mindful. bob # [ 0.005715] CPU features: detected: Data cache clean to Point of Persistence alice # [ 0.001773] LSM support for eBPF active bob # [ 0.005718] CPU features: detected: Enhanced Virtualization Traps alice # [ 0.001962] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.005729] CPU features: detected: RCpc load-acquire (LDAPR) bob # [ 0.005732] CPU features: detected: LSE atomic instructions alice # [ 0.001981] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.005734] CPU features: detected: Privileged Access Never alice # [ 0.004755] rcu: Hierarchical SRCU implementation. bob # [ 0.005736] CPU features: detected: PMUv3 alice # [ 0.004763] rcu: Max phase no-delay instances is 1000. bob # [ 0.005738] CPU features: detected: RAS Extension Support alice # [ 0.005800] fsl-mc MSI: its@8080000 domain created bob # [ 0.005742] CPU features: detected: Speculative Store Bypassing Safe (SSBS) alice # [ 0.005955] EFI services will not be available. alice # [ 0.006048] smp: Bringing up secondary CPUs ... bob # [ 0.005765] alternatives: applying system-wide alternatives alice # [ 0.006075] smp: Brought up 1 node, 1 CPU alice # [ 0.006078] SMP: Total of 1 processors activated. bob # [ 0.007329] CPU features: detected: Hardware dirty bit management on CPU0 alice # [ 0.006080] CPU: All CPU(s) started at EL1 alice # [ 0.006091] CPU features: detected: 32-bit EL0 Support bob # [ 0.007527] Memory: 891432K/1048576K available (24448K kernel code, 7094K rwdata, 26372K rodata, 4736K init, 1107K bss, 115816K reserved, 32768K cma-reserved) alice # [ 0.006094] CPU features: detected: Data cache clean to the PoU not required for I/D coherence bob # [ 0.008003] devtmpfs: initialized alice # [ 0.006097] CPU features: detected: Common not Private translations bob # [ 0.009579] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.006100] CPU features: detected: CRC32 instructions bob # [ 0.009603] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). alice # [ 0.006102] CPU features: detected: Data cache clean to Point of Persistence bob # [ 0.009735] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL alice # [ 0.006104] CPU features: detected: Enhanced Virtualization Traps bob # [ 0.009739] 0 pages in range for non-PLT usage alice # [ 0.006114] CPU features: detected: RCpc load-acquire (LDAPR) bob # [ 0.009739] 508272 pages in range for PLT usage bob # [ 0.009850] pinctrl core: initialized pinctrl subsystem alice # [ 0.006116] CPU features: detected: LSE atomic instructions bob # [ 0.010679] DMI not present or invalid. alice # [ 0.006118] CPU features: detected: Privileged Access Never clare # [ 0.114893] usbcore: registered new interface driver usbfs alice # [ 0.006121] CPU features: detected: PMUv3 bob # [ 0.014339] NET: Registered PF_NETLINK/PF_ROUTE protocol family clare # [ 0.114957] usbcore: registered new interface driver hub alice # [ 0.006123] CPU features: detected: RAS Extension Support bob # [ 0.017021] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations clare # [ 0.114971] usbcore: registered new device driver usb alice # [ 0.006127] CPU features: detected: Speculative Store Bypassing Safe (SSBS) bob # [ 0.017164] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations clare # [ 0.115231] pps_core: LinuxPPS API ver. 1 registered alice # [ 0.006149] alternatives: applying system-wide alternatives bob # [ 0.017315] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.115238] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti alice # [ 0.007695] CPU features: detected: Hardware dirty bit management on CPU0 clare # [ 0.115244] PTP clock support registered bob # [ 0.017339] audit: initializing netlink subsys (disabled) clare # [ 0.115279] EDAC MC: Ver: 3.0.0 bob # [ 0.017944] thermal_sys: Registered thermal governor 'fair_share' alice # [ 0.007890] Memory: 891448K/1048576K available (24448K kernel code, 7094K rwdata, 26372K rodata, 4736K init, 1107K bss, 115832K reserved, 32768K cma-reserved) bob # [ 0.017945] thermal_sys: Registered thermal governor 'bang_bang' clare # [ 0.118335] scmi_core: SCMI protocol bus registered alice # [ 0.008437] devtmpfs: initialized bob # [ 0.017949] thermal_sys: Registered thermal governor 'step_wise' alice # [ 0.009999] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.017951] thermal_sys: Registered thermal governor 'user_space' clare # [ 0.119157] FPGA manager framework bob # [ 0.017953] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 0.010024] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). clare # [ 0.120224] vgaarb: loaded alice # [ 0.010160] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.017977] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 bob # [ 0.017996] cpuidle: using governor ladder clare # [ 0.120668] clocksource: Switched to clocksource arch_sys_counter alice # [ 0.010164] 0 pages in range for non-PLT usage bob # [ 0.018001] cpuidle: using governor menu alice # [ 0.010165] 508272 pages in range for PLT usage alice # [ 0.010279] pinctrl core: initialized pinctrl subsystem bob # [ 0.018125] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. alice # [ 0.011052] DMI not present or invalid. bob # [ 0.018148] ASID allocator initialised with 32768 entries alice # [ 0.014519] NET: Registered PF_NETLINK/PF_ROUTE protocol family bob # [ 0.018810] Serial: AMBA PL011 UART driver alice # [ 0.016998] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations bob # [ 0.022101] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 bob # [ 0.022255] printk: console [ttyAMA0] enabled alice # [ 0.017136] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations alice # [ 0.017288] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations alice # [ 0.017310] audit: initializing netlink subsys (disabled) alice # [ 0.018116] thermal_sys: Registered thermal governor 'fair_share' alice # [ 0.018119] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 0.018123] thermal_sys: Registered thermal governor 'step_wise' clare # [ 0.125758] VFS: Disk quotas dquot_6.6.0 alice # [ 0.018125] thermal_sys: Registered thermal governor 'user_space' clare # [ 0.125799] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 0.018127] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 0.018150] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 alice # [ 0.018170] cpuidle: using governor ladder alice # [ 0.018175] cpuidle: using governor menu clare # [ 0.128201] netfs: FS-Cache loaded alice # [ 0.018306] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. clare # [ 0.128348] pnp: PnP ACPI: disabled alice # [ 0.018328] ASID allocator initialised with 32768 entries alice # [ 0.018981] Serial: AMBA PL011 UART driver alice # [ 0.022225] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 alice # [ 0.022412] printk: console [ttyAMA0] enabled bob # [ 0.094268] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.094292] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 0.094296] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.094302] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page clare # [ 0.130839] NET: Registered PF_INET protocol family bob # [ 0.094306] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages clare # [ 0.131043] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) bob # [ 0.094309] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 0.094312] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages bob # [ 0.094315] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page bob # [ 0.099518] fbcon: Taking over console bob # [ 0.099542] ACPI: Interpreter disabled. bob # [ 0.100790] iommu: Default domain type: Translated bob # [ 0.100801] iommu: DMA domain TLB invalidation policy: strict mode bob # [ 0.102018] SCSI subsystem initialized alice # [ 0.096728] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages alice # [ 0.096753] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page alice # [ 0.096757] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages alice # [ 0.096764] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page alice # [ 0.096767] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages alice # [ 0.096770] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 0.096773] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages alice # [ 0.096776] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page alice # [ 0.102096] fbcon: Taking over console alice # [ 0.102121] ACPI: Interpreter disabled. alice # [ 0.103370] iommu: Default domain type: Translated alice # [ 0.103382] iommu: DMA domain TLB invalidation policy: strict mode alice # [ 0.104603] SCSI subsystem initialized bob # [ 0.110240] usbcore: registered new interface driver usbfs bob # [ 0.110282] usbcore: registered new interface driver hub alice # [ 0.105231] usbcore: registered new interface driver usbfs bob # [ 0.110297] usbcore: registered new device driver usb alice # [ 0.105272] usbcore: registered new interface driver hub bob # [ 0.110537] pps_core: LinuxPPS API ver. 1 registered alice # [ 0.105283] usbcore: registered new device driver usb alice # [ 0.105506] pps_core: LinuxPPS API ver. 1 registered bob # [ 0.110544] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti bob # [ 0.110550] PTP clock support registered alice # [ 0.105513] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti bob # [ 0.110580] EDAC MC: Ver: 3.0.0 alice # [ 0.105519] PTP clock support registered alice # [ 0.105550] EDAC MC: Ver: 3.0.0 bob # [ 0.113646] scmi_core: SCMI protocol bus registered bob # [ 0.114555] FPGA manager framework bob # [ 0.115664] vgaarb: loaded bob # [ 0.116104] clocksource: Switched to clocksource arch_sys_counter alice # [ 0.112753] scmi_core: SCMI protocol bus registered alice # [ 0.113643] FPGA manager framework alice # [ 0.114740] vgaarb: loaded clare # [ 0.156225] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.115203] clocksource: Switched to clocksource arch_sys_counter clare # [ 0.156261] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) clare # [ 0.156276] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 0.122001] VFS: Disk quotas dquot_6.6.0 clare # [ 0.156316] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) clare # [ 0.156384] TCP: Hash tables configured (established 8192 bind 8192) bob # [ 0.122043] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) clare # [ 0.156480] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) clare # [ 0.156521] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.156580] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.124463] netfs: FS-Cache loaded bob # [ 0.124598] pnp: PnP ACPI: disabled bob # [ 0.127052] NET: Registered PF_INET protocol family bob # [ 0.127247] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.124100] VFS: Disk quotas dquot_6.6.0 alice # [ 0.124143] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 0.126647] netfs: FS-Cache loaded clare # [ 0.161450] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 0.126797] pnp: PnP ACPI: disabled clare # [ 0.161533] NET: Registered PF_XDP protocol family clare # [ 0.161558] PCI: CLS 0 bytes, default 64 clare # [ 0.161770] Trying to unpack rootfs image as initramfs... clare # [ 0.170262] kvm [1]: HYP mode not available alice # [ 0.129388] NET: Registered PF_INET protocol family alice # [ 0.129607] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) bob # [ 0.152594] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.154941] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.152628] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.152642] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) alice # [ 0.154977] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.152684] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.154992] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 0.152752] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.155034] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) bob # [ 0.152847] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 0.155103] TCP: Hash tables configured (established 8192 bind 8192) bob # [ 0.152918] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.152975] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.153113] NET: Registered PF_UNIX/PF_LOCAL protocol family bob # [ 0.153146] NET: Registered PF_XDP protocol family bob # [ 0.153168] PCI: CLS 0 bytes, default 64 bob # [ 0.153402] Trying to unpack rootfs image as initramfs... bob # [ 0.166218] kvm [1]: HYP mode not available alice # [ 0.158270] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 0.158325] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.158351] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.158515] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 0.158566] NET: Registered PF_XDP protocol family alice # [ 0.158592] PCI: CLS 0 bytes, default 64 alice # [ 0.158805] Trying to unpack rootfs image as initramfs... alice # [ 0.170422] kvm [1]: HYP mode not available clare # [ 0.245593] Initialise system trusted keyrings clare # [ 0.246143] workingset: timestamp_bits=42 max_order=18 bucket_order=0 clare # [ 0.247198] squashfs: version 4.0 (2009/01/31) Phillip Lougher clare # [ 0.247733] 9p: Installing v9fs 9p2000 file system support clare # [ 0.267195] Key type asymmetric registered clare # [ 0.267234] Asymmetric key parser 'x509' registered clare # [ 0.267332] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) clare # [ 0.272754] io scheduler mq-deadline registered clare # [ 0.272777] io scheduler kyber registered clare # [ 0.275882] pl061_gpio 9030000.pl061: PL061 GPIO chip registered bob # [ 0.244595] Initialise system trusted keyrings bob # [ 0.245156] workingset: timestamp_bits=42 max_order=18 bucket_order=0 bob # [ 0.246199] squashfs: version 4.0 (2009/01/31) Phillip Lougher bob # [ 0.246747] 9p: Installing v9fs 9p2000 file system support clare # [ 0.280730] ledtrig-cpu: registered to indicate activity on CPUs clare # [ 0.281229] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: clare # [ 0.281257] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 clare # [ 0.281274] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 clare # [ 0.281280] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.281320] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits clare # [ 0.281353] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.281452] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 clare # [ 0.281471] pci_bus 0000:00: root bus resource [bus 00-ff] clare # [ 0.281475] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] clare # [ 0.281479] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] clare # [ 0.281483] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] clare # [ 0.281579] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint clare # [ 0.282058] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.282198] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.282210] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.255738] Initialise system trusted keyrings clare # [ 0.282233] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.256289] workingset: timestamp_bits=42 max_order=18 bucket_order=0 clare # [ 0.282245] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.282584] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.257290] squashfs: version 4.0 (2009/01/31) Phillip Lougher clare # [ 0.282718] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.282730] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.282752] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.257868] 9p: Installing v9fs 9p2000 file system support clare # [ 0.283084] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint clare # [ 0.283218] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] clare # [ 0.283230] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.283251] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.266173] Key type asymmetric registered bob # [ 0.266208] Asymmetric key parser 'x509' registered clare # [ 0.283600] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.266300] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) clare # [ 0.283732] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.283744] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.283766] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.283778] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.284105] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.284238] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.284261] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.284594] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint clare # [ 0.284770] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.272227] io scheduler mq-deadline registered clare # [ 0.284792] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.272254] io scheduler kyber registered clare # [ 0.285126] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.285256] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.285450] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.285584] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.285606] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.285940] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.275660] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.286071] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.286092] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.286414] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.286545] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.286566] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.286900] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint clare # [ 0.287128] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] clare # [ 0.287142] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.276639] ledtrig-cpu: registered to indicate activity on CPUs clare # [ 0.287163] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.277047] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: clare # [ 0.287513] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.277076] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 clare # [ 0.287647] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.277092] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 clare # [ 0.287659] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.277099] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.287680] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.277375] Key type asymmetric registered alice # [ 0.277416] Asymmetric key parser 'x509' registered clare # [ 0.288112] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned bob # [ 0.277133] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits alice # [ 0.277506] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) clare # [ 0.288125] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned bob # [ 0.277174] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.288130] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned bob # [ 0.277263] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 clare # [ 0.288166] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned bob # [ 0.277283] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 0.277288] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] clare # [ 0.288200] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned bob # [ 0.277292] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] clare # [ 0.288233] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.277296] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] clare # [ 0.288267] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.277398] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint clare # [ 0.288299] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned bob # [ 0.277896] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.288332] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned alice # [ 0.283299] io scheduler mq-deadline registered bob # [ 0.278041] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.283322] io scheduler kyber registered clare # [ 0.288365] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.278054] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.278077] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.288397] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.278089] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.288428] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned bob # [ 0.278439] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.288506] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned clare # [ 0.288545] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned bob # [ 0.278585] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.278598] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.286392] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.288559] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned bob # [ 0.278621] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.288573] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.278989] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint clare # [ 0.288587] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.279129] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] bob # [ 0.279140] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.288601] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned clare # [ 0.288615] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.279163] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.288629] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.279516] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.288644] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.279663] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.288658] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.279676] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.287302] ledtrig-cpu: registered to indicate activity on CPUs bob # [ 0.279700] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.279712] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.287639] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: bob # [ 0.280073] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint alice # [ 0.287664] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 alice # [ 0.287680] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 alice # [ 0.287686] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 alice # [ 0.287724] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits alice # [ 0.287755] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] alice # [ 0.287838] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 alice # [ 0.287857] pci_bus 0000:00: root bus resource [bus 00-ff] alice # [ 0.287862] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] alice # [ 0.287866] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.287870] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.287970] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint alice # [ 0.288466] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.288607] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.288620] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.288643] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.288654] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.289010] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.289151] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.289164] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.304310] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.289189] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.304351] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.289557] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint bob # [ 0.304766] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 0.289698] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] bob # [ 0.304908] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.289710] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.304930] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.344771] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned alice # [ 0.289731] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.305220] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.344811] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned alice # [ 0.290079] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.305351] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.344828] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned alice # [ 0.290216] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.344845] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.305545] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.290228] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.344861] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.305681] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.290250] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.344876] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned bob # [ 0.305703] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.290263] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.344892] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned bob # [ 0.306045] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.290608] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.344908] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned bob # [ 0.306184] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.290751] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.344924] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.306206] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.290774] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.344947] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.306531] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.344959] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] alice # [ 0.291121] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint bob # [ 0.306666] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.344963] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] bob # [ 0.306687] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.345570] pci 0000:00:07.0: enabling device (0000 -> 0002) bob # [ 0.307061] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint bob # [ 0.307285] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.307299] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.307321] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.307659] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.307793] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.307804] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.307827] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.359595] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.315359] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.315399] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.315779] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 0.315920] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] alice # [ 0.316171] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.316312] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.316334] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.316685] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.316825] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.316846] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.317186] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.317344] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.328440] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned alice # [ 0.317368] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.328467] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned alice # [ 0.317728] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint bob # [ 0.328472] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned alice # [ 0.317971] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.317986] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.328516] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned alice # [ 0.318008] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.328552] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned alice # [ 0.318360] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.328588] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned alice # [ 0.318499] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.328624] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned alice # [ 0.318511] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.328659] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned alice # [ 0.318534] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.318995] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned bob # [ 0.328694] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.370295] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) clare # [ 0.372324] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) alice # [ 0.319007] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned alice # [ 0.319012] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned bob # [ 0.328729] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.328764] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned alice # [ 0.319052] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned alice # [ 0.319090] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned bob # [ 0.328798] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned bob # [ 0.328879] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned bob # [ 0.328944] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned alice # [ 0.319127] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.328961] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned alice # [ 0.319165] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.328976] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.328990] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.329006] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned bob # [ 0.329021] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.329037] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.329053] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.329068] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.329083] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.329098] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.329113] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned bob # [ 0.329128] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.329144] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.329158] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned bob # [ 0.329173] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned alice # [ 0.339264] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned bob # [ 0.329187] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.339353] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned bob # [ 0.329203] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned clare # [ 0.382575] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) bob # [ 0.329230] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] alice # [ 0.339391] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned clare # [ 0.384562] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) bob # [ 0.329243] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] alice # [ 0.339427] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.329247] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.339462] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned bob # [ 0.329824] pci 0000:00:07.0: enabling device (0000 -> 0002) alice # [ 0.339580] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned alice # [ 0.339618] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned alice # [ 0.339634] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned alice # [ 0.339651] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned clare # [ 0.387636] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) alice # [ 0.339668] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned alice # [ 0.339685] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned alice # [ 0.339702] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned alice # [ 0.339717] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned alice # [ 0.339732] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned alice # [ 0.339747] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned alice # [ 0.339761] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned alice # [ 0.339776] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned alice # [ 0.339791] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned alice # [ 0.339805] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned alice # [ 0.339820] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned clare # [ 0.393228] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) clare # [ 0.395436] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.339834] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned alice # [ 0.339849] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned alice # [ 0.339863] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.339877] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned alice # [ 0.339902] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] alice # [ 0.339917] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] alice # [ 0.339921] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.340539] pci 0000:00:07.0: enabling device (0000 -> 0002) bob # [ 0.360703] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) bob # [ 0.362934] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) bob # [ 0.373186] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) clare # [ 0.405308] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) bob # [ 0.375107] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.407205] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) clare # [ 0.409607] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) alice # [ 0.368724] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.371081] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) bob # [ 0.378172] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) clare # [ 0.421670] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled clare # [ 0.423612] msm_serial: driver initialized clare # [ 0.423732] SuperH (H)SCI(F) driver initialized clare # [ 0.423769] STM32 USART driver initialized alice # [ 0.380932] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) alice # [ 0.383061] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) bob # [ 0.388368] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) bob # [ 0.390266] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.392039] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) bob # [ 0.395431] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) alice # [ 0.393207] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) alice # [ 0.395190] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) bob # [ 0.401380] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) bob # [ 0.403568] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) alice # [ 0.405120] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) alice # [ 0.406863] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) clare # [ 0.450544] loop: module loaded bob # [ 0.415329] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled clare # [ 0.450720] virtio_blk virtio2: 1/0/0 default/read/poll queues clare # [ 0.451436] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 0.409930] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) clare # [ 0.457297] megasas: 07.734.00.00-rc1 clare # [ 0.457794] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] alice # [ 0.415782] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) alice # [ 0.418335] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) bob # [ 0.420938] msm_serial: driver initialized bob # [ 0.421062] SuperH (H)SCI(F) driver initialized bob # [ 0.421102] STM32 USART driver initialized alice # [ 0.428083] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled clare # [ 0.468738] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.468785] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.471188] Using buffer write method clare # [ 0.471284] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] clare # [ 0.473078] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.473105] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.474896] Using buffer write method clare # [ 0.474931] Concatenating MTD devices: clare # [ 0.474934] (0): "0.flash" clare # [ 0.474938] (1): "0.flash" clare # [ 0.474940] into device "0.flash" bob # [ 0.445769] loop: module loaded bob # [ 0.445944] virtio_blk virtio2: 1/0/0 default/read/poll queues bob # [ 0.446677] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 0.436069] msm_serial: driver initialized alice # [ 0.436176] SuperH (H)SCI(F) driver initialized alice # [ 0.436214] STM32 USART driver initialized bob # [ 0.456645] megasas: 07.734.00.00-rc1 bob # [ 0.457200] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] bob # [ 0.459289] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.459330] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.459489] loop: module loaded bob # [ 0.461015] Using buffer write method alice # [ 0.459650] virtio_blk virtio2: 1/0/0 default/read/poll queues bob # [ 0.461074] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.460313] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) bob # [ 0.462892] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.462918] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.463683] megasas: 07.734.00.00-rc1 bob # [ 0.472933] Using buffer write method bob # [ 0.472973] Concatenating MTD devices: bob # [ 0.472977] (0): "0.flash" alice # [ 0.464193] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] bob # [ 0.472980] (1): "0.flash" bob # [ 0.472982] into device "0.flash" alice # [ 0.476349] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.476403] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.478357] Using buffer write method alice # [ 0.478422] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.482298] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.482334] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.487862] Using buffer write method alice # [ 0.487899] Concatenating MTD devices: alice # [ 0.487902] (0): "0.flash" alice # [ 0.487906] (1): "0.flash" alice # [ 0.487908] into device "0.flash" clare # [ 0.742975] Freeing initrd memory: 26968K clare # [ 0.750899] tun: Universal TUN/TAP device driver, 1.6 clare # [ 0.753888] thunder_xcv, ver 1.0 clare # [ 0.753926] thunder_bgx, ver 1.0 clare # [ 0.753938] nicpf, ver 1.0 clare # [ 0.754268] e1000: Intel(R) PRO/1000 Network Driver clare # [ 0.754277] e1000: Copyright (c) 1999-2006 Intel Corporation. clare # [ 0.754292] e1000e: Intel(R) PRO/1000 Network Driver clare # [ 0.754298] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. clare # [ 0.754312] igb: Intel(R) Gigabit Ethernet Network Driver clare # [ 0.754315] igb: Copyright (c) 2007-2014 Intel Corporation. clare # [ 0.754329] igbvf: Intel(R) Gigabit Virtual Function Network Driver clare # [ 0.754333] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. clare # [ 0.754398] sky2: driver version 1.30 clare # [ 0.755178] usbcore: registered new interface driver usb-storage clare # [ 0.755281] usbcore: registered new interface driver usbserial_generic clare # [ 0.755302] usbserial: USB Serial support registered for generic clare # [ 0.755607] hv_vmbus: registering driver hyperv_keyboard clare # [ 0.756632] ehci-pci 0000:00:07.0: EHCI Host Controller clare # [ 0.756732] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 clare # [ 0.757022] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 clare # [ 0.765392] rtc-pl031 9010000.pl031: registered as rtc0 clare # [ 0.765446] rtc-pl031 9010000.pl031: setting system clock to 2026-09-21T10:07:19 UTC (1789985239) clare # [ 0.765717] i2c_dev: i2c /dev entries driver bob # [ 0.741083] Freeing initrd memory: 26948K bob # [ 0.748783] tun: Universal TUN/TAP device driver, 1.6 bob # [ 0.751807] thunder_xcv, ver 1.0 bob # [ 0.751845] thunder_bgx, ver 1.0 bob # [ 0.751858] nicpf, ver 1.0 bob # [ 0.753097] e1000: Intel(R) PRO/1000 Network Driver bob # [ 0.753114] e1000: Copyright (c) 1999-2006 Intel Corporation. bob # [ 0.753133] e1000e: Intel(R) PRO/1000 Network Driver bob # [ 0.753140] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. alice # [ 0.747095] Freeing initrd memory: 26964K bob # [ 0.753155] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.753158] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.753172] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.753176] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. bob # [ 0.753287] sky2: driver version 1.30 bob # [ 0.754133] usbcore: registered new interface driver usb-storage bob # [ 0.754230] usbcore: registered new interface driver usbserial_generic bob # [ 0.754256] usbserial: USB Serial support registered for generic alice # [ 0.754844] tun: Universal TUN/TAP device driver, 1.6 bob # [ 0.754553] hv_vmbus: registering driver hyperv_keyboard bob # [ 0.761718] ehci-pci 0000:00:07.0: EHCI Host Controller bob # [ 0.761787] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 bob # [ 0.762042] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 bob # [ 0.763838] rtc-pl031 9010000.pl031: registered as rtc0 bob # [ 0.763888] rtc-pl031 9010000.pl031: setting system clock to 2026-09-21T10:07:19 UTC (1789985239) alice # [ 0.757843] thunder_xcv, ver 1.0 bob # [ 0.765349] i2c_dev: i2c /dev entries driver alice # [ 0.757882] thunder_bgx, ver 1.0 alice # [ 0.757894] nicpf, ver 1.0 alice # [ 0.758207] e1000: Intel(R) PRO/1000 Network Driver alice # [ 0.758217] e1000: Copyright (c) 1999-2006 Intel Corporation. alice # [ 0.758233] e1000e: Intel(R) PRO/1000 Network Driver alice # [ 0.758239] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.767440] sdhci: Secure Digital Host Controller Interface driver alice # [ 0.758253] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.767465] sdhci: Copyright(c) Pierre Ossman alice # [ 0.758256] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.767610] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.758270] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.767787] sdhci-pltfm: SDHCI platform and OF driver helper alice # [ 0.758274] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. alice # [ 0.758368] sky2: driver version 1.30 alice # [ 0.759161] usbcore: registered new interface driver usb-storage alice # [ 0.760037] ehci-pci 0000:00:07.0: EHCI Host Controller clare # [ 0.769105] sdhci: Secure Digital Host Controller Interface driver clare # [ 0.769128] sdhci: Copyright(c) Pierre Ossman bob # [ 0.770679] hid: raw HID events driver (C) Jiri Kosina alice # [ 0.760086] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 alice # [ 0.760277] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 bob # [ 0.770861] usbcore: registered new interface driver usbhid clare # [ 0.769259] Synopsys Designware Multimedia Card Interface Driver bob # [ 0.770868] usbhid: USB HID core driver clare # [ 0.769424] sdhci-pltfm: SDHCI platform and OF driver helper clare # [ 0.770320] hid: raw HID events driver (C) Jiri Kosina alice # [ 0.766920] usbcore: registered new interface driver usbserial_generic clare # [ 0.770468] usbcore: registered new interface driver usbhid alice # [ 0.766981] usbserial: USB Serial support registered for generic clare # [ 0.770473] usbhid: USB HID core driver bob # [ 0.772712] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 bob # [ 0.773261] hub 1-0:1.0: USB hub found bob # [ 0.773295] hub 1-0:1.0: 6 ports detected alice # [ 0.768540] hv_vmbus: registering driver hyperv_keyboard alice # [ 0.769704] rtc-pl031 9010000.pl031: registered as rtc0 clare # [ 0.810550] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 bob # [ 0.774793] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available alice # [ 0.769745] rtc-pl031 9010000.pl031: setting system clock to 2026-09-21T10:07:19 UTC (1789985239) bob # [ 0.775681] drop_monitor: Initializing network drop monitor service clare # [ 0.810950] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available alice # [ 0.769930] i2c_dev: i2c /dev entries driver bob # [ 0.775825] NET: Registered PF_INET6 protocol family clare # [ 0.811846] drop_monitor: Initializing network drop monitor service clare # [ 0.811993] NET: Registered PF_INET6 protocol family clare # [ 0.812390] hub 1-0:1.0: USB hub found alice # [ 0.771978] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 bob # [ 0.778407] Segment Routing with IPv6 clare # [ 0.812425] hub 1-0:1.0: 6 ports detected alice # [ 0.772480] hub 1-0:1.0: USB hub found bob # [ 0.778447] In-situ OAM (IOAM) with IPv6 alice # [ 0.772512] hub 1-0:1.0: 6 ports detected bob # [ 0.778503] NET: Registered PF_PACKET protocol family clare # [ 0.816112] Segment Routing with IPv6 clare # [ 0.816149] In-situ OAM (IOAM) with IPv6 bob # [ 0.779596] 9pnet: Installing 9P2000 support bob # [ 0.779661] Key type dns_resolver registered clare # [ 0.816224] NET: Registered PF_PACKET protocol family alice # [ 0.775291] sdhci: Secure Digital Host Controller Interface driver alice # [ 0.775309] sdhci: Copyright(c) Pierre Ossman alice # [ 0.775449] Synopsys Designware Multimedia Card Interface Driver clare # [ 0.817441] 9pnet: Installing 9P2000 support bob # [ 0.783430] registered taskstats version 1 alice # [ 0.775622] sdhci-pltfm: SDHCI platform and OF driver helper clare # [ 0.817511] Key type dns_resolver registered bob # [ 0.783608] Loading compiled-in X.509 certificates alice # [ 0.776626] hid: raw HID events driver (C) Jiri Kosina alice # [ 0.776779] usbcore: registered new interface driver usbhid alice # [ 0.776784] usbhid: USB HID core driver alice # [ 0.780530] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available clare # [ 0.821378] registered taskstats version 1 alice # [ 0.781454] drop_monitor: Initializing network drop monitor service clare # [ 0.821597] Loading compiled-in X.509 certificates alice # [ 0.781682] NET: Registered PF_INET6 protocol family alice # [ 0.784255] Segment Routing with IPv6 bob # [ 0.789323] Demotion targets for Node 0: null alice # [ 0.784288] In-situ OAM (IOAM) with IPv6 bob # [ 0.789440] Key type .fscrypt registered alice # [ 0.784347] NET: Registered PF_PACKET protocol family bob # [ 0.789446] Key type fscrypt-provisioning registered bob # [ 0.789581] ima: No TPM chip found, activating TPM-bypass! bob # [ 0.789595] ima: Allocated hash algorithm: sha1 clare # [ 0.827342] Demotion targets for Node 0: null clare # [ 0.827464] Key type .fscrypt registered bob # [ 0.789657] ima: No architecture policies found clare # [ 0.827470] Key type fscrypt-provisioning registered clare # [ 0.827602] ima: No TPM chip found, activating TPM-bypass! alice # [ 0.785497] 9pnet: Installing 9P2000 support clare # [ 0.827618] ima: Allocated hash algorithm: sha1 alice # [ 0.785561] Key type dns_resolver registered bob # [ 0.792930] input: gpio-keys as /devices/platform/gpio-keys/input/input0 clare # [ 0.827679] ima: No architecture policies found clare # [ 0.830960] input: gpio-keys as /devices/platform/gpio-keys/input/input0 alice # [ 0.789424] registered taskstats version 1 alice # [ 0.789592] Loading compiled-in X.509 certificates alice # [ 0.795762] Demotion targets for Node 0: null alice # [ 0.795892] Key type .fscrypt registered alice # [ 0.795899] Key type fscrypt-provisioning registered alice # [ 0.796020] ima: No TPM chip found, activating TPM-bypass! alice # [ 0.796036] ima: Allocated hash algorithm: sha1 alice # [ 0.796103] ima: No architecture policies found alice # [ 0.799025] input: gpio-keys as /devices/platform/gpio-keys/input/input0 bob # [ 0.804423] clk: Disabling unused clocks bob # [ 0.804454] PM: genpd: Disabling unused power domains clare # [ 0.842699] clk: Disabling unused clocks bob # [ 0.807857] Freeing unused kernel memory: 4736K clare # [ 0.842735] PM: genpd: Disabling unused power domains bob # [ 0.807982] Run /init as init process clare # [ 0.846209] Freeing unused kernel memory: 4736K clare # [ 0.846366] Run /init as init process alice # [ 0.811106] clk: Disabling unused clocks alice # [ 0.811138] PM: genpd: Disabling unused power domains alice # [ 0.814530] Freeing unused kernel memory: 4736K alice # [ 0.814662] Run /init as init process bob # [ 0.831871] systemd[1]: Successfully made /usr/ read-only. clare # [ 0.868650] systemd[1]: Successfully made /usr/ read-only. alice # [ 0.838805] systemd[1]: Successfully made /usr/ read-only. bob # [ 1.020177] usb 1-1: new high-speed USB device number 2 using ehci-pci clare # [ 1.056720] usb 1-1: new high-speed USB device number 2 using ehci-pci alice # [ 1.019260] usb 1-1: new high-speed USB device number 2 using ehci-pci bob # [ 1.166714] 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.173162] systemd[1]: Detected virtualization qemu. bob # [ 1.173624] systemd[1]: Detected architecture arm64. clare # [ 1.203510] 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.174348] systemd[1]: Running in initrd. clare # [ 1.211351] systemd[1]: Detected virtualization qemu. bob # [ 1.175494] systemd[1]: Initializing machine ID from random generator. bob # [ 1.176308] systemd[1]: Hostname set to . clare # [ 1.212555] systemd[1]: Detected architecture arm64. clare # [ 1.213848] systemd[1]: Running in initrd. alice # [ 1.169524] 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 bob # [ 1.177532] 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.216271] systemd[1]: Initializing machine ID from random generator. clare # [ 1.218603] systemd[1]: Hostname set to . alice # [ 1.173609] 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.181049] systemd[1]: Detected virtualization qemu. alice # [ 1.182322] systemd[1]: Detected architecture arm64. clare # [ 1.222482] 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.183595] systemd[1]: Running in initrd. alice # [ 1.185578] systemd[1]: Initializing machine ID from random generator. alice # [ 1.187528] systemd[1]: Hostname set to . alice # [ 1.247440] 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.256412] 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.304913] 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.367290] usb 1-2: new high-speed USB device number 3 using ehci-pci bob # [ 1.376175] usb 1-2: new high-speed USB device number 3 using ehci-pci bob # [ 1.380059] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 1.424717] usb 1-2: new high-speed USB device number 3 using ehci-pci bob # [ 1.431255] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 1.442093] systemd[1]: Created slice Slice /system/modprobe. bob # [ 1.442826] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 1.443520] systemd[1]: Expecting device /dev/disk/by-label/nixos... bob # [ 1.444066] systemd[1]: Reached target Path Units. bob # [ 1.444638] systemd[1]: Reached target Slice Units. bob # [ 1.445058] systemd[1]: Reached target Swaps. bob # [ 1.445406] systemd[1]: Reached target Timer Units. bob # [ 1.446010] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 1.446724] systemd[1]: Listening on Journal Socket (/dev/log). bob # [ 1.447355] systemd[1]: Listening on Journal Sockets. bob # [ 1.447877] systemd[1]: Listening on udev Control Socket. bob # [ 1.448555] systemd[1]: Listening on udev Kernel Socket. bob # [ 1.449026] systemd[1]: Reached target Socket Units. bob # [ 1.450687] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 1.451302] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.447060] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 1.460287] systemd[1]: Mounting Kernel Configuration File System... clare # [ 1.499202] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 1.468294] systemd[1]: Starting Journal Service... bob # [ 1.476508] systemd[1]: Starting Load Kernel Modules... bob # [ 1.477089] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 1.483470] systemd[1]: Starting Coldplug All udev Devices... bob # [ 1.496238] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 1.497177] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.506459] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 1.512448] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.519266] 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 alice # [ 1.522006] 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.523734] systemd[1]: Created slice Slice /system/modprobe. alice # [ 1.524609] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 1.526376] systemd[1]: Expecting device /dev/disk/by-label/nixos... alice # [ 1.526985] systemd[1]: Reached target Path Units. alice # [ 1.527502] systemd[1]: Reached target Slice Units. alice # [ 1.527960] systemd[1]: Reached target Swaps. alice # [ 1.528372] systemd[1]: Reached target Timer Units. alice # [ 1.529188] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 1.529962] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 1.530836] systemd[1]: Listening on Journal Sockets. alice # [ 1.531468] systemd[1]: Listening on udev Control Socket. alice # [ 1.532083] systemd[1]: Listening on udev Kernel Socket. alice # [ 1.532598] systemd[1]: Reached target Socket Units. alice # [ 1.534506] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.535162] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 1.547253] systemd-journald[72]: Collecting audit messages is disabled. alice # [ 1.543057] systemd[1]: Mounting Kernel Configuration File System... bob # [ 1.548542] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 1.550572] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 1.551560] systemd[1]: Starting Journal Service... clare # [ 1.588945] 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.590933] 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 clare # [ 1.592863] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.599137] systemd[1]: Created slice Slice /system/modprobe. clare # [ 1.600649] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 1.601954] systemd[1]: Expecting device /dev/disk/by-label/nixos... clare # [ 1.603012] systemd[1]: Reached target Path Units. clare # [ 1.603768] systemd[1]: Reached target Slice Units. clare # [ 1.604553] systemd[1]: Reached target Swaps. clare # [ 1.605457] systemd[1]: Reached target Timer Units. clare # [ 1.606636] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 1.570300] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. clare # [ 1.607866] systemd[1]: Listening on Journal Socket (/dev/log). bob # [ 1.571904] 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.608970] systemd[1]: Listening on Journal Sockets. alice # [ 1.567395] systemd[1]: Starting Load Kernel Modules... bob # [ 1.572001] 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.567990] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 1.610064] systemd[1]: Listening on udev Control Socket. clare # [ 1.611057] systemd[1]: Listening on udev Kernel Socket. clare # [ 1.611993] systemd[1]: Reached target Socket Units. alice # [ 1.570387] systemd[1]: Starting Coldplug All udev Devices... bob # [ 1.580199] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev clare # [ 1.614719] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.575795] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 1.614819] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 1.624890] systemd[1]: Mounting Kernel Configuration File System... bob # [ 1.593872] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 bob # [ 1.594104] [drm] features: -virgl +edid -resource_blob -host_visible bob # [ 1.594111] [drm] features: -context_init bob # [ 1.594672] [drm] number of scanouts: 1 bob # [ 1.594691] [drm] number of cap sets: 0 alice # [ 1.592762] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.593245] systemd[1]: Mounted Kernel Configuration File System. clare # [ 1.636894] systemd[1]: Starting Journal Service... clare # [ 1.640979] systemd[1]: Starting Load Kernel Modules... clare # [ 1.641771] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 1.608674] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 1.608852] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 1.608883] systemd[1]: Reached target Local File Systems. bob # [ 1.616478] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 1.621073] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic bob # [ 1.621096] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 clare # [ 1.669121] systemd[1]: Starting Coldplug All udev Devices... alice # [ 1.635484] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. alice # [ 1.637198] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 1.640525] Console: switching to colour frame buffer device 160x50 bob # [ 1.643468] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device clare # [ 1.684872] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 1.650921] systemd[1]: Started Journal Service. bob # [ 1.646356] systemd-modules-load[73]: Inserted module 'dm_mod' alice # [ 1.643597] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 1.650872] systemd-journald[72]: Collecting audit messages is disabled. alice # [ 1.652563] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev bob # [ 1.660381] systemd-modules-load[73]: Module 'virtio_balloon' is built in bob # [ 1.661264] systemd-modules-load[73]: Module 'virtio_console' is built in bob # [ 1.661988] systemd[1]: Starting Create System Files and Directories... bob # [ 1.662670] systemd-modules-load[73]: Inserted module 'virtio_gpu' bob # [ 1.663335] systemd-modules-load[73]: Module 'virtio_rng' is built in alice # [ 1.668774] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 alice # [ 1.669017] [drm] features: -virgl +edid -resource_blob -host_visible alice # [ 1.669023] [drm] features: -context_init clare # [ 1.708970] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.669519] [drm] number of scanouts: 1 alice # [ 1.669535] [drm] number of cap sets: 0 clare # [ 1.713347] systemd-journald[72]: Collecting audit messages is disabled. bob # [ 1.673186] systemd[1]: Finished Load Kernel Modules. clare # [ 1.725166] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.683741] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 1.683917] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 1.683949] systemd[1]: Reached target Local File Systems. alice # [ 1.686548] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 1.728734] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. bob # [ 1.689961] systemd[1]: Finished Create System Files and Directories. bob # [ 1.696203] systemd[1]: Starting Apply Kernel Variables... clare # [ 1.740754] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev alice # [ 1.695542] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic alice # [ 1.695563] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 clare # [ 1.742955] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 1.702442] systemd-udevd[79]: Using default interface naming scheme 'v261'. clare # [ 1.757150] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 1.711800] Console: switching to colour frame buffer device 160x50 clare # [ 1.762228] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 clare # [ 1.762454] [drm] features: -virgl +edid -resource_blob -host_visible clare # [ 1.762460] [drm] features: -context_init clare # [ 1.762922] [drm] number of scanouts: 1 clare # [ 1.762937] [drm] number of cap sets: 0 bob # [ 1.724467] systemd[1]: Finished Apply Kernel Variables. alice # [ 1.731760] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device bob # [ 1.736162] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 1.777030] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic clare # [ 1.777051] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 alice # [ 1.737081] systemd-modules-load[73]: Inserted module 'dm_mod' alice # [ 1.745179] systemd[1]: Started Journal Service. alice # [ 1.740763] systemd-modules-load[73]: Module 'virtio_balloon' is built in alice # [ 1.741554] systemd-modules-load[73]: Module 'virtio_console' is built in alice # [ 1.742270] systemd-modules-load[73]: Inserted module 'virtio_gpu' alice # [ 1.742907] systemd-modules-load[73]: Module 'virtio_rng' is built in alice # [ 1.743614] systemd[1]: Finished Load Kernel Modules. clare # [ 1.793858] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 1.794048] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 1.794079] systemd[1]: Reached target Local File Systems. clare # [ 1.797073] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 1.756355] systemd[1]: Starting Apply Kernel Variables... clare # [ 1.801095] Console: switching to colour frame buffer device 160x50 clare # [ 1.801626] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device clare # [ 1.804905] systemd-modules-load[73]: Inserted module 'dm_mod' clare # [ 1.814425] systemd[1]: Started Journal Service. clare # [ 1.807053] systemd-modules-load[73]: Module 'virtio_balloon' is built in clare # [ 1.812364] systemd-modules-load[73]: Module 'virtio_console' is built in alice # [ 1.773001] systemd-udevd[79]: Using default interface naming scheme 'v261'. clare # [ 1.814975] systemd-modules-load[73]: Inserted module 'virtio_gpu' alice # [ 1.775361] systemd[1]: Starting Create System Files and Directories... clare # [ 1.816356] systemd-modules-load[73]: Module 'virtio_rng' is built in clare # [ 1.817715] systemd[1]: Finished Load Kernel Modules. clare # [ 1.830064] systemd[1]: Starting Apply Kernel Variables... bob # [ 1.799548] systemd[1]: Starting Virtual Console Setup... alice # [ 1.798438] systemd[1]: Finished Apply Kernel Variables. alice # [ 1.810855] systemd[1]: Finished Create System Files and Directories. clare # [ 1.852118] systemd[1]: Starting Create System Files and Directories... alice # [ 1.812119] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 1.840482] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 1.877903] systemd[1]: Finished Apply Kernel Variables. bob # [ 1.843030] systemd[1]: Finished Virtual Console Setup. clare # [ 1.882092] systemd-udevd[79]: Using default interface naming scheme 'v261'. clare # [ 1.891144] systemd[1]: Finished Create System Files and Directories. alice # [ 1.856234] systemd[1]: Starting Virtual Console Setup... clare # [ 1.912965] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 1.892409] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 1.894928] systemd[1]: Finished Virtual Console Setup. clare # [ 1.963967] systemd[1]: Starting Virtual Console Setup... clare # [ 2.000617] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 2.002936] systemd[1]: Finished Virtual Console Setup. bob # [ 2.061565] systemd[1]: Finished Coldplug All udev Devices. bob # [ 2.062187] systemd[1]: Reached target System Initialization. bob # [ 2.062712] systemd[1]: Reached target Basic System. alice # [ 2.115668] systemd[1]: Finished Coldplug All udev Devices. alice # [ 2.116349] systemd[1]: Reached target System Initialization. alice # [ 2.116871] systemd[1]: Reached target Basic System. bob # [ 2.180365] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.219206] systemd[1]: Finished Coldplug All udev Devices. clare # [ 2.219825] systemd[1]: Reached target System Initialization. clare # [ 2.220501] systemd[1]: Reached target Basic System. bob # [ 2.195462] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.210509] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.241660] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.265663] systemd[1]: Found device /dev/disk/by-label/nixos. bob # [ 2.268821] systemd[1]: Reached target Initrd Root Device. bob # [ 2.275714] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... clare # [ 2.318995] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.307503] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks clare # [ 2.344194] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.311309] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 2.315241] systemd[1]: Found device /dev/disk/by-label/nixos. alice # [ 2.319712] systemd[1]: Reached target Initrd Root Device. alice # [ 2.321585] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... alice # [ 2.366261] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks alice # [ 2.370696] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. clare # [ 2.423659] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.427392] systemd[1]: Reached target Initrd Root Device. clare # [ 2.430784] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... clare # [ 2.469949] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks clare # [ 2.474447] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. bob # [ 2.487311] systemd[1]: Mounting /sysroot... bob # [ 2.521435] EXT4-fs (vda): mounted filesystem 8e51f2ba-7b6a-436e-89fa-572b3da529ec r/w with ordered data mode. Quota mode: none. bob # [ 2.517220] systemd[1]: Mounted /sysroot. bob # [ 2.518805] systemd[1]: Reached target Initrd Root File System. bob # [ 2.522397] systemd[1]: Starting Mountpoints Configured in the Real Root... bob # [ 2.543801] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 2.550826] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... bob # [ 2.554113] systemd[1]: Reloading... alice # [ 2.566168] systemd[1]: Mounting /sysroot... alice # [ 2.601199] EXT4-fs (vda): mounted filesystem 58a30e0a-f96c-42d0-ae38-0862f6802fe9 r/w with ordered data mode. Quota mode: none. alice # [ 2.598376] systemd[1]: Mounted /sysroot. alice # [ 2.599107] systemd[1]: Reached target Initrd Root File System. alice # [ 2.601194] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.623087] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload. clare # [ 2.663997] systemd[1]: Mounting /sysroot... alice # [ 2.628881] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 2.632133] systemd[1]: Reloading... clare # [ 2.701559] EXT4-fs (vda): mounted filesystem eec86074-08cc-4b2f-b215-04f44adf23c8 r/w with ordered data mode. Quota mode: none. clare # [ 2.696604] systemd[1]: Mounted /sysroot. clare # [ 2.697613] systemd[1]: Reached target Initrd Root File System. clare # [ 2.702686] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 2.716340] systemd[1]: Mounting /sysroot/nix/.rw-store... clare # [ 2.722486] systemd[1]: Mounting /sysroot/run... clare # [ 2.732864] systemd[1]: Mounting /sysroot/tmp/shared... clare # [ 2.754589] systemd[1]: Mounting /sysroot/tmp/xchg... bob # [ 2.730962] systemd[1]: Reloading finished in 177 ms. clare # [ 2.792520] fuse: init (API version 7.45) clare # [ 2.788343] systemd[1]: Starting Mountpoints Configured in the Real Root... bob # [ 2.760982] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... bob # [ 2.762664] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... clare # [ 2.809574] virtiofs virtio6: discovered new tag: nix-store bob # [ 2.767982] systemd[1]: initrd-parse-etc.service: Deactivated successfully. clare # [ 2.810159] virtiofs virtio6: virtio_fs_setup_dax: No cache capability bob # [ 2.770646] systemd[1]: Finished Mountpoints Configured in the Real Root. bob # [ 2.771913] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. clare # [ 2.827224] virtiofs virtio7: discovered new tag: shared clare # [ 2.827821] virtiofs virtio7: virtio_fs_setup_dax: No cache capability clare # [ 2.836041] virtiofs virtio8: discovered new tag: xchg clare # [ 2.836627] virtiofs virtio8: virtio_fs_setup_dax: No cache capability clare # [ 2.841546] systemd-sysroot-fstab-check[123]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 2.813231] systemd[1]: Reloading finished in 181 ms. clare # [ 2.863997] systemd[1]: Mounted /sysroot/nix/.rw-store. clare # [ 2.872353] systemd[1]: Mounted /sysroot/nix/.ro-store. clare # [ 2.872975] systemd[1]: Mounted /sysroot/run. clare # [ 2.874302] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 2.874857] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 2.836554] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace... clare # [ 2.877086] systemd[1]: Reload requested from client PID 123 ('systemd-sysroot') (unit initrd-parse-etc.service)... clare # [ 2.879990] systemd[1]: Reloading... alice # [ 2.839703] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace... alice # [ 2.843235] systemd[1]: initrd-parse-etc.service: Deactivated successfully. alice # [ 2.845430] systemd[1]: Finished Mountpoints Configured in the Real Root. alice # [ 2.846779] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. clare # [ 3.076397] systemd[1]: Reloading finished in 203 ms. clare # [ 3.107857] systemd-sysroot-fstab-check[123]: Requesting initrd-fs.target/start/replace... clare # [ 3.113683] systemd-sysroot-fstab-check[123]: Requesting swap.target/start/replace... clare # [ 3.117497] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.119006] systemd[1]: initrd-parse-etc.service: Deactivated successfully. clare # [ 3.126829] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 3.127629] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. clare # [ 3.139237] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.140380] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 3.109016] fuse: init (API version 7.45) bob # [ 3.115432] fuse: init (API version 7.45) bob # [ 3.120066] virtiofs virtio6: discovered new tag: nix-store bob # [ 3.126673] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory bob # [ 3.138016] virtiofs virtio6: virtio_fs_setup_dax: No cache capability alice # [ 3.133849] virtiofs virtio6: discovered new tag: nix-store alice # [ 3.134381] virtiofs virtio6: virtio_fs_setup_dax: No cache capability bob # [ 3.132816] (udev-worker)[97]: 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 alice # [ 3.141595] (udev-worker)[100]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory bob # [ 3.154867] virtiofs virtio7: discovered new tag: shared bob # [ 3.155401] virtiofs virtio7: virtio_fs_setup_dax: No cache capability alice # [ 3.143954] (udev-worker)[100]: 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 # [ 3.152768] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 3.162202] virtiofs virtio8: discovered new tag: xchg bob # [ 3.162726] virtiofs virtio8: virtio_fs_setup_dax: No cache capability bob # [ 3.156486] systemd[1]: Stopped Virtual Console Setup. alice # [ 3.159069] virtiofs virtio7: discovered new tag: shared bob # [ 3.157174] systemd[1]: Stopping Virtual Console Setup... bob # [ 3.159308] systemd[1]: Starting Virtual Console Setup... alice # [ 3.165879] virtiofs virtio7: virtio_fs_setup_dax: No cache capability alice # [ 3.169405] virtiofs virtio8: discovered new tag: xchg alice # [ 3.169913] virtiofs virtio8: virtio_fs_setup_dax: No cache capability alice # [ 3.170037] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.170950] systemd[1]: Stopped Virtual Console Setup. alice # [ 3.171550] systemd[1]: Stopping Virtual Console Setup... alice # [ 3.173467] systemd[1]: Starting Virtual Console Setup... bob # [ 3.188382] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 3.190581] systemd[1]: Finished Virtual Console Setup. alice # [ 3.199647] systemd-vconsole-setup[136]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 3.202219] systemd[1]: Finished Virtual Console Setup. clare # [ 3.400131] (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 clare # [ 3.403745] (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 clare # [ 3.423408] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.424364] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.428584] systemd[1]: Stopping Virtual Console Setup... clare # [ 3.429128] systemd[1]: Starting Virtual Console Setup... clare # [ 3.452234] systemd-vconsole-setup[153]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 3.454456] systemd[1]: Finished Virtual Console Setup. bob # [ 3.489556] systemd[1]: Mounting /sysroot/nix/.ro-store... bob # [ 3.497539] systemd[1]: Mounting /sysroot/nix/.rw-store... bob # [ 3.511400] systemd[1]: Mounting /sysroot/run... bob # [ 3.529830] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 3.557220] systemd[1]: Mounting /sysroot/tmp/xchg... bob # [ 3.565861] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 3.567289] systemd[1]: Mounting /sysroot/nix/.ro-store... alice # [ 3.576212] systemd[1]: Mounting /sysroot/nix/.rw-store... bob # [ 3.583997] systemd[1]: Mounted /sysroot/run. alice # [ 3.581218] systemd[1]: Mounting /sysroot/run... bob # [ 3.589046] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 3.594249] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.599197] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 3.605456] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 3.601197] systemd[1]: Mounting /sysroot/tmp/xchg... bob # [ 3.609831] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 3.610535] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 3.613164] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 3.623911] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 3.625245] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.663472] systemd[1]: Mounting /sysroot/nix/store... alice # [ 3.633688] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.638716] systemd[1]: Mounted /sysroot/run. alice # [ 3.643915] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 3.686445] systemd[1]: Mounted /sysroot/nix/store. alice # [ 3.646763] systemd[1]: Mounted /sysroot/tmp/xchg. clare # [ 3.687680] systemd[1]: Reached target Initrd File Systems. clare # [ 3.689749] systemd[1]: Starting Find NixOS closure... alice # [ 3.655341] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.695685] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... alice # [ 3.656498] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.719381] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. clare # [ 3.721048] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. clare # [ 3.732965] systemd[1]: Finished Find NixOS closure. clare # [ 3.734196] systemd[1]: Reached target Initrd Default Target. clare # [ 3.736162] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... clare # [ 3.759857] systemd[1]: Stopped target Initrd Default Target. clare # [ 3.761153] systemd[1]: Stopped target Basic System. clare # [ 3.761942] systemd[1]: Stopped target Initrd Root Device. clare # [ 3.764151] systemd[1]: Stopped target Path Units. clare # [ 3.764891] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. clare # [ 3.765931] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. clare # [ 3.768207] systemd[1]: Stopped target Slice Units. clare # [ 3.768733] systemd[1]: Stopped target Socket Units. clare # [ 3.769636] systemd[1]: Stopped target System Initialization. clare # [ 3.771822] systemd[1]: Stopped target Swaps. clare # [ 3.773484] systemd[1]: Stopped target Timer Units. clare # [ 3.776189] systemd[1]: dbus.socket: Deactivated successfully. clare # [ 3.778355] systemd[1]: Closed D-Bus System Message Bus Socket. clare # [ 3.778955] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. clare # [ 3.780897] systemd[1]: Stopped Find NixOS closure. clare # [ 3.782277] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.786870] systemd[1]: systemd-sysctl.service: Deactivated successfully. clare # [ 3.788689] systemd[1]: Stopped Apply Kernel Variables. clare # [ 3.789219] systemd[1]: systemd-modules-load.service: Deactivated successfully. clare # [ 3.791838] systemd[1]: Stopped Load Kernel Modules. clare # [ 3.792525] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. clare # [ 3.793192] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 3.793831] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. clare # [ 3.794425] systemd[1]: Stopped Create System Files and Directories. clare # [ 3.794959] systemd[1]: Stopped target Local File Systems. clare # [ 3.795469] systemd[1]: Stopped target Preparation for Local File Systems. clare # [ 3.797870] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. clare # [ 3.798692] systemd[1]: Stopped Coldplug All udev Devices. clare # [ 3.799224] systemd[1]: Stopping Rule-based Manager for Device Events and Files... clare # [ 3.799901] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.800714] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.801204] systemd[1]: initrd-cleanup.service: Deactivated successfully. clare # [ 3.811043] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. clare # [ 3.811870] systemd[1]: systemd-udevd.service: Deactivated successfully. clare # [ 3.812635] systemd[1]: Stopped Rule-based Manager for Device Events and Files. clare # [ 3.815028] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. clare # [ 3.816589] systemd[1]: Closed udev Control Socket. clare # [ 3.817965] systemd[1]: Starting Cleanup udev Database... clare # [ 3.819342] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. clare # [ 3.823910] systemd[1]: Stopped Create Static Device Nodes in /dev. clare # [ 3.824970] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. clare # [ 3.825691] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. clare # [ 3.826315] systemd[1]: kmod-static-nodes.service: Deactivated successfully. clare # [ 3.826948] systemd[1]: Stopped Create List of Static Device Nodes. clare # [ 3.827507] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.829331] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.851427] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. clare # [ 3.852353] systemd[1]: Finished Cleanup udev Database. clare # [ 3.852856] systemd[1]: Reached target Switch Root. clare # [ 3.857701] systemd[1]: Starting NixOS Activation... clare # [ 3.943745] initrd-nixos-activation-start[177]: booting system configuration /nix/store/48d43zfi2vli8nvl465idklqygs42ym9-nixos-system-clare-test clare # [ 3.973210] initrd-nixos-activation-start[177]: running activation script... clare # [ 4.219107] initrd-nixos-activation-start[200]: setting up /etc... clare # [ 4.302846] initrd-nixos-activation-start[200]: setting up age secrets... clare # [ 4.382478] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. clare # [ 4.384194] systemd[1]: Finished NixOS Activation. clare # [ 4.385194] systemd[1]: Starting Switch Root... clare # [ 4.405313] systemd[1]: Switching root. bob # [ 4.486373] systemd[1]: Mounting /sysroot/nix/store... bob # [ 4.515396] systemd[1]: Mounted /sysroot/nix/store. bob # [ 4.516901] systemd[1]: Reached target Initrd File Systems. bob # [ 4.518288] systemd[1]: Starting Find NixOS closure... bob # [ 4.523373] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... clare # [ 4.570753] systemd-journald[72]: Received SIGTERM from PID 1 (systemd). bob # [ 4.536262] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 4.537084] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob # [ 4.558269] systemd[1]: Finished Find NixOS closure. bob # [ 4.558863] systemd[1]: Reached target Initrd Default Target. bob # [ 4.560163] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 4.566251] systemd[1]: Mounting /sysroot/nix/store... bob # [ 4.582086] systemd[1]: Stopped target Initrd Default Target. bob # [ 4.583269] systemd[1]: Stopped target Basic System. bob # [ 4.587299] systemd[1]: Stopped target Initrd Root Device. bob # [ 4.588224] systemd[1]: Stopped target Path Units. bob # [ 4.588722] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. bob # [ 4.589419] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. bob # [ 4.590468] systemd[1]: Stopped target Slice Units. bob # [ 4.591441] systemd[1]: Stopped target Socket Units. bob # [ 4.592473] systemd[1]: Stopped target System Initialization. bob # [ 4.593736] systemd[1]: Stopped target Swaps. bob # [ 4.594860] systemd[1]: Stopped target Timer Units. bob # [ 4.596854] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 4.600410] systemd[1]: Closed D-Bus System Message Bus Socket. alice # [ 4.596453] systemd[1]: Mounted /sysroot/nix/store. bob # [ 4.601071] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. alice # [ 4.598004] systemd[1]: Reached target Initrd File Systems. bob # [ 4.603547] systemd[1]: Stopped Find NixOS closure. alice # [ 4.598945] systemd[1]: Starting Find NixOS closure... bob # [ 4.607868] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 4.609710] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 4.610368] systemd[1]: Stopped Apply Kernel Variables. bob # [ 4.610869] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 4.611518] systemd[1]: Stopped Load Kernel Modules. alice # [ 4.608544] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 4.614512] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. bob # [ 4.615344] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. bob # [ 4.616183] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 4.616864] systemd[1]: Stopped Create System Files and Directories. bob # [ 4.617457] systemd[1]: Stopped target Local File Systems. bob # [ 4.617985] systemd[1]: Stopped target Preparation for Local File Systems. bob # [ 4.618622] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. bob # [ 4.619320] systemd[1]: Stopped Coldplug All udev Devices. bob # [ 4.619852] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 4.628600] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 4.631506] systemd[1]: Stopped Virtual Console Setup. bob # [ 4.634496] systemd[1]: initrd-cleanup.service: Deactivated successfully. bob # [ 4.635234] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. bob # [ 4.635812] systemd[1]: systemd-udevd.service: Deactivated successfully. bob # [ 4.637571] systemd[1]: Stopped Rule-based Manager for Device Events and Files. bob # [ 4.640470] systemd[1]: systemd-udevd.service: Consumed 1.029s CPU time over 3.021s wall clock time, 20.7M memory peak. bob # [ 4.641540] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 4.637446] systemd[1]: Finished Find NixOS closure. bob # [ 4.643432] systemd[1]: Closed udev Control Socket. bob # [ 4.643994] systemd[1]: Starting Cleanup udev Database... alice # [ 4.640389] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 4.645331] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. alice # [ 4.642087] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob # [ 4.648218] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 4.644358] systemd[1]: Reached target Initrd Default Target. bob # [ 4.648880] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. bob # [ 4.650541] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. alice # [ 4.646152] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... bob # [ 4.651311] systemd[1]: kmod-static-nodes.service: Deactivated successfully. bob # [ 4.652386] systemd[1]: Stopped Create List of Static Device Nodes. bob # [ 4.653199] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 4.653839] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 4.669212] systemd[1]: Stopped target Initrd Default Target. alice # [ 4.670708] systemd[1]: Stopped target Basic System. alice # [ 4.672265] systemd[1]: Stopped target Initrd Root Device. alice # [ 4.673780] systemd[1]: Stopped target Path Units. bob # [ 4.678616] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 4.679457] systemd[1]: Finished Cleanup udev Database. alice # [ 4.676242] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 4.677004] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. alice # [ 4.678356] systemd[1]: Stopped target Slice Units. bob # [ 4.679984] systemd[1]: Reached target Switch Root. alice # [ 4.680161] systemd[1]: Stopped target Socket Units. bob # [ 4.684977] systemd[1]: Starting NixOS Activation... alice # [ 4.680701] systemd[1]: Stopped target System Initialization. alice # [ 4.681792] systemd[1]: Stopped target Swaps. alice # [ 4.683139] systemd[1]: Stopped target Timer Units. alice # [ 4.684194] systemd[1]: dbus.socket: Deactivated successfully. alice # [ 4.687867] systemd[1]: Closed D-Bus System Message Bus Socket. alice # [ 4.688599] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. alice # [ 4.689289] systemd[1]: Stopped Find NixOS closure. alice # [ 4.689876] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 4.690458] systemd[1]: systemd-sysctl.service: Deactivated successfully. alice # [ 4.692764] systemd[1]: Stopped Apply Kernel Variables. alice # [ 4.693669] systemd[1]: systemd-modules-load.service: Deactivated successfully. alice # [ 4.701355] systemd[1]: Stopped Load Kernel Modules. alice # [ 4.701981] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 4.702880] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. alice # [ 4.703600] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. alice # [ 4.712320] systemd[1]: Stopped Create System Files and Directories. alice # [ 4.712984] systemd[1]: Stopped target Local File Systems. alice # [ 4.713502] systemd[1]: Stopped target Preparation for Local File Systems. alice # [ 4.714125] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 4.714787] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 4.715288] systemd[1]: Stopping Rule-based Manager for Device Events and Files... alice # [ 4.715942] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 4.719764] systemd[1]: Stopped Virtual Console Setup. alice # [ 4.720552] systemd[1]: initrd-cleanup.service: Deactivated successfully. alice # [ 4.721160] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. alice # [ 4.721770] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 4.722422] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 4.727547] systemd[1]: systemd-udevd.service: Deactivated successfully. alice # [ 4.728492] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 4.729860] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 4.732128] systemd[1]: Closed udev Control Socket. alice # [ 4.732639] systemd[1]: Starting Cleanup udev Database... alice # [ 4.735536] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. alice # [ 4.736851] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 4.737409] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. alice # [ 4.738122] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. alice # [ 4.738747] systemd[1]: kmod-static-nodes.service: Deactivated successfully. alice # [ 4.739358] systemd[1]: Stopped Create List of Static Device Nodes. bob # [ 4.759203] initrd-nixos-activation-start[174]: booting system configuration /nix/store/d8kcs93jcill7b2299xbjmwnzczl6sbz-nixos-system-bob-test alice # [ 4.763294] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. alice # [ 4.768340] systemd[1]: Finished Cleanup udev Database. alice # [ 4.773783] systemd[1]: Reached target Switch Root. alice # [ 4.777956] systemd[1]: Starting NixOS Activation... bob # [ 4.821517] initrd-nixos-activation-start[174]: running activation script... alice # [ 5.200384] initrd-nixos-activation-start[171]: booting system configuration /nix/store/mkf3xhkpx4y16h0rmmhhsxkxj82z03ph-nixos-system-alice-test alice # [ 5.249012] initrd-nixos-activation-start[171]: running activation script... bob # [ 5.255112] initrd-nixos-activation-start[197]: setting up /etc... bob # [ 5.413647] initrd-nixos-activation-start[197]: setting up age secrets... bob # [ 5.550583] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 5.552200] systemd[1]: Finished NixOS Activation. bob # [ 5.554031] systemd[1]: Starting Switch Root... bob # [ 5.586170] systemd[1]: Switching root. alice # [ 5.707589] initrd-nixos-activation-start[194]: setting up /etc... bob # [ 5.779983] systemd-journald[72]: Received SIGTERM from PID 1 (systemd). alice # [ 5.967812] initrd-nixos-activation-start[194]: setting up age secrets... alice # [ 6.109360] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. alice # [ 6.111673] systemd[1]: Finished NixOS Activation. alice # [ 6.112721] systemd[1]: Starting Switch Root... alice # [ 6.134666] systemd[1]: Switching root. alice # [ 6.305676] systemd-journald[72]: Received SIGTERM from PID 1 (systemd). clare # [ 6.382365] 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 # [ 6.385471] systemd[1]: Detected virtualization qemu. clare # [ 6.386646] systemd[1]: Detected architecture arm64. clare # [ 6.388286] systemd[1]: Detected first boot. clare # [ 6.390316] systemd[1]: Initializing machine ID from random generator. bob # [ 6.479386] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 6.482626] systemd[1]: Detected virtualization qemu. bob # [ 6.483813] systemd[1]: Detected architecture arm64. bob # [ 6.485563] systemd[1]: Detected first boot. bob # [ 6.488090] systemd[1]: Initializing machine ID from random generator. clare # [ 6.631451] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 6.759601] systemd[1]: Applying preset policy. bob # [ 6.724550] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 6.853072] systemd[1]: Applying preset policy. clare # [ 6.924501] systemd[1]: Populated /etc with preset unit settings. alice # [ 6.981016] 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 # [ 6.983948] systemd[1]: Detected virtualization qemu. alice # [ 6.984869] systemd[1]: Detected architecture arm64. alice # [ 6.986204] systemd[1]: Detected first boot. alice # [ 6.988457] systemd[1]: Initializing machine ID from random generator. bob # [ 7.045588] systemd[1]: Populated /etc with preset unit settings. clare # [ 7.137336] systemd[1]: initrd-switch-root.service: Deactivated successfully. clare # [ 7.138306] systemd[1]: Stopped initrd-switch-root.service. clare # [ 7.141449] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. clare # [ 7.143607] systemd[1]: Created slice Slice /system/getty. clare # [ 7.145585] systemd[1]: Created slice User and Session Slice. clare # [ 7.146765] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 7.148110] systemd[1]: Started Forward Password Requests to Wall Directory Watch. clare # [ 7.150071] systemd[1]: Expecting device /dev/hvc0... clare # [ 7.151604] systemd[1]: Expecting device /dev/ttyAMA0... clare # [ 7.153412] systemd[1]: Reached target Local Encrypted Volumes. clare # [ 7.154408] systemd[1]: Stopped target initrd-fs.target. clare # [ 7.155510] systemd[1]: Stopped target initrd-root-fs.target. clare # [ 7.157353] systemd[1]: Stopped target initrd-switch-root.target. clare # [ 7.159018] systemd[1]: Reached target Virtual Machines and Containers. clare # [ 7.160834] systemd[1]: Reached target Path Units. clare # [ 7.162482] systemd[1]: Reached target Remote File Systems. clare # [ 7.164084] systemd[1]: Reached target Slice Units. clare # [ 7.165660] systemd[1]: Reached target Swaps. clare # [ 7.168127] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 7.173353] systemd[1]: Listening on Process Core Dump Socket. clare # [ 7.177933] systemd[1]: Listening on Credential Encryption/Decryption. clare # [ 7.180649] systemd[1]: Listening on Factory Reset Management. clare # [ 7.181883] systemd[1]: Listening on Hostname Service Socket. clare # [ 7.185537] systemd[1]: Starting Journal Log Access Socket... clare # [ 7.187757] systemd[1]: Listening on Journal Audit Socket. clare # [ 7.191861] systemd[1]: Listening on Console Output Muting Service Socket. clare # [ 7.194078] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 7.195553] systemd[1]: Listening on Network Management Varlink Socket. clare # [ 7.197672] systemd[1]: Listening on Network Management Netlink Socket. clare # [ 7.199511] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. clare # [ 7.201027] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os clare # [ 7.202697] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki clare # [ 7.206848] systemd[1]: Listening on Disk Repartitioning Service Socket. clare # [ 7.208027] systemd[1]: Listening on Resolve Monitor Varlink Socket. clare # [ 7.209214] systemd[1]: Listening on Resolve Service Varlink Socket. clare # [ 7.210706] systemd[1]: Listening on udev Control Socket. clare # [ 7.212137] systemd[1]: Listening on udev Varlink Socket. clare # [ 7.214973] systemd[1]: Mounting Huge Pages File System... clare # [ 7.217747] systemd[1]: Mounting POSIX Message Queue File System... clare # [ 7.225099] systemd[1]: Mounting Kernel Debug File System... clare # [ 7.238364] systemd[1]: Mounting Kernel Trace File System... clare # [ 7.250880] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 7.253313] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 7.268210] systemd[1]: Mounting Kernel Configuration File System... clare # [ 7.272844] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm clare # [ 7.277488] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore alice # [ 7.237394] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 7.283352] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 7.267452] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 7.268442] systemd[1]: Stopped initrd-switch-root.service. clare # [ 7.307891] systemd[1]: Mounting FUSE Control File System... bob # [ 7.271557] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. clare # [ 7.308374] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 bob # [ 7.274895] systemd[1]: Created slice Slice /system/getty. bob # [ 7.276649] systemd[1]: Created slice User and Session Slice. bob # [ 7.277720] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 7.278948] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 7.280683] systemd[1]: Expecting device /dev/hvc0... bob # [ 7.282029] systemd[1]: Expecting device /dev/ttyAMA0... bob # [ 7.283453] systemd[1]: Reached target Local Encrypted Volumes. bob # [ 7.285133] systemd[1]: Stopped target initrd-fs.target. bob # [ 7.286048] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 7.287324] systemd[1]: Stopped target initrd-switch-root.target. bob # [ 7.288785] systemd[1]: Reached target Virtual Machines and Containers. bob # [ 7.290417] systemd[1]: Reached target Path Units. bob # [ 7.291868] systemd[1]: Reached target Remote File Systems. clare # [ 7.328928] systemd[1]: Starting Journal Service... bob # [ 7.293476] systemd[1]: Reached target Slice Units. bob # [ 7.294892] systemd[1]: Reached target Swaps. bob # [ 7.297608] systemd[1]: Listening on Query the User Interactively for a Password. bob # [ 7.300809] systemd[1]: Listening on Process Core Dump Socket. bob # [ 7.303146] systemd[1]: Listening on Credential Encryption/Decryption. clare # [ 7.340452] systemd[1]: Starting Load Kernel Modules... bob # [ 7.305763] systemd[1]: Listening on Factory Reset Management. bob # [ 7.306878] systemd[1]: Listening on Hostname Service Socket. bob # [ 7.310544] systemd[1]: Starting Journal Log Access Socket... bob # [ 7.312424] systemd[1]: Listening on Journal Audit Socket. bob # [ 7.315383] systemd[1]: Listening on Console Output Muting Service Socket. bob # [ 7.317376] systemd[1]: Listening on Network Management Metrics Varlink Socket. bob # [ 7.319775] systemd[1]: Listening on Network Management Varlink Socket. bob # [ 7.321824] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 7.324279] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 7.326103] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os bob # [ 7.327775] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki clare # [ 7.367861] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... bob # [ 7.335895] systemd[1]: Listening on Disk Repartitioning Service Socket. bob # [ 7.337196] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 7.338374] systemd[1]: Listening on Resolve Service Varlink Socket. bob # [ 7.340020] systemd[1]: Listening on udev Control Socket. bob # [ 7.341233] systemd[1]: Listening on udev Varlink Socket. clare # [ 7.375080] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 7.381190] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 7.344624] systemd[1]: Mounting Huge Pages File System... bob # [ 7.347627] systemd[1]: Mounting POSIX Message Queue File System... bob # [ 7.364385] systemd[1]: Mounting Kernel Debug File System... bob # [ 7.369041] systemd[1]: Mounting Kernel Trace File System... clare # [ 7.414264] systemd[1]: Starting Coldplug All udev Devices... clare # [ 7.420090] systemd[1]: Listening on Journal Log Access Socket. bob # [ 7.383827] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 7.385104] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 7.383805] systemd[1]: Applying preset policy. clare # [ 7.428538] systemd[1]: Mounted Huge Pages File System. bob # [ 7.389964] systemd[1]: Mounting Kernel Configuration File System... bob # [ 7.391403] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm bob # [ 7.394453] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 7.430808] systemd[1]: Mounted POSIX Message Queue File System. clare # [ 7.435174] systemd-journald[285]: Collecting audit messages is enabled. clare # [ 7.437244] systemd[1]: Mounted Kernel Debug File System. bob # [ 7.402367] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 7.443275] systemd[1]: Queued start job for default target Multi-User System. clare # [ 7.446376] systemd[1]: systemd-journald.service: Deactivated successfully. bob # [ 7.418040] systemd[1]: Mounting FUSE Control File System... bob # [ 7.420963] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 clare # [ 7.458677] systemd[1]: Started Journal Service. clare # [ 7.453326] systemd[1]: Mounted Kernel Trace File System. clare # [ 7.456178] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 7.475105] systemd-modules-load[286]: Module 'atkbd' is built in clare # [ 7.475763] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 7.453471] systemd[1]: Starting Journal Service... clare # [ 7.484322] systemd-modules-load[286]: Module 'loop' is built in clare # [ 7.498178] systemd[1]: Finished Load Kernel Modules. clare # [ 7.507510] EXT4-fs (vda): re-mounted eec86074-08cc-4b2f-b215-04f44adf23c8. bob # [ 7.474819] systemd[1]: Starting Load Kernel Modules... clare # [ 7.507976] systemd[1]: Starting Firewall... clare # [ 7.518065] systemd[1]: Starting Apply Kernel Variables... bob # [ 7.489793] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 7.524298] systemd[1]: Finished Remount Root and Kernel File Systems. clare # [ 7.524981] systemd[1]: Mounted Kernel Configuration File System. clare # [ 7.534496] systemd-oomd[288]: No swap; memory pressure usage will be degraded bob # [ 7.513075] systemd[1]: Starting Remount Root and Kernel File Systems... bob # [ 7.513553] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 7.547602] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 7.521670] systemd[1]: Starting Coldplug All udev Devices... bob # [ 7.523982] systemd[1]: Listening on Journal Log Access Socket. bob # [ 7.528486] systemd[1]: Mounted Huge Pages File System. clare # [ 7.556659] systemd[1]: Listening on Disk Image Download Service Socket. bob # [ 7.529869] systemd[1]: Mounted POSIX Message Queue File System. clare # [ 7.569656] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 7.541901] systemd[1]: Mounted Kernel Debug File System. clare # [ 7.570455] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 7.545208] systemd-journald[289]: Collecting audit messages is enabled. clare # [ 7.580135] systemd[1]: Starting Load/Save OS Random Seed... clare # [ 7.580789] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 7.555684] systemd[1]: Mounted Kernel Trace File System. clare # [ 7.588493] systemd[1]: Mounted FUSE Control File System. bob # [ 7.556795] systemd[1]: Queued start job for default target Multi-User System. bob # [ 7.557713] systemd[1]: systemd-journald.service: Deactivated successfully. bob # [ 7.561911] systemd-modules-load[290]: Module 'atkbd' is built in bob # [ 7.564292] systemd-modules-load[290]: Module 'loop' is built in bob # [ 7.573640] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 7.576839] systemd[1]: Started Journal Service. alice # [ 7.579951] systemd[1]: Populated /etc with preset unit settings. bob # [ 7.593618] systemd[1]: Finished Load Kernel Modules. bob # [ 7.594307] systemd[1]: Mounted Kernel Configuration File System. bob # [ 7.594906] systemd[1]: Mounted FUSE Control File System. bob # [ 7.616584] systemd[1]: Starting Firewall... bob # [ 7.632324] EXT4-fs (vda): re-mounted 8e51f2ba-7b6a-436e-89fa-572b3da529ec. bob # [ 7.627506] systemd[1]: Starting Apply Kernel Variables... clare # [ 7.680145] systemd-journald[285]: Received client request to flush runtime journal. bob # [ 7.637558] systemd-oomd[291]: No swap; memory pressure usage will be degraded bob # [ 7.642681] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 7.652530] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 7.655012] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 7.674458] systemd[1]: Listening on Disk Image Download Service Socket. bob # [ 7.689852] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 7.690558] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 7.698727] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 7.699391] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 7.742860] systemd[1]: Finished Load/Save OS Random Seed. clare # [ 7.743501] systemd[1]: Reached target First Boot Complete. clare # [ 7.752464] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 7.753275] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 7.763399] systemd[1]: Finished Apply Kernel Variables. clare # [ 7.763995] systemd[1]: Finished Flush Journal to Persistent Storage. clare # [ 7.769867] systemd[1]: Starting Network Name Resolution... bob # [ 7.802622] systemd-journald[289]: Received client request to flush runtime journal. alice # [ 7.839441] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 7.840440] systemd[1]: Stopped initrd-switch-root.service. alice # [ 7.842878] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. alice # [ 7.845682] systemd[1]: Created slice Slice /system/getty. alice # [ 7.847671] systemd[1]: Created slice User and Session Slice. alice # [ 7.848852] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 7.850677] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 7.852436] systemd[1]: Expecting device /dev/hvc0... alice # [ 7.853771] systemd[1]: Expecting device /dev/ttyAMA0... alice # [ 7.855089] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 7.856563] systemd[1]: Stopped target initrd-fs.target. alice # [ 7.857907] systemd[1]: Stopped target initrd-root-fs.target. alice # [ 7.859334] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 7.860296] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 7.861328] systemd[1]: Reached target Path Units. alice # [ 7.862211] systemd[1]: Reached target Remote File Systems. alice # [ 7.863128] systemd[1]: Reached target Slice Units. alice # [ 7.864027] systemd[1]: Reached target Swaps. alice # [ 7.867403] systemd[1]: Listening on Query the User Interactively for a Password. alice # [ 7.870817] systemd[1]: Listening on Process Core Dump Socket. alice # [ 7.873191] systemd[1]: Listening on Credential Encryption/Decryption. alice # [ 7.875877] systemd[1]: Listening on Factory Reset Management. alice # [ 7.877086] systemd[1]: Listening on Hostname Service Socket. clare # [ 7.917983] systemd-resolved[316]: Positive Trust Anchors: alice # [ 7.884701] systemd[1]: Starting Journal Log Access Socket... bob # [ 7.882842] systemd[1]: Finished Apply Kernel Variables. clare # [ 7.918845] systemd-resolved[316]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d clare # [ 7.919728] systemd-resolved[316]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 alice # [ 7.886976] systemd[1]: Listening on Journal Audit Socket. bob # [ 7.888844] systemd[1]: Starting Network Name Resolution... bob # [ 7.889520] systemd[1]: Finished Load/Save OS Random Seed. bob # [ 7.890012] systemd[1]: Reached target First Boot Complete. alice # [ 7.892804] systemd[1]: Listening on Console Output Muting Service Socket. alice # [ 7.894268] systemd[1]: Listening on Network Management Metrics Varlink Socket. alice # [ 7.896752] systemd[1]: Listening on Network Management Varlink Socket. alice # [ 7.898430] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 7.897814] systemd[1]: Finished Flush Journal to Persistent Storage. alice # [ 7.900209] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. alice # [ 7.902000] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 7.903864] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki clare # [ 7.928272] systemd-resolved[316]: 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.908541] systemd[1]: Listening on Disk Repartitioning Service Socket. alice # [ 7.909810] systemd[1]: Listening on Resolve Monitor Varlink Socket. alice # [ 7.910931] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 7.912066] systemd[1]: Listening on udev Control Socket. alice # [ 7.913065] systemd[1]: Listening on udev Varlink Socket. alice # [ 7.916772] systemd[1]: Mounting Huge Pages File System... alice # [ 7.919898] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 7.935313] systemd[1]: Mounting Kernel Debug File System... alice # [ 7.940776] systemd[1]: Mounting Kernel Trace File System... bob # [ 7.940776] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 7.950198] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 7.957576] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 7.958645] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 7.997025] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 7.998347] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 8.006175] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 7.975153] systemd[1]: Mounting Kernel Configuration File System... alice # [ 7.979438] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 7.984900] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore alice # [ 7.989076] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 7.992346] systemd-resolved[314]: Positive Trust Anchors: bob # [ 7.993001] systemd-resolved[314]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d bob # [ 7.993831] systemd-resolved[314]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 bob # [ 7.994672] systemd-resolved[314]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 8.010244] systemd[1]: Mounting FUSE Control File System... alice # [ 8.015445] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 alice # [ 8.036959] systemd[1]: Starting Journal Service... alice # [ 8.062267] systemd[1]: Starting Load Kernel Modules... alice # [ 8.071563] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... alice # [ 8.088560] systemd[1]: Starting Remount Root and Kernel File Systems... alice # [ 8.090147] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 8.133226] systemd[1]: Mounting /run/wrappers... clare # [ 8.151914] systemd-resolved[316]: Using system hostname 'clare'. clare # [ 8.154499] systemd[1]: Started Network Name Resolution. alice # [ 8.119428] systemd[1]: Starting Coldplug All udev Devices... alice # [ 8.121860] systemd[1]: Listening on Journal Log Access Socket. alice # [ 8.122389] systemd[1]: Mounted Huge Pages File System. alice # [ 8.122847] systemd[1]: Mounted POSIX Message Queue File System. clare # [ 8.161918] systemd[1]: Finished Coldplug All udev Devices. alice # [ 8.135772] systemd[1]: Mounted Kernel Debug File System. alice # [ 8.137287] systemd[1]: Mounted Kernel Trace File System. alice # [ 8.140149] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 8.148535] systemd[1]: Finished Load Kernel Modules. clare # [ 8.189422] systemd-udevd[324]: Using default interface naming scheme 'v261'. alice # [ 8.165088] systemd[1]: Starting Firewall... clare # [ 8.199684] systemd[1]: Mounted /run/wrappers. clare # [ 8.200397] systemd[1]: Reached target Local File Systems. clare # [ 8.204821] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 8.211043] systemd[1]: Starting register-nix-paths.service... alice # [ 8.178657] systemd-journald[279]: Collecting audit messages is enabled. bob # [ 8.178837] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 8.179542] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 8.218232] systemd[1]: Starting Create SUID/SGID Wrappers... clare # [ 8.218923] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. clare # [ 8.222884] systemd[1]: Starting Save Transient machine-id to Disk... alice # [ 8.189759] systemd[1]: Starting Apply Kernel Variables... bob # [ 8.189186] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 8.230953] systemd[1]: Starting Create System Files and Directories... alice # [ 8.194570] systemd[1]: Queued start job for default target Multi-User System. bob # [ 8.204794] systemd-resolved[314]: Using system hostname 'bob'. alice # [ 8.203129] systemd[1]: systemd-journald.service: Deactivated successfully. alice # [ 8.214352] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 8.214035] systemd[1]: Started Network Name Resolution. alice # [ 8.210565] systemd-modules-load[280]: Module 'atkbd' is built in alice # [ 8.211488] systemd-modules-load[280]: Module 'loop' is built in alice # [ 8.220267] systemd[1]: Started Journal Service. alice # [ 8.221237] systemd-oomd[281]: No swap; memory pressure usage will be degraded alice # [ 8.230707] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. alice # [ 8.243380] EXT4-fs (vda): re-mounted 58a30e0a-f96c-42d0-ae38-0862f6802fe9. alice # [ 8.249096] systemd[1]: Mounted Kernel Configuration File System. bob # [ 8.273628] systemd[1]: Mounting /run/wrappers... alice # [ 8.270709] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 8.285026] systemd[1]: Finished Coldplug All udev Devices. alice # [ 8.307147] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 8.315771] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 8.324450] systemd[1]: Mounted /run/wrappers. alice # [ 8.320443] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 8.325778] systemd[1]: Reached target Local File Systems. alice # [ 8.326041] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 8.330784] systemd[1]: Listening on Boot Loader Control Service Socket. alice # [ 8.326757] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 8.333186] systemd[1]: Starting register-nix-paths.service... bob # [ 8.337188] systemd-udevd[331]: Using default interface naming scheme 'v261'. bob # [ 8.341368] systemd[1]: Starting Create SUID/SGID Wrappers... bob # [ 8.344666] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. clare # [ 8.393663] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 8.358814] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 8.376384] systemd[1]: Starting Create System Files and Directories... clare # [ 8.418708] systemd[1]: Finished Create System Files and Directories. clare # [ 8.425483] systemd[1]: Starting Rebuild Journal Catalog... alice # [ 8.391191] systemd[1]: Mounted FUSE Control File System. clare # [ 8.441000] systemd[1]: Starting Record System Boot/Shutdown in UTMP... alice # [ 8.427771] systemd-journald[279]: Received client request to flush runtime journal. alice # [ 8.529736] systemd[1]: Finished Apply Kernel Variables. alice # [ 8.540193] systemd[1]: Starting Network Name Resolution... alice # [ 8.540908] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 8.547929] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 8.548311] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 8.548998] systemd[1]: Finished Load/Save OS Random Seed. alice # [ 8.549638] systemd[1]: Reached target First Boot Complete. bob # [ 8.566471] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. alice # [ 8.564229] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 8.575315] systemd[1]: Finished Save Transient machine-id to Disk. clare # [ 8.628094] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 8.632635] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. clare # [ 8.646393] systemd[1]: Finished Save Transient machine-id to Disk. bob # [ 8.620221] systemd[1]: Finished Create System Files and Directories. bob # [ 8.624277] systemd[1]: Starting Rebuild Journal Catalog... bob # [ 8.637309] systemd[1]: Starting Record System Boot/Shutdown in UTMP... clare # [ 8.671831] systemd[1]: Finished Rebuild Journal Catalog. clare # [ 8.682959] systemd[1]: Starting Update is Completed... alice # [ 8.675729] systemd-resolved[306]: Positive Trust Anchors: alice # [ 8.679640] systemd-resolved[306]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d alice # [ 8.685999] systemd-resolved[306]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 alice # [ 8.687032] systemd-resolved[306]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 8.757975] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 8.759224] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 8.801350] systemd[1]: Finished Update is Completed. alice # [ 8.763415] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 8.805237] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 8.813251] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 8.865871] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 8.846127] systemd[1]: Mounting /run/wrappers... bob # [ 8.866674] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 8.879352] systemd[1]: Starting Update is Completed... alice # [ 8.913118] systemd-resolved[306]: Using system hostname 'alice'. alice # [ 8.921394] systemd[1]: Started Network Name Resolution. bob # [ 8.950144] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 8.947384] systemd[1]: Mounted /run/wrappers. alice # [ 8.947963] systemd[1]: Reached target Local File Systems. alice # [ 8.956148] systemd[1]: Listening on Boot Loader Control Service Socket. bob # [ 8.962899] systemd[1]: Finished Update is Completed. alice # [ 8.965602] systemd[1]: Starting register-nix-paths.service... alice # [ 8.970139] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 8.970850] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. alice # [ 8.983117] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 8.993391] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 9.005057] systemd[1]: Starting Create System Files and Directories... alice # [ 9.046096] systemd-udevd[319]: Using default interface naming scheme 'v261'. clare # [ 9.106409] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. alice # [ 9.076766] systemd[1]: Finished Coldplug All udev Devices. clare # [ 9.242804] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. bob # [ 9.216590] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. alice # [ 9.228957] systemd[1]: Finished Create System Files and Directories. bob # [ 9.255109] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. alice # [ 9.253028] systemd[1]: Starting Rebuild Journal Catalog... alice # [ 9.261498] systemd[1]: Starting Record System Boot/Shutdown in UTMP... alice # [ 9.268824] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 9.291945] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. alice # [ 9.314044] systemd[1]: Finished Save Transient machine-id to Disk. clare # [ 9.437791] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. clare # [ 9.446274] systemd[1]: Finished Create SUID/SGID Wrappers. clare # [ 9.462085] (udev-worker)[391]: Network interface NamePolicy= disabled on kernel command line. alice # [ 9.424119] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 9.474876] (udev-worker)[390]: Network interface NamePolicy= disabled on kernel command line. alice # [ 9.435090] systemd[1]: Finished Rebuild Journal Catalog. alice # [ 9.443837] systemd[1]: Starting Update is Completed... clare # [ 9.527847] systemd[1]: Finished register-nix-paths.service. clare # [ 9.530217] systemd[1]: Reached target System Initialization. clare # [ 9.530846] systemd[1]: Started Discard unused filesystem blocks once a week. clare # [ 9.531462] systemd[1]: Started Daily Cleanup of Temporary Directories. clare # [ 9.537111] systemd[1]: Reached target Timer Units. clare # [ 9.537720] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 9.548471] systemd[1]: Listening on harmonia binary cache socket. clare # [ 9.549276] systemd[1]: Listening on Nix Daemon Socket. clare # [ 9.551884] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. clare # [ 9.556661] systemd[1]: Reached target Socket Units. clare # [ 9.560350] systemd[1]: Reached target Basic System. clare # [ 9.560909] systemd[1]: Started backdoor.service. clare # [ 9.576316] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 9.547153] (udev-worker)[384]: Network interface NamePolicy= disabled on kernel command line. alice # [ 9.545234] systemd[1]: Finished Update is Completed. clare # [ 9.586110] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 9.554510] (udev-worker)[389]: Network interface NamePolicy= disabled on kernel command line. clare # [ 9.610079] systemd[1]: Starting Post-Boot Actions... clare # [ 9.617183] systemd[1]: Started Reset console on configuration changes. clare # [ 9.673303] systemd[1]: Starting D-Bus System Message Bus... bob # [ 9.650741] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. alice # [ 9.648583] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 9.654725] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 9.690497] systemd[1]: Finished register-nix-paths.service. bob # [ 9.691198] systemd[1]: Reached target System Initialization. bob # [ 9.702927] systemd[1]: Started Discard unused filesystem blocks once a week. bob # [ 9.705085] systemd[1]: Started Daily Cleanup of Temporary Directories. alice # [ 9.701267] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 9.707985] systemd[1]: Reached target Timer Units. bob # [ 9.708587] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 9.709174] systemd[1]: Listening on Nix Daemon Socket. bob # [ 9.709679] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. bob # [ 9.710417] systemd[1]: Reached target Socket Units. bob # [ 9.710907] systemd[1]: Reached target Basic System. clare # connecting to host... bob # [ 9.715803] systemd[1]: Started backdoor.service. bob # [ 9.720671] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 9.726021] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 9.739470] systemd[1]: Starting Post-Boot Actions... bob # [ 9.761400] systemd[1]: Started Reset console on configuration changes. clare # [ 9.806978] mousedev: PS/2 mouse device common for all mice clare # [ 9.839124] systemd[1]: Finished Post-Boot Actions. clare # [ 9.845000] nsncd[470]: Sep 21 10:07:28.585 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # [ 9.846333] systemd[1]: Started Name Service Cache Daemon (nsncd). clare # [ 9.846918] systemd[1]: Reached target Host and Network Name Lookups. clare # [ 9.847472] systemd[1]: Reached target User and Group Name Lookups. clare # [ 9.857710] systemd[1]: Starting User Login Management... bob # [ 9.819693] systemd[1]: Starting D-Bus System Message Bus... clare # [ 9.908183] systemd[1]: Finished Import lastlog data into lastlog2 database. alice # [ 9.922345] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. bob # [ 9.956095] systemd[1]: Finished Post-Boot Actions. bob # connecting to host... clare # [ 10.021425] systemd-logind[500]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) clare # [ 10.024777] systemd-logind[500]: Watching system buttons on /dev/input/event0 (gpio-keys) clare # [ 10.026583] systemd-logind[500]: New seat seat0. clare # [ 10.031280] systemd[1]: Started User Login Management. clare # [ 10.034788] dbus-broker-launch[475]: Looking up NSS user entry for 'systemd-timesync'... clare # [ 10.039688] systemd[1]: Starting linger-users.service... bob # [ 10.010527] systemd[1]: Started Name Service Cache Daemon (nsncd). alice # [ 10.009519] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. bob # [ 10.015926] nsncd[473]: Sep 21 10:07:28.755 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # [ 10.054099] dbus-broker-launch[475]: NSS returned no entry for 'systemd-timesync' clare # [ 10.055877] dbus-broker-launch[475]: Invalid user-name in /nix/store/qbpalghp756r6i8828kqv4b1w9v4pj5k-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" bob # [ 10.023373] systemd[1]: Finished Import lastlog data into lastlog2 database. bob # [ 10.025507] systemd[1]: Reached target Host and Network Name Lookups. bob # [ 10.026148] systemd[1]: Reached target User and Group Name Lookups. bob # [ 10.036773] mousedev: PS/2 mouse device common for all mice bob: Guest shell says: b'Spawning backdoor root shell...\n' bob # [ 10.037433] systemd[1]: Starting User Login Management... clare # [ 10.084848] systemd[1]: Started D-Bus System Message Bus. bob: connected to guest root shell bob: (connecting took 10.41 seconds) bob: (finished: waiting for the VM to finish booting, in 10.41 seconds) bob # [ 10.087777] dbus-broker-launch[477]: Looking up NSS user entry for 'systemd-timesync'... clare # [ 10.132741] dbus-broker-launch[475]: Ready clare # [ 10.144099] systemd[1]: linger-users.service: Deactivated successfully. clare # [ 10.147106] systemd[1]: Finished linger-users.service. bob # [ 10.113872] dbus-broker-launch[477]: NSS returned no entry for 'systemd-timesync' bob # [ 10.114654] dbus-broker-launch[477]: Invalid user-name in /nix/store/v0ibjj1v9niigv3kglsa2hsp7vpjna5p-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" bob # [ 10.167196] systemd[1]: Started D-Bus System Message Bus. alice # [ 10.189871] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. alice # [ 10.190677] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 10.229138] systemd-logind[505]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 10.236767] systemd-logind[505]: Watching system buttons on /dev/input/event0 (gpio-keys) bob # [ 10.237580] systemd-logind[505]: New seat seat0. alice # [ 10.234346] (udev-worker)[387]: Network interface NamePolicy= disabled on kernel command line. alice # [ 10.240967] (udev-worker)[397]: Network interface NamePolicy= disabled on kernel command line. bob # [ 10.247230] systemd[1]: Started User Login Management. bob # [ 10.253107] dbus-broker-launch[477]: Ready bob # [ 10.260284] systemd[1]: Starting linger-users.service... alice # [ 10.278891] systemd[1]: Finished register-nix-paths.service. alice # [ 10.281419] systemd[1]: Reached target System Initialization. alice # [ 10.282089] systemd[1]: Started Discard unused filesystem blocks once a week. alice # [ 10.282782] systemd[1]: Started Daily Cleanup of Temporary Directories. alice # [ 10.283409] systemd[1]: Reached target Timer Units. alice # [ 10.297357] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 10.298144] systemd[1]: Listening on Nix Daemon Socket. alice # [ 10.298667] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 10.299470] systemd[1]: Reached target Socket Units. alice # [ 10.299983] systemd[1]: Reached target Basic System. alice # [ 10.306380] systemd[1]: Started backdoor.service. alice # [ 10.312193] systemd[1]: Starting Import lastlog data into lastlog2 database... clare # [ 10.360690] systemd[1]: Finished Firewall. clare # [ 10.362195] systemd[1]: Reached target Preparation for Network. clare # [ 10.364864] systemd[1]: Listening on Network Management Resolve Hook Socket. alice # [ 10.326026] systemd[1]: Starting Name Service Cache Daemon (nsncd)... clare # [ 10.371188] systemd[1]: Starting Network Management... alice # [ 10.347421] systemd[1]: Starting Post-Boot Actions... alice # [ 10.356150] systemd[1]: Started Reset console on configuration changes. bob # [ 10.372660] systemd[1]: linger-users.service: Deactivated successfully. bob # [ 10.375669] systemd[1]: Finished linger-users.service. alice # [ 10.388321] systemd[1]: Starting D-Bus System Message Bus... alice # connecting to host... alice # [ 10.531524] systemd[1]: Finished Post-Boot Actions. alice # [ 10.546067] systemd[1]: Started Name Service Cache Daemon (nsncd). alice # [ 10.552682] nsncd[463]: Sep 21 10:07:29.287 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # [ 10.606822] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 10.567123] systemd[1]: Finished Firewall. alice # [ 10.564296] systemd[1]: Reached target Host and Network Name Lookups. alice # [ 10.565093] systemd[1]: Reached target User and Group Name Lookups. bob # [ 10.571728] systemd[1]: Reached target Preparation for Network. bob # [ 10.572491] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # [ 10.577084] systemd[1]: Starting Network Management... alice # [ 10.580756] systemd[1]: Starting User Login Management... clare # [ 10.656242] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' clare # [ 10.657927] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' clare # [ 10.661096] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 clare # [ 10.661514] cfg80211: failed to load regulatory.db alice # [ 10.624297] systemd[1]: Finished Import lastlog data into lastlog2 database. clare # [ 10.687210] systemd-networkd[544]: lo: Link UP clare # [ 10.687732] systemd-networkd[544]: lo: Gained carrier clare # [ 10.692727] systemd[1]: Started Network Management. clare # [ 10.693314] systemd[1]: Reached target Network. clare # [ 10.696895] systemd-networkd[544]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 10.662434] dbus-broker-launch[467]: Looking up NSS user entry for 'systemd-timesync'... clare # [ 10.704667] systemd-networkd[544]: eth1: Configuring with /etc/systemd/network/40-eth1.network. clare # [ 10.710031] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 10.710827] systemd-networkd[544]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. clare # [ 10.716087] systemd-networkd[544]: eth1: Link UP clare # [ 10.716629] systemd-networkd[544]: eth1: Gained carrier clare # [ 10.717125] systemd-networkd[544]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 10.718477] systemd[1]: Starting Permit User Sessions... alice # [ 10.682090] dbus-broker-launch[467]: NSS returned no entry for 'systemd-timesync' alice # [ 10.682769] dbus-broker-launch[467]: Invalid user-name in /nix/store/nnbavp86sdcc05f1v79mqcwi08cvshd7-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" clare # [ 10.731347] systemd-networkd[544]: eth0: Link UP clare # [ 10.731953] systemd-networkd[544]: eth0: Gained carrier clare # [ 10.736939] systemd-networkd[544]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 alice # [ 10.741571] systemd[1]: Started D-Bus System Message Bus. alice # [ 10.747982] systemd-logind[492]: Watching system buttons on /dev/input/event0 (gpio-keys) alice # [ 10.751827] systemd-logind[492]: New seat seat0. bob # [ 10.767794] cfg80211: Loading compiled-in X.509 certificates for regulatory database alice # [ 10.757622] systemd[1]: Started User Login Management. clare # [ 10.799488] systemd[1]: Finished Permit User Sessions. clare # [ 10.807216] systemd[1]: Started Getty on tty1. alice # [ 10.768843] systemd[1]: Starting linger-users.service... clare # [ 10.809864] systemd[1]: Reached target Login Prompts. clare # [ 10.810470] systemd[1]: Reached target Multi-User System. alice # [ 10.779427] mousedev: PS/2 mouse device common for all mice alice # [ 10.778994] systemd-logind[492]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) clare # [ 10.823891] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 10.826678] systemd[1]: Startup finished in 844ms (kernel) + 5.089s (initrd) + 4.892s (userspace) = 10.825s. alice # [ 10.796584] dbus-broker-launch[467]: Ready bob # [ 10.810810] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' bob # [ 10.811426] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 10.815242] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 bob # [ 10.815668] cfg80211: failed to load regulatory.db bob # [ 10.838800] systemd-networkd[556]: lo: Link UP bob # [ 10.839761] systemd-networkd[556]: lo: Gained carrier alice # [ 10.836104] systemd[1]: linger-users.service: Deactivated successfully. alice # [ 10.837829] systemd[1]: Finished linger-users.service. bob # [ 10.847913] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 10.857461] systemd[1]: Started Network Management. bob # [ 10.857979] systemd-networkd[556]: eth1: Configuring with /etc/systemd/network/40-eth1.network. bob # [ 10.858738] systemd[1]: Reached target Network. bob # [ 10.859170] systemd[1]: Reached target Network is Online. bob # [ 10.868208] systemd-networkd[556]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. bob # [ 10.869166] systemd-networkd[556]: eth1: Link UP bob # [ 10.869625] systemd-networkd[556]: eth1: Gained carrier bob # [ 10.870064] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 10.871351] systemd[1]: Starting ncps binary cache proxy service... bob # [ 10.877990] systemd-networkd[556]: eth0: Link UP bob # [ 10.878485] systemd-networkd[556]: eth0: Gained carrier bob # [ 10.885273] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... bob # [ 10.888613] systemd-networkd[556]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 10.903357] systemd[1]: Starting Permit User Sessions... clare # [ 10.986762] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 10.976627] systemd[1]: Finished Permit User Sessions. bob # [ 10.989445] systemd[1]: Started Getty on tty1. bob # [ 10.989915] systemd[1]: Reached target Login Prompts. bob # [ 10.992156] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. alice # [ 11.012570] systemd[1]: Finished Firewall. alice # [ 11.014112] systemd[1]: Reached target Preparation for Network. alice # [ 11.016862] systemd[1]: Listening on Network Management Resolve Hook Socket. alice # [ 11.021865] systemd[1]: Starting Network Management... bob # [ 11.033198] ncps-pre-start[563]: /nix/store/0m8la0b5a7q97yz1mc9c2x9xb5mc67vz-ncps-0.9.4/bin/dbmate-ncps up bob # [ 11.200927] ncps-pre-start[578]: Creating: /var/lib/ncps/db/db.sqlite alice # [ 11.225223] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 11.242801] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 alice # [ 11.269090] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' alice # [ 11.269648] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' alice # [ 11.273037] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 alice # [ 11.273502] cfg80211: failed to load regulatory.db alice # [ 11.299851] systemd-networkd[535]: lo: Link UP alice # [ 11.300554] systemd-networkd[535]: lo: Gained carrier alice # [ 11.308186] systemd[1]: Started Network Management. alice # [ 11.312091] systemd[1]: Reached target Network. clare # [ 11.355352] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 11.320361] ncps-pre-start[578]: Applying: 20241210054814_create-narinfos-table.sql alice # [ 11.315756] systemd-networkd[535]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. alice # [ 11.319912] systemd-networkd[535]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 11.326560] ncps-pre-start[578]: Applied: 20241210054814_create-narinfos-table.sql in 6.27068ms clare # [ 11.363079] systemd[1]: Starting Virtual Console Setup... bob # [ 11.327347] ncps-pre-start[578]: Applying: 20241210054829_create-nars-table.sql alice # [ 11.328467] systemd-networkd[535]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 11.329281] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... alice # [ 11.329947] systemd-networkd[535]: eth0: Link UP alice # [ 11.330397] systemd-networkd[535]: eth0: Gained carrier bob # [ 11.336366] ncps-pre-start[578]: Applied: 20241210054829_create-nars-table.sql in 9.73956ms bob # [ 11.337524] ncps-pre-start[578]: Applying: 20241213014846_add-query-to-nars-table.sql bob # [ 11.344864] ncps-pre-start[578]: Applied: 20241213014846_add-query-to-nars-table.sql in 8.179ms bob # [ 11.345662] ncps-pre-start[578]: Applying: 20251230224159_add-cascade-to-nars-fk.sql clare # [ 11.381171] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 11.342698] systemd[1]: Starting Permit User Sessions... alice # [ 11.343300] systemd-networkd[535]: eth1: Link UP alice # [ 11.343757] systemd-networkd[535]: eth1: Gained carrier clare # [ 11.386008] systemd[1]: Stopped Virtual Console Setup. alice # [ 11.346062] systemd-networkd[535]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 11.387186] systemd-logind[500]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) clare # [ 11.390393] systemd[1]: Starting Virtual Console Setup... alice # [ 11.351078] systemd-networkd[535]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 11.359949] ncps-pre-start[578]: Applied: 20251230224159_add-cascade-to-nars-fk.sql in 15.2688ms bob # [ 11.363773] ncps-pre-start[578]: Applying: 20260101000000_add-config-table.sql bob # [ 11.366549] ncps-pre-start[578]: Applied: 20260101000000_add-config-table.sql in 6.66728ms bob # [ 11.367366] ncps-pre-start[578]: Applying: 20260105025735_remove-redundant-indexes.sql bob # [ 11.383410] ncps-pre-start[578]: Applied: 20260105025735_remove-redundant-indexes.sql in 15.45092ms bob # [ 11.386757] ncps-pre-start[578]: Applying: 20260105030513_support_shared_nars_between_narinfos.sql bob # [ 11.392454] ncps-pre-start[578]: Applied: 20260105030513_support_shared_nars_between_narinfos.sql in 10.4112ms bob # [ 11.397074] ncps-pre-start[578]: Applying: 20260117195000_add_narinfo_de_normalized.sql bob # [ 11.411121] ncps-pre-start[578]: Applied: 20260117195000_add_narinfo_de_normalized.sql in 18.62444ms bob # [ 11.411944] ncps-pre-start[578]: Applying: 20260127223000_allow_multiple_nar_representations.sql alice # [ 11.410422] systemd[1]: Finished Permit User Sessions. alice # [ 11.417254] systemd[1]: Started Getty on tty1. alice # [ 11.418948] systemd[1]: Reached target Login Prompts. bob # [ 11.425168] ncps-pre-start[578]: Applied: 20260127223000_allow_multiple_nar_representations.sql in 14.127ms alice # [ 11.421645] systemd[1]: Reached target Multi-User System. bob # [ 11.426357] ncps-pre-start[578]: Applying: 20260131021850_add_chunks.sql alice # [ 11.427312] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. alice # [ 11.430240] systemd[1]: Startup finished in 813ms (kernel) + 5.733s (initrd) + 4.880s (userspace) = 11.428s. bob # [ 11.439921] ncps-pre-start[578]: Applied: 20260131021850_add_chunks.sql in 13.60184ms bob # [ 11.443659] ncps-pre-start[578]: Applying: 20260205063651_add_total_chunks_to_nar_files.sql bob # [ 11.455199] ncps-pre-start[578]: Applied: 20260205063651_add_total_chunks_to_nar_files.sql in 15.3196ms bob # [ 11.456181] ncps-pre-start[578]: Applying: 20260217071237_add_chunking_started_at_to_nar_files.sql clare # [ 11.501614] systemd-vconsole-setup[573]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 11.468373] ncps-pre-start[578]: Applied: 20260217071237_add_chunking_started_at_to_nar_files.sql in 13.146ms clare # [ 11.504787] systemd[1]: Finished Virtual Console Setup. bob # [ 11.469305] ncps-pre-start[578]: Applying: 20260301000000_add_verified_at_to_nar_files.sql bob # [ 11.486143] ncps-pre-start[578]: Applied: 20260301000000_add_verified_at_to_nar_files.sql in 17.20068ms bob # [ 11.626662] systemd[1]: Started ncps binary cache proxy service. bob # [ 11.627270] systemd[1]: Reached target Multi-User System. bob # [ 11.631161] systemd[1]: Startup finished in 806ms (kernel) + 5.197s (initrd) + 5.624s (userspace) = 11.628s. alice # [ 11.667360] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 11.845212] systemd[1]: Starting Virtual Console Setup... bob # [ 11.893459] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 11.934025] systemd-logind[505]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 11.968765] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","log_level":"info","time":"2026-09-21T10:07:30Z","message":"logger created"} bob # [ 11.969973] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","xz-binary-path":"/nix/store/hrvsv5knsgy3kjqykgh75d0yfxm92j8g-xz-5.8.3-bin/bin/xz","time":"2026-09-21T10:07:30Z","message":"Using xz binary for xz decompression"} bob # [ 11.979530] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-09-21T10:07:30Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"} alice # [ 11.991165] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. alice # [ 12.000350] systemd[1]: Starting Virtual Console Setup... bob # [ 12.016090] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","time":"2026-09-21T10:07:30Z","message":"using local locks (single-instance mode)"} bob # [ 12.026618] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-09-21T10:07:30Z","message":"setting up tracer provider to discard traces"} bob # [ 12.029915] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-09-21T10:07:30Z","message":"setting up meter provider to discard metrics"} alice # [ 12.028583] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 12.033356] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-09-21T10:07:30Z","message":"setting up logger provider to discard logs"} alice # [ 12.031186] systemd[1]: Stopped Virtual Console Setup. alice # [ 12.037762] systemd[1]: Starting Virtual Console Setup... bob # [ 12.041844] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-09-21T10:07:30Z","message":"Reporting anonymous metrics to the project maintainers"} bob # [ 12.047020] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-09-21T10:07:30Z","message":"failed to parse netrc file, proceeding without netrc authentication"} bob # [ 12.051063] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-09-21T10:07:30Z","message":"using local storage"} clare # [ 12.108631] systemd[1]: Starting harmonia binary cache service... bob # [ 12.074788] systemd-vconsole-setup[610]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 12.077480] systemd[1]: Finished Virtual Console Setup. alice # [ 12.098753] systemd-logind[492]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) alice # [ 12.149537] systemd-vconsole-setup[555]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 12.152797] systemd[1]: Finished Virtual Console Setup. clare # [ 12.193035] harmonia-cache[576]: 2026-09-21T10:07:30.935777Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000)) clare # [ 12.195691] harmonia-cache[576]: 2026-09-21T10:07:30.937955Z INFO actix_server::builder: starting 4 workers clare # [ 12.198684] harmonia-cache[576]: 2026-09-21T10:07:30.938596Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s clare # [ 12.199865] harmonia-cache[576]: 2026-09-21T10:07:30.938651Z INFO actix_server::server: Actix runtime found; starting in Actix runtime clare # [ 12.201601] harmonia-cache[576]: 2026-09-21T10:07:30.938672Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:5000 clare # [ 12.203542] systemd[1]: Started harmonia binary cache service. bob # [ 12.180158] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-09-21T10:07:30Z","message":"upstream became healthy and is now available for requests"} bob # [ 12.181750] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-09-21T10:07:30Z","message":"Server started"} clare # [ 12.225775] systemd-networkd[544]: eth1: Gained IPv6LL bob # [ 12.227977] systemd-networkd[556]: eth1: Gained IPv6LL bob # [ 12.229507] systemd-networkd[556]: eth0: Gained IPv6LL bob: (finished: waiting for unit ncps, in 12.78 seconds) bob: must succeed: systemctl status ncps bob: (finished: must succeed: systemctl status ncps, in 0.02 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.03 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) clare # [ 12.577036] systemd-networkd[544]: eth0: Gained IPv6LL alice # [ 12.578571] systemd-networkd[535]: eth0: Gained IPv6LL alice # % Total % Received % Xferd Average Speed Time Time Time Current alice # Dload Upload Total Spent Left Speed bob # [ 12.782670] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"192.168.1.1:47032","trace_id":"3fcf970a3559761667b6f05741fe021e","span_id":"9c2d4615ed74f2d5","status":200,"elapsed":0.07236,"bytes":50,"time":"2026-09-21T10:07:31Z","message":"handled request"} alice # 0 0 0 0 0 0 0 0 0100 50 100 50 0 0 222 0 0100 50 100 50 0 0 220 0 0100 50 100 50 0 0 219 0 0 alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 0.29 seconds) bob: waiting for success: curl clare:5000/nix-cache-info alice # [ 12.833314] systemd-networkd[535]: eth1: Gained IPv6LL bob # % Total % Received % Xferd Average Speed Time Time Time Current bob # Dload Upload Total Spent Left Speed bob # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 4254 0 0100 51 100 51 0 0 3636 0 0100 51 100 51 0 0 3211 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.03 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 bob # [ 13.048516] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: 2026/09/21 10:07:31 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host clare # [ 13.116380] systemd-resolved[316]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. clare # [ 13.119056] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 13.120540] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 13.387133] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 13.389519] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 13.390633] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 13.392060] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 13.988760] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 13.991567] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 13.996391] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 13.998183] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 15.119148] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 15.121743] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 15.128531] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 15.130438] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 17.682037] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 17.683829] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 17.686456] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 17.687500] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare: (finished: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 , in 4.89 seconds) alice: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 bob # [ 17.917811] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:50766","trace_id":"18e0e4cfb4277561c41eac1841174c9e","span_id":"ca20beb7a2b27ccb","status":200,"elapsed":0.02728,"bytes":50,"time":"2026-09-21T10:07:36Z","message":"handled request"} alice # [ 17.918823] systemd-resolved[306]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. alice # [ 17.921758] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 17.923294] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 18.263194] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 18.267094] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 18.270356] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 18.272649] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 18.912284] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 18.913912] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 18.915891] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 18.917462] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 19.997995] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 19.999750] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 20.002003] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 20.003682] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 22.228535] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 22.230321] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 22.232741] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 22.234450] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 22.243130] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","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-21T10:07:40Z","message":"download the narinfo from upstream"} bob # [ 22.261199] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-09-21T10:07:41Z","message":"downloading the nar from upstream"} bob # [ 22.267711] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","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-21T10:07:41Z","message":"download the nar from upstream"} bob # [ 22.282105] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-09-21T10:07:41Z","message":"storing narinfo and nar_file record in the database"} bob # [ 22.290044] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":46.00584,"time":"2026-09-21T10:07:41Z","message":"download of narinfo complete"} bob # [ 22.295895] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","status":200,"elapsed":47.46312,"bytes":532,"time":"2026-09-21T10:07:41Z","message":"handled request"} bob # [ 22.299078] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":28.5062,"time":"2026-09-21T10:07:41Z","message":"download of nar complete"} bob # [ 22.303379] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:50766","trace_id":"8ed13db46e1e2f7da14f7b9c217e660b","span_id":"d6017e3f6a4e5632","status":200,"elapsed":0.66152,"bytes":95,"time":"2026-09-21T10:07:41Z","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.51 seconds) (finished: run the VM test script, in 23.28 seconds) test script finished in 23.82s 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-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-09-21T10:07:42Z 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-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-09-21T10:07:42Z 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-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.45 seconds)