build01: building 8x0wlbh5cjxjwkj53sxnwjc9wcj8x0zp-vm-test-run-ncps.drv Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script additionally exposed symbols: alice, bob, clare, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh start all VMs alice: starting vm bob: starting vm clare: starting vm clare: QEMU running (pid 50) clare # Disk image does not exist, creating the virtualisation disk image... alice: QEMU running (pid 48) clare # Formatting '/build/vm-state-clare/tmp.vb4czSn8q2', fmt=raw size=1073741824 alice # Disk image does not exist, creating the virtualisation disk image... clare # mke2fs 1.47.4 (6-Mar-2025) alice # Formatting '/build/vm-state-alice/tmp.UKxrTordt9', fmt=raw size=1073741824 clare # Discarding device blocks: 0/262144 done alice # mke2fs 1.47.4 (6-Mar-2025) bob # Disk image does not exist, creating the virtualisation disk image... bob: QEMU running (pid 49) clare # Creating filesystem with 262144 4k blocks and 65536 inodes alice # Discarding device blocks: 0/262144 done clare # Filesystem UUID: 3c03a584-5778-40ca-89c8-804af6ee8a0c bob # Formatting '/build/vm-state-bob/tmp.H8E0y4n6uI', fmt=raw size=1073741824 alice # Creating filesystem with 262144 4k blocks and 65536 inodes bob # mke2fs 1.47.4 (6-Mar-2025) (finished: start all VMs, in 0.51 seconds) clare # Superblock backups stored on blocks: bob # Discarding device blocks: 0/262144 done bob: waiting for unit ncps clare # 32768, 98304, 163840, 229376 bob # Creating filesystem with 262144 4k blocks and 65536 inodes bob: waiting for the VM to finish booting alice # Filesystem UUID: 50142622-57b5-4dfa-9490-28a1b3a21de1 clare # bob # Filesystem UUID: dceae672-475b-4dbd-a218-398d406d48e0 clare # Allocating group tables: 0/8 done bob # Superblock backups stored on blocks: clare # Writing inode tables: 0/8 done bob # 32768, 98304, 163840, 229376 clare # Creating journal (8192 blocks): done bob # clare # Writing superblocks and filesystem accounting information: 0/8 done bob # Allocating group tables: 0/8 done clare # alice # Superblock backups stored on blocks: bob # Writing inode tables: 0/8 done alice # 32768, 98304, 163840, 229376 bob # Creating journal (8192 blocks): done alice # bob # Writing superblocks and filesystem accounting information: 0/8 done alice # Allocating group tables: 0/8 done bob # alice # Writing inode tables: 0/8 done bob # Virtualisation disk image created. alice # Creating journal (8192 blocks): done bob # Starting virtiofs daemons... alice # Writing superblocks and filesystem accounting information: 0/8 done bob # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # bob # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # Virtualisation disk image created. bob # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # Starting virtiofs daemons... bob # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-07T05:20:47Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-07T05:20:47Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-07T05:20:47Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-07T05:20:47Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-10-07T05:20:47Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-07T05:20:47Z INFO virtiofsd] Client connected, servicing requests alice # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-07T05:20:47Z INFO virtiofsd] Client connected, servicing requests alice # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-07T05:20:47Z INFO virtiofsd] Client connected, servicing requests alice # [2026-10-07T05:20:47Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # Virtualisation disk image created. alice # [2026-10-07T05:20:47Z INFO virtiofsd] Client connected, servicing requests clare # Starting virtiofs daemons... alice # [2026-10-07T05:20:47Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-07T05:20:47Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-10-07T05:20:47Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-10-07T05:20:47Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-10-07T05:20:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-10-07T05:20:47Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-07T05:20:47Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-07T05:20:47Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-07T05:20:47Z INFO virtiofsd] Client connected, servicing requests clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] clare # [ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Oct 3 10:39:03 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 0x7fc08700-0x7fc0be7f] 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 s186840 r8192 d116264 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/pz32wfgyriqbvmxl7nchdj3rlpir4vwl-nixos-system-clare-test/init regInfo=/nix/.ro-store/7p8wgmr5hzx6r6y0akpzw1i7i78nv2sq-closure-info/registration console=ttyAMA0,115200n8 console=tty0 bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/7p8wgmr5hzx6r6y0akpzw1i7i78nv2sq-closure-info/registration", will be passed to user space. clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes bob # [ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Oct 3 10:39:03 UTC 2026 bob # [ 0.000000] KASLR enabled bob # [ 0.000000] random: crng init done clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) bob # [ 0.000000] Machine model: linux,dummy-virt bob # [ 0.000000] efi: UEFI not found. clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB clare # [ 0.000000] software IO TLB: area num 1. bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) alice # [ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Oct 3 10:39:03 UTC 2026 bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] alice # [ 0.000000] KASLR enabled clare # [ 0.000000] Fallback order for Node 0: 0 bob # [ 0.000000] Zone ranges: alice # [ 0.000000] random: crng init done bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 alice # [ 0.000000] Machine model: linux,dummy-virt bob # [ 0.000000] DMA32 empty clare # [ 0.000000] Policy zone: DMA alice # [ 0.000000] efi: UEFI not found. bob # [ 0.000000] Normal empty bob # [ 0.000000] Device empty clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT bob # [ 0.000000] Movable zone start for each node bob # [ 0.000000] Early memory node ranges clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] allocated 2097152 bytes of page_ext bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] clare # [ 0.000000] ftrace: allocating 75039 entries in 294 pages alice # [ 0.000000] Zone ranges: bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000000] DMA32 empty clare # [ 0.000000] rcu: Hierarchical RCU implementation. alice # [ 0.000000] Normal empty bob # [ 0.000000] psci: probing for conduit method from DT. clare # [ 0.000000] rcu: RCU event tracing is enabled. alice # [ 0.000000] Device empty bob # [ 0.000000] psci: PSCIv1.3 detected in firmware. alice # [ 0.000000] Movable zone start for each node clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs alice # [ 0.000000] Early memory node ranges clare # [ 0.000000] Trampoline variant of Tasks RCU enabled. bob # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] Rude variant of Tasks RCU enabled. bob # [ 0.000000] psci: SMC Calling Convention v1.1 clare # [ 0.000000] Tracing variant of Tasks RCU enabled. alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. alice # [ 0.000000] psci: probing for conduit method from DT. bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u311296 clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 0.000000] psci: PSCIv1.3 detected in firmware. bob # [ 0.000000] Detected PIPT I-cache on CPU0 alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs bob # [ 0.000000] CPU features: detected: GICv3 CPU interface clare # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.000000] psci: Trusted OS migration not required bob # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.000000] psci: SMC Calling Convention v1.1 bob # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 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] CPU features: kernel page table isolation forced ON by KASLR alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u311296 bob # [ 0.000000] CPU features: detected: ARM erratum 1418040 clare # [ 0.000000] GICv3: 256 SPIs implemented alice # [ 0.000000] Detected PIPT I-cache on CPU0 clare # [ 0.000000] GICv3: 0 Extended SPIs implemented bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion alice # [ 0.000000] CPU features: detected: GICv3 CPU interface clare # [ 0.000000] Root IRQ handler: gic_handle_irq alice # [ 0.000000] CPU features: detected: Spectre-v4 bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI alice # [ 0.000000] CPU features: detected: Spectre-BHB bob # [ 0.000000] alternatives: applying boot alternatives clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] alice # [ 0.000000] CPU features: detected: ARM erratum 1418040 clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) alice # [ 0.000000] alternatives: applying boot alternatives 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/vd8qkla9gq340l9f8ily9my4z20h0px6-nixos-system-bob-test/init regInfo=/nix/.ro-store/lnq6syj90jc8ia27fks0dcxczwin88wj-closure-info/registration console=ttyAMA0,115200n8 console=tty0 clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/lnq6syj90jc8ia27fks0dcxczwin88wj-closure-info/registration", will be passed to user space. clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 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/xp1n05bw700zw1i0y8x199f0abc3ij9n-nixos-system-alice-test/init regInfo=/nix/.ro-store/wigmyb0c46y497rg9m7mffjmwz48frhc-closure-info/registration console=ttyAMA0,115200n8 console=tty0 bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB bob # [ 0.000000] software IO TLB: area num 1. clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/wigmyb0c46y497rg9m7mffjmwz48frhc-closure-info/registration", will be passed to user space. bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes bob # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.000056] arm-pv: using stolen time PV alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 bob # [ 0.000000] Policy zone: DMA clare # [ 0.000390] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.000694] Console: colour dummy device 80x25 bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB clare # [ 0.000706] printk: legacy console [tty0] enabled bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.000000] software IO TLB: area num 1. bob # [ 0.000000] allocated 2097152 bytes of page_ext clare # [ 0.000927] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) bob # [ 0.000000] ftrace: allocating 75039 entries in 294 pages clare # [ 0.000933] pid_max: default: 32768 minimum: 301 alice # [ 0.000000] Fallback order for Node 0: 0 bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups clare # [ 0.001010] LSM: initializing lsm=capability,landlock,yama,bpf,ima alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 bob # [ 0.000000] rcu: Hierarchical RCU implementation. clare # [ 0.001159] landlock: Up and running. alice # [ 0.000000] Policy zone: DMA bob # [ 0.000000] rcu: RCU event tracing is enabled. clare # [ 0.001162] Yama: becoming mindful. clare # [ 0.001568] LSM support for eBPF active alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 clare # [ 0.001741] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.000000] Trampoline variant of Tasks RCU enabled. alice # [ 0.000000] allocated 2097152 bytes of page_ext bob # [ 0.000000] Rude variant of Tasks RCU enabled. clare # [ 0.001760] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.000000] ftrace: allocating 75039 entries in 294 pages bob # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.004495] rcu: Hierarchical SRCU implementation. alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. clare # [ 0.004501] rcu: Max phase no-delay instances is 1000. alice # [ 0.000000] rcu: Hierarchical RCU implementation. clare # [ 0.005448] fsl-mc MSI: its@8080000 domain created alice # [ 0.000000] rcu: RCU event tracing is enabled. bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 clare # [ 0.005647] EFI services will not be available. alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. clare # [ 0.005730] smp: Bringing up secondary CPUs ... bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.005762] smp: Brought up 1 node, 1 CPU alice # [ 0.000000] Trampoline variant of Tasks RCU enabled. alice # [ 0.000000] Rude variant of Tasks RCU enabled. 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.005766] SMP: Total of 1 processors activated. clare # [ 0.005768] CPU: All CPU(s) started at EL1 alice # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.005780] CPU features: detected: 32-bit EL0 Support 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] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.005784] CPU features: detected: Data cache clean to the PoU not required for I/D coherence alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 bob # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.005786] CPU features: detected: Common not Private translations bob # [ 0.000000] GICv3: 0 Extended SPIs implemented 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] Root IRQ handler: gic_handle_irq clare # [ 0.005788] CPU features: detected: CRC32 instructions bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI clare # [ 0.005790] CPU features: detected: Data cache clean to Point of Persistence alice # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.005793] CPU features: detected: Enhanced Virtualization Traps bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 alice # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.005804] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.005806] CPU features: detected: LSE atomic instructions alice # [ 0.000000] GICv3: 256 SPIs implemented bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) alice # [ 0.000000] GICv3: 0 Extended SPIs implemented clare # [ 0.005808] CPU features: detected: Privileged Access Never clare # [ 0.005810] CPU features: detected: PMUv3 alice # [ 0.000000] Root IRQ handler: gic_handle_irq bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.005812] CPU features: detected: RAS Extension Support alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 clare # [ 0.005815] CPU features: detected: Speculative Store Bypassing Safe (SSBS) alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 clare # [ 0.005836] alternatives: applying system-wide alternatives bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.007238] CPU features: detected: Hardware dirty bit management on CPU0 bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.007439] Memory: 891116K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116132K reserved, 32768K cma-reserved) bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 clare # [ 0.007922] devtmpfs: initialized alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). clare # [ 0.009410] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns clare # [ 0.009433] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. clare # [ 0.009565] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.000051] arm-pv: using stolen time PV clare # [ 0.009570] 0 pages in range for non-PLT usage alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns clare # [ 0.009571] 508192 pages in range for PLT usage alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.000366] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) clare # [ 0.009660] pinctrl core: initialized pinctrl subsystem alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). clare # [ 0.010503] DMI not present or invalid. bob # [ 0.000684] Console: colour dummy device 80x25 bob # [ 0.000697] printk: legacy console [tty0] enabled clare # [ 0.013788] NET: Registered PF_NETLINK/PF_ROUTE protocol family alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns clare # [ 0.016459] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations bob # [ 0.000919] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.000924] pid_max: default: 32768 minimum: 301 clare # [ 0.016589] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations alice # [ 0.000054] arm-pv: using stolen time PV bob # [ 0.000998] LSM: initializing lsm=capability,landlock,yama,bpf,ima clare # [ 0.016736] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations bob # [ 0.001147] landlock: Up and running. alice # [ 0.000384] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) clare # [ 0.016752] audit: initializing netlink subsys (disabled) bob # [ 0.001149] Yama: becoming mindful. alice # [ 0.000677] Console: colour dummy device 80x25 bob # [ 0.001535] LSM support for eBPF active clare # [ 0.017330] thermal_sys: Registered thermal governor 'fair_share' alice # [ 0.000688] printk: legacy console [tty0] enabled bob # [ 0.001680] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.017333] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 0.000923] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) clare # [ 0.017336] thermal_sys: Registered thermal governor 'step_wise' bob # [ 0.001704] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.000928] pid_max: default: 32768 minimum: 301 clare # [ 0.017338] thermal_sys: Registered thermal governor 'user_space' bob # [ 0.004427] rcu: Hierarchical SRCU implementation. alice # [ 0.001031] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.004433] rcu: Max phase no-delay instances is 1000. clare # [ 0.017340] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 0.001166] landlock: Up and running. bob # [ 0.005406] fsl-mc MSI: its@8080000 domain created alice # [ 0.001168] Yama: becoming mindful. bob # [ 0.005599] EFI services will not be available. clare # [ 0.017377] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 alice # [ 0.001579] LSM support for eBPF active clare # [ 0.017386] cpuidle: using governor ladder bob # [ 0.005686] smp: Bringing up secondary CPUs ... clare # [ 0.017390] cpuidle: using governor menu alice # [ 0.001754] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.005722] smp: Brought up 1 node, 1 CPU bob # [ 0.005727] SMP: Total of 1 processors activated. clare # [ 0.017506] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. alice # [ 0.001773] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.005729] CPU: All CPU(s) started at EL1 clare # [ 0.017527] ASID allocator initialised with 32768 entries alice # [ 0.004463] rcu: Hierarchical SRCU implementation. bob # [ 0.005742] CPU features: detected: 32-bit EL0 Support clare # [ 0.018172] Serial: AMBA PL011 UART driver alice # [ 0.004470] rcu: Max phase no-delay instances is 1000. alice # [ 0.005408] fsl-mc MSI: its@8080000 domain created bob # [ 0.005745] CPU features: detected: Data cache clean to the PoU not required for I/D coherence clare # [ 0.021744] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 alice # [ 0.005610] EFI services will not be available. clare # [ 0.021891] printk: console [ttyAMA0] enabled bob # [ 0.005748] CPU features: detected: Common not Private translations alice # [ 0.005709] smp: Bringing up secondary CPUs ... bob # [ 0.005750] CPU features: detected: CRC32 instructions alice # [ 0.005738] smp: Brought up 1 node, 1 CPU alice # [ 0.005743] SMP: Total of 1 processors activated. bob # [ 0.005752] CPU features: detected: Data cache clean to Point of Persistence alice # [ 0.005744] CPU: All CPU(s) started at EL1 bob # [ 0.005755] CPU features: detected: Enhanced Virtualization Traps alice # [ 0.005756] CPU features: detected: 32-bit EL0 Support bob # [ 0.005765] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.005759] CPU features: detected: Data cache clean to the PoU not required for I/D coherence bob # [ 0.005767] CPU features: detected: LSE atomic instructions bob # [ 0.005769] CPU features: detected: Privileged Access Never alice # [ 0.005762] CPU features: detected: Common not Private translations bob # [ 0.005771] CPU features: detected: PMUv3 alice # [ 0.005764] CPU features: detected: CRC32 instructions bob # [ 0.005772] CPU features: detected: RAS Extension Support alice # [ 0.005766] CPU features: detected: Data cache clean to Point of Persistence bob # [ 0.005776] CPU features: detected: Speculative Store Bypassing Safe (SSBS) alice # [ 0.005769] CPU features: detected: Enhanced Virtualization Traps bob # [ 0.005796] alternatives: applying system-wide alternatives alice # [ 0.005778] CPU features: detected: RCpc load-acquire (LDAPR) bob # [ 0.007220] CPU features: detected: Hardware dirty bit management on CPU0 alice # [ 0.005781] CPU features: detected: LSE atomic instructions alice # [ 0.005783] CPU features: detected: Privileged Access Never alice # [ 0.005785] CPU features: detected: PMUv3 bob # [ 0.007419] Memory: 891124K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116112K reserved, 32768K cma-reserved) bob # [ 0.007875] devtmpfs: initialized alice # [ 0.005787] CPU features: detected: RAS Extension Support bob # [ 0.009592] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.005790] CPU features: detected: Speculative Store Bypassing Safe (SSBS) alice # [ 0.005810] alternatives: applying system-wide alternatives bob # [ 0.009616] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). alice # [ 0.007344] CPU features: detected: Hardware dirty bit management on CPU0 bob # [ 0.009743] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.009748] 0 pages in range for non-PLT usage bob # [ 0.009749] 508192 pages in range for PLT usage alice # [ 0.007567] Memory: 891144K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116128K reserved, 32768K cma-reserved) bob # [ 0.009854] pinctrl core: initialized pinctrl subsystem alice # [ 0.008033] devtmpfs: initialized bob # [ 0.010627] DMI not present or invalid. alice # [ 0.009565] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.013983] NET: Registered PF_NETLINK/PF_ROUTE protocol family clare # [ 0.093523] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.016467] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations alice # [ 0.009589] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). clare # [ 0.093536] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page alice # [ 0.009707] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.016600] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations clare # [ 0.093538] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages alice # [ 0.009713] 0 pages in range for non-PLT usage bob # [ 0.016752] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations alice # [ 0.009713] 508192 pages in range for PLT usage clare # [ 0.093542] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page bob # [ 0.016768] audit: initializing netlink subsys (disabled) alice # [ 0.009817] pinctrl core: initialized pinctrl subsystem clare # [ 0.093545] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages alice # [ 0.010568] DMI not present or invalid. bob # [ 0.017333] thermal_sys: Registered thermal governor 'fair_share' clare # [ 0.093546] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 0.014118] NET: Registered PF_NETLINK/PF_ROUTE protocol family bob # [ 0.017336] thermal_sys: Registered thermal governor 'bang_bang' clare # [ 0.093548] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages alice # [ 0.016597] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations bob # [ 0.017339] thermal_sys: Registered thermal governor 'step_wise' clare # [ 0.093550] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page bob # [ 0.017341] thermal_sys: Registered thermal governor 'user_space' alice # [ 0.016726] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations clare # [ 0.094551] fbcon: Taking over console clare # [ 0.094561] ACPI: Interpreter disabled. bob # [ 0.017343] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 0.016873] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.097210] iommu: Default domain type: Translated alice # [ 0.016889] audit: initializing netlink subsys (disabled) bob # [ 0.017381] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 clare # [ 0.097215] iommu: DMA domain TLB invalidation policy: strict mode bob # [ 0.017390] cpuidle: using governor ladder clare # [ 0.097516] SCSI subsystem initialized alice # [ 0.017426] thermal_sys: Registered thermal governor 'fair_share' bob # [ 0.017395] cpuidle: using governor menu alice # [ 0.017429] thermal_sys: Registered thermal governor 'bang_bang' bob # [ 0.017509] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. alice # [ 0.017432] thermal_sys: Registered thermal governor 'step_wise' bob # [ 0.017529] ASID allocator initialised with 32768 entries alice # [ 0.017434] thermal_sys: Registered thermal governor 'user_space' bob # [ 0.018197] Serial: AMBA PL011 UART driver alice # [ 0.017435] thermal_sys: Registered thermal governor 'power_allocator' bob # [ 0.021712] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 bob # [ 0.021852] printk: console [ttyAMA0] enabled alice # [ 0.017475] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 clare # [ 0.103394] usbcore: registered new interface driver usbfs alice # [ 0.017484] cpuidle: using governor ladder alice # [ 0.017489] cpuidle: using governor menu clare # [ 0.103431] usbcore: registered new interface driver hub clare # [ 0.103441] usbcore: registered new device driver usb alice # [ 0.017604] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. clare # [ 0.103662] pps_core: LinuxPPS API ver. 1 registered alice # [ 0.017624] ASID allocator initialised with 32768 entries alice # [ 0.018291] Serial: AMBA PL011 UART driver clare # [ 0.103665] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti clare # [ 0.103670] PTP clock support registered alice # [ 0.021773] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 clare # [ 0.103702] EDAC MC: Ver: 3.0.0 alice # [ 0.021965] printk: console [ttyAMA0] enabled clare # [ 0.103862] scmi_core: SCMI protocol bus registered clare # [ 0.104136] FPGA manager framework clare # [ 0.108990] vgaarb: loaded clare # [ 0.109204] clocksource: Switched to clocksource arch_sys_counter bob # [ 0.093549] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.093563] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 0.093566] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.093570] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page bob # [ 0.093572] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 0.093574] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 0.093576] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages bob # [ 0.093578] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page bob # [ 0.094560] fbcon: Taking over console alice # [ 0.094888] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.094570] ACPI: Interpreter disabled. bob # [ 0.097222] iommu: Default domain type: Translated alice # [ 0.094902] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 0.097227] iommu: DMA domain TLB invalidation policy: strict mode alice # [ 0.094905] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.097551] SCSI subsystem initialized alice # [ 0.094909] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page alice # [ 0.094911] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages alice # [ 0.094913] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 0.094915] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages alice # [ 0.094917] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page bob # [ 0.103612] usbcore: registered new interface driver usbfs alice # [ 0.095935] fbcon: Taking over console alice # [ 0.095945] ACPI: Interpreter disabled. bob # [ 0.103644] usbcore: registered new interface driver hub alice # [ 0.096610] iommu: Default domain type: Translated bob # [ 0.103655] usbcore: registered new device driver usb alice # [ 0.096614] iommu: DMA domain TLB invalidation policy: strict mode bob # [ 0.103870] pps_core: LinuxPPS API ver. 1 registered alice # [ 0.096905] SCSI subsystem initialized clare # [ 0.122492] VFS: Disk quotas dquot_6.6.0 bob # [ 0.103874] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti alice # [ 0.098713] usbcore: registered new interface driver usbfs clare # [ 0.122517] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.103878] PTP clock support registered alice # [ 0.098746] usbcore: registered new interface driver hub clare # [ 0.123972] netfs: FS-Cache loaded bob # [ 0.103911] EDAC MC: Ver: 3.0.0 clare # [ 0.124079] pnp: PnP ACPI: disabled alice # [ 0.098756] usbcore: registered new device driver usb bob # [ 0.104077] scmi_core: SCMI protocol bus registered alice # [ 0.098998] pps_core: LinuxPPS API ver. 1 registered bob # [ 0.104370] FPGA manager framework alice # [ 0.099002] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti bob # [ 0.109155] vgaarb: loaded alice # [ 0.099006] PTP clock support registered bob # [ 0.109382] clocksource: Switched to clocksource arch_sys_counter alice # [ 0.099042] EDAC MC: Ver: 3.0.0 clare # [ 0.127299] NET: Registered PF_INET protocol family clare # [ 0.127469] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.108080] scmi_core: SCMI protocol bus registered alice # [ 0.108408] FPGA manager framework alice # [ 0.112133] vgaarb: loaded alice # [ 0.112344] clocksource: Switched to clocksource arch_sys_counter bob # [ 0.122598] VFS: Disk quotas dquot_6.6.0 bob # [ 0.122625] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.124063] netfs: FS-Cache loaded bob # [ 0.124174] pnp: PnP ACPI: disabled alice # [ 0.123048] VFS: Disk quotas dquot_6.6.0 alice # [ 0.123075] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.127466] NET: Registered PF_INET protocol family bob # [ 0.127646] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.125414] netfs: FS-Cache loaded alice # [ 0.125541] pnp: PnP ACPI: disabled alice # [ 0.127385] NET: Registered PF_INET protocol family alice # [ 0.127566] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) clare # [ 0.151835] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) clare # [ 0.151853] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) clare # [ 0.151864] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) clare # [ 0.151901] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) clare # [ 0.151967] TCP: Hash tables configured (established 8192 bind 8192) clare # [ 0.152059] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) clare # [ 0.152115] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.152170] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.152281] NET: Registered PF_UNIX/PF_LOCAL protocol family clare # [ 0.152309] NET: Registered PF_XDP protocol family clare # [ 0.152329] PCI: CLS 0 bytes, default 64 clare # [ 0.152526] Trying to unpack rootfs image as initramfs... clare # [ 0.165227] kvm [1]: HYP mode not available alice # [ 0.152714] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.152736] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) alice # [ 0.152748] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) alice # [ 0.152783] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.152850] TCP: Hash tables configured (established 8192 bind 8192) bob # [ 0.152213] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.152948] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) bob # [ 0.152237] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) alice # [ 0.152974] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.152248] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) alice # [ 0.153029] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.152285] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.153135] NET: Registered PF_UNIX/PF_LOCAL protocol family bob # [ 0.152364] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.153162] NET: Registered PF_XDP protocol family alice # [ 0.153183] PCI: CLS 0 bytes, default 64 bob # [ 0.152476] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 0.153468] Trying to unpack rootfs image as initramfs... bob # [ 0.152530] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.152586] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.152711] NET: Registered PF_UNIX/PF_LOCAL protocol family bob # [ 0.152745] NET: Registered PF_XDP protocol family bob # [ 0.152767] PCI: CLS 0 bytes, default 64 alice # [ 0.162720] kvm [1]: HYP mode not available bob # [ 0.152973] Trying to unpack rootfs image as initramfs... bob # [ 0.167616] kvm [1]: HYP mode not available clare # [ 0.229664] Initialise system trusted keyrings clare # [ 0.229791] workingset: timestamp_bits=42 max_order=18 bucket_order=0 clare # [ 0.230106] squashfs: version 4.0 (2009/01/31) Phillip Lougher clare # [ 0.230150] 9p: Installing v9fs 9p2000 file system support bob # [ 0.229825] Initialise system trusted keyrings bob # [ 0.229947] workingset: timestamp_bits=42 max_order=18 bucket_order=0 bob # [ 0.230293] squashfs: version 4.0 (2009/01/31) Phillip Lougher bob # [ 0.230333] 9p: Installing v9fs 9p2000 file system support alice # [ 0.232756] Initialise system trusted keyrings alice # [ 0.232912] workingset: timestamp_bits=42 max_order=18 bucket_order=0 alice # [ 0.233231] squashfs: version 4.0 (2009/01/31) Phillip Lougher alice # [ 0.233276] 9p: Installing v9fs 9p2000 file system support clare # [ 0.258742] Key type asymmetric registered clare # [ 0.258758] Asymmetric key parser 'x509' registered clare # [ 0.258822] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) clare # [ 0.258923] io scheduler mq-deadline registered clare # [ 0.258927] io scheduler kyber registered clare # [ 0.263153] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.263510] ledtrig-cpu: registered to indicate activity on CPUs clare # [ 0.263808] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: clare # [ 0.263824] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 bob # [ 0.249145] Key type asymmetric registered bob # [ 0.249161] Asymmetric key parser 'x509' registered clare # [ 0.263849] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 bob # [ 0.249225] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) clare # [ 0.263853] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.263876] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits clare # [ 0.263895] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.263962] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 clare # [ 0.263982] pci_bus 0000:00: root bus resource [bus 00-ff] clare # [ 0.263985] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] clare # [ 0.263988] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] clare # [ 0.263990] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] clare # [ 0.264060] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint clare # [ 0.264520] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.264643] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.264653] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.264671] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.264679] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.264989] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.265112] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.265120] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.253888] Key type asymmetric registered clare # [ 0.265138] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.253904] Asymmetric key parser 'x509' registered alice # [ 0.253964] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) alice # [ 0.254056] io scheduler mq-deadline registered alice # [ 0.254059] io scheduler kyber registered bob # [ 0.257434] io scheduler mq-deadline registered bob # [ 0.257442] io scheduler kyber registered bob # [ 0.259776] pl061_gpio 9030000.pl061: PL061 GPIO chip registered bob # [ 0.262564] ledtrig-cpu: registered to indicate activity on CPUs bob # [ 0.262930] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: bob # [ 0.262948] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 bob # [ 0.262969] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 bob # [ 0.262973] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 bob # [ 0.263001] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits bob # [ 0.263021] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] bob # [ 0.263099] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 bob # [ 0.263119] pci_bus 0000:00: root bus resource [bus 00-ff] alice # [ 0.264471] pl061_gpio 9030000.pl061: PL061 GPIO chip registered bob # [ 0.263122] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] alice # [ 0.264836] ledtrig-cpu: registered to indicate activity on CPUs bob # [ 0.263125] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] clare # [ 0.285552] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.265148] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: clare # [ 0.285682] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] bob # [ 0.263127] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] clare # [ 0.285692] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.265165] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 bob # [ 0.263198] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint clare # [ 0.285711] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.265177] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 bob # [ 0.263657] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.286029] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.263782] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.265182] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.286155] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.263791] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.286164] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.265208] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits bob # [ 0.263810] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.286182] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.263818] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.265230] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.286190] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.265297] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 bob # [ 0.264137] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.286536] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint alice # [ 0.265318] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 0.264262] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.286664] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.264270] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.265322] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] clare # [ 0.286682] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.265324] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] bob # [ 0.264288] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.287004] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 0.265327] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] bob # [ 0.264621] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint clare # [ 0.287131] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.264744] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.265402] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint clare # [ 0.287149] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.264753] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.265876] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.264771] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.287421] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 0.266004] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.287545] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] bob # [ 0.265081] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.266014] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.265205] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.287734] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.266033] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.265213] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.287860] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.266042] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.265231] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.287877] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.266375] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.265240] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.288188] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.266502] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.288318] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.266511] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.288336] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.266530] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.288644] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.266849] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint clare # [ 0.288773] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.266977] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.266987] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.288790] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.267005] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.289115] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint alice # [ 0.267328] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.267459] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.267469] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.267486] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.267495] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.303085] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] clare # [ 0.303097] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.267815] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.303115] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.267944] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.267962] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.303462] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.303588] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.268289] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint clare # [ 0.303597] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.303616] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.304065] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned alice # [ 0.287475] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.304072] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned alice # [ 0.287496] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.287854] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.304075] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned alice # [ 0.287979] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.304108] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned alice # [ 0.288161] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.304138] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned alice # [ 0.288289] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.289653] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.304169] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.289792] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.288307] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.304200] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.289811] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.304229] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned alice # [ 0.292314] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.290141] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint bob # [ 0.290269] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.304259] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned bob # [ 0.290287] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.304288] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.290567] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.304318] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.290692] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.304347] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned bob # [ 0.290877] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.304406] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned bob # [ 0.291006] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.304435] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned bob # [ 0.291023] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.304519] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned bob # [ 0.291342] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.304533] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.291472] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.304545] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.291490] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.304557] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned clare # [ 0.304569] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.291810] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.291938] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.304581] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned clare # [ 0.304593] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.291956] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.304605] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.292278] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint bob # [ 0.292456] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] clare # [ 0.304617] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.292466] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.304629] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.292484] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.304641] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned clare # [ 0.304653] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.292888] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.304664] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.293014] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.304676] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned bob # [ 0.293023] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.304687] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned bob # [ 0.293041] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.304699] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned clare # [ 0.304711] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned alice # [ 0.300527] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.304728] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] alice # [ 0.300549] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.304732] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] alice # [ 0.300891] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.304739] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.301023] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.301041] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.306531] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned alice # [ 0.301369] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint bob # [ 0.306538] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned alice # [ 0.301533] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.301542] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.306542] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned alice # [ 0.301613] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.306577] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned clare # [ 0.326612] pci 0000:00:07.0: enabling device (0000 -> 0002) alice # [ 0.301948] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.306609] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned alice # [ 0.302076] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.306640] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned alice # [ 0.302085] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.302104] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.306671] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned alice # [ 0.302544] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned bob # [ 0.306702] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned alice # [ 0.302551] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned bob # [ 0.306733] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned alice # [ 0.302554] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned bob # [ 0.306764] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned alice # [ 0.302589] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned bob # [ 0.306794] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned alice # [ 0.302620] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned bob # [ 0.306825] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned alice # [ 0.302652] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.306886] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned alice # [ 0.302683] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.306916] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned alice # [ 0.302714] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned bob # [ 0.306957] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned bob # [ 0.306970] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned alice # [ 0.302746] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned bob # [ 0.306983] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned alice # [ 0.302777] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.306995] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned alice # [ 0.302807] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.307007] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned alice # [ 0.302838] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned bob # [ 0.307020] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned alice # [ 0.302920] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned bob # [ 0.307032] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned alice # [ 0.302966] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned bob # [ 0.307044] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned alice # [ 0.302978] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned bob # [ 0.307056] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned alice # [ 0.302990] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.307069] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned alice # [ 0.303002] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.307081] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned alice # [ 0.303014] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned bob # [ 0.307093] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned alice # [ 0.303026] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.307105] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.307117] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned alice # [ 0.303038] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.307129] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned alice # [ 0.303051] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.307141] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.303063] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.307153] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned alice # [ 0.303075] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.307169] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] alice # [ 0.303087] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.307173] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] alice # [ 0.303099] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned bob # [ 0.307182] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] clare # [ 0.341314] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.303111] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.307736] pci 0000:00:07.0: enabling device (0000 -> 0002) clare # [ 0.342915] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.303122] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned clare # [ 0.344312] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) alice # [ 0.303134] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned alice # [ 0.303146] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned alice # [ 0.303158] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.303170] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned alice # [ 0.303187] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] alice # [ 0.303191] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] alice # [ 0.303199] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.303751] pci 0000:00:07.0: enabling device (0000 -> 0002) alice # [ 0.335751] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) clare # [ 0.353709] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.355507] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) bob # [ 0.338673] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) bob # [ 0.340207] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) clare # [ 0.358260] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) clare # [ 0.359627] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) clare # [ 0.361059] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.345518] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.347757] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) bob # [ 0.349628] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) bob # [ 0.351808] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.370422] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) clare # [ 0.372030] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) clare # [ 0.375051] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) alice # [ 0.357351] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) bob # [ 0.361530] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) bob # [ 0.364192] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) alice # [ 0.361149] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) alice # [ 0.363030] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) bob # [ 0.366726] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.368217] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) clare # [ 0.386499] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled clare # [ 0.387455] msm_serial: driver initialized clare # [ 0.387542] SuperH (H)SCI(F) driver initialized clare # [ 0.387584] STM32 USART driver initialized alice # [ 0.372477] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) alice # [ 0.374169] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.375759] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) bob # [ 0.377528] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) bob # [ 0.379105] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) alice # [ 0.378678] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) bob # [ 0.381129] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) clare # [ 0.408647] loop: module loaded clare # [ 0.408774] virtio_blk virtio2: 1/0/0 default/read/poll queues alice # [ 0.388416] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) alice # [ 0.391986] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.394140] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.395160] msm_serial: driver initialized bob # [ 0.395240] SuperH (H)SCI(F) driver initialized bob # [ 0.395280] STM32 USART driver initialized alice # [ 0.394050] msm_serial: driver initialized alice # [ 0.394137] SuperH (H)SCI(F) driver initialized alice # [ 0.394178] STM32 USART driver initialized clare # [ 0.417476] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) clare # [ 0.421596] megasas: 07.734.00.00-rc1 clare # [ 0.422093] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] clare # [ 0.424402] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.424429] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.426069] Using buffer write method clare # [ 0.426123] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] clare # [ 0.427864] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.427875] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.418315] loop: module loaded bob # [ 0.418449] virtio_blk virtio2: 1/0/0 default/read/poll queues bob # [ 0.419039] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 0.418321] loop: module loaded alice # [ 0.418453] virtio_blk virtio2: 1/0/0 default/read/poll queues alice # [ 0.419054] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) bob # [ 0.423103] megasas: 07.734.00.00-rc1 bob # [ 0.423583] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] clare # [ 0.441228] Using buffer write method clare # [ 0.441241] Concatenating MTD devices: clare # [ 0.441243] (0): "0.flash" clare # [ 0.441245] (1): "0.flash" clare # [ 0.441247] into device "0.flash" alice # [ 0.422122] megasas: 07.734.00.00-rc1 alice # [ 0.422622] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] bob # [ 0.427111] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.427139] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.428818] Using buffer write method bob # [ 0.428876] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.428283] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.428310] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.439270] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.439283] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.440937] Using buffer write method bob # [ 0.440949] Concatenating MTD devices: bob # [ 0.440951] (0): "0.flash" alice # [ 0.437716] Using buffer write method bob # [ 0.440952] (1): "0.flash" bob # [ 0.440954] into device "0.flash" alice # [ 0.437781] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.439472] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.439484] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.443288] Using buffer write method alice # [ 0.443300] Concatenating MTD devices: alice # [ 0.443302] (0): "0.flash" alice # [ 0.443304] (1): "0.flash" alice # [ 0.443305] into device "0.flash" clare # [ 0.653170] Freeing initrd memory: 26948K clare # [ 0.659937] tun: Universal TUN/TAP device driver, 1.6 clare # [ 0.662551] thunder_xcv, ver 1.0 clare # [ 0.662567] thunder_bgx, ver 1.0 clare # [ 0.662576] nicpf, ver 1.0 clare # [ 0.662901] e1000: Intel(R) PRO/1000 Network Driver clare # [ 0.662906] e1000: Copyright (c) 1999-2006 Intel Corporation. clare # [ 0.662918] e1000e: Intel(R) PRO/1000 Network Driver clare # [ 0.662923] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. clare # [ 0.662934] igb: Intel(R) Gigabit Ethernet Network Driver clare # [ 0.662936] igb: Copyright (c) 2007-2014 Intel Corporation. clare # [ 0.662946] igbvf: Intel(R) Gigabit Virtual Function Network Driver clare # [ 0.662951] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. clare # [ 0.663017] sky2: driver version 1.30 clare # [ 0.663859] usbcore: registered new interface driver usb-storage clare # [ 0.663933] usbcore: registered new interface driver usbserial_generic clare # [ 0.663939] usbserial: USB Serial support registered for generic clare # [ 0.664241] hv_vmbus: registering driver hyperv_keyboard bob # [ 0.661770] Freeing initrd memory: 26928K alice # [ 0.664033] Freeing initrd memory: 26944K bob # [ 0.668714] tun: Universal TUN/TAP device driver, 1.6 bob # [ 0.671449] thunder_xcv, ver 1.0 bob # [ 0.671466] thunder_bgx, ver 1.0 bob # [ 0.671475] nicpf, ver 1.0 bob # [ 0.671770] e1000: Intel(R) PRO/1000 Network Driver bob # [ 0.671774] e1000: Copyright (c) 1999-2006 Intel Corporation. alice # [ 0.670865] tun: Universal TUN/TAP device driver, 1.6 bob # [ 0.671787] e1000e: Intel(R) PRO/1000 Network Driver bob # [ 0.671792] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.671804] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.671806] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.671816] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.671820] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. bob # [ 0.671883] sky2: driver version 1.30 bob # [ 0.672752] usbcore: registered new interface driver usb-storage bob # [ 0.672840] usbcore: registered new interface driver usbserial_generic alice # [ 0.673509] thunder_xcv, ver 1.0 bob # [ 0.672846] usbserial: USB Serial support registered for generic alice # [ 0.673525] thunder_bgx, ver 1.0 alice # [ 0.673534] nicpf, ver 1.0 bob # [ 0.673149] hv_vmbus: registering driver hyperv_keyboard alice # [ 0.673898] e1000: Intel(R) PRO/1000 Network Driver alice # [ 0.673903] e1000: Copyright (c) 1999-2006 Intel Corporation. alice # [ 0.673919] e1000e: Intel(R) PRO/1000 Network Driver alice # [ 0.673924] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. alice # [ 0.673937] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.680484] ehci-pci 0000:00:07.0: EHCI Host Controller alice # [ 0.673939] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.680510] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 alice # [ 0.673948] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.680773] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 alice # [ 0.673953] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. bob # [ 0.681146] rtc-pl031 9010000.pl031: registered as rtc0 alice # [ 0.674027] sky2: driver version 1.30 bob # [ 0.681171] rtc-pl031 9010000.pl031: setting system clock to 2026-10-07T05:20:48 UTC (1791350448) alice # [ 0.674891] usbcore: registered new interface driver usb-storage bob # [ 0.681342] i2c_dev: i2c /dev entries driver alice # [ 0.674968] usbcore: registered new interface driver usbserial_generic alice # [ 0.674974] usbserial: USB Serial support registered for generic clare # [ 0.671576] ehci-pci 0000:00:07.0: EHCI Host Controller alice # [ 0.675281] hv_vmbus: registering driver hyperv_keyboard clare # [ 0.671607] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 alice # [ 0.676248] ehci-pci 0000:00:07.0: EHCI Host Controller clare # [ 0.671829] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 alice # [ 0.676274] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 clare # [ 0.672009] rtc-pl031 9010000.pl031: registered as rtc0 clare # [ 0.672035] rtc-pl031 9010000.pl031: setting system clock to 2026-10-07T05:20:48 UTC (1791350448) clare # [ 0.672238] i2c_dev: i2c /dev entries driver alice # [ 0.683984] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 clare # [ 0.673798] sdhci: Secure Digital Host Controller Interface driver alice # [ 0.684171] rtc-pl031 9010000.pl031: registered as rtc0 clare # [ 0.673803] sdhci: Copyright(c) Pierre Ossman clare # [ 0.673956] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.684198] rtc-pl031 9010000.pl031: setting system clock to 2026-10-07T05:20:48 UTC (1791350448) clare # [ 0.674135] sdhci-pltfm: SDHCI platform and OF driver helper alice # [ 0.684424] i2c_dev: i2c /dev entries driver clare # [ 0.675085] hid: raw HID events driver (C) Jiri Kosina clare # [ 0.675220] usbcore: registered new interface driver usbhid clare # [ 0.675222] usbhid: USB HID core driver bob # [ 0.685865] sdhci: Secure Digital Host Controller Interface driver bob # [ 0.685871] sdhci: Copyright(c) Pierre Ossman bob # [ 0.686016] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.687922] sdhci: Secure Digital Host Controller Interface driver alice # [ 0.687928] sdhci: Copyright(c) Pierre Ossman bob # [ 0.686194] sdhci-pltfm: SDHCI platform and OF driver helper bob # [ 0.687042] hid: raw HID events driver (C) Jiri Kosina alice # [ 0.688081] Synopsys Designware Multimedia Card Interface Driver bob # [ 0.687177] usbcore: registered new interface driver usbhid alice # [ 0.688261] sdhci-pltfm: SDHCI platform and OF driver helper clare # [ 0.708139] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 bob # [ 0.687180] usbhid: USB HID core driver clare # [ 0.708336] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available bob # [ 0.687851] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available clare # [ 0.709232] drop_monitor: Initializing network drop monitor service bob # [ 0.688684] drop_monitor: Initializing network drop monitor service clare # [ 0.709396] NET: Registered PF_INET6 protocol family bob # [ 0.688790] NET: Registered PF_INET6 protocol family clare # [ 0.709840] hub 1-0:1.0: USB hub found clare # [ 0.709859] hub 1-0:1.0: 6 ports detected bob # [ 0.689427] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 bob # [ 0.689805] hub 1-0:1.0: USB hub found alice # [ 0.691014] hid: raw HID events driver (C) Jiri Kosina bob # [ 0.689824] hub 1-0:1.0: 6 ports detected alice # [ 0.691154] usbcore: registered new interface driver usbhid clare # [ 0.713266] Segment Routing with IPv6 alice # [ 0.691157] usbhid: USB HID core driver clare # [ 0.713283] In-situ OAM (IOAM) with IPv6 clare # [ 0.713353] NET: Registered PF_PACKET protocol family alice # [ 0.691842] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available bob # [ 0.695623] Segment Routing with IPv6 bob # [ 0.695642] In-situ OAM (IOAM) with IPv6 bob # [ 0.695703] NET: Registered PF_PACKET protocol family bob # [ 0.695770] 9pnet: Installing 9P2000 support bob # [ 0.695800] Key type dns_resolver registered alice # [ 0.694208] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 alice # [ 0.694550] hub 1-0:1.0: USB hub found clare # [ 0.714449] 9pnet: Installing 9P2000 support alice # [ 0.694569] hub 1-0:1.0: 6 ports detected clare # [ 0.714485] Key type dns_resolver registered alice # [ 0.695178] drop_monitor: Initializing network drop monitor service alice # [ 0.695289] NET: Registered PF_INET6 protocol family alice # [ 0.695947] Segment Routing with IPv6 alice # [ 0.695966] In-situ OAM (IOAM) with IPv6 bob # [ 0.700389] registered taskstats version 1 alice # [ 0.696030] NET: Registered PF_PACKET protocol family bob # [ 0.700526] Loading compiled-in X.509 certificates alice # [ 0.696092] 9pnet: Installing 9P2000 support alice # [ 0.696126] Key type dns_resolver registered clare # [ 0.717956] registered taskstats version 1 clare # [ 0.718091] Loading compiled-in X.509 certificates alice # [ 0.702602] registered taskstats version 1 alice # [ 0.702758] Loading compiled-in X.509 certificates clare # [ 0.723376] Demotion targets for Node 0: null clare # [ 0.723450] Key type .fscrypt registered clare # [ 0.723456] Key type fscrypt-provisioning registered clare # [ 0.723560] ima: No TPM chip found, activating TPM-bypass! clare # [ 0.723571] ima: Allocated hash algorithm: sha1 clare # [ 0.723626] ima: No architecture policies found bob # [ 0.706020] Demotion targets for Node 0: null bob # [ 0.706105] Key type .fscrypt registered clare # [ 0.724095] input: gpio-keys as /devices/platform/gpio-keys/input/input0 bob # [ 0.706111] Key type fscrypt-provisioning registered bob # [ 0.706224] ima: No TPM chip found, activating TPM-bypass! bob # [ 0.706234] ima: Allocated hash algorithm: sha1 alice # [ 0.708053] Demotion targets for Node 0: null bob # [ 0.706298] ima: No architecture policies found alice # [ 0.708129] Key type .fscrypt registered alice # [ 0.708136] Key type fscrypt-provisioning registered bob # [ 0.706731] input: gpio-keys as /devices/platform/gpio-keys/input/input0 alice # [ 0.708243] ima: No TPM chip found, activating TPM-bypass! alice # [ 0.708254] ima: Allocated hash algorithm: sha1 alice # [ 0.708308] ima: No architecture policies found alice # [ 0.711247] input: gpio-keys as /devices/platform/gpio-keys/input/input0 clare # [ 0.736962] clk: Disabling unused clocks clare # [ 0.736971] PM: genpd: Disabling unused power domains bob # [ 0.719432] clk: Disabling unused clocks bob # [ 0.719441] PM: genpd: Disabling unused power domains clare # [ 0.739958] Freeing unused kernel memory: 4736K clare # [ 0.740036] Run /init as init process bob # [ 0.722482] Freeing unused kernel memory: 4736K bob # [ 0.722564] Run /init as init process alice # [ 0.721631] clk: Disabling unused clocks alice # [ 0.721641] PM: genpd: Disabling unused power domains alice # [ 0.723932] Freeing unused kernel memory: 4736K alice # [ 0.724030] Run /init as init process bob # [ 0.743871] systemd[1]: Successfully made /usr/ read-only. clare # [ 0.763629] systemd[1]: Successfully made /usr/ read-only. alice # [ 0.746755] systemd[1]: Successfully made /usr/ read-only. clare # [ 0.957260] usb 1-1: new high-speed USB device number 2 using ehci-pci bob # [ 0.941443] usb 1-1: new high-speed USB device number 2 using ehci-pci alice # [ 0.944622] usb 1-1: new high-speed USB device number 2 using ehci-pci bob # [ 1.078022] systemd[1]: systemd 261.3 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.078052] systemd[1]: Detected virtualization qemu. clare # [ 1.097699] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) clare # [ 1.097731] systemd[1]: Detected virtualization qemu. bob # [ 1.078092] systemd[1]: Detected architecture arm64. bob # [ 1.078101] systemd[1]: Running in initrd. clare # [ 1.097772] systemd[1]: Detected architecture arm64. bob # [ 1.078913] systemd[1]: Initializing machine ID from random generator. clare # [ 1.097781] systemd[1]: Running in initrd. alice # [ 1.080893] systemd[1]: systemd 261.3 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.079180] systemd[1]: Hostname set to . clare # [ 1.098651] systemd[1]: Initializing machine ID from random generator. alice # [ 1.080924] systemd[1]: Detected virtualization qemu. clare # [ 1.098940] systemd[1]: Hostname set to . alice # [ 1.080965] systemd[1]: Detected architecture arm64. alice # [ 1.080975] systemd[1]: Running in initrd. alice # [ 1.081753] systemd[1]: Initializing machine ID from random generator. alice # [ 1.082174] systemd[1]: Hostname set to . bob # [ 1.101210] 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.120766] 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.103811] 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.189554] 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.209379] 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.192524] 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.216175] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 1.233661] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 1.217182] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 1.283042] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 1.267249] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.287313] systemd[1]: Created slice Slice /system/modprobe. alice # [ 1.266557] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.287457] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 1.287487] systemd[1]: Expecting device /dev/disk/by-label/nixos... clare # [ 1.287507] systemd[1]: Reached target Path Units. clare # [ 1.287519] systemd[1]: Reached target Slice Units. clare # [ 1.287531] systemd[1]: Reached target Swaps. clare # [ 1.287542] systemd[1]: Reached target Timer Units. clare # [ 1.287666] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 1.287773] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 1.270451] systemd[1]: Created slice Slice /system/modprobe. clare # [ 1.287880] systemd[1]: Listening on Journal Sockets. alice # [ 1.270596] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 1.287946] systemd[1]: Listening on udev Control Socket. alice # [ 1.270624] systemd[1]: Expecting device /dev/disk/by-label/nixos... clare # [ 1.287988] systemd[1]: Listening on udev Kernel Socket. alice # [ 1.270643] systemd[1]: Reached target Path Units. clare # [ 1.288002] systemd[1]: Reached target Socket Units. alice # [ 1.270654] systemd[1]: Reached target Slice Units. clare # [ 1.289028] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.270666] systemd[1]: Reached target Swaps. bob # [ 1.274833] systemd[1]: Created slice Slice /system/modprobe. alice # [ 1.270676] systemd[1]: Reached target Timer Units. clare # [ 1.289077] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.270804] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 1.274972] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 1.270915] systemd[1]: Listening on Journal Socket (/dev/log). bob # [ 1.275001] systemd[1]: Expecting device /dev/disk/by-label/nixos... alice # [ 1.271032] systemd[1]: Listening on Journal Sockets. bob # [ 1.275022] systemd[1]: Reached target Path Units. alice # [ 1.271102] systemd[1]: Listening on udev Control Socket. bob # [ 1.275034] systemd[1]: Reached target Slice Units. alice # [ 1.271144] systemd[1]: Listening on udev Kernel Socket. bob # [ 1.275046] systemd[1]: Reached target Swaps. alice # [ 1.271157] systemd[1]: Reached target Socket Units. bob # [ 1.275057] systemd[1]: Reached target Timer Units. alice # [ 1.272217] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 1.275183] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 1.275297] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 1.272270] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 1.297978] systemd[1]: Mounting Kernel Configuration File System... bob # [ 1.275609] systemd[1]: Listening on Journal Sockets. bob # [ 1.275683] systemd[1]: Listening on udev Control Socket. bob # [ 1.275727] systemd[1]: Listening on udev Kernel Socket. bob # [ 1.275740] systemd[1]: Reached target Socket Units. bob # [ 1.276817] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.281271] systemd[1]: Mounting Kernel Configuration File System... bob # [ 1.276868] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 1.307176] systemd[1]: Starting Journal Service... bob # [ 1.293191] systemd[1]: Mounting Kernel Configuration File System... alice # [ 1.288528] systemd[1]: Starting Journal Service... clare # [ 1.316091] systemd[1]: Starting Load Kernel Modules... clare # [ 1.316160] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 1.298973] systemd[1]: Starting Journal Service... bob # [ 1.304669] systemd[1]: Starting Load Kernel Modules... bob # [ 1.304740] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 1.312444] usb 1-2: new high-speed USB device number 3 using ehci-pci bob # [ 1.309583] usb 1-2: new high-speed USB device number 3 using ehci-pci alice # [ 1.317113] systemd[1]: Starting Load Kernel Modules... alice # [ 1.317187] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 1.317980] systemd[1]: Starting Coldplug All udev Devices... clare # [ 1.333228] usb 1-2: new high-speed USB device number 3 using ehci-pci clare # [ 1.333539] systemd[1]: Starting Coldplug All udev Devices... bob # [ 1.324856] systemd[1]: Starting Coldplug All udev Devices... bob # [ 1.329048] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 1.345299] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 1.346146] systemd[1]: Mounted Kernel Configuration File System. bob # [ 1.330407] systemd[1]: Mounted Kernel Configuration File System. clare # [ 1.353433] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.336466] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 1.339468] systemd-journald[71]: Collecting audit messages is disabled. clare # [ 1.360285] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. bob # [ 1.342158] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 1.350718] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. bob # [ 1.354572] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev clare # [ 1.373320] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev alice # [ 1.359181] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. bob # [ 1.363203] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 bob # [ 1.363434] [drm] features: -virgl +edid -resource_blob -host_visible bob # [ 1.363436] [drm] features: -context_init bob # [ 1.363886] [drm] number of scanouts: 1 bob # [ 1.363894] [drm] number of cap sets: 0 alice # [ 1.361517] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.361902] systemd[1]: Mounted Kernel Configuration File System. clare # [ 1.385630] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 1.386661] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 1.368472] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev clare # [ 1.392058] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 clare # [ 1.392354] [drm] features: -virgl +edid -resource_blob -host_visible clare # [ 1.392356] [drm] features: -context_init clare # [ 1.392808] [drm] number of scanouts: 1 clare # [ 1.392820] [drm] number of cap sets: 0 bob # [ 1.381098] systemd-journald[71]: Collecting audit messages is disabled. clare # [ 1.400926] systemd-journald[71]: Collecting audit messages is disabled. alice # [ 1.381006] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 1.382129] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 1.385818] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 1.386917] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 1.405494] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic clare # [ 1.405501] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 bob # [ 1.390834] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic bob # [ 1.390842] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 alice # [ 1.390147] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 alice # [ 1.390360] [drm] features: -virgl +edid -resource_blob -host_visible alice # [ 1.390362] [drm] features: -context_init alice # [ 1.390944] [drm] number of scanouts: 1 alice # [ 1.390953] [drm] number of cap sets: 0 alice # [ 1.400654] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic alice # [ 1.400662] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 clare # [ 1.422394] Console: switching to colour frame buffer device 160x50 bob # [ 1.405817] Console: switching to colour frame buffer device 160x50 clare # [ 1.437662] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 1.437800] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 1.437818] systemd[1]: Reached target Local File Systems. alice # [ 1.418836] systemd[1]: Started Journal Service. clare # [ 1.442938] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 1.419894] Console: switching to colour frame buffer device 160x50 alice # [ 1.420123] systemd-modules-load[73]: Inserted module 'dm_mod' bob # [ 1.426162] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.421630] systemd-modules-load[73]: Module 'virtio_balloon' is built in clare # [ 1.449850] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.425473] systemd-modules-load[73]: Module 'virtio_console' is built in bob # [ 1.433835] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 1.427377] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 1.433996] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 1.434026] systemd[1]: Reached target Local File Systems. bob # [ 1.435184] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 1.432218] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 1.436811] systemd[1]: Reached target Local File Systems. alice # [ 1.438454] systemd[1]: Starting Create System Files and Directories... alice # [ 1.444833] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device clare # [ 1.468682] systemd[1]: Finished Load Kernel Modules. bob # [ 1.449726] systemd[1]: Finished Load Kernel Modules. alice # [ 1.444374] systemd-modules-load[73]: Inserted module 'virtio_gpu' alice # [ 1.444978] systemd-modules-load[73]: Module 'virtio_rng' is built in clare # [ 1.470940] systemd[1]: Starting Apply Kernel Variables... clare # [ 1.468526] systemd-modules-load[72]: Inserted module 'dm_mod' clare # [ 1.469438] systemd-modules-load[72]: Module 'virtio_balloon' is built in clare # [ 1.472163] systemd-modules-load[72]: Module 'virtio_console' is built in alice # [ 1.452834] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 1.479372] systemd[1]: Started Journal Service. alice # [ 1.454454] systemd[1]: Finished Load Kernel Modules. bob # [ 1.463059] systemd[1]: Starting Apply Kernel Variables... bob # [ 1.465040] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 bob # [ 1.465223] 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.477489] systemd-modules-load[72]: Inserted module 'virtio_gpu' clare # [ 1.485745] 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.485905] 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.470452] 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.470615] 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.485208] systemd-modules-load[72]: Module 'virtio_rng' is built in bob # [ 1.469215] systemd-modules-load[72]: Inserted module 'dm_mod' alice # [ 1.469053] systemd[1]: Starting Apply Kernel Variables... bob # [ 1.477567] systemd[1]: Started Journal Service. bob # [ 1.472708] systemd-modules-load[72]: Module 'virtio_balloon' is built in bob # [ 1.473462] systemd-modules-load[72]: Module 'virtio_console' is built in bob # [ 1.474196] systemd-modules-load[72]: Inserted module 'virtio_gpu' bob # [ 1.474906] systemd-modules-load[72]: Module 'virtio_rng' is built in clare # [ 1.500098] systemd[1]: Starting Create System Files and Directories... bob # [ 1.486395] systemd[1]: Starting Create System Files and Directories... clare # [ 1.515194] systemd[1]: Finished Apply Kernel Variables. alice # [ 1.500528] systemd[1]: Finished Create System Files and Directories. clare # [ 1.523592] systemd-udevd[79]: Using default interface naming scheme 'v261'. bob # [ 1.512122] systemd[1]: Finished Apply Kernel Variables. bob # [ 1.513742] systemd-udevd[79]: Using default interface naming scheme 'v261'. alice # [ 1.516734] systemd[1]: Finished Apply Kernel Variables. clare # [ 1.537865] systemd[1]: Finished Create System Files and Directories. bob # [ 1.520668] systemd[1]: Finished Create System Files and Directories. clare # [ 1.548487] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 1.534523] systemd-udevd[81]: Using default interface naming scheme 'v261'. bob # [ 1.536313] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 1.552802] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 1.597166] systemd[1]: Starting Virtual Console Setup... bob # [ 1.584081] systemd[1]: Starting Virtual Console Setup... alice # [ 1.612204] systemd[1]: Starting Virtual Console Setup... clare # [ 1.640343] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 1.644111] systemd[1]: Finished Virtual Console Setup. bob # [ 1.633426] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 1.634510] systemd[1]: Finished Virtual Console Setup. alice # [ 1.652342] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 1.654541] systemd[1]: Finished Virtual Console Setup. bob # [ 1.856994] systemd[1]: Finished Coldplug All udev Devices. bob # [ 1.857606] systemd[1]: Reached target System Initialization. bob # [ 1.858135] systemd[1]: Reached target Basic System. alice # [ 1.860118] systemd[1]: Finished Coldplug All udev Devices. alice # [ 1.860723] systemd[1]: Reached target System Initialization. alice # [ 1.861261] systemd[1]: Reached target Basic System. clare # [ 1.885333] systemd[1]: Finished Coldplug All udev Devices. clare # [ 1.885957] systemd[1]: Reached target System Initialization. clare # [ 1.886546] systemd[1]: Reached target Basic System. clare # [ 1.974268] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. bob # [ 1.958837] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. alice # [ 1.983851] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line. bob # [ 1.986204] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.004337] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. alice # [ 1.996985] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.034124] systemd[1]: Found device /dev/disk/by-label/nixos. bob # [ 2.036295] systemd[1]: Reached target Initrd Root Device. bob # [ 2.038693] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... clare # [ 2.073357] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.076137] systemd[1]: Reached target Initrd Root Device. alice # [ 2.056863] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.079238] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... alice # [ 2.063770] systemd[1]: Reached target Initrd Root Device. alice # [ 2.068581] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... bob # [ 2.085559] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 2.095806] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. clare # [ 2.114135] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks clare # [ 2.121747] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 2.112237] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks alice # [ 2.118536] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 2.312551] systemd[1]: Mounting /sysroot... clare # [ 2.333562] systemd[1]: Mounting /sysroot... bob # [ 2.318351] systemd[1]: Mounting /sysroot... alice # [ 2.342207] EXT4-fs (vda): mounted filesystem 50142622-57b5-4dfa-9490-28a1b3a21de1 r/w with ordered data mode. Quota mode: none. alice # [ 2.338104] systemd[1]: Mounted /sysroot. alice # [ 2.339417] systemd[1]: Reached target Initrd Root File System. clare # [ 2.363515] systemd[1]: Mounted /sysroot. alice # [ 2.343943] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 2.370041] EXT4-fs (vda): mounted filesystem 3c03a584-5778-40ca-89c8-804af6ee8a0c r/w with ordered data mode. Quota mode: none. bob # [ 2.347435] systemd[1]: Mounted /sysroot. clare # [ 2.366106] systemd[1]: Reached target Initrd Root File System. bob # [ 2.353423] EXT4-fs (vda): mounted filesystem dceae672-475b-4dbd-a218-398d406d48e0 r/w with ordered data mode. Quota mode: none. bob # [ 2.349714] systemd[1]: Reached target Initrd Root File System. clare # [ 2.370595] systemd[1]: Mounting /sysroot/nix/.ro-store... bob # [ 2.353118] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.355894] systemd[1]: Mounting /sysroot/nix/.rw-store... clare # [ 2.382011] systemd[1]: Mounting /sysroot/nix/.rw-store... alice # [ 2.364091] systemd[1]: Mounting /sysroot/run... clare # [ 2.387081] systemd[1]: Mounting /sysroot/run... bob # [ 2.374767] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 2.380221] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... bob # [ 2.383342] systemd[1]: Reloading... clare # [ 2.402516] systemd[1]: Mounting /sysroot/tmp/shared... alice # [ 2.385477] systemd[1]: Mounting /sysroot/tmp/shared... alice # [ 2.405841] fuse: init (API version 7.45) alice # [ 2.404104] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 2.436749] fuse: init (API version 7.45) alice # [ 2.418909] virtiofs virtio6: discovered new tag: nix-store alice # [ 2.419426] virtiofs virtio6: virtio_fs_setup_dax: No cache capability alice # [ 2.415760] systemd[1]: Starting Mountpoints Configured in the Real Root... clare # [ 2.436058] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 2.440140] systemd[1]: Starting Mountpoints Configured in the Real Root... clare # [ 2.445878] systemd[1]: Mounted /sysroot/nix/.rw-store. clare # [ 2.453588] virtiofs virtio6: discovered new tag: nix-store alice # [ 2.433709] virtiofs virtio7: discovered new tag: shared clare # [ 2.454096] virtiofs virtio6: virtio_fs_setup_dax: No cache capability alice # [ 2.434217] virtiofs virtio7: virtio_fs_setup_dax: No cache capability alice # [ 2.436050] virtiofs virtio8: discovered new tag: xchg alice # [ 2.443383] virtiofs virtio8: virtio_fs_setup_dax: No cache capability clare # [ 2.470611] virtiofs virtio7: discovered new tag: shared clare # [ 2.471126] virtiofs virtio7: virtio_fs_setup_dax: No cache capability clare # [ 2.472879] virtiofs virtio8: discovered new tag: xchg alice # [ 2.450441] systemd[1]: Mounted /sysroot/nix/.ro-store. clare # [ 2.479773] virtiofs virtio8: virtio_fs_setup_dax: No cache capability clare # [ 2.482691] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 2.465199] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 2.467541] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 2.475042] systemd[1]: Mounted /sysroot/run. alice # [ 2.476238] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 2.484808] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 2.506237] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload. clare # [ 2.507278] systemd[1]: Mounted /sysroot/run. alice # [ 2.487298] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 2.491441] systemd[1]: Reloading... clare # [ 2.511346] systemd[1]: Mounted /sysroot/nix/.ro-store. clare # [ 2.512953] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 2.514313] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 2.515817] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 2.527533] systemd[1]: Mounted /sysroot/tmp/xchg. clare # [ 2.529794] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)... clare # [ 2.532685] systemd[1]: Reloading... bob # [ 2.564158] systemd[1]: Reloading finished in 182 ms. bob # [ 2.592247] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... bob # [ 2.594802] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... bob # [ 2.597276] systemd[1]: initrd-parse-etc.service: Deactivated successfully. bob # [ 2.599170] systemd[1]: Finished Mountpoints Configured in the Real Root. bob # [ 2.600734] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.660734] systemd[1]: Reloading finished in 170 ms. clare # [ 2.698215] systemd[1]: Reloading finished in 166 ms. alice # [ 2.681230] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace... alice # [ 2.682719] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace... alice # [ 2.690751] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 2.692812] systemd[1]: initrd-parse-etc.service: Deactivated successfully. alice # [ 2.701829] systemd[1]: Finished Mountpoints Configured in the Real Root. alice # [ 2.702468] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. clare # [ 2.724583] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace... alice # [ 2.705433] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 2.706150] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 2.727467] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace... clare # [ 2.730676] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 2.732490] systemd[1]: initrd-parse-etc.service: Deactivated successfully. clare # [ 2.738424] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 2.739154] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. clare # [ 2.757972] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 2.758656] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 2.779281] fuse: init (API version 7.45) bob # [ 2.822615] virtiofs virtio6: discovered new tag: nix-store bob # [ 2.823127] virtiofs virtio6: virtio_fs_setup_dax: No cache capability bob # [ 2.828969] (udev-worker)[90]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory bob # [ 2.833015] (udev-worker)[90]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory bob # [ 2.851133] virtiofs virtio7: discovered new tag: shared bob # [ 2.851657] virtiofs virtio7: virtio_fs_setup_dax: No cache capability bob # [ 2.855416] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[ 2.864147] virtiofs virtio8: discovered new tag: xchg bob # [ 2.864685] virtiofs virtio8: virtio_fs_setup_dax: No cache capability bob # bob # [ 2.859325] systemd[1]: Stopped Virtual Console Setup. bob # [ 2.864782] systemd[1]: Stopping Virtual Console Setup... bob # [ 2.865464] systemd[1]: Starting Virtual Console Setup... bob # [ 2.873881] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 2.875900] systemd[1]: Stopped Virtual Console Setup. bob # [ 2.880204] systemd[1]: Starting Virtual Console Setup... bob # [ 2.894976] systemd-vconsole-setup[139]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 2.897256] systemd[1]: Finished Virtual Console Setup. clare # [ 2.938268] (udev-worker)[98]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory clare # [ 2.944128] (udev-worker)[98]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory clare # [ 2.958872] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 2.960382] systemd[1]: Stopped Virtual Console Setup. clare # [ 2.963890] systemd[1]: Stopping Virtual Console Setup... clare # [ 2.964547] systemd[1]: Starting Virtual Console Setup... clare # [ 2.971828] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 2.972921] systemd[1]: Stopped Virtual Console Setup. clare # [ 2.974341] systemd[1]: Starting Virtual Console Setup... alice # [ 2.961732] (udev-worker)[95]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory alice # [ 2.967273] (udev-worker)[95]: 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 # [ 2.990732] systemd-vconsole-setup[154]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 2.992808] systemd[1]: Finished Virtual Console Setup. alice # [ 2.993763] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 2.996681] systemd[1]: Stopped Virtual Console Setup. alice # [ 2.997225] systemd[1]: Stopping Virtual Console Setup... alice # [ 3.000844] systemd[1]: Starting Virtual Console Setup... alice # [ 3.018855] systemd-vconsole-setup[151]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 3.021032] systemd[1]: Finished Virtual Console Setup. alice # [ 3.313793] systemd[1]: Mounting /sysroot/nix/store... clare # [ 3.336072] systemd[1]: Mounting /sysroot/nix/store... bob # [ 3.321891] systemd[1]: Mounting /sysroot/nix/.ro-store... bob # [ 3.333730] systemd[1]: Mounting /sysroot/nix/.rw-store... bob # [ 3.336123] systemd[1]: Mounting /sysroot/run... bob # [ 3.348272] systemd[1]: Mounting /sysroot/tmp/shared... alice # [ 3.363987] systemd[1]: Mounted /sysroot/nix/store. alice # [ 3.366461] systemd[1]: Reached target Initrd File Systems. bob # [ 3.368343] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 3.388484] systemd[1]: Mounted /sysroot/nix/store. alice # [ 3.370385] systemd[1]: Starting Find NixOS closure... clare # [ 3.391464] systemd[1]: Reached target Initrd File Systems. clare # [ 3.394654] systemd[1]: Starting Find NixOS closure... alice # [ 3.377320] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... clare # [ 3.404639] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 3.402194] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 3.409235] systemd[1]: Mounted /sysroot/nix/.ro-store. bob # [ 3.411551] systemd[1]: Mounted /sysroot/run. bob # [ 3.417109] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 3.416293] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 3.424184] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.422355] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob # [ 3.426540] systemd[1]: Mounted /sysroot/tmp/xchg. clare # [ 3.444433] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. clare # [ 3.447178] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. alice # [ 3.436274] systemd[1]: Finished Find NixOS closure. bob # [ 3.439131] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.438761] systemd[1]: Reached target Initrd Default Target. bob # [ 3.440340] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.462898] systemd[1]: Finished Find NixOS closure. alice # [ 3.443993] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... clare # [ 3.464747] systemd[1]: Reached target Initrd Default Target. clare # [ 3.467086] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 3.459576] systemd[1]: Stopped target Initrd Default Target. alice # [ 3.461246] systemd[1]: Stopped target Basic System. alice # [ 3.462206] systemd[1]: Stopped target Initrd Root Device. alice # [ 3.463277] systemd[1]: Stopped target Path Units. alice # [ 3.464739] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 3.468269] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. alice # [ 3.469228] systemd[1]: Stopped target Slice Units. alice # [ 3.472138] systemd[1]: Stopped target Socket Units. alice # [ 3.472814] systemd[1]: Stopped target System Initialization. alice # [ 3.474116] systemd[1]: Stopped target Swaps. alice # [ 3.475320] systemd[1]: Stopped target Timer Units. clare # [ 3.496786] systemd[1]: Stopped target Initrd Default Target. alice # [ 3.477839] systemd[1]: dbus.socket: Deactivated successfully. clare # [ 3.498229] systemd[1]: Stopped target Basic System. alice # [ 3.480758] systemd[1]: Closed D-Bus System Message Bus Socket. clare # [ 3.500248] systemd[1]: Stopped target Initrd Root Device. alice # [ 3.481926] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. alice # [ 3.484519] systemd[1]: Stopped Find NixOS closure. clare # [ 3.504287] systemd[1]: Stopped target Path Units. clare # [ 3.505692] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 3.486887] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.507357] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. alice # [ 3.490100] systemd[1]: systemd-sysctl.service: Deactivated successfully. alice # [ 3.491978] systemd[1]: Stopped Apply Kernel Variables. clare # [ 3.512087] systemd[1]: Stopped target Slice Units. alice # [ 3.492614] systemd[1]: systemd-modules-load.service: Deactivated successfully. clare # [ 3.512797] systemd[1]: Stopped target Socket Units. clare # [ 3.513593] systemd[1]: Stopped target System Initialization. alice # [ 3.494635] systemd[1]: Stopped Load Kernel Modules. clare # [ 3.514312] systemd[1]: Stopped target Swaps. clare # [ 3.514868] systemd[1]: Stopped target Timer Units. alice # [ 3.495572] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. clare # [ 3.515496] systemd[1]: dbus.socket: Deactivated successfully. alice # [ 3.502199] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. alice # [ 3.504194] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. alice # [ 3.507161] systemd[1]: Stopped Create System Files and Directories. alice # [ 3.507805] systemd[1]: Stopped target Local File Systems. clare # [ 3.527505] systemd[1]: Closed D-Bus System Message Bus Socket. alice # [ 3.509123] systemd[1]: Stopped target Preparation for Local File Systems. clare # [ 3.528556] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. clare # [ 3.529642] systemd[1]: Stopped Find NixOS closure. alice # [ 3.510807] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 3.512117] systemd[1]: Stopped Coldplug All udev Devices. clare # [ 3.531488] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.532528] systemd[1]: systemd-sysctl.service: Deactivated successfully. alice # [ 3.513258] systemd[1]: Stopping Rule-based Manager for Device Events and Files... clare # [ 3.533696] systemd[1]: Stopped Apply Kernel Variables. clare # [ 3.534342] systemd[1]: systemd-modules-load.service: Deactivated successfully. clare # [ 3.535186] systemd[1]: Stopped Load Kernel Modules. alice # [ 3.516309] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.535778] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 3.517006] systemd[1]: Stopped Virtual Console Setup. alice # [ 3.517507] systemd[1]: initrd-cleanup.service: Deactivated successfully. clare # [ 3.537853] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. alice # [ 3.518797] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. alice # [ 3.519469] systemd[1]: systemd-udevd.service: Deactivated successfully. clare # [ 3.538777] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. alice # [ 3.520182] systemd[1]: Stopped Rule-based Manager for Device Events and Files. clare # [ 3.539613] systemd[1]: Stopped Create System Files and Directories. alice # [ 3.520835] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 3.521462] systemd[1]: Closed udev Control Socket. alice # [ 3.521936] systemd[1]: Starting Cleanup udev Database... alice # [ 3.522431] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. alice # [ 3.523124] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 3.523686] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. clare # [ 3.545769] systemd[1]: Stopped target Local File Systems. clare # [ 3.546437] systemd[1]: Stopped target Preparation for Local File Systems. clare # [ 3.547330] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 3.528189] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. alice # [ 3.532157] systemd[1]: kmod-static-nodes.service: Deactivated successfully. clare # [ 3.551639] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 3.532920] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 3.533492] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.534111] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.553450] systemd[1]: Stopping Rule-based Manager for Device Events and Files... clare # [ 3.556350] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.557845] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.558935] systemd[1]: initrd-cleanup.service: Deactivated successfully. clare # [ 3.562318] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. clare # [ 3.563045] systemd[1]: systemd-udevd.service: Deactivated successfully. clare # [ 3.563813] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 3.545044] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. alice # [ 3.545767] systemd[1]: Finished Cleanup udev Database. clare # [ 3.564825] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. clare # [ 3.566091] systemd[1]: Closed udev Control Socket. clare # [ 3.566613] systemd[1]: Starting Cleanup udev Database... clare # [ 3.567181] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. clare # [ 3.567947] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 3.548776] systemd[1]: Reached target Switch Root. alice # [ 3.549284] systemd[1]: Starting NixOS Activation... clare # [ 3.571784] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. clare # [ 3.576173] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. clare # [ 3.577210] systemd[1]: kmod-static-nodes.service: Deactivated successfully. clare # [ 3.577868] systemd[1]: Stopped Create List of Static Device Nodes. clare # [ 3.578448] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.579091] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.590949] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. clare # [ 3.595952] systemd[1]: Finished Cleanup udev Database. clare # [ 3.596602] systemd[1]: Reached target Switch Root. clare # [ 3.597169] systemd[1]: Starting NixOS Activation... alice # [ 3.621699] initrd-nixos-activation-start[174]: booting system configuration /nix/store/xp1n05bw700zw1i0y8x199f0abc3ij9n-nixos-system-alice-test clare # [ 3.668187] initrd-nixos-activation-start[177]: booting system configuration /nix/store/pz32wfgyriqbvmxl7nchdj3rlpir4vwl-nixos-system-clare-test alice # [ 3.649127] initrd-nixos-activation-start[174]: running activation script... clare # [ 3.695544] initrd-nixos-activation-start[177]: running activation script... alice # [ 3.866228] initrd-nixos-activation-start[197]: setting up /etc... clare # [ 3.905617] initrd-nixos-activation-start[200]: setting up /etc... alice # [ 3.951772] initrd-nixos-activation-start[197]: setting up age secrets... clare # [ 3.992774] initrd-nixos-activation-start[200]: setting up age secrets... alice # [ 4.013430] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. alice # [ 4.015226] systemd[1]: Finished NixOS Activation. alice # [ 4.015996] systemd[1]: Starting Switch Root... clare # [ 4.050473] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. alice # [ 4.032283] systemd[1]: Switching root. clare # [ 4.052164] systemd[1]: Finished NixOS Activation. clare # [ 4.053143] systemd[1]: Starting Switch Root... clare # [ 4.068436] systemd[1]: Switching root. alice # [ 4.200315] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). clare # [ 4.249585] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). bob # [ 4.319361] systemd[1]: Mounting /sysroot/nix/store... bob # [ 4.365242] systemd[1]: Mounted /sysroot/nix/store. bob # [ 4.368184] systemd[1]: Reached target Initrd File Systems. bob # [ 4.371618] systemd[1]: Starting Find NixOS closure... bob # [ 4.380343] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 4.418016] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 4.419846] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob # [ 4.434257] systemd[1]: Finished Find NixOS closure. bob # [ 4.436198] systemd[1]: Reached target Initrd Default Target. bob # [ 4.437734] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... bob # [ 4.466119] systemd[1]: Stopped target Initrd Default Target. bob # [ 4.468580] systemd[1]: Stopped target Basic System. bob # [ 4.472310] systemd[1]: Stopped target Initrd Root Device. bob # [ 4.473175] systemd[1]: Stopped target Path Units. bob # [ 4.473925] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. bob # [ 4.476322] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. bob # [ 4.480496] systemd[1]: Stopped target Slice Units. bob # [ 4.481658] systemd[1]: Stopped target Socket Units. bob # [ 4.484825] systemd[1]: Stopped target System Initialization. bob # [ 4.488901] systemd[1]: Stopped target Swaps. bob # [ 4.489508] systemd[1]: Stopped target Timer Units. bob # [ 4.490124] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 4.490833] systemd[1]: Closed D-Bus System Message Bus Socket. bob # [ 4.491714] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 4.497333] systemd[1]: Stopped Find NixOS closure. bob # [ 4.500981] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 4.501798] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 4.503154] systemd[1]: Stopped Apply Kernel Variables. bob # [ 4.503786] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 4.509479] systemd[1]: Stopped Load Kernel Modules. bob # [ 4.510074] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. bob # [ 4.511125] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. bob # [ 4.511964] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 4.520365] systemd[1]: Stopped Create System Files and Directories. bob # [ 4.521058] systemd[1]: Stopped target Local File Systems. bob # [ 4.521647] systemd[1]: Stopped target Preparation for Local File Systems. bob # [ 4.522350] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. bob # [ 4.523228] systemd[1]: Stopped Coldplug All udev Devices. bob # [ 4.523762] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 4.525614] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 4.526331] systemd[1]: Stopped Virtual Console Setup. bob # [ 4.526855] systemd[1]: systemd-udevd.service: Deactivated successfully. bob # [ 4.527596] systemd[1]: Stopped Rule-based Manager for Device Events and Files. bob # [ 4.528401] systemd[1]: initrd-cleanup.service: Deactivated successfully. bob # [ 4.529036] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. bob # [ 4.529647] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. bob # [ 4.530318] systemd[1]: Closed udev Control Socket. bob # [ 4.530798] systemd[1]: Starting Cleanup udev Database... bob # [ 4.531443] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 4.536243] systemd[1]: Stopped Create Static Device Nodes in /dev. bob # [ 4.536863] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. bob # [ 4.540191] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. bob # [ 4.540945] systemd[1]: kmod-static-nodes.service: Deactivated successfully. bob # [ 4.541584] systemd[1]: Stopped Create List of Static Device Nodes. bob # [ 4.544184] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 4.544877] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 4.554293] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 4.556513] systemd[1]: Finished Cleanup udev Database. bob # [ 4.559168] systemd[1]: Reached target Switch Root. bob # [ 4.559717] systemd[1]: Starting NixOS Activation... bob # [ 4.637021] initrd-nixos-activation-start[175]: booting system configuration /nix/store/vd8qkla9gq340l9f8ily9my4z20h0px6-nixos-system-bob-test bob # [ 4.664456] initrd-nixos-activation-start[175]: running activation script... clare # [ 4.762738] systemd[1]: systemd 261.3 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 # [ 4.741419] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) clare # [ 4.762833] systemd[1]: Detected virtualization qemu. alice # [ 4.741532] systemd[1]: Detected virtualization qemu. clare # [ 4.762893] systemd[1]: Detected architecture arm64. alice # [ 4.741590] systemd[1]: Detected architecture arm64. clare # [ 4.763026] systemd[1]: Detected first boot. alice # [ 4.741700] systemd[1]: Detected first boot. alice # [ 4.743844] systemd[1]: Initializing machine ID from random generator. clare # [ 4.776069] systemd[1]: Initializing machine ID from random generator. bob # [ 4.872891] initrd-nixos-activation-start[198]: setting up /etc... alice # [ 4.892212] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 4.913957] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 4.966495] initrd-nixos-activation-start[198]: setting up age secrets... clare # [ 5.043151] systemd[1]: Applying preset policy. alice # [ 5.028733] systemd[1]: Applying preset policy. bob # [ 5.044789] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 5.046550] systemd[1]: Finished NixOS Activation. bob # [ 5.047318] systemd[1]: Starting Switch Root... bob # [ 5.064402] systemd[1]: Switching root. alice # [ 5.203169] systemd[1]: Populated /etc with preset unit settings. clare # [ 5.226124] systemd[1]: Populated /etc with preset unit settings. bob # [ 5.234007] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). alice # [ 5.399135] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 5.399409] systemd[1]: Stopped initrd-switch-root.service. alice # [ 5.401901] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. alice # [ 5.403190] systemd[1]: Created slice Slice /system/getty. alice # [ 5.404202] systemd[1]: Created slice User and Session Slice. alice # [ 5.407315] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 5.407902] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 5.410349] systemd[1]: Expecting device /dev/hvc0... alice # [ 5.410848] systemd[1]: Expecting device /dev/ttyAMA0... alice # [ 5.411444] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 5.412024] systemd[1]: Stopped target initrd-fs.target. alice # [ 5.414917] systemd[1]: Stopped target initrd-root-fs.target. alice # [ 5.415410] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 5.415993] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 5.418618] systemd[1]: Reached target Path Units. alice # [ 5.419106] systemd[1]: Reached target Remote File Systems. alice # [ 5.419652] systemd[1]: Reached target Slice Units. alice # [ 5.420205] systemd[1]: Reached target Swaps. alice # [ 5.424274] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 5.444554] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 5.427102] systemd[1]: Listening on Process Core Dump Socket. clare # [ 5.446075] systemd[1]: Stopped initrd-switch-root.service. clare # [ 5.447894] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. clare # [ 5.449015] systemd[1]: Created slice Slice /system/getty. alice # [ 5.429206] systemd[1]: Listening on Credential Encryption/Decryption. alice # [ 5.430974] systemd[1]: Listening on Factory Reset Management. alice # [ 5.431536] systemd[1]: Listening on Hostname Service Socket. clare # [ 5.451885] systemd[1]: Created slice User and Session Slice. clare # [ 5.452470] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 5.453068] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 5.435741] systemd[1]: Starting Journal Log Access Socket... clare # [ 5.456352] systemd[1]: Expecting device /dev/hvc0... clare # [ 5.456827] systemd[1]: Expecting device /dev/ttyAMA0... alice # [ 5.437874] systemd[1]: Listening on Journal Audit Socket. alice # [ 5.440124] systemd[1]: Listening on Console Output Muting Service Socket. clare # [ 5.458988] systemd[1]: Reached target Local Encrypted Volumes. clare # [ 5.459475] systemd[1]: Stopped target initrd-fs.target. clare # [ 5.460065] systemd[1]: Stopped target initrd-root-fs.target. clare # [ 5.460608] systemd[1]: Stopped target initrd-switch-root.target. clare # [ 5.461179] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 5.442385] systemd[1]: Listening on Network Management Metrics Varlink Socket. alice # [ 5.443095] systemd[1]: Listening on Network Management Varlink Socket. clare # [ 5.464745] systemd[1]: Reached target Path Units. alice # [ 5.443753] systemd[1]: Listening on Network Management Netlink Socket. clare # [ 5.465196] systemd[1]: Reached target Remote File Systems. alice # [ 5.446746] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. clare # [ 5.467146] systemd[1]: Reached target Slice Units. clare # [ 5.467640] systemd[1]: Reached target Swaps. alice # [ 5.447533] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 5.449968] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki clare # [ 5.470550] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 5.472669] systemd[1]: Listening on Process Core Dump Socket. alice # [ 5.454748] systemd[1]: Listening on Disk Repartitioning Service Socket. clare # [ 5.475841] systemd[1]: Listening on Credential Encryption/Decryption. alice # [ 5.455285] systemd[1]: Listening on Resolve Monitor Varlink Socket. alice # [ 5.455835] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 5.458432] systemd[1]: Listening on udev Control Socket. clare # [ 5.478208] systemd[1]: Listening on Factory Reset Management. alice # [ 5.458875] systemd[1]: Listening on udev Varlink Socket. clare # [ 5.478778] systemd[1]: Listening on Hostname Service Socket. alice # [ 5.461807] systemd[1]: Mounting Huge Pages File System... clare # [ 5.482340] systemd[1]: Starting Journal Log Access Socket... alice # [ 5.463848] systemd[1]: Mounting POSIX Message Queue File System... clare # [ 5.483635] systemd[1]: Listening on Journal Audit Socket. clare # [ 5.486926] systemd[1]: Listening on Console Output Muting Service Socket. clare # [ 5.487636] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 5.488356] systemd[1]: Listening on Network Management Varlink Socket. clare # [ 5.489044] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 5.475703] systemd[1]: Mounting Kernel Debug File System... clare # [ 5.493334] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. clare # [ 5.494404] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os clare # [ 5.494857] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki clare # [ 5.500421] systemd[1]: Listening on Disk Repartitioning Service Socket. clare # [ 5.500971] systemd[1]: Listening on Resolve Monitor Varlink Socket. alice # [ 5.482179] systemd[1]: Mounting Kernel Trace File System... clare # [ 5.502560] systemd[1]: Listening on Resolve Service Varlink Socket. clare # [ 5.503127] systemd[1]: Listening on udev Control Socket. clare # [ 5.503695] systemd[1]: Listening on udev Varlink Socket. alice # [ 5.486430] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 5.507252] systemd[1]: Mounting Huge Pages File System... alice # [ 5.488982] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 5.510161] systemd[1]: Mounting POSIX Message Queue File System... clare # [ 5.517544] systemd[1]: Mounting Kernel Debug File System... alice # [ 5.500488] systemd[1]: Mounting Kernel Configuration File System... alice # [ 5.501045] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 5.506373] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore alice # [ 5.506847] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 5.530206] systemd[1]: Mounting Kernel Trace File System... clare # [ 5.534579] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 5.537981] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 5.545571] systemd[1]: Mounting Kernel Configuration File System... clare # [ 5.549167] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 5.531365] systemd[1]: Mounting FUSE Control File System... alice # [ 5.531763] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 clare # [ 5.554271] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 5.554756] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 5.569179] systemd[1]: Mounting FUSE Control File System... alice # [ 5.555374] systemd[1]: Starting Journal Service... clare # [ 5.573599] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 alice # [ 5.563498] systemd[1]: Starting Load Kernel Modules... clare # [ 5.595493] systemd[1]: Starting Journal Service... alice # [ 5.576751] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... alice # [ 5.584668] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 5.606206] systemd[1]: Starting Load Kernel Modules... alice # [ 5.585040] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 5.603693] systemd[1]: Starting Coldplug All udev Devices... alice # [ 5.607358] systemd[1]: Listening on Journal Log Access Socket. alice # [ 5.607851] systemd[1]: Mounted Huge Pages File System. alice # [ 5.608217] systemd[1]: Mounted POSIX Message Queue File System. clare # [ 5.631421] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... alice # [ 5.619986] systemd[1]: Mounted Kernel Debug File System. alice # [ 5.619588] systemd[1]: Queued start job for default target Multi-User System. clare # [ 5.645460] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 5.645860] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 5.648701] systemd[1]: Starting Coldplug All udev Devices... alice # [ 5.624608] systemd-journald[282]: Collecting audit messages is enabled. alice # [ 5.629947] systemd[1]: Started Journal Service. clare # [ 5.652430] systemd[1]: Listening on Journal Log Access Socket. clare # [ 5.652836] systemd[1]: Mounted Huge Pages File System. alice # [ 5.628781] systemd[1]: systemd-journald.service: Deactivated successfully. alice # [ 5.629697] systemd-modules-load[283]: Module 'atkbd' is built in alice # [ 5.630418] systemd-modules-load[283]: Module 'loop' is built in alice # [ 5.631095] systemd[1]: Mounted Kernel Trace File System. alice # [ 5.631756] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 5.636466] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 5.639144] systemd[1]: Finished Load Kernel Modules. clare # [ 5.667084] systemd-journald[285]: Collecting audit messages is enabled. clare # [ 5.669409] systemd[1]: Mounted POSIX Message Queue File System. clare # [ 5.669866] systemd[1]: Mounted Kernel Debug File System. clare # [ 5.670266] systemd[1]: Mounted Kernel Trace File System. clare # [ 5.673110] systemd[1]: Queued start job for default target Multi-User System. alice # [ 5.654310] systemd[1]: Mounted Kernel Configuration File System. clare # [ 5.676160] systemd[1]: systemd-journald.service: Deactivated successfully. clare # [ 5.677121] systemd-modules-load[286]: Module 'atkbd' is built in clare # [ 5.677789] systemd-modules-load[286]: Module 'loop' is built in clare # [ 5.691761] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 5.693073] systemd[1]: Started Journal Service. alice # [ 5.675465] systemd[1]: Starting Firewall... clare # [ 5.697552] systemd[1]: Finished Load Kernel Modules. alice # [ 5.678983] systemd[1]: Starting Apply Kernel Variables... clare # [ 5.713861] systemd[1]: Starting Firewall... clare # [ 5.718036] systemd[1]: Starting Apply Kernel Variables... clare # [ 5.725710] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 5.732495] systemd[1]: Mounted Kernel Configuration File System. alice # [ 5.727311] systemd[1]: Mounted FUSE Control File System. clare # [ 5.757304] EXT4-fs (vda): re-mounted 3c03a584-5778-40ca-89c8-804af6ee8a0c. clare # [ 5.758373] systemd-oomd[287]: No swap; memory pressure usage will be degraded alice # [ 5.755496] EXT4-fs (vda): re-mounted 50142622-57b5-4dfa-9490-28a1b3a21de1. clare # [ 5.768955] systemd[1]: Finished Remount Root and Kernel File Systems. alice # [ 5.749835] systemd-oomd[284]: No swap; memory pressure usage will be degraded clare # [ 5.769586] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 5.754132] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. clare # [ 5.778098] systemd[1]: Starting Flush Journal to Persistent Storage... clare # [ 5.778721] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore alice # [ 5.761302] systemd[1]: Finished Remount Root and Kernel File Systems. alice # [ 5.761958] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 5.764631] systemd[1]: Starting Flush Journal to Persistent Storage... alice # [ 5.765264] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore clare # [ 5.785974] systemd[1]: Starting Load/Save OS Random Seed... clare # [ 5.786539] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 5.787285] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 5.771117] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 5.771210] systemd[1]: Detected virtualization qemu. bob # [ 5.771268] systemd[1]: Detected architecture arm64. bob # [ 5.771401] systemd[1]: Detected first boot. alice # [ 5.775284] systemd[1]: Starting Load/Save OS Random Seed... alice # [ 5.775850] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 5.784882] systemd[1]: Initializing machine ID from random generator. clare # [ 5.810833] systemd[1]: Mounted FUSE Control File System. clare # [ 5.852297] systemd-journald[285]: Received client request to flush runtime journal. alice # [ 5.833239] systemd-journald[282]: Received client request to flush runtime journal. alice # [ 5.872350] systemd[1]: Finished Load/Save OS Random Seed. alice # [ 5.873020] systemd[1]: Reached target First Boot Complete. alice # [ 5.873579] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 5.874227] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 5.874806] systemd[1]: Finished Apply Kernel Variables. alice # [ 5.875298] systemd[1]: Starting Network Name Resolution... clare # [ 5.896539] systemd[1]: Finished Load/Save OS Random Seed. clare # [ 5.897155] systemd[1]: Reached target First Boot Complete. clare # [ 5.897675] systemd[1]: Finished Apply Kernel Variables. clare # [ 5.898157] systemd[1]: Starting Network Name Resolution... clare # [ 5.908812] systemd[1]: Finished Flush Journal to Persistent Storage. alice # [ 5.890071] systemd[1]: Finished Flush Journal to Persistent Storage. clare # [ 5.932336] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 5.923756] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 5.937650] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 6.004855] systemd[1]: Applying preset policy. alice # [ 6.017361] systemd-resolved[312]: Positive Trust Anchors: alice # [ 6.017983] systemd-resolved[312]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d alice # [ 6.018871] systemd-resolved[312]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 alice # [ 6.019750] systemd-resolved[312]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test clare # [ 6.046637] systemd-resolved[312]: Positive Trust Anchors: clare # [ 6.047203] systemd-resolved[312]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d clare # [ 6.052311] systemd-resolved[312]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 clare # [ 6.053220] systemd-resolved[312]: 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 # [ 6.105765] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 6.106414] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 6.108376] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 6.166110] systemd[1]: Populated /etc with preset unit settings. clare # [ 6.221728] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 6.222386] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 6.224444] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 6.212404] systemd-resolved[312]: Using system hostname 'alice'. alice # [ 6.215318] systemd[1]: Started Network Name Resolution. clare # [ 6.240244] systemd-resolved[312]: Using system hostname 'clare'. alice # [ 6.223899] systemd[1]: Finished Coldplug All udev Devices. clare # [ 6.248701] systemd[1]: Started Network Name Resolution. alice # [ 6.244329] systemd-udevd[321]: Using default interface naming scheme 'v261'. clare # [ 6.266128] systemd[1]: Finished Coldplug All udev Devices. clare # [ 6.300827] systemd-udevd[326]: Using default interface naming scheme 'v261'. alice # [ 6.296161] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 6.352485] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 6.380272] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 6.380961] systemd[1]: Stopped initrd-switch-root.service. bob # [ 6.384120] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 6.386477] systemd[1]: Created slice Slice /system/getty. bob # [ 6.387505] systemd[1]: Created slice User and Session Slice. bob # [ 6.388114] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 6.388743] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 6.389325] systemd[1]: Expecting device /dev/hvc0... bob # [ 6.392922] systemd[1]: Expecting device /dev/ttyAMA0... bob # [ 6.394430] systemd[1]: Reached target Local Encrypted Volumes. bob # [ 6.394948] systemd[1]: Stopped target initrd-fs.target. bob # [ 6.395513] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 6.396083] systemd[1]: Stopped target initrd-switch-root.target. bob # [ 6.396680] systemd[1]: Reached target Virtual Machines and Containers. bob # [ 6.397269] systemd[1]: Reached target Path Units. bob # [ 6.401088] systemd[1]: Reached target Remote File Systems. alice # [ 6.394514] systemd[1]: Mounting /run/wrappers... bob # [ 6.402669] systemd[1]: Reached target Slice Units. bob # [ 6.403175] systemd[1]: Reached target Swaps. bob # [ 6.405293] systemd[1]: Listening on Query the User Interactively for a Password. bob # [ 6.409325] systemd[1]: Listening on Process Core Dump Socket. bob # [ 6.411454] systemd[1]: Listening on Credential Encryption/Decryption. bob # [ 6.413240] systemd[1]: Listening on Factory Reset Management. alice # [ 6.405083] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 6.415448] systemd[1]: Listening on Hostname Service Socket. bob # [ 6.418643] systemd[1]: Starting Journal Log Access Socket... bob # [ 6.419903] systemd[1]: Listening on Journal Audit Socket. bob # [ 6.423227] systemd[1]: Listening on Console Output Muting Service Socket. bob # [ 6.423913] systemd[1]: Listening on Network Management Metrics Varlink Socket. bob # [ 6.424654] systemd[1]: Listening on Network Management Varlink Socket. bob # [ 6.425360] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 6.429111] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. clare # [ 6.440308] systemd[1]: Mounting /run/wrappers... bob # [ 6.431093] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os bob # [ 6.432248] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki bob # [ 6.437257] systemd[1]: Listening on Disk Repartitioning Service Socket. bob # [ 6.438387] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 6.438953] systemd[1]: Listening on Resolve Service Varlink Socket. bob # [ 6.439523] systemd[1]: Listening on udev Control Socket. bob # [ 6.440097] systemd[1]: Listening on udev Varlink Socket. alice # [ 6.432147] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 6.444485] systemd[1]: Mounting Huge Pages File System... bob # [ 6.446869] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 6.442915] systemd[1]: Mounted /run/wrappers. alice # [ 6.444101] systemd[1]: Reached target Local File Systems. alice # [ 6.447779] systemd[1]: Listening on Boot Loader Control Service Socket. bob # [ 6.457835] systemd[1]: Mounting Kernel Debug File System... alice # [ 6.451864] systemd[1]: Starting register-nix-paths.service... alice # [ 6.456370] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 6.456952] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. clare # [ 6.475770] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 6.465815] systemd[1]: Mounting Kernel Trace File System... bob # [ 6.475986] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 6.476453] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 6.468349] systemd[1]: Starting Save Transient machine-id to Disk... clare # [ 6.488198] systemd[1]: Mounted /run/wrappers. clare # [ 6.489299] systemd[1]: Reached target Local File Systems. clare # [ 6.493163] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 6.498073] systemd[1]: Starting register-nix-paths.service... bob # [ 6.488632] systemd[1]: Mounting Kernel Configuration File System... bob # [ 6.489141] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm clare # [ 6.503253] systemd[1]: Starting Create SUID/SGID Wrappers... bob # [ 6.492318] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore bob # [ 6.493156] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 6.504111] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. clare # [ 6.507467] systemd[1]: Starting Save Transient machine-id to Disk... alice # [ 6.491444] systemd[1]: Starting Create System Files and Directories... clare # [ 6.516081] systemd[1]: Starting Create System Files and Directories... clare # [ 6.516689] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 6.510561] systemd[1]: Mounting FUSE Control File System... bob # [ 6.512884] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 bob # [ 6.537911] systemd[1]: Starting Journal Service... bob # [ 6.551402] systemd[1]: Starting Load Kernel Modules... bob # [ 6.565059] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... bob # [ 6.578993] systemd[1]: Starting Remount Root and Kernel File Systems... bob # [ 6.579383] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 6.597669] systemd[1]: Starting Coldplug All udev Devices... bob # [ 6.599619] systemd[1]: Listening on Journal Log Access Socket. bob # [ 6.600025] systemd[1]: Mounted Huge Pages File System. bob # [ 6.600465] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 6.600824] systemd[1]: Mounted Kernel Debug File System. alice # [ 6.594088] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. bob # [ 6.601166] systemd[1]: Mounted Kernel Trace File System. clare # [ 6.617184] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. alice # [ 6.600539] systemd[1]: Finished Save Transient machine-id to Disk. bob # [ 6.611650] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 6.612213] systemd[1]: Mounted Kernel Configuration File System. clare # [ 6.626605] systemd[1]: Finished Save Transient machine-id to Disk. bob # [ 6.629929] systemd-journald[291]: Collecting audit messages is enabled. bob # [ 6.636564] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 6.636975] systemd[1]: Queued start job for default target Multi-User System. bob # [ 6.647798] systemd[1]: Started Journal Service. alice # [ 6.639393] systemd[1]: Finished Create System Files and Directories. bob # [ 6.642732] systemd[1]: systemd-journald.service: Deactivated successfully. alice # [ 6.645469] systemd[1]: Starting Rebuild Journal Catalog... bob # [ 6.649596] systemd-modules-load[292]: Module 'atkbd' is built in bob # [ 6.652294] systemd-modules-load[292]: Module 'loop' is built in alice # [ 6.652128] systemd[1]: Starting Record System Boot/Shutdown in UTMP... clare # [ 6.682566] systemd[1]: Finished Create System Files and Directories. bob # [ 6.667735] systemd[1]: Finished Load Kernel Modules. bob # [ 6.673963] systemd[1]: Starting Firewall... clare # [ 6.692218] systemd[1]: Starting Rebuild Journal Catalog... bob # [ 6.681673] systemd[1]: Starting Apply Kernel Variables... clare # [ 6.700463] systemd[1]: Starting Record System Boot/Shutdown in UTMP... bob # [ 6.693781] EXT4-fs (vda): re-mounted dceae672-475b-4dbd-a218-398d406d48e0. bob # [ 6.699539] systemd[1]: Mounted FUSE Control File System. bob # [ 6.704988] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 6.706919] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 6.707322] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. bob # [ 6.715078] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 6.715713] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 6.740373] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 6.740970] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 6.750543] systemd-oomd[293]: No swap; memory pressure usage will be degraded alice # [ 6.754000] systemd[1]: Finished Record System Boot/Shutdown in UTMP. bob # [ 6.763492] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. alice # [ 6.789822] systemd[1]: Finished Rebuild Journal Catalog. clare # [ 6.810416] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 6.812602] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. alice # [ 6.796577] systemd[1]: Starting Update is Completed... bob # [ 6.806022] systemd-journald[291]: Received client request to flush runtime journal. alice # [ 6.814242] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. clare # [ 6.844967] systemd[1]: Finished Rebuild Journal Catalog. clare # [ 6.851398] systemd[1]: Starting Update is Completed... clare # [ 6.870792] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. alice # [ 6.860794] systemd[1]: Finished Update is Completed. bob # [ 6.890645] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 6.891498] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 6.916466] systemd[1]: Finished Update is Completed. bob # [ 6.900309] systemd[1]: Finished Load/Save OS Random Seed. bob # [ 6.900856] systemd[1]: Reached target First Boot Complete. bob # [ 6.901382] systemd[1]: Finished Apply Kernel Variables. bob # [ 6.901861] systemd[1]: Starting Network Name Resolution... bob # [ 6.902349] systemd[1]: Finished Flush Journal to Persistent Storage. alice # [ 7.050030] (udev-worker)[346]: Network interface NamePolicy= disabled on kernel command line. alice # [ 7.055187] (udev-worker)[350]: Network interface NamePolicy= disabled on kernel command line. bob # [ 7.065135] systemd-resolved[321]: Positive Trust Anchors: bob # [ 7.065732] systemd-resolved[321]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d bob # [ 7.066574] systemd-resolved[321]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 bob # [ 7.067453] systemd-resolved[321]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test clare # [ 7.098986] (udev-worker)[346]: Network interface NamePolicy= disabled on kernel command line. clare # [ 7.102677] (udev-worker)[347]: Network interface NamePolicy= disabled on kernel command line. bob # [ 7.122283] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 7.122945] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 7.125082] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 7.224244] systemd[1]: Finished Coldplug All udev Devices. bob # [ 7.234268] systemd-resolved[321]: Using system hostname 'bob'. bob # [ 7.238239] systemd[1]: Started Network Name Resolution. bob # [ 7.251289] systemd-udevd[330]: Using default interface naming scheme 'v261'. alice # [ 7.302625] mousedev: PS/2 mouse device common for all mice bob # [ 7.304461] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 7.367590] mousedev: PS/2 mouse device common for all mice alice # [ 7.361507] systemd[1]: Finished register-nix-paths.service. alice # [ 7.369936] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. alice # [ 7.372695] systemd[1]: Finished Create SUID/SGID Wrappers. alice # [ 7.373271] systemd[1]: Reached target System Initialization. alice # [ 7.373817] systemd[1]: Started Discard unused filesystem blocks once a week. alice # [ 7.374433] systemd[1]: Started Daily Cleanup of Temporary Directories. alice # [ 7.375013] systemd[1]: Reached target Timer Units. bob # [ 7.377441] systemd[1]: Mounting /run/wrappers... alice # [ 7.383532] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 7.384686] systemd[1]: Listening on Nix Daemon Socket. alice # [ 7.387700] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 7.392803] systemd[1]: Reached target Socket Units. alice # [ 7.393312] systemd[1]: Reached target Basic System. alice # [ 7.393804] systemd[1]: Started backdoor.service. alice # [ 7.394268] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 7.394899] systemd[1]: Starting Name Service Cache Daemon (nsncd)... clare # [ 7.422162] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. clare # [ 7.425072] systemd[1]: Finished Create SUID/SGID Wrappers. alice # [ 7.407048] systemd[1]: Starting Post-Boot Actions... clare # [ 7.426852] systemd[1]: Finished register-nix-paths.service. clare # [ 7.427407] systemd[1]: Reached target System Initialization. clare # [ 7.427937] systemd[1]: Started Discard unused filesystem blocks once a week. clare # [ 7.433464] systemd[1]: Started Daily Cleanup of Temporary Directories. clare # [ 7.434673] systemd[1]: Reached target Timer Units. clare # [ 7.435134] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 7.441940] systemd[1]: Listening on harmonia binary cache socket. clare # [ 7.442546] systemd[1]: Listening on Nix Daemon Socket. clare # [ 7.443034] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. clare # [ 7.443746] systemd[1]: Reached target Socket Units. alice # [ 7.424441] systemd[1]: Started Reset console on configuration changes. bob # [ 7.426682] systemd[1]: Mounted /run/wrappers. bob # [ 7.427535] systemd[1]: Reached target Local File Systems. clare # [ 7.448694] systemd[1]: Reached target Basic System. clare # [ 7.449192] systemd[1]: Started backdoor.service. bob # [ 7.432195] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 7.452332] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 7.435298] systemd[1]: Starting register-nix-paths.service... clare # [ 7.457320] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 7.439357] systemd[1]: Starting Create SUID/SGID Wrappers... bob # [ 7.439917] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. bob # [ 7.444813] systemd[1]: Starting Save Transient machine-id to Disk... clare # [ 7.473940] systemd[1]: Starting Post-Boot Actions... alice # [ 7.459775] systemd[1]: Starting D-Bus System Message Bus... bob # [ 7.463971] systemd[1]: Starting Create System Files and Directories... bob # [ 7.465960] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 7.477516] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 7.501512] systemd[1]: Started Reset console on configuration changes. clare # [ 7.536120] systemd[1]: Starting D-Bus System Message Bus... alice # [ 7.531471] systemd[1]: Finished Post-Boot Actions. alice # connecting to host... bob # [ 7.550460] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. bob # [ 7.555328] systemd[1]: Finished Save Transient machine-id to Disk. alice # [ 7.558679] systemd[1]: Started Name Service Cache Daemon (nsncd). alice # [ 7.561371] systemd[1]: Reached target Host and Network Name Lookups. alice # [ 7.561998] systemd[1]: Reached target User and Group Name Lookups. alice # [ 7.571058] nsncd[473]: Oct 07 05:20:55.387 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # [ 7.598445] systemd[1]: Started Name Service Cache Daemon (nsncd). alice # [ 7.581515] systemd[1]: Starting User Login Management... clare # [ 7.601695] nsncd[476]: Oct 07 05:20:55.438 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # [ 7.610871] systemd[1]: Reached target Host and Network Name Lookups. clare # [ 7.611559] systemd[1]: Reached target User and Group Name Lookups. clare # connecting to host... clare # [ 7.624538] systemd[1]: Starting User Login Management... clare # [ 7.626918] systemd[1]: Finished Post-Boot Actions. alice # [ 7.615775] systemd[1]: Finished Import lastlog data into lastlog2 database. bob # [ 7.638745] systemd[1]: Finished Create System Files and Directories. clare # [ 7.662400] systemd[1]: Finished Import lastlog data into lastlog2 database. bob # [ 7.646293] systemd[1]: Starting Rebuild Journal Catalog... bob # [ 7.652339] systemd[1]: Starting Record System Boot/Shutdown in UTMP... alice # [ 7.692596] dbus-broker-launch[479]: Looking up NSS user entry for 'systemd-timesync'... alice # [ 7.706976] dbus-broker-launch[479]: NSS returned no entry for 'systemd-timesync' alice # [ 7.707683] dbus-broker-launch[479]: Invalid user-name in /nix/store/1hq4agwd3q80lb722fxb35wmyzlnrk6q-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 7.718013] systemd-logind[498]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) alice # [ 7.720928] systemd-logind[498]: Watching system buttons on /dev/input/event0 (gpio-keys) alice # [ 7.723910] systemd-logind[498]: New seat seat0. alice # [ 7.732693] systemd[1]: Started User Login Management. alice # [ 7.733200] systemd[1]: Starting linger-users.service... clare # [ 7.755293] dbus-broker-launch[482]: Looking up NSS user entry for 'systemd-timesync'... clare # [ 7.760454] systemd-logind[504]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 7.748102] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 7.764326] systemd-logind[504]: Watching system buttons on /dev/input/event0 (gpio-keys) clare # [ 7.767233] systemd-logind[504]: New seat seat0. clare # [ 7.771466] systemd[1]: Started User Login Management. alice # [ 7.752077] systemd[1]: Started D-Bus System Message Bus. clare # [ 7.773800] systemd[1]: Starting linger-users.service... clare # [ 7.776476] dbus-broker-launch[482]: NSS returned no entry for 'systemd-timesync' clare # [ 7.780649] dbus-broker-launch[482]: Invalid user-name in /nix/store/16ww7sz86177drhs17kx18vlbhkmib53-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 7.784399] dbus-broker-launch[479]: Ready bob # [ 7.786523] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. clare # [ 7.807080] systemd[1]: Started D-Bus System Message Bus. bob # [ 7.792445] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 7.796603] systemd[1]: Starting Update is Completed... alice # [ 7.800319] systemd[1]: linger-users.service: Deactivated successfully. alice # [ 7.803460] systemd[1]: Finished linger-users.service. clare # [ 7.842204] systemd[1]: linger-users.service: Deactivated successfully. clare # [ 7.845292] systemd[1]: Finished linger-users.service. clare # [ 7.847143] dbus-broker-launch[482]: Ready bob # [ 7.851409] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. alice # [ 7.874784] systemd[1]: Finished Firewall. alice # [ 7.876559] systemd[1]: Reached target Preparation for Network. alice # [ 7.879844] systemd[1]: Listening on Network Management Resolve Hook Socket. alice # [ 7.883431] systemd[1]: Starting Network Management... bob # [ 7.900250] systemd[1]: Finished Update is Completed. clare # [ 7.928354] systemd[1]: Finished Firewall. clare # [ 7.929807] systemd[1]: Reached target Preparation for Network. clare # [ 7.933122] systemd[1]: Listening on Network Management Resolve Hook Socket. clare # [ 7.936957] systemd[1]: Starting Network Management... alice # [ 8.067114] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 8.083878] (udev-worker)[354]: Network interface NamePolicy= disabled on kernel command line. clare # [ 8.108605] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 8.087551] (udev-worker)[360]: Network interface NamePolicy= disabled on kernel command line. alice # [ 8.112436] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' alice # [ 8.112951] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' alice # [ 8.115359] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 alice # [ 8.115744] cfg80211: failed to load regulatory.db clare # [ 8.141375] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' clare # [ 8.141911] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' clare # [ 8.143242] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 clare # [ 8.143623] cfg80211: failed to load regulatory.db alice # [ 8.136807] systemd-networkd[538]: lo: Link UP alice # [ 8.138276] systemd-networkd[538]: lo: Gained carrier clare # [ 8.162966] systemd-networkd[544]: lo: Link UP clare # [ 8.164733] systemd-networkd[544]: lo: Gained carrier alice # [ 8.147694] systemd[1]: Started Network Management. alice # [ 8.148247] systemd[1]: Reached target Network. alice # [ 8.148652] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 8.171344] systemd[1]: Started Network Management. alice # [ 8.153793] systemd[1]: Starting Permit User Sessions... clare # [ 8.175588] systemd-networkd[544]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. alice # [ 8.156574] systemd-networkd[538]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. clare # [ 8.179023] systemd[1]: Reached target Network. clare # [ 8.181507] systemd-networkd[544]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 8.182826] systemd-networkd[544]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 8.163367] systemd-networkd[538]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 8.166998] systemd-networkd[538]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 8.167725] systemd-networkd[538]: eth0: Link UP clare # [ 8.189913] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 8.190576] systemd-networkd[544]: eth0: Link UP clare # [ 8.190986] systemd-networkd[544]: eth0: Gained carrier clare # [ 8.191424] systemd[1]: Starting Permit User Sessions... alice # [ 8.174525] systemd-networkd[538]: eth0: Gained carrier alice # [ 8.175012] systemd-networkd[538]: eth1: Link UP alice # [ 8.175412] systemd-networkd[538]: eth1: Gained carrier alice # [ 8.175872] systemd-networkd[538]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 8.199130] systemd-networkd[544]: eth1: Link UP clare # [ 8.199597] systemd-networkd[544]: eth1: Gained carrier clare # [ 8.202418] systemd-networkd[544]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 8.188732] systemd-networkd[538]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 clare # [ 8.210183] systemd-networkd[544]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 alice # [ 8.226127] systemd[1]: Finished Permit User Sessions. alice # [ 8.234469] systemd[1]: Started Getty on tty1. alice # [ 8.234999] systemd[1]: Reached target Login Prompts. alice # [ 8.235458] systemd[1]: Reached target Multi-User System. clare # [ 8.256497] systemd[1]: Finished Permit User Sessions. clare # [ 8.262205] systemd[1]: Started Getty on tty1. clare # [ 8.264285] systemd[1]: Reached target Login Prompts. alice # [ 8.246553] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 8.266277] systemd[1]: Reached target Multi-User System. alice # [ 8.248672] systemd[1]: Startup finished in 723ms (kernel) + 3.586s (initrd) + 3.936s (userspace) = 8.247s. clare # [ 8.271002] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 8.281255] systemd[1]: Startup finished in 736ms (kernel) + 3.592s (initrd) + 3.951s (userspace) = 8.280s. bob # [ 8.285267] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. bob # [ 8.285985] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 8.298433] systemd[1]: Finished register-nix-paths.service. bob # [ 8.300510] systemd[1]: Reached target System Initialization. bob # [ 8.301099] systemd[1]: Started Discard unused filesystem blocks once a week. bob # [ 8.301719] systemd[1]: Started Daily Cleanup of Temporary Directories. bob # [ 8.302295] systemd[1]: Reached target Timer Units. bob # [ 8.308799] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 8.309420] systemd[1]: Listening on Nix Daemon Socket. bob # [ 8.309909] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. bob # [ 8.310645] systemd[1]: Reached target Socket Units. bob # [ 8.311115] systemd[1]: Reached target Basic System. bob # [ 8.311565] systemd[1]: Started backdoor.service. bob # [ 8.318983] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 8.319623] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 8.335319] mousedev: PS/2 mouse device common for all mice bob # [ 8.336354] systemd[1]: Starting Post-Boot Actions... bob # [ 8.348655] systemd[1]: Started Reset console on configuration changes. bob # [ 8.386759] systemd[1]: Starting D-Bus System Message Bus... alice # [ 8.400827] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 8.437527] systemd[1]: Finished Post-Boot Actions. clare # [ 8.475464] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # connecting to host... bob # [ 8.486774] systemd[1]: Started Name Service Cache Daemon (nsncd). bob # [ 8.487398] systemd[1]: Reached target Host and Network Name Lookups. bob # [ 8.487932] systemd[1]: Reached target User and Group Name Lookups. bob # [ 8.493486] nsncd[476]: Oct 07 05:20:56.317 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" bob # [ 8.508185] systemd[1]: Starting User Login Management... bob: Guest shell says: b'Spawning backdoor root shell...\n' bob # [ 8.540498] systemd[1]: Finished Import lastlog data into lastlog2 database. bob: connected to guest root shell bob: (connecting took 8.89 seconds) bob: (finished: waiting for the VM to finish booting, in 8.89 seconds) bob # [ 8.619328] dbus-broker-launch[483]: Looking up NSS user entry for 'systemd-timesync'... bob # [ 8.627815] systemd-logind[503]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 8.634888] systemd-logind[503]: Watching system buttons on /dev/input/event0 (gpio-keys) bob # [ 8.637204] systemd-logind[503]: New seat seat0. bob # [ 8.641987] systemd[1]: Started User Login Management. bob # [ 8.646968] systemd[1]: Starting linger-users.service... bob # [ 8.647956] dbus-broker-launch[483]: NSS returned no entry for 'systemd-timesync' bob # [ 8.656731] dbus-broker-launch[483]: Invalid user-name in /nix/store/c19cl6mgva5ipipijba7s905fspc9svi-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" bob # [ 8.682303] systemd[1]: Started D-Bus System Message Bus. alice # [ 8.691692] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. alice # [ 8.693943] systemd[1]: Starting Virtual Console Setup... alice # [ 8.726031] systemd-logind[498]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 8.731126] dbus-broker-launch[483]: Ready bob # [ 8.734889] systemd[1]: linger-users.service: Deactivated successfully. bob # [ 8.736924] systemd[1]: Finished linger-users.service. clare # [ 8.776396] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. clare # [ 8.783665] systemd[1]: Starting Virtual Console Setup... clare # [ 8.809503] systemd-logind[504]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) alice # [ 8.798802] systemd-vconsole-setup[556]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 8.801913] systemd[1]: Finished Virtual Console Setup. clare # [ 8.889799] systemd-vconsole-setup[562]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 8.892436] systemd[1]: Finished Virtual Console Setup. bob # [ 8.893957] systemd[1]: Finished Firewall. bob # [ 8.895514] systemd[1]: Reached target Preparation for Network. bob # [ 8.899159] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # [ 8.902723] systemd[1]: Starting Network Management... bob # [ 9.072819] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 9.119262] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' bob # [ 9.119786] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 9.120827] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 bob # [ 9.121202] cfg80211: failed to load regulatory.db bob # [ 9.141492] systemd-networkd[558]: lo: Link UP bob # [ 9.143198] systemd-networkd[558]: lo: Gained carrier bob # [ 9.151590] systemd[1]: Started Network Management. bob # [ 9.153584] systemd[1]: Reached target Network. bob # [ 9.154021] systemd[1]: Reached target Network is Online. bob # [ 9.154467] systemd[1]: Starting ncps binary cache proxy service... bob # [ 9.160596] systemd-networkd[558]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 9.161915] systemd-networkd[558]: eth1: Configuring with /etc/systemd/network/40-eth1.network. bob # [ 9.162615] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... bob # [ 9.175298] systemd-networkd[558]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. bob # [ 9.177309] systemd-networkd[558]: eth1: Link UP bob # [ 9.177745] systemd-networkd[558]: eth1: Gained carrier bob # [ 9.178175] systemd-networkd[558]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 9.179468] systemd-networkd[558]: eth0: Link UP bob # [ 9.179866] systemd-networkd[558]: eth0: Gained carrier bob # [ 9.189277] systemd-networkd[558]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 9.192278] systemd[1]: Starting Permit User Sessions... clare # [ 9.250519] systemd-networkd[544]: eth1: Gained IPv6LL bob # [ 9.264218] systemd[1]: Finished Permit User Sessions. bob # [ 9.274112] systemd[1]: Started Getty on tty1. bob # [ 9.283155] systemd[1]: Reached target Login Prompts. bob # [ 9.283664] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. bob # [ 9.305722] ncps-pre-start[565]: /nix/store/z27dji5sjdkkdpzjlm37nq6d5cwbgl8i-ncps-0.9.4/bin/dbmate-ncps up alice # [ 9.409509] systemd-networkd[538]: eth1: Gained IPv6LL clare # [ 9.442168] systemd-networkd[544]: eth0: Gained IPv6LL alice # [ 9.474747] systemd-networkd[538]: eth0: Gained IPv6LL bob # [ 9.519676] ncps-pre-start[580]: Creating: /var/lib/ncps/db/db.sqlite bob # [ 9.557411] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 9.567909] ncps-pre-start[580]: Applying: 20241210054814_create-narinfos-table.sql bob # [ 9.581932] ncps-pre-start[580]: Applied: 20241210054814_create-narinfos-table.sql in 14.00044ms bob # [ 9.582927] ncps-pre-start[580]: Applying: 20241210054829_create-nars-table.sql bob # [ 9.592232] ncps-pre-start[580]: Applied: 20241210054829_create-nars-table.sql in 10.2818ms bob # [ 9.593166] ncps-pre-start[580]: Applying: 20241213014846_add-query-to-nars-table.sql bob # [ 9.601121] ncps-pre-start[580]: Applied: 20241213014846_add-query-to-nars-table.sql in 8.877ms bob # [ 9.602480] ncps-pre-start[580]: Applying: 20251230224159_add-cascade-to-nars-fk.sql bob # [ 9.610630] ncps-pre-start[580]: Applied: 20251230224159_add-cascade-to-nars-fk.sql in 9.49664ms bob # [ 9.612145] ncps-pre-start[580]: Applying: 20260101000000_add-config-table.sql bob # [ 9.624192] ncps-pre-start[580]: Applied: 20260101000000_add-config-table.sql in 13.54808ms bob # [ 9.625155] ncps-pre-start[580]: Applying: 20260105025735_remove-redundant-indexes.sql bob # [ 9.636209] ncps-pre-start[580]: Applied: 20260105025735_remove-redundant-indexes.sql in 12.00072ms bob # [ 9.637160] ncps-pre-start[580]: Applying: 20260105030513_support_shared_nars_between_narinfos.sql bob # [ 9.646711] ncps-pre-start[580]: Applied: 20260105030513_support_shared_nars_between_narinfos.sql in 10.49316ms bob # [ 9.648866] ncps-pre-start[580]: Applying: 20260117195000_add_narinfo_de_normalized.sql bob # [ 9.657712] ncps-pre-start[580]: Applied: 20260117195000_add_narinfo_de_normalized.sql in 11.04792ms bob # [ 9.658553] ncps-pre-start[580]: Applying: 20260127223000_allow_multiple_nar_representations.sql bob # [ 9.668191] ncps-pre-start[580]: Applied: 20260127223000_allow_multiple_nar_representations.sql in 8.71476ms bob # [ 9.670467] ncps-pre-start[580]: Applying: 20260131021850_add_chunks.sql bob # [ 9.684202] ncps-pre-start[580]: Applied: 20260131021850_add_chunks.sql in 15.98416ms bob # [ 9.685085] ncps-pre-start[580]: Applying: 20260205063651_add_total_chunks_to_nar_files.sql bob # [ 9.695611] ncps-pre-start[580]: Applied: 20260205063651_add_total_chunks_to_nar_files.sql in 11.39804ms bob # [ 9.696693] ncps-pre-start[580]: Applying: 20260217071237_add_chunking_started_at_to_nar_files.sql bob # [ 9.710242] ncps-pre-start[580]: Applied: 20260217071237_add_chunking_started_at_to_nar_files.sql in 14.62488ms bob # [ 9.712285] ncps-pre-start[580]: Applying: 20260301000000_add_verified_at_to_nar_files.sql bob # [ 9.717902] ncps-pre-start[580]: Applied: 20260301000000_add_verified_at_to_nar_files.sql in 7.32104ms bob # [ 9.864290] systemd[1]: Started ncps binary cache proxy service. bob # [ 9.868811] systemd[1]: Reached target Multi-User System. bob # [ 9.869732] systemd[1]: Startup finished in 719ms (kernel) + 4.581s (initrd) + 4.565s (userspace) = 9.865s. bob: (finished: waiting for unit ncps, in 10.18 seconds) bob: must succeed: systemctl status ncps bob: (finished: must succeed: systemctl status ncps, in 0.07 seconds) clare: waiting for unit harmonia.socket clare: waiting for the VM to finish booting clare: Guest shell says: b'Spawning backdoor root shell...\n' clare: connected to guest root shell clare: (connecting took 0.00 seconds) clare: (finished: waiting for the VM to finish booting, in 0.00 seconds) clare: (finished: waiting for unit harmonia.socket, in 0.05 seconds) alice: waiting for success: curl bob:8502/nix-cache-info alice: waiting for the VM to finish booting alice: Guest shell says: b'Spawning backdoor root shell...\n' alice: connected to guest root shell alice: (connecting took 0.00 seconds) alice: (finished: waiting for the VM to finish booting, in 0.00 seconds) bob # [ 10.035140] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 10.044180] systemd[1]: Starting Virtual Console Setup... bob # [ 10.067941] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 10.071778] systemd[1]: Stopped Virtual Console Setup. bob # [ 10.075667] systemd[1]: Starting Virtual Console Setup... bob # [ 10.079253] systemd-logind[503]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 10.202530] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","log_level":"info","time":"2026-10-07T05:20:58Z","message":"logger created"} bob # [ 10.204076] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","xz-binary-path":"/nix/store/csrwf31hprwghnbyiglxa3p787lgrl67-xz-5.8.4-bin/bin/xz","time":"2026-10-07T05:20:58Z","message":"Using xz binary for xz decompression"} bob # [ 10.218371] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-10-07T05:20:58Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"} bob # [ 10.225212] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","time":"2026-10-07T05:20:58Z","message":"using local locks (single-instance mode)"} bob # [ 10.231533] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-07T05:20:58Z","message":"setting up tracer provider to discard traces"} bob # [ 10.235127] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-07T05:20:58Z","message":"setting up meter provider to discard metrics"} bob # [ 10.239838] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-07T05:20:58Z","message":"setting up logger provider to discard logs"} bob # [ 10.243413] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-10-07T05:20:58Z","message":"Reporting anonymous metrics to the project maintainers"} bob # [ 10.246679] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-10-07T05:20:58Z","message":"failed to parse netrc file, proceeding without netrc authentication"} bob # [ 10.249392] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-10-07T05:20:58Z","message":"using local storage"} bob # [ 10.272828] systemd-networkd[558]: eth1: Gained IPv6LL clare # [ 10.296928] systemd[1]: Starting harmonia binary cache service... bob # [ 10.302348] systemd-vconsole-setup[605]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 10.304834] systemd[1]: Finished Virtual Console Setup. clare # [ 10.436209] harmonia-cache[569]: 2026-10-07T05:20:58.272675Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000)) clare # [ 10.441656] harmonia-cache[569]: 2026-10-07T05:20:58.276949Z INFO actix_server::builder: starting 4 workers clare # [ 10.451403] harmonia-cache[569]: 2026-10-07T05:20:58.281810Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s clare # [ 10.455171] harmonia-cache[569]: 2026-10-07T05:20:58.281891Z INFO actix_server::server: Actix runtime found; starting in Actix runtime clare # [ 10.458910] harmonia-cache[569]: 2026-10-07T05:20:58.281997Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:5000 clare # [ 10.463395] systemd[1]: Started harmonia binary cache service. bob # [ 10.469165] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-10-07T05:20:58Z","message":"upstream became healthy and is now available for requests"} bob # [ 10.475527] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-10-07T05:20:58Z","message":"Server started"} bob # [ 10.688945] systemd-networkd[558]: eth0: Gained IPv6LL alice # % Total % Received % Xferd Average Speed Time Time Time Current alice # Dload Upload Total Spent Left Speed bob # [ 11.141697] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:50256","trace_id":"67f3a8ee8d155b2a2dd8c9515b2f24dc","span_id":"6434b2450d3ed0ee","status":200,"elapsed":0.06868,"bytes":50,"time":"2026-10-07T05:20:58Z","message":"handled request"} alice # 0 0 0 0 0 0 0 0 00:01 0100 50 100 50 0 0 47 0 00:01 00:01 0100 50 100 50 0 0 46 0 00:01 00:01 0100 50 100 50 0 0 46 0 00:01 00:01 0 alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 1.14 seconds) bob: waiting for success: curl clare:5000/nix-cache-info bob # % Total % Received % Xferd Average Speed Time Time Time Current bob # Dload Upload T[ 11.270053] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: 2026/10/07 05:20:59 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host bob # otal Spent Left Speed bob # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1814 0 0100 51 100 51 0 0 1682 0 0100 51 100 51 0 0 1565 0 0 bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.11 seconds) clare: waiting for unit harmonia clare: (finished: waiting for unit harmonia, in 0.07 seconds) clare: must succeed: systemctl status harmonia clare: (finished: must succeed: systemctl status harmonia, in 0.03 seconds) clare: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 clare # [ 11.785768] systemd-resolved[312]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. clare # [ 12.360817] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 12.364782] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 13.624719] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 13.628677] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 16.056908] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 16.060432] systemd-resolved[312]: 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.77 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 # [ 16.263671] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:36090","trace_id":"cf950d5130e4b46a2f53c108413dacd5","span_id":"c274164cf77d473c","status":200,"elapsed":0.03032,"bytes":50,"time":"2026-10-07T05:21:04Z","message":"handled request"} alice # [ 16.555026] systemd-resolved[312]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. alice # [ 16.559486] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 16.563442] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 17.172332] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 17.176423] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 18.361471] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 18.365432] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 20.852299] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 20.855598] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 20.864984] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:36090","trace_id":"eadc1e028167a3647cd6177c6c22e68f","span_id":"3318ab9adea05b29","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-10-07T05:21:08Z","message":"download the narinfo from upstream"} bob # [ 20.896190] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:36090","trace_id":"eadc1e028167a3647cd6177c6c22e68f","span_id":"3318ab9adea05b29","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-07T05:21:08Z","message":"downloading the nar from upstream"} bob # [ 20.907538] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:36090","trace_id":"eadc1e028167a3647cd6177c6c22e68f","span_id":"3318ab9adea05b29","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-10-07T05:21:08Z","message":"download the nar from upstream"} bob # [ 20.936211] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:36090","trace_id":"eadc1e028167a3647cd6177c6c22e68f","span_id":"3318ab9adea05b29","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":37.96608,"time":"2026-10-07T05:21:08Z","message":"download of nar complete"} bob # [ 20.960081] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:36090","trace_id":"eadc1e028167a3647cd6177c6c22e68f","span_id":"3318ab9adea05b29","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-07T05:21:08Z","message":"storing narinfo and nar_file record in the database"} bob # [ 20.968480] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:36090","trace_id":"eadc1e028167a3647cd6177c6c22e68f","span_id":"3318ab9adea05b29","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":93.63072,"time":"2026-10-07T05:21:08Z","message":"download of narinfo complete"} bob # [ 20.977248] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:36090","trace_id":"eadc1e028167a3647cd6177c6c22e68f","span_id":"3318ab9adea05b29","status":200,"elapsed":98.07848,"bytes":532,"time":"2026-10-07T05:21:08Z","message":"handled request"} bob # [ 20.981782] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:36090","trace_id":"637deb986db71f4e829d8aeaee5dad6e","span_id":"8e39b75f3a83b29e","status":200,"elapsed":0.54216,"bytes":95,"time":"2026-10-07T05:21:08Z","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.85 seconds) (finished: run the VM test script, in 21.79 seconds) test script finished in 21.93s cleanup kill QemuMachine (pid 48) alice # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) alice # [2026-10-07T05:21:09Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-10-07T05:21:09Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-10-07T05:21:09Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 49) bob # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) bob # [2026-10-07T05:21:09Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-10-07T05:21:09Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-10-07T05:21:09Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 50) clare # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) clare # [2026-10-07T05:21:09Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-10-07T05:21:09Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-10-07T05:21:09Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.43 seconds)