build01: building ldgrxmjs2d0ijxys73cmqqrcwm806jk6-vm-test-run-ncps.drv Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script additionally exposed symbols: alice, bob, clare, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh start all VMs alice: starting vm bob: starting vm clare: starting vm alice # Disk image does not exist, creating the virtualisation disk image... alice # Formatting '/build/vm-state-alice/tmp.QJFgPXhNyl', fmt=raw size=1073741824 alice # mke2fs 1.47.4 (6-Mar-2025) alice # Discarding device blocks: 0/262144 done alice: QEMU running (pid 48) alice # Creating filesystem with 262144 4k blocks and 65536 inodes alice # Filesystem UUID: 7a79a265-bd73-4d5e-adc5-39ba3bc92172 bob # Disk image does not exist, creating the virtualisation disk image... alice # Superblock backups stored on blocks: bob: QEMU running (pid 49) bob # Formatting '/build/vm-state-bob/tmp.H0tRlOfWK3', fmt=raw size=1073741824 clare: QEMU running (pid 50) clare # Disk image does not exist, creating the virtualisation disk image... alice # 32768, 98304, 163840, 229376 clare # Formatting '/build/vm-state-clare/tmp.qGBfTFiU2r', fmt=raw size=1073741824 alice # clare # mke2fs 1.47.4 (6-Mar-2025) alice # Allocating group tables: 0/8 done (finished: start all VMs, in 0.49 seconds) bob # mke2fs 1.47.4 (6-Mar-2025) clare # Discarding device blocks: 0/262144 done clare # Creating filesystem with 262144 4k blocks and 65536 inodes bob # Discarding device blocks: 0/262144 done alice # Writing inode tables: 0/8 done clare # Filesystem UUID: 25b10962-955d-47fe-b97b-0e3150546f0f bob # Creating filesystem with 262144 4k blocks and 65536 inodes clare # Superblock backups stored on blocks: alice # Creating journal (8192 blocks): done bob # Filesystem UUID: 25e77e32-f3e8-48fc-a2eb-b8ca851e413a bob: waiting for unit ncps clare # 32768, 98304, 163840, 229376 bob # Superblock backups stored on blocks: bob: waiting for the VM to finish booting alice # Writing superblocks and filesystem accounting information: 0/8 done clare # bob # 32768, 98304, 163840, 229376 clare # Allocating group tables: 0/8 done bob # clare # Writing inode tables: 0/8 done alice # clare # Creating journal (8192 blocks): done clare # Writing superblocks and filesystem accounting information: 0/8 done alice # Virtualisation disk image created. clare # alice # Starting virtiofs daemons... clare # Virtualisation disk image created. alice # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # Starting virtiofs daemons... clare # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # Allocating group tables: 0/8 done bob # Writing inode tables: 0/8 done alice # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # Creating journal (8192 blocks): done alice # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # Writing superblocks and filesystem accounting information: 0/8 done alice # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # bob # Virtualisation disk image created. alice # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # Starting virtiofs daemons... alice # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] alice # [ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Oct 3 10:39:03 UTC 2026 alice # [ 0.000000] KASLR enabled alice # [ 0.000000] random: crng init done alice # [ 0.000000] Machine model: linux,dummy-virt alice # [ 0.000000] efi: UEFI not found. clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Oct 3 10:39:03 UTC 2026 clare # [ 0.000000] KASLR enabled alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] clare # [ 0.000000] random: crng init done alice # [ 0.000000] Zone ranges: clare # [ 0.000000] Machine model: linux,dummy-virt clare # [ 0.000000] efi: UEFI not found. alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] DMA32 empty alice # [ 0.000000] Normal empty clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT alice # [ 0.000000] Device empty alice # [ 0.000000] Movable zone start for each node clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] Early memory node ranges clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] Zone ranges: clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] DMA32 empty clare # [ 0.000000] Normal empty alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 clare # [ 0.000000] Device empty alice # [ 0.000000] psci: probing for conduit method from DT. clare # [ 0.000000] Movable zone start for each node alice # [ 0.000000] psci: PSCIv1.3 detected in firmware. clare # [ 0.000000] Early memory node ranges alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] psci: SMC Calling Convention v1.1 clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) clare # [ 0.000000] psci: probing for conduit method from DT. alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u311296 clare # [ 0.000000] psci: PSCIv1.3 detected in firmware. alice # [ 0.000000] Detected PIPT I-cache on CPU0 clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs alice # [ 0.000000] CPU features: detected: GICv3 CPU interface clare # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] CPU features: detected: Spectre-v4 clare # [ 0.000000] psci: SMC Calling Convention v1.1 alice # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u311296 alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.000000] Detected PIPT I-cache on CPU0 alice # [ 0.000000] CPU features: detected: ARM erratum 1418040 clare # [ 0.000000] CPU features: detected: GICv3 CPU interface alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion clare # [ 0.000000] CPU features: detected: Spectre-v4 clare # [ 0.000000] CPU features: detected: Spectre-BHB alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing alice # [ 0.000000] alternatives: applying boot alternatives clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.000000] CPU features: detected: ARM erratum 1418040 clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.000000] alternatives: applying boot alternatives 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/kq0j6qy8xgmdx7cbimmnsnci26v7rpzv-nixos-system-alice-test/init regInfo=/nix/.ro-store/g7d7n1bh2cxyj50ymjmx7njnidvd5sr5-closure-info/registration console=ttyAMA0,115200n8 console=tty0 alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/g7d7n1bh2cxyj50ymjmx7njnidvd5sr5-closure-info/registration", will be passed to user space. alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) 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/q82yc0bv5n8fk6y1nv8g5kdp80x45p46-nixos-system-clare-test/init regInfo=/nix/.ro-store/qb537ylw1d8nfimjimmn6h2vp3dikbjj-closure-info/registration console=ttyAMA0,115200n8 console=tty0 alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/qb537ylw1d8nfimjimmn6h2vp3dikbjj-closure-info/registration", will be passed to user space. alice # [ 0.000000] software IO TLB: area num 1. clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) alice # [ 0.000000] Fallback order for Node 0: 0 alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.000000] Policy zone: DMA clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off clare # [ 0.000000] software IO TLB: area num 1. alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) alice # [ 0.000000] allocated 2097152 bytes of page_ext clare # [ 0.000000] Fallback order for Node 0: 0 alice # [ 0.000000] ftrace: allocating 75039 entries in 294 pages clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups clare # [ 0.000000] Policy zone: DMA alice # [ 0.000000] rcu: Hierarchical RCU implementation. alice # [ 0.000000] rcu: RCU event tracing is enabled. clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. clare # [ 0.000000] allocated 2097152 bytes of page_ext alice # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.000000] ftrace: allocating 75039 entries in 294 pages alice # [ 0.000000] Rude variant of Tasks RCU enabled. clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups alice # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.000000] rcu: Hierarchical RCU implementation. alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. clare # [ 0.000000] rcu: RCU event tracing is enabled. alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] alice # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.000000] Rude variant of Tasks RCU enabled. clare # [ 0.000000] Tracing variant of Tasks RCU enabled. 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] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Oct 3 10:39:03 UTC 2026 bob # [ 0.000000] KASLR enabled clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. bob # [ 0.000000] random: crng init done alice # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 bob # [ 0.000000] Machine model: linux,dummy-virt bob # [ 0.000000] efi: UEFI not found. alice # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.000000] GICv3: 0 Extended SPIs implemented bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT alice # [ 0.000000] Root IRQ handler: gic_handle_irq 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.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] Zone ranges: alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] bob # [ 0.000000] DMA32 empty clare # [ 0.000000] GICv3: 256 SPIs implemented bob # [ 0.000000] Normal empty clare # [ 0.000000] GICv3: 0 Extended SPIs implemented bob # [ 0.000000] Device empty alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) clare # [ 0.000000] Root IRQ handler: gic_handle_irq bob # [ 0.000000] Movable zone start for each node bob # [ 0.000000] Early memory node ranges clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 bob # [ 0.000000] psci: probing for conduit method from DT. alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) bob # [ 0.000000] psci: PSCIv1.3 detected in firmware. alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) bob # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 bob # [ 0.000000] psci: SMC Calling Convention v1.1 alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u311296 bob # [ 0.000000] Detected PIPT I-cache on CPU0 alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns bob # [ 0.000000] CPU features: detected: GICv3 CPU interface alice # [ 0.000056] arm-pv: using stolen time PV clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.000000] CPU features: detected: Spectre-v4 bob # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). alice # [ 0.000330] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.000611] Console: colour dummy device 80x25 bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR alice # [ 0.000623] printk: legacy console [tty0] enabled clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.000000] CPU features: detected: ARM erratum 1418040 alice # [ 0.000844] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) clare # [ 0.000063] arm-pv: using stolen time PV alice # [ 0.000849] pid_max: default: 32768 minimum: 301 bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion alice # [ 0.000934] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.000397] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.001064] landlock: Up and running. clare # [ 0.000700] Console: colour dummy device 80x25 bob # [ 0.000000] alternatives: applying boot alternatives alice # [ 0.001066] Yama: becoming mindful. clare # [ 0.000711] printk: legacy console [tty0] enabled alice # [ 0.001404] LSM support for eBPF active alice # [ 0.001590] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.000928] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) clare # [ 0.000933] pid_max: default: 32768 minimum: 301 alice # [ 0.001609] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.004460] rcu: Hierarchical SRCU implementation. clare # [ 0.001007] LSM: initializing lsm=capability,landlock,yama,bpf,ima clare # [ 0.001147] landlock: Up and running. alice # [ 0.004466] rcu: Max phase no-delay instances is 1000. 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/y6bki65bnyl7icghswfggs0rgqryrg6y-nixos-system-bob-test/init regInfo=/nix/.ro-store/qb8fazqzc1z6aw1lplcpid4zbvyzpbfn-closure-info/registration console=ttyAMA0,115200n8 console=tty0 clare # [ 0.001150] Yama: becoming mindful. alice # [ 0.005452] fsl-mc MSI: its@8080000 domain created clare # [ 0.001530] LSM support for eBPF active alice # [ 0.005657] EFI services will not be available. clare # [ 0.001707] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.005742] smp: Bringing up secondary CPUs ... bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/qb8fazqzc1z6aw1lplcpid4zbvyzpbfn-closure-info/registration", will be passed to user space. alice # [ 0.005776] smp: Brought up 1 node, 1 CPU alice # [ 0.005781] SMP: Total of 1 processors activated. clare # [ 0.001726] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.005782] CPU: All CPU(s) started at EL1 clare # [ 0.004570] rcu: Hierarchical SRCU implementation. alice # [ 0.005796] CPU features: detected: 32-bit EL0 Support clare # [ 0.004576] rcu: Max phase no-delay instances is 1000. bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) clare # [ 0.005520] fsl-mc MSI: its@8080000 domain created bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.005800] CPU features: detected: Data cache clean to the PoU not required for I/D coherence clare # [ 0.005713] EFI services will not be available. clare # [ 0.005796] smp: Bringing up secondary CPUs ... alice # [ 0.005803] CPU features: detected: Common not Private translations bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB clare # [ 0.005826] smp: Brought up 1 node, 1 CPU bob # [ 0.000000] software IO TLB: area num 1. alice # [ 0.005805] CPU features: detected: CRC32 instructions clare # [ 0.005830] SMP: Total of 1 processors activated. alice # [ 0.005807] CPU features: detected: Data cache clean to Point of Persistence bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.005832] CPU: All CPU(s) started at EL1 bob # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.005844] CPU features: detected: 32-bit EL0 Support alice # [ 0.005809] CPU features: detected: Enhanced Virtualization Traps bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 alice # [ 0.005820] CPU features: detected: RCpc load-acquire (LDAPR) bob # [ 0.000000] Policy zone: DMA clare # [ 0.005847] CPU features: detected: Data cache clean to the PoU not required for I/D coherence alice # [ 0.005822] CPU features: detected: LSE atomic instructions clare # [ 0.005850] CPU features: detected: Common not Private translations bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.005824] CPU features: detected: Privileged Access Never clare # [ 0.005852] CPU features: detected: CRC32 instructions alice # [ 0.005826] CPU features: detected: PMUv3 bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.005828] CPU features: detected: RAS Extension Support bob # [ 0.000000] allocated 2097152 bytes of page_ext clare # [ 0.005854] CPU features: detected: Data cache clean to Point of Persistence bob # [ 0.000000] ftrace: allocating 75039 entries in 294 pages alice # [ 0.005831] CPU features: detected: Speculative Store Bypassing Safe (SSBS) clare # [ 0.005857] CPU features: detected: Enhanced Virtualization Traps bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups clare # [ 0.005867] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.005853] alternatives: applying system-wide alternatives bob # [ 0.000000] rcu: Hierarchical RCU implementation. clare # [ 0.005869] CPU features: detected: LSE atomic instructions bob # [ 0.000000] rcu: RCU event tracing is enabled. alice # [ 0.007233] CPU features: detected: Hardware dirty bit management on CPU0 clare # [ 0.005871] CPU features: detected: Privileged Access Never bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. clare # [ 0.005873] CPU features: detected: PMUv3 bob # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.005875] CPU features: detected: RAS Extension Support alice # [ 0.007432] Memory: 891144K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116128K reserved, 32768K cma-reserved) bob # [ 0.000000] Rude variant of Tasks RCU enabled. alice # [ 0.007945] devtmpfs: initialized clare # [ 0.005879] CPU features: detected: Speculative Store Bypassing Safe (SSBS) bob # [ 0.000000] Tracing variant of Tasks RCU enabled. alice # [ 0.009501] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) clare # [ 0.005899] alternatives: applying system-wide alternatives bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. clare # [ 0.007334] CPU features: detected: Hardware dirty bit management on CPU0 alice # [ 0.009523] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 0.009650] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL alice # [ 0.009655] 0 pages in range for non-PLT usage clare # [ 0.007537] Memory: 890924K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116132K reserved, 32768K cma-reserved) 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.009656] 508192 pages in range for PLT usage clare # [ 0.008003] devtmpfs: initialized alice # [ 0.009759] pinctrl core: initialized pinctrl subsystem bob # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.009517] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.010576] DMI not present or invalid. alice # [ 0.014010] NET: Registered PF_NETLINK/PF_ROUTE protocol family bob # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.009538] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). alice # [ 0.016474] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.009670] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.009675] 0 pages in range for non-PLT usage alice # [ 0.016606] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 0.000000] GICv3: 0 Extended SPIs implemented clare # [ 0.009676] 508192 pages in range for PLT usage bob # [ 0.000000] Root IRQ handler: gic_handle_irq clare # [ 0.009762] pinctrl core: initialized pinctrl subsystem alice # [ 0.016755] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI clare # [ 0.010559] DMI not present or invalid. alice # [ 0.016772] audit: initializing netlink subsys (disabled) bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.013816] NET: Registered PF_NETLINK/PF_ROUTE protocol family alice # [ 0.017418] thermal_sys: Registered thermal governor 'fair_share' bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 clare # [ 0.016133] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations alice # [ 0.017420] thermal_sys: Registered thermal governor 'bang_bang' bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.016259] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations alice # [ 0.017423] thermal_sys: Registered thermal governor 'step_wise' bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) alice # [ 0.017425] thermal_sys: Registered thermal governor 'user_space' clare # [ 0.016406] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations alice # [ 0.017427] thermal_sys: Registered thermal governor 'power_allocator' clare # [ 0.016421] audit: initializing netlink subsys (disabled) bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.017009] thermal_sys: Registered thermal governor 'fair_share' bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 alice # [ 0.017468] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 clare # [ 0.017011] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 0.017477] cpuidle: using governor ladder bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 alice # [ 0.017482] cpuidle: using governor menu clare # [ 0.017014] thermal_sys: Registered thermal governor 'step_wise' bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. alice # [ 0.017603] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. clare # [ 0.017016] thermal_sys: Registered thermal governor 'user_space' alice # [ 0.017624] ASID allocator initialised with 32768 entries clare # [ 0.017018] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 0.018310] Serial: AMBA PL011 UART driver bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns clare # [ 0.017058] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 alice # [ 0.021934] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 clare # [ 0.017066] cpuidle: using governor ladder alice # [ 0.022108] printk: console [ttyAMA0] enabled bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). clare # [ 0.017071] cpuidle: using governor menu clare # [ 0.017200] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns clare # [ 0.017222] ASID allocator initialised with 32768 entries clare # [ 0.017884] Serial: AMBA PL011 UART driver bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.000050] arm-pv: using stolen time PV clare # [ 0.021332] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 clare # [ 0.021498] printk: console [ttyAMA0] enabled bob # [ 0.000332] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.000576] Console: colour dummy device 80x25 bob # [ 0.000586] printk: legacy console [tty0] enabled bob # [ 0.000785] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) bob # [ 0.000790] pid_max: default: 32768 minimum: 301 bob # [ 0.000862] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.000981] landlock: Up and running. bob # [ 0.000983] Yama: becoming mindful. bob # [ 0.001314] LSM support for eBPF active bob # [ 0.001465] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.001484] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.003875] rcu: Hierarchical SRCU implementation. bob # [ 0.003881] rcu: Max phase no-delay instances is 1000. bob # [ 0.004898] fsl-mc MSI: its@8080000 domain created bob # [ 0.005076] EFI services will not be available. bob # [ 0.005164] smp: Bringing up secondary CPUs ... bob # [ 0.005192] smp: Brought up 1 node, 1 CPU bob # [ 0.005196] SMP: Total of 1 processors activated. bob # [ 0.005198] CPU: All CPU(s) started at EL1 bob # [ 0.005209] CPU features: detected: 32-bit EL0 Support bob # [ 0.005212] CPU features: detected: Data cache clean to the PoU not required for I/D coherence bob # [ 0.005215] CPU features: detected: Common not Private translations bob # [ 0.005217] CPU features: detected: CRC32 instructions alice # [ 0.094075] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.005219] CPU features: detected: Data cache clean to Point of Persistence alice # [ 0.094088] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 0.005221] CPU features: detected: Enhanced Virtualization Traps alice # [ 0.094091] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.005231] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.094095] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page bob # [ 0.005233] CPU features: detected: LSE atomic instructions alice # [ 0.094097] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 0.005234] CPU features: detected: Privileged Access Never clare # [ 0.093698] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages alice # [ 0.094099] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 0.005236] CPU features: detected: PMUv3 bob # [ 0.005238] CPU features: detected: RAS Extension Support clare # [ 0.093711] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page alice # [ 0.094101] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages bob # [ 0.005241] CPU features: detected: Speculative Store Bypassing Safe (SSBS) alice # [ 0.094103] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page clare # [ 0.093713] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages alice # [ 0.095044] fbcon: Taking over console bob # [ 0.005262] alternatives: applying system-wide alternatives clare # [ 0.093718] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page alice # [ 0.095054] ACPI: Interpreter disabled. bob # [ 0.006627] CPU features: detected: Hardware dirty bit management on CPU0 alice # [ 0.095701] iommu: Default domain type: Translated clare # [ 0.093720] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages clare # [ 0.093722] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 0.095705] iommu: DMA domain TLB invalidation policy: strict mode alice # [ 0.096004] SCSI subsystem initialized clare # [ 0.093724] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages bob # [ 0.006849] Memory: 891172K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116112K reserved, 32768K cma-reserved) alice # [ 0.096204] usbcore: registered new interface driver usbfs bob # [ 0.007270] devtmpfs: initialized clare # [ 0.093726] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page alice # [ 0.096233] usbcore: registered new interface driver hub clare # [ 0.094694] fbcon: Taking over console bob # [ 0.008745] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.096248] usbcore: registered new device driver usb clare # [ 0.094703] ACPI: Interpreter disabled. alice # [ 0.096476] pps_core: LinuxPPS API ver. 1 registered clare # [ 0.095346] iommu: Default domain type: Translated bob # [ 0.008767] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). clare # [ 0.095350] iommu: DMA domain TLB invalidation policy: strict mode bob # [ 0.008888] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL alice # [ 0.096479] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti clare # [ 0.095605] SCSI subsystem initialized alice # [ 0.096484] PTP clock support registered bob # [ 0.008893] 0 pages in range for non-PLT usage clare # [ 0.095804] usbcore: registered new interface driver usbfs alice # [ 0.096518] EDAC MC: Ver: 3.0.0 bob # [ 0.008894] 508192 pages in range for PLT usage clare # [ 0.095833] usbcore: registered new interface driver hub bob # [ 0.008994] pinctrl core: initialized pinctrl subsystem clare # [ 0.095848] usbcore: registered new device driver usb bob # [ 0.009756] DMI not present or invalid. clare # [ 0.096062] pps_core: LinuxPPS API ver. 1 registered bob # [ 0.013025] NET: Registered PF_NETLINK/PF_ROUTE protocol family bob # [ 0.015286] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations clare # [ 0.096065] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti clare # [ 0.096070] PTP clock support registered clare # [ 0.096105] EDAC MC: Ver: 3.0.0 bob # [ 0.015412] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 0.015558] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.105556] scmi_core: SCMI protocol bus registered bob # [ 0.015574] audit: initializing netlink subsys (disabled) bob # [ 0.016107] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1 bob # [ 0.016135] thermal_sys: Registered thermal governor 'fair_share' alice # [ 0.105938] scmi_core: SCMI protocol bus registered alice # [ 0.106250] FPGA manager framework bob # [ 0.016137] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 0.109883] vgaarb: loaded bob # [ 0.016139] thermal_sys: Registered thermal governor 'step_wise' bob # [ 0.016141] thermal_sys: Registered thermal governor 'user_space' bob # [ 0.016143] thermal_sys: Registered thermal governor 'power_allocator' bob # [ 0.016177] cpuidle: using governor ladder clare # [ 0.106288] FPGA manager framework bob # [ 0.016183] cpuidle: using governor menu clare # [ 0.109509] vgaarb: loaded bob # [ 0.016292] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. bob # [ 0.016311] ASID allocator initialised with 32768 entries bob # [ 0.016946] Serial: AMBA PL011 UART driver alice # [ 0.110930] clocksource: Switched to clocksource arch_sys_counter bob # [ 0.020394] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 bob # [ 0.020531] printk: console [ttyAMA0] enabled clare # [ 0.110186] clocksource: Switched to clocksource arch_sys_counter alice # [ 0.120078] VFS: Disk quotas dquot_6.6.0 alice # [ 0.120104] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 0.121498] netfs: FS-Cache loaded alice # [ 0.121609] pnp: PnP ACPI: disabled clare # [ 0.119316] VFS: Disk quotas dquot_6.6.0 clare # [ 0.119340] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) clare # [ 0.120796] netfs: FS-Cache loaded clare # [ 0.120884] pnp: PnP ACPI: disabled alice # [ 0.124860] NET: Registered PF_INET protocol family bob # [ 0.094238] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages alice # [ 0.125036] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) bob # [ 0.094252] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 0.094254] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.094258] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page clare # [ 0.124115] NET: Registered PF_INET protocol family bob # [ 0.094261] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages clare # [ 0.124282] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) bob # [ 0.094262] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 0.094265] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages bob # [ 0.094267] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page bob # [ 0.095242] fbcon: Taking over console bob # [ 0.095252] ACPI: Interpreter disabled. bob # [ 0.095904] iommu: Default domain type: Translated bob # [ 0.095908] iommu: DMA domain TLB invalidation policy: strict mode bob # [ 0.096208] SCSI subsystem initialized bob # [ 0.096413] usbcore: registered new interface driver usbfs bob # [ 0.096442] usbcore: registered new interface driver hub bob # [ 0.096456] usbcore: registered new device driver usb bob # [ 0.096662] pps_core: LinuxPPS API ver. 1 registered bob # [ 0.096665] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti bob # [ 0.096669] PTP clock support registered bob # [ 0.096701] EDAC MC: Ver: 3.0.0 bob # [ 0.106479] scmi_core: SCMI protocol bus registered bob # [ 0.107231] FPGA manager framework bob # [ 0.110405] vgaarb: loaded bob # [ 0.111113] clocksource: Switched to clocksource arch_sys_counter alice # [ 0.149066] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.149091] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.119878] VFS: Disk quotas dquot_6.6.0 alice # [ 0.149104] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 0.119907] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.121382] netfs: FS-Cache loaded alice # [ 0.149140] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) bob # [ 0.121497] pnp: PnP ACPI: disabled alice # [ 0.149206] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.149302] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 0.149362] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.149410] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.149523] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 0.149568] NET: Registered PF_XDP protocol family alice # [ 0.149589] PCI: CLS 0 bytes, default 64 bob # [ 0.124763] NET: Registered PF_INET protocol family alice # [ 0.149799] Trying to unpack rootfs image as initramfs... bob # [ 0.124936] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) clare # [ 0.148820] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) clare # [ 0.148844] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) clare # [ 0.148856] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) clare # [ 0.148892] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) clare # [ 0.148958] TCP: Hash tables configured (established 8192 bind 8192) clare # [ 0.149251] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) clare # [ 0.149305] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.149354] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.149459] NET: Registered PF_UNIX/PF_LOCAL protocol family clare # [ 0.149506] NET: Registered PF_XDP protocol family clare # [ 0.149528] PCI: CLS 0 bytes, default 64 clare # [ 0.149729] Trying to unpack rootfs image as initramfs... alice # [ 0.162953] kvm [1]: HYP mode not available clare # [ 0.164326] kvm [1]: HYP mode not available bob # [ 0.149042] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.149065] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.149077] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 0.149111] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) bob # [ 0.149178] TCP: Hash tables configured (established 8192 bind 8192) bob # [ 0.149279] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) bob # [ 0.149335] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.149357] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.149462] NET: Registered PF_UNIX/PF_LOCAL protocol family bob # [ 0.149489] NET: Registered PF_XDP protocol family bob # [ 0.149508] PCI: CLS 0 bytes, default 64 bob # [ 0.149700] Trying to unpack rootfs image as initramfs... bob # [ 0.163134] kvm [1]: HYP mode not available clare # [ 0.226576] Initialise system trusted keyrings clare # [ 0.226728] workingset: timestamp_bits=42 max_order=18 bucket_order=0 clare # [ 0.227058] squashfs: version 4.0 (2009/01/31) Phillip Lougher clare # [ 0.227091] 9p: Installing v9fs 9p2000 file system support alice # [ 0.227370] Initialise system trusted keyrings alice # [ 0.227524] workingset: timestamp_bits=42 max_order=18 bucket_order=0 alice # [ 0.227898] squashfs: version 4.0 (2009/01/31) Phillip Lougher alice # [ 0.227935] 9p: Installing v9fs 9p2000 file system support clare # [ 0.245868] Key type asymmetric registered clare # [ 0.245886] Asymmetric key parser 'x509' registered clare # [ 0.245951] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) alice # [ 0.256484] Key type asymmetric registered alice # [ 0.256501] Asymmetric key parser 'x509' registered alice # [ 0.256573] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) alice # [ 0.256822] io scheduler mq-deadline registered bob # [ 0.227626] Initialise system trusted keyrings alice # [ 0.256826] io scheduler kyber registered bob # [ 0.227765] workingset: timestamp_bits=42 max_order=18 bucket_order=0 clare # [ 0.254256] io scheduler mq-deadline registered bob # [ 0.228109] squashfs: version 4.0 (2009/01/31) Phillip Lougher clare # [ 0.254266] io scheduler kyber registered bob # [ 0.228144] 9p: Installing v9fs 9p2000 file system support clare # [ 0.256713] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.257046] ledtrig-cpu: registered to indicate activity on CPUs clare # [ 0.257387] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: alice # [ 0.261139] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.257403] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 alice # [ 0.261483] ledtrig-cpu: registered to indicate activity on CPUs clare # [ 0.257416] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 alice # [ 0.261793] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: clare # [ 0.257420] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 alice # [ 0.261810] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 clare # [ 0.257445] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits alice # [ 0.261823] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 clare # [ 0.257465] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] alice # [ 0.261828] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.257530] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 alice # [ 0.261867] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits clare # [ 0.257550] pci_bus 0000:00: root bus resource [bus 00-ff] alice # [ 0.261887] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.257554] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] clare # [ 0.257556] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.261969] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 alice # [ 0.261990] pci_bus 0000:00: root bus resource [bus 00-ff] clare # [ 0.257559] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.261993] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] clare # [ 0.257628] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint alice # [ 0.261996] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] clare # [ 0.258070] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.261998] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.262071] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint alice # [ 0.262525] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.262650] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.262660] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.262678] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.262687] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.274258] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.274270] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.274289] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.274298] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.274639] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.279064] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.274762] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.279194] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.274771] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.279203] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.246941] Key type asymmetric registered clare # [ 0.274788] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.279222] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.246957] Asymmetric key parser 'x509' registered clare # [ 0.275094] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint bob # [ 0.247008] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) alice # [ 0.279556] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint clare # [ 0.275213] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.279677] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] clare # [ 0.275222] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.279686] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.275239] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.279704] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.275546] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.280031] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.275667] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.280153] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.275676] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.280162] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.275693] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.256389] io scheduler mq-deadline registered alice # [ 0.280181] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.256396] io scheduler kyber registered clare # [ 0.275701] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.280189] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.258707] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.276008] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint bob # [ 0.259052] ledtrig-cpu: registered to indicate activity on CPUs alice # [ 0.280501] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.276131] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.280626] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.276148] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.280643] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.276465] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 0.280956] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint clare # [ 0.276592] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.281079] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.276609] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.281096] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.276908] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint bob # [ 0.260938] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: alice # [ 0.281394] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.277028] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] alice # [ 0.281514] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] bob # [ 0.260956] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 clare # [ 0.277216] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.281701] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.260969] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 clare # [ 0.277339] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.281826] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.277356] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.260973] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 alice # [ 0.281844] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.277659] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.261003] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits alice # [ 0.282148] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.277784] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.261023] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] alice # [ 0.282276] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.277802] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.261088] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 clare # [ 0.278107] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.282294] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.261108] pci_bus 0000:00: root bus resource [bus 00-ff] alice # [ 0.282597] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.294036] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.261111] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] alice # [ 0.282720] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.294056] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.261113] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.282737] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.261116] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] bob # [ 0.261182] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint alice # [ 0.298132] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint bob # [ 0.261617] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.298327] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.261743] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.298337] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.261752] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.298354] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.261771] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.298682] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.261780] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.295311] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint alice # [ 0.298803] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.295596] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.262105] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.298812] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.295606] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.262232] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.298830] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.295623] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.262241] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.295933] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.262259] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.296055] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.262571] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint clare # [ 0.296063] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.262696] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] clare # [ 0.296081] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.262704] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.296507] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned bob # [ 0.262723] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.296514] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned bob # [ 0.263050] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.296517] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned clare # [ 0.296549] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned alice # [ 0.303114] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned clare # [ 0.296580] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned alice # [ 0.303122] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned clare # [ 0.296610] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned alice # [ 0.303125] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned clare # [ 0.296642] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned alice # [ 0.303158] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned clare # [ 0.296671] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned alice # [ 0.303189] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned clare # [ 0.296703] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned alice # [ 0.303219] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned clare # [ 0.296733] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned alice # [ 0.303250] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned clare # [ 0.296762] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned alice # [ 0.303279] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned clare # [ 0.296791] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned alice # [ 0.303309] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.296873] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned alice # [ 0.303339] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned clare # [ 0.296921] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned alice # [ 0.303368] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned clare # [ 0.296934] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.296945] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned alice # [ 0.303398] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned clare # [ 0.296958] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned alice # [ 0.303452] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned clare # [ 0.296970] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned alice # [ 0.303481] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned clare # [ 0.296982] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned alice # [ 0.303495] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.296994] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned alice # [ 0.303523] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned clare # [ 0.297006] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned alice # [ 0.303535] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.283227] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.297018] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.283239] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.303547] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned clare # [ 0.297031] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.283258] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.303559] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned clare # [ 0.297042] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.283267] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.303571] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned clare # [ 0.297054] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned alice # [ 0.303583] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.283602] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.297066] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned alice # [ 0.303595] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.283731] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.297078] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.283749] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.303607] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned clare # [ 0.297090] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned alice # [ 0.303619] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.284075] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint clare # [ 0.297101] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned alice # [ 0.303630] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned clare # [ 0.297122] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned bob # [ 0.284202] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.303642] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned clare # [ 0.297134] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.284220] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.303654] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned clare # [ 0.297150] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.284488] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 0.303665] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned clare # [ 0.297154] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] bob # [ 0.284611] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] alice # [ 0.303677] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned clare # [ 0.297162] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.303688] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned clare # [ 0.297694] pci 0000:00:07.0: enabling device (0000 -> 0002) bob # [ 0.284792] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.303700] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.284918] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.303716] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.284936] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.303720] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] bob # [ 0.285242] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.303728] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] bob # [ 0.285370] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.304270] pci 0000:00:07.0: enabling device (0000 -> 0002) bob # [ 0.285387] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.285695] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.285821] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.285839] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.286151] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint bob # [ 0.286312] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.286322] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.286340] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.286766] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.286891] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.286899] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.286918] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.301939] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned bob # [ 0.301947] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned bob # [ 0.301950] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned bob # [ 0.301983] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned bob # [ 0.302013] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned bob # [ 0.302044] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.302075] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.302105] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned clare # [ 0.332284] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) bob # [ 0.302134] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.333797] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) bob # [ 0.302165] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.302195] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.302225] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned alice # [ 0.336544] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.338068] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) bob # [ 0.302283] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned bob # [ 0.302312] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned bob # [ 0.302323] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned bob # [ 0.302366] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.302379] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.302391] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned bob # [ 0.302403] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.302415] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.302428] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.302440] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.302452] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.302463] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.302475] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned bob # [ 0.302487] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.302499] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.302510] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned bob # [ 0.302522] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned bob # [ 0.302533] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned bob # [ 0.302545] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned clare # [ 0.343561] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) bob # [ 0.302560] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] clare # [ 0.344871] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) bob # [ 0.302564] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] bob # [ 0.302573] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.347677] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) alice # [ 0.349736] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) alice # [ 0.352377] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) alice # [ 0.353920] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) bob # [ 0.327196] pci 0000:00:07.0: enabling device (0000 -> 0002) bob # [ 0.327547] pci 0000:00:07.0: quirk_usb_early_handoff+0x0/0xa60 took 23875 usecs clare # [ 0.354310] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) clare # [ 0.356321] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) clare # [ 0.357801] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) clare # [ 0.360594] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) clare # [ 0.362003] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) alice # [ 0.363057] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) alice # [ 0.364670] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.366239] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) bob # [ 0.341873] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) bob # [ 0.344138] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) bob # [ 0.345556] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) clare # [ 0.371378] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) bob # [ 0.346903] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.373544] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) alice # [ 0.376226] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) alice # [ 0.378288] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) bob # [ 0.356137] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) clare # [ 0.382908] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.357891] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) clare # [ 0.383866] msm_serial: driver initialized clare # [ 0.383968] SuperH (H)SCI(F) driver initialized clare # [ 0.384009] STM32 USART driver initialized bob # [ 0.360441] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.361882] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.391512] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled alice # [ 0.392539] msm_serial: driver initialized alice # [ 0.392621] SuperH (H)SCI(F) driver initialized alice # [ 0.392663] STM32 USART driver initialized bob # [ 0.371254] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) bob # [ 0.372939] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) bob # [ 0.375043] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) clare # [ 0.406616] loop: module loaded clare # [ 0.406749] virtio_blk virtio2: 1/0/0 default/read/poll queues clare # [ 0.407344] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) bob # [ 0.386563] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled clare # [ 0.411933] megasas: 07.734.00.00-rc1 clare # [ 0.412380] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] alice # [ 0.415446] loop: module loaded alice # [ 0.415586] virtio_blk virtio2: 1/0/0 default/read/poll queues alice # [ 0.416272] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) bob # [ 0.388094] msm_serial: driver initialized bob # [ 0.388164] SuperH (H)SCI(F) driver initialized bob # [ 0.388203] STM32 USART driver initialized clare # [ 0.415756] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.415812] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.417421] Using buffer write method clare # [ 0.417468] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.420726] megasas: 07.734.00.00-rc1 alice # [ 0.421239] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] clare # [ 0.427319] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.427332] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.428963] Using buffer write method clare # [ 0.428974] Concatenating MTD devices: clare # [ 0.428977] (0): "0.flash" clare # [ 0.428978] (1): "0.flash" clare # [ 0.428980] into device "0.flash" alice # [ 0.432318] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.432350] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.434390] Using buffer write method alice # [ 0.434456] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.438251] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.438264] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.412458] loop: module loaded bob # [ 0.412594] virtio_blk virtio2: 1/0/0 default/read/poll queues bob # [ 0.413166] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) bob # [ 0.416823] megasas: 07.734.00.00-rc1 bob # [ 0.417325] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] alice # [ 0.447986] Using buffer write method alice # [ 0.448000] Concatenating MTD devices: alice # [ 0.448002] (0): "0.flash" alice # [ 0.448004] (1): "0.flash" alice # [ 0.448005] into device "0.flash" bob # [ 0.421152] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.421180] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.422862] Using buffer write method bob # [ 0.422891] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] bob # [ 0.433182] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.433231] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.434878] Using buffer write method bob # [ 0.434889] Concatenating MTD devices: bob # [ 0.434891] (0): "0.flash" bob # [ 0.434893] (1): "0.flash" bob # [ 0.434895] into device "0.flash" clare # [ 0.639486] Freeing initrd memory: 26948K clare # [ 0.646438] tun: Universal TUN/TAP device driver, 1.6 clare # [ 0.648599] thunder_xcv, ver 1.0 clare # [ 0.648615] thunder_bgx, ver 1.0 clare # [ 0.648624] nicpf, ver 1.0 clare # [ 0.648944] e1000: Intel(R) PRO/1000 Network Driver clare # [ 0.648949] e1000: Copyright (c) 1999-2006 Intel Corporation. clare # [ 0.648962] e1000e: Intel(R) PRO/1000 Network Driver clare # [ 0.648967] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. clare # [ 0.648979] igb: Intel(R) Gigabit Ethernet Network Driver clare # [ 0.648981] igb: Copyright (c) 2007-2014 Intel Corporation. clare # [ 0.648991] igbvf: Intel(R) Gigabit Virtual Function Network Driver clare # [ 0.648995] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. clare # [ 0.649059] sky2: driver version 1.30 clare # [ 0.649905] usbcore: registered new interface driver usb-storage clare # [ 0.649945] usbcore: registered new interface driver usbserial_generic clare # [ 0.649951] usbserial: USB Serial support registered for generic alice # [ 0.658560] Freeing initrd memory: 26944K clare # [ 0.657022] ehci-pci 0000:00:07.0: EHCI Host Controller clare # [ 0.657053] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 clare # [ 0.657283] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 clare # [ 0.657440] hv_vmbus: registering driver hyperv_keyboard clare # [ 0.658017] rtc-pl031 9010000.pl031: registered as rtc0 clare # [ 0.658043] rtc-pl031 9010000.pl031: setting system clock to 2026-10-05T10:48:57 UTC (1791197337) clare # [ 0.661149] i2c_dev: i2c /dev entries driver alice # [ 0.665499] tun: Universal TUN/TAP device driver, 1.6 alice # [ 0.668285] thunder_xcv, ver 1.0 alice # [ 0.668301] thunder_bgx, ver 1.0 alice # [ 0.668310] nicpf, ver 1.0 alice # [ 0.668648] e1000: Intel(R) PRO/1000 Network Driver alice # [ 0.668653] e1000: Copyright (c) 1999-2006 Intel Corporation. alice # [ 0.668665] e1000e: Intel(R) PRO/1000 Network Driver alice # [ 0.668670] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. alice # [ 0.668682] igb: Intel(R) Gigabit Ethernet Network Driver alice # [ 0.668684] igb: Copyright (c) 2007-2014 Intel Corporation. alice # [ 0.668694] igbvf: Intel(R) Gigabit Virtual Function Network Driver alice # [ 0.668698] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. alice # [ 0.668768] sky2: driver version 1.30 alice # [ 0.669655] usbcore: registered new interface driver usb-storage alice # [ 0.669732] usbcore: registered new interface driver usbserial_generic alice # [ 0.669738] usbserial: USB Serial support registered for generic alice # [ 0.670052] hv_vmbus: registering driver hyperv_keyboard bob # [ 0.650648] Freeing initrd memory: 26928K bob # [ 0.657591] tun: Universal TUN/TAP device driver, 1.6 bob # [ 0.660277] thunder_xcv, ver 1.0 bob # [ 0.660292] thunder_bgx, ver 1.0 bob # [ 0.660302] nicpf, ver 1.0 bob # [ 0.660622] e1000: Intel(R) PRO/1000 Network Driver bob # [ 0.660626] e1000: Copyright (c) 1999-2006 Intel Corporation. bob # [ 0.660640] e1000e: Intel(R) PRO/1000 Network Driver bob # [ 0.660645] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.660656] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.660658] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.660670] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.660672] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. bob # [ 0.660740] sky2: driver version 1.30 bob # [ 0.661552] usbcore: registered new interface driver usb-storage bob # [ 0.661629] usbcore: registered new interface driver usbserial_generic bob # [ 0.661636] usbserial: USB Serial support registered for generic bob # [ 0.661932] hv_vmbus: registering driver hyperv_keyboard bob # [ 0.662994] ehci-pci 0000:00:07.0: EHCI Host Controller bob # [ 0.663020] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 bob # [ 0.670567] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 bob # [ 0.670763] rtc-pl031 9010000.pl031: registered as rtc0 bob # [ 0.670791] rtc-pl031 9010000.pl031: setting system clock to 2026-10-05T10:48:57 UTC (1791197337) bob # [ 0.670990] i2c_dev: i2c /dev entries driver bob # [ 0.674416] sdhci: Secure Digital Host Controller Interface driver bob # [ 0.674422] sdhci: Copyright(c) Pierre Ossman bob # [ 0.674571] Synopsys Designware Multimedia Card Interface Driver bob # [ 0.674768] sdhci-pltfm: SDHCI platform and OF driver helper alice # [ 0.677449] ehci-pci 0000:00:07.0: EHCI Host Controller clare # [ 0.663150] sdhci: Secure Digital Host Controller Interface driver bob # [ 0.677432] hid: raw HID events driver (C) Jiri Kosina clare # [ 0.663155] sdhci: Copyright(c) Pierre Ossman bob # [ 0.677573] usbcore: registered new interface driver usbhid alice # [ 0.677477] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 bob # [ 0.677576] usbhid: USB HID core driver clare # [ 0.663309] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.677707] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 clare # [ 0.663487] sdhci-pltfm: SDHCI platform and OF driver helper alice # [ 0.677893] rtc-pl031 9010000.pl031: registered as rtc0 clare # [ 0.664358] hid: raw HID events driver (C) Jiri Kosina alice # [ 0.677921] rtc-pl031 9010000.pl031: setting system clock to 2026-10-05T10:48:57 UTC (1791197337) clare # [ 0.664493] usbcore: registered new interface driver usbhid clare # [ 0.664495] usbhid: USB HID core driver alice # [ 0.678139] i2c_dev: i2c /dev entries driver alice # [ 0.679812] sdhci: Secure Digital Host Controller Interface driver alice # [ 0.679831] sdhci: Copyright(c) Pierre Ossman alice # [ 0.679998] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.680190] sdhci-pltfm: SDHCI platform and OF driver helper bob # [ 0.688004] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 alice # [ 0.681136] hid: raw HID events driver (C) Jiri Kosina bob # [ 0.688399] hub 1-0:1.0: USB hub found bob # [ 0.688418] hub 1-0:1.0: 6 ports detected alice # [ 0.681283] usbcore: registered new interface driver usbhid clare # [ 0.715986] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 alice # [ 0.681286] usbhid: USB HID core driver clare # [ 0.716418] hub 1-0:1.0: USB hub found bob # [ 0.688712] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available clare # [ 0.716437] hub 1-0:1.0: 6 ports detected bob # [ 0.689519] drop_monitor: Initializing network drop monitor service clare # [ 0.716739] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available bob # [ 0.689659] NET: Registered PF_INET6 protocol family clare # [ 0.717616] drop_monitor: Initializing network drop monitor service bob # [ 0.690291] Segment Routing with IPv6 bob # [ 0.690308] In-situ OAM (IOAM) with IPv6 clare # [ 0.717729] NET: Registered PF_INET6 protocol family bob # [ 0.690365] NET: Registered PF_PACKET protocol family alice # [ 0.721596] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 bob # [ 0.690429] 9pnet: Installing 9P2000 support bob # [ 0.690457] Key type dns_resolver registered alice # [ 0.721784] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available alice # [ 0.722680] drop_monitor: Initializing network drop monitor service clare # [ 0.720925] Segment Routing with IPv6 clare # [ 0.720943] In-situ OAM (IOAM) with IPv6 alice # [ 0.722832] NET: Registered PF_INET6 protocol family alice # [ 0.723324] hub 1-0:1.0: USB hub found clare # [ 0.720998] NET: Registered PF_PACKET protocol family alice # [ 0.723356] hub 1-0:1.0: 6 ports detected clare # [ 0.721068] 9pnet: Installing 9P2000 support clare # [ 0.721111] Key type dns_resolver registered bob # [ 0.697593] registered taskstats version 1 bob # [ 0.697729] Loading compiled-in X.509 certificates clare # [ 0.725531] registered taskstats version 1 clare # [ 0.725665] Loading compiled-in X.509 certificates alice # [ 0.726639] Segment Routing with IPv6 alice # [ 0.726657] In-situ OAM (IOAM) with IPv6 alice # [ 0.726725] NET: Registered PF_PACKET protocol family alice # [ 0.726795] 9pnet: Installing 9P2000 support alice # [ 0.726826] Key type dns_resolver registered bob # [ 0.703005] Demotion targets for Node 0: null bob # [ 0.703080] Key type .fscrypt registered bob # [ 0.703082] Key type fscrypt-provisioning registered bob # [ 0.703199] ima: No TPM chip found, activating TPM-bypass! bob # [ 0.703209] ima: Allocated hash algorithm: sha1 bob # [ 0.703263] ima: No architecture policies found alice # [ 0.731421] registered taskstats version 1 alice # [ 0.731558] Loading compiled-in X.509 certificates bob # [ 0.705855] input: gpio-keys as /devices/platform/gpio-keys/input/input0 clare # [ 0.731012] Demotion targets for Node 0: null clare # [ 0.731092] Key type .fscrypt registered clare # [ 0.731098] Key type fscrypt-provisioning registered clare # [ 0.731197] ima: No TPM chip found, activating TPM-bypass! clare # [ 0.731206] ima: Allocated hash algorithm: sha1 clare # [ 0.731262] ima: No architecture policies found alice # [ 0.737152] Demotion targets for Node 0: null alice # [ 0.737231] Key type .fscrypt registered clare # [ 0.731727] input: gpio-keys as /devices/platform/gpio-keys/input/input0 alice # [ 0.737237] Key type fscrypt-provisioning registered alice # [ 0.737343] ima: No TPM chip found, activating TPM-bypass! alice # [ 0.737352] ima: Allocated hash algorithm: sha1 alice # [ 0.737407] ima: No architecture policies found alice # [ 0.737868] input: gpio-keys as /devices/platform/gpio-keys/input/input0 bob # [ 0.716478] clk: Disabling unused clocks bob # [ 0.716487] PM: genpd: Disabling unused power domains clare # [ 0.744081] clk: Disabling unused clocks bob # [ 0.718676] Freeing unused kernel memory: 4736K bob # [ 0.718770] Run /init as init process clare # [ 0.744090] PM: genpd: Disabling unused power domains alice # [ 0.750416] clk: Disabling unused clocks alice # [ 0.750427] PM: genpd: Disabling unused power domains clare # [ 0.747010] Freeing unused kernel memory: 4736K clare # [ 0.747095] Run /init as init process alice # [ 0.753521] Freeing unused kernel memory: 4736K alice # [ 0.753627] Run /init as init process bob # [ 0.741694] systemd[1]: Successfully made /usr/ read-only. clare # [ 0.768824] systemd[1]: Successfully made /usr/ read-only. alice # [ 0.777441] systemd[1]: Successfully made /usr/ read-only. bob # [ 0.939193] usb 1-1: new high-speed USB device number 2 using ehci-pci clare # [ 0.966234] usb 1-1: new high-speed USB device number 2 using ehci-pci alice # [ 0.970978] usb 1-1: new high-speed USB device number 2 using ehci-pci bob # [ 1.078158] 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.102928] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 1.078188] systemd[1]: Detected virtualization qemu. clare # [ 1.102957] systemd[1]: Detected virtualization qemu. bob # [ 1.078228] systemd[1]: Detected architecture arm64. clare # [ 1.102995] systemd[1]: Detected architecture arm64. bob # [ 1.078237] systemd[1]: Running in initrd. clare # [ 1.103004] systemd[1]: Running in initrd. bob # [ 1.079087] systemd[1]: Initializing machine ID from random generator. clare # [ 1.103784] systemd[1]: Initializing machine ID from random generator. bob # [ 1.087497] systemd[1]: Hostname set to . clare # [ 1.104044] systemd[1]: Hostname set to . alice # [ 1.111532] 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.111562] systemd[1]: Detected virtualization qemu. alice # [ 1.111600] systemd[1]: Detected architecture arm64. alice # [ 1.111609] systemd[1]: Running in initrd. alice # [ 1.112440] systemd[1]: Initializing machine ID from random generator. alice # [ 1.112809] systemd[1]: Hostname set to . bob # [ 1.098861] 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.124168] 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.141941] 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.187281] 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.214351] 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.231123] 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.211922] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 1.239253] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 1.252351] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 1.260400] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.288094] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 1.269698] systemd[1]: Created slice Slice /system/modprobe. bob # [ 1.269940] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 1.269995] systemd[1]: Expecting device /dev/disk/by-label/nixos... clare # [ 1.295765] systemd[1]: Created slice Slice /system/modprobe. clare # [ 1.295902] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 1.270038] systemd[1]: Reached target Path Units. bob # [ 1.270062] systemd[1]: Reached target Slice Units. clare # [ 1.295931] systemd[1]: Expecting device /dev/disk/by-label/nixos... bob # [ 1.270084] systemd[1]: Reached target Swaps. clare # [ 1.295952] systemd[1]: Reached target Path Units. bob # [ 1.270107] systemd[1]: Reached target Timer Units. clare # [ 1.295964] systemd[1]: Reached target Slice Units. clare # [ 1.295976] systemd[1]: Reached target Swaps. bob # [ 1.270325] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 1.295988] systemd[1]: Reached target Timer Units. alice # [ 1.300736] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 1.270540] systemd[1]: Listening on Journal Socket (/dev/log). clare # [ 1.296123] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 1.296234] systemd[1]: Listening on Journal Socket (/dev/log). bob # [ 1.270837] systemd[1]: Listening on Journal Sockets. clare # [ 1.296347] systemd[1]: Listening on Journal Sockets. bob # [ 1.275462] systemd[1]: Listening on udev Control Socket. clare # [ 1.296421] systemd[1]: Listening on udev Control Socket. bob # [ 1.275533] systemd[1]: Listening on udev Kernel Socket. bob # [ 1.275548] systemd[1]: Reached target Socket Units. clare # [ 1.296467] systemd[1]: Listening on udev Kernel Socket. alice # [ 1.305089] systemd[1]: Created slice Slice /system/modprobe. alice # [ 1.305228] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 1.296482] systemd[1]: Reached target Socket Units. alice # [ 1.305260] systemd[1]: Expecting device /dev/disk/by-label/nixos... bob # [ 1.278385] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 1.297788] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.305278] systemd[1]: Reached target Path Units. alice # [ 1.305290] systemd[1]: Reached target Slice Units. bob # [ 1.278436] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.305302] systemd[1]: Reached target Swaps. alice # [ 1.305313] systemd[1]: Reached target Timer Units. clare # [ 1.297839] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.305451] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 1.305566] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 1.305680] systemd[1]: Listening on Journal Sockets. alice # [ 1.305751] systemd[1]: Listening on udev Control Socket. alice # [ 1.305794] systemd[1]: Listening on udev Kernel Socket. alice # [ 1.305808] systemd[1]: Reached target Socket Units. alice # [ 1.306797] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.306845] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.315770] systemd[1]: Mounting Kernel Configuration File System... bob # [ 1.287215] systemd[1]: Mounting Kernel Configuration File System... clare # [ 1.314318] systemd[1]: Mounting Kernel Configuration File System... bob # [ 1.295780] systemd[1]: Starting Journal Service... bob # [ 1.296689] systemd[1]: Starting Load Kernel Modules... clare # [ 1.323001] systemd[1]: Starting Journal Service... bob # [ 1.296751] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 1.323119] systemd[1]: Starting Journal Service... bob # [ 1.307154] usb 1-2: new high-speed USB device number 3 using ehci-pci clare # [ 1.334238] usb 1-2: new high-speed USB device number 3 using ehci-pci clare # [ 1.334741] systemd[1]: Starting Load Kernel Modules... clare # [ 1.334826] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 1.311836] systemd[1]: Starting Coldplug All udev Devices... clare # [ 1.340969] systemd[1]: Starting Coldplug All udev Devices... bob # [ 1.319274] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 1.350983] usb 1-2: new high-speed USB device number 3 using ehci-pci alice # [ 1.351251] systemd[1]: Starting Load Kernel Modules... alice # [ 1.351329] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 1.352186] systemd[1]: Starting Coldplug All udev Devices... clare # [ 1.358379] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 1.359207] systemd[1]: Mounted Kernel Configuration File System. clare # [ 1.364382] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.369586] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 1.373471] systemd-journald[71]: Collecting audit messages is disabled. bob # [ 1.343286] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 1.343676] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.380918] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. alice # [ 1.381488] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.381666] systemd[1]: Mounted Kernel Configuration File System. bob # [ 1.364865] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 1.395294] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev bob # [ 1.368058] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 1.373836] systemd-journald[71]: Collecting audit messages is disabled. alice # [ 1.409754] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 alice # [ 1.410069] [drm] features: -virgl +edid -resource_blob -host_visible alice # [ 1.410072] [drm] features: -context_init alice # [ 1.410528] [drm] number of scanouts: 1 alice # [ 1.410537] [drm] number of cap sets: 0 bob # [ 1.384127] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. clare # [ 1.414754] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 1.415959] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 1.419981] systemd-journald[71]: Collecting audit messages is disabled. bob # [ 1.395191] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev clare # [ 1.425334] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. alice # [ 1.423075] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 1.424261] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 1.432377] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic alice # [ 1.432385] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 clare # [ 1.430321] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev bob # [ 1.408256] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 bob # [ 1.408471] [drm] features: -virgl +edid -resource_blob -host_visible bob # [ 1.408475] [drm] features: -context_init bob # [ 1.409064] [drm] number of scanouts: 1 bob # [ 1.409073] [drm] number of cap sets: 0 bob # [ 1.413165] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 1.413329] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 1.413350] systemd[1]: Reached target Local File Systems. clare # [ 1.443552] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 clare # [ 1.443768] [drm] features: -virgl +edid -resource_blob -host_visible clare # [ 1.443770] [drm] features: -context_init clare # [ 1.444227] [drm] number of scanouts: 1 clare # [ 1.444235] [drm] number of cap sets: 0 alice # [ 1.447314] Console: switching to colour frame buffer device 160x50 alice # [ 1.450775] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device bob # [ 1.419453] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 1.431643] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic bob # [ 1.431653] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 clare # [ 1.458999] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 1.459159] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 1.459180] systemd[1]: Reached target Local File Systems. clare # [ 1.460232] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 1.461876] systemd-modules-load[73]: Inserted module 'dm_mod' clare # [ 1.464663] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic clare # [ 1.464671] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 alice # [ 1.462661] systemd-modules-load[73]: Module 'virtio_balloon' is built in alice # [ 1.467351] systemd[1]: Started Journal Service. alice # [ 1.471964] systemd-modules-load[73]: Module 'virtio_console' is built in alice # [ 1.472992] systemd-modules-load[73]: Inserted module 'virtio_gpu' alice # [ 1.473715] systemd-modules-load[73]: Module 'virtio_rng' is built in alice # [ 1.474597] systemd[1]: Finished Load Kernel Modules. alice # [ 1.476355] systemd[1]: Starting Apply Kernel Variables... bob # [ 1.448624] Console: switching to colour frame buffer device 160x50 alice # [ 1.484504] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 1.486695] Console: switching to colour frame buffer device 160x50 bob # [ 1.463533] systemd[1]: Started Journal Service. bob # [ 1.468209] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.498663] 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.497211] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 alice # [ 1.498882] 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.465455] systemd-modules-load[72]: Inserted module 'dm_mod' clare # [ 1.497316] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 alice # [ 1.496824] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 1.475945] 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.476184] 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.474422] systemd-modules-load[72]: Module 'virtio_balloon' is built in alice # [ 1.506016] systemd[1]: Reached target Local File Systems. alice # [ 1.508184] systemd[1]: Starting Create System Files and Directories... clare # [ 1.510504] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device bob # [ 1.481854] systemd-modules-load[72]: Module 'virtio_console' is built in clare # [ 1.514872] systemd[1]: Started Journal Service. bob # [ 1.487327] systemd[1]: Starting Create System Files and Directories... alice # [ 1.517282] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 1.492703] systemd-modules-load[72]: Inserted module 'virtio_gpu' clare # [ 1.520471] systemd-modules-load[72]: Inserted module 'dm_mod' clare # [ 1.521419] systemd-modules-load[72]: Module 'virtio_balloon' is built in alice # [ 1.524217] systemd[1]: Finished Apply Kernel Variables. clare # [ 1.522217] systemd-modules-load[72]: Module 'virtio_console' is built in clare # [ 1.522968] systemd-modules-load[72]: Inserted module 'virtio_gpu' bob # [ 1.496405] systemd-modules-load[72]: Module 'virtio_rng' is built in clare # [ 1.523654] systemd-modules-load[72]: Module 'virtio_rng' is built in bob # [ 1.497143] systemd[1]: Finished Load Kernel Modules. clare # [ 1.532383] systemd[1]: Starting Create System Files and Directories... clare # [ 1.534720] systemd[1]: Finished Load Kernel Modules. bob # [ 1.517374] systemd-udevd[79]: Using default interface naming scheme 'v261'. bob # [ 1.520222] systemd[1]: Starting Apply Kernel Variables... clare # [ 1.552321] systemd-udevd[79]: Using default interface naming scheme 'v261'. clare # [ 1.556989] systemd[1]: Starting Apply Kernel Variables... alice # [ 1.569070] systemd[1]: Finished Create System Files and Directories. bob # [ 1.547130] systemd[1]: Finished Apply Kernel Variables. bob # [ 1.556916] systemd[1]: Finished Create System Files and Directories. alice # [ 1.586352] systemd-udevd[86]: Using default interface naming scheme 'v261'. clare # [ 1.586651] systemd[1]: Finished Create System Files and Directories. bob # [ 1.566701] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 1.595214] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 1.598703] systemd[1]: Finished Apply Kernel Variables. alice # [ 1.612101] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 1.640077] systemd[1]: Starting Virtual Console Setup... clare # [ 1.672106] systemd[1]: Starting Virtual Console Setup... alice # [ 1.689530] systemd[1]: Starting Virtual Console Setup... bob # [ 1.688335] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 1.691407] systemd[1]: Finished Virtual Console Setup. clare # [ 1.720314] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 1.722964] systemd[1]: Finished Virtual Console Setup. alice # [ 1.740421] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 1.743107] systemd[1]: Finished Virtual Console Setup. bob # [ 1.891510] systemd[1]: Finished Coldplug All udev Devices. bob # [ 1.892221] systemd[1]: Reached target System Initialization. bob # [ 1.892776] systemd[1]: Reached target Basic System. clare # [ 1.927681] systemd[1]: Finished Coldplug All udev Devices. clare # [ 1.928402] systemd[1]: Reached target System Initialization. clare # [ 1.928947] systemd[1]: Reached target Basic System. alice # [ 1.941608] systemd[1]: Finished Coldplug All udev Devices. alice # [ 1.942216] systemd[1]: Reached target System Initialization. alice # [ 1.942742] systemd[1]: Reached target Basic System. bob # [ 1.998196] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.035078] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.008253] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.055782] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.054232] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.072488] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.079670] systemd[1]: Found device /dev/disk/by-label/nixos. bob # [ 2.086477] systemd[1]: Reached target Initrd Root Device. bob # [ 2.090050] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... clare # [ 2.124678] systemd[1]: Found device /dev/disk/by-label/nixos. alice # [ 2.127289] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.125745] systemd[1]: Reached target Initrd Root Device. clare # [ 2.128337] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... alice # [ 2.142588] systemd[1]: Reached target Initrd Root Device. alice # [ 2.146500] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... bob # [ 2.127645] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks clare # [ 2.161732] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 2.136103] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. clare # [ 2.168362] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 2.180671] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks alice # [ 2.188238] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. clare # [ 2.337069] systemd[1]: Mounting /sysroot... bob # [ 2.307674] systemd[1]: Mounting /sysroot... alice # [ 2.348099] systemd[1]: Mounting /sysroot... bob # [ 2.334702] systemd[1]: Mounted /sysroot. bob # [ 2.339974] EXT4-fs (vda): mounted filesystem 25e77e32-f3e8-48fc-a2eb-b8ca851e413a r/w with ordered data mode. Quota mode: none. bob # [ 2.337105] systemd[1]: Reached target Initrd Root File System. clare # [ 2.369815] EXT4-fs (vda): mounted filesystem 25b10962-955d-47fe-b97b-0e3150546f0f r/w with ordered data mode. Quota mode: none. clare # [ 2.368072] systemd[1]: Mounted /sysroot. bob # [ 2.341584] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 2.370888] systemd[1]: Reached target Initrd Root File System. alice # [ 2.375640] systemd[1]: Mounted /sysroot.[ 2.379178] EXT4-fs (vda): mounted filesystem 7a79a265-bd73-4d5e-adc5-39ba3bc92172 r/w with ordered data mode. Quota mode: none. alice # alice # [ 2.378236] systemd[1]: Reached target Initrd Root File System. bob # [ 2.351468] systemd[1]: Mounting /sysroot/nix/.rw-store... clare # [ 2.378822] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.382732] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 2.390093] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 2.363564] systemd[1]: Mounting /sysroot/run... alice # [ 2.393025] systemd[1]: Mounting /sysroot/nix/.rw-store... clare # [ 2.395506] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 2.402466] systemd[1]: Mounting /sysroot/run... clare # [ 2.400683] systemd[1]: Reloading... alice # [ 2.413046] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 2.386745] systemd[1]: Mounting /sysroot/tmp/shared... alice # [ 2.425607] systemd[1]: Mounting /sysroot/tmp/xchg... bob # [ 2.409633] systemd[1]: Mounting /sysroot/tmp/xchg... bob # [ 2.412119] systemd[1]: Starting Mountpoints Configured in the Real Root... bob # [ 2.426245] fuse: init (API version 7.45) alice # [ 2.460269] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.461540] systemd[1]: Mounted /sysroot/run. bob # [ 2.435934] virtiofs virtio6: discovered new tag: nix-store bob # [ 2.436439] virtiofs virtio6: virtio_fs_setup_dax: No cache capability alice # [ 2.474566] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 2.452820] virtiofs virtio7: discovered new tag: shared bob # [ 2.453399] virtiofs virtio7: virtio_fs_setup_dax: No cache capability bob # [ 2.449126] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 2.458733] virtiofs virtio8: discovered new tag: xchg bob # [ 2.462869] virtiofs virtio8: virtio_fs_setup_dax: No cache capability bob # [ 2.459972] systemd[1]: Mounted /sysroot/run. alice # [ 2.496740] fuse: init (API version 7.45) alice # [ 2.494114] systemd-sysroot-fstab-check[123]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 2.504484] virtiofs virtio6: discovered new tag: nix-store alice # [ 2.504994] virtiofs virtio6: virtio_fs_setup_dax: No cache capability alice # [ 2.504750] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 2.477355] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 2.481476] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 2.483161] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 2.520272] virtiofs virtio7: discovered new tag: shared alice # [ 2.520807] virtiofs virtio7: virtio_fs_setup_dax: No cache capability alice # [ 2.517096] systemd[1]: Reload requested from client PID 123 ('systemd-sysroot') (unit initrd-parse-etc.service)... bob # [ 2.489094] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 2.526207] virtiofs virtio8: discovered new tag: xchg alice # [ 2.526694] virtiofs virtio8: virtio_fs_setup_dax: No cache capability alice # [ 2.523331] systemd[1]: Reloading... bob # [ 2.499790] systemd[1]: Mounted /sysroot/tmp/xchg. bob # [ 2.504478] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)... bob # [ 2.507259] systemd[1]: Reloading... clare # [ 2.571624] systemd[1]: Reloading finished in 174 ms. clare # [ 2.600848] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... clare # [ 2.602831] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... clare # [ 2.608299] systemd[1]: initrd-parse-etc.service: Deactivated successfully. clare # [ 2.610010] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 2.611322] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.713016] systemd[1]: Reloading finished in 197 ms. bob # [ 2.685907] systemd[1]: Reloading finished in 182 ms. alice # [ 2.740682] systemd-sysroot-fstab-check[123]: Requesting initrd-fs.target/start/replace... alice # [ 2.743315] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 2.744830] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 2.746454] systemd[1]: Mounted /sysroot/tmp/xchg. bob # [ 2.717766] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace... alice # [ 2.748855] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 2.720321] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 2.721018] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 2.751560] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 2.722335] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace... alice # [ 2.755153] systemd-sysroot-fstab-check[123]: Requesting swap.target/start/replace... alice # [ 2.759188] systemd[1]: initrd-parse-etc.service: Deactivated successfully. bob # [ 2.730967] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 2.760548] systemd[1]: Finished Mountpoints Configured in the Real Root. alice # [ 2.763593] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. bob # [ 2.734493] systemd[1]: initrd-parse-etc.service: Deactivated successfully. bob # [ 2.736128] systemd[1]: Finished Mountpoints Configured in the Real Root. bob # [ 2.738648] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.769638] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 2.753867] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 2.754857] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 2.787483] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 2.788571] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 2.862310] fuse: init (API version 7.45) clare # [ 2.889704] virtiofs virtio6: discovered new tag: nix-store clare # [ 2.905573] virtiofs virtio6: virtio_fs_setup_dax: No cache capability clare # [ 2.901371] (udev-worker)[90]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory clare # [ 2.903375] (udev-worker)[90]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory clare # [ 2.919786] virtiofs virtio7: discovered new tag: shared clare # [ 2.920262] virtiofs virtio7: virtio_fs_setup_dax: No cache capability clare # [ 2.927728] virtiofs virtio8: discovered new tag: xchg clare # [ 2.928199] virtiofs virtio8: virtio_fs_setup_dax: No cache capability clare # [ 2.925620] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 2.927722] systemd[1]: Stopped Virtual Console Setup. clare # [ 2.931855] systemd[1]: Stopping Virtual Console Setup... clare # [ 2.933431] systemd[1]: Starting Virtual Console Setup... clare # [ 2.954043] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 2.956170] systemd[1]: Finished Virtual Console Setup. bob # [ 2.964827] (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 bob # [ 2.966890] (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 bob # [ 2.987077] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 2.988821] systemd[1]: Stopped Virtual Console Setup. bob # [ 2.992100] systemd[1]: Stopping Virtual Console Setup... bob # [ 2.992918] systemd[1]: Starting Virtual Console Setup... alice # [ 3.032330] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory alice # [ 3.036255] (udev-worker)[99]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory bob # [ 3.010635] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 3.012932] systemd[1]: Finished Virtual Console Setup. alice # [ 3.056590] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.058120] systemd[1]: Stopped Virtual Console Setup. alice # [ 3.058908] systemd[1]: Stopping Virtual Console Setup... alice # [ 3.061141] systemd[1]: Starting Virtual Console Setup... alice # [ 3.082816] systemd-vconsole-setup[159]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 3.084947] systemd[1]: Finished Virtual Console Setup. bob # [ 3.308826] systemd[1]: Mounting /sysroot/nix/store... clare # [ 3.340137] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 3.345021] systemd[1]: Mounting /sysroot/nix/.rw-store... alice # [ 3.349528] systemd[1]: Mounting /sysroot/nix/store... clare # [ 3.360223] systemd[1]: Mounting /sysroot/run... clare # [ 3.368127] systemd[1]: Mounting /sysroot/tmp/shared... clare # [ 3.380289] systemd[1]: Mounting /sysroot/tmp/xchg... bob # [ 3.360776] systemd[1]: Mounted /sysroot/nix/store. bob # [ 3.363005] systemd[1]: Reached target Initrd File Systems. bob # [ 3.366823] systemd[1]: Starting Find NixOS closure... alice # [ 3.400357] systemd[1]: Mounted /sysroot/nix/store. alice # [ 3.402764] systemd[1]: Reached target Initrd File Systems. bob # [ 3.376301] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... alice # [ 3.406665] systemd[1]: Starting Find NixOS closure... alice # [ 3.413711] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... clare # [ 3.416260] systemd[1]: Mounted /sysroot/nix/.ro-store. clare # [ 3.425101] systemd[1]: Mounted /sysroot/nix/.rw-store. clare # [ 3.428655] systemd[1]: Mounted /sysroot/run. clare # [ 3.433042] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 3.433946] systemd[1]: Mounted /sysroot/tmp/xchg. clare # [ 3.437045] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 3.418760] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 3.422337] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. alice # [ 3.453480] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. clare # [ 3.455728] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.457257] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 3.459922] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. clare # [ 3.459532] systemd[1]: Mounting /sysroot/nix/store... bob # [ 3.437732] systemd[1]: Finished Find NixOS closure. bob # [ 3.440254] systemd[1]: Reached target Initrd Default Target. alice # [ 3.472155] systemd[1]: Finished Find NixOS closure. bob # [ 3.442951] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 3.474674] systemd[1]: Reached target Initrd Default Target. alice # [ 3.480353] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... clare # [ 3.500397] systemd[1]: Mounted /sysroot/nix/store. bob # [ 3.473147] systemd[1]: Stopped target Initrd Default Target. clare # [ 3.501567] systemd[1]: Reached target Initrd File Systems. bob # [ 3.474742] systemd[1]: Stopped target Basic System. alice # [ 3.504701] systemd[1]: initrd-cleanup.service: Deactivated successfully. bob # [ 3.476313] systemd[1]: Stopped target Initrd Root Device. clare # [ 3.503803] systemd[1]: Starting Find NixOS closure... alice # [ 3.506163] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. alice # [ 3.509575] systemd[1]: Stopped target Initrd Default Target. bob # [ 3.480469] systemd[1]: Stopped target Path Units. bob # [ 3.481524] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 3.512170] systemd[1]: Stopped target Basic System. alice # [ 3.512988] systemd[1]: Stopped target Initrd Root Device. bob # [ 3.483932] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. clare # [ 3.512214] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 3.485875] systemd[1]: Stopped target Slice Units. alice # [ 3.515272] systemd[1]: Stopped target Path Units. bob # [ 3.488131] systemd[1]: Stopped target Socket Units. bob # [ 3.489289] systemd[1]: Stopped target System Initialization. alice # [ 3.520168] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 3.523318] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. bob # [ 3.494809] systemd[1]: Stopped target Swaps. bob # [ 3.496630] systemd[1]: Stopped target Timer Units. alice # [ 3.526018] systemd[1]: Stopped target Slice Units. alice # [ 3.527717] systemd[1]: Stopped target Socket Units. bob # [ 3.501211] systemd[1]: dbus.socket: Deactivated successfully. alice # [ 3.532234] systemd[1]: Stopped target System Initialization. alice # [ 3.533101] systemd[1]: Stopped target Swaps. alice # [ 3.533709] systemd[1]: Stopped target Timer Units. bob # [ 3.504219] systemd[1]: Closed D-Bus System Message Bus Socket. alice # [ 3.534284] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 3.505042] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. alice # [ 3.534959] systemd[1]: Closed D-Bus System Message Bus Socket. alice # [ 3.535664] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 3.509002] systemd[1]: Stopped Find NixOS closure. bob # [ 3.509691] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 3.511977] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 3.513409] systemd[1]: Stopped Apply Kernel Variables. clare # [ 3.541416] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. alice # [ 3.544202] systemd[1]: Stopped Find NixOS closure. alice # [ 3.544890] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.545856] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 3.517282] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 3.518976] systemd[1]: Stopped Load Kernel Modules. bob # [ 3.519697] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 3.551730] systemd[1]: Stopped Apply Kernel Variables. alice # [ 3.552483] systemd[1]: systemd-modules-load.service: Deactivated successfully. alice # [ 3.553593] systemd[1]: Stopped Load Kernel Modules. alice # [ 3.556138] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. bob # [ 3.527065] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 3.555398] systemd[1]: Finished Find NixOS closure. alice # [ 3.558808] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 3.556972] systemd[1]: Reached target Initrd Default Target. bob # [ 3.530229] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 3.532296] systemd[1]: Stopped Create System Files and Directories. clare # [ 3.559703] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 3.562037] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 3.534001] systemd[1]: Stopped target Local File Systems. alice # [ 3.564149] systemd[1]: Stopped Create System Files and Directories. bob # [ 3.534818] systemd[1]: Stopped target Preparation for Local File Systems. alice # [ 3.564794] systemd[1]: Stopped target Local File Systems. alice # [ 3.568147] systemd[1]: Stopped target Preparation for Local File Systems. alice # [ 3.568811] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. bob # [ 3.539637] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 3.569625] systemd[1]: Stopped Coldplug All udev Devices. bob # [ 3.540836] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 3.570147] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 3.541377] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 3.542090] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 3.542819] systemd[1]: Stopped Virtual Console Setup. alice # [ 3.572231] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.573022] systemd[1]: Stopped Virtual Console Setup. bob # [ 3.543353] systemd[1]: initrd-cleanup.service: Deactivated successfully. bob # [ 3.546199] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. bob # [ 3.546881] systemd[1]: systemd-udevd.service: Deactivated successfully. alice # [ 3.576506] systemd[1]: systemd-udevd.service: Deactivated successfully. bob # [ 3.547523] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 3.577299] systemd[1]: Stopped Rule-based Manager for Device Events and Files. bob # [ 3.548545] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 3.577933] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. bob # [ 3.549206] systemd[1]: Closed udev Control Socket. alice # [ 3.578560] systemd[1]: Closed udev Control Socket. alice # [ 3.579017] systemd[1]: Starting Cleanup udev Database... bob # [ 3.549701] systemd[1]: Starting Cleanup udev Database... bob # [ 3.550231] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 3.550930] systemd[1]: Stopped Create Static Device Nodes in /dev. bob # [ 3.551532] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. alice # [ 3.579499] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. alice # [ 3.584189] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 3.584751] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. alice # [ 3.585454] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. alice # [ 3.586061] systemd[1]: kmod-static-nodes.service: Deactivated successfully. alice # [ 3.586668] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 3.587253] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.587884] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.586357] systemd[1]: Stopped target Initrd Default Target. bob # [ 3.560258] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. bob # [ 3.560929] systemd[1]: kmod-static-nodes.service: Deactivated successfully. clare # [ 3.588370] systemd[1]: Stopped target Basic System. bob # [ 3.561746] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 3.590971] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 3.562354] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.589660] systemd[1]: Stopped target Initrd Root Device. alice # [ 3.593356] systemd[1]: Finished Cleanup udev Database. bob # [ 3.564180] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 3.594170] systemd[1]: Reached target Switch Root. clare # [ 3.592244] systemd[1]: Stopped target Path Units. clare # [ 3.592908] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 3.596463] systemd[1]: Starting NixOS Activation... clare # [ 3.594554] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. clare # [ 3.599942] systemd[1]: Stopped target Slice Units. clare # [ 3.600658] systemd[1]: Stopped target Socket Units. bob # [ 3.573730] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. clare # [ 3.601276] systemd[1]: Stopped target System Initialization. bob # [ 3.574452] systemd[1]: Finished Cleanup udev Database. clare # [ 3.601915] systemd[1]: Stopped target Swaps. clare # [ 3.602440] systemd[1]: Stopped target Timer Units. clare # [ 3.603183] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 3.576645] systemd[1]: Reached target Switch Root. clare # [ 3.603863] systemd[1]: Closed D-Bus System Message Bus Socket. bob # [ 3.577165] systemd[1]: Starting NixOS Activation... clare # [ 3.607481] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. clare # [ 3.616523] systemd[1]: Stopped Find NixOS closure. clare # [ 3.617116] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.617843] systemd[1]: systemd-sysctl.service: Deactivated successfully. clare # [ 3.618541] systemd[1]: Stopped Apply Kernel Variables. clare # [ 3.619225] systemd[1]: systemd-modules-load.service: Deactivated successfully. clare # [ 3.619935] systemd[1]: Stopped Load Kernel Modules. clare # [ 3.627377] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. clare # [ 3.628902] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 3.629813] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. clare # [ 3.630512] systemd[1]: Stopped Create System Files and Directories. clare # [ 3.631218] systemd[1]: Stopped target Local File Systems. clare # [ 3.631740] systemd[1]: Stopped target Preparation for Local File Systems. clare # [ 3.635500] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. clare # [ 3.640216] systemd[1]: Stopped Coldplug All udev Devices. clare # [ 3.640725] systemd[1]: Stopping Rule-based Manager for Device Events and Files... clare # [ 3.641372] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.642020] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.642492] systemd[1]: initrd-cleanup.service: Deactivated successfully. clare # [ 3.643184] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. clare # [ 3.643750] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.644677] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.645211] systemd[1]: systemd-udevd.service: Deactivated successfully. clare # [ 3.645794] systemd[1]: Stopped Rule-based Manager for Device Events and Files. clare # [ 3.646438] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. clare # [ 3.647079] systemd[1]: Closed udev Control Socket. clare # [ 3.647521] systemd[1]: Starting Cleanup udev Database... clare # [ 3.647992] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. clare # [ 3.648780] systemd[1]: Stopped Create Static Device Nodes in /dev. clare # [ 3.649348] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. clare # [ 3.650060] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. clare # [ 3.650672] systemd[1]: kmod-static-nodes.service: Deactivated successfully. clare # [ 3.651290] systemd[1]: Stopped Create List of Static Device Nodes. clare # [ 3.668505] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. alice # [ 3.670263] initrd-nixos-activation-start[182]: booting system configuration /nix/store/kq0j6qy8xgmdx7cbimmnsnci26v7rpzv-nixos-system-alice-test clare # [ 3.669936] systemd[1]: Finished Cleanup udev Database. clare # [ 3.670446] systemd[1]: Reached target Switch Root. clare # [ 3.672866] systemd[1]: Starting NixOS Activation... bob # [ 3.650157] initrd-nixos-activation-start[175]: booting system configuration /nix/store/y6bki65bnyl7icghswfggs0rgqryrg6y-nixos-system-bob-test alice # [ 3.698534] initrd-nixos-activation-start[182]: running activation script... bob # [ 3.678390] initrd-nixos-activation-start[175]: running activation script... clare # [ 3.744616] initrd-nixos-activation-start[172]: booting system configuration /nix/store/q82yc0bv5n8fk6y1nv8g5kdp80x45p46-nixos-system-clare-test clare # [ 3.771131] initrd-nixos-activation-start[172]: running activation script... alice # [ 3.909984] initrd-nixos-activation-start[205]: setting up /etc... bob # [ 3.892362] initrd-nixos-activation-start[198]: setting up /etc... clare # [ 3.991173] initrd-nixos-activation-start[195]: setting up /etc... alice # [ 3.996064] initrd-nixos-activation-start[205]: setting up age secrets... bob # [ 3.974906] initrd-nixos-activation-start[198]: setting up age secrets... alice # [ 4.058088] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. alice # [ 4.059856] systemd[1]: Finished NixOS Activation. alice # [ 4.060802] systemd[1]: Starting Switch Root... bob # [ 4.046060] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 4.047912] systemd[1]: Finished NixOS Activation. bob # [ 4.048855] systemd[1]: Starting Switch Root... clare # [ 4.076156] initrd-nixos-activation-start[195]: setting up age secrets... alice # [ 4.080520] systemd[1]: Switching root. bob # [ 4.068534] systemd[1]: Switching root. clare # [ 4.136267] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. clare # [ 4.137995] systemd[1]: Finished NixOS Activation. clare # [ 4.138710] systemd[1]: Starting Switch Root... clare # [ 4.156123] systemd[1]: Switching root. alice # [ 4.255162] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). bob # [ 4.235586] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). clare # [ 4.322322] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). alice # [ 4.800237] 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.800331] systemd[1]: Detected virtualization qemu. alice # [ 4.800388] systemd[1]: Detected architecture arm64. alice # [ 4.800502] systemd[1]: Detected first boot. alice # [ 4.802575] systemd[1]: Initializing machine ID from random generator. clare # [ 4.822770] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) clare # [ 4.822879] systemd[1]: Detected virtualization qemu. clare # [ 4.822937] systemd[1]: Detected architecture arm64. clare # [ 4.823046] systemd[1]: Detected first boot. clare # [ 4.825160] systemd[1]: Initializing machine ID from random generator. bob # [ 4.831795] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 4.831912] systemd[1]: Detected virtualization qemu. bob # [ 4.831970] systemd[1]: Detected architecture arm64. bob # [ 4.832084] systemd[1]: Detected first boot. bob # [ 4.834618] systemd[1]: Initializing machine ID from random generator. alice # [ 4.945324] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 4.972511] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 4.979969] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 5.060095] systemd[1]: Applying preset policy. alice # [ 5.118089] systemd[1]: Applying preset policy. clare # [ 5.126822] systemd[1]: Applying preset policy. bob # [ 5.232979] systemd[1]: Populated /etc with preset unit settings. alice # [ 5.275694] systemd[1]: Populated /etc with preset unit settings. clare # [ 5.277721] systemd[1]: Populated /etc with preset unit settings. bob # [ 5.450535] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 5.450825] systemd[1]: Stopped initrd-switch-root.service. bob # [ 5.454491] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 5.457080] systemd[1]: Created slice Slice /system/getty. bob # [ 5.457988] systemd[1]: Created slice User and Session Slice. bob # [ 5.458550] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 5.490019] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 5.461290] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 5.461790] systemd[1]: Expecting device /dev/hvc0... bob # [ 5.462372] systemd[1]: Expecting device /dev/ttyAMA0... bob # [ 5.462999] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 5.490296] systemd[1]: Stopped initrd-switch-root.service. alice # [ 5.493929] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 5.466244] systemd[1]: Stopped target initrd-fs.target. bob # [ 5.466681] systemd[1]: Stopped target initrd-root-fs.target. alice # [ 5.496366] systemd[1]: Created slice Slice /system/getty. clare # [ 5.497514] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 5.468740] systemd[1]: Stopped target initrd-switch-root.target. clare # [ 5.497824] systemd[1]: Stopped initrd-switch-root.service. bob # [ 5.469208] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 5.497348] systemd[1]: Created slice User and Session Slice. alice # [ 5.497916] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 5.498514] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 5.469792] systemd[1]: Reached target Path Units. bob # [ 5.470446] systemd[1]: Reached target Remote File Systems. bob # [ 5.471069] systemd[1]: Reached target Slice Units. alice # [ 5.501641] systemd[1]: Expecting device /dev/hvc0... alice # [ 5.502120] systemd[1]: Expecting device /dev/ttyAMA0... bob # [ 5.474571] systemd[1]: Reached target Swaps. alice # [ 5.502710] systemd[1]: Reached target Local Encrypted Volumes. clare # [ 5.500522] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. clare # [ 5.501808] systemd[1]: Created slice Slice /system/getty. alice # [ 5.505145] systemd[1]: Stopped target initrd-fs.target. alice # [ 5.505631] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 5.478109] systemd[1]: Listening on Query the User Interactively for a Password. alice # [ 5.506192] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 5.506754] systemd[1]: Reached target Virtual Machines and Containers. clare # [ 5.504650] systemd[1]: Created slice User and Session Slice. clare # [ 5.505266] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 5.505866] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 5.509695] systemd[1]: Reached target Path Units. alice # [ 5.510159] systemd[1]: Reached target Remote File Systems. alice # [ 5.510703] systemd[1]: Reached target Slice Units. bob # [ 5.481008] systemd[1]: Listening on Process Core Dump Socket. bob # [ 5.482648] systemd[1]: Listening on Credential Encryption/Decryption. clare # [ 5.508813] systemd[1]: Expecting device /dev/hvc0... clare # [ 5.509321] systemd[1]: Expecting device /dev/ttyAMA0... clare # [ 5.509882] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 5.512945] systemd[1]: Reached target Swaps. bob # [ 5.486135] systemd[1]: Listening on Factory Reset Management. bob # [ 5.486702] systemd[1]: Listening on Hostname Service Socket. clare # [ 5.512287] systemd[1]: Stopped target initrd-fs.target. clare # [ 5.512771] systemd[1]: Stopped target initrd-root-fs.target. clare # [ 5.513346] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 5.515371] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 5.513927] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 5.517540] systemd[1]: Listening on Process Core Dump Socket. clare # [ 5.516913] systemd[1]: Reached target Path Units. clare # [ 5.517407] systemd[1]: Reached target Remote File Systems. clare # [ 5.517971] systemd[1]: Reached target Slice Units. alice # [ 5.520158] systemd[1]: Listening on Credential Encryption/Decryption. bob # [ 5.491539] systemd[1]: Starting Journal Log Access Socket... alice # [ 5.521920] systemd[1]: Listening on Factory Reset Management. alice # [ 5.522472] systemd[1]: Listening on Hostname Service Socket. clare # [ 5.520315] systemd[1]: Reached target Swaps. bob # [ 5.492500] systemd[1]: Listening on Journal Audit Socket. bob # [ 5.494933] systemd[1]: Listening on Console Output Muting Service Socket. bob # [ 5.497112] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 5.522864] systemd[1]: Listening on Query the User Interactively for a Password. bob # [ 5.497851] systemd[1]: Listening on Network Management Varlink Socket. clare # [ 5.525017] systemd[1]: Listening on Process Core Dump Socket. bob # [ 5.498483] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 5.527418] systemd[1]: Starting Journal Log Access Socket... alice # [ 5.528352] systemd[1]: Listening on Journal Audit Socket. alice # [ 5.530606] systemd[1]: Listening on Console Output Muting Service Socket. bob # [ 5.501322] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. clare # [ 5.527798] systemd[1]: Listening on Credential Encryption/Decryption. clare # [ 5.529635] systemd[1]: Listening on Factory Reset Management. bob # [ 5.502147] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os bob # [ 5.502844] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki alice # [ 5.533272] systemd[1]: Listening on Network Management Metrics Varlink Socket. alice # [ 5.533936] systemd[1]: Listening on Network Management Varlink Socket. alice # [ 5.534576] systemd[1]: Listening on Network Management Netlink Socket. clare # [ 5.531666] systemd[1]: Listening on Hostname Service Socket. alice # [ 5.537498] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 5.510233] systemd[1]: Listening on Disk Repartitioning Service Socket. alice # [ 5.538726] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os clare # [ 5.534966] systemd[1]: Starting Journal Log Access Socket... bob # [ 5.510777] systemd[1]: Listening on Resolve Monitor Varlink Socket. clare # [ 5.536346] systemd[1]: Listening on Journal Audit Socket. alice # [ 5.541205] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki bob # [ 5.512496] systemd[1]: Listening on Resolve Service Varlink Socket. bob # [ 5.513141] systemd[1]: Listening on udev Control Socket. bob # [ 5.513692] systemd[1]: Listening on udev Varlink Socket. clare # [ 5.539782] systemd[1]: Listening on Console Output Muting Service Socket. clare # [ 5.540466] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 5.541232] systemd[1]: Listening on Network Management Varlink Socket. clare # [ 5.541868] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 5.545928] systemd[1]: Listening on Disk Repartitioning Service Socket. alice # [ 5.546450] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 5.517754] systemd[1]: Mounting Huge Pages File System... clare # [ 5.545120] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. clare # [ 5.546086] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 5.548571] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 5.549074] systemd[1]: Listening on udev Control Socket. alice # [ 5.549626] systemd[1]: Listening on udev Varlink Socket. bob # [ 5.520770] systemd[1]: Mounting POSIX Message Queue File System... clare # [ 5.548430] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki alice # [ 5.553169] systemd[1]: Mounting Huge Pages File System... clare # [ 5.553026] systemd[1]: Listening on Disk Repartitioning Service Socket. clare # [ 5.553571] systemd[1]: Listening on Resolve Monitor Varlink Socket. clare # [ 5.554131] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 5.555510] systemd[1]: Mounting POSIX Message Queue File System... bob # [ 5.527709] systemd[1]: Mounting Kernel Debug File System... clare # [ 5.556150] systemd[1]: Listening on udev Control Socket. clare # [ 5.556741] systemd[1]: Listening on udev Varlink Socket. clare # [ 5.559853] systemd[1]: Mounting Huge Pages File System... alice # [ 5.563225] systemd[1]: Mounting Kernel Debug File System... clare # [ 5.562012] systemd[1]: Mounting POSIX Message Queue File System... bob # [ 5.537968] systemd[1]: Mounting Kernel Trace File System... clare # [ 5.570591] systemd[1]: Mounting Kernel Debug File System... alice # [ 5.576047] systemd[1]: Mounting Kernel Trace File System... bob # [ 5.548214] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 5.548627] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 5.581015] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 5.582776] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 5.583310] systemd[1]: Mounting Kernel Trace File System... bob # [ 5.560433] systemd[1]: Mounting Kernel Configuration File System... bob # [ 5.561244] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm clare # [ 5.588020] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 5.589956] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 5.598403] systemd[1]: Mounting Kernel Configuration File System... bob # [ 5.568057] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore alice # [ 5.598879] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm bob # [ 5.568670] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 5.604085] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore alice # [ 5.604757] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 5.603928] systemd[1]: Mounting Kernel Configuration File System... clare # [ 5.604380] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm clare # [ 5.610861] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 5.611326] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 5.591109] systemd[1]: Mounting FUSE Control File System... bob # [ 5.591692] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 clare # [ 5.624761] systemd[1]: Mounting FUSE Control File System... clare # [ 5.625289] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 alice # [ 5.628650] systemd[1]: Mounting FUSE Control File System... alice # [ 5.629069] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 bob # [ 5.607593] systemd[1]: Starting Journal Service... bob # [ 5.622696] systemd[1]: Starting Load Kernel Modules... alice # [ 5.654607] systemd[1]: Starting Journal Service... clare # [ 5.653082] systemd[1]: Starting Journal Service... alice # [ 5.664857] systemd[1]: Starting Load Kernel Modules... bob # [ 5.646224] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 5.671833] systemd[1]: Starting Load Kernel Modules... alice # [ 5.677273] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... bob # [ 5.662365] systemd[1]: Starting Remount Root and Kernel File Systems... bob # [ 5.662760] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 5.691629] systemd[1]: Starting Remount Root and Kernel File Systems... alice # [ 5.692041] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 5.691912] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 5.699233] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 5.699637] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 5.680749] systemd-journald[291]: Collecting audit messages is enabled. clare # [ 5.708501] systemd[1]: Starting Coldplug All udev Devices... alice # [ 5.713916] systemd[1]: Starting Coldplug All udev Devices... alice # [ 5.718289] systemd[1]: Listening on Journal Log Access Socket. clare # [ 5.716543] systemd[1]: Listening on Journal Log Access Socket. alice # [ 5.718791] systemd[1]: Mounted Huge Pages File System. bob # [ 5.685609] systemd[1]: Queued start job for default target Multi-User System. bob # [ 5.687679] systemd[1]: Starting Coldplug All udev Devices... bob # [ 5.689569] systemd[1]: Listening on Journal Log Access Socket. clare # [ 5.717085] systemd[1]: Mounted Huge Pages File System. clare # [ 5.717469] systemd[1]: Mounted POSIX Message Queue File System. clare # [ 5.717807] systemd[1]: Mounted Kernel Debug File System. clare # [ 5.718150] systemd[1]: Mounted Kernel Trace File System. bob # [ 5.689993] systemd[1]: Mounted Huge Pages File System. bob # [ 5.690363] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 5.690737] systemd[1]: Mounted Kernel Debug File System. bob # [ 5.691081] systemd[1]: Mounted Kernel Trace File System. bob # [ 5.690043] systemd[1]: systemd-journald.service: Deactivated successfully. bob # [ 5.694224] systemd-modules-load[292]: Module 'atkbd' is built in alice # [ 5.727247] systemd[1]: Mounted POSIX Message Queue File System. alice # [ 5.727710] systemd[1]: Mounted Kernel Debug File System. alice # [ 5.728150] systemd[1]: Mounted Kernel Trace File System. alice # [ 5.730309] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 5.730869] systemd[1]: Mounted Kernel Configuration File System. bob # [ 5.699394] systemd-modules-load[292]: Module 'loop' is built in[ 5.705949] systemd[1]: Started Journal Service. bob # bob # [ 5.706223] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 5.709005] systemd[1]: Finished Load Kernel Modules. clare # [ 5.735845] systemd[1]: Queued start job for default target Multi-User System. clare # [ 5.738661] systemd-journald[280]: Collecting audit messages is enabled. clare # [ 5.744331] systemd[1]: Started Journal Service. alice # [ 5.747049] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 5.742332] systemd[1]: systemd-journald.service: Deactivated successfully. clare # [ 5.743068] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 5.743731] systemd[1]: Mounted Kernel Configuration File System. clare # [ 5.758067] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 5.759097] systemd-modules-load[281]: Module 'atkbd' is built in clare # [ 5.759758] systemd-modules-load[281]: Module 'loop' is built in alice # [ 5.767726] systemd-journald[290]: Collecting audit messages is enabled. bob # [ 5.736515] systemd[1]: Starting Firewall... clare # [ 5.769808] systemd[1]: Finished Load Kernel Modules. alice # [ 5.776710] systemd[1]: Mounted FUSE Control File System. clare # [ 5.774522] systemd[1]: Mounted FUSE Control File System. bob # [ 5.749436] systemd[1]: Starting Apply Kernel Variables... alice # [ 5.786792] systemd[1]: Started Journal Service. bob # [ 5.752232] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 5.754517] systemd[1]: Mounted Kernel Configuration File System. alice # [ 5.783563] systemd[1]: Queued start job for default target Multi-User System. bob # [ 5.763203] EXT4-fs (vda): re-mounted 25e77e32-f3e8-48fc-a2eb-b8ca851e413a. clare # [ 5.788533] systemd[1]: Starting Firewall... bob # [ 5.770608] systemd-oomd[294]: No swap; memory pressure usage will be degraded clare # [ 5.802811] systemd[1]: Starting Apply Kernel Variables... bob # [ 5.777569] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 5.779847] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 5.823052] EXT4-fs (vda): re-mounted 7a79a265-bd73-4d5e-adc5-39ba3bc92172. clare # [ 5.823450] EXT4-fs (vda): re-mounted 25b10962-955d-47fe-b97b-0e3150546f0f. bob # [ 5.799085] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 5.799755] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore clare # [ 5.831648] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 5.804798] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 5.805366] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 5.806161] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. clare # [ 5.833548] systemd[1]: Listening on Disk Image Download Service Socket. clare # [ 5.837830] systemd[1]: Starting Flush Journal to Persistent Storage... clare # [ 5.838455] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore clare # [ 5.844736] systemd[1]: Starting Load/Save OS Random Seed... clare # [ 5.845295] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 5.824916] systemd[1]: Mounted FUSE Control File System. clare # [ 5.866199] systemd-oomd[283]: No swap; memory pressure usage will be degraded clare # [ 5.876543] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 5.884216] systemd-journald[291]: Received client request to flush runtime journal. clare # [ 5.916907] systemd-journald[280]: Received client request to flush runtime journal. clare # [ 5.952339] systemd[1]: Finished Load/Save OS Random Seed. clare # [ 5.952953] systemd[1]: Reached target First Boot Complete. clare # [ 5.960201] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 5.936765] systemd[1]: Finished Apply Kernel Variables. clare # [ 5.964374] systemd[1]: Finished Apply Kernel Variables. bob # [ 5.938450] systemd[1]: Starting Network Name Resolution... bob # [ 5.939026] systemd[1]: Finished Load/Save OS Random Seed. bob # [ 5.939600] systemd[1]: Reached target First Boot Complete. bob # [ 5.945393] systemd[1]: Finished Flush Journal to Persistent Storage. clare # [ 5.973052] systemd[1]: Starting Network Name Resolution... clare # [ 6.001815] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 6.003130] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 6.073260] systemd-resolved[318]: Positive Trust Anchors: bob # [ 6.073880] systemd-resolved[318]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d bob # [ 6.074695] systemd-resolved[318]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 bob # [ 6.075505] systemd-resolved[318]: 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.144769] systemd-resolved[310]: Positive Trust Anchors: clare # [ 6.145353] systemd-resolved[310]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d clare # [ 6.146232] systemd-resolved[310]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 bob # [ 6.121035] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 6.121973] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 6.147106] systemd-resolved[310]: 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.280682] systemd[1]: Finished Coldplug All udev Devices. bob # [ 6.268925] systemd[1]: Finished Coldplug All udev Devices. clare # [ 6.297406] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 6.298252] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 6.272207] systemd-resolved[318]: Using system hostname 'bob'. clare # [ 6.300845] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 6.274781] systemd[1]: Started Network Name Resolution. clare # [ 6.310029] systemd-resolved[310]: Using system hostname 'clare'. clare # [ 6.313835] systemd[1]: Started Network Name Resolution. bob # [ 6.288073] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 6.288704] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 6.289939] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 6.358218] systemd-udevd[321]: Using default interface naming scheme 'v261'. bob # [ 6.343570] systemd-udevd[334]: Using default interface naming scheme 'v261'. alice # [ 5.785078] systemd[1]: systemd-journald.service: Deactivated successfully. alice # [ 6.415862] systemd-journald[290]: Received client request to flush runtime journal. clare # [ 6.412125] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 6.393783] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 6.446511] systemd-modules-load[291]: Module 'atkbd' is built in alice # [ 6.447249] systemd-modules-load[291]: Module 'loop' is built in alice # [ 6.447933] systemd[1]: Finished Load Kernel Modules. alice # [ 6.453685] systemd[1]: Starting Firewall... alice # [ 6.456098] systemd[1]: Starting Apply Kernel Variables... alice # [ 6.458525] systemd-oomd[292]: No swap; memory pressure usage will be degraded alice # [ 6.461660] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. alice # [ 6.463166] systemd[1]: Finished Remount Root and Kernel File Systems. alice # [ 6.465561] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 6.467995] systemd[1]: Starting Flush Journal to Persistent Storage... alice # [ 6.468765] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore alice # [ 6.470027] systemd[1]: Starting Load/Save OS Random Seed... alice # [ 6.470662] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 6.471516] systemd[1]: Finished Load/Save OS Random Seed. alice # [ 6.479457] systemd[1]: Reached target First Boot Complete. alice # [ 6.481679] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 6.452760] systemd[1]: Mounting /run/wrappers... alice # [ 6.484753] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 6.485429] systemd[1]: Finished Apply Kernel Variables. alice # [ 6.485948] systemd[1]: Starting Network Name Resolution... alice # [ 6.486635] systemd-resolved[308]: Positive Trust Anchors: alice # [ 6.487156] systemd-resolved[308]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d alice # [ 6.494540] systemd-resolved[308]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 clare # [ 6.495272] systemd[1]: Mounting /run/wrappers... alice # [ 6.495456] systemd-resolved[308]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 6.517785] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 6.518467] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 6.522395] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 6.523114] systemd[1]: Finished Coldplug All udev Devices. alice # [ 6.523624] systemd-resolved[308]: Using system hostname 'alice'. alice # [ 6.526865] systemd[1]: Started Network Name Resolution. alice # [ 6.527614] systemd-udevd[313]: Using default interface naming scheme 'v261'. alice # [ 6.532461] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 6.533146] systemd[1]: Finished Flush Journal to Persistent Storage. alice # [ 6.533745] systemd[1]: Mounting /run/wrappers... alice # [ 6.534369] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 6.540487] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 6.539553] systemd[1]: Mounted /run/wrappers. clare # [ 6.540143] systemd[1]: Reached target Local File Systems. clare # [ 6.545082] systemd[1]: Listening on Boot Loader Control Service Socket. bob # [ 6.519773] systemd[1]: Mounted /run/wrappers. bob # [ 6.520767] systemd[1]: Reached target Local File Systems. clare # [ 6.550718] systemd[1]: Starting register-nix-paths.service... alice # [ 6.554582] systemd[1]: Mounted /run/wrappers. alice # [ 6.555084] systemd[1]: Reached target Local File Systems. bob # [ 6.525747] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 6.554617] systemd[1]: Starting Create SUID/SGID Wrappers... clare # [ 6.555668] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. alice # [ 6.559642] systemd[1]: Listening on Boot Loader Control Service Socket. bob # [ 6.530223] systemd[1]: Starting register-nix-paths.service... clare # [ 6.560102] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 6.533575] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 6.563494] systemd[1]: Starting register-nix-paths.service... bob # [ 6.534141] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. bob # [ 6.537593] systemd[1]: Starting Save Transient machine-id to Disk... alice # [ 6.567787] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 6.569162] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. alice # [ 6.572680] systemd[1]: Starting Save Transient machine-id to Disk... clare # [ 6.575717] systemd[1]: Starting Create System Files and Directories... bob # [ 6.549799] systemd[1]: Starting Create System Files and Directories... clare # [ 6.576980] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 6.550468] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 6.577916] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 6.587558] systemd[1]: Starting Create System Files and Directories... bob # [ 6.581202] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 6.675397] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. clare # [ 6.681199] systemd[1]: Finished Save Transient machine-id to Disk. alice # [ 6.685190] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. bob # [ 6.659304] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. alice # [ 6.693167] systemd[1]: Finished Save Transient machine-id to Disk. bob # [ 6.668449] systemd[1]: Finished Save Transient machine-id to Disk. clare # [ 6.728173] systemd[1]: Finished Create System Files and Directories. clare # [ 6.735695] systemd[1]: Starting Rebuild Journal Catalog... alice # [ 6.740687] systemd[1]: Finished Create System Files and Directories. clare # [ 6.741396] systemd[1]: Starting Record System Boot/Shutdown in UTMP... alice # [ 6.749838] systemd[1]: Starting Rebuild Journal Catalog... bob # [ 6.722427] systemd[1]: Finished Create System Files and Directories. alice # [ 6.754941] systemd[1]: Starting Record System Boot/Shutdown in UTMP... bob # [ 6.729577] systemd[1]: Starting Rebuild Journal Catalog... bob # [ 6.737450] systemd[1]: Starting Record System Boot/Shutdown in UTMP... alice # [ 6.778001] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. alice # [ 6.843378] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. clare # [ 6.849812] systemd[1]: Finished Record System Boot/Shutdown in UTMP. alice # [ 6.866615] systemd[1]: Finished Record System Boot/Shutdown in UTMP. bob # [ 6.843064] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 6.879668] systemd[1]: Finished Rebuild Journal Catalog. clare # [ 6.887561] systemd[1]: Starting Update is Completed... alice # [ 6.900872] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 6.873712] systemd[1]: Finished Rebuild Journal Catalog. alice # [ 6.907084] systemd[1]: Starting Update is Completed... clare # [ 6.906508] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. bob # [ 6.882564] systemd[1]: Starting Update is Completed... clare # [ 6.942082] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. bob # [ 6.923520] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. clare # [ 6.958925] systemd[1]: Finished Update is Completed. bob # [ 6.939007] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. alice # [ 6.969136] systemd[1]: Finished Update is Completed. bob # [ 6.975095] systemd[1]: Finished Update is Completed. alice # [ 7.114820] (udev-worker)[338]: Network interface NamePolicy= disabled on kernel command line. alice # [ 7.119206] (udev-worker)[345]: Network interface NamePolicy= disabled on kernel command line. clare # [ 7.193882] (udev-worker)[348]: Network interface NamePolicy= disabled on kernel command line. clare # [ 7.203598] (udev-worker)[342]: Network interface NamePolicy= disabled on kernel command line. bob # [ 7.189766] (udev-worker)[367]: Network interface NamePolicy= disabled on kernel command line. bob # [ 7.194444] (udev-worker)[365]: Network interface NamePolicy= disabled on kernel command line. clare # [ 7.374982] mousedev: PS/2 mouse device common for all mice bob # [ 7.400428] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. bob # [ 7.403531] systemd[1]: Finished Create SUID/SGID Wrappers. clare # [ 7.431587] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. clare # [ 7.432971] systemd[1]: Finished Create SUID/SGID Wrappers. alice # [ 7.439641] mousedev: PS/2 mouse device common for all mice clare # [ 7.451451] systemd[1]: Finished register-nix-paths.service. bob # [ 7.425773] systemd[1]: Finished register-nix-paths.service. bob # [ 7.427285] systemd[1]: Reached target System Initialization. bob # [ 7.427883] systemd[1]: Started Discard unused filesystem blocks once a week. clare # [ 7.456125] systemd[1]: Reached target System Initialization. clare # [ 7.456681] systemd[1]: Started Discard unused filesystem blocks once a week. clare # [ 7.457308] systemd[1]: Started Daily Cleanup of Temporary Directories. clare # [ 7.457887] systemd[1]: Reached target Timer Units. bob # [ 7.432116] systemd[1]: Started Daily Cleanup of Temporary Directories. clare # [ 7.459848] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 7.462188] systemd[1]: Listening on harmonia binary cache socket. bob # [ 7.435333] systemd[1]: Reached target Timer Units. clare # [ 7.462752] systemd[1]: Listening on Nix Daemon Socket. clare # [ 7.463251] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. clare # [ 7.463975] systemd[1]: Reached target Socket Units. bob # [ 7.437412] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 7.438042] systemd[1]: Listening on Nix Daemon Socket. bob # [ 7.438571] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. bob # [ 7.439346] systemd[1]: Reached target Socket Units. alice # [ 7.468625] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. bob # [ 7.439876] systemd[1]: Reached target Basic System. alice # [ 7.469724] systemd[1]: Finished Create SUID/SGID Wrappers. clare # [ 7.470338] systemd[1]: Reached target Basic System. clare # [ 7.470882] systemd[1]: Started backdoor.service. clare # [ 7.471323] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 7.478782] systemd[1]: Finished register-nix-paths.service. alice # [ 7.479640] systemd[1]: Reached target System Initialization. bob # [ 7.451420] systemd[1]: Started backdoor.service. bob # [ 7.451922] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 7.482103] systemd[1]: Started Discard unused filesystem blocks once a week. clare # [ 7.480219] systemd[1]: Starting Name Service Cache Daemon (nsncd)... alice # [ 7.482818] systemd[1]: Started Daily Cleanup of Temporary Directories. alice # [ 7.483405] systemd[1]: Reached target Timer Units. bob # [ 7.454211] systemd[1]: Starting Name Service Cache Daemon (nsncd)... alice # [ 7.489228] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 7.487533] systemd[1]: Starting Post-Boot Actions... alice # [ 7.489848] systemd[1]: Listening on Nix Daemon Socket. alice # [ 7.490327] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 7.491036] systemd[1]: Reached target Socket Units. alice # [ 7.491508] systemd[1]: Reached target Basic System. alice # [ 7.491979] systemd[1]: Started backdoor.service. bob # [ 7.465969] systemd[1]: Starting Post-Boot Actions... clare # [ 7.497018] systemd[1]: Started Reset console on configuration changes. alice # [ 7.503720] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 7.476160] systemd[1]: Started Reset console on configuration changes. alice # [ 7.505667] systemd[1]: Starting Name Service Cache Daemon (nsncd)... alice # [ 7.509883] systemd[1]: Starting Post-Boot Actions... alice # [ 7.524281] systemd[1]: Started Reset console on configuration changes. clare # [ 7.536089] systemd[1]: Starting D-Bus System Message Bus... bob # [ 7.527057] mousedev: PS/2 mouse device common for all mice alice # [ 7.554992] systemd[1]: Starting D-Bus System Message Bus... bob # [ 7.525901] systemd[1]: Starting D-Bus System Message Bus... bob # [ 7.568920] systemd[1]: Finished Post-Boot Actions. bob # connecting to host... clare # connecting to host... clare # [ 7.626812] systemd[1]: Finished Post-Boot Actions. alice # connecting to host... clare # [ 7.635990] systemd[1]: Started Name Service Cache Daemon (nsncd). clare # [ 7.643164] systemd[1]: Reached target Host and Network Name Lookups. clare # [ 7.648135] nsncd[470]: Oct 05 10:49:04.489 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # [ 7.649417] systemd[1]: Reached target User and Group Name Lookups. clare # [ 7.654804] systemd[1]: Starting User Login Management... bob # [ 7.627803] systemd[1]: Started Name Service Cache Daemon (nsncd). clare # [ 7.656912] systemd[1]: Finished Import lastlog data into lastlog2 database. bob # [ 7.632305] systemd[1]: Reached target Host and Network Name Lookups. bob # [ 7.632955] systemd[1]: Reached target User and Group Name Lookups. alice # [ 7.662653] systemd[1]: Finished Post-Boot Actions. alice # [ 7.663173] systemd[1]: Started Name Service Cache Daemon (nsncd). bob # [ 7.638579] nsncd[483]: Oct 05 10:49:04.465 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" alice # [ 7.671473] systemd[1]: Reached target Host and Network Name Lookups. bob: Guest shell says: b'Spawning backdoor root shell...\n' alice # [ 7.672541] nsncd[478]: Oct 05 10:49:04.497 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" alice # [ 7.673704] systemd[1]: Reached target User and Group Name Lookups. bob # [ 7.652109] systemd[1]: Starting User Login Management... alice # [ 7.682604] systemd[1]: Starting User Login Management... alice # [ 7.710366] systemd[1]: Finished Import lastlog data into lastlog2 database. bob # [ 7.699171] systemd[1]: Finished Import lastlog data into lastlog2 database. clare # [ 7.761733] dbus-broker-launch[474]: Looking up NSS user entry for 'systemd-timesync'... clare # [ 7.774119] dbus-broker-launch[474]: NSS returned no entry for 'systemd-timesync' clare # [ 7.774763] dbus-broker-launch[474]: Invalid user-name in /nix/store/6h4i96sch7l32kqjsx5rqqjdnbyjanf9-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" clare # [ 7.781695] systemd-logind[493]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 7.746804] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'... clare # [ 7.785752] systemd-logind[493]: Watching system buttons on /dev/input/event0 (gpio-keys) bob: connected to guest root shell clare # [ 7.786531] systemd-logind[493]: New seat seat0. bob: (connecting took 7.99 seconds) clare # [ 7.790560] systemd[1]: Started User Login Management. bob: (finished: waiting for the VM to finish booting, in 7.99 seconds) alice # [ 7.793284] dbus-broker-launch[483]: Looking up NSS user entry for 'systemd-timesync'... bob # [ 7.764625] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync' clare # [ 7.792450] systemd[1]: Starting linger-users.service... bob # [ 7.768366] dbus-broker-launch[489]: Invalid user-name in /nix/store/fm6gxvgadz7qdl7q58a8v2dwzj2zj3va-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 7.804990] dbus-broker-launch[483]: NSS returned no entry for 'systemd-timesync' alice # [ 7.805673] dbus-broker-launch[483]: Invalid user-name in /nix/store/z6zbsgwyph96jp8sx9668447ra6dnvjl-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 7.811766] systemd-logind[502]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) alice # [ 7.817405] systemd-logind[502]: Watching system buttons on /dev/input/event0 (gpio-keys) alice # [ 7.818125] systemd-logind[502]: New seat seat0. alice # [ 7.820943] systemd[1]: Started User Login Management. bob # [ 7.792264] systemd-logind[506]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) clare # [ 7.819805] systemd[1]: Started D-Bus System Message Bus. alice # [ 7.823010] systemd[1]: Starting linger-users.service... bob # [ 7.797726] systemd-logind[506]: Watching system buttons on /dev/input/event0 (gpio-keys) bob # [ 7.798531] systemd-logind[506]: New seat seat0. bob # [ 7.804240] systemd[1]: Started User Login Management. bob # [ 7.806676] systemd[1]: Starting linger-users.service... bob # [ 7.807190] systemd[1]: Started D-Bus System Message Bus. alice # [ 7.847493] systemd[1]: Started D-Bus System Message Bus. clare # [ 7.850119] dbus-broker-launch[474]: Ready clare # [ 7.864934] systemd[1]: linger-users.service: Deactivated successfully. clare # [ 7.867176] systemd[1]: Finished linger-users.service. alice # [ 7.880648] dbus-broker-launch[483]: Ready bob # [ 7.860614] dbus-broker-launch[489]: Ready alice # [ 7.894959] systemd[1]: linger-users.service: Deactivated successfully. alice # [ 7.896915] systemd[1]: Finished linger-users.service. bob # [ 7.899407] systemd[1]: linger-users.service: Deactivated successfully. bob # [ 7.902016] systemd[1]: Finished linger-users.service. clare # [ 7.989251] systemd[1]: Finished Firewall. clare # [ 7.990554] systemd[1]: Reached target Preparation for Network. alice # [ 7.992894] systemd[1]: Finished Firewall. alice # [ 7.994287] systemd[1]: Reached target Preparation for Network. clare # [ 7.992944] systemd[1]: Listening on Network Management Resolve Hook Socket. alice # [ 7.996679] systemd[1]: Listening on Network Management Resolve Hook Socket. clare # [ 7.997186] systemd[1]: Starting Network Management... alice # [ 8.001183] systemd[1]: Starting Network Management... bob # [ 7.994371] systemd[1]: Finished Firewall. bob # [ 7.996448] systemd[1]: Reached target Preparation for Network. bob # [ 7.999874] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # [ 8.003520] systemd[1]: Starting Network Management... alice # [ 8.178502] cfg80211: Loading compiled-in X.509 certificates for regulatory database clare # [ 8.177975] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 8.182865] cfg80211: Loading compiled-in X.509 certificates for regulatory database alice # [ 8.221621] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' alice # [ 8.222145] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' clare # [ 8.220497] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' clare # [ 8.221029] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' alice # [ 8.224761] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 alice # [ 8.225188] cfg80211: failed to load regulatory.db clare # [ 8.223679] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 clare # [ 8.224043] cfg80211: failed to load regulatory.db alice # [ 8.247763] systemd-networkd[545]: lo: Link UP clare # [ 8.246387] systemd-networkd[539]: lo: Link UP clare # [ 8.246916] systemd-networkd[539]: lo: Gained carrier alice # [ 8.249993] systemd-networkd[545]: lo: Gained carrier bob # [ 8.229352] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' bob # [ 8.229838] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' clare # [ 8.255046] systemd[1]: Started Network Management. alice # [ 8.257483] systemd[1]: Started Network Management. clare # [ 8.255559] systemd[1]: Reached target Network. bob # [ 8.233254] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 bob # [ 8.233634] cfg80211: failed to load regulatory.db alice # [ 8.257967] systemd-networkd[545]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. clare # [ 8.257867] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... alice # [ 8.263269] systemd[1]: Reached target Network. clare # [ 8.261174] systemd-networkd[539]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. clare # [ 8.262072] systemd[1]: Starting Permit User Sessions... alice # [ 8.265584] systemd-networkd[545]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 8.266904] systemd-networkd[545]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 8.273993] systemd-networkd[545]: eth0: Link UP clare # [ 8.271318] systemd-networkd[539]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 8.274464] systemd-networkd[545]: eth0: Gained carrier alice # [ 8.274923] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 8.277333] systemd-networkd[539]: eth1: Configuring with /etc/systemd/network/40-eth1.network. clare # [ 8.278074] systemd-networkd[539]: eth0: Link UP clare # [ 8.278486] systemd-networkd[539]: eth0: Gained carrier alice # [ 8.280813] systemd[1]: Starting Permit User Sessions... clare # [ 8.278937] systemd-networkd[539]: eth1: Link UP alice # [ 8.281330] systemd-networkd[545]: eth1: Link UP alice # [ 8.281739] systemd-networkd[545]: eth1: Gained carrier alice # [ 8.282173] systemd-networkd[545]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 8.255032] systemd-networkd[558]: lo: Link UP bob # [ 8.255561] systemd-networkd[558]: lo: Gained carrier clare # [ 8.284456] systemd-networkd[539]: eth1: Gained carrier clare # [ 8.284946] systemd-networkd[539]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 8.261609] systemd[1]: Started Network Management. bob # [ 8.264165] systemd-networkd[558]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. alice # [ 8.293572] systemd-networkd[545]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 8.269624] systemd[1]: Reached target Network. bob # [ 8.270094] systemd[1]: Reached target Network is Online. clare # [ 8.297401] systemd-networkd[539]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 8.275452] systemd-networkd[558]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 8.280486] systemd-networkd[558]: eth1: Configuring with /etc/systemd/network/40-eth1.network. bob # [ 8.281336] systemd-networkd[558]: eth0: Link UP bob # [ 8.281797] systemd-networkd[558]: eth0: Gained carrier bob # [ 8.282279] systemd[1]: Starting ncps binary cache proxy service... bob # [ 8.293670] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... bob # [ 8.294359] systemd-networkd[558]: eth1: Link UP bob # [ 8.294800] systemd-networkd[558]: eth1: Gained carrier bob # [ 8.295304] systemd-networkd[558]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 8.301307] systemd-networkd[558]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 clare # [ 8.340806] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. alice # [ 8.343636] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 8.343207] systemd[1]: Finished Permit User Sessions. alice # [ 8.346804] systemd[1]: Finished Permit User Sessions. clare # [ 8.347645] systemd[1]: Started Getty on tty1. clare # [ 8.349501] systemd[1]: Reached target Login Prompts. alice # [ 8.352336] systemd[1]: Started Getty on tty1. bob # [ 8.323816] systemd[1]: Starting Permit User Sessions... alice # [ 8.353795] systemd[1]: Reached target Login Prompts. clare # [ 8.352218] systemd[1]: Reached target Multi-User System. alice # [ 8.354290] systemd[1]: Reached target Multi-User System. alice # [ 8.356862] systemd[1]: Startup finished in 754ms (kernel) + 3.612s (initrd) + 3.988s (userspace) = 8.355s. clare # [ 8.364792] systemd[1]: Startup finished in 747ms (kernel) + 3.639s (initrd) + 3.977s (userspace) = 8.364s. bob # [ 8.387376] systemd[1]: Finished Permit User Sessions. bob # [ 8.394271] systemd[1]: Started Getty on tty1. bob # [ 8.397842] systemd[1]: Reached target Login Prompts. bob # [ 8.398336] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. bob # [ 8.434878] ncps-pre-start[565]: /nix/store/z27dji5sjdkkdpzjlm37nq6d5cwbgl8i-ncps-0.9.4/bin/dbmate-ncps up alice # [ 8.490587] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 clare # [ 8.502424] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 8.665131] ncps-pre-start[580]: Creating: /var/lib/ncps/db/db.sqlite bob # [ 8.695223] ncps-pre-start[580]: Applying: 20241210054814_create-narinfos-table.sql bob # [ 8.710783] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 8.708277] ncps-pre-start[580]: Applied: 20241210054814_create-narinfos-table.sql in 13.07184ms bob # [ 8.710456] ncps-pre-start[580]: Applying: 20241210054829_create-nars-table.sql bob # [ 8.716385] ncps-pre-start[580]: Applied: 20241210054829_create-nars-table.sql in 8.09216ms bob # [ 8.717681] ncps-pre-start[580]: Applying: 20241213014846_add-query-to-nars-table.sql bob # [ 8.724262] ncps-pre-start[580]: Applied: 20241213014846_add-query-to-nars-table.sql in 7.85824ms bob # [ 8.725054] ncps-pre-start[580]: Applying: 20251230224159_add-cascade-to-nars-fk.sql bob # [ 8.733844] ncps-pre-start[580]: Applied: 20251230224159_add-cascade-to-nars-fk.sql in 9.55436ms bob # [ 8.736061] ncps-pre-start[580]: Applying: 20260101000000_add-config-table.sql bob # [ 8.740239] ncps-pre-start[580]: Applied: 20260101000000_add-config-table.sql in 6.38916ms bob # [ 8.742857] ncps-pre-start[580]: Applying: 20260105025735_remove-redundant-indexes.sql bob # [ 8.745752] ncps-pre-start[580]: Applied: 20260105025735_remove-redundant-indexes.sql in 5.50132ms bob # [ 8.749157] ncps-pre-start[580]: Applying: 20260105030513_support_shared_nars_between_narinfos.sql bob # [ 8.756250] ncps-pre-start[580]: Applied: 20260105030513_support_shared_nars_between_narinfos.sql in 10.46544ms alice # [ 8.790649] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 8.764135] ncps-pre-start[580]: Applying: 20260117195000_add_narinfo_de_normalized.sql alice # [ 8.796981] systemd[1]: Starting Virtual Console Setup... clare # [ 8.798221] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 8.772326] ncps-pre-start[580]: Applied: 20260117195000_add_narinfo_de_normalized.sql in 16.02888ms bob # [ 8.773162] ncps-pre-start[580]: Applying: 20260127223000_allow_multiple_nar_representations.sql clare # [ 8.803936] systemd[1]: Starting Virtual Console Setup... bob # [ 8.781260] ncps-pre-start[580]: Applied: 20260127223000_allow_multiple_nar_representations.sql in 8.9552ms bob # [ 8.782109] ncps-pre-start[580]: Applying: 20260131021850_add_chunks.sql bob # [ 8.792289] ncps-pre-start[580]: Applied: 20260131021850_add_chunks.sql in 10.97108ms alice # [ 8.822298] systemd-logind[502]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 8.793070] ncps-pre-start[580]: Applying: 20260205063651_add_total_chunks_to_nar_files.sql clare # [ 8.820683] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 8.821382] systemd[1]: Stopped Virtual Console Setup. clare # [ 8.825615] systemd[1]: Starting Virtual Console Setup... clare # [ 8.830312] systemd-logind[493]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 8.806165] ncps-pre-start[580]: Applied: 20260205063651_add_total_chunks_to_nar_files.sql in 13.84012ms bob # [ 8.807013] ncps-pre-start[580]: Applying: 20260217071237_add_chunking_started_at_to_nar_files.sql bob # [ 8.816116] ncps-pre-start[580]: Applied: 20260217071237_add_chunking_started_at_to_nar_files.sql in 9.87112ms bob # [ 8.817277] ncps-pre-start[580]: Applying: 20260301000000_add_verified_at_to_nar_files.sql bob # [ 8.826098] ncps-pre-start[580]: Applied: 20260301000000_add_verified_at_to_nar_files.sql in 9.68208ms clare # [ 8.900503] systemd-vconsole-setup[558]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 8.903231] systemd[1]: Finished Virtual Console Setup. alice # [ 8.908887] systemd-vconsole-setup[563]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 8.911634] systemd[1]: Finished Virtual Console Setup. bob # [ 8.985268] systemd[1]: Started ncps binary cache proxy service. bob # [ 8.985872] systemd[1]: Reached target Multi-User System. bob # [ 8.989133] systemd[1]: Startup finished in 718ms (kernel) + 3.620s (initrd) + 4.649s (userspace) = 8.988s. bob: (finished: waiting for unit ncps, in 9.29 seconds) bob: must succeed: systemctl status ncps bob: (finished: must succeed: systemctl status ncps, in 0.08 seconds) clare: waiting for unit harmonia.socket clare: waiting for the VM to finish booting clare: Guest shell says: b'Spawning backdoor root shell...\n' clare: connected to guest root shell clare: (connecting took 0.00 seconds) clare: (finished: waiting for the VM to finish booting, in 0.00 seconds) clare: (finished: waiting for unit harmonia.socket, in 0.05 seconds) alice: waiting for success: curl bob:8502/nix-cache-info alice: waiting for the VM to finish booting alice: Guest shell says: b'Spawning backdoor root shell...\n' alice: connected to guest root shell alice: (connecting took 0.00 seconds) alice: (finished: waiting for the VM to finish booting, in 0.00 seconds) bob # [ 9.206259] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 9.215056] systemd[1]: Starting Virtual Console Setup... bob # [ 9.255451] systemd-logind[506]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 9.360372] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","log_level":"info","time":"2026-10-05T10:49:06Z","message":"logger created"} bob # [ 9.361931] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","xz-binary-path":"/nix/store/csrwf31hprwghnbyiglxa3p787lgrl67-xz-5.8.4-bin/bin/xz","time":"2026-10-05T10:49:06Z","message":"Using xz binary for xz decompression"} bob # [ 9.370490] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-10-05T10:49:06Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"} bob # [ 9.381089] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","time":"2026-10-05T10:49:06Z","message":"using local locks (single-instance mode)"} bob # [ 9.387811] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-05T10:49:06Z","message":"setting up tracer provider to discard traces"} bob # [ 9.390689] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-05T10:49:06Z","message":"setting up meter provider to discard metrics"} bob # [ 9.393525] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-05T10:49:06Z","message":"setting up logger provider to discard logs"} bob # [ 9.401747] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-10-05T10:49:06Z","message":"Reporting anonymous metrics to the project maintainers"} bob # [ 9.404129] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-10-05T10:49:06Z","message":"failed to parse netrc file, proceeding without netrc authentication"} bob # [ 9.405909] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-10-05T10:49:06Z","message":"using local storage"} bob # [ 9.452710] systemd-vconsole-setup[604]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 9.455322] systemd[1]: Finished Virtual Console Setup. bob # [ 9.473413] systemd-networkd[558]: eth0: Gained IPv6LL alice # [ 9.665653] systemd-networkd[545]: eth1: Gained IPv6LL bob # [ 9.731266] systemd-networkd[558]: eth1: Gained IPv6LL alice # [ 9.761327] systemd-networkd[545]: eth0: Gained IPv6LL clare # [ 9.763792] systemd[1]: Starting harmonia binary cache service... clare # [ 9.905485] harmonia-cache[565]: 2026-10-05T10:49:06.752917Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000)) clare # [ 9.910932] harmonia-cache[565]: 2026-10-05T10:49:06.757260Z INFO actix_server::builder: starting 4 workers clare # [ 9.918028] harmonia-cache[565]: 2026-10-05T10:49:06.762185Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s clare # [ 9.922268] harmonia-cache[565]: 2026-10-05T10:49:06.762269Z INFO actix_server::server: Actix runtime found; starting in Actix runtime clare # [ 9.931117] harmonia-cache[565]: 2026-10-05T10:49:06.762372Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:5000 clare # [ 9.935995] systemd[1]: Started harmonia binary cache service. clare # [ 9.952606] systemd-networkd[539]: eth1: Gained IPv6LL bob # [ 9.925396] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-10-05T10:49:06Z","message":"upstream became healthy and is now available for requests"} bob # [ 9.930482] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-10-05T10:49:06Z","message":"Server started"} clare # [ 10.082925] systemd-networkd[539]: eth0: Gained IPv6LL bob # [ 10.419174] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: 2026/10/05 10:49:07 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host alice # curl: (28) Failed to connect to bob:8502 after 135071 ms: Could not connect to server alice # % Total % Received % Xferd Average Speed Time Time Time Current alice # Dload Upload Total Spent Left Speed bob # [ 145.414287] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:40492","trace_id":"5904488f3e960de280aa1d7cf0685778","span_id":"4632bc639d8799e3","status":200,"elapsed":0.15612,"bytes":50,"time":"2026-10-05T10:51:22Z","message":"handled request"} alice # 0 0 0 0 0 0 0 0 0100 50 100 50 0 0 1888 0 0100 50 100 50 0 0 1635 0 0100 50 100 50 0 0 1440 0 0 alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 136.25 seconds) bob: waiting for success: curl clare:5000/nix-cache-info bob # % Total % Received % Xferd Average Speed Time Time Time Current bob # Dload Upload Total Spent Left Speed bob # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2834 0 0100 51 100 51 0 0 2354 0 0100 51 100 51 0 0 2014 0 0 bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.10 seconds) clare: waiting for unit harmonia clare: (finished: waiting for unit harmonia, in 0.07 seconds) clare: must succeed: systemctl status harmonia clare: (finished: must succeed: systemctl status harmonia, in 0.03 seconds) clare: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 clare # [ 146.075185] systemd-resolved[310]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. clare # [ 146.082379] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 146.086070] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 146.600469] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 146.605389] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 147.840064] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 147.844075] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 150.045274] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 150.049394] systemd-resolved[310]: 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.51 seconds) alice: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 bob # [ 150.267899] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:40502","trace_id":"3c32776d4c45d96c587b567bcb53dcbb","span_id":"27e4dec79445f5bc","status":200,"elapsed":0.03008,"bytes":50,"time":"2026-10-05T10:51:27Z","message":"handled request"} alice # [ 150.583400] systemd-resolved[308]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. alice # [ 151.284539] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 151.289535] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 152.423275] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 152.427099] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 154.705070] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 154.709297] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 154.688355] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","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-05T10:51:31Z","message":"download the narinfo from upstream"} bob # [ 154.723690] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-05T10:51:31Z","message":"downloading the nar from upstream"} bob # [ 154.735447] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","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-05T10:51:31Z","message":"download the nar from upstream"} bob # [ 154.772934] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-05T10:51:31Z","message":"storing narinfo and nar_file record in the database"} bob # [ 154.790991] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":62.86996,"time":"2026-10-05T10:51:31Z","message":"download of nar complete"} bob # [ 154.799182] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":101.5204,"time":"2026-10-05T10:51:31Z","message":"download of narinfo complete"} bob # [ 154.808991] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","status":200,"elapsed":104.87468,"bytes":532,"time":"2026-10-05T10:51:31Z","message":"handled request"} bob # [ 154.814166] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:40502","trace_id":"6041e0f00c9c7a18c8a58b1791d51e70","span_id":"f236034f32f0c71b","status":200,"elapsed":0.62324,"bytes":95,"time":"2026-10-05T10:51:31Z","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.69 seconds) (finished: run the VM test script, in 155.57 seconds) test script finished in 155.72s 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-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-10-05T10:51:31Z 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-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-10-05T10:51:31Z 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-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.38 seconds)