build01: building jygi54v3s32ngmjczj5969sjrxbh6hhd-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 bob # Disk image does not exist, creating the virtualisation disk image... alice # Disk image does not exist, creating the virtualisation disk image... alice: QEMU running (pid 48) clare # Disk image does not exist, creating the virtualisation disk image... clare: QEMU running (pid 50) bob # Formatting '/build/vm-state-bob/tmp.NolgoCKY9K', fmt=raw size=1073741824 clare # Formatting '/build/vm-state-clare/tmp.c6lu4yo0nN', fmt=raw size=1073741824 bob: QEMU running (pid 49) clare # mke2fs 1.47.4 (6-Mar-2025) alice # Formatting '/build/vm-state-alice/tmp.JfEHsdc4a9', fmt=raw size=1073741824 clare # Discarding device blocks: 0/262144 done bob # mke2fs 1.47.4 (6-Mar-2025) clare # Creating filesystem with 262144 4k blocks and 65536 inodes (finished: start all VMs, in 0.47 seconds) alice # mke2fs 1.47.4 (6-Mar-2025) clare # Filesystem UUID: 1f7ec755-49cf-45bb-b9f7-48001bdbae15 bob # Discarding device blocks: 0/262144 done bob: waiting for unit ncps alice # Discarding device blocks: 0/262144 done bob # Creating filesystem with 262144 4k blocks and 65536 inodes clare # Superblock backups stored on blocks: bob # Filesystem UUID: afcb5a73-ee4d-4d2a-b291-be171a5f7d71 clare # 32768, 98304, 163840, 229376 bob # Superblock backups stored on blocks: clare # bob # 32768, 98304, 163840, 229376 clare # Allocating group tables: 0/8 done bob # clare # Writing inode tables: 0/8 done bob # Allocating group tables: 0/8 done clare # Creating journal (8192 blocks): done bob # Writing inode tables: 0/8 done clare # Writing superblocks and filesystem accounting information: 0/8 done bob # Creating journal (8192 blocks): done clare # bob # Writing superblocks and filesystem accounting information: 0/8 done clare # Virtualisation disk image created. bob # clare # Starting virtiofs daemons... bob # Virtualisation disk image created. bob: waiting for the VM to finish booting alice # Creating filesystem with 262144 4k blocks and 65536 inodes bob # Starting virtiofs daemons... alice # Filesystem UUID: 1d3ce35a-71b9-45f8-b73f-e763415b48b4 clare # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # Superblock backups stored on blocks: bob # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # 32768, 98304, 163840, 229376 bob # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # clare # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # Allocating group tables: 0/8 done bob # [2026-10-15T15:15:58Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # Writing inode tables: 0/8 done clare # [2026-10-15T15:15:58Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # Creating journal (8192 blocks): done bob # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # Writing superblocks and filesystem accounting information: 0/8 done clare # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # bob # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # Virtualisation disk image created. bob # [2026-10-15T15:15:58Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # Starting virtiofs daemons... clare # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-10-15T15:15:58Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-15T15:15:58Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-10-15T15:15:58Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-15T15:15:58Z INFO virtiofsd] Client connected, servicing requests alice # [2026-10-15T15:15:58Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-15T15:15:58Z INFO virtiofsd] Client connected, servicing requests alice # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-15T15:15:58Z INFO virtiofsd] Client connected, servicing requests alice # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-15T15:15:58Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-15T15:15:58Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-15T15:15:58Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-15T15:15:58Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-10-15T15:15:58Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-15T15:15:58Z INFO virtiofsd] Client connected, servicing requests alice # [2026-10-15T15:15:58Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-15T15:15:58Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-15T15:15:58Z INFO virtiofsd] Client connected, servicing requests bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] bob # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172 bob # [ 0.000000] KASLR enabled bob # [ 0.000000] random: crng init done bob # [ 0.000000] Machine model: linux,dummy-virt bob # [ 0.000000] efi: UEFI not found. bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] bob # [ 0.000000] Zone ranges: bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] DMA32 empty bob # [ 0.000000] Normal empty bob # [ 0.000000] Device empty bob # [ 0.000000] Movable zone start for each node bob # [ 0.000000] Early memory node ranges bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 bob # [ 0.000000] psci: probing for conduit method from DT. bob # [ 0.000000] psci: PSCIv1.3 detected in firmware. bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs bob # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] bob # [ 0.000000] psci: SMC Calling Convention v1.1 bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172 alice # [ 0.000000] KASLR enabled bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296 alice # [ 0.000000] random: crng init done bob # [ 0.000000] Detected PIPT I-cache on CPU0 alice # [ 0.000000] Machine model: linux,dummy-virt alice # [ 0.000000] efi: UEFI not found. bob # [ 0.000000] CPU features: detected: GICv3 CPU interface bob # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT bob # [ 0.000000] CPU features: detected: Spectre-BHB alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] alice # [ 0.000000] Zone ranges: bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) bob # [ 0.000000] CPU features: detected: ARM erratum 1418040 alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] DMA32 empty bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion alice # [ 0.000000] Normal empty alice # [ 0.000000] Device empty bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing alice # [ 0.000000] Movable zone start for each node alice # [ 0.000000] Early memory node ranges bob # [ 0.000000] alternatives: applying boot alternatives alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000000] psci: probing for conduit method from DT. alice # [ 0.000000] psci: PSCIv1.3 detected in firmware. 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/a2nyxfw3nc3jfy7lcza1q7ykcvv1dxrq-nixos-system-bob-test/init regInfo=/nix/.ro-store/8cr38alaib46ns4r2vgp6is7labskfsa-closure-info/registration console=ttyAMA0,115200n8 console=tty0 alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs alice # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] psci: SMC Calling Convention v1.1 bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/8cr38alaib46ns4r2vgp6is7labskfsa-closure-info/registration", will be passed to user space. alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296 bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) alice # [ 0.000000] Detected PIPT I-cache on CPU0 alice # [ 0.000000] CPU features: detected: GICv3 CPU interface bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.000000] CPU features: detected: Spectre-v4 bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB alice # [ 0.000000] CPU features: detected: Spectre-BHB bob # [ 0.000000] software IO TLB: area num 1. alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) bob # [ 0.000000] Fallback order for Node 0: 0 alice # [ 0.000000] CPU features: detected: ARM erratum 1418040 bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 bob # [ 0.000000] Policy zone: DMA alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing alice # [ 0.000000] alternatives: applying boot alternatives bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 bob # [ 0.000000] allocated 2097152 bytes of page_ext bob # [ 0.000000] ftrace: allocating 75049 entries in 294 pages bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups bob # [ 0.000000] rcu: Hierarchical RCU implementation. bob # [ 0.000000] rcu: RCU event tracing is enabled. bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. 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/8cqrj5mhhvi07l6nv3iqrg03b1aa7iiv-nixos-system-alice-test/init regInfo=/nix/.ro-store/8vi4aza7qh25vqz66p2g537x60gbayfv-closure-info/registration console=ttyAMA0,115200n8 console=tty0 bob # [ 0.000000] Trampoline variant of Tasks RCU enabled. bob # [ 0.000000] Rude variant of Tasks RCU enabled. bob # [ 0.000000] Tracing variant of Tasks RCU enabled. alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/8vi4aza7qh25vqz66p2g537x60gbayfv-closure-info/registration", will be passed to user space. alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB bob # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.000000] software IO TLB: area num 1. alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) 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] Fallback order for Node 0: 0 bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 bob # [ 0.000000] GICv3: 256 SPIs implemented alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 alice # [ 0.000000] Policy zone: DMA bob # [ 0.000000] GICv3: 0 Extended SPIs implemented bob # [ 0.000000] Root IRQ handler: gic_handle_irq alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 alice # [ 0.000000] allocated 2097152 bytes of page_ext bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 alice # [ 0.000000] ftrace: allocating 75049 entries in 294 pages bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups alice # [ 0.000000] rcu: Hierarchical RCU implementation. bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) alice # [ 0.000000] rcu: RCU event tracing is enabled. alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) alice # [ 0.000000] Trampoline variant of Tasks RCU enabled. bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 alice # [ 0.000000] Rude variant of Tasks RCU enabled. alice # [ 0.000000] Tracing variant of Tasks RCU enabled. bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns 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] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). 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] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns alice # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns alice # [ 0.000000] GICv3: 256 SPIs implemented bob # [ 0.000055] arm-pv: using stolen time PV alice # [ 0.000000] GICv3: 0 Extended SPIs implemented alice # [ 0.000000] Root IRQ handler: gic_handle_irq bob # [ 0.000394] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI bob # [ 0.000678] Console: colour dummy device 80x25 alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 bob # [ 0.000690] printk: legacy console [tty0] enabled alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] bob # [ 0.000912] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) bob # [ 0.000917] pid_max: default: 32768 minimum: 301 alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) bob # [ 0.001005] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.001143] landlock: Up and running. alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) bob # [ 0.001146] Yama: becoming mindful. bob # [ 0.001601] LSM support for eBPF active alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 bob # [ 0.001780] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] bob # [ 0.001801] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.004650] rcu: Hierarchical SRCU implementation. clare # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172 clare # [ 0.000000] KASLR enabled bob # [ 0.004657] rcu: Max phase no-delay instances is 1000. alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns clare # [ 0.000000] random: crng init done bob # [ 0.005621] fsl-mc MSI: its@8080000 domain created alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 clare # [ 0.000000] Machine model: linux,dummy-virt bob # [ 0.005807] EFI services will not be available. clare # [ 0.000000] efi: UEFI not found. alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). bob # [ 0.005904] smp: Bringing up secondary CPUs ... bob # [ 0.005935] smp: Brought up 1 node, 1 CPU clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns bob # [ 0.005938] SMP: Total of 1 processors activated. clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.005939] CPU: All CPU(s) started at EL1 clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.005951] CPU features: detected: 32-bit EL0 Support clare # [ 0.000000] Zone ranges: alice # [ 0.000056] arm-pv: using stolen time PV clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.005956] CPU features: detected: Data cache clean to the PoU not required for I/D coherence clare # [ 0.000000] DMA32 empty alice # [ 0.000375] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.005959] CPU features: detected: Common not Private translations clare # [ 0.000000] Normal empty clare # [ 0.000000] Device empty alice # [ 0.000659] Console: colour dummy device 80x25 bob # [ 0.005961] CPU features: detected: CRC32 instructions clare # [ 0.000000] Movable zone start for each node alice # [ 0.000672] printk: legacy console [tty0] enabled clare # [ 0.000000] Early memory node ranges bob # [ 0.005963] CPU features: detected: Data cache clean to Point of Persistence clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000899] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) bob # [ 0.005965] CPU features: detected: Enhanced Virtualization Traps alice # [ 0.000904] pid_max: default: 32768 minimum: 301 clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.005975] CPU features: detected: RCpc load-acquire (LDAPR) clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000982] LSM: initializing lsm=capability,landlock,yama,bpf,ima alice # [ 0.001157] landlock: Up and running. bob # [ 0.005978] CPU features: detected: LSE atomic instructions clare # [ 0.000000] psci: probing for conduit method from DT. alice # [ 0.001160] Yama: becoming mindful. bob # [ 0.005980] CPU features: detected: Privileged Access Never clare # [ 0.000000] psci: PSCIv1.3 detected in firmware. alice # [ 0.001606] LSM support for eBPF active bob # [ 0.005981] CPU features: detected: PMUv3 clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs bob # [ 0.005983] CPU features: detected: RAS Extension Support alice # [ 0.001795] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.000000] psci: Trusted OS migration not required clare # [ 0.000000] psci: SMC Calling Convention v1.1 bob # [ 0.005987] CPU features: detected: Speculative Store Bypassing Safe (SSBS) alice # [ 0.001814] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.006007] alternatives: applying system-wide alternatives clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.004510] rcu: Hierarchical SRCU implementation. bob # [ 0.007467] CPU features: detected: Hardware dirty bit management on CPU0 alice # [ 0.004516] rcu: Max phase no-delay instances is 1000. clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296 alice # [ 0.005496] fsl-mc MSI: its@8080000 domain created clare # [ 0.000000] Detected PIPT I-cache on CPU0 alice # [ 0.005704] EFI services will not be available. clare # [ 0.000000] CPU features: detected: GICv3 CPU interface bob # [ 0.007679] Memory: 891184K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 116084K reserved, 32768K cma-reserved) alice # [ 0.005808] smp: Bringing up secondary CPUs ... clare # [ 0.000000] CPU features: detected: Spectre-v4 bob # [ 0.008214] devtmpfs: initialized alice # [ 0.005841] smp: Brought up 1 node, 1 CPU clare # [ 0.000000] CPU features: detected: Spectre-BHB alice # [ 0.005843] SMP: Total of 1 processors activated. bob # [ 0.009761] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR alice # [ 0.005845] CPU: All CPU(s) started at EL1 alice # [ 0.005857] CPU features: detected: 32-bit EL0 Support bob # [ 0.009784] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.000000] CPU features: detected: ARM erratum 1418040 bob # [ 0.009915] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL alice # [ 0.005861] CPU features: detected: Data cache clean to the PoU not required for I/D coherence bob # [ 0.009920] 0 pages in range for non-PLT usage clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion bob # [ 0.009921] 508192 pages in range for PLT usage alice # [ 0.005864] CPU features: detected: Common not Private translations clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing alice # [ 0.005866] CPU features: detected: CRC32 instructions bob # [ 0.010054] pinctrl core: initialized pinctrl subsystem clare # [ 0.000000] alternatives: applying boot alternatives bob # [ 0.010851] DMI not present or invalid. alice # [ 0.005868] CPU features: detected: Data cache clean to Point of Persistence bob # [ 0.014261] NET: Registered PF_NETLINK/PF_ROUTE protocol family alice # [ 0.005870] CPU features: detected: Enhanced Virtualization Traps bob # [ 0.016947] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations alice # [ 0.005880] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.005883] CPU features: detected: LSE atomic instructions bob # [ 0.017078] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations alice # [ 0.005884] CPU features: detected: Privileged Access Never bob # [ 0.017224] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations alice # [ 0.005886] CPU features: detected: PMUv3 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/pypphm6vxi6yw5aad522jmihx38f8bdi-nixos-system-clare-test/init regInfo=/nix/.ro-store/m5va5kd7z8gdki2cad931j7pn1jfyfls-closure-info/registration console=ttyAMA0,115200n8 console=tty0 bob # [ 0.017246] audit: initializing netlink subsys (disabled) alice # [ 0.005888] CPU features: detected: RAS Extension Support bob # [ 0.017922] thermal_sys: Registered thermal governor 'fair_share' alice # [ 0.005892] CPU features: detected: Speculative Store Bypassing Safe (SSBS) clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/m5va5kd7z8gdki2cad931j7pn1jfyfls-closure-info/registration", will be passed to user space. bob # [ 0.017925] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 0.005912] alternatives: applying system-wide alternatives clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes bob # [ 0.017928] thermal_sys: Registered thermal governor 'step_wise' alice # [ 0.007276] CPU features: detected: Hardware dirty bit management on CPU0 bob # [ 0.017930] thermal_sys: Registered thermal governor 'user_space' clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) bob # [ 0.017932] thermal_sys: Registered thermal governor 'power_allocator' clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.007626] Memory: 891168K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 116088K reserved, 32768K cma-reserved) alice # [ 0.008129] devtmpfs: initialized bob # [ 0.017969] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB bob # [ 0.017978] cpuidle: using governor ladder clare # [ 0.000000] software IO TLB: area num 1. alice # [ 0.009637] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.017982] cpuidle: using governor menu clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) alice # [ 0.009660] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). bob # [ 0.018097] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. clare # [ 0.000000] Fallback order for Node 0: 0 bob # [ 0.018119] ASID allocator initialised with 32768 entries alice # [ 0.009795] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 bob # [ 0.018787] Serial: AMBA PL011 UART driver alice # [ 0.009799] 0 pages in range for non-PLT usage clare # [ 0.000000] Policy zone: DMA alice # [ 0.009800] 508192 pages in range for PLT usage clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off bob # [ 0.022222] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 alice # [ 0.009919] pinctrl core: initialized pinctrl subsystem bob # [ 0.022409] printk: console [ttyAMA0] enabled clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.010729] DMI not present or invalid. clare # [ 0.000000] allocated 2097152 bytes of page_ext alice # [ 0.014101] NET: Registered PF_NETLINK/PF_ROUTE protocol family clare # [ 0.000000] ftrace: allocating 75049 entries in 294 pages alice # [ 0.016486] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups clare # [ 0.000000] rcu: Hierarchical RCU implementation. alice # [ 0.016615] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations clare # [ 0.000000] rcu: RCU event tracing is enabled. alice # [ 0.016760] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. alice # [ 0.016779] audit: initializing netlink subsys (disabled) clare # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.000000] Rude variant of Tasks RCU enabled. alice # [ 0.017400] thermal_sys: Registered thermal governor 'fair_share' clare # [ 0.000000] Tracing variant of Tasks RCU enabled. alice # [ 0.017402] thermal_sys: Registered thermal governor 'bang_bang' clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. alice # [ 0.017405] thermal_sys: Registered thermal governor 'step_wise' clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 0.017407] thermal_sys: Registered thermal governor 'user_space' alice # [ 0.017409] thermal_sys: Registered thermal governor 'power_allocator' 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.017452] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 clare # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.017461] cpuidle: using governor ladder alice # [ 0.017466] cpuidle: using governor menu clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.017581] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 alice # [ 0.017602] ASID allocator initialised with 32768 entries clare # [ 0.000000] GICv3: 256 SPIs implemented alice # [ 0.018255] Serial: AMBA PL011 UART driver clare # [ 0.000000] GICv3: 0 Extended SPIs implemented clare # [ 0.000000] Root IRQ handler: gic_handle_irq alice # [ 0.021702] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI alice # [ 0.021870] printk: console [ttyAMA0] enabled clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 bob # [ 0.092532] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 bob # [ 0.092547] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.092549] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.092551] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns bob # [ 0.092554] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.092555] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). bob # [ 0.092558] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns bob # [ 0.092560] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page bob # [ 0.093542] fbcon: Taking over console clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.093552] ACPI: Interpreter disabled. clare # [ 0.000046] arm-pv: using stolen time PV bob # [ 0.094192] iommu: Default domain type: Translated bob # [ 0.094196] iommu: DMA domain TLB invalidation policy: strict mode clare # [ 0.000339] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.094528] SCSI subsystem initialized clare # [ 0.000628] Console: colour dummy device 80x25 bob # [ 0.096375] usbcore: registered new interface driver usbfs clare # [ 0.000639] printk: legacy console [tty0] enabled bob # [ 0.096417] usbcore: registered new interface driver hub bob # [ 0.096425] usbcore: registered new device driver usb clare # [ 0.000849] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) clare # [ 0.000854] pid_max: default: 32768 minimum: 301 bob # [ 0.096653] pps_core: LinuxPPS API ver. 1 registered clare # [ 0.000954] LSM: initializing lsm=capability,landlock,yama,bpf,ima clare # [ 0.001073] landlock: Up and running. bob # [ 0.096656] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti clare # [ 0.001076] Yama: becoming mindful. bob # [ 0.096661] PTP clock support registered clare # [ 0.001437] LSM support for eBPF active bob # [ 0.096693] EDAC MC: Ver: 3.0.0 clare # [ 0.001598] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.001617] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.093362] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages clare # [ 0.004257] rcu: Hierarchical SRCU implementation. clare # [ 0.004263] rcu: Max phase no-delay instances is 1000. alice # [ 0.093376] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page clare # [ 0.005227] fsl-mc MSI: its@8080000 domain created alice # [ 0.093379] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages clare # [ 0.005414] EFI services will not be available. alice # [ 0.093381] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page clare # [ 0.005503] smp: Bringing up secondary CPUs ... clare # [ 0.005533] smp: Brought up 1 node, 1 CPU alice # [ 0.093383] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages clare # [ 0.005536] SMP: Total of 1 processors activated. clare # [ 0.005538] CPU: All CPU(s) started at EL1 bob # [ 0.105275] scmi_core: SCMI protocol bus registered alice # [ 0.093385] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 0.105626] FPGA manager framework clare # [ 0.005549] CPU features: detected: 32-bit EL0 Support alice # [ 0.093387] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages alice # [ 0.093389] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page clare # [ 0.005553] CPU features: detected: Data cache clean to the PoU not required for I/D coherence alice # [ 0.094369] fbcon: Taking over console clare # [ 0.005556] CPU features: detected: Common not Private translations alice # [ 0.094378] ACPI: Interpreter disabled. clare # [ 0.005558] CPU features: detected: CRC32 instructions alice # [ 0.095017] iommu: Default domain type: Translated alice # [ 0.095020] iommu: DMA domain TLB invalidation policy: strict mode clare # [ 0.005560] CPU features: detected: Data cache clean to Point of Persistence alice # [ 0.095308] SCSI subsystem initialized clare # [ 0.005563] CPU features: detected: Enhanced Virtualization Traps alice # [ 0.095506] usbcore: registered new interface driver usbfs clare # [ 0.005573] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.095535] usbcore: registered new interface driver hub clare # [ 0.005575] CPU features: detected: LSE atomic instructions alice # [ 0.095542] usbcore: registered new device driver usb bob # [ 0.109298] vgaarb: loaded clare # [ 0.005577] CPU features: detected: Privileged Access Never alice # [ 0.095759] pps_core: LinuxPPS API ver. 1 registered clare # [ 0.005579] CPU features: detected: PMUv3 bob # [ 0.109523] clocksource: Switched to clocksource arch_sys_counter clare # [ 0.005581] CPU features: detected: RAS Extension Support alice # [ 0.095762] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti alice # [ 0.095766] PTP clock support registered clare # [ 0.005585] CPU features: detected: Speculative Store Bypassing Safe (SSBS) alice # [ 0.095798] EDAC MC: Ver: 3.0.0 clare # [ 0.005605] alternatives: applying system-wide alternatives alice # [ 0.105277] scmi_core: SCMI protocol bus registered clare # [ 0.007015] CPU features: detected: Hardware dirty bit management on CPU0 clare # [ 0.007273] Memory: 891188K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 116096K reserved, 32768K cma-reserved) clare # [ 0.007738] devtmpfs: initialized clare # [ 0.009308] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) clare # [ 0.009330] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). clare # [ 0.009458] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL clare # [ 0.009463] 0 pages in range for non-PLT usage clare # [ 0.009464] 508192 pages in range for PLT usage alice # [ 0.106024] FPGA manager framework alice # [ 0.109195] vgaarb: loaded clare # [ 0.009582] pinctrl core: initialized pinctrl subsystem clare # [ 0.010326] DMI not present or invalid. clare # [ 0.013681] NET: Registered PF_NETLINK/PF_ROUTE protocol family clare # [ 0.016028] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations clare # [ 0.016183] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations clare # [ 0.016331] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.016350] audit: initializing netlink subsys (disabled) bob # [ 0.120936] VFS: Disk quotas dquot_6.6.0 clare # [ 0.016973] thermal_sys: Registered thermal governor 'fair_share' bob # [ 0.120963] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) clare # [ 0.016975] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 0.109891] clocksource: Switched to clocksource arch_sys_counter clare # [ 0.016978] thermal_sys: Registered thermal governor 'step_wise' clare # [ 0.016980] thermal_sys: Registered thermal governor 'user_space' clare # [ 0.016982] thermal_sys: Registered thermal governor 'power_allocator' clare # [ 0.017016] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 clare # [ 0.017024] cpuidle: using governor ladder clare # [ 0.017028] cpuidle: using governor menu clare # [ 0.017137] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. bob # [ 0.122556] netfs: FS-Cache loaded clare # [ 0.017158] ASID allocator initialised with 32768 entries bob # [ 0.122674] pnp: PnP ACPI: disabled clare # [ 0.017797] Serial: AMBA PL011 UART driver bob # [ 0.125343] NET: Registered PF_INET protocol family clare # [ 0.021348] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 clare # [ 0.021515] printk: console [ttyAMA0] enabled bob # [ 0.126460] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.119667] VFS: Disk quotas dquot_6.6.0 alice # [ 0.119695] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 0.121083] netfs: FS-Cache loaded alice # [ 0.121195] pnp: PnP ACPI: disabled alice # [ 0.124408] NET: Registered PF_INET protocol family alice # [ 0.124739] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) clare # [ 0.091993] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages clare # [ 0.092008] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page clare # [ 0.092011] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages clare # [ 0.092013] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page clare # [ 0.092015] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages clare # [ 0.092017] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page clare # [ 0.092019] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages clare # [ 0.092021] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page clare # [ 0.093033] fbcon: Taking over console clare # [ 0.093043] ACPI: Interpreter disabled. clare # [ 0.093701] iommu: Default domain type: Translated clare # [ 0.093704] iommu: DMA domain TLB invalidation policy: strict mode clare # [ 0.093989] SCSI subsystem initialized clare # [ 0.094203] usbcore: registered new interface driver usbfs clare # [ 0.094238] usbcore: registered new interface driver hub clare # [ 0.094245] usbcore: registered new device driver usb clare # [ 0.094462] pps_core: LinuxPPS API ver. 1 registered clare # [ 0.094465] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti clare # [ 0.094470] PTP clock support registered clare # [ 0.094502] EDAC MC: Ver: 3.0.0 clare # [ 0.103945] scmi_core: SCMI protocol bus registered clare # [ 0.104705] FPGA manager framework clare # [ 0.108172] vgaarb: loaded clare # [ 0.108392] clocksource: Switched to clocksource arch_sys_counter bob # [ 0.151247] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.151271] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.151283] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 0.151321] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) clare # [ 0.118856] VFS: Disk quotas dquot_6.6.0 bob # [ 0.151387] TCP: Hash tables configured (established 8192 bind 8192) clare # [ 0.118882] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) clare # [ 0.120339] netfs: FS-Cache loaded bob # [ 0.151471] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) bob # [ 0.151530] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.151578] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.151681] NET: Registered PF_UNIX/PF_LOCAL protocol family bob # [ 0.151739] NET: Registered PF_XDP protocol family bob # [ 0.151757] PCI: CLS 0 bytes, default 64 bob # [ 0.152101] Trying to unpack rootfs image as initramfs... alice # [ 0.149050] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.153742] kvm [1]: HYP mode not available clare # [ 0.121559] pnp: PnP ACPI: disabled alice # [ 0.149073] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) clare # [ 0.123390] NET: Registered PF_INET protocol family alice # [ 0.149085] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) clare # [ 0.123572] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.149119] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.149185] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.149267] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 0.149333] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.149383] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.149499] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 0.149532] NET: Registered PF_XDP protocol family alice # [ 0.149548] PCI: CLS 0 bytes, default 64 alice # [ 0.149785] Trying to unpack rootfs image as initramfs... alice # [ 0.163940] kvm [1]: HYP mode not available clare # [ 0.148915] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) clare # [ 0.148940] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) clare # [ 0.148952] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) clare # [ 0.148987] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) clare # [ 0.149054] TCP: Hash tables configured (established 8192 bind 8192) clare # [ 0.149162] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) clare # [ 0.149219] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.149267] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.149378] NET: Registered PF_UNIX/PF_LOCAL protocol family clare # [ 0.149408] NET: Registered PF_XDP protocol family clare # [ 0.149425] PCI: CLS 0 bytes, default 64 clare # [ 0.149741] Trying to unpack rootfs image as initramfs... clare # [ 0.158397] kvm [1]: HYP mode not available bob # [ 0.232003] Initialise system trusted keyrings bob # [ 0.232147] workingset: timestamp_bits=42 max_order=18 bucket_order=0 bob # [ 0.232499] squashfs: version 4.0 (2009/01/31) Phillip Lougher bob # [ 0.232541] 9p: Installing v9fs 9p2000 file system support alice # [ 0.230279] Initialise system trusted keyrings alice # [ 0.230391] workingset: timestamp_bits=42 max_order=18 bucket_order=0 alice # [ 0.230697] squashfs: version 4.0 (2009/01/31) Phillip Lougher alice # [ 0.230755] 9p: Installing v9fs 9p2000 file system support bob # [ 0.253102] Key type asymmetric registered bob # [ 0.253121] Asymmetric key parser 'x509' registered bob # [ 0.253192] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) alice # [ 0.249569] Key type asymmetric registered alice # [ 0.249588] Asymmetric key parser 'x509' registered alice # [ 0.249649] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) bob # [ 0.261600] io scheduler mq-deadline registered bob # [ 0.261611] io scheduler kyber registered bob # [ 0.263985] pl061_gpio 9030000.pl061: PL061 GPIO chip registered bob # [ 0.264306] ledtrig-cpu: registered to indicate activity on CPUs bob # [ 0.264618] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: bob # [ 0.264634] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 bob # [ 0.264647] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 bob # [ 0.264651] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 bob # [ 0.264677] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits bob # [ 0.264702] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] alice # [ 0.257963] io scheduler mq-deadline registered bob # [ 0.264784] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 alice # [ 0.257972] io scheduler kyber registered bob # [ 0.264800] pci_bus 0000:00: root bus resource [bus 00-ff] alice # [ 0.260367] pl061_gpio 9030000.pl061: PL061 GPIO chip registered bob # [ 0.264803] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] alice # [ 0.260689] ledtrig-cpu: registered to indicate activity on CPUs bob # [ 0.264806] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.261012] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: bob # [ 0.264808] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.261028] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 bob # [ 0.264918] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint alice # [ 0.261040] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 bob # [ 0.265356] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.265478] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.261044] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 bob # [ 0.265487] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.261074] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits bob # [ 0.265506] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.228763] Initialise system trusted keyrings alice # [ 0.261094] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] bob # [ 0.265518] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.228902] workingset: timestamp_bits=42 max_order=18 bucket_order=0 alice # [ 0.261160] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 clare # [ 0.229248] squashfs: version 4.0 (2009/01/31) Phillip Lougher alice # [ 0.261178] pci_bus 0000:00: root bus resource [bus 00-ff] clare # [ 0.229284] 9p: Installing v9fs 9p2000 file system support alice # [ 0.261181] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] alice # [ 0.261184] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] bob # [ 0.276768] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.276896] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.261186] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] bob # [ 0.276906] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.261261] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint bob # [ 0.276925] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.261725] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.277230] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.261849] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.277351] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.261858] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.277360] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.261877] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.277378] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.272045] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.272401] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.272520] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.272528] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.272547] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.272837] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.272953] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.272961] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.272979] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.273271] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.273401] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.273410] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.273427] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.273435] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.273737] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint alice # [ 0.273856] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.273874] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.289770] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.289902] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.289911] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.289931] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.289939] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.290250] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint bob # [ 0.290373] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.290391] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.290723] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint bob # [ 0.290847] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.290865] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.291127] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.248061] Key type asymmetric registered bob # [ 0.291246] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.248079] Asymmetric key parser 'x509' registered bob # [ 0.291462] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.248157] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) bob # [ 0.291582] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.291599] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.291902] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.292024] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.292042] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.290312] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint bob # [ 0.292337] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.257698] io scheduler mq-deadline registered alice # [ 0.290444] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.257706] io scheduler kyber registered bob # [ 0.292458] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.290462] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.260184] pl061_gpio 9030000.pl061: PL061 GPIO chip registered bob # [ 0.292475] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.290727] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint bob # [ 0.292794] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint alice # [ 0.290846] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] bob # [ 0.293030] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.291026] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.293040] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.291150] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.293058] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.291168] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.293366] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.291467] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.293485] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.291591] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.261605] ledtrig-cpu: registered to indicate activity on CPUs bob # [ 0.293494] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.291610] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.293512] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.261918] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: alice # [ 0.291914] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.261935] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 alice # [ 0.292036] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.261960] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 bob # [ 0.309119] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned alice # [ 0.292053] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.261964] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 bob # [ 0.309128] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned alice # [ 0.292375] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint alice # [ 0.292595] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] clare # [ 0.261987] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits bob # [ 0.309131] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned alice # [ 0.292605] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.309165] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned clare # [ 0.262008] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] alice # [ 0.292624] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.262074] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 bob # [ 0.309196] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned alice # [ 0.292934] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.262090] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 0.309228] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned alice # [ 0.293054] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.262093] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] alice # [ 0.293063] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.309260] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned clare # [ 0.262095] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.293081] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.309291] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned clare # [ 0.262098] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.293512] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned bob # [ 0.309326] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.262211] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint alice # [ 0.293519] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned bob # [ 0.309358] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned clare # [ 0.262653] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.293522] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned clare # [ 0.262774] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.309389] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned alice # [ 0.293555] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned clare # [ 0.262784] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.309419] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned clare # [ 0.262802] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.293585] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned clare # [ 0.262814] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.293616] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned clare # [ 0.263118] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.293648] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned clare # [ 0.263251] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.316269] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned alice # [ 0.293679] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned clare # [ 0.263261] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.316309] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned clare # [ 0.263279] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.293712] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned bob # [ 0.316322] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.263590] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.293741] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.316335] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned clare # [ 0.263712] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.293771] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.316348] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned clare # [ 0.263721] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.316360] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned clare # [ 0.263740] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.293801] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned bob # [ 0.316374] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned alice # [ 0.293873] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned clare # [ 0.264039] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.316386] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned clare # [ 0.264160] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.264169] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.316399] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned clare # [ 0.264187] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.316421] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned clare # [ 0.264195] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.316433] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.316446] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned alice # [ 0.311962] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned bob # [ 0.316459] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned alice # [ 0.311981] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned bob # [ 0.316471] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned alice # [ 0.311994] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.316483] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned alice # [ 0.312007] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.316495] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned alice # [ 0.312020] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned bob # [ 0.316507] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned alice # [ 0.312034] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.316518] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.312047] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.316531] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned alice # [ 0.312060] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.316549] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.316553] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] alice # [ 0.312081] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.316555] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.312094] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.317094] pci 0000:00:07.0: enabling device (0000 -> 0002) alice # [ 0.312106] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned alice # [ 0.312119] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned alice # [ 0.312131] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned alice # [ 0.312143] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned alice # [ 0.312155] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned alice # [ 0.312166] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned alice # [ 0.312178] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.312190] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned alice # [ 0.312209] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] clare # [ 0.284582] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint alice # [ 0.312213] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] clare # [ 0.284715] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.312215] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] clare # [ 0.284733] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.312751] pci 0000:00:07.0: enabling device (0000 -> 0002) clare # [ 0.285052] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint clare # [ 0.285173] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.285191] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.285456] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.285573] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.285785] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.285908] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.285926] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.286230] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.286352] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.286370] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.286668] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.286791] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.286809] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.287112] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint clare # [ 0.287352] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] clare # [ 0.287362] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.287380] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.287694] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.287815] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.287823] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.287840] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.288265] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned clare # [ 0.288271] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned clare # [ 0.288275] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned bob # [ 0.342780] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) clare # [ 0.288307] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned bob # [ 0.344333] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) clare # [ 0.288339] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned clare # [ 0.288370] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned alice # [ 0.335418] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.336934] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) bob # [ 0.346809] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) bob # [ 0.348177] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) alice # [ 0.346453] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) alice # [ 0.348306] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.312563] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned clare # [ 0.312601] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned clare # [ 0.312637] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.312668] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.357659] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) clare # [ 0.312698] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.359661] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) bob # [ 0.361195] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) clare # [ 0.312728] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned clare # [ 0.312791] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned clare # [ 0.312821] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned clare # [ 0.312834] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.312847] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned clare # [ 0.312920] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.364247] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) clare # [ 0.312932] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned clare # [ 0.312944] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned clare # [ 0.312956] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned clare # [ 0.312968] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned clare # [ 0.312985] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned clare # [ 0.312997] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned clare # [ 0.313009] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned clare # [ 0.313020] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned clare # [ 0.313032] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned clare # [ 0.313044] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned clare # [ 0.313055] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned alice # [ 0.358014] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) clare # [ 0.313067] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned alice # [ 0.359681] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) clare # [ 0.313078] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.361177] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) clare # [ 0.313090] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned clare # [ 0.313110] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] clare # [ 0.313114] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] clare # [ 0.313116] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] clare # [ 0.313650] pci 0000:00:07.0: enabling device (0000 -> 0002) alice # [ 0.364061] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.365463] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) bob # [ 0.373661] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) bob # [ 0.375326] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) bob # [ 0.377433] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) clare # [ 0.335110] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.375085] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) alice # [ 0.377138] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) clare # [ 0.344606] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) clare # [ 0.346434] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) clare # [ 0.348223] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) bob # [ 0.390285] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.391390] msm_serial: driver initialized bob # [ 0.391476] SuperH (H)SCI(F) driver initialized bob # [ 0.391516] STM32 USART driver initialized alice # [ 0.386506] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled alice # [ 0.387510] msm_serial: driver initialized alice # [ 0.387581] SuperH (H)SCI(F) driver initialized alice # [ 0.387620] STM32 USART driver initialized clare # [ 0.358747] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) clare # [ 0.360978] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) clare # [ 0.362458] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) clare # [ 0.363994] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) bob # [ 0.414965] loop: module loaded bob # [ 0.415113] virtio_blk virtio2: 1/0/0 default/read/poll queues bob # [ 0.415726] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) clare # [ 0.373646] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) clare # [ 0.375268] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) alice # [ 0.411002] loop: module loaded alice # [ 0.411142] virtio_blk virtio2: 1/0/0 default/read/poll queues clare # [ 0.378247] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) alice # [ 0.411743] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 0.415612] megasas: 07.734.00.00-rc1 alice # [ 0.416083] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] bob # [ 0.419249] megasas: 07.734.00.00-rc1 bob # [ 0.419770] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] clare # [ 0.389853] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled clare # [ 0.390820] msm_serial: driver initialized clare # [ 0.390892] SuperH (H)SCI(F) driver initialized clare # [ 0.390932] STM32 USART driver initialized bob # [ 0.435610] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.435642] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.437343] Using buffer write method bob # [ 0.437403] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.430983] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.431046] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.432722] Using buffer write method alice # [ 0.432781] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] bob # [ 0.446403] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.446427] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.447945] Using buffer write method bob # [ 0.447960] Concatenating MTD devices: bob # [ 0.447962] (0): "0.flash" bob # [ 0.447963] (1): "0.flash" bob # [ 0.447965] into device "0.flash" alice # [ 0.442797] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.442813] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.444397] Using buffer write method alice # [ 0.444410] Concatenating MTD devices: alice # [ 0.444412] (0): "0.flash" alice # [ 0.444414] (1): "0.flash" alice # [ 0.444415] into device "0.flash" clare # [ 0.414841] loop: module loaded clare # [ 0.414990] virtio_blk virtio2: 1/0/0 default/read/poll queues clare # [ 0.415609] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) clare # [ 0.420841] megasas: 07.734.00.00-rc1 clare # [ 0.421338] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] clare # [ 0.437367] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.437421] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.439824] Using buffer write method clare # [ 0.439886] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] clare # [ 0.443462] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.443476] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.452948] Using buffer write method clare # [ 0.452965] Concatenating MTD devices: clare # [ 0.452966] (0): "0.flash" clare # [ 0.452968] (1): "0.flash" clare # [ 0.452970] into device "0.flash" alice # [ 0.661819] Freeing initrd memory: 26904K bob # [ 0.673701] Freeing initrd memory: 26900K alice # [ 0.669480] tun: Universal TUN/TAP device driver, 1.6 bob # [ 0.681403] tun: Universal TUN/TAP device driver, 1.6 alice # [ 0.672091] thunder_xcv, ver 1.0 alice # [ 0.672107] thunder_bgx, ver 1.0 alice # [ 0.672116] nicpf, ver 1.0 alice # [ 0.672413] e1000: Intel(R) PRO/1000 Network Driver alice # [ 0.672417] e1000: Copyright (c) 1999-2006 Intel Corporation. alice # [ 0.672429] e1000e: Intel(R) PRO/1000 Network Driver alice # [ 0.672435] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. alice # [ 0.672446] igb: Intel(R) Gigabit Ethernet Network Driver alice # [ 0.672448] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.684104] thunder_xcv, ver 1.0 alice # [ 0.672458] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.684121] thunder_bgx, ver 1.0 bob # [ 0.684130] nicpf, ver 1.0 alice # [ 0.672460] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. bob # [ 0.684467] e1000: Intel(R) PRO/1000 Network Driver alice # [ 0.672552] sky2: driver version 1.30 bob # [ 0.684472] e1000: Copyright (c) 1999-2006 Intel Corporation. alice # [ 0.673440] usbcore: registered new interface driver usb-storage bob # [ 0.684485] e1000e: Intel(R) PRO/1000 Network Driver alice # [ 0.673488] usbcore: registered new interface driver usbserial_generic bob # [ 0.684490] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. alice # [ 0.673509] usbserial: USB Serial support registered for generic bob # [ 0.684502] igb: Intel(R) Gigabit Ethernet Network Driver alice # [ 0.673809] hv_vmbus: registering driver hyperv_keyboard bob # [ 0.684504] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.684513] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.684515] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. bob # [ 0.684580] sky2: driver version 1.30 bob # [ 0.685436] usbcore: registered new interface driver usb-storage alice # [ 0.680629] ehci-pci 0000:00:07.0: EHCI Host Controller bob # [ 0.685516] usbcore: registered new interface driver usbserial_generic bob # [ 0.686109] ehci-pci 0000:00:07.0: EHCI Host Controller alice # [ 0.680655] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 alice # [ 0.680821] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 bob # [ 0.686135] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 alice # [ 0.681386] rtc-pl031 9010000.pl031: registered as rtc0 bob # [ 0.686304] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 alice # [ 0.681418] rtc-pl031 9010000.pl031: setting system clock to 2026-10-15T15:16:00 UTC (1792077360) alice # [ 0.681578] i2c_dev: i2c /dev entries driver bob # [ 0.693019] usbserial: USB Serial support registered for generic bob # [ 0.693377] hv_vmbus: registering driver hyperv_keyboard bob # [ 0.694897] rtc-pl031 9010000.pl031: registered as rtc0 bob # [ 0.694930] rtc-pl031 9010000.pl031: setting system clock to 2026-10-15T15:16:00 UTC (1792077360) bob # [ 0.695129] i2c_dev: i2c /dev entries driver bob # [ 0.696737] sdhci: Secure Digital Host Controller Interface driver alice # [ 0.685963] sdhci: Secure Digital Host Controller Interface driver bob # [ 0.696743] sdhci: Copyright(c) Pierre Ossman alice # [ 0.685968] sdhci: Copyright(c) Pierre Ossman bob # [ 0.696896] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.686122] Synopsys Designware Multimedia Card Interface Driver bob # [ 0.697075] sdhci-pltfm: SDHCI platform and OF driver helper alice # [ 0.686305] sdhci-pltfm: SDHCI platform and OF driver helper bob # [ 0.697578] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 alice # [ 0.687238] hid: raw HID events driver (C) Jiri Kosina alice # [ 0.687370] usbcore: registered new interface driver usbhid bob # [ 0.701435] hub 1-0:1.0: USB hub found alice # [ 0.687373] usbhid: USB HID core driver bob # [ 0.701461] hub 1-0:1.0: 6 ports detected alice # [ 0.688067] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available alice # [ 0.688912] drop_monitor: Initializing network drop monitor service alice # [ 0.689057] NET: Registered PF_INET6 protocol family alice # [ 0.689670] Segment Routing with IPv6 alice # [ 0.689680] In-situ OAM (IOAM) with IPv6 alice # [ 0.689754] NET: Registered PF_PACKET protocol family alice # [ 0.689813] 9pnet: Installing 9P2000 support alice # [ 0.689845] Key type dns_resolver registered alice # [ 0.689936] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 bob # [ 0.702889] hid: raw HID events driver (C) Jiri Kosina bob # [ 0.703025] usbcore: registered new interface driver usbhid bob # [ 0.703031] usbhid: USB HID core driver bob # [ 0.703714] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available alice # [ 0.696688] hub 1-0:1.0: USB hub found alice # [ 0.696726] hub 1-0:1.0: 6 ports detected bob # [ 0.704530] drop_monitor: Initializing network drop monitor service bob # [ 0.704682] NET: Registered PF_INET6 protocol family bob # [ 0.705320] Segment Routing with IPv6 bob # [ 0.705331] In-situ OAM (IOAM) with IPv6 bob # [ 0.705383] NET: Registered PF_PACKET protocol family bob # [ 0.705440] 9pnet: Installing 9P2000 support bob # [ 0.705473] Key type dns_resolver registered alice # [ 0.700398] registered taskstats version 1 alice # [ 0.700564] Loading compiled-in X.509 certificates clare # [ 0.664551] Freeing initrd memory: 26912K bob # [ 0.712686] registered taskstats version 1 bob # [ 0.712827] Loading compiled-in X.509 certificates alice # [ 0.705844] Demotion targets for Node 0: null alice # [ 0.705950] Key type .fscrypt registered clare # [ 0.671459] tun: Universal TUN/TAP device driver, 1.6 alice # [ 0.705953] Key type fscrypt-provisioning registered alice # [ 0.706057] ima: No TPM chip found, activating TPM-bypass! alice # [ 0.706067] ima: Allocated hash algorithm: sha1 alice # [ 0.706139] ima: No architecture policies found alice # [ 0.708763] input: gpio-keys as /devices/platform/gpio-keys/input/input0 clare # [ 0.674039] thunder_xcv, ver 1.0 clare # [ 0.674055] thunder_bgx, ver 1.0 clare # [ 0.674064] nicpf, ver 1.0 clare # [ 0.674397] e1000: Intel(R) PRO/1000 Network Driver clare # [ 0.674402] e1000: Copyright (c) 1999-2006 Intel Corporation. bob # [ 0.718135] Demotion targets for Node 0: null clare # [ 0.674414] e1000e: Intel(R) PRO/1000 Network Driver bob # [ 0.718213] Key type .fscrypt registered clare # [ 0.674419] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.718215] Key type fscrypt-provisioning registered clare # [ 0.674431] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.718324] ima: No TPM chip found, activating TPM-bypass! clare # [ 0.674433] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.718336] ima: Allocated hash algorithm: sha1 bob # [ 0.718395] ima: No architecture policies found clare # [ 0.674444] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.718916] input: gpio-keys as /devices/platform/gpio-keys/input/input0 clare # [ 0.674447] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. clare # [ 0.674514] sky2: driver version 1.30 clare # [ 0.675383] usbcore: registered new interface driver usb-storage clare # [ 0.675470] usbcore: registered new interface driver usbserial_generic clare # [ 0.675491] usbserial: USB Serial support registered for generic clare # [ 0.675806] hv_vmbus: registering driver hyperv_keyboard clare # [ 0.676850] ehci-pci 0000:00:07.0: EHCI Host Controller clare # [ 0.676881] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 clare # [ 0.677094] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 alice # [ 0.719041] clk: Disabling unused clocks alice # [ 0.719051] PM: genpd: Disabling unused power domains clare # [ 0.684684] rtc-pl031 9010000.pl031: registered as rtc0 alice # [ 0.721430] Freeing unused kernel memory: 4736K alice # [ 0.721506] Run /init as init process clare # [ 0.684723] rtc-pl031 9010000.pl031: setting system clock to 2026-10-15T15:16:00 UTC (1792077360) clare # [ 0.684956] i2c_dev: i2c /dev entries driver clare # [ 0.686537] sdhci: Secure Digital Host Controller Interface driver clare # [ 0.686542] sdhci: Copyright(c) Pierre Ossman bob # [ 0.731711] clk: Disabling unused clocks bob # [ 0.731724] PM: genpd: Disabling unused power domains clare # [ 0.686698] Synopsys Designware Multimedia Card Interface Driver clare # [ 0.686883] sdhci-pltfm: SDHCI platform and OF driver helper clare # [ 0.687826] hid: raw HID events driver (C) Jiri Kosina clare # [ 0.687963] usbcore: registered new interface driver usbhid clare # [ 0.687966] usbhid: USB HID core driver bob # [ 0.734719] Freeing unused kernel memory: 4736K bob # [ 0.734786] Run /init as init process clare # [ 0.692547] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 clare # [ 0.693093] hub 1-0:1.0: USB hub found clare # [ 0.693130] hub 1-0:1.0: 6 ports detected clare # [ 0.693736] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available clare # [ 0.694608] drop_monitor: Initializing network drop monitor service clare # [ 0.694719] NET: Registered PF_INET6 protocol family clare # [ 0.695638] Segment Routing with IPv6 clare # [ 0.695648] In-situ OAM (IOAM) with IPv6 clare # [ 0.695754] NET: Registered PF_PACKET protocol family clare # [ 0.695819] 9pnet: Installing 9P2000 support clare # [ 0.695858] Key type dns_resolver registered clare # [ 0.702892] registered taskstats version 1 clare # [ 0.703046] Loading compiled-in X.509 certificates alice # [ 0.744380] systemd[1]: Successfully made /usr/ read-only. clare # [ 0.708440] Demotion targets for Node 0: null clare # [ 0.708515] Key type .fscrypt registered clare # [ 0.708518] Key type fscrypt-provisioning registered clare # [ 0.708620] ima: No TPM chip found, activating TPM-bypass! clare # [ 0.708630] ima: Allocated hash algorithm: sha1 bob # [ 0.756098] systemd[1]: Successfully made /usr/ read-only. clare # [ 0.708702] ima: No architecture policies found clare # [ 0.709229] input: gpio-keys as /devices/platform/gpio-keys/input/input0 clare # [ 0.721641] clk: Disabling unused clocks clare # [ 0.721649] PM: genpd: Disabling unused power domains clare # [ 0.723899] Freeing unused kernel memory: 4736K clare # [ 0.723964] Run /init as init process clare # [ 0.742841] systemd[1]: Successfully made /usr/ read-only. alice # [ 0.941970] usb 1-1: new high-speed USB device number 2 using ehci-pci bob # [ 0.953582] usb 1-1: new high-speed USB device number 2 using ehci-pci clare # [ 0.944473] usb 1-1: new high-speed USB device number 2 using ehci-pci alice # [ 1.078518] 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 # [ 1.078548] systemd[1]: Detected virtualization qemu. bob # [ 1.090199] 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 # [ 1.078587] systemd[1]: Detected architecture arm64. bob # [ 1.090228] systemd[1]: Detected virtualization qemu. alice # [ 1.078596] systemd[1]: Running in initrd. bob # [ 1.090268] systemd[1]: Detected architecture arm64. alice # [ 1.079376] systemd[1]: Initializing machine ID from random generator. alice # [ 1.079651] systemd[1]: Hostname set to . bob # [ 1.090277] systemd[1]: Running in initrd. bob # [ 1.091121] systemd[1]: Initializing machine ID from random generator. bob # [ 1.091406] systemd[1]: Hostname set to . bob # [ 1.112660] 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.108379] 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.076705] 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.076738] systemd[1]: Detected virtualization qemu. clare # [ 1.076779] systemd[1]: Detected architecture arm64. clare # [ 1.076788] systemd[1]: Running in initrd. clare # [ 1.077598] systemd[1]: Initializing machine ID from random generator. clare # [ 1.077869] systemd[1]: Hostname set to . clare # [ 1.099410] 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.201696] 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.194051] 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.225826] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 1.218688] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 1.188577] 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.214234] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 1.275677] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 1.279764] systemd[1]: Created slice Slice /system/modprobe. bob # [ 1.279912] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 1.279942] systemd[1]: Expecting device /dev/disk/by-label/nixos... bob # [ 1.279961] systemd[1]: Reached target Path Units. alice # [ 1.272963] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 1.279972] systemd[1]: Reached target Slice Units. bob # [ 1.279984] systemd[1]: Reached target Swaps. bob # [ 1.280002] systemd[1]: Reached target Timer Units. bob # [ 1.280153] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 1.280263] systemd[1]: Listening on Journal Socket (/dev/log). bob # [ 1.280381] systemd[1]: Listening on Journal Sockets. bob # [ 1.280451] systemd[1]: Listening on udev Control Socket. bob # [ 1.280495] systemd[1]: Listening on udev Kernel Socket. bob # [ 1.280508] systemd[1]: Reached target Socket Units. bob # [ 1.287166] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 1.287223] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.279365] systemd[1]: Created slice Slice /system/modprobe. alice # [ 1.279512] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 1.279542] systemd[1]: Expecting device /dev/disk/by-label/nixos... alice # [ 1.279562] systemd[1]: Reached target Path Units. alice # [ 1.279574] systemd[1]: Reached target Slice Units. alice # [ 1.279585] systemd[1]: Reached target Swaps. alice # [ 1.279605] systemd[1]: Reached target Timer Units. alice # [ 1.279737] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 1.279849] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 1.279929] systemd[1]: Listening on Journal Sockets. alice # [ 1.279986] systemd[1]: Listening on udev Control Socket. alice # [ 1.280058] systemd[1]: Listening on udev Kernel Socket. alice # [ 1.280074] systemd[1]: Reached target Socket Units. alice # [ 1.281098] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.281146] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 1.293645] systemd[1]: Mounting Kernel Configuration File System... bob # [ 1.298197] systemd[1]: Starting Journal Service... alice # [ 1.294024] systemd[1]: Mounting Kernel Configuration File System... clare # [ 1.263831] systemd[1]: Queued start job for default target Initrd Default Target. alice # [ 1.302275] systemd[1]: Starting Journal Service... bob # [ 1.319088] systemd[1]: Starting Load Kernel Modules... bob # [ 1.319162] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 1.273913] systemd[1]: Created slice Slice /system/modprobe. bob # [ 1.321668] usb 1-2: new high-speed USB device number 3 using ehci-pci clare # [ 1.274059] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 1.274090] systemd[1]: Expecting device /dev/disk/by-label/nixos... clare # [ 1.274110] systemd[1]: Reached target Path Units. clare # [ 1.274121] systemd[1]: Reached target Slice Units. clare # [ 1.274134] systemd[1]: Reached target Swaps. clare # [ 1.274152] systemd[1]: Reached target Timer Units. clare # [ 1.274286] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 1.274420] systemd[1]: Listening on Journal Socket (/dev/log). clare # [ 1.274500] systemd[1]: Listening on Journal Sockets. clare # [ 1.274557] systemd[1]: Listening on udev Control Socket. clare # [ 1.274628] systemd[1]: Listening on udev Kernel Socket. clare # [ 1.274644] systemd[1]: Reached target Socket Units. clare # [ 1.275708] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 1.275757] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.313961] usb 1-2: new high-speed USB device number 3 using ehci-pci alice # [ 1.326516] systemd[1]: Starting Load Kernel Modules... clare # [ 1.292548] systemd[1]: Mounting Kernel Configuration File System... alice # [ 1.326594] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 1.333709] systemd[1]: Starting Coldplug All udev Devices... bob # [ 1.343885] systemd-journald[71]: Collecting audit messages is disabled. clare # [ 1.300548] systemd[1]: Starting Journal Service... clare # [ 1.301465] systemd[1]: Starting Load Kernel Modules... clare # [ 1.301525] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 1.349690] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 1.350444] systemd[1]: Mounted Kernel Configuration File System. bob # [ 1.351429] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.342011] systemd[1]: Starting Coldplug All udev Devices... clare # [ 1.308449] usb 1-2: new high-speed USB device number 3 using ehci-pci clare # [ 1.308581] systemd[1]: Starting Coldplug All udev Devices... clare # [ 1.311284] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 1.346452] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 1.347892] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.348045] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.352690] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. alice # [ 1.355247] systemd-journald[71]: Collecting audit messages is disabled. alice # [ 1.358552] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev clare # [ 1.328700] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.366695] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 alice # [ 1.366914] [drm] features: -virgl +edid -resource_blob -host_visible alice # [ 1.366916] [drm] features: -context_init alice # [ 1.367354] [drm] number of scanouts: 1 alice # [ 1.367362] [drm] number of cap sets: 0 clare # [ 1.344839] systemd[1]: Mounted Kernel Configuration File System. bob # [ 1.397455] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. alice # [ 1.386326] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic alice # [ 1.386335] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 bob # [ 1.402004] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 1.403134] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 1.398316] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 1.399383] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 1.356806] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 1.357903] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 1.368153] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. bob # [ 1.409675] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev alice # [ 1.407187] Console: switching to colour frame buffer device 160x50 clare # [ 1.374084] systemd-journald[71]: Collecting audit messages is disabled. bob # [ 1.424783] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 bob # [ 1.424991] [drm] features: -virgl +edid -resource_blob -host_visible bob # [ 1.424993] [drm] features: -context_init bob # [ 1.425454] [drm] number of scanouts: 1 bob # [ 1.425463] [drm] number of cap sets: 0 bob # [ 1.428677] systemd-modules-load[73]: Inserted module 'dm_mod' alice # [ 1.422495] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device bob # [ 1.429751] systemd-modules-load[73]: Module 'virtio_balloon' is built in clare # [ 1.384480] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev bob # [ 1.431143] systemd-modules-load[73]: Module 'virtio_console' is built in bob # [ 1.434981] systemd[1]: Started Journal Service. bob # [ 1.438938] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic bob # [ 1.438946] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 clare # [ 1.398473] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 clare # [ 1.398701] [drm] features: -virgl +edid -resource_blob -host_visible clare # [ 1.398703] [drm] features: -context_init alice # [ 1.429422] systemd-modules-load[72]: Inserted module 'dm_mod' clare # [ 1.399165] [drm] number of scanouts: 1 clare # [ 1.399174] [drm] number of cap sets: 0 alice # [ 1.430519] systemd-modules-load[72]: Module 'virtio_balloon' is built in alice # [ 1.436051] systemd[1]: Started Journal Service. alice # [ 1.435867] systemd-modules-load[72]: Module 'virtio_console' is built in bob # [ 1.447702] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 1.448448] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 1.440184] systemd-modules-load[72]: Inserted module 'virtio_gpu' bob # [ 1.453248] systemd[1]: Reached target Local File Systems. bob # [ 1.453792] systemd[1]: Starting Create System Files and Directories... alice # [ 1.444317] systemd-modules-load[72]: Module 'virtio_rng' is built in clare # [ 1.412850] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 1.445001] systemd[1]: Finished Load Kernel Modules. clare # [ 1.412998] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 1.445565] systemd[1]: Starting Apply Kernel Variables... clare # [ 1.413017] systemd[1]: Reached target Local File Systems. clare # [ 1.414019] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 1.460143] Console: switching to colour frame buffer device 160x50 alice # [ 1.450550] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 1.451152] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 1.420739] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic clare # [ 1.420746] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 alice # [ 1.457745] systemd[1]: Reached target Local File Systems. alice # [ 1.460660] systemd[1]: Starting Create System Files and Directories... bob # [ 1.475869] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 1.470934] 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.478093] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.471120] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 bob # [ 1.483146] systemd-modules-load[73]: Inserted module 'virtio_gpu' bob # [ 1.483892] systemd-modules-load[73]: Module 'virtio_rng' is built in[ 1.486911] 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.487083] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 bob # alice # [ 1.476640] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 1.478003] systemd[1]: Finished Apply Kernel Variables. clare # [ 1.444826] Console: switching to colour frame buffer device 160x50 clare # [ 1.448616] systemd-modules-load[72]: Inserted module 'dm_mod' clare # [ 1.449535] systemd-modules-load[72]: Module 'virtio_balloon' is built in clare # [ 1.450237] systemd-modules-load[72]: Module 'virtio_console' is built in bob # [ 1.495843] systemd[1]: Finished Load Kernel Modules. clare # [ 1.457540] systemd[1]: Started Journal Service. bob # [ 1.505267] systemd[1]: Starting Apply Kernel Variables... clare # [ 1.464573] 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.464669] 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.472955] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device clare # [ 1.474094] systemd[1]: Starting Create System Files and Directories... clare # [ 1.476720] systemd-modules-load[72]: Inserted module 'virtio_gpu' alice # [ 1.510324] systemd[1]: Finished Create System Files and Directories. bob # [ 1.520212] systemd[1]: Finished Create System Files and Directories. clare # [ 1.480138] systemd-modules-load[72]: Module 'virtio_rng' is built in clare # [ 1.487934] systemd[1]: Finished Load Kernel Modules. bob # [ 1.537110] systemd[1]: Finished Apply Kernel Variables. alice # [ 1.536399] systemd-udevd[86]: Using default interface naming scheme 'v261'. clare # [ 1.512266] systemd-udevd[79]: Using default interface naming scheme 'v261'. bob # [ 1.554893] systemd-udevd[83]: Using default interface naming scheme 'v261'. clare # [ 1.513661] systemd[1]: Starting Apply Kernel Variables... clare # [ 1.521008] systemd[1]: Finished Create System Files and Directories. alice # [ 1.556577] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 1.525185] systemd[1]: Finished Apply Kernel Variables. bob # [ 1.577102] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 1.537189] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 1.608094] systemd[1]: Starting Virtual Console Setup... clare # [ 1.586378] systemd[1]: Starting Virtual Console Setup... bob # [ 1.636099] systemd[1]: Starting Virtual Console Setup... alice # [ 1.654195] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 1.656656] systemd[1]: Finished Virtual Console Setup. clare # [ 1.636389] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 1.638643] systemd[1]: Finished Virtual Console Setup. bob # [ 1.680428] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 1.682104] systemd[1]: Finished Virtual Console Setup. alice # [ 1.857689] systemd[1]: Finished Coldplug All udev Devices. alice # [ 1.858298] systemd[1]: Reached target System Initialization. alice # [ 1.858822] systemd[1]: Reached target Basic System. bob # [ 1.876986] systemd[1]: Finished Coldplug All udev Devices. bob # [ 1.877587] systemd[1]: Reached target System Initialization. bob # [ 1.878114] systemd[1]: Reached target Basic System. clare # [ 1.849927] systemd[1]: Finished Coldplug All udev Devices. clare # [ 1.850567] systemd[1]: Reached target System Initialization. clare # [ 1.851090] systemd[1]: Reached target Basic System. alice # [ 1.955656] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line. bob # [ 1.975413] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. alice # [ 1.978727] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line. clare # [ 1.951703] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.002717] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. clare # [ 1.971499] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.036656] systemd[1]: Found device /dev/disk/by-label/nixos. alice # [ 2.044912] systemd[1]: Reached target Initrd Root Device. bob # [ 2.055274] systemd[1]: Found device /dev/disk/by-label/nixos. alice # [ 2.048795] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... bob # [ 2.062884] systemd[1]: Reached target Initrd Root Device. bob # [ 2.068068] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... clare # [ 2.048914] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.050215] systemd[1]: Reached target Initrd Root Device. alice # [ 2.082848] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks clare # [ 2.052788] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... alice # [ 2.089962] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. bob # [ 2.109166] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 2.116193] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. clare # [ 2.085727] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks clare # [ 2.093063] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. bob # [ 2.339188] systemd[1]: Mounting /sysroot... alice # [ 2.340439] systemd[1]: Mounting /sysroot... clare # [ 2.308115] systemd[1]: Mounting /sysroot... bob # [ 2.370661] EXT4-fs (vda): mounted filesystem afcb5a73-ee4d-4d2a-b291-be171a5f7d71 r/w with ordered data mode. Quota mode: none. bob # [ 2.369630] systemd[1]: Mounted /sysroot. bob # [ 2.370495] systemd[1]: Reached target Initrd Root File System. bob # [ 2.372978] systemd[1]: Starting Mountpoints Configured in the Real Root... clare # [ 2.334713] EXT4-fs (vda): mounted filesystem 1f7ec755-49cf-45bb-b9f7-48001bdbae15 r/w with ordered data mode. Quota mode: none. alice # [ 2.365952] systemd[1]: Mounted /sysroot. alice # [ 2.367068] systemd[1]: Reached target Initrd Root File System. clare # [ 2.335314] systemd[1]: Mounted /sysroot. alice # [ 2.369968] EXT4-fs (vda): mounted filesystem 1d3ce35a-71b9-45f8-b73f-e763415b48b4 r/w with ordered data mode. Quota mode: none. clare # [ 2.335774] systemd[1]: Reached target Initrd Root File System. clare # [ 2.336631] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.372447] systemd[1]: Mounting /sysroot/nix/.ro-store... alice # [ 2.383410] systemd[1]: Mounting /sysroot/nix/.rw-store... bob # [ 2.394796] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. clare # [ 2.354950] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 2.400539] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 2.392294] systemd[1]: Mounting /sysroot/run... clare # [ 2.360199] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... bob # [ 2.403638] systemd[1]: Reloading... clare # [ 2.362794] systemd[1]: Reloading... alice # [ 2.417155] systemd[1]: Mounting /sysroot/tmp/shared... alice # [ 2.435118] systemd[1]: Mounting /sysroot/tmp/xchg... alice # [ 2.451313] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.454315] systemd[1]: Mounted /sysroot/run. alice # [ 2.466531] fuse: init (API version 7.45) alice # [ 2.472744] virtiofs virtio6: discovered new tag: nix-store alice # [ 2.473234] virtiofs virtio6: virtio_fs_setup_dax: No cache capability alice # [ 2.470746] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 2.488206] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 2.496165] virtiofs virtio7: discovered new tag: shared alice # [ 2.496684] virtiofs virtio7: virtio_fs_setup_dax: No cache capability alice # [ 2.500490] virtiofs virtio8: discovered new tag: xchg alice # [ 2.500970] virtiofs virtio8: virtio_fs_setup_dax: No cache capability alice # [ 2.503389] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 2.517900] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 2.523296] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 2.524973] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 2.526710] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 2.528168] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 2.531059] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 2.533233] systemd[1]: Reloading... bob # [ 2.573114] systemd[1]: Reloading finished in 169 ms. clare # [ 2.535158] systemd[1]: Reloading finished in 172 ms. bob # [ 2.599317] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... bob # [ 2.600930] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... clare # [ 2.559305] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... clare # [ 2.561658] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... bob # [ 2.605935] systemd[1]: initrd-parse-etc.service: Deactivated successfully. clare # [ 2.565642] systemd[1]: initrd-parse-etc.service: Deactivated successfully. bob # [ 2.607643] systemd[1]: Finished Mountpoints Configured in the Real Root. bob # [ 2.608942] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. clare # [ 2.567446] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 2.569133] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.703109] systemd[1]: Reloading finished in 172 ms. alice # [ 2.724414] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace... alice # [ 2.728186] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace... alice # [ 2.731826] systemd[1]: initrd-parse-etc.service: Deactivated successfully. alice # [ 2.733212] systemd[1]: Finished Mountpoints Configured in the Real Root. alice # [ 2.735981] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.746628] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 2.757711] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 2.758431] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 2.791795] fuse: init (API version 7.45) bob # [ 2.837587] fuse: init (API version 7.45) clare # [ 2.800859] virtiofs virtio6: discovered new tag: nix-store clare # [ 2.801346] virtiofs virtio6: virtio_fs_setup_dax: No cache capability bob # [ 2.852813] virtiofs virtio6: discovered new tag: nix-store bob # [ 2.853308] virtiofs virtio6: virtio_fs_setup_dax: No cache capability clare # [ 2.808538] (udev-worker)[92]: 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.811986] (udev-worker)[92]: 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.859063] (udev-worker)[94]: 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.823173] virtiofs virtio7: discovered new tag: shared clare # [ 2.823655] virtiofs virtio7: virtio_fs_setup_dax: No cache capability bob # [ 2.863831] (udev-worker)[94]: 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[ 2.867935] virtiofs virtio7: discovered new tag: shared bob # [ 2.868434] virtiofs virtio7: virtio_fs_setup_dax: No cache capability bob # clare # [ 2.830241] virtiofs virtio8: discovered new tag: xchg clare # [ 2.830732] virtiofs virtio8: virtio_fs_setup_dax: No cache capability clare # [ 2.832462] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 2.834936] systemd[1]: Stopped Virtual Console Setup. clare # [ 2.836266] systemd[1]: Stopping Virtual Console Setup... bob # [ 2.879417] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[ 2.882850] virtiofs virtio8: discovered new tag: xchg bob # [ 2.883341] virtiofs virtio8: virtio_fs_setup_dax: No cache capability clare # [ 2.840100] systemd[1]: Starting Virtual Console Setup... bob # bob # [ 2.884267] systemd[1]: Stopped Virtual Console Setup. bob # [ 2.885737] systemd[1]: Stopping Virtual Console Setup... bob # [ 2.889034] systemd[1]: Starting Virtual Console Setup... clare # [ 2.863148] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 2.865358] systemd[1]: Finished Virtual Console Setup. bob # [ 2.911193] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 2.912979] systemd[1]: Finished Virtual Console Setup. alice # [ 2.934557] (udev-worker)[100]: 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.940111] (udev-worker)[100]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory alice # [ 2.959526] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 2.961402] systemd[1]: Stopped Virtual Console Setup. alice # [ 2.963206] systemd[1]: Stopping Virtual Console Setup... alice # [ 2.963734] systemd[1]: Starting Virtual Console Setup... alice # [ 2.978549] systemd-vconsole-setup[158]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 2.980967] systemd[1]: Finished Virtual Console Setup. bob # [ 3.341605] systemd[1]: Mounting /sysroot/nix/.ro-store... bob # [ 3.348835] systemd[1]: Mounting /sysroot/nix/.rw-store... alice # [ 3.340255] systemd[1]: Mounting /sysroot/nix/store... clare # [ 3.309996] systemd[1]: Mounting /sysroot/nix/.ro-store... bob # [ 3.356278] systemd[1]: Mounting /sysroot/run... clare # [ 3.321722] systemd[1]: Mounting /sysroot/nix/.rw-store... clare # [ 3.324249] systemd[1]: Mounting /sysroot/run... bob # [ 3.371745] systemd[1]: Mounting /sysroot/tmp/shared... clare # [ 3.336284] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 3.393068] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 3.358518] systemd[1]: Mounting /sysroot/tmp/xchg... alice # [ 3.391875] systemd[1]: Mounted /sysroot/nix/store. alice # [ 3.394822] systemd[1]: Reached target Initrd File Systems. alice # [ 3.397658] systemd[1]: Starting Find NixOS closure... alice # [ 3.404245] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 3.416539] systemd[1]: Mounted /sysroot/nix/.ro-store. clare # [ 3.382559] systemd[1]: Mounted /sysroot/nix/.ro-store. bob # [ 3.427173] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 3.429465] systemd[1]: Mounted /sysroot/run. bob # [ 3.430315] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 3.389400] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 3.440269] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.399855] systemd[1]: Mounted /sysroot/run. bob # [ 3.444845] systemd[1]: Mounted /sysroot/tmp/xchg. clare # [ 3.403317] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 3.405772] systemd[1]: Mounted /sysroot/tmp/xchg. clare # [ 3.408857] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.445468] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. alice # [ 3.451822] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob # [ 3.462829] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 3.464034] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.426741] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.427883] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 3.462129] systemd[1]: Finished Find NixOS closure. alice # [ 3.464188] systemd[1]: Reached target Initrd Default Target. alice # [ 3.466534] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 3.499966] systemd[1]: Stopped target Initrd Default Target. alice # [ 3.501639] systemd[1]: Stopped target Basic System. alice # [ 3.504255] systemd[1]: Stopped target Initrd Root Device. alice # [ 3.505139] systemd[1]: Stopped target Path Units. alice # [ 3.506503] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 3.509960] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. alice # [ 3.511743] systemd[1]: Stopped target Slice Units. alice # [ 3.516165] systemd[1]: Stopped target Socket Units. alice # [ 3.516900] systemd[1]: Stopped target System Initialization. alice # [ 3.517673] systemd[1]: Stopped target Swaps. alice # [ 3.518291] systemd[1]: Stopped target Timer Units. alice # [ 3.518972] systemd[1]: dbus.socket: Deactivated successfully. alice # [ 3.519912] systemd[1]: Closed D-Bus System Message Bus Socket. alice # [ 3.524321] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. alice # [ 3.527598] systemd[1]: Stopped Find NixOS closure. alice # [ 3.529953] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.532647] systemd[1]: systemd-sysctl.service: Deactivated successfully. alice # [ 3.541871] systemd[1]: Stopped Apply Kernel Variables. alice # [ 3.542548] systemd[1]: systemd-modules-load.service: Deactivated successfully. alice # [ 3.543572] systemd[1]: Stopped Load Kernel Modules. alice # [ 3.546411] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 3.547259] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. alice # [ 3.551017] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. alice # [ 3.552899] systemd[1]: Stopped Create System Files and Directories. alice # [ 3.554928] systemd[1]: Stopped target Local File Systems. alice # [ 3.555730] systemd[1]: Stopped target Preparation for Local File Systems. alice # [ 3.560329] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 3.561002] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 3.561540] systemd[1]: Stopping Rule-based Manager for Device Events and Files... alice # [ 3.562227] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.562913] systemd[1]: Stopped Virtual Console Setup. alice # [ 3.563540] systemd[1]: initrd-cleanup.service: Deactivated successfully. alice # [ 3.565013] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. alice # [ 3.565642] systemd[1]: systemd-udevd.service: Deactivated successfully. alice # [ 3.566256] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 3.566919] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.567699] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 3.568334] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 3.568984] systemd[1]: Closed udev Control Socket. alice # [ 3.569461] systemd[1]: Starting Cleanup udev Database... alice # [ 3.569970] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. alice # [ 3.570643] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 3.571224] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. alice # [ 3.574768] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. alice # [ 3.580126] systemd[1]: kmod-static-nodes.service: Deactivated successfully. alice # [ 3.580993] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 3.591248] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. alice # [ 3.591964] systemd[1]: Finished Cleanup udev Database. alice # [ 3.596296] systemd[1]: Reached target Switch Root. alice # [ 3.596756] systemd[1]: Starting NixOS Activation... alice # [ 3.661369] initrd-nixos-activation-start[181]: booting system configuration /nix/store/8cqrj5mhhvi07l6nv3iqrg03b1aa7iiv-nixos-system-alice-test alice # [ 3.688582] initrd-nixos-activation-start[181]: running activation script... alice # [ 3.897848] initrd-nixos-activation-start[204]: setting up /etc... alice # [ 3.971590] initrd-nixos-activation-start[204]: setting up age secrets... alice # [ 4.020258] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. alice # [ 4.022048] systemd[1]: Finished NixOS Activation. alice # [ 4.022784] systemd[1]: Starting Switch Root... alice # [ 4.036285] systemd[1]: Switching root. alice # [ 4.206233] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). bob # [ 4.339552] systemd[1]: Mounting /sysroot/nix/store... clare # [ 4.307778] systemd[1]: Mounting /sysroot/nix/store... bob # [ 4.390477] systemd[1]: Mounted /sysroot/nix/store. bob # [ 4.392960] systemd[1]: Reached target Initrd File Systems. bob # [ 4.396330] systemd[1]: Starting Find NixOS closure... clare # [ 4.359978] systemd[1]: Mounted /sysroot/nix/store. clare # [ 4.363705] systemd[1]: Reached target Initrd File Systems. bob # [ 4.408334] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... clare # [ 4.368253] systemd[1]: Starting Find NixOS closure... clare # [ 4.370845] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 4.448783] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. clare # [ 4.408111] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. clare # [ 4.409447] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob # [ 4.451578] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. clare # [ 4.423266] systemd[1]: Finished Find NixOS closure. bob # [ 4.466163] systemd[1]: Finished Find NixOS closure. clare # [ 4.425308] systemd[1]: Reached target Initrd Default Target. bob # [ 4.468154] systemd[1]: Reached target Initrd Default Target. clare # [ 4.426463] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... bob # [ 4.470162] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... bob # [ 4.495564] systemd[1]: Stopped target Initrd Default Target. bob # [ 4.497110] systemd[1]: Stopped target Basic System. bob # [ 4.498011] systemd[1]: Stopped target Initrd Root Device. clare # [ 4.455811] systemd[1]: initrd-cleanup.service: Deactivated successfully. clare # [ 4.457209] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. bob # [ 4.500264] systemd[1]: Stopped target Path Units. bob # [ 4.500948] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. clare # [ 4.460632] systemd[1]: Stopped target Initrd Default Target. bob # [ 4.503599] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. clare # [ 4.463190] systemd[1]: Stopped target Basic System. bob # [ 4.505647] systemd[1]: Stopped target Slice Units. clare # [ 4.463842] systemd[1]: Stopped target Initrd Root Device. clare # [ 4.464660] systemd[1]: Stopped target Path Units. bob # [ 4.508110] systemd[1]: Stopped target Socket Units. clare # [ 4.465728] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. bob # [ 4.508724] systemd[1]: Stopped target System Initialization. bob # [ 4.509455] systemd[1]: Stopped target Swaps. bob # [ 4.510633] systemd[1]: Stopped target Timer Units. clare # [ 4.469085] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. bob # [ 4.512191] systemd[1]: dbus.socket: Deactivated successfully. clare # [ 4.471711] systemd[1]: Stopped target Slice Units. bob # [ 4.516226] systemd[1]: Closed D-Bus System Message Bus Socket. clare # [ 4.476145] systemd[1]: Stopped target Socket Units. bob # [ 4.518125] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. clare # [ 4.476709] systemd[1]: Stopped target System Initialization. clare # [ 4.477376] systemd[1]: Stopped target Swaps. clare # [ 4.477909] systemd[1]: Stopped target Timer Units. clare # [ 4.478489] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 4.520922] systemd[1]: Stopped Find NixOS closure. clare # [ 4.479179] systemd[1]: Closed D-Bus System Message Bus Socket. clare # [ 4.479784] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 4.523600] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 4.484941] systemd[1]: Stopped Find NixOS closure. clare # [ 4.485499] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 4.486135] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 4.528613] systemd[1]: systemd-sysctl.service: Deactivated successfully. clare # [ 4.486893] systemd[1]: Stopped Apply Kernel Variables. bob # [ 4.529393] systemd[1]: Stopped Apply Kernel Variables. clare # [ 4.487463] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 4.529911] systemd[1]: systemd-modules-load.service: Deactivated successfully. clare # [ 4.488276] systemd[1]: Stopped Load Kernel Modules. bob # [ 4.530610] systemd[1]: Stopped Load Kernel Modules. clare # [ 4.488793] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. bob # [ 4.531092] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. clare # [ 4.489567] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. bob # [ 4.531834] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 4.490339] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. clare # [ 4.491120] systemd[1]: Stopped Create System Files and Directories. clare # [ 4.491758] systemd[1]: Stopped target Local File Systems. bob # [ 4.541052] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. clare # [ 4.499470] systemd[1]: Stopped target Preparation for Local File Systems. bob # [ 4.543946] systemd[1]: Stopped Create System Files and Directories. clare # [ 4.502268] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. bob # [ 4.544834] systemd[1]: Stopped target Local File Systems. clare # [ 4.504291] systemd[1]: Stopped Coldplug All udev Devices. bob # [ 4.546741] systemd[1]: Stopped target Preparation for Local File Systems. bob # [ 4.548448] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. clare # [ 4.506470] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 4.549437] systemd[1]: Stopped Coldplug All udev Devices. clare # [ 4.507422] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 4.508847] systemd[1]: Stopped Virtual Console Setup. clare # [ 4.509737] systemd[1]: systemd-udevd.service: Deactivated successfully. bob # [ 4.552235] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 4.553117] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 4.510882] systemd[1]: Stopped Rule-based Manager for Device Events and Files. bob # [ 4.553756] systemd[1]: Stopped Virtual Console Setup. clare # [ 4.511903] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. bob # [ 4.554425] systemd[1]: initrd-cleanup.service: Deactivated successfully. clare # [ 4.512694] systemd[1]: Closed udev Control Socket. clare # [ 4.513143] systemd[1]: Starting Cleanup udev Database... bob # [ 4.555410] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. clare # [ 4.513621] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 4.556123] systemd[1]: systemd-udevd.service: Deactivated successfully. clare # [ 4.514275] systemd[1]: Stopped Create Static Device Nodes in /dev. bob # [ 4.556706] systemd[1]: Stopped Rule-based Manager for Device Events and Files. clare # [ 4.514927] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. bob # [ 4.557323] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. bob # [ 4.557929] systemd[1]: Closed udev Control Socket. clare # [ 4.515617] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. bob # [ 4.558368] systemd[1]: Starting Cleanup udev Database... bob # [ 4.558837] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 4.559481] systemd[1]: Stopped Create Static Device Nodes in /dev. bob # [ 4.560076] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. bob # [ 4.560776] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. clare # [ 4.519127] systemd[1]: kmod-static-nodes.service: Deactivated successfully. bob # [ 4.561389] systemd[1]: kmod-static-nodes.service: Deactivated successfully. bob # [ 4.561982] systemd[1]: Stopped Create List of Static Device Nodes. bob # [ 4.562518] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 4.563116] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 4.524112] systemd[1]: Stopped Create List of Static Device Nodes. clare # [ 4.524890] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 4.525523] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 4.536424] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. clare # [ 4.537126] systemd[1]: Finished Cleanup udev Database. bob # [ 4.580783] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 4.582581] systemd[1]: Finished Cleanup udev Database. bob # [ 4.583106] systemd[1]: Reached target Switch Root. clare # [ 4.541294] systemd[1]: Reached target Switch Root. clare # [ 4.541778] systemd[1]: Starting NixOS Activation... bob # [ 4.584806] systemd[1]: Starting NixOS Activation... clare # [ 4.612340] initrd-nixos-activation-start[172]: booting system configuration /nix/store/pypphm6vxi6yw5aad522jmihx38f8bdi-nixos-system-clare-test bob # [ 4.660676] initrd-nixos-activation-start[172]: booting system configuration /nix/store/a2nyxfw3nc3jfy7lcza1q7ykcvv1dxrq-nixos-system-bob-test clare # [ 4.639525] initrd-nixos-activation-start[172]: running activation script... bob # [ 4.687530] initrd-nixos-activation-start[172]: running activation script... clare # [ 4.862711] initrd-nixos-activation-start[195]: setting up /etc... bob # [ 4.927416] initrd-nixos-activation-start[195]: setting up /etc... clare # [ 4.937236] initrd-nixos-activation-start[195]: setting up age secrets... alice # [ 4.975029] 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.975150] systemd[1]: Detected virtualization qemu. alice # [ 4.975210] systemd[1]: Detected architecture arm64. alice # [ 4.975340] systemd[1]: Detected first boot. alice # [ 4.977512] systemd[1]: Initializing machine ID from random generator. bob # [ 5.018729] initrd-nixos-activation-start[195]: setting up age secrets... clare # [ 4.994491] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. clare # [ 4.996176] systemd[1]: Finished NixOS Activation. clare # [ 4.997062] systemd[1]: Starting Switch Root... clare # [ 5.016666] systemd[1]: Switching root. bob # [ 5.088521] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 5.090285] systemd[1]: Finished NixOS Activation. bob # [ 5.091043] systemd[1]: Starting Switch Root... bob # [ 5.104381] systemd[1]: Switching root. alice # [ 5.120371] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 5.188762] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). alice # [ 5.265754] systemd[1]: Applying preset policy. bob # [ 5.281857] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). alice # [ 5.454682] systemd[1]: Populated /etc with preset unit settings. alice # [ 5.677134] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 5.677443] systemd[1]: Stopped initrd-switch-root.service. alice # [ 5.681352] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. alice # [ 5.684163] systemd[1]: Created slice Slice /system/getty. alice # [ 5.685197] systemd[1]: Created slice User and Session Slice. alice # [ 5.685829] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 5.688545] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 5.689118] systemd[1]: Expecting device /dev/hvc0... alice # [ 5.689719] systemd[1]: Expecting device /dev/ttyAMA0... alice # [ 5.692260] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 5.692795] systemd[1]: Stopped target initrd-fs.target. alice # [ 5.693395] systemd[1]: Stopped target initrd-root-fs.target. alice # [ 5.696309] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 5.696821] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 5.697421] systemd[1]: Reached target Path Units. alice # [ 5.699896] systemd[1]: Reached target Remote File Systems. alice # [ 5.700395] systemd[1]: Reached target Slice Units. alice # [ 5.700985] systemd[1]: Reached target Swaps. alice # [ 5.704588] systemd[1]: Listening on Query the User Interactively for a Password. alice # [ 5.707501] systemd[1]: Listening on Process Core Dump Socket. alice # [ 5.709138] systemd[1]: Listening on Credential Encryption/Decryption. alice # [ 5.712249] systemd[1]: Listening on Factory Reset Management. alice # [ 5.712848] systemd[1]: Listening on Hostname Service Socket. alice # [ 5.716458] systemd[1]: Starting Journal Log Access Socket... alice # [ 5.717600] systemd[1]: Listening on Journal Audit Socket. clare # [ 5.676851] 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 # [ 5.676945] systemd[1]: Detected virtualization qemu. clare # [ 5.677004] systemd[1]: Detected architecture arm64. clare # [ 5.677118] systemd[1]: Detected first boot. clare # [ 5.679238] systemd[1]: Initializing machine ID from random generator. alice # [ 5.722366] systemd[1]: Listening on Console Output Muting Service Socket. alice # [ 5.723019] systemd[1]: Listening on Network Management Metrics Varlink Socket. alice # [ 5.723694] systemd[1]: Listening on Network Management Varlink Socket. alice # [ 5.724337] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 5.725009] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. alice # [ 5.725553] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 5.729972] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki alice # [ 5.733307] systemd[1]: Listening on Disk Repartitioning Service Socket. alice # [ 5.733865] systemd[1]: Listening on Resolve Monitor Varlink Socket. alice # [ 5.736189] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 5.736763] systemd[1]: Listening on udev Control Socket. alice # [ 5.737350] systemd[1]: Listening on udev Varlink Socket. alice # [ 5.741364] systemd[1]: Mounting Huge Pages File System... alice # [ 5.744188] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 5.753387] systemd[1]: Mounting Kernel Debug File System... alice # [ 5.763267] systemd[1]: Mounting Kernel Trace File System... alice # [ 5.772168] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 5.773750] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 5.800797] systemd[1]: Mounting Kernel Configuration File System... alice # [ 5.801179] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 5.801488] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore alice # [ 5.801765] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 5.825411] 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 # [ 5.819483] systemd[1]: Mounting FUSE Control File System... alice # [ 5.819905] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 bob # [ 5.832130] systemd[1]: Detected virtualization qemu. bob # [ 5.832822] systemd[1]: Detected architecture arm64. bob # [ 5.833458] systemd[1]: Detected first boot. bob # [ 5.839302] systemd[1]: Initializing machine ID from random generator. alice # [ 5.838187] systemd[1]: Starting Journal Service... alice # [ 5.844809] systemd[1]: Starting Load Kernel Modules... clare # [ 5.827365] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 5.868680] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... alice # [ 5.888424] systemd[1]: Starting Remount Root and Kernel File Systems... alice # [ 5.888812] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 5.893482] systemd[1]: Starting Coldplug All udev Devices... alice # [ 5.896108] systemd[1]: Listening on Journal Log Access Socket. alice # [ 5.896528] systemd[1]: Mounted Huge Pages File System. alice # [ 5.896905] systemd[1]: Mounted POSIX Message Queue File System. alice # [ 5.897292] systemd[1]: Mounted Kernel Debug File System. alice # [ 5.897654] systemd[1]: Mounted Kernel Trace File System. alice # [ 5.919082] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 5.920723] systemd-journald[289]: Collecting audit messages is enabled. alice # [ 5.936710] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 5.938833] systemd[1]: Queued start job for default target Multi-User System. alice # [ 5.944948] systemd[1]: Started Journal Service. alice # [ 5.942979] systemd[1]: systemd-journald.service: Deactivated successfully. alice # [ 5.943950] systemd-modules-load[290]: Module 'atkbd' is built in alice # [ 5.953009] systemd-modules-load[290]: Module 'loop' is built in alice # [ 5.953699] systemd[1]: Finished Load Kernel Modules. bob # [ 5.976264] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 5.945030] systemd[1]: Applying preset policy. alice # [ 5.987408] EXT4-fs (vda): re-mounted 1d3ce35a-71b9-45f8-b73f-e763415b48b4. alice # [ 5.992606] systemd[1]: Starting Firewall... alice # [ 5.994325] systemd[1]: Starting Apply Kernel Variables... alice # [ 5.994871] systemd[1]: Mounted FUSE Control File System. alice # [ 5.996534] systemd[1]: Finished Remount Root and Kernel File Systems. alice # [ 5.997147] systemd[1]: Mounted Kernel Configuration File System. alice # [ 6.001573] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 6.013223] systemd[1]: Starting Flush Journal to Persistent Storage... alice # [ 6.013929] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore alice # [ 6.020593] systemd[1]: Starting Load/Save OS Random Seed... alice # [ 6.021144] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 6.029572] systemd-oomd[292]: No swap; memory pressure usage will be degraded alice # [ 6.037267] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 6.057030] systemd[1]: Applying preset policy. alice # [ 6.118899] systemd-journald[289]: Received client request to flush runtime journal. clare # [ 6.129109] systemd[1]: Populated /etc with preset unit settings. alice # [ 6.161276] systemd[1]: Finished Load/Save OS Random Seed. alice # [ 6.161884] systemd[1]: Reached target First Boot Complete. alice # [ 6.170442] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 6.171133] systemd[1]: Finished Apply Kernel Variables. alice # [ 6.177291] systemd[1]: Starting Network Name Resolution... alice # [ 6.179075] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 6.179705] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 6.231461] systemd[1]: Populated /etc with preset unit settings. alice # [ 6.325908] systemd-resolved[319]: Positive Trust Anchors: alice # [ 6.328785] systemd-resolved[319]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d alice # [ 6.329676] systemd-resolved[319]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 alice # [ 6.330528] systemd-resolved[319]: 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.347431] systemd[1]: initrd-switch-root.service: Deactivated successfully. clare # [ 6.347679] systemd[1]: Stopped initrd-switch-root.service. clare # [ 6.350447] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. clare # [ 6.351684] systemd[1]: Created slice Slice /system/getty. clare # [ 6.354051] systemd[1]: Created slice User and Session Slice. clare # [ 6.354643] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 6.355233] systemd[1]: Started Forward Password Requests to Wall Directory Watch. clare # [ 6.355791] systemd[1]: Expecting device /dev/hvc0... clare # [ 6.356349] systemd[1]: Expecting device /dev/ttyAMA0... clare # [ 6.359684] systemd[1]: Reached target Local Encrypted Volumes. clare # [ 6.360161] systemd[1]: Stopped target initrd-fs.target. clare # [ 6.362023] systemd[1]: Stopped target initrd-root-fs.target. clare # [ 6.362487] systemd[1]: Stopped target initrd-switch-root.target. clare # [ 6.363039] systemd[1]: Reached target Virtual Machines and Containers. clare # [ 6.363607] systemd[1]: Reached target Path Units. clare # [ 6.364144] systemd[1]: Reached target Remote File Systems. clare # [ 6.367216] systemd[1]: Reached target Slice Units. clare # [ 6.367675] systemd[1]: Reached target Swaps. clare # [ 6.370690] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 6.373397] systemd[1]: Listening on Process Core Dump Socket. clare # [ 6.374944] systemd[1]: Listening on Credential Encryption/Decryption. clare # [ 6.377667] systemd[1]: Listening on Factory Reset Management. clare # [ 6.378206] systemd[1]: Listening on Hostname Service Socket. clare # [ 6.381830] systemd[1]: Starting Journal Log Access Socket... clare # [ 6.383072] systemd[1]: Listening on Journal Audit Socket. clare # [ 6.386132] systemd[1]: Listening on Console Output Muting Service Socket. clare # [ 6.386806] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 6.387516] systemd[1]: Listening on Network Management Varlink Socket. clare # [ 6.388154] systemd[1]: Listening on Network Management Netlink Socket. clare # [ 6.391393] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. clare # [ 6.391854] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os clare # [ 6.394470] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki clare # [ 6.399072] systemd[1]: Listening on Disk Repartitioning Service Socket. clare # [ 6.399574] systemd[1]: Listening on Resolve Monitor Varlink Socket. clare # [ 6.400104] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 6.429760] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 6.430404] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 6.402068] systemd[1]: Listening on udev Control Socket. clare # [ 6.402628] systemd[1]: Listening on udev Varlink Socket. bob # [ 6.448894] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 6.449169] systemd[1]: Stopped initrd-switch-root.service. clare # [ 6.405588] systemd[1]: Mounting Huge Pages File System... alice # [ 6.437009] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 6.407781] systemd[1]: Mounting POSIX Message Queue File System... bob # [ 6.452290] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 6.454598] systemd[1]: Created slice Slice /system/getty. bob # [ 6.455598] systemd[1]: Created slice User and Session Slice. bob # [ 6.456178] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 6.456768] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 6.457359] systemd[1]: Expecting device /dev/hvc0... bob # [ 6.460984] systemd[1]: Expecting device /dev/ttyAMA0... bob # [ 6.461476] systemd[1]: Reached target Local Encrypted Volumes. bob # [ 6.463500] systemd[1]: Stopped target initrd-fs.target. bob # [ 6.463990] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 6.464697] systemd[1]: Stopped target initrd-switch-root.target. bob # [ 6.465253] systemd[1]: Reached target Virtual Machines and Containers. clare # [ 6.422918] systemd[1]: Mounting Kernel Debug File System... bob # [ 6.468182] systemd[1]: Reached target Path Units. bob # [ 6.468660] systemd[1]: Reached target Remote File Systems. bob # [ 6.469191] systemd[1]: Reached target Slice Units. clare # [ 6.426061] systemd[1]: Mounting Kernel Trace File System... bob # [ 6.471411] systemd[1]: Reached target Swaps. bob # [ 6.473430] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 6.431397] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 6.476541] systemd[1]: Listening on Process Core Dump Socket. clare # [ 6.433727] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 6.478611] systemd[1]: Listening on Credential Encryption/Decryption. bob # [ 6.480330] systemd[1]: Listening on Factory Reset Management. bob # [ 6.480872] systemd[1]: Listening on Hostname Service Socket. bob # [ 6.485220] systemd[1]: Starting Journal Log Access Socket... bob # [ 6.487410] systemd[1]: Listening on Journal Audit Socket. clare # [ 6.445609] systemd[1]: Mounting Kernel Configuration File System... clare # [ 6.446811] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm bob # [ 6.490116] systemd[1]: Listening on Console Output Muting Service Socket. bob # [ 6.490831] systemd[1]: Listening on Network Management Metrics Varlink Socket. bob # [ 6.491530] systemd[1]: Listening on Network Management Varlink Socket. bob # [ 6.492184] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 6.492888] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 6.493414] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os clare # [ 6.452564] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 6.453000] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 6.498120] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki bob # [ 6.503449] systemd[1]: Listening on Disk Repartitioning Service Socket. bob # [ 6.503994] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 6.504537] systemd[1]: Listening on Resolve Service Varlink Socket. bob # [ 6.505088] systemd[1]: Listening on udev Control Socket. bob # [ 6.508079] systemd[1]: Listening on udev Varlink Socket. clare # [ 6.464652] systemd[1]: Mounting FUSE Control File System... clare # [ 6.465041] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 bob # [ 6.510721] systemd[1]: Mounting Huge Pages File System... bob # [ 6.512890] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 6.503565] systemd-resolved[319]: Using system hostname 'alice'. alice # [ 6.507089] systemd[1]: Started Network Name Resolution. bob # [ 6.521892] systemd[1]: Mounting Kernel Debug File System... clare # [ 6.488944] systemd[1]: Starting Journal Service... bob # [ 6.534709] systemd[1]: Mounting Kernel Trace File System... bob # [ 6.539630] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 6.542219] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 6.534432] systemd[1]: Finished Coldplug All udev Devices. clare # [ 6.505113] systemd[1]: Starting Load Kernel Modules... bob # [ 6.552359] systemd[1]: Mounting Kernel Configuration File System... bob # [ 6.554308] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm bob # [ 6.562514] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore bob # [ 6.563027] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 6.524801] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... alice # [ 6.556610] systemd-udevd[328]: Using default interface naming scheme 'v261'. bob # [ 6.584373] systemd[1]: Mounting FUSE Control File System... bob # [ 6.584805] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 clare # [ 6.541411] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 6.541812] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 6.553014] systemd[1]: Starting Coldplug All udev Devices... clare # [ 6.554905] systemd[1]: Listening on Journal Log Access Socket. clare # [ 6.555340] systemd[1]: Mounted Huge Pages File System. clare # [ 6.555691] systemd[1]: Mounted POSIX Message Queue File System. clare # [ 6.556016] systemd[1]: Mounted Kernel Debug File System. bob # [ 6.616248] systemd[1]: Starting Journal Service... clare # [ 6.574568] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 6.575237] systemd[1]: Mounted Kernel Trace File System. clare # [ 6.575758] systemd[1]: Finished Load Kernel Modules. alice # [ 6.608237] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 6.626861] systemd[1]: Starting Load Kernel Modules... clare # [ 6.585847] systemd-journald[280]: Collecting audit messages is enabled. clare # [ 6.589954] systemd[1]: Queued start job for default target Multi-User System. clare # [ 6.596639] systemd[1]: systemd-journald.service: Deactivated successfully. clare # [ 6.597431] systemd-modules-load[281]: Module 'atkbd' is built in bob # [ 6.642112] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 6.598065] systemd-modules-load[281]: Module 'loop' is built in bob # [ 6.649066] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 6.606958] systemd[1]: Starting Firewall... bob # [ 6.649465] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 6.664805] systemd[1]: Starting Coldplug All udev Devices... clare # [ 6.622312] systemd[1]: Starting Apply Kernel Variables... bob # [ 6.671184] systemd[1]: Listening on Journal Log Access Socket. bob # [ 6.671694] systemd[1]: Mounted Huge Pages File System. bob # [ 6.672070] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 6.672417] systemd[1]: Mounted Kernel Debug File System. bob # [ 6.672763] systemd[1]: Mounted Kernel Trace File System. clare # [ 6.632639] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 6.633177] systemd[1]: Started Journal Service. clare # [ 6.634245] systemd[1]: Mounted Kernel Configuration File System. bob # [ 6.685974] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 6.686607] systemd[1]: Mounted Kernel Configuration File System. clare # [ 6.649113] EXT4-fs (vda): re-mounted 1f7ec755-49cf-45bb-b9f7-48001bdbae15. alice # [ 6.681247] systemd[1]: Mounting /run/wrappers... bob # [ 6.702257] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 6.665250] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 6.723162] systemd-journald[288]: Collecting audit messages is enabled. clare # [ 6.678884] systemd-oomd[282]: No swap; memory pressure usage will be degraded bob # [ 6.727897] systemd[1]: Finished Load Kernel Modules. clare # [ 6.683598] systemd[1]: Listening on Disk Image Download Service Socket. bob # [ 6.731426] systemd[1]: Queued start job for default target Multi-User System. clare # [ 6.693663] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 6.738402] systemd[1]: Starting Firewall... clare # [ 6.694303] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 6.744944] systemd[1]: Starting Apply Kernel Variables... bob # [ 6.741431] systemd[1]: systemd-journald.service: Deactivated successfully. bob # [ 6.742213] systemd-modules-load[289]: Module 'atkbd' is built in bob # [ 6.742882] systemd-modules-load[289]: Module 'loop' is built in clare # [ 6.702372] systemd[1]: Starting Load/Save OS Random Seed... clare # [ 6.702935] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 6.703636] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 6.750272] systemd[1]: Started Journal Service. alice # [ 6.741137] systemd[1]: Mounted /run/wrappers. alice # [ 6.741944] systemd[1]: Reached target Local File Systems. bob # [ 6.755761] systemd[1]: Mounted FUSE Control File System. alice # [ 6.746269] systemd[1]: Listening on Boot Loader Control Service Socket. alice # [ 6.749612] systemd[1]: Starting register-nix-paths.service... alice # [ 6.754278] systemd[1]: Starting Create SUID/SGID Wrappers... bob # [ 6.765740] EXT4-fs (vda): re-mounted afcb5a73-ee4d-4d2a-b291-be171a5f7d71. alice # [ 6.755525] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. alice # [ 6.759609] systemd[1]: Starting Save Transient machine-id to Disk... clare # [ 6.734352] systemd[1]: Mounted FUSE Control File System. bob # [ 6.779987] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 6.785579] systemd[1]: Listening on Disk Image Download Service Socket. bob # [ 6.797301] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 6.797936] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 6.799060] systemd-oomd[290]: No swap; memory pressure usage will be degraded alice # [ 6.790873] systemd[1]: Starting Create System Files and Directories... clare # [ 6.766033] systemd-journald[280]: Received client request to flush runtime journal. alice # [ 6.796479] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 6.811368] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 6.824618] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 6.825270] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 6.826002] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 6.873643] systemd-journald[288]: Received client request to flush runtime journal. clare # [ 6.848381] systemd[1]: Finished Apply Kernel Variables. clare # [ 6.850306] systemd[1]: Starting Network Name Resolution... clare # [ 6.850884] systemd[1]: Finished Load/Save OS Random Seed. clare # [ 6.851360] systemd[1]: Reached target First Boot Complete. clare # [ 6.868213] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 6.915184] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 6.923485] systemd[1]: Finished Load/Save OS Random Seed. alice # [ 6.914357] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. bob # [ 6.928186] systemd[1]: Reached target First Boot Complete. alice # [ 6.920207] systemd[1]: Finished Save Transient machine-id to Disk. clare # [ 6.889937] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 6.933316] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 6.938406] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 6.939027] systemd[1]: Finished Apply Kernel Variables. clare # [ 6.900780] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 6.945013] systemd[1]: Starting Network Name Resolution... alice # [ 6.944620] systemd[1]: Finished Create System Files and Directories. alice # [ 6.950083] systemd[1]: Starting Rebuild Journal Catalog... alice # [ 6.952952] systemd[1]: Starting Record System Boot/Shutdown in UTMP... clare # [ 6.964851] systemd-resolved[307]: Positive Trust Anchors: clare # [ 6.965428] systemd-resolved[307]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d clare # [ 6.966285] systemd-resolved[307]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 clare # [ 6.967133] systemd-resolved[307]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test bob # [ 7.060955] systemd-resolved[319]: Positive Trust Anchors: bob # [ 7.061542] systemd-resolved[319]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d bob # [ 7.062398] systemd-resolved[319]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 bob # [ 7.063238] systemd-resolved[319]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 7.065897] systemd[1]: Finished Record System Boot/Shutdown in UTMP. alice # [ 7.081769] systemd[1]: Finished Rebuild Journal Catalog. alice # [ 7.089321] systemd[1]: Starting Update is Completed... alice # [ 7.091551] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. alice # [ 7.145098] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. alice # [ 7.156244] systemd[1]: Finished Update is Completed. bob # [ 7.197509] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 7.198380] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 7.156840] systemd-resolved[307]: Using system hostname 'clare'. clare # [ 7.157982] systemd[1]: Finished Coldplug All udev Devices. clare # [ 7.160749] systemd[1]: Started Network Name Resolution. bob # [ 7.204151] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 7.164241] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 7.165000] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 7.168986] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 7.226738] systemd-udevd[324]: Using default interface naming scheme 'v261'. bob # [ 7.272211] systemd-resolved[319]: Using system hostname 'bob'. bob # [ 7.275432] systemd[1]: Started Network Name Resolution. bob # [ 7.295106] systemd[1]: Finished Coldplug All udev Devices. bob # [ 7.319903] systemd-udevd[326]: Using default interface naming scheme 'v261'. clare # [ 7.280101] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 7.376575] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 7.384545] (udev-worker)[349]: Network interface NamePolicy= disabled on kernel command line. clare # [ 7.352579] systemd[1]: Mounting /run/wrappers... alice # [ 7.411535] (udev-worker)[358]: Network interface NamePolicy= disabled on kernel command line. clare # [ 7.402299] systemd[1]: Mounted /run/wrappers. clare # [ 7.403094] systemd[1]: Reached target Local File Systems. bob # [ 7.449389] systemd[1]: Mounting /run/wrappers... clare # [ 7.407210] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 7.411497] systemd[1]: Starting register-nix-paths.service... clare # [ 7.414779] systemd[1]: Starting Create SUID/SGID Wrappers... clare # [ 7.415334] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. clare # [ 7.419171] systemd[1]: Starting Save Transient machine-id to Disk... clare # [ 7.424343] systemd[1]: Starting Create System Files and Directories... clare # [ 7.424948] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 7.454497] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 7.503559] systemd[1]: Mounted /run/wrappers. bob # [ 7.504311] systemd[1]: Reached target Local File Systems. bob # [ 7.508253] systemd[1]: Listening on Boot Loader Control Service Socket. bob # [ 7.512471] systemd[1]: Starting register-nix-paths.service... bob # [ 7.516139] systemd[1]: Starting Create SUID/SGID Wrappers... bob # [ 7.518334] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. bob # [ 7.532123] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 7.546935] systemd[1]: Starting Create System Files and Directories... bob # [ 7.547597] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 7.554396] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 7.562350] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. alice # [ 7.568482] systemd[1]: Finished Create SUID/SGID Wrappers. clare # [ 7.587784] systemd[1]: Finished Create System Files and Directories. clare # [ 7.596748] systemd[1]: Starting Rebuild Journal Catalog... clare # [ 7.604444] systemd[1]: Starting Record System Boot/Shutdown in UTMP... clare # [ 7.605116] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. bob # [ 7.650326] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. clare # [ 7.611656] systemd[1]: Finished Save Transient machine-id to Disk. bob # [ 7.658401] systemd[1]: Finished Save Transient machine-id to Disk. alice # [ 7.677960] mousedev: PS/2 mouse device common for all mice bob # [ 7.707299] systemd[1]: Finished Create System Files and Directories. bob # [ 7.714292] systemd[1]: Starting Rebuild Journal Catalog... bob # [ 7.720200] systemd[1]: Starting Record System Boot/Shutdown in UTMP... alice # [ 7.722099] systemd[1]: Finished register-nix-paths.service. alice # [ 7.724238] systemd[1]: Reached target System Initialization. alice # [ 7.724837] systemd[1]: Started Discard unused filesystem blocks once a week. alice # [ 7.725469] systemd[1]: Started Daily Cleanup of Temporary Directories. alice # [ 7.726035] systemd[1]: Reached target Timer Units. alice # [ 7.732610] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 7.733256] systemd[1]: Listening on Nix Daemon Socket. alice # [ 7.733727] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 7.734430] systemd[1]: Reached target Socket Units. alice # [ 7.734875] systemd[1]: Reached target Basic System. alice # [ 7.735347] systemd[1]: Started backdoor.service. alice # [ 7.744172] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 7.744867] systemd[1]: Starting Name Service Cache Daemon (nsncd)... alice # [ 7.751167] systemd[1]: Starting Post-Boot Actions... clare # [ 7.721858] systemd[1]: Finished Record System Boot/Shutdown in UTMP. alice # [ 7.786756] systemd[1]: Started Reset console on configuration changes. clare # [ 7.762000] systemd[1]: Finished Rebuild Journal Catalog. clare # [ 7.770621] systemd[1]: Starting Update is Completed... clare # [ 7.771218] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. bob # [ 7.818764] systemd[1]: Finished Record System Boot/Shutdown in UTMP. alice # [ 7.821123] systemd[1]: Starting D-Bus System Message Bus... clare # [ 7.814693] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. bob # [ 7.860430] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 7.867838] systemd[1]: Starting Update is Completed... bob # [ 7.871165] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. alice # connecting to host... bob # [ 7.911545] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. alice # [ 7.909310] systemd[1]: Finished Post-Boot Actions. clare # [ 7.885008] systemd[1]: Finished Update is Completed. alice # [ 7.920507] systemd[1]: Started Name Service Cache Daemon (nsncd). alice # [ 7.921104] systemd[1]: Reached target Host and Network Name Lookups. alice # [ 7.921636] systemd[1]: Reached target User and Group Name Lookups. alice # [ 7.930852] nsncd[481]: Oct 15 15:16:07.746 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" alice # [ 7.938081] systemd[1]: Starting User Login Management... alice # [ 7.966926] systemd[1]: Finished Import lastlog data into lastlog2 database. bob # [ 7.981106] systemd[1]: Finished Update is Completed. alice # [ 8.048680] dbus-broker-launch[487]: Looking up NSS user entry for 'systemd-timesync'... alice # [ 8.061588] dbus-broker-launch[487]: NSS returned no entry for 'systemd-timesync' alice # [ 8.062278] dbus-broker-launch[487]: Invalid user-name in /nix/store/ah686xacysvqrh7xsqvibww49bg7wqpf-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 8.069634] systemd-logind[514]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) alice # [ 8.074899] systemd-logind[514]: Watching system buttons on /dev/input/event0 (gpio-keys) alice # [ 8.075607] systemd-logind[514]: New seat seat0. alice # [ 8.080187] systemd[1]: Started User Login Management. alice # [ 8.080669] systemd[1]: Starting linger-users.service... clare # [ 8.057314] (udev-worker)[350]: Network interface NamePolicy= disabled on kernel command line. clare # [ 8.071058] (udev-worker)[355]: Network interface NamePolicy= disabled on kernel command line. alice # [ 8.104097] systemd[1]: Started D-Bus System Message Bus. bob # [ 8.140375] (udev-worker)[348]: Network interface NamePolicy= disabled on kernel command line. bob # [ 8.156304] (udev-worker)[353]: Network interface NamePolicy= disabled on kernel command line. alice # [ 8.148672] dbus-broker-launch[487]: Ready alice # [ 8.165166] systemd[1]: linger-users.service: Deactivated successfully. alice # [ 8.167606] systemd[1]: Finished linger-users.service. alice # [ 8.222653] systemd[1]: Finished Firewall. alice # [ 8.224095] systemd[1]: Reached target Preparation for Network. alice # [ 8.227376] systemd[1]: Listening on Network Management Resolve Hook Socket. alice # [ 8.230622] systemd[1]: Starting Network Management... clare # [ 8.257276] mousedev: PS/2 mouse device common for all mice clare # [ 8.288975] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. clare # [ 8.291730] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 8.356870] mousedev: PS/2 mouse device common for all mice clare # [ 8.331887] systemd[1]: Finished register-nix-paths.service. clare # [ 8.333872] systemd[1]: Reached target System Initialization. clare # [ 8.334468] systemd[1]: Started Discard unused filesystem blocks once a week. clare # [ 8.335110] systemd[1]: Started Daily Cleanup of Temporary Directories. clare # [ 8.335695] systemd[1]: Reached target Timer Units. clare # [ 8.340281] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 8.346225] systemd[1]: Listening on harmonia binary cache socket. clare # [ 8.347716] systemd[1]: Listening on Nix Daemon Socket. clare # [ 8.350703] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. clare # [ 8.351445] systemd[1]: Reached target Socket Units. clare # [ 8.351908] systemd[1]: Reached target Basic System. bob # [ 8.396897] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. clare # [ 8.356278] systemd[1]: Started backdoor.service. clare # [ 8.356738] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 8.399970] systemd[1]: Finished Create SUID/SGID Wrappers. clare # [ 8.360379] systemd[1]: Starting Name Service Cache Daemon (nsncd)... alice # [ 8.410083] cfg80211: Loading compiled-in X.509 certificates for regulatory database clare # [ 8.389720] systemd[1]: Starting Post-Boot Actions... clare # [ 8.403538] systemd[1]: Started Reset console on configuration changes. bob # [ 8.451819] systemd[1]: Finished register-nix-paths.service. bob # [ 8.454170] systemd[1]: Reached target System Initialization. bob # [ 8.454709] systemd[1]: Started Discard unused filesystem blocks once a week. bob # [ 8.455313] systemd[1]: Started Daily Cleanup of Temporary Directories. bob # [ 8.455890] systemd[1]: Reached target Timer Units. alice # [ 8.454074] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' alice # [ 8.454594] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 8.462340] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 8.455945] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 bob # [ 8.462916] systemd[1]: Listening on Nix Daemon Socket. alice # [ 8.456332] cfg80211: failed to load regulatory.db bob # [ 8.463379] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. bob # [ 8.467792] systemd[1]: Reached target Socket Units. bob # [ 8.472505] systemd[1]: Reached target Basic System. bob # [ 8.473021] systemd[1]: Started backdoor.service. clare # [ 8.431265] systemd[1]: Starting D-Bus System Message Bus... bob # [ 8.473456] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 8.478031] systemd[1]: Starting Name Service Cache Daemon (nsncd)... alice # [ 8.479643] systemd-networkd[544]: lo: Link UP alice # [ 8.483478] systemd-networkd[544]: lo: Gained carrier bob # [ 8.495135] systemd[1]: Starting Post-Boot Actions... alice # [ 8.487473] systemd[1]: Started Network Management. alice # [ 8.493525] systemd[1]: Reached target Network. alice # [ 8.493994] systemd-networkd[544]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. alice # [ 8.498201] systemd-networkd[544]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 8.512293] systemd[1]: Started Reset console on configuration changes. alice # [ 8.506487] systemd-networkd[544]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 8.507258] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... alice # [ 8.507892] systemd-networkd[544]: eth0: Link UP clare # connecting to host... alice # [ 8.516567] systemd-networkd[544]: eth0: Gained carrier alice # [ 8.517061] systemd[1]: Starting Permit User Sessions... alice # [ 8.517519] systemd-networkd[544]: eth1: Link UP alice # [ 8.517922] systemd-networkd[544]: eth1: Gained carrier alice # [ 8.518397] systemd-networkd[544]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 8.530076] systemd-networkd[544]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 8.544130] systemd[1]: Starting D-Bus System Message Bus... clare # [ 8.526319] systemd[1]: Started Name Service Cache Daemon (nsncd). clare # [ 8.532987] nsncd[475]: Oct 15 15:16:08.349 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # [ 8.534264] systemd[1]: Finished Post-Boot Actions. alice # [ 8.578845] systemd[1]: Finished Permit User Sessions. clare # [ 8.547114] systemd[1]: Reached target Host and Network Name Lookups. clare # [ 8.547764] systemd[1]: Reached target User and Group Name Lookups. alice # [ 8.581967] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 8.552499] systemd[1]: Starting User Login Management... alice # [ 8.588212] systemd[1]: Started Getty on tty1. bob # connecting to host... alice # [ 8.589815] systemd[1]: Reached target Login Prompts. alice # [ 8.591863] systemd[1]: Reached target Multi-User System. alice # [ 8.600582] systemd[1]: Startup finished in 721ms (kernel) + 3.817s (initrd) + 4.060s (userspace) = 8.600s. clare # [ 8.586245] systemd[1]: Finished Import lastlog data into lastlog2 database. bob # [ 8.633623] systemd[1]: Started Name Service Cache Daemon (nsncd). bob # [ 8.636860] systemd[1]: Reached target Host and Network Name Lookups. bob # [ 8.637472] systemd[1]: Reached target User and Group Name Lookups. bob # [ 8.646849] nsncd[479]: Oct 15 15:16:08.450 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" bob # [ 8.655203] systemd[1]: Starting User Login Management... bob: Guest shell says: b'Spawning backdoor root shell...\n' bob # [ 8.682935] systemd[1]: Finished Import lastlog data into lastlog2 database. clare # [ 8.660619] dbus-broker-launch[483]: Looking up NSS user entry for 'systemd-timesync'... bob # [ 8.704327] systemd[1]: Finished Post-Boot Actions. clare # [ 8.673834] dbus-broker-launch[483]: NSS returned no entry for 'systemd-timesync' alice # [ 8.714683] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 clare # [ 8.674460] dbus-broker-launch[483]: Invalid user-name in /nix/store/q01akv3dn2hf5zpsaprwcv1lvz3q3c9f-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" clare # [ 8.679809] systemd-logind[500]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob: connected to guest root shell clare # [ 8.683100] systemd-logind[500]: Watching system buttons on /dev/input/event0 (gpio-keys) bob: (connecting took 8.98 seconds) clare # [ 8.687489] systemd-logind[500]: New seat seat0. bob: (finished: waiting for the VM to finish booting, in 8.98 seconds) clare # [ 8.688642] systemd[1]: Started User Login Management. clare # [ 8.691013] systemd[1]: Starting linger-users.service... clare # [ 8.714742] systemd[1]: Started D-Bus System Message Bus. bob # [ 8.789829] dbus-broker-launch[486]: Looking up NSS user entry for 'systemd-timesync'... clare # [ 8.750462] dbus-broker-launch[483]: Ready bob # [ 8.795137] systemd-logind[503]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 8.800178] systemd-logind[503]: Watching system buttons on /dev/input/event0 (gpio-keys) bob # [ 8.801075] systemd-logind[503]: New seat seat0. bob # [ 8.806355] systemd[1]: Started User Login Management. clare # [ 8.764464] systemd[1]: linger-users.service: Deactivated successfully. clare # [ 8.766066] systemd[1]: Finished linger-users.service. bob # [ 8.812055] systemd[1]: Starting linger-users.service... bob # [ 8.812566] dbus-broker-launch[486]: NSS returned no entry for 'systemd-timesync' bob # [ 8.813156] dbus-broker-launch[486]: Invalid user-name in /nix/store/xhwz9jrhs1b8k9i8nsalw3vdxy993zp4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" bob # [ 8.856736] systemd[1]: Started D-Bus System Message Bus. clare # [ 8.825518] systemd[1]: Finished Firewall. clare # [ 8.828200] systemd[1]: Reached target Preparation for Network. clare # [ 8.828780] systemd[1]: Listening on Network Management Resolve Hook Socket. clare # [ 8.833835] systemd[1]: Starting Network Management... bob # [ 8.887590] systemd[1]: linger-users.service: Deactivated successfully. bob # [ 8.891823] systemd[1]: Finished linger-users.service. bob # [ 8.897347] dbus-broker-launch[486]: Ready bob # [ 9.003783] systemd[1]: Finished Firewall. bob # [ 9.004343] systemd[1]: Reached target Preparation for Network. bob # [ 9.004867] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # [ 9.009617] systemd[1]: Starting Network Management... alice # [ 9.012712] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. alice # [ 9.016861] systemd[1]: Starting Virtual Console Setup... alice # [ 9.037738] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 9.039929] systemd[1]: Stopped Virtual Console Setup. clare # [ 9.011240] cfg80211: Loading compiled-in X.509 certificates for regulatory database alice # [ 9.041373] systemd[1]: Starting Virtual Console Setup... alice # [ 9.045997] systemd-logind[514]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) clare # [ 9.052005] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' clare # [ 9.053071] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' clare # [ 9.054704] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 clare # [ 9.055094] cfg80211: failed to load regulatory.db clare # [ 9.076172] systemd-networkd[539]: lo: Link UP clare # [ 9.077740] systemd-networkd[539]: lo: Gained carrier clare # [ 9.084168] systemd[1]: Started Network Management. clare # [ 9.084641] systemd[1]: Reached target Network. clare # [ 9.087510] systemd-networkd[539]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 9.094378] systemd-networkd[539]: eth1: Configuring with /etc/systemd/network/40-eth1.network. clare # [ 9.095080] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 9.101298] systemd-networkd[539]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. clare # [ 9.102139] systemd-networkd[539]: eth1: Link UP clare # [ 9.102561] systemd-networkd[539]: eth1: Gained carrier clare # [ 9.103015] systemd-networkd[539]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 9.111443] systemd[1]: Starting Permit User Sessions... clare # [ 9.111941] systemd-networkd[539]: eth0: Link UP alice # [ 9.145125] systemd-vconsole-setup[563]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 9.147933] systemd[1]: Finished Virtual Console Setup. clare # [ 9.119263] systemd-networkd[539]: eth0: Gained carrier clare # [ 9.124553] systemd-networkd[539]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 9.180518] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 9.203556] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' bob # [ 9.204073] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 9.207128] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 bob # [ 9.207508] cfg80211: failed to load regulatory.db clare # [ 9.168605] systemd[1]: Finished Permit User Sessions. clare # [ 9.171107] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 9.178237] systemd[1]: Started Getty on tty1. clare # [ 9.182054] systemd[1]: Reached target Login Prompts. clare # [ 9.183946] systemd[1]: Reached target Multi-User System. bob # [ 9.227463] systemd-networkd[555]: lo: Link UP clare # [ 9.187957] systemd[1]: Startup finished in 725ms (kernel) + 4.528s (initrd) + 3.929s (userspace) = 9.183s. bob # [ 9.231455] systemd-networkd[555]: lo: Gained carrier bob # [ 9.236687] systemd[1]: Started Network Management. bob # [ 9.237183] systemd[1]: Reached target Network. bob # [ 9.237566] systemd[1]: Reached target Network is Online. bob # [ 9.243864] systemd-networkd[555]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. bob # [ 9.248581] systemd[1]: Starting ncps binary cache proxy service... bob # [ 9.249146] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 9.250374] systemd-networkd[555]: eth1: Configuring with /etc/systemd/network/40-eth1.network. bob # [ 9.257882] systemd-networkd[555]: eth0: Link UP bob # [ 9.258349] systemd-networkd[555]: eth0: Gained carrier bob # [ 9.258789] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... bob # [ 9.264710] systemd-networkd[555]: eth1: Link UP bob # [ 9.265163] systemd-networkd[555]: eth1: Gained carrier bob # [ 9.265616] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 9.272706] systemd[1]: Starting Permit User Sessions... bob # [ 9.277460] systemd-networkd[555]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 9.353290] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. bob # [ 9.364749] systemd[1]: Finished Permit User Sessions. bob # [ 9.369788] systemd[1]: Started Getty on tty1. bob # [ 9.373684] systemd[1]: Reached target Login Prompts. clare # [ 9.366508] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 9.574160] ncps-pre-start[572]: {"level":"info","log_level":"info","time":"2026-10-15T15:16:09Z","message":"logger created"} bob # [ 9.577251] ncps-pre-start[572]: {"level":"info","xz-binary-path":"/nix/store/csrwf31hprwghnbyiglxa3p787lgrl67-xz-5.8.4-bin/bin/xz","time":"2026-10-15T15:16:09Z","message":"Using xz binary for xz decompression"} bob # [ 9.593191] ncps-pre-start[572]: {"level":"info","time":"2026-10-15T15:16:09Z","message":"running migrate up"} bob # [ 9.607635] ncps-pre-start[572]: {"level":"info","time":"2026-10-15T15:16:09Z","message":"migrate up complete"} alice # [ 9.633439] systemd-networkd[544]: eth0: Gained IPv6LL clare # [ 9.648547] systemd[1]: Starting Virtual Console Setup... bob # [ 9.706119] systemd[1]: Started ncps binary cache proxy service. bob # [ 9.706757] systemd[1]: Reached target Multi-User System. bob # [ 9.710616] systemd[1]: Startup finished in 735ms (kernel) + 4.611s (initrd) + 4.360s (userspace) = 9.707s. clare # [ 9.677022] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. clare # [ 9.687398] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 9.689747] systemd[1]: Stopped Virtual Console Setup. clare # [ 9.694803] systemd[1]: Starting Virtual Console Setup... clare # [ 9.702219] systemd-logind[500]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 9.770462] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 clare # [ 9.797337] systemd-vconsole-setup[559]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 9.800297] systemd[1]: Finished Virtual Console Setup. bob # [ 9.940817] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","log_level":"info","time":"2026-10-15T15:16:09Z","message":"logger created"} bob # [ 9.941920] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","xz-binary-path":"/nix/store/csrwf31hprwghnbyiglxa3p787lgrl67-xz-5.8.4-bin/bin/xz","time":"2026-10-15T15:16:09Z","message":"Using xz binary for xz decompression"} bob # [ 9.948335] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","time":"2026-10-15T15:16:09Z","message":"using local locks (single-instance mode)"} bob # [ 9.957271] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-10-15T15:16:09Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"} bob # [ 9.972185] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-15T15:16:09Z","message":"setting up tracer provider to discard traces"} bob # [ 9.980069] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-15T15:16:09Z","message":"setting up meter provider to discard metrics"} bob # [ 9.993152] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-15T15:16:09Z","message":"setting up logger provider to discard logs"} bob # [ 9.998556] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-10-15T15:16:09Z","message":"Reporting anonymous metrics to the project maintainers"} bob # [ 10.005532] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-10-15T15:16:09Z","message":"failed to parse netrc file, proceeding without netrc authentication"} bob # [ 10.011380] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-10-15T15:16:09Z","message":"using local storage"} bob # [ 10.017077] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","cdc-enabled":false,"cdc-min":16384,"cdc-avg":65536,"cdc-max":262144,"time":"2026-10-15T15:16:09Z","message":"configuring Content-Defined-Chunking (CDC)"} bob # [ 10.018690] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","lazy-cdc":false,"lazy-cdc-workers":1,"time":"2026-10-15T15:16:09Z","message":"configuring lazy Content-Defined-Chunking (CDC)"} bob # [ 10.025349] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","inflight-staging-enabled":false,"distributed-lock":false,"inflight-staging-retention":300000,"inflight-staging-part-size":8388608,"time":"2026-10-15T15:16:09Z","message":"configuring in-flight NAR staging"} bob: (finished: waiting for unit ncps, in 10.30 seconds) bob: must succeed: systemctl status ncps clare # [ 10.043501] systemd[1]: Starting harmonia binary cache service... alice # [ 10.082553] systemd-networkd[544]: eth1: Gained IPv6LL bob: (finished: must succeed: systemctl status ncps, in 0.05 seconds) clare: waiting for unit harmonia.socket clare: waiting for the VM to finish booting clare: Guest shell says: b'Spawning backdoor root shell...\n' clare: connected to guest root shell clare: (connecting took 0.00 seconds) clare: (finished: waiting for the VM to finish booting, in 0.00 seconds) bob # [ 10.192411] systemd[1]: Starting Virtual Console Setup... bob # [ 10.212335] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 10.214422] systemd[1]: Stopped Virtual Console Setup. bob # [ 10.224299] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 10.227974] systemd[1]: Starting Virtual Console Setup... bob # [ 10.251737] systemd-logind[503]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) clare: (finished: waiting for unit harmonia.socket, in 0.12 seconds) alice: waiting for success: curl bob:8502/nix-cache-info alice: waiting for the VM to finish booting alice: Guest shell says: b'Spawning backdoor root shell...\n' alice: connected to guest root shell alice: (connecting took 0.00 seconds) alice: (finished: waiting for the VM to finish booting, in 0.00 seconds) clare # [ 10.223278] harmonia-cache[562]: 2026-10-15T15:16:10.042401Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000)) clare # [ 10.227785] harmonia-cache[562]: 2026-10-15T15:16:10.045944Z INFO actix_server::builder: starting 4 workers clare # [ 10.232983] harmonia-cache[562]: 2026-10-15T15:16:10.050747Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s clare # [ 10.235936] harmonia-cache[562]: 2026-10-15T15:16:10.050825Z INFO actix_server::server: Actix runtime found; starting in Actix runtime clare # [ 10.240300] harmonia-cache[562]: 2026-10-15T15:16:10.050925Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:5000 clare # [ 10.243531] systemd[1]: Started harmonia binary cache service. bob # [ 10.308500] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-10-15T15:16:10Z","message":"upstream became healthy and is now available for requests"} bob # [ 10.310602] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","time_zone":"UTC","time":"2026-10-15T15:16:10Z","message":"setting up the cache timezone location"} bob # [ 10.313648] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","time":"2026-10-15T15:16:10Z","message":"cron setup complete"} bob # [ 10.314670] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","time":"2026-10-15T15:16:10Z","message":"starting the cron scheduler"} bob # [ 10.315735] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-10-15T15:16:10Z","message":"Server started"} bob # [ 10.350325] systemd-vconsole-setup[598]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 10.352857] systemd[1]: Finished Virtual Console Setup. bob # [ 10.369556] systemd-networkd[555]: eth0: Gained IPv6LL clare # [ 10.467101] systemd-networkd[539]: eth1: Gained IPv6LL clare # [ 10.470904] systemd-networkd[539]: eth0: Gained IPv6LL alice # % Total % Received % Xferd Average Speed Time Time Time Current alice # Dload Upload Total Spent Left Speed bob # [ 10.559234] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"192.168.1.1:50864","trace_id":"b26635713afd2bc950aaf4562230371e","span_id":"41a971639f34b240","status":200,"elapsed":0.1188,"bytes":50,"time":"2026-10-15T15:16:10Z","message":"handled request"} alice # 0 0 0 0 0 0 0 0 0100 50 100 50 0 0 203 0 0100 50 100 50 0 0 199 0 0100 50 100 50 0 0 196 0 0 alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 0.33 seconds) bob: waiting for success: curl clare:5000/nix-cache-info bob # [ 10.754411] systemd-networkd[555]: eth1: Gained IPv6LL bob # % Total % Received % Xferd Average Speed Time Time Time Current bob # Dload Upload Total Spent Left Speed bob # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 221 0 0100 51 100 51 0 0 217 0 0100 51 100 51 0 0 213 0 0 bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.31 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.04 seconds) clare: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 bob # [ 11.016747] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: 2026/10/15 15:16:10 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host clare # [ 11.316706] systemd-resolved[307]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. clare # [ 11.323603] systemd-resolved[307]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 11.327199] systemd-resolved[307]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 11.990217] systemd-resolved[307]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 11.994183] systemd-resolved[307]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 13.360266] systemd-resolved[307]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 13.364141] systemd-resolved[307]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 15.404918] systemd-resolved[307]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 15.407376] systemd-resolved[307]: 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.55 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 # [ 15.632512] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:33028","trace_id":"b35a85c86426c2233f5353672a6202d3","span_id":"ecef353af84bb256","status":200,"elapsed":0.03144,"bytes":50,"time":"2026-10-15T15:16:15Z","message":"handled request"} alice # [ 15.914099] systemd-resolved[319]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. alice # [ 15.918725] systemd-resolved[319]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 15.922543] systemd-resolved[319]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 16.431018] systemd-resolved[319]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 16.434590] systemd-resolved[319]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 17.499852] systemd-resolved[319]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 17.503546] systemd-resolved[319]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 20.216833] systemd-resolved[319]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 20.220735] systemd-resolved[319]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 20.242026] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33028","trace_id":"d1b604e229763ca6367cf6bdb7762f95","span_id":"3e3bd40b22028664","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-15T15:16:20Z","message":"download the narinfo from upstream"} bob # [ 20.273996] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33028","trace_id":"d1b604e229763ca6367cf6bdb7762f95","span_id":"3e3bd40b22028664","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-15T15:16:20Z","message":"downloading the nar from upstream"} bob # [ 20.289697] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33028","trace_id":"d1b604e229763ca6367cf6bdb7762f95","span_id":"3e3bd40b22028664","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-15T15:16:20Z","message":"download the nar from upstream"} bob # [ 20.317392] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33028","trace_id":"d1b604e229763ca6367cf6bdb7762f95","span_id":"3e3bd40b22028664","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":43.3864,"time":"2026-10-15T15:16:20Z","message":"download of nar complete"} bob # [ 20.338432] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33028","trace_id":"d1b604e229763ca6367cf6bdb7762f95","span_id":"3e3bd40b22028664","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-15T15:16:20Z","message":"storing narinfo and nar_file record in the database"} bob # [ 20.352223] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33028","trace_id":"d1b604e229763ca6367cf6bdb7762f95","span_id":"3e3bd40b22028664","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":110.30772,"time":"2026-10-15T15:16:20Z","message":"download of narinfo complete"} bob # [ 20.361722] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:33028","trace_id":"d1b604e229763ca6367cf6bdb7762f95","span_id":"3e3bd40b22028664","status":200,"elapsed":117.116,"bytes":532,"time":"2026-10-15T15:16:20Z","message":"handled request"} bob # [ 20.366503] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[577]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:33028","trace_id":"c799b9f365e98e848f1feed1bba05ed1","span_id":"a13fa2191b2a6986","status":200,"elapsed":0.87664,"bytes":95,"time":"2026-10-15T15:16:20Z","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.84 seconds) (finished: run the VM test script, in 21.07 seconds) test script finished in 21.22s 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-15T15:16:20Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-10-15T15:16:20Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-10-15T15:16:20Z 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-15T15:16:20Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-10-15T15:16:20Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-10-15T15:16:20Z 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-15T15:16:20Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-10-15T15:16:20Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-10-15T15:16:20Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.40 seconds)