build01: building 2vszkvj6phf9al38mr6nfrhdk8av704y-vm-test-run-ncps.drv Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script additionally exposed symbols: alice, bob, clare, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh start all VMs alice: starting vm bob: starting vm clare: starting vm clare # Disk image does not exist, creating the virtualisation disk image... bob # Disk image does not exist, creating the virtualisation disk image... clare # Formatting '/build/vm-state-clare/tmp.9VgjT71VpY', fmt=raw size=1073741824 bob: QEMU running (pid 50) clare: QEMU running (pid 49) bob # Formatting '/build/vm-state-bob/tmp.HnmOHYYl3G', fmt=raw size=1073741824 alice: QEMU running (pid 48) alice # Disk image does not exist, creating the virtualisation disk image... clare # mke2fs 1.47.4 (6-Mar-2025) alice # Formatting '/build/vm-state-alice/tmp.MOdz3RIdVJ', fmt=raw size=1073741824 bob # mke2fs 1.47.4 (6-Mar-2025) clare # Discarding device blocks: 0/262144 done bob # Discarding device blocks: 0/262144 done (finished: start all VMs, in 0.47 seconds) alice # mke2fs 1.47.4 (6-Mar-2025) bob # Creating filesystem with 262144 4k blocks and 65536 inodes clare # Creating filesystem with 262144 4k blocks and 65536 inodes bob: waiting for unit ncps bob # Filesystem UUID: 9a85103c-e61a-45f1-815c-081cff456cc0 alice # Discarding device blocks: 0/262144 done alice # Creating filesystem with 262144 4k blocks and 65536 inodes bob # Superblock backups stored on blocks: bob: waiting for the VM to finish booting bob # 32768, 98304, 163840, 229376 clare # Filesystem UUID: 3317e99a-9d03-426d-9dd0-980aa506fcea bob # clare # Superblock backups stored on blocks: bob # Allocating group tables: 0/8 done clare # 32768, 98304, 163840, 229376 bob # Writing inode tables: 0/8 done clare # bob # Creating journal (8192 blocks): done clare # Allocating group tables: 0/8 done bob # Writing superblocks and filesystem accounting information: 0/8 done clare # Writing inode tables: 0/8 done bob # clare # Creating journal (8192 blocks): done bob # Virtualisation disk image created. clare # Writing superblocks and filesystem accounting information: 0/8 done bob # Starting virtiofs daemons... clare # bob # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # Virtualisation disk image created. bob # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # Starting virtiofs daemons... bob # [2026-10-04T15:27:10Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-10-04T15:27:10Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-04T15:27:10Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-10-04T15:27:10Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-04T15:27:10Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-04T15:27:10Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-04T15:27:10Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-04T15:27:10Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-04T15:27:10Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-04T15:27:10Z INFO virtiofsd] Client connected, servicing requests alice # Filesystem UUID: 5ea69935-7a05-4fd9-9dea-02574aa75611 clare # [2026-10-04T15:27:10Z INFO virtiofsd] Client connected, servicing requests alice # Superblock backups stored on blocks: clare # [2026-10-04T15:27:10Z INFO virtiofsd] Client connected, servicing requests alice # 32768, 98304, 163840, 229376 alice # alice # Allocating group tables: 0/8 done alice # Writing inode tables: 0/8 done alice # Creating journal (8192 blocks): done alice # Writing superblocks and filesystem accounting information: 0/8 done alice # alice # Virtualisation disk image created. alice # Starting virtiofs daemons... alice # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-04T15:27:10Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-04T15:27:10Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-04T15:27:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-04T15:27:10Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-10-04T15:27:10Z INFO virtiofsd] Client connected, servicing requests alice # [2026-10-04T15:27:10Z INFO virtiofsd] Client connected, servicing requests alice # [2026-10-04T15:27:10Z INFO virtiofsd] Client connected, servicing requests alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] alice # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 alice # [ 0.000000] KASLR enabled alice # [ 0.000000] random: crng init done alice # [ 0.000000] Machine model: linux,dummy-virt alice # [ 0.000000] efi: UEFI not found. alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] alice # [ 0.000000] Zone ranges: alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] DMA32 empty alice # [ 0.000000] Normal empty alice # [ 0.000000] Device empty alice # [ 0.000000] Movable zone start for each node alice # [ 0.000000] Early memory node ranges alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000000] psci: probing for conduit method from DT. alice # [ 0.000000] psci: PSCIv1.3 detected in firmware. alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs alice # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] psci: SMC Calling Convention v1.1 alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 alice # [ 0.000000] Detected PIPT I-cache on CPU0 alice # [ 0.000000] CPU features: detected: GICv3 CPU interface alice # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] alice # [ 0.000000] CPU features: detected: ARM erratum 1418040 clare # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 clare # [ 0.000000] KASLR enabled alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion clare # [ 0.000000] random: crng init done clare # [ 0.000000] Machine model: linux,dummy-virt bob # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.000000] efi: UEFI not found. bob # [ 0.000000] KASLR enabled alice # [ 0.000000] alternatives: applying boot alternatives bob # [ 0.000000] random: crng init done clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT bob # [ 0.000000] Machine model: linux,dummy-virt bob # [ 0.000000] efi: UEFI not found. clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] clare # [ 0.000000] Zone ranges: bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] clare # [ 0.000000] DMA32 empty bob # [ 0.000000] Zone ranges: clare # [ 0.000000] Normal empty 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/224zj3jyaabv4hz182jfl10dhc1dfxmj-nixos-system-alice-test/init regInfo=/nix/.ro-store/s3ijbsqxx0p1n6g4bzim347sgbdkp05b-closure-info/registration console=ttyAMA0,115200n8 console=tty0 clare # [ 0.000000] Device empty bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] Movable zone start for each node bob # [ 0.000000] DMA32 empty clare # [ 0.000000] Early memory node ranges bob # [ 0.000000] Normal empty bob # [ 0.000000] Device empty alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/s3ijbsqxx0p1n6g4bzim347sgbdkp05b-closure-info/registration", will be passed to user space. clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] Movable zone start for each node bob # [ 0.000000] Early memory node ranges alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) clare # [ 0.000000] psci: probing for conduit method from DT. bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.000000] psci: PSCIv1.3 detected in firmware. bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB bob # [ 0.000000] psci: probing for conduit method from DT. clare # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] software IO TLB: area num 1. bob # [ 0.000000] psci: PSCIv1.3 detected in firmware. clare # [ 0.000000] psci: SMC Calling Convention v1.1 bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) bob # [ 0.000000] psci: Trusted OS migration not required alice # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) bob # [ 0.000000] psci: SMC Calling Convention v1.1 alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 alice # [ 0.000000] Policy zone: DMA clare # [ 0.000000] Detected PIPT I-cache on CPU0 bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off clare # [ 0.000000] CPU features: detected: GICv3 CPU interface bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 clare # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 bob # [ 0.000000] Detected PIPT I-cache on CPU0 clare # [ 0.000000] CPU features: detected: Spectre-BHB bob # [ 0.000000] CPU features: detected: GICv3 CPU interface alice # [ 0.000000] allocated 2097152 bytes of page_ext alice # [ 0.000000] ftrace: allocating 75026 entries in 294 pages clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR bob # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups bob # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) alice # [ 0.000000] rcu: Hierarchical RCU implementation. bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.000000] CPU features: detected: ARM erratum 1418040 alice # [ 0.000000] rcu: RCU event tracing is enabled. bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. bob # [ 0.000000] CPU features: detected: ARM erratum 1418040 clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing alice # [ 0.000000] Trampoline variant of Tasks RCU enabled. bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion clare # [ 0.000000] alternatives: applying boot alternatives alice # [ 0.000000] Rude variant of Tasks RCU enabled. alice # [ 0.000000] Tracing variant of Tasks RCU enabled. bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing bob # [ 0.000000] alternatives: applying boot alternatives alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. 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/8bymk31vx2mmv1ipicyvn9gg03cx5h69-nixos-system-clare-test/init regInfo=/nix/.ro-store/3vif0aqkj0hw6ry1h33rmzkb019hwd1s-closure-info/registration console=ttyAMA0,115200n8 console=tty0 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] 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/xnn913dv5vbwaw6kjszszkc89wy4ryrr-nixos-system-bob-test/init regInfo=/nix/.ro-store/z4fx6153slkdfivwdn36cymmy0y9hgvg-closure-info/registration console=ttyAMA0,115200n8 console=tty0 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] Unknown kernel command line parameters "regInfo=/nix/.ro-store/3vif0aqkj0hw6ry1h33rmzkb019hwd1s-closure-info/registration", will be passed to user space. alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.000000] GICv3: 256 SPIs implemented bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/z4fx6153slkdfivwdn36cymmy0y9hgvg-closure-info/registration", will be passed to user space. alice # [ 0.000000] GICv3: 0 Extended SPIs implemented clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) alice # [ 0.000000] Root IRQ handler: gic_handle_irq bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.000000] software IO TLB: area num 1. alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB bob # [ 0.000000] software IO TLB: area num 1. clare # [ 0.000000] Fallback order for Node 0: 0 alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 clare # [ 0.000000] Policy zone: DMA alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) bob # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 bob # [ 0.000000] Policy zone: DMA clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 clare # [ 0.000000] allocated 2097152 bytes of page_ext bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. clare # [ 0.000000] ftrace: allocating 75026 entries in 294 pages bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups bob # [ 0.000000] allocated 2097152 bytes of page_ext alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns clare # [ 0.000000] rcu: Hierarchical RCU implementation. bob # [ 0.000000] ftrace: allocating 75026 entries in 294 pages clare # [ 0.000000] rcu: RCU event tracing is enabled. alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. bob # [ 0.000000] rcu: Hierarchical RCU implementation. bob # [ 0.000000] rcu: RCU event tracing is enabled. clare # [ 0.000000] Trampoline variant of Tasks RCU enabled. alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns clare # [ 0.000000] Rude variant of Tasks RCU enabled. bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. clare # [ 0.000000] Tracing variant of Tasks RCU enabled. alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.000000] Trampoline variant of Tasks RCU enabled. bob # [ 0.000000] Rude variant of Tasks RCU enabled. clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. alice # [ 0.000049] arm-pv: using stolen time PV bob # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 0.000411] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. alice # [ 0.000697] Console: colour dummy device 80x25 clare # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 alice # [ 0.000709] printk: legacy console [tty0] enabled clare # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.000960] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) alice # [ 0.000965] pid_max: default: 32768 minimum: 301 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] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 alice # [ 0.001038] LSM: initializing lsm=capability,landlock,yama,bpf,ima alice # [ 0.001210] landlock: Up and running. clare # [ 0.000000] GICv3: 256 SPIs implemented bob # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.001213] Yama: becoming mindful. clare # [ 0.000000] GICv3: 0 Extended SPIs implemented bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.000000] Root IRQ handler: gic_handle_irq alice # [ 0.001692] LSM support for eBPF active bob # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI alice # [ 0.001884] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.000000] GICv3: 0 Extended SPIs implemented clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 bob # [ 0.000000] Root IRQ handler: gic_handle_irq alice # [ 0.001902] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI alice # [ 0.004625] rcu: Hierarchical SRCU implementation. clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 alice # [ 0.004632] rcu: Max phase no-delay instances is 1000. bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) alice # [ 0.005605] fsl-mc MSI: its@8080000 domain created bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] alice # [ 0.005797] EFI services will not be available. alice # [ 0.005893] smp: Bringing up secondary CPUs ... clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) alice # [ 0.005937] smp: Brought up 1 node, 1 CPU clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 alice # [ 0.005941] SMP: Total of 1 processors activated. alice # [ 0.005943] CPU: All CPU(s) started at EL1 bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 alice # [ 0.005957] CPU features: detected: 32-bit EL0 Support bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. alice # [ 0.005959] CPU features: detected: Data cache clean to the PoU not required for I/D coherence bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns alice # [ 0.005962] CPU features: detected: Common not Private translations bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. alice # [ 0.005964] CPU features: detected: CRC32 instructions clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns alice # [ 0.005966] CPU features: detected: Data cache clean to Point of Persistence bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 alice # [ 0.005969] CPU features: detected: Enhanced Virtualization Traps clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns alice # [ 0.005980] CPU features: detected: RCpc load-acquire (LDAPR) bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns alice # [ 0.005982] CPU features: detected: LSE atomic instructions clare # [ 0.000042] arm-pv: using stolen time PV alice # [ 0.005984] CPU features: detected: Privileged Access Never bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns alice # [ 0.005986] CPU features: detected: PMUv3 bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns clare # [ 0.000327] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.005987] CPU features: detected: RAS Extension Support bob # [ 0.000048] arm-pv: using stolen time PV clare # [ 0.000621] Console: colour dummy device 80x25 alice # [ 0.005991] CPU features: detected: Speculative Store Bypassing Safe (SSBS) clare # [ 0.000632] printk: legacy console [tty0] enabled bob # [ 0.000401] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.006011] alternatives: applying system-wide alternatives bob # [ 0.000733] Console: colour dummy device 80x25 clare # [ 0.000862] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) alice # [ 0.007377] CPU features: detected: Hardware dirty bit management on CPU0 bob # [ 0.000743] printk: legacy console [tty0] enabled clare # [ 0.000867] pid_max: default: 32768 minimum: 301 clare # [ 0.000947] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.000995] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) clare # [ 0.001076] landlock: Up and running. alice # [ 0.007582] Memory: 891208K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116068K reserved, 32768K cma-reserved) bob # [ 0.001001] pid_max: default: 32768 minimum: 301 clare # [ 0.001078] Yama: becoming mindful. alice # [ 0.008031] devtmpfs: initialized clare # [ 0.001499] LSM support for eBPF active bob # [ 0.001105] LSM: initializing lsm=capability,landlock,yama,bpf,ima alice # [ 0.009478] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.001233] landlock: Up and running. clare # [ 0.001702] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.001235] Yama: becoming mindful. alice # [ 0.009517] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). bob # [ 0.001614] LSM support for eBPF active clare # [ 0.001721] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.009647] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.001782] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.004401] rcu: Hierarchical SRCU implementation. alice # [ 0.009650] 0 pages in range for non-PLT usage clare # [ 0.004408] rcu: Max phase no-delay instances is 1000. alice # [ 0.009651] 508208 pages in range for PLT usage bob # [ 0.001801] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.005418] fsl-mc MSI: its@8080000 domain created alice # [ 0.009753] pinctrl core: initialized pinctrl subsystem bob # [ 0.004583] rcu: Hierarchical SRCU implementation. clare # [ 0.005609] EFI services will not be available. alice # [ 0.010502] DMI not present or invalid. clare # [ 0.005718] smp: Bringing up secondary CPUs ... bob # [ 0.004590] rcu: Max phase no-delay instances is 1000. alice # [ 0.013893] NET: Registered PF_NETLINK/PF_ROUTE protocol family clare # [ 0.005757] smp: Brought up 1 node, 1 CPU bob # [ 0.005524] fsl-mc MSI: its@8080000 domain created clare # [ 0.005761] SMP: Total of 1 processors activated. bob # [ 0.005703] EFI services will not be available. alice # [ 0.016499] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations clare # [ 0.005763] CPU: All CPU(s) started at EL1 bob # [ 0.005791] smp: Bringing up secondary CPUs ... alice # [ 0.016629] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 0.005830] smp: Brought up 1 node, 1 CPU clare # [ 0.005774] CPU features: detected: 32-bit EL0 Support bob # [ 0.005834] SMP: Total of 1 processors activated. alice # [ 0.016774] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations bob # [ 0.005836] CPU: All CPU(s) started at EL1 clare # [ 0.005777] CPU features: detected: Data cache clean to the PoU not required for I/D coherence alice # [ 0.016791] audit: initializing netlink subsys (disabled) bob # [ 0.005847] CPU features: detected: 32-bit EL0 Support clare # [ 0.005780] CPU features: detected: Common not Private translations alice # [ 0.017401] thermal_sys: Registered thermal governor 'fair_share' clare # [ 0.005782] CPU features: detected: CRC32 instructions bob # [ 0.005849] CPU features: detected: Data cache clean to the PoU not required for I/D coherence alice # [ 0.017403] thermal_sys: Registered thermal governor 'bang_bang' clare # [ 0.005784] CPU features: detected: Data cache clean to Point of Persistence bob # [ 0.005852] CPU features: detected: Common not Private translations clare # [ 0.005786] CPU features: detected: Enhanced Virtualization Traps bob # [ 0.005854] CPU features: detected: CRC32 instructions alice # [ 0.017406] thermal_sys: Registered thermal governor 'step_wise' clare # [ 0.005796] CPU features: detected: RCpc load-acquire (LDAPR) bob # [ 0.005856] CPU features: detected: Data cache clean to Point of Persistence alice # [ 0.017408] thermal_sys: Registered thermal governor 'user_space' clare # [ 0.005799] CPU features: detected: LSE atomic instructions bob # [ 0.005859] CPU features: detected: Enhanced Virtualization Traps alice # [ 0.017410] thermal_sys: Registered thermal governor 'power_allocator' clare # [ 0.005801] CPU features: detected: Privileged Access Never bob # [ 0.005869] CPU features: detected: RCpc load-acquire (LDAPR) clare # [ 0.005803] CPU features: detected: PMUv3 alice # [ 0.017451] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 bob # [ 0.005871] CPU features: detected: LSE atomic instructions clare # [ 0.005805] CPU features: detected: RAS Extension Support alice # [ 0.017460] cpuidle: using governor ladder bob # [ 0.005873] CPU features: detected: Privileged Access Never alice # [ 0.017465] cpuidle: using governor menu clare # [ 0.005809] CPU features: detected: Speculative Store Bypassing Safe (SSBS) bob # [ 0.005875] CPU features: detected: PMUv3 alice # [ 0.017601] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. clare # [ 0.005829] alternatives: applying system-wide alternatives bob # [ 0.005877] CPU features: detected: RAS Extension Support alice # [ 0.017652] ASID allocator initialised with 32768 entries clare # [ 0.007253] CPU features: detected: Hardware dirty bit management on CPU0 bob # [ 0.005880] CPU features: detected: Speculative Store Bypassing Safe (SSBS) alice # [ 0.018291] Serial: AMBA PL011 UART driver bob # [ 0.005904] alternatives: applying system-wide alternatives alice # [ 0.021745] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 bob # [ 0.007280] CPU features: detected: Hardware dirty bit management on CPU0 clare # [ 0.007440] Memory: 891232K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116076K reserved, 32768K cma-reserved) alice # [ 0.021906] printk: console [ttyAMA0] enabled clare # [ 0.007902] devtmpfs: initialized clare # [ 0.009434] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.007438] Memory: 891228K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116052K reserved, 32768K cma-reserved) bob # [ 0.007917] devtmpfs: initialized clare # [ 0.009458] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). bob # [ 0.009375] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) clare # [ 0.009600] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL clare # [ 0.009604] 0 pages in range for non-PLT usage bob # [ 0.009398] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). clare # [ 0.009605] 508208 pages in range for PLT usage bob # [ 0.009526] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL clare # [ 0.009713] pinctrl core: initialized pinctrl subsystem bob # [ 0.009530] 0 pages in range for non-PLT usage clare # [ 0.010473] DMI not present or invalid. bob # [ 0.009530] 508208 pages in range for PLT usage clare # [ 0.013664] NET: Registered PF_NETLINK/PF_ROUTE protocol family bob # [ 0.009639] pinctrl core: initialized pinctrl subsystem clare # [ 0.016230] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations bob # [ 0.010419] DMI not present or invalid. bob # [ 0.013716] NET: Registered PF_NETLINK/PF_ROUTE protocol family clare # [ 0.016360] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 0.016266] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations clare # [ 0.016507] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations bob # [ 0.016397] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations clare # [ 0.016523] audit: initializing netlink subsys (disabled) clare # [ 0.017066] thermal_sys: Registered thermal governor 'fair_share' bob # [ 0.016544] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.017068] thermal_sys: Registered thermal governor 'bang_bang' bob # [ 0.016561] audit: initializing netlink subsys (disabled) bob # [ 0.017101] thermal_sys: Registered thermal governor 'fair_share' clare # [ 0.017071] thermal_sys: Registered thermal governor 'step_wise' clare # [ 0.017074] thermal_sys: Registered thermal governor 'user_space' bob # [ 0.017103] thermal_sys: Registered thermal governor 'bang_bang' bob # [ 0.017107] thermal_sys: Registered thermal governor 'step_wise' clare # [ 0.017076] thermal_sys: Registered thermal governor 'power_allocator' bob # [ 0.017109] thermal_sys: Registered thermal governor 'user_space' clare # [ 0.017126] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 clare # [ 0.017135] cpuidle: using governor ladder bob # [ 0.017111] thermal_sys: Registered thermal governor 'power_allocator' clare # [ 0.017140] cpuidle: using governor menu bob # [ 0.017148] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 clare # [ 0.017264] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. bob # [ 0.017157] cpuidle: using governor ladder clare # [ 0.017284] ASID allocator initialised with 32768 entries bob # [ 0.017162] cpuidle: using governor menu clare # [ 0.017925] Serial: AMBA PL011 UART driver bob # [ 0.017282] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. bob # [ 0.017301] ASID allocator initialised with 32768 entries clare # [ 0.021391] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 bob # [ 0.017931] Serial: AMBA PL011 UART driver clare # [ 0.021559] printk: console [ttyAMA0] enabled bob # [ 0.021270] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 alice # [ 0.094425] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.021406] printk: console [ttyAMA0] enabled alice # [ 0.094439] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page alice # [ 0.094442] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages alice # [ 0.094447] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page alice # [ 0.094449] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages alice # [ 0.094451] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 0.094453] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages alice # [ 0.094455] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page alice # [ 0.095437] fbcon: Taking over console alice # [ 0.095453] ACPI: Interpreter disabled. alice # [ 0.096098] iommu: Default domain type: Translated alice # [ 0.096102] iommu: DMA domain TLB invalidation policy: strict mode alice # [ 0.096403] SCSI subsystem initialized alice # [ 0.096586] usbcore: registered new interface driver usbfs alice # [ 0.096615] usbcore: registered new interface driver hub alice # [ 0.096635] usbcore: registered new device driver usb alice # [ 0.096838] pps_core: LinuxPPS API ver. 1 registered alice # [ 0.096841] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti alice # [ 0.096846] PTP clock support registered alice # [ 0.096878] EDAC MC: Ver: 3.0.0 clare # [ 0.093631] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages clare # [ 0.093646] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page alice # [ 0.106718] scmi_core: SCMI protocol bus registered bob # [ 0.093550] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages clare # [ 0.093649] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages alice # [ 0.107097] FPGA manager framework alice # [ 0.110658] vgaarb: loaded bob # [ 0.093564] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page clare # [ 0.093653] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page bob # [ 0.093567] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages clare # [ 0.093655] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 0.093571] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page clare # [ 0.093657] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 0.093573] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages clare # [ 0.093659] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages bob # [ 0.093575] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page clare # [ 0.093661] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page clare # [ 0.094690] fbcon: Taking over console bob # [ 0.093577] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages clare # [ 0.094706] ACPI: Interpreter disabled. bob # [ 0.093579] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page clare # [ 0.095369] iommu: Default domain type: Translated bob # [ 0.094588] fbcon: Taking over console clare # [ 0.095373] iommu: DMA domain TLB invalidation policy: strict mode bob # [ 0.094606] ACPI: Interpreter disabled. alice # [ 0.111714] clocksource: Switched to clocksource arch_sys_counter clare # [ 0.095675] SCSI subsystem initialized bob # [ 0.095255] iommu: Default domain type: Translated clare # [ 0.095880] usbcore: registered new interface driver usbfs bob # [ 0.095259] iommu: DMA domain TLB invalidation policy: strict mode clare # [ 0.095909] usbcore: registered new interface driver hub bob # [ 0.095551] SCSI subsystem initialized clare # [ 0.095924] usbcore: registered new device driver usb bob # [ 0.095751] usbcore: registered new interface driver usbfs clare # [ 0.096132] pps_core: LinuxPPS API ver. 1 registered bob # [ 0.095786] usbcore: registered new interface driver hub bob # [ 0.095801] usbcore: registered new device driver usb clare # [ 0.096135] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti bob # [ 0.095997] pps_core: LinuxPPS API ver. 1 registered clare # [ 0.096140] PTP clock support registered clare # [ 0.096171] EDAC MC: Ver: 3.0.0 bob # [ 0.096000] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti bob # [ 0.096005] PTP clock support registered clare # [ 0.105800] scmi_core: SCMI protocol bus registered bob # [ 0.096036] EDAC MC: Ver: 3.0.0 bob # [ 0.105690] scmi_core: SCMI protocol bus registered clare # [ 0.106522] FPGA manager framework clare # [ 0.109725] vgaarb: loaded bob # [ 0.106417] FPGA manager framework bob # [ 0.109603] vgaarb: loaded alice # [ 0.121177] VFS: Disk quotas dquot_6.6.0 alice # [ 0.121202] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 0.122673] netfs: FS-Cache loaded alice # [ 0.122764] pnp: PnP ACPI: disabled clare # [ 0.110455] clocksource: Switched to clocksource arch_sys_counter bob # [ 0.110299] clocksource: Switched to clocksource arch_sys_counter alice # [ 0.126033] NET: Registered PF_INET protocol family alice # [ 0.126207] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) clare # [ 0.120347] VFS: Disk quotas dquot_6.6.0 clare # [ 0.120374] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) clare # [ 0.121861] netfs: FS-Cache loaded bob # [ 0.120260] VFS: Disk quotas dquot_6.6.0 clare # [ 0.121970] pnp: PnP ACPI: disabled bob # [ 0.120285] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.121742] netfs: FS-Cache loaded bob # [ 0.121842] pnp: PnP ACPI: disabled clare # [ 0.125202] NET: Registered PF_INET protocol family clare # [ 0.125372] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) bob # [ 0.125124] NET: Registered PF_INET protocol family bob # [ 0.125305] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.150394] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.150419] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) alice # [ 0.150432] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) alice # [ 0.150469] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.150535] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.150633] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 0.150666] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.150687] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.150798] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 0.150851] NET: Registered PF_XDP protocol family alice # [ 0.150874] PCI: CLS 0 bytes, default 64 alice # [ 0.151090] Trying to unpack rootfs image as initramfs... bob # [ 0.150240] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.150265] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.150277] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) clare # [ 0.150694] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.163739] kvm [1]: HYP mode not available clare # [ 0.150718] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) clare # [ 0.150732] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) clare # [ 0.150770] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) clare # [ 0.150837] TCP: Hash tables configured (established 8192 bind 8192) clare # [ 0.150951] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) clare # [ 0.151014] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.151068] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.151179] NET: Registered PF_UNIX/PF_LOCAL protocol family clare # [ 0.151241] NET: Registered PF_XDP protocol family clare # [ 0.151261] PCI: CLS 0 bytes, default 64 clare # [ 0.151486] Trying to unpack rootfs image as initramfs... clare # [ 0.160621] kvm [1]: HYP mode not available bob # [ 0.152322] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) bob # [ 0.152392] TCP: Hash tables configured (established 8192 bind 8192) bob # [ 0.152499] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) bob # [ 0.152563] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.152585] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.152694] NET: Registered PF_UNIX/PF_LOCAL protocol family bob # [ 0.152725] NET: Registered PF_XDP protocol family bob # [ 0.152744] PCI: CLS 0 bytes, default 64 bob # [ 0.152971] Trying to unpack rootfs image as initramfs... bob # [ 0.154599] kvm [1]: HYP mode not available alice # [ 0.228162] Initialise system trusted keyrings alice # [ 0.228275] workingset: timestamp_bits=42 max_order=18 bucket_order=0 alice # [ 0.228604] squashfs: version 4.0 (2009/01/31) Phillip Lougher alice # [ 0.228637] 9p: Installing v9fs 9p2000 file system support clare # [ 0.226107] Initialise system trusted keyrings clare # [ 0.226248] workingset: timestamp_bits=42 max_order=18 bucket_order=0 bob # [ 0.230710] Initialise system trusted keyrings bob # [ 0.230821] workingset: timestamp_bits=42 max_order=18 bucket_order=0 bob # [ 0.231152] squashfs: version 4.0 (2009/01/31) Phillip Lougher bob # [ 0.231195] 9p: Installing v9fs 9p2000 file system support clare # [ 0.235578] squashfs: version 4.0 (2009/01/31) Phillip Lougher clare # [ 0.235633] 9p: Installing v9fs 9p2000 file system support alice # [ 0.257241] Key type asymmetric registered alice # [ 0.257256] Asymmetric key parser 'x509' registered alice # [ 0.257317] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) alice # [ 0.257409] io scheduler mq-deadline registered alice # [ 0.257412] io scheduler kyber registered alice # [ 0.261712] pl061_gpio 9030000.pl061: PL061 GPIO chip registered alice # [ 0.262053] ledtrig-cpu: registered to indicate activity on CPUs alice # [ 0.262350] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: alice # [ 0.262366] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 alice # [ 0.262378] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 alice # [ 0.262382] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 alice # [ 0.262413] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits bob # [ 0.251741] Key type asymmetric registered bob # [ 0.251755] Asymmetric key parser 'x509' registered alice # [ 0.262433] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] bob # [ 0.251804] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) alice # [ 0.262504] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 bob # [ 0.251895] io scheduler mq-deadline registered alice # [ 0.262520] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 0.251898] io scheduler kyber registered alice # [ 0.262523] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] alice # [ 0.262526] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.262528] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] clare # [ 0.255362] Key type asymmetric registered alice # [ 0.262601] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint clare # [ 0.255377] Asymmetric key parser 'x509' registered clare # [ 0.255434] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) alice # [ 0.263038] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.255524] io scheduler mq-deadline registered alice # [ 0.263162] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.255527] io scheduler kyber registered alice # [ 0.263171] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.257850] pl061_gpio 9030000.pl061: PL061 GPIO chip registered alice # [ 0.263189] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.258165] ledtrig-cpu: registered to indicate activity on CPUs alice # [ 0.263198] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.263512] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.263635] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.261337] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: alice # [ 0.263644] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.263662] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.261357] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 clare # [ 0.261370] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 clare # [ 0.261374] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.261402] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits clare # [ 0.261423] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.261512] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 clare # [ 0.261529] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 0.262432] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.261532] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] bob # [ 0.262803] ledtrig-cpu: registered to indicate activity on CPUs clare # [ 0.261535] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] bob # [ 0.263092] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: clare # [ 0.261537] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] bob # [ 0.263108] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 clare # [ 0.261602] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint bob # [ 0.263121] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 clare # [ 0.262047] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.262173] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.263126] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.262182] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.263156] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits clare # [ 0.262201] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.262210] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.263177] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] bob # [ 0.263243] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 bob # [ 0.263259] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 0.263263] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] bob # [ 0.263265] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] bob # [ 0.263268] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] bob # [ 0.263347] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint bob # [ 0.263796] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.284072] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint bob # [ 0.263923] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.284205] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] bob # [ 0.263932] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.284214] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.263951] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.284234] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.263960] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.284550] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.284674] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.264292] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.264417] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.284683] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.264426] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.284701] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.284710] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.264444] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.285023] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint bob # [ 0.264755] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint bob # [ 0.264881] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.285150] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.264890] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.285168] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.264908] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.285501] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint bob # [ 0.265218] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.285631] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.265344] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.285649] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.278607] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.265353] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.278738] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.285931] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint bob # [ 0.265371] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.278748] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.286053] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] bob # [ 0.265380] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.278766] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.286245] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.265692] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.279101] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.286372] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.279227] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] bob # [ 0.265820] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.286390] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.279237] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.265838] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.286698] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.279255] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.266167] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 0.286828] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.266294] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.279568] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.286845] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.279695] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.287151] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.279704] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.285907] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.287276] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.279722] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.286225] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 0.287293] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.279731] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.287606] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint clare # [ 0.280048] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.280175] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.280193] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.280508] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint clare # [ 0.280635] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.301756] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] clare # [ 0.280653] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.301767] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.301785] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.280931] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.281053] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] alice # [ 0.302120] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.302245] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.281276] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.302254] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.281403] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.302273] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.281422] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.302712] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned clare # [ 0.281732] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.302719] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned clare # [ 0.281861] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.281879] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.302722] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned clare # [ 0.282189] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.302756] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned clare # [ 0.282317] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.302785] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned clare # [ 0.282335] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.302815] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned alice # [ 0.302847] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned clare # [ 0.298140] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint alice # [ 0.302876] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned clare # [ 0.298362] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.294410] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.298371] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.302907] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.298388] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.294626] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.302936] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.294756] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.302966] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.294774] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.302996] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned bob # [ 0.295085] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.295217] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.303057] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned bob # [ 0.295235] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.303085] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned clare # [ 0.300680] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.303098] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.300806] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.295570] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.300815] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.303109] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.295699] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.300833] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.303171] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.295717] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.303184] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned clare # [ 0.301304] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned bob # [ 0.296037] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint alice # [ 0.303196] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned clare # [ 0.301311] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned bob # [ 0.296210] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.303208] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.296220] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.301314] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned alice # [ 0.303220] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.296239] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.301348] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned alice # [ 0.303233] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.296649] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.301378] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned alice # [ 0.303244] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.296775] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.301408] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.296784] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.303256] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.296802] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.303268] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned clare # [ 0.301439] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.297235] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned alice # [ 0.303280] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned clare # [ 0.301469] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned alice # [ 0.303292] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.297242] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned clare # [ 0.301499] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned alice # [ 0.303304] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned bob # [ 0.297245] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned clare # [ 0.301528] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned alice # [ 0.303316] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned bob # [ 0.297279] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned alice # [ 0.303328] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned clare # [ 0.301558] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned alice # [ 0.303339] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.297309] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned clare # [ 0.301587] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned alice # [ 0.303356] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.297339] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned clare # [ 0.301677] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned alice # [ 0.303360] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] bob # [ 0.297370] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned clare # [ 0.301729] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned alice # [ 0.303368] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] bob # [ 0.297399] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned clare # [ 0.301741] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.301754] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.297430] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.301766] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.297459] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned clare # [ 0.301778] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned bob # [ 0.297487] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned clare # [ 0.301790] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.297516] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned clare # [ 0.301802] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned alice # [ 0.325019] pci 0000:00:07.0: enabling device (0000 -> 0002) bob # [ 0.297599] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned clare # [ 0.301815] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.297642] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned clare # [ 0.301827] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.297655] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.301840] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.297666] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned clare # [ 0.301851] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.297678] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned clare # [ 0.301864] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned bob # [ 0.297690] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned clare # [ 0.301876] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned clare # [ 0.301887] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.297702] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned clare # [ 0.301899] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned bob # [ 0.297715] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned clare # [ 0.301911] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned bob # [ 0.297727] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned clare # [ 0.301923] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned bob # [ 0.297738] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned clare # [ 0.301935] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.297750] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned clare # [ 0.301952] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.297762] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned clare # [ 0.301957] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] bob # [ 0.297774] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned clare # [ 0.301965] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] bob # [ 0.297785] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.297797] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.297809] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned bob # [ 0.297821] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned alice # [ 0.333422] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) bob # [ 0.297832] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.334931] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) bob # [ 0.297844] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.297860] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.297864] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] bob # [ 0.297872] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] bob # [ 0.325308] pci 0000:00:07.0: enabling device (0000 -> 0002) clare # [ 0.326592] pci 0000:00:07.0: enabling device (0000 -> 0002) bob # [ 0.332352] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.344456] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) bob # [ 0.333828] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.346314] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.341355] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) clare # [ 0.343562] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.355861] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) bob # [ 0.343713] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) clare # [ 0.344969] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) alice # [ 0.358362] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) bob # [ 0.345392] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.346311] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.355657] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) clare # [ 0.357399] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) alice # [ 0.368122] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.356019] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) alice # [ 0.369708] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) bob # [ 0.357873] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) alice # [ 0.371258] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) clare # [ 0.359898] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) clare # [ 0.361431] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.374281] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) bob # [ 0.368454] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.370048] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) clare # [ 0.370599] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) alice # [ 0.383877] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) clare # [ 0.372197] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) bob # [ 0.372622] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) alice # [ 0.387321] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.373983] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) clare # [ 0.374311] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) alice # [ 0.389269] msm_serial: driver initialized alice # [ 0.389397] SuperH (H)SCI(F) driver initialized alice # [ 0.389439] STM32 USART driver initialized clare # [ 0.385698] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.383484] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) clare # [ 0.387177] msm_serial: driver initialized clare # [ 0.387261] SuperH (H)SCI(F) driver initialized clare # [ 0.387310] STM32 USART driver initialized bob # [ 0.387574] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.388557] msm_serial: driver initialized bob # [ 0.388628] SuperH (H)SCI(F) driver initialized bob # [ 0.388667] STM32 USART driver initialized alice # [ 0.412368] loop: module loaded alice # [ 0.412492] virtio_blk virtio2: 1/0/0 default/read/poll queues alice # [ 0.413079] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 0.417409] megasas: 07.734.00.00-rc1 alice # [ 0.417902] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] alice # [ 0.420709] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.420730] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.422370] Using buffer write method alice # [ 0.422418] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] clare # [ 0.412119] loop: module loaded clare # [ 0.412250] virtio_blk virtio2: 1/0/0 default/read/poll queues bob # [ 0.412503] loop: module loaded clare # [ 0.412870] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) bob # [ 0.412619] virtio_blk virtio2: 1/0/0 default/read/poll queues bob # [ 0.413292] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 0.432298] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.432339] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.434317] Using buffer write method alice # [ 0.434329] Concatenating MTD devices: alice # [ 0.434331] (0): "0.flash" alice # [ 0.434333] (1): "0.flash" alice # [ 0.434334] into device "0.flash" bob # [ 0.418681] megasas: 07.734.00.00-rc1 bob # [ 0.419137] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] clare # [ 0.416146] megasas: 07.734.00.00-rc1 clare # [ 0.416616] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] bob # [ 0.431330] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.431353] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.432938] Using buffer write method bob # [ 0.432992] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] clare # [ 0.439595] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.439620] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.441224] Using buffer write method clare # [ 0.441280] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] clare # [ 0.445125] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.445138] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.442313] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.442338] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.444178] Using buffer write method bob # [ 0.444191] Concatenating MTD devices: bob # [ 0.444192] (0): "0.flash" bob # [ 0.444194] (1): "0.flash" bob # [ 0.444196] into device "0.flash" clare # [ 0.454478] Using buffer write method clare # [ 0.454491] Concatenating MTD devices: clare # [ 0.454492] (0): "0.flash" clare # [ 0.454494] (1): "0.flash" clare # [ 0.454496] into device "0.flash" alice # [ 0.649999] Freeing initrd memory: 26948K alice # [ 0.656843] tun: Universal TUN/TAP device driver, 1.6 alice # [ 0.659034] thunder_xcv, ver 1.0 alice # [ 0.659049] thunder_bgx, ver 1.0 alice # [ 0.659058] nicpf, ver 1.0 alice # [ 0.659382] e1000: Intel(R) PRO/1000 Network Driver alice # [ 0.659387] e1000: Copyright (c) 1999-2006 Intel Corporation. alice # [ 0.659401] e1000e: Intel(R) PRO/1000 Network Driver alice # [ 0.659406] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. alice # [ 0.659417] igb: Intel(R) Gigabit Ethernet Network Driver alice # [ 0.659419] igb: Copyright (c) 2007-2014 Intel Corporation. alice # [ 0.659428] igbvf: Intel(R) Gigabit Virtual Function Network Driver alice # [ 0.659433] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. alice # [ 0.659499] sky2: driver version 1.30 alice # [ 0.665257] usbcore: registered new interface driver usb-storage alice # [ 0.665343] usbcore: registered new interface driver usbserial_generic alice # [ 0.665361] usbserial: USB Serial support registered for generic alice # [ 0.665714] hv_vmbus: registering driver hyperv_keyboard alice # [ 0.666710] ehci-pci 0000:00:07.0: EHCI Host Controller alice # [ 0.666741] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 alice # [ 0.667000] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 alice # [ 0.667173] rtc-pl031 9010000.pl031: registered as rtc0 bob # [ 0.657793] Freeing initrd memory: 26932K alice # [ 0.667201] rtc-pl031 9010000.pl031: setting system clock to 2026-10-04T15:27:12 UTC (1791127632) alice # [ 0.667394] i2c_dev: i2c /dev entries driver alice # [ 0.673864] sdhci: Secure Digital Host Controller Interface driver alice # [ 0.673871] sdhci: Copyright(c) Pierre Ossman alice # [ 0.674032] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.674217] sdhci-pltfm: SDHCI platform and OF driver helper alice # [ 0.675147] hid: raw HID events driver (C) Jiri Kosina alice # [ 0.675294] usbcore: registered new interface driver usbhid clare # [ 0.663525] Freeing initrd memory: 26956K alice # [ 0.675298] usbhid: USB HID core driver alice # [ 0.675771] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 bob # [ 0.664835] tun: Universal TUN/TAP device driver, 1.6 alice # [ 0.676175] hub 1-0:1.0: USB hub found alice # [ 0.676201] hub 1-0:1.0: 6 ports detected bob # [ 0.667542] thunder_xcv, ver 1.0 bob # [ 0.667557] thunder_bgx, ver 1.0 alice # [ 0.680808] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available bob # [ 0.667566] nicpf, ver 1.0 bob # [ 0.667896] e1000: Intel(R) PRO/1000 Network Driver alice # [ 0.681689] drop_monitor: Initializing network drop monitor service alice # [ 0.681852] NET: Registered PF_INET6 protocol family bob # [ 0.667900] e1000: Copyright (c) 1999-2006 Intel Corporation. alice # [ 0.682481] Segment Routing with IPv6 bob # [ 0.667915] e1000e: Intel(R) PRO/1000 Network Driver alice # [ 0.682497] In-situ OAM (IOAM) with IPv6 bob # [ 0.667920] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. alice # [ 0.682541] NET: Registered PF_PACKET protocol family alice # [ 0.682598] 9pnet: Installing 9P2000 support bob # [ 0.667931] igb: Intel(R) Gigabit Ethernet Network Driver alice # [ 0.682630] Key type dns_resolver registered bob # [ 0.667933] igb: Copyright (c) 2007-2014 Intel Corporation. clare # [ 0.670565] tun: Universal TUN/TAP device driver, 1.6 bob # [ 0.667943] igbvf: Intel(R) Gigabit Virtual Function Network Driver clare # [ 0.672816] thunder_xcv, ver 1.0 clare # [ 0.672832] thunder_bgx, ver 1.0 bob # [ 0.667948] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. clare # [ 0.672841] nicpf, ver 1.0 bob # [ 0.668012] sky2: driver version 1.30 clare # [ 0.673184] e1000: Intel(R) PRO/1000 Network Driver bob # [ 0.668869] usbcore: registered new interface driver usb-storage clare # [ 0.673189] e1000: Copyright (c) 1999-2006 Intel Corporation. clare # [ 0.673205] e1000e: Intel(R) PRO/1000 Network Driver bob # [ 0.668959] usbcore: registered new interface driver usbserial_generic clare # [ 0.673210] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.668981] usbserial: USB Serial support registered for generic clare # [ 0.673221] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.669289] hv_vmbus: registering driver hyperv_keyboard clare # [ 0.673223] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.670290] ehci-pci 0000:00:07.0: EHCI Host Controller clare # [ 0.673233] igbvf: Intel(R) Gigabit Virtual Function Network Driver clare # [ 0.673237] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. clare # [ 0.673305] sky2: driver version 1.30 alice # [ 0.688670] registered taskstats version 1 clare # [ 0.674130] usbcore: registered new interface driver usb-storage alice # [ 0.688826] Loading compiled-in X.509 certificates clare # [ 0.674215] usbcore: registered new interface driver usbserial_generic clare # [ 0.674234] usbserial: USB Serial support registered for generic clare # [ 0.681488] ehci-pci 0000:00:07.0: EHCI Host Controller bob # [ 0.677226] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 clare # [ 0.681521] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 bob # [ 0.677428] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 clare # [ 0.681763] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 alice # [ 0.694160] Demotion targets for Node 0: null bob # [ 0.677603] rtc-pl031 9010000.pl031: registered as rtc0 alice # [ 0.694228] Key type .fscrypt registered clare # [ 0.681940] hv_vmbus: registering driver hyperv_keyboard alice # [ 0.694235] Key type fscrypt-provisioning registered bob # [ 0.677632] rtc-pl031 9010000.pl031: setting system clock to 2026-10-04T15:27:12 UTC (1791127632) bob # [ 0.677810] i2c_dev: i2c /dev entries driver alice # [ 0.694339] ima: No TPM chip found, activating TPM-bypass! alice # [ 0.694350] ima: Allocated hash algorithm: sha1 bob # [ 0.679416] sdhci: Secure Digital Host Controller Interface driver alice # [ 0.694404] ima: No architecture policies found bob # [ 0.679422] sdhci: Copyright(c) Pierre Ossman alice # [ 0.694897] input: gpio-keys as /devices/platform/gpio-keys/input/input0 bob # [ 0.679571] Synopsys Designware Multimedia Card Interface Driver bob # [ 0.679748] sdhci-pltfm: SDHCI platform and OF driver helper clare # [ 0.684339] rtc-pl031 9010000.pl031: registered as rtc0 bob # [ 0.680649] hid: raw HID events driver (C) Jiri Kosina bob # [ 0.680780] usbcore: registered new interface driver usbhid clare # [ 0.684373] rtc-pl031 9010000.pl031: setting system clock to 2026-10-04T15:27:12 UTC (1791127632) bob # [ 0.680783] usbhid: USB HID core driver clare # [ 0.684550] i2c_dev: i2c /dev entries driver clare # [ 0.686150] sdhci: Secure Digital Host Controller Interface driver clare # [ 0.686155] sdhci: Copyright(c) Pierre Ossman clare # [ 0.686311] Synopsys Designware Multimedia Card Interface Driver clare # [ 0.689306] sdhci-pltfm: SDHCI platform and OF driver helper clare # [ 0.690249] hid: raw HID events driver (C) Jiri Kosina bob # [ 0.686749] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 clare # [ 0.690400] usbcore: registered new interface driver usbhid bob # [ 0.687122] hub 1-0:1.0: USB hub found clare # [ 0.690404] usbhid: USB HID core driver bob # [ 0.687146] hub 1-0:1.0: 6 ports detected clare # [ 0.690509] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 bob # [ 0.687441] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available bob # [ 0.688330] drop_monitor: Initializing network drop monitor service bob # [ 0.688443] NET: Registered PF_INET6 protocol family bob # [ 0.689057] Segment Routing with IPv6 bob # [ 0.689075] In-situ OAM (IOAM) with IPv6 clare # [ 0.692978] hub 1-0:1.0: USB hub found bob # [ 0.689119] NET: Registered PF_PACKET protocol family clare # [ 0.693003] hub 1-0:1.0: 6 ports detected bob # [ 0.689177] 9pnet: Installing 9P2000 support bob # [ 0.689211] Key type dns_resolver registered alice # [ 0.707446] clk: Disabling unused clocks clare # [ 0.693952] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available alice # [ 0.707455] PM: genpd: Disabling unused power domains clare # [ 0.696131] drop_monitor: Initializing network drop monitor service clare # [ 0.696247] NET: Registered PF_INET6 protocol family clare # [ 0.696919] Segment Routing with IPv6 alice # [ 0.710461] Freeing unused kernel memory: 4736K bob # [ 0.696415] registered taskstats version 1 clare # [ 0.696935] In-situ OAM (IOAM) with IPv6 alice # [ 0.710536] Run /init as init process bob # [ 0.696576] Loading compiled-in X.509 certificates clare # [ 0.696980] NET: Registered PF_PACKET protocol family clare # [ 0.697037] 9pnet: Installing 9P2000 support clare # [ 0.697068] Key type dns_resolver registered bob # [ 0.701894] Demotion targets for Node 0: null bob # [ 0.701966] Key type .fscrypt registered bob # [ 0.701973] Key type fscrypt-provisioning registered bob # [ 0.702093] ima: No TPM chip found, activating TPM-bypass! bob # [ 0.702104] ima: Allocated hash algorithm: sha1 bob # [ 0.702181] ima: No architecture policies found clare # [ 0.702520] registered taskstats version 1 clare # [ 0.702694] Loading compiled-in X.509 certificates bob # [ 0.704971] input: gpio-keys as /devices/platform/gpio-keys/input/input0 clare # [ 0.708040] Demotion targets for Node 0: null clare # [ 0.708112] Key type .fscrypt registered clare # [ 0.708119] Key type fscrypt-provisioning registered clare # [ 0.708230] ima: No TPM chip found, activating TPM-bypass! clare # [ 0.708240] ima: Allocated hash algorithm: sha1 clare # [ 0.708341] ima: No architecture policies found clare # [ 0.708850] input: gpio-keys as /devices/platform/gpio-keys/input/input0 bob # [ 0.715305] clk: Disabling unused clocks alice # [ 0.730248] systemd[1]: Successfully made /usr/ read-only. bob # [ 0.715316] PM: genpd: Disabling unused power domains bob # [ 0.717659] Freeing unused kernel memory: 4736K bob # [ 0.717735] Run /init as init process clare # [ 0.721719] clk: Disabling unused clocks clare # [ 0.721729] PM: genpd: Disabling unused power domains clare # [ 0.724829] Freeing unused kernel memory: 4736K clare # [ 0.724914] Run /init as init process bob # [ 0.740740] systemd[1]: Successfully made /usr/ read-only. clare # [ 0.744888] systemd[1]: Successfully made /usr/ read-only. alice # [ 0.927831] usb 1-1: new high-speed USB device number 2 using ehci-pci bob # [ 0.938392] usb 1-1: new high-speed USB device number 2 using ehci-pci clare # [ 0.942525] usb 1-1: new high-speed USB device number 2 using ehci-pci alice # [ 1.064050] 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.064080] systemd[1]: Detected virtualization qemu. alice # [ 1.064120] systemd[1]: Detected architecture arm64. alice # [ 1.064129] systemd[1]: Running in initrd. alice # [ 1.064906] systemd[1]: Initializing machine ID from random generator. alice # [ 1.065261] systemd[1]: Hostname set to . bob # [ 1.074842] 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.094331] 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.074873] systemd[1]: Detected virtualization qemu. bob # [ 1.074912] systemd[1]: Detected architecture arm64. bob # [ 1.074921] systemd[1]: Running in initrd. clare # [ 1.078997] 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.079029] systemd[1]: Detected virtualization qemu. bob # [ 1.075702] systemd[1]: Initializing machine ID from random generator. clare # [ 1.079069] systemd[1]: Detected architecture arm64. bob # [ 1.075965] systemd[1]: Hostname set to . clare # [ 1.079078] systemd[1]: Running in initrd. clare # [ 1.079930] systemd[1]: Initializing machine ID from random generator. clare # [ 1.080221] systemd[1]: Hostname set to . bob # [ 1.097897] 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.109448] 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.183674] 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.203518] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 1.186466] 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.194637] 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.211336] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 1.224786] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 1.258127] systemd[1]: Queued start job for default target Initrd Default Target. alice # [ 1.265135] systemd[1]: Created slice Slice /system/modprobe. alice # [ 1.265302] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 1.265337] systemd[1]: Expecting device /dev/disk/by-label/nixos... alice # [ 1.265356] systemd[1]: Reached target Path Units. alice # [ 1.265368] systemd[1]: Reached target Slice Units. alice # [ 1.265380] systemd[1]: Reached target Swaps. alice # [ 1.265390] systemd[1]: Reached target Timer Units. alice # [ 1.265527] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 1.265641] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 1.265752] systemd[1]: Listening on Journal Sockets. bob # [ 1.259491] systemd[1]: Queued start job for default target Initrd Default Target. alice # [ 1.265819] systemd[1]: Listening on udev Control Socket. alice # [ 1.265858] systemd[1]: Listening on udev Kernel Socket. alice # [ 1.265871] systemd[1]: Reached target Socket Units. alice # [ 1.266857] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.266904] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.278735] systemd[1]: Mounting Kernel Configuration File System... bob # [ 1.267642] systemd[1]: Created slice Slice /system/modprobe. bob # [ 1.267769] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 1.267798] systemd[1]: Expecting device /dev/disk/by-label/nixos... bob # [ 1.267817] systemd[1]: Reached target Path Units. bob # [ 1.267829] systemd[1]: Reached target Slice Units. bob # [ 1.267838] systemd[1]: Reached target Swaps. bob # [ 1.267849] systemd[1]: Reached target Timer Units. bob # [ 1.267968] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 1.268074] systemd[1]: Listening on Journal Socket (/dev/log). bob # [ 1.268218] systemd[1]: Listening on Journal Sockets. alice # [ 1.283938] systemd[1]: Starting Journal Service... bob # [ 1.268287] systemd[1]: Listening on udev Control Socket. bob # [ 1.268326] systemd[1]: Listening on udev Kernel Socket. bob # [ 1.268338] systemd[1]: Reached target Socket Units. bob # [ 1.269328] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 1.269374] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 1.280983] systemd[1]: Mounting Kernel Configuration File System... alice # [ 1.294772] systemd[1]: Starting Load Kernel Modules... clare # [ 1.280755] systemd[1]: Queued start job for default target Initrd Default Target. alice # [ 1.294829] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 1.284601] systemd[1]: Created slice Slice /system/modprobe. clare # [ 1.284743] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 1.284801] systemd[1]: Expecting device /dev/disk/by-label/nixos... clare # [ 1.284822] systemd[1]: Reached target Path Units. clare # [ 1.284834] systemd[1]: Reached target Slice Units. clare # [ 1.284846] systemd[1]: Reached target Swaps. clare # [ 1.284858] systemd[1]: Reached target Timer Units. clare # [ 1.284991] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 1.285164] systemd[1]: Listening on Journal Socket (/dev/log). clare # [ 1.285779] systemd[1]: Listening on Journal Sockets. bob # [ 1.286511] systemd[1]: Starting Journal Service... clare # [ 1.285861] systemd[1]: Listening on udev Control Socket. clare # [ 1.285904] systemd[1]: Listening on udev Kernel Socket. clare # [ 1.285918] systemd[1]: Reached target Socket Units. clare # [ 1.293342] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 1.293397] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.303775] usb 1-2: new high-speed USB device number 3 using ehci-pci alice # [ 1.304751] systemd[1]: Starting Coldplug All udev Devices... bob # [ 1.296784] systemd[1]: Starting Load Kernel Modules... bob # [ 1.296842] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 1.301035] systemd[1]: Mounting Kernel Configuration File System... bob # [ 1.306350] usb 1-2: new high-speed USB device number 3 using ehci-pci bob # [ 1.307247] systemd[1]: Starting Coldplug All udev Devices... alice # [ 1.319821] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 1.310768] systemd[1]: Starting Journal Service... alice # [ 1.320713] systemd[1]: Mounted Kernel Configuration File System. clare # [ 1.314485] usb 1-2: new high-speed USB device number 3 using ehci-pci bob # [ 1.318575] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 1.319312] systemd[1]: Mounted Kernel Configuration File System. bob # [ 1.320430] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.332339] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.335062] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. alice # [ 1.338041] systemd-journald[71]: Collecting audit messages is disabled. clare # [ 1.334574] systemd[1]: Starting Load Kernel Modules... clare # [ 1.334653] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 1.335602] systemd[1]: Starting Coldplug All udev Devices... alice # [ 1.348593] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev clare # [ 1.340255] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 1.341019] systemd[1]: Mounted Kernel Configuration File System. bob # [ 1.336951] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. alice # [ 1.359200] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 alice # [ 1.359433] [drm] features: -virgl +edid -resource_blob -host_visible alice # [ 1.359436] [drm] features: -context_init clare # [ 1.347677] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. clare # [ 1.352233] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 1.353314] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev alice # [ 1.363856] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 1.368695] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 1.354396] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev bob # [ 1.364588] systemd-journald[71]: Collecting audit messages is disabled. clare # [ 1.360443] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 clare # [ 1.360672] [drm] features: -virgl +edid -resource_blob -host_visible clare # [ 1.360674] [drm] features: -context_init bob # [ 1.367305] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 1.361157] [drm] number of scanouts: 1 clare # [ 1.361166] [drm] number of cap sets: 0 alice # [ 1.380041] [drm] number of scanouts: 1 alice # [ 1.380051] [drm] number of cap sets: 0 alice # [ 1.380629] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic alice # [ 1.380637] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 bob # [ 1.371304] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 1.375824] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 bob # [ 1.376050] [drm] features: -virgl +edid -resource_blob -host_visible bob # [ 1.376052] [drm] features: -context_init bob # [ 1.376530] [drm] number of scanouts: 1 bob # [ 1.376539] [drm] number of cap sets: 0 clare # [ 1.382831] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic clare # [ 1.382843] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 bob # [ 1.382656] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic bob # [ 1.382663] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 alice # [ 1.401791] Console: switching to colour frame buffer device 160x50 clare # [ 1.394904] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 1.395978] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 1.408275] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 1.408404] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 1.408421] systemd[1]: Reached target Local File Systems. alice # [ 1.413441] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 1.402866] Console: switching to colour frame buffer device 160x50 bob # [ 1.402631] Console: switching to colour frame buffer device 160x50 clare # [ 1.412335] systemd-journald[71]: Collecting audit messages is disabled. bob # [ 1.410674] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 1.426607] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device bob # [ 1.410805] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 1.410822] systemd[1]: Reached target Local File Systems. bob # [ 1.411926] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 1.435899] systemd[1]: Finished Load Kernel Modules. alice # [ 1.436963] systemd[1]: Starting Apply Kernel Variables... alice # [ 1.433952] systemd-modules-load[72]: Inserted module 'dm_mod' alice # [ 1.436327] systemd-modules-load[72]: Module 'virtio_balloon' is built in alice # [ 1.437095] systemd-modules-load[72]: Module 'virtio_console' is built in alice # [ 1.437850] systemd-modules-load[72]: Inserted module 'virtio_gpu' alice # [ 1.438607] systemd-modules-load[72]: Module 'virtio_rng' is built in clare # [ 1.430989] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device bob # [ 1.430910] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.449360] systemd[1]: Started Journal Service. clare # [ 1.438871] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 1.439031] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 1.439059] systemd[1]: Reached target Local File Systems. clare # [ 1.440224] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 1.442739] systemd[1]: Finished Load Kernel Modules. alice # [ 1.458227] 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.458375] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 clare # [ 1.449365] systemd[1]: Finished Load Kernel Modules. bob # [ 1.451020] systemd[1]: Starting Apply Kernel Variables... clare # [ 1.454746] systemd[1]: Starting Apply Kernel Variables... alice # [ 1.469138] systemd[1]: Starting Create System Files and Directories... bob # [ 1.461909] systemd-modules-load[72]: Inserted module 'dm_mod' bob # [ 1.466660] 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.466831] 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.469035] systemd[1]: Started Journal Service. bob # [ 1.467214] systemd-modules-load[72]: Module 'virtio_balloon' is built in clare # [ 1.470511] 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.478101] 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.486971] systemd-udevd[79]: Using default interface naming scheme 'v261'. bob # [ 1.478754] systemd-modules-load[72]: Module 'virtio_console' is built in bob # [ 1.479599] systemd-modules-load[72]: Inserted module 'virtio_gpu' alice # [ 1.492584] systemd[1]: Finished Apply Kernel Variables. clare # [ 1.486653] systemd[1]: Started Journal Service. bob # [ 1.488266] systemd-modules-load[72]: Module 'virtio_rng' is built in alice # [ 1.500685] systemd[1]: Finished Create System Files and Directories. bob # [ 1.488931] systemd[1]: Starting Create System Files and Directories... bob # [ 1.496117] systemd[1]: Finished Apply Kernel Variables. clare # [ 1.497414] systemd-modules-load[72]: Inserted module 'dm_mod' clare # [ 1.498300] systemd-modules-load[72]: Module 'virtio_balloon' is built in clare # [ 1.499029] systemd-modules-load[72]: Module 'virtio_console' is built in clare # [ 1.499792] systemd-modules-load[72]: Inserted module 'virtio_gpu' bob # [ 1.502280] systemd-udevd[79]: Using default interface naming scheme 'v261'. bob # [ 1.503718] systemd[1]: Finished Create System Files and Directories. alice # [ 1.517449] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 1.512315] systemd-modules-load[72]: Module 'virtio_rng' is built in clare # [ 1.512989] systemd[1]: Starting Create System Files and Directories... clare # [ 1.513709] systemd[1]: Finished Apply Kernel Variables. bob # [ 1.524255] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 1.527749] systemd[1]: Finished Create System Files and Directories. clare # [ 1.529520] systemd-udevd[79]: Using default interface naming scheme 'v261'. clare # [ 1.548271] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 1.565066] systemd[1]: Starting Virtual Console Setup... clare # [ 1.590601] systemd[1]: Starting Virtual Console Setup... alice # [ 1.604302] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 1.606606] systemd[1]: Finished Virtual Console Setup. bob # [ 1.596067] systemd[1]: Starting Virtual Console Setup... clare # [ 1.640316] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 1.642785] systemd[1]: Finished Virtual Console Setup. bob # [ 1.647311] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 1.649622] systemd[1]: Finished Virtual Console Setup. alice # [ 1.834878] systemd[1]: Finished Coldplug All udev Devices. alice # [ 1.835479] systemd[1]: Reached target System Initialization. alice # [ 1.836039] systemd[1]: Reached target Basic System. bob # [ 1.858900] systemd[1]: Finished Coldplug All udev Devices. bob # [ 1.859489] systemd[1]: Reached target System Initialization. bob # [ 1.860053] systemd[1]: Reached target Basic System. clare # [ 1.867403] systemd[1]: Finished Coldplug All udev Devices. clare # [ 1.868037] systemd[1]: Reached target System Initialization. clare # [ 1.872095] systemd[1]: Reached target Basic System. alice # [ 1.928211] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. alice # [ 1.958705] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line. bob # [ 1.960225] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. clare # [ 1.966114] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. bob # [ 1.970784] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. clare # [ 1.988852] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.024352] systemd[1]: Found device /dev/disk/by-label/nixos. alice # [ 2.028881] systemd[1]: Reached target Initrd Root Device. alice # [ 2.032196] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... clare # [ 2.041530] systemd[1]: Found device /dev/disk/by-label/nixos. bob # [ 2.047309] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.049010] systemd[1]: Reached target Initrd Root Device. bob # [ 2.049438] systemd[1]: Reached target Initrd Root Device. clare # [ 2.052139] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... bob # [ 2.051880] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... alice # [ 2.064651] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks alice # [ 2.068979] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. bob # [ 2.084288] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 2.089144] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. clare # [ 2.093936] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks clare # [ 2.098387] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 2.298445] systemd[1]: Mounting /sysroot... bob # [ 2.304152] systemd[1]: Mounting /sysroot... alice # [ 2.330037] EXT4-fs (vda): mounted filesystem 5ea69935-7a05-4fd9-9dea-02574aa75611 r/w with ordered data mode. Quota mode: none. alice # [ 2.326300] systemd[1]: Mounted /sysroot. alice # [ 2.326729] systemd[1]: Reached target Initrd Root File System. alice # [ 2.330479] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.337727] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 2.334012] EXT4-fs (vda): mounted filesystem 9a85103c-e61a-45f1-815c-081cff456cc0 r/w with ordered data mode. Quota mode: none. alice # [ 2.342591] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... clare # [ 2.331861] systemd[1]: Mounting /sysroot... bob # [ 2.333004] systemd[1]: Mounted /sysroot. alice # [ 2.345570] systemd[1]: Reloading... bob # [ 2.334905] systemd[1]: Reached target Initrd Root File System. bob # [ 2.340435] systemd[1]: Starting Mountpoints Configured in the Real Root... bob # [ 2.357552] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. clare # [ 2.365132] EXT4-fs (vda): mounted filesystem 3317e99a-9d03-426d-9dd0-980aa506fcea r/w with ordered data mode. Quota mode: none. clare # [ 2.362794] systemd[1]: Mounted /sysroot. clare # [ 2.363265] systemd[1]: Reached target Initrd Root File System. bob # [ 2.362144] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... bob # [ 2.364397] systemd[1]: Reloading... clare # [ 2.367114] systemd[1]: Starting Mountpoints Configured in the Real Root... clare # [ 2.377564] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. clare # [ 2.380303] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... clare # [ 2.382989] systemd[1]: Reloading... alice # [ 2.508084] systemd[1]: Reloading finished in 163 ms. alice # [ 2.531870] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... alice # [ 2.535493] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... alice # [ 2.537945] systemd[1]: initrd-parse-etc.service: Deactivated successfully. alice # [ 2.539625] systemd[1]: Finished Mountpoints Configured in the Real Root. alice # [ 2.541319] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. bob # [ 2.531261] systemd[1]: Reloading finished in 166 ms. clare # [ 2.548839] systemd[1]: Reloading finished in 165 ms. bob # [ 2.559364] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... bob # [ 2.561388] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... bob # [ 2.565889] systemd[1]: initrd-parse-etc.service: Deactivated successfully. clare # [ 2.568286] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... bob # [ 2.567801] systemd[1]: Finished Mountpoints Configured in the Real Root. bob # [ 2.569578] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. clare # [ 2.571126] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... clare # [ 2.573286] systemd[1]: initrd-parse-etc.service: Deactivated successfully. clare # [ 2.575073] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 2.576380] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.766005] fuse: init (API version 7.45) bob # [ 2.769947] fuse: init (API version 7.45) alice # [ 2.790645] virtiofs virtio6: discovered new tag: nix-store alice # [ 2.791134] virtiofs virtio6: virtio_fs_setup_dax: No cache capability clare # [ 2.781204] fuse: init (API version 7.45) alice # [ 2.792727] (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 alice # [ 2.797388] (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.803913] virtiofs virtio6: discovered new tag: nix-store alice # [ 2.818822] virtiofs virtio7: discovered new tag: shared bob # [ 2.804429] virtiofs virtio6: virtio_fs_setup_dax: No cache capability alice # [ 2.819303] virtiofs virtio7: virtio_fs_setup_dax: No cache capability alice # [ 2.818707] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 2.819391] systemd[1]: Stopped Virtual Console Setup. alice # [ 2.825143] virtiofs virtio8: discovered new tag: xchg alice # [ 2.825606] virtiofs virtio8: virtio_fs_setup_dax: No cache capability bob # [ 2.809165] (udev-worker)[96]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory alice # [ 2.824323] systemd[1]: Stopping Virtual Console Setup... alice # [ 2.825058] systemd[1]: Starting Virtual Console Setup... bob # [ 2.812832] (udev-worker)[96]: 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.820421] virtiofs virtio6: discovered new tag: nix-store clare # [ 2.820934] virtiofs virtio6: virtio_fs_setup_dax: No cache capability alice # [ 2.837313] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 2.838013] systemd[1]: Stopped Virtual Console Setup. bob # [ 2.832392] virtiofs virtio7: discovered new tag: shared alice # [ 2.840167] systemd[1]: Starting Virtual Console Setup... bob # [ 2.832890] virtiofs virtio7: virtio_fs_setup_dax: No cache capability clare # [ 2.840646] virtiofs virtio7: discovered new tag: shared clare # [ 2.841164] virtiofs virtio7: virtio_fs_setup_dax: No cache capability bob # [ 2.842190] virtiofs virtio8: discovered new tag: xchg bob # [ 2.837584] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 2.838536] systemd[1]: Stopped Virtual Console Setup. bob # [ 2.839149] systemd[1]: Stopping Virtual Console Setup... clare # [ 2.840389] (udev-worker)[96]: 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.840690] systemd[1]: Starting Virtual Console Setup... clare # [ 2.843994] (udev-worker)[96]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory alice # [ 2.856630] systemd-vconsole-setup[139]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 2.858700] systemd[1]: Finished Virtual Console Setup. bob # [ 2.851352] virtiofs virtio8: virtio_fs_setup_dax: No cache capability bob # [ 2.852455] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 2.854829] systemd[1]: Stopped Virtual Console Setup. bob # [ 2.856326] systemd[1]: Starting Virtual Console Setup... clare # [ 2.866298] virtiofs virtio8: discovered new tag: xchg clare # [ 2.868981] virtiofs virtio8: virtio_fs_setup_dax: No cache capability clare # [ 2.865189] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 2.866861] systemd[1]: Stopped Virtual Console Setup. clare # [ 2.867405] systemd[1]: Stopping Virtual Console Setup... clare # [ 2.869140] systemd[1]: Starting Virtual Console Setup... bob # [ 2.882688] systemd-vconsole-setup[139]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 2.884834] systemd[1]: Finished Virtual Console Setup. clare # [ 2.898882] systemd-vconsole-setup[138]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 2.901191] systemd[1]: Finished Virtual Console Setup. alice # [ 3.302757] systemd[1]: Mounting /sysroot/nix/.ro-store... alice # [ 3.312270] systemd[1]: Mounting /sysroot/nix/.rw-store... alice # [ 3.315451] systemd[1]: Mounting /sysroot/run... bob # [ 3.306847] systemd[1]: Mounting /sysroot/nix/.ro-store... alice # [ 3.326600] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 3.316294] systemd[1]: Mounting /sysroot/nix/.rw-store... bob # [ 3.319652] systemd[1]: Mounting /sysroot/run... bob # [ 3.330765] systemd[1]: Mounting /sysroot/tmp/shared... clare # [ 3.335336] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 3.344331] systemd[1]: Mounting /sysroot/nix/.rw-store... alice # [ 3.356664] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 3.352294] systemd[1]: Mounting /sysroot/run... bob # [ 3.360256] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 3.362030] systemd[1]: Mounting /sysroot/tmp/shared... alice # [ 3.374136] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 3.384504] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 3.386192] systemd[1]: Mounted /sysroot/run. alice # [ 3.389519] systemd[1]: Mounted /sysroot/tmp/shared. bob # [ 3.381116] systemd[1]: Mounted /sysroot/nix/.ro-store. alice # [ 3.398016] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.402594] systemd[1]: Mounted /sysroot/tmp/xchg. clare # [ 3.396242] systemd[1]: Mounting /sysroot/tmp/xchg... bob # [ 3.396453] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 3.397507] systemd[1]: Mounted /sysroot/run. bob # [ 3.399045] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 3.415236] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.416660] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 3.406150] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.418926] systemd[1]: Mounting /sysroot/nix/store... bob # [ 3.407819] systemd[1]: Mounted /sysroot/tmp/xchg. clare # [ 3.409512] systemd[1]: Mounted /sysroot/nix/.rw-store. clare # [ 3.420868] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 3.422257] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 3.423415] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.424334] systemd[1]: Mounted /sysroot/nix/.ro-store. clare # [ 3.425041] systemd[1]: Mounted /sysroot/run. clare # [ 3.425621] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 3.442954] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 3.454704] systemd[1]: Mounted /sysroot/nix/store. alice # [ 3.456467] systemd[1]: Reached target Initrd File Systems. clare # [ 3.446248] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.447525] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 3.458664] systemd[1]: Starting Find NixOS closure... alice # [ 3.464676] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... alice # [ 3.491789] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. alice # [ 3.503412] systemd[1]: Finished Find NixOS closure. alice # [ 3.505179] systemd[1]: Reached target Initrd Default Target. alice # [ 3.506358] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 3.528451] systemd[1]: Stopped target Initrd Default Target. alice # [ 3.529703] systemd[1]: Stopped target Basic System. alice # [ 3.530688] systemd[1]: Stopped target Initrd Root Device. alice # [ 3.531671] systemd[1]: Stopped target Path Units. alice # [ 3.532655] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 3.536316] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. alice # [ 3.537312] systemd[1]: Stopped target Slice Units. alice # [ 3.538616] systemd[1]: Stopped target Socket Units. alice # [ 3.540114] systemd[1]: Stopped target System Initialization. alice # [ 3.541994] systemd[1]: Stopped target Swaps. alice # [ 3.544118] systemd[1]: Stopped target Timer Units. alice # [ 3.548107] systemd[1]: dbus.socket: Deactivated successfully. alice # [ 3.549930] systemd[1]: Closed D-Bus System Message Bus Socket. alice # [ 3.551489] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. alice # [ 3.553387] systemd[1]: Stopped Find NixOS closure. alice # [ 3.557923] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.558579] systemd[1]: systemd-sysctl.service: Deactivated successfully. alice # [ 3.559926] systemd[1]: Stopped Apply Kernel Variables. alice # [ 3.562265] systemd[1]: systemd-modules-load.service: Deactivated successfully. alice # [ 3.563478] systemd[1]: Stopped Load Kernel Modules. alice # [ 3.569562] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 3.572153] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. alice # [ 3.573086] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. alice # [ 3.574951] systemd[1]: Stopped Create System Files and Directories. alice # [ 3.576663] systemd[1]: Stopped target Local File Systems. alice # [ 3.579684] systemd[1]: Stopped target Preparation for Local File Systems. alice # [ 3.580530] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 3.581348] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 3.582148] systemd[1]: Stopping Rule-based Manager for Device Events and Files... alice # [ 3.582951] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.583723] systemd[1]: Stopped Virtual Console Setup. alice # [ 3.584377] systemd[1]: initrd-cleanup.service: Deactivated successfully. alice # [ 3.585086] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. alice # [ 3.585794] systemd[1]: systemd-udevd.service: Deactivated successfully. alice # [ 3.586491] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 3.587247] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.587995] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 3.596089] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 3.596832] systemd[1]: Closed udev Control Socket. alice # [ 3.597424] systemd[1]: Starting Cleanup udev Database... alice # [ 3.598113] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. alice # [ 3.598811] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 3.599378] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. alice # [ 3.604194] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. alice # [ 3.604832] systemd[1]: kmod-static-nodes.service: Deactivated successfully. alice # [ 3.605444] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 3.615355] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. alice # [ 3.616804] systemd[1]: Finished Cleanup udev Database. alice # [ 3.617294] systemd[1]: Reached target Switch Root. alice # [ 3.617757] systemd[1]: Starting NixOS Activation... alice # [ 3.685313] initrd-nixos-activation-start[174]: booting system configuration /nix/store/224zj3jyaabv4hz182jfl10dhc1dfxmj-nixos-system-alice-test alice # [ 3.711732] initrd-nixos-activation-start[174]: running activation script... alice # [ 3.914387] initrd-nixos-activation-start[197]: setting up /etc... alice # [ 3.997487] initrd-nixos-activation-start[197]: setting up age secrets... alice # [ 4.055798] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. alice # [ 4.057651] systemd[1]: Finished NixOS Activation. alice # [ 4.058398] systemd[1]: Starting Switch Root... alice # [ 4.071811] systemd[1]: Switching root. alice # [ 4.240185] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). bob # [ 4.303939] systemd[1]: Mounting /sysroot/nix/store... clare # [ 4.332710] systemd[1]: Mounting /sysroot/nix/store... bob # [ 4.353517] systemd[1]: Mounted /sysroot/nix/store. bob # [ 4.356177] systemd[1]: Reached target Initrd File Systems. bob # [ 4.359765] systemd[1]: Starting Find NixOS closure... bob # [ 4.368246] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... clare # [ 4.381136] systemd[1]: Mounted /sysroot/nix/store. clare # [ 4.384155] systemd[1]: Reached target Initrd File Systems. clare # [ 4.387542] systemd[1]: Starting Find NixOS closure... clare # [ 4.396257] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 4.404411] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 4.408228] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob # [ 4.423985] systemd[1]: Finished Find NixOS closure. bob # [ 4.425807] systemd[1]: Reached target Initrd Default Target. bob # [ 4.428683] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... clare # [ 4.438392] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. clare # [ 4.440233] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. clare # [ 4.451381] systemd[1]: Finished Find NixOS closure. clare # [ 4.453522] systemd[1]: Reached target Initrd Default Target. clare # [ 4.456258] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... bob # [ 4.456697] systemd[1]: Stopped target Initrd Default Target. bob # [ 4.460100] systemd[1]: Stopped target Basic System. bob # [ 4.460935] systemd[1]: Stopped target Initrd Root Device. bob # [ 4.462612] systemd[1]: Stopped target Path Units. bob # [ 4.464142] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. bob # [ 4.468140] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. bob # [ 4.469174] systemd[1]: Stopped target Slice Units. bob # [ 4.469889] systemd[1]: Stopped target Socket Units. bob # [ 4.470598] systemd[1]: Stopped target System Initialization. bob # [ 4.471385] systemd[1]: Stopped target Swaps. bob # [ 4.471972] systemd[1]: Stopped target Timer Units. bob # [ 4.477844] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 4.480124] systemd[1]: Closed D-Bus System Message Bus Socket. bob # [ 4.482937] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 4.485594] systemd[1]: Stopped Find NixOS closure. bob # [ 4.486521] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 4.488398] systemd[1]: Stopped target Initrd Default Target. bob # [ 4.487647] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 4.488584] systemd[1]: Stopped Apply Kernel Variables. bob # [ 4.489196] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 4.490191] systemd[1]: Stopped Load Kernel Modules. bob # [ 4.490768] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. clare # [ 4.492337] systemd[1]: Stopped target Basic System. bob # [ 4.491600] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 4.493113] systemd[1]: Stopped target Initrd Root Device. clare # [ 4.493891] systemd[1]: Stopped target Path Units. clare # [ 4.495104] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. clare # [ 4.497646] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. clare # [ 4.500123] systemd[1]: Stopped target Slice Units. bob # [ 4.499646] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. clare # [ 4.501718] systemd[1]: Stopped target Socket Units. clare # [ 4.504108] systemd[1]: Stopped target System Initialization. clare # [ 4.504894] systemd[1]: Stopped target Swaps. bob # [ 4.504271] systemd[1]: Stopped Create System Files and Directories. bob # [ 4.505017] systemd[1]: Stopped target Local File Systems. clare # [ 4.506236] systemd[1]: Stopped target Timer Units. bob # [ 4.505647] systemd[1]: Stopped target Preparation for Local File Systems. clare # [ 4.508125] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 4.507747] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. bob # [ 4.508771] systemd[1]: Stopped Coldplug All udev Devices. clare # [ 4.509633] systemd[1]: Closed D-Bus System Message Bus Socket. bob # [ 4.510266] systemd[1]: Stopping Rule-based Manager for Device Events and Files... clare # [ 4.511835] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 4.511351] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 4.512119] systemd[1]: Stopped Virtual Console Setup. bob # [ 4.512619] systemd[1]: initrd-cleanup.service: Deactivated successfully. bob # [ 4.513229] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. clare # [ 4.514537] systemd[1]: Stopped Find NixOS closure. bob # [ 4.513879] systemd[1]: systemd-udevd.service: Deactivated successfully. bob # [ 4.514495] systemd[1]: Stopped Rule-based Manager for Device Events and Files. bob # [ 4.515157] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. bob # [ 4.515810] systemd[1]: Closed udev Control Socket. clare # [ 4.517158] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 4.519048] systemd[1]: Starting Cleanup udev Database... clare # [ 4.519667] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 4.520179] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 4.524173] systemd[1]: Stopped Create Static Device Nodes in /dev. bob # [ 4.524756] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. bob # [ 4.525465] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. clare # [ 4.526797] systemd[1]: Stopped Apply Kernel Variables. bob # [ 4.526095] systemd[1]: kmod-static-nodes.service: Deactivated successfully. bob # [ 4.526693] systemd[1]: Stopped Create List of Static Device Nodes. clare # [ 4.527422] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 4.527226] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 4.527855] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 4.531787] systemd[1]: Stopped Load Kernel Modules. clare # [ 4.532479] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. clare # [ 4.535024] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 4.538088] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. clare # [ 4.539626] systemd[1]: Stopped Create System Files and Directories. bob # [ 4.538958] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 4.539663] systemd[1]: Finished Cleanup udev Database. clare # [ 4.541438] systemd[1]: Stopped target Local File Systems. clare # [ 4.542783] systemd[1]: Stopped target Preparation for Local File Systems. clare # [ 4.544146] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. bob # [ 4.543788] systemd[1]: Reached target Switch Root. bob # [ 4.544981] systemd[1]: Starting NixOS Activation... clare # [ 4.547340] systemd[1]: Stopped Coldplug All udev Devices. clare # [ 4.547887] systemd[1]: Stopping Rule-based Manager for Device Events and Files... clare # [ 4.549078] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 4.549734] systemd[1]: Stopped Virtual Console Setup. clare # [ 4.550230] systemd[1]: initrd-cleanup.service: Deactivated successfully. clare # [ 4.551024] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. clare # [ 4.551625] systemd[1]: systemd-udevd.service: Deactivated successfully. clare # [ 4.552299] systemd[1]: Stopped Rule-based Manager for Device Events and Files. clare # [ 4.552932] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 4.553571] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 4.554103] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. clare # [ 4.554776] systemd[1]: Closed udev Control Socket. clare # [ 4.555284] systemd[1]: Starting Cleanup udev Database... clare # [ 4.555789] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. clare # [ 4.559292] systemd[1]: Stopped Create Static Device Nodes in /dev. clare # [ 4.560359] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. clare # [ 4.564193] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. clare # [ 4.564953] systemd[1]: kmod-static-nodes.service: Deactivated successfully. clare # [ 4.565590] systemd[1]: Stopped Create List of Static Device Nodes. clare # [ 4.577010] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. clare # [ 4.577735] systemd[1]: Finished Cleanup udev Database. clare # [ 4.580739] systemd[1]: Reached target Switch Root. clare # [ 4.581248] systemd[1]: Starting NixOS Activation... bob # [ 4.614860] initrd-nixos-activation-start[174]: booting system configuration /nix/store/xnn913dv5vbwaw6kjszszkc89wy4ryrr-nixos-system-bob-test bob # [ 4.641204] initrd-nixos-activation-start[174]: running activation script... clare # [ 4.652335] initrd-nixos-activation-start[173]: booting system configuration /nix/store/8bymk31vx2mmv1ipicyvn9gg03cx5h69-nixos-system-clare-test clare # [ 4.679670] initrd-nixos-activation-start[173]: running activation script... alice # [ 4.764552] 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.764644] systemd[1]: Detected virtualization qemu. alice # [ 4.764715] systemd[1]: Detected architecture arm64. alice # [ 4.764836] systemd[1]: Detected first boot. alice # [ 4.776556] systemd[1]: Initializing machine ID from random generator. bob # [ 4.856292] initrd-nixos-activation-start[197]: setting up /etc... clare # [ 4.915347] initrd-nixos-activation-start[196]: setting up /etc... bob # [ 4.958553] initrd-nixos-activation-start[197]: setting up age secrets... alice # [ 4.995156] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 5.011583] initrd-nixos-activation-start[196]: setting up age secrets... bob # [ 5.046022] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 5.048164] systemd[1]: Finished NixOS Activation. bob # [ 5.049086] systemd[1]: Starting Switch Root... bob # [ 5.070129] systemd[1]: Switching root. clare # [ 5.072980] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. clare # [ 5.075378] systemd[1]: Finished NixOS Activation. clare # [ 5.076521] systemd[1]: Starting Switch Root... clare # [ 5.098235] systemd[1]: Switching root. alice # [ 5.176181] systemd[1]: Applying preset policy. bob # [ 5.248601] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). clare # [ 5.272815] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). alice # [ 5.381730] systemd[1]: Populated /etc with preset unit settings. alice # [ 5.616753] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 5.617761] systemd[1]: Stopped initrd-switch-root.service. alice # [ 5.619466] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. alice # [ 5.623151] systemd[1]: Created slice Slice /system/getty. alice # [ 5.625220] systemd[1]: Created slice User and Session Slice. alice # [ 5.625844] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 5.626467] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 5.627075] systemd[1]: Expecting device /dev/hvc0... alice # [ 5.627666] systemd[1]: Expecting device /dev/ttyAMA0... alice # [ 5.631293] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 5.632968] systemd[1]: Stopped target initrd-fs.target. alice # [ 5.633486] systemd[1]: Stopped target initrd-root-fs.target. alice # [ 5.634092] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 5.634696] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 5.635301] systemd[1]: Reached target Path Units. alice # [ 5.638728] systemd[1]: Reached target Remote File Systems. alice # [ 5.639220] systemd[1]: Reached target Slice Units. alice # [ 5.641166] systemd[1]: Reached target Swaps. alice # [ 5.643464] systemd[1]: Listening on Query the User Interactively for a Password. alice # [ 5.646871] systemd[1]: Listening on Process Core Dump Socket. alice # [ 5.649402] systemd[1]: Listening on Credential Encryption/Decryption. alice # [ 5.651488] systemd[1]: Listening on Factory Reset Management. alice # [ 5.653057] systemd[1]: Listening on Hostname Service Socket. alice # [ 5.656746] systemd[1]: Starting Journal Log Access Socket... alice # [ 5.658333] systemd[1]: Listening on Journal Audit Socket. alice # [ 5.661946] systemd[1]: Listening on Console Output Muting Service Socket. alice # [ 5.662700] systemd[1]: Listening on Network Management Metrics Varlink Socket. alice # [ 5.663497] systemd[1]: Listening on Network Management Varlink Socket. alice # [ 5.666522] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 5.668512] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. alice # [ 5.669518] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 5.669983] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki alice # [ 5.676293] systemd[1]: Listening on Disk Repartitioning Service Socket. alice # [ 5.676881] systemd[1]: Listening on Resolve Monitor Varlink Socket. alice # [ 5.677459] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 5.678063] systemd[1]: Listening on udev Control Socket. alice # [ 5.678668] systemd[1]: Listening on udev Varlink Socket. alice # [ 5.683575] systemd[1]: Mounting Huge Pages File System... alice # [ 5.686579] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 5.696162] systemd[1]: Mounting Kernel Debug File System... alice # [ 5.703543] systemd[1]: Mounting Kernel Trace File System... alice # [ 5.716468] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 5.720311] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 5.731355] systemd[1]: Mounting Kernel Configuration File System... alice # [ 5.733998] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 5.740277] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore alice # [ 5.740814] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 5.763084] systemd[1]: Mounting FUSE Control File System... alice # [ 5.763564] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 alice # [ 5.789853] systemd[1]: Starting Journal Service... alice # [ 5.808671] systemd[1]: Starting Load Kernel Modules... alice # [ 5.818300] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 5.799902] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) clare # [ 5.800062] systemd[1]: Detected virtualization qemu. clare # [ 5.800208] systemd[1]: Detected architecture arm64. clare # [ 5.812451] systemd[1]: Detected first boot. clare # [ 5.818026] systemd[1]: Initializing machine ID from random generator. alice # [ 5.843987] systemd[1]: Starting Remount Root and Kernel File Systems... alice # [ 5.844466] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 5.856638] systemd[1]: Starting Coldplug All udev Devices... alice # [ 5.859166] systemd[1]: Listening on Journal Log Access Socket. alice # [ 5.859593] systemd[1]: Mounted Huge Pages File System. bob # [ 5.842913] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 5.843197] systemd[1]: Detected virtualization qemu. bob # [ 5.843928] systemd[1]: Detected architecture arm64. bob # [ 5.844797] systemd[1]: Detected first boot. alice # [ 5.876151] systemd[1]: Mounted POSIX Message Queue File System. alice # [ 5.876810] systemd[1]: Mounted Kernel Debug File System. bob # [ 5.863906] systemd[1]: Initializing machine ID from random generator. alice # [ 5.877208] systemd[1]: Mounted Kernel Trace File System. alice # [ 5.881970] systemd-journald[282]: Collecting audit messages is enabled. alice # [ 5.879232] systemd[1]: Queued start job for default target Multi-User System. alice # [ 5.889197] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 5.889833] systemd[1]: Started Journal Service. alice # [ 5.889387] systemd[1]: systemd-journald.service: Deactivated successfully. alice # [ 5.901592] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 5.926673] systemd-modules-load[283]: Module 'atkbd' is built in alice # [ 5.931052] systemd-modules-load[283]: Module 'loop' is built in alice # [ 5.933286] systemd-oomd[285]: No swap; memory pressure usage will be degraded alice # [ 5.940431] systemd[1]: Finished Load Kernel Modules. alice # [ 5.944136] systemd[1]: Starting Firewall... alice # [ 5.958656] systemd[1]: Starting Apply Kernel Variables... alice # [ 5.959252] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. alice # [ 5.973934] systemd[1]: Mounted Kernel Configuration File System. alice # [ 5.980650] EXT4-fs (vda): re-mounted 5ea69935-7a05-4fd9-9dea-02574aa75611. clare # [ 5.976339] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 5.992458] systemd[1]: Finished Remount Root and Kernel File Systems. alice # [ 5.993117] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 5.998570] systemd[1]: Starting Flush Journal to Persistent Storage... alice # [ 5.999223] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 5.997464] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 6.005841] systemd[1]: Starting Load/Save OS Random Seed... alice # [ 6.006418] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 6.007173] systemd[1]: Mounted FUSE Control File System. alice # [ 6.071630] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 6.082293] systemd-journald[282]: Received client request to flush runtime journal. bob # [ 6.081013] systemd[1]: Applying preset policy. clare # [ 6.124818] systemd[1]: Applying preset policy. alice # [ 6.160476] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 6.164549] systemd[1]: Finished Load/Save OS Random Seed. alice # [ 6.165131] systemd[1]: Reached target First Boot Complete. alice # [ 6.165672] systemd[1]: Finished Apply Kernel Variables. alice # [ 6.166182] systemd[1]: Starting Network Name Resolution... alice # [ 6.172594] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 6.248714] systemd[1]: Populated /etc with preset unit settings. clare # [ 6.284777] systemd[1]: Populated /etc with preset unit settings. alice # [ 6.320817] systemd-resolved[312]: Positive Trust Anchors: alice # [ 6.321427] systemd-resolved[312]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d alice # [ 6.322331] systemd-resolved[312]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 alice # [ 6.323213] systemd-resolved[312]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 6.366097] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 6.367197] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 6.367861] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 6.469820] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 6.471732] systemd[1]: Stopped initrd-switch-root.service. bob # [ 6.473535] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. alice # [ 6.482408] systemd[1]: Finished Coldplug All udev Devices. bob # [ 6.476200] systemd[1]: Created slice Slice /system/getty. bob # [ 6.477185] systemd[1]: Created slice User and Session Slice. bob # [ 6.477761] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 6.480387] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 6.480909] systemd[1]: Expecting device /dev/hvc0... bob # [ 6.481463] systemd[1]: Expecting device /dev/ttyAMA0... bob # [ 6.482012] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 6.491113] systemd-resolved[312]: Using system hostname 'alice'. bob # [ 6.484998] systemd[1]: Stopped target initrd-fs.target. bob # [ 6.485474] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 6.486008] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 6.494842] systemd[1]: Started Network Name Resolution. bob # [ 6.488434] systemd[1]: Reached target Virtual Machines and Containers. bob # [ 6.488923] systemd[1]: Reached target Path Units. bob # [ 6.489456] systemd[1]: Reached target Remote File Systems. bob # [ 6.489997] systemd[1]: Reached target Slice Units. bob # [ 6.492780] systemd[1]: Reached target Swaps. bob # [ 6.495207] systemd[1]: Listening on Query the User Interactively for a Password. bob # [ 6.497287] systemd[1]: Listening on Process Core Dump Socket. bob # [ 6.499958] systemd[1]: Listening on Credential Encryption/Decryption. bob # [ 6.501700] systemd[1]: Listening on Factory Reset Management. alice # [ 6.508917] systemd-udevd[320]: Using default interface naming scheme 'v261'. bob # [ 6.502264] systemd[1]: Listening on Hostname Service Socket. clare # [ 6.505491] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 6.506258] systemd[1]: Starting Journal Log Access Socket... clare # [ 6.506135] systemd[1]: Stopped initrd-switch-root.service. clare # [ 6.509700] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 6.507786] systemd[1]: Listening on Journal Audit Socket. bob # [ 6.509940] systemd[1]: Listening on Console Output Muting Service Socket. clare # [ 6.512121] systemd[1]: Created slice Slice /system/getty. clare # [ 6.513128] systemd[1]: Created slice User and Session Slice. bob # [ 6.511567] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 6.513693] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 6.512315] systemd[1]: Listening on Network Management Varlink Socket. clare # [ 6.514281] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 6.512956] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 6.513657] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 6.514195] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os clare # [ 6.517461] systemd[1]: Expecting device /dev/hvc0... clare # [ 6.517927] systemd[1]: Expecting device /dev/ttyAMA0... bob # [ 6.518227] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki clare # [ 6.519777] systemd[1]: Reached target Local Encrypted Volumes. clare # [ 6.520256] systemd[1]: Stopped target initrd-fs.target. clare # [ 6.520805] systemd[1]: Stopped target initrd-root-fs.target. clare # [ 6.521367] systemd[1]: Stopped target initrd-switch-root.target. clare # [ 6.521924] systemd[1]: Reached target Virtual Machines and Containers. clare # [ 6.525243] systemd[1]: Reached target Path Units. clare # [ 6.525721] systemd[1]: Reached target Remote File Systems. bob # [ 6.523678] systemd[1]: Listening on Disk Repartitioning Service Socket. clare # [ 6.526257] systemd[1]: Reached target Slice Units. bob # [ 6.524207] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 6.524741] systemd[1]: Listening on Resolve Service Varlink Socket. bob # [ 6.525290] systemd[1]: Listening on udev Control Socket. bob # [ 6.525835] systemd[1]: Listening on udev Varlink Socket. clare # [ 6.528513] systemd[1]: Reached target Swaps. clare # [ 6.530960] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 6.533084] systemd[1]: Listening on Process Core Dump Socket. bob # [ 6.530609] systemd[1]: Mounting Huge Pages File System... bob # [ 6.532629] systemd[1]: Mounting POSIX Message Queue File System... clare # [ 6.535778] systemd[1]: Listening on Credential Encryption/Decryption. clare # [ 6.537611] systemd[1]: Listening on Factory Reset Management. clare # [ 6.538144] systemd[1]: Listening on Hostname Service Socket. clare # [ 6.542173] systemd[1]: Starting Journal Log Access Socket... clare # [ 6.544462] systemd[1]: Listening on Journal Audit Socket. clare # [ 6.546816] systemd[1]: Listening on Console Output Muting Service Socket. clare # [ 6.547418] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 6.547916] systemd[1]: Listening on Network Management Varlink Socket. clare # [ 6.548341] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 6.546956] systemd[1]: Mounting Kernel Debug File System... clare # [ 6.548810] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 6.548927] systemd[1]: Mounting Kernel Trace File System... clare # [ 6.549111] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os clare # [ 6.549394] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki alice # [ 6.558567] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 6.558559] systemd[1]: Listening on Disk Repartitioning Service Socket. clare # [ 6.559086] systemd[1]: Listening on Resolve Monitor Varlink Socket. clare # [ 6.559601] systemd[1]: Listening on Resolve Service Varlink Socket. clare # [ 6.560125] systemd[1]: Listening on udev Control Socket. bob # [ 6.561504] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 6.560681] systemd[1]: Listening on udev Varlink Socket. bob # [ 6.561915] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 6.565158] systemd[1]: Mounting Huge Pages File System... clare # [ 6.568227] systemd[1]: Mounting POSIX Message Queue File System... bob # [ 6.572608] systemd[1]: Mounting Kernel Configuration File System... bob # [ 6.572970] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm bob # [ 6.573247] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore bob # [ 6.573507] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 6.582238] systemd[1]: Mounting Kernel Debug File System... clare # [ 6.587718] systemd[1]: Mounting Kernel Trace File System... clare # [ 6.592049] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 6.591098] systemd[1]: Mounting FUSE Control File System... bob # [ 6.591814] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 clare # [ 6.595069] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 6.606754] systemd[1]: Mounting Kernel Configuration File System... clare # [ 6.607182] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 6.618196] systemd[1]: Mounting /run/wrappers... clare # [ 6.611981] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 6.612604] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 6.624665] systemd[1]: Mounting FUSE Control File System... clare # [ 6.625128] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 bob # [ 6.622621] systemd[1]: Starting Journal Service... bob # [ 6.634606] systemd[1]: Starting Load Kernel Modules... bob # [ 6.645696] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 6.647232] systemd[1]: Starting Journal Service... bob # [ 6.656180] systemd[1]: Starting Remount Root and Kernel File Systems... bob # [ 6.656561] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 6.662172] systemd[1]: Starting Load Kernel Modules... alice # [ 6.683862] systemd[1]: Mounted /run/wrappers. alice # [ 6.684530] systemd[1]: Reached target Local File Systems. bob # [ 6.674602] systemd[1]: Starting Coldplug All udev Devices... bob # [ 6.676729] systemd[1]: Listening on Journal Log Access Socket. bob # [ 6.677130] systemd[1]: Mounted Huge Pages File System. bob # [ 6.677487] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 6.677821] systemd[1]: Mounted Kernel Debug File System. bob # [ 6.678152] systemd[1]: Mounted Kernel Trace File System. alice # [ 6.688883] systemd[1]: Listening on Boot Loader Control Service Socket. alice # [ 6.694028] systemd[1]: Starting register-nix-paths.service... clare # [ 6.689188] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... alice # [ 6.698248] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 6.699427] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. clare # [ 6.698769] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 6.699162] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 6.707841] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 6.703561] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 6.716182] systemd[1]: Starting Create System Files and Directories... bob # [ 6.704151] systemd[1]: Mounted Kernel Configuration File System. bob # [ 6.707721] systemd-journald[290]: Collecting audit messages is enabled. alice # [ 6.716813] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 6.714055] systemd[1]: Starting Coldplug All udev Devices... bob # [ 6.718272] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 6.720188] systemd[1]: Listening on Journal Log Access Socket. clare # [ 6.720679] systemd[1]: Mounted Huge Pages File System. clare # [ 6.721139] systemd[1]: Mounted Kernel Debug File System. clare # [ 6.729467] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 6.730008] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 6.723647] systemd[1]: Queued start job for default target Multi-User System. bob # [ 6.724780] systemd[1]: systemd-journald.service: Deactivated successfully. bob # [ 6.725662] systemd-modules-load[291]: Module 'atkbd' is built in bob # [ 6.726349] systemd-modules-load[291]: Module 'loop' is built in clare # [ 6.733886] systemd-journald[281]: Collecting audit messages is enabled. bob # [ 6.734075] systemd[1]: Started Journal Service. alice # [ 6.744685] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 6.744824] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 6.741158] systemd[1]: Finished Load Kernel Modules. clare # [ 6.746712] systemd[1]: Queued start job for default target Multi-User System. clare # [ 6.748481] systemd[1]: systemd-journald.service: Deactivated successfully. clare # [ 6.749369] systemd-modules-load[282]: Module 'atkbd' is built in clare # [ 6.750016] systemd-modules-load[282]: Module 'loop' is built in clare # [ 6.758854] systemd[1]: Finished Load Kernel Modules. clare # [ 6.759363] systemd[1]: Started Journal Service. bob # [ 6.759382] systemd[1]: Starting Firewall... bob # [ 6.762472] systemd[1]: Starting Apply Kernel Variables... clare # [ 6.764259] systemd[1]: Mounted Kernel Trace File System. clare # [ 6.786659] systemd[1]: Starting Firewall... bob # [ 6.797038] systemd[1]: Mounted FUSE Control File System. clare # [ 6.801945] systemd[1]: Starting Apply Kernel Variables... clare # [ 6.821103] EXT4-fs (vda): re-mounted 3317e99a-9d03-426d-9dd0-980aa506fcea. clare # [ 6.819967] systemd[1]: Mounted Kernel Configuration File System. bob # [ 6.824358] systemd-oomd[292]: No swap; memory pressure usage will be degraded bob # [ 6.831247] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 6.834523] EXT4-fs (vda): re-mounted 9a85103c-e61a-45f1-815c-081cff456cc0. clare # [ 6.834329] systemd[1]: Finished Remount Root and Kernel File Systems. clare # [ 6.834983] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 6.851706] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. bob # [ 6.840558] systemd[1]: Finished Remount Root and Kernel File Systems. clare # [ 6.842262] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 6.841468] systemd[1]: Listening on Disk Image Download Service Socket. clare # [ 6.842926] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore alice # [ 6.858282] systemd[1]: Finished Save Transient machine-id to Disk. clare # [ 6.852148] systemd[1]: Starting Load/Save OS Random Seed... clare # [ 6.852771] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 6.854335] systemd[1]: Starting Flush Journal to Persistent Storage... clare # [ 6.856189] systemd[1]: Mounted FUSE Control File System. bob # [ 6.854967] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore clare # [ 6.860405] systemd-oomd[283]: No swap; memory pressure usage will be degraded bob # [ 6.865844] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 6.866405] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 6.894311] systemd[1]: Finished Create System Files and Directories. clare # [ 6.884452] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. clare # [ 6.916302] systemd-journald[281]: Received client request to flush runtime journal. bob # [ 6.914847] systemd[1]: Finished Apply Kernel Variables. alice # [ 6.931221] systemd[1]: Starting Rebuild Journal Catalog... bob # [ 6.929017] systemd-journald[290]: Received client request to flush runtime journal. alice # [ 6.938460] systemd[1]: Starting Record System Boot/Shutdown in UTMP... clare # [ 6.964459] systemd[1]: Finished Load/Save OS Random Seed. clare # [ 6.965066] systemd[1]: Reached target First Boot Complete. clare # [ 6.967499] systemd[1]: Finished Flush Journal to Persistent Storage. clare # [ 6.981860] systemd[1]: Finished Apply Kernel Variables. clare # [ 6.984809] systemd[1]: Starting Network Name Resolution... bob # [ 6.984580] systemd[1]: Starting Network Name Resolution... bob # [ 6.987478] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 6.990362] systemd[1]: Finished Load/Save OS Random Seed. bob # [ 6.990900] systemd[1]: Reached target First Boot Complete. bob # [ 6.991395] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 6.997104] systemd[1]: Finished Flush Journal to Persistent Storage. clare # [ 6.999512] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 7.004961] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 7.048765] systemd[1]: Finished Record System Boot/Shutdown in UTMP. alice # [ 7.066842] systemd[1]: Finished Rebuild Journal Catalog. alice # [ 7.074911] systemd[1]: Starting Update is Completed... alice # [ 7.093325] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. bob # [ 7.102581] systemd-resolved[316]: Positive Trust Anchors: bob # [ 7.103166] systemd-resolved[316]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d bob # [ 7.104389] systemd-resolved[316]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 bob # [ 7.105246] systemd-resolved[316]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 7.122181] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. clare # [ 7.144790] systemd-resolved[312]: Positive Trust Anchors: clare # [ 7.145396] systemd-resolved[312]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d clare # [ 7.146282] systemd-resolved[312]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 clare # [ 7.147175] systemd-resolved[312]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 7.185788] systemd[1]: Finished Update is Completed. bob # [ 7.231957] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 7.232642] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 7.240752] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 7.271461] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 7.272145] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 7.280192] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 7.295145] systemd-resolved[316]: Using system hostname 'bob'. bob # [ 7.296256] systemd[1]: Finished Coldplug All udev Devices. bob # [ 7.299216] systemd[1]: Started Network Name Resolution. clare # [ 7.325436] systemd[1]: Finished Coldplug All udev Devices. clare # [ 7.326783] systemd-resolved[312]: Using system hostname 'clare'. clare # [ 7.331096] systemd[1]: Started Network Name Resolution. bob # [ 7.335164] systemd-udevd[329]: Using default interface naming scheme 'v261'. clare # [ 7.365339] systemd-udevd[323]: Using default interface naming scheme 'v261'. alice # [ 7.387179] (udev-worker)[348]: Network interface NamePolicy= disabled on kernel command line. alice # [ 7.391887] (udev-worker)[346]: Network interface NamePolicy= disabled on kernel command line. bob # [ 7.388128] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 7.416140] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 7.467550] systemd[1]: Mounting /run/wrappers... clare # [ 7.504823] systemd[1]: Mounting /run/wrappers... bob # [ 7.512160] systemd[1]: Mounted /run/wrappers. bob # [ 7.514438] systemd[1]: Reached target Local File Systems. bob # [ 7.516681] systemd[1]: Listening on Boot Loader Control Service Socket. bob # [ 7.521281] systemd[1]: Starting register-nix-paths.service... bob # [ 7.525892] systemd[1]: Starting Create SUID/SGID Wrappers... bob # [ 7.527082] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. bob # [ 7.530349] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 7.541471] systemd[1]: Starting Create System Files and Directories... bob # [ 7.542091] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 7.542846] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 7.551643] systemd[1]: Mounted /run/wrappers. clare # [ 7.552830] systemd[1]: Reached target Local File Systems. clare # [ 7.556537] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 7.561241] systemd[1]: Starting register-nix-paths.service... clare # [ 7.565606] systemd[1]: Starting Create SUID/SGID Wrappers... clare # [ 7.566193] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. clare # [ 7.569698] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 7.569094] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 7.585140] systemd[1]: Finished register-nix-paths.service. clare # [ 7.579701] systemd[1]: Starting Create System Files and Directories... clare # [ 7.581069] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 7.592518] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. alice # [ 7.594003] systemd[1]: Finished Create SUID/SGID Wrappers. alice # [ 7.596347] systemd[1]: Reached target System Initialization. alice # [ 7.599594] systemd[1]: Started Discard unused filesystem blocks once a week. alice # [ 7.603775] systemd[1]: Started Daily Cleanup of Temporary Directories.[ 7.608230] mousedev: PS/2 mouse device common for all mice alice # alice # [ 7.606187] systemd[1]: Reached target Timer Units. alice # [ 7.606695] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 7.607275] systemd[1]: Listening on Nix Daemon Socket. alice # [ 7.607748] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 7.616269] systemd[1]: Reached target Socket Units. alice # [ 7.616777] systemd[1]: Reached target Basic System. alice # [ 7.617256] systemd[1]: Started backdoor.service. alice # [ 7.617716] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 7.618339] systemd[1]: Starting Name Service Cache Daemon (nsncd)... alice # [ 7.625859] systemd[1]: Starting Post-Boot Actions... bob # [ 7.625107] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. bob # [ 7.629086] systemd[1]: Finished Save Transient machine-id to Disk. alice # [ 7.659138] systemd[1]: Started Reset console on configuration changes. alice # [ 7.689762] systemd[1]: Starting D-Bus System Message Bus... clare # [ 7.683550] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. clare # [ 7.691757] systemd[1]: Finished Save Transient machine-id to Disk. bob # [ 7.708818] systemd[1]: Finished Create System Files and Directories. bob # [ 7.718630] systemd[1]: Starting Rebuild Journal Catalog... bob # [ 7.724632] systemd[1]: Starting Record System Boot/Shutdown in UTMP... alice # [ 7.746271] systemd[1]: Finished Post-Boot Actions. alice # connecting to host... clare # [ 7.752578] systemd[1]: Finished Create System Files and Directories. clare # [ 7.760511] systemd[1]: Starting Rebuild Journal Catalog... clare # [ 7.767266] systemd[1]: Starting Record System Boot/Shutdown in UTMP... alice # [ 7.779454] systemd[1]: Started Name Service Cache Daemon (nsncd). alice # [ 7.783081] systemd[1]: Reached target Host and Network Name Lookups. alice # [ 7.783680] systemd[1]: Reached target User and Group Name Lookups. alice # [ 7.788779] nsncd[469]: Oct 04 15:27:19.625 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" alice # [ 7.798890] systemd[1]: Starting User Login Management... alice # [ 7.817347] systemd[1]: Finished Import lastlog data into lastlog2 database. bob # [ 7.824174] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 7.845147] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. bob # [ 7.858158] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. clare # [ 7.865641] systemd[1]: Finished Record System Boot/Shutdown in UTMP. bob # [ 7.864912] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 7.871576] systemd[1]: Starting Update is Completed... clare # [ 7.891016] systemd[1]: Finished Rebuild Journal Catalog. alice # [ 7.904390] dbus-broker-launch[475]: Looking up NSS user entry for 'systemd-timesync'... clare # [ 7.896197] systemd[1]: Starting Update is Completed... alice # [ 7.915046] systemd-logind[493]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) alice # [ 7.918515] systemd-logind[493]: Watching system buttons on /dev/input/event0 (gpio-keys) alice # [ 7.920833] dbus-broker-launch[475]: NSS returned no entry for 'systemd-timesync' bob # [ 7.909636] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. alice # [ 7.921437] dbus-broker-launch[475]: Invalid user-name in /nix/store/b1fb9lxs8ykr9ig9ywgxry5j83pbx1i1-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 7.922764] systemd-logind[493]: New seat seat0. alice # [ 7.930416] systemd[1]: Started User Login Management. alice # [ 7.930934] systemd[1]: Starting linger-users.service... clare # [ 7.943316] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. alice # [ 7.957724] systemd[1]: Started D-Bus System Message Bus. bob # [ 7.962767] systemd[1]: Finished Update is Completed. clare # [ 7.981092] systemd[1]: Finished Update is Completed. alice # [ 7.994928] dbus-broker-launch[475]: Ready alice # [ 8.004286] systemd[1]: linger-users.service: Deactivated successfully. alice # [ 8.006536] systemd[1]: Finished linger-users.service. bob # [ 8.109303] (udev-worker)[355]: Network interface NamePolicy= disabled on kernel command line. alice # [ 8.124761] systemd[1]: Finished Firewall. bob # [ 8.113208] (udev-worker)[353]: Network interface NamePolicy= disabled on kernel command line. alice # [ 8.126129] systemd[1]: Reached target Preparation for Network. alice # [ 8.129396] systemd[1]: Listening on Network Management Resolve Hook Socket. alice # [ 8.134177] systemd[1]: Starting Network Management... clare # [ 8.141473] (udev-worker)[351]: Network interface NamePolicy= disabled on kernel command line. clare # [ 8.148672] (udev-worker)[343]: Network interface NamePolicy= disabled on kernel command line. alice # [ 8.311681] cfg80211: Loading compiled-in X.509 certificates for regulatory database alice # [ 8.359358] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' alice # [ 8.360400] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' alice # [ 8.362420] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 alice # [ 8.362807] cfg80211: failed to load regulatory.db bob # [ 8.357407] systemd[1]: Finished register-nix-paths.service. bob # [ 8.368264] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. bob # [ 8.370628] systemd[1]: Finished Create SUID/SGID Wrappers. alice # [ 8.384933] systemd-networkd[538]: lo: Link UP alice # [ 8.385420] systemd-networkd[538]: lo: Gained carrier bob # [ 8.374401] systemd[1]: Reached target System Initialization. bob # [ 8.374979] systemd[1]: Started Discard unused filesystem blocks once a week. bob # [ 8.375593] systemd[1]: Started Daily Cleanup of Temporary Directories. bob # [ 8.378176] systemd[1]: Reached target Timer Units. bob # [ 8.378639] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 8.379205] systemd[1]: Listening on Nix Daemon Socket. bob # [ 8.379675] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 8.393842] systemd-networkd[538]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. alice # [ 8.394724] systemd[1]: Started Network Management. bob # [ 8.384795] systemd[1]: Reached target Socket Units. bob # [ 8.385287] systemd[1]: Reached target Basic System. bob # [ 8.385768] systemd[1]: Started backdoor.service. clare # [ 8.393126] mousedev: PS/2 mouse device common for all mice alice # [ 8.401199] systemd[1]: Reached target Network. bob # [ 8.390545] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 8.402519] systemd-networkd[538]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 8.409715] systemd-networkd[538]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 8.410433] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... alice # [ 8.411025] systemd-networkd[538]: eth0: Link UP alice # [ 8.411450] systemd-networkd[538]: eth0: Gained carrier bob # [ 8.399450] systemd[1]: Starting Name Service Cache Daemon (nsncd)... clare # [ 8.408495] systemd[1]: Finished register-nix-paths.service. clare # [ 8.409850] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. alice # [ 8.421353] systemd[1]: Starting Permit User Sessions... alice # [ 8.421896] systemd-networkd[538]: eth1: Link UP alice # [ 8.422304] systemd-networkd[538]: eth1: Gained carrier clare # [ 8.411501] systemd[1]: Finished Create SUID/SGID Wrappers. alice # [ 8.422743] systemd-networkd[538]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 8.412504] systemd[1]: Starting Post-Boot Actions... clare # [ 8.415623] systemd[1]: Reached target System Initialization. clare # [ 8.416511] systemd[1]: Started Discard unused filesystem blocks once a week. clare # [ 8.417144] systemd[1]: Started Daily Cleanup of Temporary Directories. clare # [ 8.417730] systemd[1]: Reached target Timer Units. clare # [ 8.418197] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 8.434203] systemd-networkd[538]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 clare # [ 8.425735] systemd[1]: Listening on harmonia binary cache socket. clare # [ 8.426335] systemd[1]: Listening on Nix Daemon Socket. clare # [ 8.426914] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. clare # [ 8.427633] systemd[1]: Reached target Socket Units. bob # [ 8.429976] systemd[1]: Started Reset console on configuration changes. clare # [ 8.432643] systemd[1]: Reached target Basic System. clare # [ 8.433193] systemd[1]: Started backdoor.service. clare # [ 8.433615] systemd[1]: Starting Import lastlog data into lastlog2 database... clare # [ 8.440169] systemd[1]: Starting Name Service Cache Daemon (nsncd)... clare # [ 8.447561] systemd[1]: Starting Post-Boot Actions... clare # [ 8.455815] systemd[1]: Started Reset console on configuration changes. bob # [ 8.462081] systemd[1]: Starting D-Bus System Message Bus... alice # [ 8.485188] systemd[1]: Finished Permit User Sessions. clare # [ 8.477567] systemd[1]: Starting D-Bus System Message Bus... alice # [ 8.491059] systemd[1]: Started Getty on tty1. bob # [ 8.483818] mousedev: PS/2 mouse device common for all mice alice # [ 8.493464] systemd[1]: Reached target Login Prompts. alice # [ 8.498563] systemd[1]: Reached target Multi-User System. alice # [ 8.502653] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. bob # connecting to host... alice # [ 8.517979] systemd[1]: Startup finished in 708ms (kernel) + 3.627s (initrd) + 4.181s (userspace) = 8.517s. bob # [ 8.533382] systemd[1]: Finished Post-Boot Actions. clare # [ 8.565702] systemd[1]: Finished Post-Boot Actions. bob # [ 8.567653] systemd[1]: Started Name Service Cache Daemon (nsncd). bob # [ 8.568368] systemd[1]: Reached target Host and Network Name Lookups. bob # [ 8.568917] systemd[1]: Reached target User and Group Name Lookups. bob # [ 8.576571] nsncd[479]: Oct 04 15:27:20.398 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # [ 8.579123] systemd[1]: Started Name Service Cache Daemon (nsncd). bob: Guest shell says: b'Spawning backdoor root shell...\n' bob # [ 8.581597] systemd[1]: Starting User Login Management... clare # [ 8.579755] systemd[1]: Reached target Host and Network Name Lookups. clare # [ 8.583781] systemd[1]: Reached target User and Group Name Lookups. clare # [ 8.584607] nsncd[471]: Oct 04 15:27:20.402 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # connecting to host... clare # [ 8.597472] systemd[1]: Starting User Login Management... bob # [ 8.604346] systemd[1]: Finished Import lastlog data into lastlog2 database. clare # [ 8.635404] systemd[1]: Finished Import lastlog data into lastlog2 database. alice # [ 8.677158] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 8.693775] systemd-logind[500]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 8.696511] systemd-logind[500]: Watching system buttons on /dev/input/event0 (gpio-keys) bob: connected to guest root shell bob # [ 8.698264] systemd-logind[500]: New seat seat0. bob: (connecting took 8.97 seconds) bob # [ 8.701960] systemd[1]: Started User Login Management. bob: (finished: waiting for the VM to finish booting, in 8.97 seconds) bob # [ 8.703785] dbus-broker-launch[484]: Looking up NSS user entry for 'systemd-timesync'... clare # [ 8.708453] dbus-broker-launch[475]: Looking up NSS user entry for 'systemd-timesync'... bob # [ 8.709604] systemd[1]: Starting linger-users.service... clare # [ 8.718112] dbus-broker-launch[475]: NSS returned no entry for 'systemd-timesync' clare # [ 8.718853] dbus-broker-launch[475]: Invalid user-name in /nix/store/adj0502s0rji8cqpinaag61h2xhajgmv-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" bob # [ 8.727413] dbus-broker-launch[484]: NSS returned no entry for 'systemd-timesync' bob # [ 8.729420] dbus-broker-launch[484]: Invalid user-name in /nix/store/0avqdcl9fwdl570wq2rf6s7lwamv4l0n-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" clare # [ 8.733063] systemd-logind[495]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) clare # [ 8.735288] systemd-logind[495]: Watching system buttons on /dev/input/event0 (gpio-keys) clare # [ 8.736793] systemd-logind[495]: New seat seat0. clare # [ 8.741068] systemd[1]: Started User Login Management. clare # [ 8.743769] systemd[1]: Starting linger-users.service... bob # [ 8.758221] systemd[1]: Started D-Bus System Message Bus. clare # [ 8.761051] systemd[1]: Started D-Bus System Message Bus. bob # [ 8.780422] systemd[1]: linger-users.service: Deactivated successfully. bob # [ 8.781030] systemd[1]: Finished linger-users.service. clare # [ 8.794911] dbus-broker-launch[475]: Ready bob # [ 8.798392] dbus-broker-launch[484]: Ready clare # [ 8.809975] systemd[1]: linger-users.service: Deactivated successfully. clare # [ 8.811909] systemd[1]: Finished linger-users.service. bob # [ 8.916773] systemd[1]: Finished Firewall. bob # [ 8.918544] systemd[1]: Reached target Preparation for Network. bob # [ 8.922018] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # [ 8.924935] systemd[1]: Starting Network Management... clare # [ 8.931815] systemd[1]: Finished Firewall. clare # [ 8.934516] systemd[1]: Reached target Preparation for Network. clare # [ 8.935058] systemd[1]: Listening on Network Management Resolve Hook Socket. clare # [ 8.939310] systemd[1]: Starting Network Management... alice # [ 8.953351] systemd[1]: Starting Virtual Console Setup... alice # [ 8.975055] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. alice # [ 8.988218] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 8.992150] systemd[1]: Stopped Virtual Console Setup. alice # [ 8.996294] systemd[1]: Starting Virtual Console Setup... alice # [ 9.002199] systemd-logind[493]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) alice # [ 9.099496] systemd-vconsole-setup[558]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 9.093688] cfg80211: Loading compiled-in X.509 certificates for regulatory database alice # [ 9.102613] systemd[1]: Finished Virtual Console Setup. clare # [ 9.123841] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 9.136828] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' bob # [ 9.137341] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 9.140733] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 bob # [ 9.141100] cfg80211: failed to load regulatory.db clare # [ 9.168129] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' clare # [ 9.168653] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' clare # [ 9.170316] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 clare # [ 9.172700] cfg80211: failed to load regulatory.db bob # [ 9.167691] systemd-networkd[557]: lo: Link UP bob # [ 9.169016] systemd-networkd[557]: lo: Gained carrier bob # [ 9.176447] systemd-networkd[557]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. bob # [ 9.177299] systemd[1]: Started Network Management. bob # [ 9.183569] systemd[1]: Reached target Network. bob # [ 9.184068] systemd[1]: Reached target Network is Online. bob # [ 9.187147] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 9.192853] systemd-networkd[541]: lo: Link UP clare # [ 9.194279] systemd-networkd[541]: lo: Gained carrier bob # [ 9.193632] systemd-networkd[557]: eth1: Configuring with /etc/systemd/network/40-eth1.network. bob # [ 9.194376] systemd-networkd[557]: eth0: Link UP bob # [ 9.194781] systemd-networkd[557]: eth0: Gained carrier bob # [ 9.195210] systemd[1]: Starting ncps binary cache proxy service... bob # [ 9.199790] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 9.201659] systemd[1]: Started Network Management. clare # [ 9.202129] systemd[1]: Reached target Network. bob # [ 9.202856] systemd-networkd[557]: eth1: Link UP bob # [ 9.203285] systemd-networkd[557]: eth1: Gained carrier bob # [ 9.203737] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 9.207429] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 9.213302] systemd-networkd[557]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 clare # [ 9.214249] systemd-networkd[541]: eth1: Configuring with /etc/systemd/network/40-eth1.network. clare # [ 9.215002] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 9.215613] systemd[1]: Starting Permit User Sessions... clare # [ 9.225679] systemd-networkd[541]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. clare # [ 9.226548] systemd-networkd[541]: eth1: Link UP clare # [ 9.226959] systemd-networkd[541]: eth1: Gained carrier clare # [ 9.227407] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 9.229920] systemd[1]: Starting Permit User Sessions... clare # [ 9.236100] systemd-networkd[541]: eth0: Link UP clare # [ 9.236538] systemd-networkd[541]: eth0: Gained carrier clare # [ 9.240493] systemd-networkd[541]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 clare # [ 9.287204] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. bob # [ 9.288096] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. bob # [ 9.288756] systemd[1]: Finished Permit User Sessions. clare # [ 9.290302] systemd[1]: Finished Permit User Sessions. clare # [ 9.294800] systemd[1]: Started Getty on tty1. clare # [ 9.295254] systemd[1]: Reached target Login Prompts. clare # [ 9.295696] systemd[1]: Reached target Multi-User System. clare # [ 9.297051] systemd[1]: Startup finished in 724ms (kernel) + 4.633s (initrd) + 3.937s (userspace) = 9.294s. bob # [ 9.299928] systemd[1]: Started Getty on tty1. bob # [ 9.300548] systemd[1]: Reached target Login Prompts. bob # [ 9.341050] ncps-pre-start[564]: /nix/store/z27dji5sjdkkdpzjlm37nq6d5cwbgl8i-ncps-0.9.4/bin/dbmate-ncps up clare # [ 9.433437] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 9.537727] ncps-pre-start[579]: Creating: /var/lib/ncps/db/db.sqlite bob # [ 9.561261] ncps-pre-start[579]: Applying: 20241210054814_create-narinfos-table.sql bob # [ 9.569651] ncps-pre-start[579]: Applied: 20241210054814_create-narinfos-table.sql in 8.36176ms bob # [ 9.570611] ncps-pre-start[579]: Applying: 20241210054829_create-nars-table.sql bob # [ 9.577875] ncps-pre-start[579]: Applied: 20241210054829_create-nars-table.sql in 8.20988ms bob # [ 9.578736] ncps-pre-start[579]: Applying: 20241213014846_add-query-to-nars-table.sql bob # [ 9.586587] ncps-pre-start[579]: Applied: 20241213014846_add-query-to-nars-table.sql in 8.69772ms bob # [ 9.588093] ncps-pre-start[579]: Applying: 20251230224159_add-cascade-to-nars-fk.sql bob # [ 9.604511] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 9.601706] ncps-pre-start[579]: Applied: 20251230224159_add-cascade-to-nars-fk.sql in 15.08968ms bob # [ 9.603414] ncps-pre-start[579]: Applying: 20260101000000_add-config-table.sql bob # [ 9.608620] ncps-pre-start[579]: Applied: 20260101000000_add-config-table.sql in 6.9156ms bob # [ 9.610049] ncps-pre-start[579]: Applying: 20260105025735_remove-redundant-indexes.sql bob # [ 9.615688] ncps-pre-start[579]: Applied: 20260105025735_remove-redundant-indexes.sql in 7.05388ms bob # [ 9.616688] ncps-pre-start[579]: Applying: 20260105030513_support_shared_nars_between_narinfos.sql bob # [ 9.624650] ncps-pre-start[579]: Applied: 20260105030513_support_shared_nars_between_narinfos.sql in 8.9316ms bob # [ 9.625742] ncps-pre-start[579]: Applying: 20260117195000_add_narinfo_de_normalized.sql bob # [ 9.634921] ncps-pre-start[579]: Applied: 20260117195000_add_narinfo_de_normalized.sql in 10.33844ms bob # [ 9.636356] ncps-pre-start[579]: Applying: 20260127223000_allow_multiple_nar_representations.sql bob # [ 9.645968] ncps-pre-start[579]: Applied: 20260127223000_allow_multiple_nar_representations.sql in 10.17656ms bob # [ 9.648130] ncps-pre-start[579]: Applying: 20260131021850_add_chunks.sql bob # [ 9.656216] ncps-pre-start[579]: Applied: 20260131021850_add_chunks.sql in 10.2224ms bob # [ 9.657048] ncps-pre-start[579]: Applying: 20260205063651_add_total_chunks_to_nar_files.sql bob # [ 9.668203] ncps-pre-start[579]: Applied: 20260205063651_add_total_chunks_to_nar_files.sql in 11.95444ms bob # [ 9.669161] ncps-pre-start[579]: Applying: 20260217071237_add_chunking_started_at_to_nar_files.sql bob # [ 9.679402] ncps-pre-start[579]: Applied: 20260217071237_add_chunking_started_at_to_nar_files.sql in 11.2068ms bob # [ 9.680427] ncps-pre-start[579]: Applying: 20260301000000_add_verified_at_to_nar_files.sql bob # [ 9.692517] ncps-pre-start[579]: Applied: 20260301000000_add_verified_at_to_nar_files.sql in 12.77252ms clare # [ 9.717665] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. clare # [ 9.719862] systemd[1]: Starting Virtual Console Setup... clare # [ 9.740060] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 9.740710] systemd[1]: Stopped Virtual Console Setup. clare # [ 9.745474] systemd[1]: Starting Virtual Console Setup... clare # [ 9.750062] systemd-logind[495]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) clare # [ 9.849103] systemd-vconsole-setup[560]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 9.852289] systemd[1]: Finished Virtual Console Setup. bob # [ 9.866696] systemd[1]: Started ncps binary cache proxy service. bob # [ 9.867266] systemd[1]: Reached target Multi-User System. bob # [ 9.872646] systemd[1]: Startup finished in 717ms (kernel) + 4.643s (initrd) + 4.511s (userspace) = 9.872s. bob: (finished: waiting for unit ncps, in 10.21 seconds) bob: must succeed: systemctl status ncps bob: (finished: must succeed: systemctl status ncps, in 0.07 seconds) clare: waiting for unit harmonia.socket clare: waiting for the VM to finish booting clare: Guest shell says: b'Spawning backdoor root shell...\n' clare: connected to guest root shell clare: (connecting took 0.00 seconds) clare: (finished: waiting for the VM to finish booting, in 0.00 seconds) bob # [ 10.055564] systemd[1]: Starting Virtual Console Setup... clare: (finished: waiting for unit harmonia.socket, in 0.05 seconds) alice: waiting for success: curl bob:8502/nix-cache-info alice: waiting for the VM to finish booting alice: Guest shell says: b'Spawning backdoor root shell...\n' alice: connected to guest root shell alice: (connecting took 0.00 seconds) alice: (finished: waiting for the VM to finish booting, in 0.00 seconds) bob # [ 10.100710] systemd-logind[500]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 10.104926] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 10.115055] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 10.116446] systemd[1]: Stopped Virtual Console Setup. bob # [ 10.124730] systemd[1]: Starting Virtual Console Setup... bob # [ 10.227167] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","log_level":"info","time":"2026-10-04T15:27:22Z","message":"logger created"} bob # [ 10.228889] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","xz-binary-path":"/nix/store/csrwf31hprwghnbyiglxa3p787lgrl67-xz-5.8.4-bin/bin/xz","time":"2026-10-04T15:27:22Z","message":"Using xz binary for xz decompression"} bob # [ 10.241597] systemd-networkd[557]: eth0: Gained IPv6LL bob # [ 10.246124] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","time":"2026-10-04T15:27:22Z","message":"using local locks (single-instance mode)"} bob # [ 10.250911] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-10-04T15:27:22Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"} bob # [ 10.256442] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-04T15:27:22Z","message":"setting up tracer provider to discard traces"} bob # [ 10.259126] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-04T15:27:22Z","message":"setting up meter provider to discard metrics"} bob # [ 10.263085] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-04T15:27:22Z","message":"setting up logger provider to discard logs"} bob # [ 10.267914] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-10-04T15:27:22Z","message":"Reporting anonymous metrics to the project maintainers"} bob # [ 10.271330] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-10-04T15:27:22Z","message":"failed to parse netrc file, proceeding without netrc authentication"} bob # [ 10.273613] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-10-04T15:27:22Z","message":"using local storage"} clare # [ 10.305465] systemd[1]: Starting harmonia binary cache service... bob # [ 10.329550] systemd-vconsole-setup[605]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 10.331677] systemd[1]: Finished Virtual Console Setup. alice # [ 10.369340] systemd-networkd[538]: eth1: Gained IPv6LL bob # [ 10.369247] systemd-networkd[557]: eth1: Gained IPv6LL alice # [ 10.433139] systemd-networkd[538]: eth0: Gained IPv6LL clare # [ 10.446142] harmonia-cache[567]: 2026-10-04T15:27:22.267559Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000)) clare # [ 10.451752] harmonia-cache[567]: 2026-10-04T15:27:22.271896Z INFO actix_server::builder: starting 4 workers clare # [ 10.457158] harmonia-cache[567]: 2026-10-04T15:27:22.277359Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s clare # [ 10.465843] harmonia-cache[567]: 2026-10-04T15:27:22.277440Z INFO actix_server::server: Actix runtime found; starting in Actix runtime clare # [ 10.469496] harmonia-cache[567]: 2026-10-04T15:27:22.277548Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:5000 clare # [ 10.473657] systemd[1]: Started harmonia binary cache service. bob # [ 10.494633] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-10-04T15:27:22Z","message":"upstream became healthy and is now available for requests"} bob # [ 10.499133] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-10-04T15:27:22Z","message":"Server started"} clare # [ 10.691203] systemd-networkd[541]: eth1: Gained IPv6LL clare # [ 11.202296] systemd-networkd[541]: eth0: Gained IPv6LL bob # [ 11.276848] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: 2026/10/04 15:27:23 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 134201 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.428990] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:39394","trace_id":"ab9d63a89e40f13f051f5cfe63284004","span_id":"d1e046dc757031c9","status":200,"elapsed":0.14136,"bytes":50,"time":"2026-10-04T15:29:37Z","message":"handled request"} alice # 0 0 0 0 0 0 0 0 0100 50 100 50 0 0 1957 0 0100 50 100 50 0 0 1665 0 0100 50 100 50 0 0 1454 0 0 alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 135.38 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 2730 0 0100 51 100 51 0 0 2277 0 0100 51 100 51 0 0 1934 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.08 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.041430] systemd-resolved[312]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. clare # [ 146.048596] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 146.052327] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 146.570978] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 146.575989] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 147.931765] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 147.935842] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 150.044830] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 150.049053] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare: (finished: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 , in 4.80 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.617101] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:39406","trace_id":"69b9389c141b2f03b6e3c7df4529358a","span_id":"02ed497c8e80c8d1","status":200,"elapsed":0.03352,"bytes":50,"time":"2026-10-04T15:29:42Z","message":"handled request"} alice # [ 150.942839] systemd-resolved[312]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. alice # [ 150.948182] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 150.951996] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 151.491802] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 151.496090] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 152.862946] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 152.866666] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 154.964347] systemd-resolved[312]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 154.968330] systemd-resolved[312]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 154.963255] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:39406","trace_id":"a5e48cfa0e64d7d454607b645f4bd6ad","span_id":"140071157aa662cb","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-04T15:29:46Z","message":"download the narinfo from upstream"} bob # [ 154.995948] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:39406","trace_id":"a5e48cfa0e64d7d454607b645f4bd6ad","span_id":"140071157aa662cb","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-04T15:29:46Z","message":"downloading the nar from upstream"} bob # [ 155.012746] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:39406","trace_id":"a5e48cfa0e64d7d454607b645f4bd6ad","span_id":"140071157aa662cb","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-04T15:29:46Z","message":"download the nar from upstream"} bob # [ 155.048069] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:39406","trace_id":"a5e48cfa0e64d7d454607b645f4bd6ad","span_id":"140071157aa662cb","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-04T15:29:46Z","message":"storing narinfo and nar_file record in the database"} bob # [ 155.062433] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:39406","trace_id":"a5e48cfa0e64d7d454607b645f4bd6ad","span_id":"140071157aa662cb","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":98.3538,"time":"2026-10-04T15:29:46Z","message":"download of narinfo complete"} bob # [ 155.073164] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:39406","trace_id":"a5e48cfa0e64d7d454607b645f4bd6ad","span_id":"140071157aa662cb","status":200,"elapsed":100.4026,"bytes":532,"time":"2026-10-04T15:29:46Z","message":"handled request"} bob # [ 155.078452] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:39406","trace_id":"a5e48cfa0e64d7d454607b645f4bd6ad","span_id":"140071157aa662cb","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":66.00988,"time":"2026-10-04T15:29:46Z","message":"download of nar complete"} bob # [ 155.087136] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[586]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:39406","trace_id":"8cc60dd4b831bb034d2eec5ec072b71e","span_id":"bd07b6645570e095","status":200,"elapsed":0.6058,"bytes":95,"time":"2026-10-04T15:29:46Z","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.71 seconds) (finished: run the VM test script, in 155.91 seconds) test script finished in 156.13s 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-04T15:29:46Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-10-04T15:29:46Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-10-04T15:29:46Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 50) bob # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) bob # [2026-10-04T15:29:47Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-10-04T15:29:47Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-10-04T15:29:47Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 49) clare # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) clare # [2026-10-04T15:29:47Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-10-04T15:29:47Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-10-04T15:29:47Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.42 seconds)