build01: building 4s3lra1mshjqhjbjzvvbf7pynz9pb4j3-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.07 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... clare # Formatting '/build/vm-state-clare/tmp.RzIjXRUybh', fmt=raw size=1073741824 clare # mke2fs 1.47.4 (6-Mar-2025) clare # Discarding device blocks: 0/262144 done clare # Creating filesystem with 262144 4k blocks and 65536 inodes clare # Filesystem UUID: 56dcb38e-425e-47d8-85ec-3d528b744938 clare # Superblock backups stored on blocks: clare # 32768, 98304, 163840, 229376 clare # clare # Allocating group tables: 0/8 done clare # Writing inode tables: 0/8 done clare # Creating journal (8192 blocks): done clare # Writing superblocks and filesystem accounting information: 0/8 done clare # clare # Virtualisation disk image created. clare # Starting virtiofs daemons... clare # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) clare # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether clare # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection... clare # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests clare # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests clare: QEMU running (pid 50) alice # Disk image does not exist, creating the virtualisation disk image... alice: QEMU running (pid 47) alice # Formatting '/build/vm-state-alice/tmp.eFvxCdozm0', fmt=raw size=1073741824 alice # mke2fs 1.47.4 (6-Mar-2025) alice # Discarding device blocks: 0/262144 done alice # Creating filesystem with 262144 4k blocks and 65536 inodes alice # Filesystem UUID: 8b4716f3-2b68-4ac2-a216-c4e011e1480a alice # Superblock backups stored on blocks: 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-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) alice # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether alice # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection... alice # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # Disk image does not exist, creating the virtualisation disk image... alice # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # Formatting '/build/vm-state-bob/tmp.H894aykIAv', fmt=raw size=1073741824 alice # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests bob # mke2fs 1.47.4 (6-Mar-2025) alice # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests bob # Discarding device blocks: 0/262144 done alice # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests bob # Creating filesystem with 262144 4k blocks and 65536 inodes bob: QEMU running (pid 49) bob # Filesystem UUID: eefe96ea-986a-4dfb-8199-7760d741e2ab bob # Superblock backups stored on blocks: bob # 32768, 98304, 163840, 229376 bob # bob # Allocating group tables: 0/8 done bob # Writing inode tables: 0/8 done bob # Creating journal (8192 blocks): done bob # Writing superblocks and filesystem accounting information: 0/8 done (finished: start all VMs, in 1.73 seconds) bob # bob: waiting for unit ncps bob # Virtualisation disk image created. bob: waiting for the VM to finish booting bob # Starting virtiofs daemons... bob # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) bob # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether bob # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection... bob # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests bob # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] clare # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172 clare # [ 0.000000] KASLR enabled clare # [ 0.000000] random: crng init done clare # [ 0.000000] Machine model: linux,dummy-virt clare # [ 0.000000] efi: UEFI not found. clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] clare # [ 0.000000] Zone ranges: clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] DMA32 empty clare # [ 0.000000] Normal empty clare # [ 0.000000] Device empty clare # [ 0.000000] Movable zone start for each node clare # [ 0.000000] Early memory node ranges clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 clare # [ 0.000000] psci: probing for conduit method from DT. clare # [ 0.000000] psci: PSCIv1.3 detected in firmware. clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs clare # [ 0.000000] psci: Trusted OS migration not required clare # [ 0.000000] psci: SMC Calling Convention v1.1 clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296 clare # [ 0.000000] Detected PIPT I-cache on CPU0 clare # [ 0.000000] CPU features: detected: GICv3 CPU interface clare # [ 0.000000] CPU features: detected: Spectre-v4 clare # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.000000] CPU features: detected: ARM erratum 1418040 clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.000000] alternatives: applying boot alternatives clare # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/j4fzgfjgricvyd8zav51kvpldd2mwf76-nixos-system-clare-test/init regInfo=/nix/.ro-store/r3p01a4rnqc6f0dc0k7hzkrmyjh2q32y-closure-info/registration console=ttyAMA0,115200n8 console=tty0 clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/r3p01a4rnqc6f0dc0k7hzkrmyjh2q32y-closure-info/registration", will be passed to user space. clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB clare # [ 0.000000] software IO TLB: area num 1. clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 clare # [ 0.000000] Policy zone: DMA clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 clare # [ 0.000000] allocated 2097152 bytes of page_ext clare # [ 0.000000] ftrace: allocating 75049 entries in 294 pages clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups clare # [ 0.000000] rcu: Hierarchical RCU implementation. clare # [ 0.000000] rcu: RCU event tracing is enabled. clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. clare # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.000000] Rude variant of Tasks RCU enabled. clare # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 clare # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.000000] GICv3: 0 Extended SPIs implemented clare # [ 0.000000] Root IRQ handler: gic_handle_irq clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. alice # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172 clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns alice # [ 0.000000] KASLR enabled alice # [ 0.000000] random: crng init done clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 alice # [ 0.000000] Machine model: linux,dummy-virt clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). alice # [ 0.000000] efi: UEFI not found. alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns 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] clare # [ 0.000085] arm-pv: using stolen time PV alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000803] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 clare # [ 0.001134] Console: colour dummy device 80x25 alice # [ 0.000000] psci: probing for conduit method from DT. clare # [ 0.001148] printk: legacy console [tty0] enabled 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 clare # [ 0.001400] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) clare # [ 0.001406] pid_max: default: 32768 minimum: 301 alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) clare # [ 0.001492] LSM: initializing lsm=capability,landlock,yama,bpf,ima alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296 clare # [ 0.001740] landlock: Up and running. alice # [ 0.000000] Detected PIPT I-cache on CPU0 clare # [ 0.001743] Yama: becoming mindful. clare # [ 0.002830] LSM support for eBPF active alice # [ 0.000000] CPU features: detected: GICv3 CPU interface alice # [ 0.000000] CPU features: detected: Spectre-v4 clare # [ 0.003074] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.000000] CPU features: detected: Spectre-BHB alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.003096] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.006450] rcu: Hierarchical SRCU implementation. alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.006460] rcu: Max phase no-delay instances is 1000. alice # [ 0.000000] CPU features: detected: ARM erratum 1418040 clare # [ 0.007533] fsl-mc MSI: its@8080000 domain created alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion clare # [ 0.007750] EFI services will not be available. clare # [ 0.009104] smp: Bringing up secondary CPUs ... alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.009145] smp: Brought up 1 node, 1 CPU alice # [ 0.000000] alternatives: applying boot alternatives clare # [ 0.009148] SMP: Total of 1 processors activated. clare # [ 0.009150] CPU: All CPU(s) started at EL1 clare # [ 0.009165] CPU features: detected: 32-bit EL0 Support clare # [ 0.009169] CPU features: detected: Data cache clean to the PoU not required for I/D coherence clare # [ 0.009172] CPU features: detected: Common not Private translations 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/z9f82k8cch6hhsyq4na4bfcwhpbkripm-nixos-system-alice-test/init regInfo=/nix/.ro-store/n1nsmb1xzg6q0jkh7hf96ky653rdfqia-closure-info/registration console=ttyAMA0,115200n8 console=tty0 clare # [ 0.009174] CPU features: detected: CRC32 instructions clare # [ 0.009176] CPU features: detected: Data cache clean to Point of Persistence clare # [ 0.009179] CPU features: detected: Enhanced Virtualization Traps alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/n1nsmb1xzg6q0jkh7hf96ky653rdfqia-closure-info/registration", will be passed to user space. clare # [ 0.009191] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes clare # [ 0.009193] CPU features: detected: LSE atomic instructions alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) clare # [ 0.009196] CPU features: detected: Privileged Access Never clare # [ 0.009198] CPU features: detected: PMUv3 alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.009200] CPU features: detected: RAS Extension Support alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB clare # [ 0.009203] CPU features: detected: Speculative Store Bypassing Safe (SSBS) alice # [ 0.000000] software IO TLB: area num 1. clare # [ 0.009229] alternatives: applying system-wide alternatives alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.011080] CPU features: detected: Hardware dirty bit management on CPU0 alice # [ 0.000000] Fallback order for Node 0: 0 alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 alice # [ 0.000000] Policy zone: DMA clare # [ 0.011325] Memory: 889148K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 118128K reserved, 32768K cma-reserved) clare # [ 0.011964] devtmpfs: initialized alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 clare # [ 0.013585] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.000000] allocated 2097152 bytes of page_ext clare # [ 0.013609] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). alice # [ 0.000000] ftrace: allocating 75049 entries in 294 pages alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups clare # [ 0.013763] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL clare # [ 0.013768] 0 pages in range for non-PLT usage alice # [ 0.000000] rcu: Hierarchical RCU implementation. clare # [ 0.013769] 508192 pages in range for PLT usage alice # [ 0.000000] rcu: RCU event tracing is enabled. clare # [ 0.013906] pinctrl core: initialized pinctrl subsystem alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. clare # [ 0.014731] DMI not present or invalid. alice # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.019385] NET: Registered PF_NETLINK/PF_ROUTE protocol family alice # [ 0.000000] Rude variant of Tasks RCU enabled. alice # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.022236] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. clare # [ 0.022388] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 clare # [ 0.022538] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.022576] audit: initializing netlink subsys (disabled) 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.023444] thermal_sys: Registered thermal governor 'fair_share' alice # [ 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.023447] thermal_sys: Registered thermal governor 'bang_bang' clare # [ 0.023451] thermal_sys: Registered thermal governor 'step_wise' 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.023453] thermal_sys: Registered thermal governor 'user_space' alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 alice # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.023455] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 0.000000] GICv3: 0 Extended SPIs implemented clare # [ 0.023508] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 alice # [ 0.000000] Root IRQ handler: gic_handle_irq clare # [ 0.023518] cpuidle: using governor ladder alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI clare # [ 0.023523] cpuidle: using governor menu alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.023691] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 clare # [ 0.023721] ASID allocator initialised with 32768 entries clare # [ 0.024429] Serial: AMBA PL011 UART driver alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.028897] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 clare # [ 0.029181] printk: console [ttyAMA0] enabled alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns alice # [ 0.000062] arm-pv: using stolen time PV alice # [ 0.000477] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.000842] Console: colour dummy device 80x25 alice # [ 0.000857] printk: legacy console [tty0] enabled alice # [ 0.001094] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) alice # [ 0.001100] pid_max: default: 32768 minimum: 301 alice # [ 0.001192] LSM: initializing lsm=capability,landlock,yama,bpf,ima alice # [ 0.001377] landlock: Up and running. alice # [ 0.001380] Yama: becoming mindful. alice # [ 0.001955] LSM support for eBPF active alice # [ 0.002143] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.002163] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.006024] rcu: Hierarchical SRCU implementation. alice # [ 0.006038] rcu: Max phase no-delay instances is 1000. alice # [ 0.007183] fsl-mc MSI: its@8080000 domain created clare # [ 0.111158] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages alice # [ 0.007388] EFI services will not be available. clare # [ 0.111179] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page alice # [ 0.007504] smp: Bringing up secondary CPUs ... clare # [ 0.111182] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages alice # [ 0.007541] smp: Brought up 1 node, 1 CPU alice # [ 0.007544] SMP: Total of 1 processors activated. clare # [ 0.111185] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page alice # [ 0.007546] CPU: All CPU(s) started at EL1 clare # [ 0.111187] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages alice # [ 0.007560] CPU features: detected: 32-bit EL0 Support clare # [ 0.111189] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 0.007565] CPU features: detected: Data cache clean to the PoU not required for I/D coherence clare # [ 0.111191] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages alice # [ 0.007568] CPU features: detected: Common not Private translations clare # [ 0.111193] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page alice # [ 0.007570] CPU features: detected: CRC32 instructions clare # [ 0.112308] fbcon: Taking over console clare # [ 0.112329] ACPI: Interpreter disabled. alice # [ 0.007572] CPU features: detected: Data cache clean to Point of Persistence clare # [ 0.115248] iommu: Default domain type: Translated alice # [ 0.007575] CPU features: detected: Enhanced Virtualization Traps clare # [ 0.115255] iommu: DMA domain TLB invalidation policy: strict mode alice # [ 0.007587] CPU features: detected: RCpc load-acquire (LDAPR) clare # [ 0.115854] SCSI subsystem initialized alice # [ 0.007589] CPU features: detected: LSE atomic instructions alice # [ 0.007591] CPU features: detected: Privileged Access Never alice # [ 0.007594] CPU features: detected: PMUv3 alice # [ 0.007596] CPU features: detected: RAS Extension Support clare # [ 0.122982] usbcore: registered new interface driver usbfs clare # [ 0.123017] usbcore: registered new interface driver hub alice # [ 0.007599] CPU features: detected: Speculative Store Bypassing Safe (SSBS) clare # [ 0.123029] usbcore: registered new device driver usb alice # [ 0.007627] alternatives: applying system-wide alternatives alice # [ 0.009284] CPU features: detected: Hardware dirty bit management on CPU0 alice # [ 0.010160] Memory: 889112K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 118124K reserved, 32768K cma-reserved) alice # [ 0.010786] devtmpfs: initialized alice # [ 0.012413] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.012438] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). clare # [ 0.124789] pps_core: LinuxPPS API ver. 1 registered alice # [ 0.012604] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL clare # [ 0.124796] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti alice # [ 0.012610] 0 pages in range for non-PLT usage clare # [ 0.124802] PTP clock support registered alice # [ 0.012612] 508192 pages in range for PLT usage clare # [ 0.124849] EDAC MC: Ver: 3.0.0 alice # [ 0.012755] pinctrl core: initialized pinctrl subsystem clare # [ 0.127396] scmi_core: SCMI protocol bus registered alice # [ 0.013731] DMI not present or invalid. alice # [ 0.018608] NET: Registered PF_NETLINK/PF_ROUTE protocol family alice # [ 0.021977] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations alice # [ 0.022123] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations clare # [ 0.129895] FPGA manager framework clare # [ 0.130769] vgaarb: loaded alice # [ 0.022272] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.130968] clocksource: Switched to clocksource arch_sys_counter alice # [ 0.022306] audit: initializing netlink subsys (disabled) alice # [ 0.023735] thermal_sys: Registered thermal governor 'fair_share' alice # [ 0.023738] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 0.023742] thermal_sys: Registered thermal governor 'step_wise' alice # [ 0.023744] thermal_sys: Registered thermal governor 'user_space' alice # [ 0.023746] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 0.023783] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 alice # [ 0.023794] cpuidle: using governor ladder alice # [ 0.023798] cpuidle: using governor menu alice # [ 0.023946] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. alice # [ 0.023971] ASID allocator initialised with 32768 entries alice # [ 0.024664] Serial: AMBA PL011 UART driver alice # [ 0.029277] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 alice # [ 0.029521] printk: console [ttyAMA0] enabled bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] clare # [ 0.144355] VFS: Disk quotas dquot_6.6.0 bob # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172 bob # [ 0.000000] KASLR enabled clare # [ 0.144389] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.000000] random: crng init done clare # [ 0.146366] netfs: FS-Cache loaded bob # [ 0.000000] Machine model: linux,dummy-virt clare # [ 0.146520] pnp: PnP ACPI: disabled bob # [ 0.000000] efi: UEFI not found. bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT alice # [ 0.106915] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.106935] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] alice # [ 0.106938] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.000000] Zone ranges: alice # [ 0.106940] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] DMA32 empty bob # [ 0.000000] Normal empty alice # [ 0.106942] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages clare # [ 0.149919] NET: Registered PF_INET protocol family bob # [ 0.000000] Device empty alice # [ 0.106944] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 0.000000] Movable zone start for each node clare # [ 0.150115] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) bob # [ 0.000000] Early memory node ranges alice # [ 0.106946] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.106948] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page alice # [ 0.108059] fbcon: Taking over console bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] alice # [ 0.108071] ACPI: Interpreter disabled. bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.110529] iommu: Default domain type: Translated bob # [ 0.000000] psci: probing for conduit method from DT. alice # [ 0.110535] iommu: DMA domain TLB invalidation policy: strict mode bob # [ 0.000000] psci: PSCIv1.3 detected in firmware. bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs bob # [ 0.000000] psci: Trusted OS migration not required bob # [ 0.000000] psci: SMC Calling Convention v1.1 bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.116872] SCSI subsystem initialized bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296 alice # [ 0.117443] usbcore: registered new interface driver usbfs bob # [ 0.000000] Detected PIPT I-cache on CPU0 alice # [ 0.117483] usbcore: registered new interface driver hub bob # [ 0.000000] CPU features: detected: GICv3 CPU interface alice # [ 0.117491] usbcore: registered new device driver usb bob # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.117773] pps_core: LinuxPPS API ver. 1 registered bob # [ 0.000000] CPU features: detected: Spectre-BHB bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR alice # [ 0.117778] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti alice # [ 0.117782] PTP clock support registered bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) alice # [ 0.117816] EDAC MC: Ver: 3.0.0 bob # [ 0.000000] CPU features: detected: ARM erratum 1418040 alice # [ 0.118036] scmi_core: SCMI protocol bus registered bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion alice # [ 0.122329] FPGA manager framework bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing bob # [ 0.000000] alternatives: applying boot alternatives bob # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/g481r9hzfzlyykghr4xgmgb8b9rc5pbb-nixos-system-bob-test/init regInfo=/nix/.ro-store/fx6l16z5lkcpzd05h24bif70bw1zhd4n-closure-info/registration console=ttyAMA0,115200n8 console=tty0 bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/fx6l16z5lkcpzd05h24bif70bw1zhd4n-closure-info/registration", will be passed to user space. bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB bob # [ 0.000000] software IO TLB: area num 1. bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) bob # [ 0.000000] Fallback order for Node 0: 0 bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 bob # [ 0.000000] Policy zone: DMA bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 bob # [ 0.000000] allocated 2097152 bytes of page_ext bob # [ 0.000000] ftrace: allocating 75049 entries in 294 pages bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups bob # [ 0.000000] rcu: Hierarchical RCU implementation. alice # [ 0.131308] vgaarb: loaded bob # [ 0.000000] rcu: RCU event tracing is enabled. alice # [ 0.131582] clocksource: Switched to clocksource arch_sys_counter bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. bob # [ 0.000000] Trampoline variant of Tasks RCU enabled. bob # [ 0.000000] Rude variant of Tasks RCU enabled. bob # [ 0.000000] Tracing variant of Tasks RCU enabled. bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 bob # [ 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 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 Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 bob # [ 0.000000] GICv3: 256 SPIs implemented alice # [ 0.139108] VFS: Disk quotas dquot_6.6.0 bob # [ 0.000000] GICv3: 0 Extended SPIs implemented alice # [ 0.139139] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.000000] Root IRQ handler: gic_handle_irq bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI clare # [ 0.176489] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.176515] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 clare # [ 0.176528] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.176569] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.141742] netfs: FS-Cache loaded bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) alice # [ 0.141913] pnp: PnP ACPI: disabled clare # [ 0.176636] TCP: Hash tables configured (established 8192 bind 8192) bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.176735] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 clare # [ 0.176813] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 clare # [ 0.176863] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. clare # [ 0.176969] NET: Registered PF_UNIX/PF_LOCAL protocol family clare # [ 0.177018] NET: Registered PF_XDP protocol family bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns clare # [ 0.177034] PCI: CLS 0 bytes, default 64 clare # [ 0.177405] Trying to unpack rootfs image as initramfs... bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). alice # [ 0.144631] NET: Registered PF_INET protocol family alice # [ 0.144862] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns clare # [ 0.185452] kvm [1]: HYP mode not available bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.000052] arm-pv: using stolen time PV bob # [ 0.000421] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.000712] Console: colour dummy device 80x25 bob # [ 0.000724] printk: legacy console [tty0] enabled bob # [ 0.000938] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) bob # [ 0.000944] pid_max: default: 32768 minimum: 301 bob # [ 0.001028] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.001168] landlock: Up and running. bob # [ 0.001170] Yama: becoming mindful. bob # [ 0.001593] LSM support for eBPF active bob # [ 0.001762] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.001782] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.004553] rcu: Hierarchical SRCU implementation. bob # [ 0.004562] rcu: Max phase no-delay instances is 1000. bob # [ 0.005548] fsl-mc MSI: its@8080000 domain created bob # [ 0.005727] EFI services will not be available. bob # [ 0.005866] smp: Bringing up secondary CPUs ... bob # [ 0.005898] smp: Brought up 1 node, 1 CPU bob # [ 0.005900] SMP: Total of 1 processors activated. bob # [ 0.005902] CPU: All CPU(s) started at EL1 bob # [ 0.005914] CPU features: detected: 32-bit EL0 Support bob # [ 0.005918] CPU features: detected: Data cache clean to the PoU not required for I/D coherence bob # [ 0.005921] CPU features: detected: Common not Private translations bob # [ 0.005923] CPU features: detected: CRC32 instructions bob # [ 0.005925] CPU features: detected: Data cache clean to Point of Persistence bob # [ 0.005928] CPU features: detected: Enhanced Virtualization Traps bob # [ 0.005938] CPU features: detected: RCpc load-acquire (LDAPR) bob # [ 0.005940] CPU features: detected: LSE atomic instructions bob # [ 0.005942] CPU features: detected: Privileged Access Never bob # [ 0.005943] CPU features: detected: PMUv3 bob # [ 0.005945] CPU features: detected: RAS Extension Support bob # [ 0.005948] CPU features: detected: Speculative Store Bypassing Safe (SSBS) bob # [ 0.005970] alternatives: applying system-wide alternatives bob # [ 0.007611] CPU features: detected: Hardware dirty bit management on CPU0 bob # [ 0.008047] Memory: 889120K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 118120K reserved, 32768K cma-reserved) bob # [ 0.008603] devtmpfs: initialized bob # [ 0.010156] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.010179] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). bob # [ 0.010315] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.010321] 0 pages in range for non-PLT usage bob # [ 0.010322] 508192 pages in range for PLT usage bob # [ 0.010454] pinctrl core: initialized pinctrl subsystem bob # [ 0.011389] DMI not present or invalid. bob # [ 0.016263] NET: Registered PF_NETLINK/PF_ROUTE protocol family bob # [ 0.018956] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations bob # [ 0.019119] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations bob # [ 0.019270] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations bob # [ 0.019303] audit: initializing netlink subsys (disabled) bob # [ 0.020042] thermal_sys: Registered thermal governor 'fair_share' bob # [ 0.020045] thermal_sys: Registered thermal governor 'bang_bang' bob # [ 0.020049] thermal_sys: Registered thermal governor 'step_wise' bob # [ 0.020051] thermal_sys: Registered thermal governor 'user_space' bob # [ 0.020053] thermal_sys: Registered thermal governor 'power_allocator' bob # [ 0.020088] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 bob # [ 0.020096] cpuidle: using governor ladder bob # [ 0.020101] cpuidle: using governor menu alice # [ 0.173614] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.020250] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. bob # [ 0.020275] ASID allocator initialised with 32768 entries alice # [ 0.173647] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) bob # [ 0.020937] Serial: AMBA PL011 UART driver alice # [ 0.173662] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) bob # [ 0.025101] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 alice # [ 0.173720] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) bob # [ 0.025292] printk: console [ttyAMA0] enabled alice # [ 0.173788] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.173885] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 0.173948] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.174004] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.174123] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 0.174156] NET: Registered PF_XDP protocol family alice # [ 0.174174] PCI: CLS 0 bytes, default 64 alice # [ 0.174451] Trying to unpack rootfs image as initramfs... bob # [ 0.102571] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages bob # [ 0.102590] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page bob # [ 0.102593] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages bob # [ 0.102595] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page bob # [ 0.102597] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages bob # [ 0.102599] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 0.102601] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages bob # [ 0.102603] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page bob # [ 0.103661] fbcon: Taking over console bob # [ 0.103674] ACPI: Interpreter disabled. bob # [ 0.106219] iommu: Default domain type: Translated bob # [ 0.106226] iommu: DMA domain TLB invalidation policy: strict mode bob # [ 0.112471] SCSI subsystem initialized bob # [ 0.112755] usbcore: registered new interface driver usbfs bob # [ 0.112803] usbcore: registered new interface driver hub bob # [ 0.112811] usbcore: registered new device driver usb bob # [ 0.113072] pps_core: LinuxPPS API ver. 1 registered bob # [ 0.113075] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti bob # [ 0.113087] PTP clock support registered bob # [ 0.113120] EDAC MC: Ver: 3.0.0 bob # [ 0.113297] scmi_core: SCMI protocol bus registered bob # [ 0.114693] FPGA manager framework alice # [ 0.191635] kvm [1]: HYP mode not available bob # [ 0.127237] vgaarb: loaded bob # [ 0.127604] clocksource: Switched to clocksource arch_sys_counter bob # [ 0.133584] VFS: Disk quotas dquot_6.6.0 bob # [ 0.133616] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.135292] netfs: FS-Cache loaded bob # [ 0.135457] pnp: PnP ACPI: disabled bob # [ 0.139891] NET: Registered PF_INET protocol family bob # [ 0.140115] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) clare # [ 0.263189] Initialise system trusted keyrings clare # [ 0.263350] workingset: timestamp_bits=42 max_order=18 bucket_order=0 clare # [ 0.263775] squashfs: version 4.0 (2009/01/31) Phillip Lougher clare # [ 0.263822] 9p: Installing v9fs 9p2000 file system support clare # [ 0.292724] Key type asymmetric registered clare # [ 0.292750] Asymmetric key parser 'x509' registered clare # [ 0.292846] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) clare # [ 0.293012] io scheduler mq-deadline registered clare # [ 0.293016] io scheduler kyber registered clare # [ 0.298357] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.298706] ledtrig-cpu: registered to indicate activity on CPUs bob # [ 0.169048] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.169076] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) alice # [ 0.260395] Initialise system trusted keyrings bob # [ 0.169089] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) alice # [ 0.260525] workingset: timestamp_bits=42 max_order=18 bucket_order=0 bob # [ 0.169129] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.260897] squashfs: version 4.0 (2009/01/31) Phillip Lougher bob # [ 0.169195] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.260946] 9p: Installing v9fs 9p2000 file system support bob # [ 0.169289] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) clare # [ 0.300091] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: bob # [ 0.169352] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.300113] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 bob # [ 0.169404] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.300127] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 bob # [ 0.169515] NET: Registered PF_UNIX/PF_LOCAL protocol family bob # [ 0.169545] NET: Registered PF_XDP protocol family clare # [ 0.300131] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 bob # [ 0.169561] PCI: CLS 0 bytes, default 64 clare # [ 0.300158] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits bob # [ 0.169810] Trying to unpack rootfs image as initramfs... clare # [ 0.300188] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.300271] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 clare # [ 0.300288] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 0.186222] kvm [1]: HYP mode not available clare # [ 0.300292] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] clare # [ 0.300295] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] clare # [ 0.300297] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] clare # [ 0.300366] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint clare # [ 0.300836] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.300966] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.300975] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.300994] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.301007] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.301321] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.301448] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.301458] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.301477] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.301788] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint clare # [ 0.301911] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] clare # [ 0.301920] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.301939] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.302268] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.302394] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.302403] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.302422] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.302430] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.302743] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.302873] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.302891] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.288014] Key type asymmetric registered alice # [ 0.288035] Asymmetric key parser 'x509' registered alice # [ 0.288109] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) alice # [ 0.288260] io scheduler mq-deadline registered alice # [ 0.288265] io scheduler kyber registered clare # [ 0.327389] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 0.290866] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.327527] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.327544] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.327832] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.327955] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.328173] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.328298] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.328317] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.328629] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.328760] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.328779] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.329097] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.329225] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.329242] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.329554] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint clare # [ 0.329732] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] clare # [ 0.329742] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.329760] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.330147] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.330273] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.300184] ledtrig-cpu: registered to indicate activity on CPUs clare # [ 0.330282] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.300555] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: clare # [ 0.330302] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.300576] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 clare # [ 0.330769] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned alice # [ 0.300589] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 clare # [ 0.330778] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned clare # [ 0.330782] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned alice # [ 0.300593] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.330819] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned alice # [ 0.300621] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits clare # [ 0.330850] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned alice # [ 0.300646] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.330882] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned alice # [ 0.300729] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 alice # [ 0.300746] pci_bus 0000:00: root bus resource [bus 00-ff] clare # [ 0.330921] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned alice # [ 0.300749] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] alice # [ 0.300752] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] clare # [ 0.330952] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned alice # [ 0.300754] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.300846] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint alice # [ 0.301306] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.301436] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.301444] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.301463] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.301476] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.301823] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.301949] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.301959] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.301977] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.302313] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.302443] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.302453] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.302473] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.302800] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.302928] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.302937] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.302955] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.302964] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.303328] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint alice # [ 0.303458] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.303478] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.355054] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.355099] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned alice # [ 0.322733] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint clare # [ 0.355136] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned alice # [ 0.322895] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.355174] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned alice # [ 0.322917] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.355260] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned clare # [ 0.355340] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned clare # [ 0.355357] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.355372] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned clare # [ 0.355387] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned alice # [ 0.325603] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 0.325761] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.355402] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned clare # [ 0.355419] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned alice # [ 0.326075] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint alice # [ 0.326213] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.355432] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned alice # [ 0.326232] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.355447] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned clare # [ 0.355468] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned alice # [ 0.326550] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.355481] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned alice # [ 0.326687] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.355495] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned alice # [ 0.326705] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.355508] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned alice # [ 0.327020] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint clare # [ 0.355521] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned alice # [ 0.327153] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.355534] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned alice # [ 0.327172] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.355548] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned alice # [ 0.327504] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint clare # [ 0.355570] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned clare # [ 0.355584] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned clare # [ 0.355597] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned clare # [ 0.355623] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] clare # [ 0.355628] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] clare # [ 0.355630] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] clare # [ 0.356209] pci 0000:00:07.0: enabling device (0000 -> 0002) bob # [ 0.260106] Initialise system trusted keyrings bob # [ 0.260246] workingset: timestamp_bits=42 max_order=18 bucket_order=0 bob # [ 0.260575] squashfs: version 4.0 (2009/01/31) Phillip Lougher bob # [ 0.260613] 9p: Installing v9fs 9p2000 file system support alice # [ 0.343759] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.343779] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.343799] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.344238] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.344373] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.344382] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.344401] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.345158] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned alice # [ 0.345166] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned alice # [ 0.345170] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned alice # [ 0.345207] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned alice # [ 0.345238] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned alice # [ 0.345270] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned alice # [ 0.345302] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned alice # [ 0.345333] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned alice # [ 0.345367] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned alice # [ 0.345398] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned alice # [ 0.345428] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned clare # [ 0.389117] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.345459] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned alice # [ 0.345541] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned alice # [ 0.345573] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned alice # [ 0.345585] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned alice # [ 0.345597] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned alice # [ 0.345609] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned alice # [ 0.345621] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned alice # [ 0.345634] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned alice # [ 0.345646] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned alice # [ 0.345659] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned alice # [ 0.345685] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned alice # [ 0.345697] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned alice # [ 0.345709] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned alice # [ 0.345721] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned alice # [ 0.345733] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned alice # [ 0.345745] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned alice # [ 0.345757] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned alice # [ 0.345769] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned alice # [ 0.345780] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.345792] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned alice # [ 0.345808] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] alice # [ 0.345813] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] clare # [ 0.404496] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.345815] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.346402] pci 0000:00:07.0: enabling device (0000 -> 0002) bob # [ 0.290861] Key type asymmetric registered bob # [ 0.290880] Asymmetric key parser 'x509' registered bob # [ 0.290957] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) bob # [ 0.291126] io scheduler mq-deadline registered bob # [ 0.291130] io scheduler kyber registered bob # [ 0.304378] pl061_gpio 9030000.pl061: PL061 GPIO chip registered bob # [ 0.304783] ledtrig-cpu: registered to indicate activity on CPUs bob # [ 0.305135] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: bob # [ 0.305153] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 bob # [ 0.305166] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 bob # [ 0.305171] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 bob # [ 0.305199] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits bob # [ 0.305223] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] bob # [ 0.305320] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 bob # [ 0.305338] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 0.305341] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] bob # [ 0.305344] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] bob # [ 0.305347] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] bob # [ 0.305439] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint bob # [ 0.305938] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.306081] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.377563] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) bob # [ 0.306091] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.306111] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.306124] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.306460] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.306600] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.306610] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.306630] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.306948] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint bob # [ 0.307092] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] bob # [ 0.307102] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.307119] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.307442] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.307582] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.307591] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.324365] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.324381] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.324782] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint bob # [ 0.324913] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.324932] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.325252] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint bob # [ 0.325378] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.325397] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.325678] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint bob # [ 0.325798] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] bob # [ 0.326014] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.326141] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.326159] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.326465] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.326591] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.326609] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.326913] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint bob # [ 0.327037] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.327055] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.327382] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint bob # [ 0.327555] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.327565] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.327584] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.340651] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.340794] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.340804] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.340823] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.341291] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned bob # [ 0.341299] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned bob # [ 0.341303] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned bob # [ 0.341340] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned bob # [ 0.341373] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned bob # [ 0.341404] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.341438] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.341470] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned bob # [ 0.341506] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned bob # [ 0.341538] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.341569] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.341599] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned bob # [ 0.341664] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned bob # [ 0.341694] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned bob # [ 0.341707] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned bob # [ 0.341721] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned bob # [ 0.341733] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned bob # [ 0.341839] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned bob # [ 0.341853] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.341865] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.341879] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.341898] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.341910] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.341922] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.341935] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned bob # [ 0.341947] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.341958] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned alice # [ 0.417032] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) bob # [ 0.341971] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned bob # [ 0.341983] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned bob # [ 0.341994] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned bob # [ 0.342006] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.342025] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] bob # [ 0.342030] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] bob # [ 0.342032] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] bob # [ 0.342575] pci 0000:00:07.0: enabling device (0000 -> 0002) clare # [ 0.415177] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) clare # [ 0.417447] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) alice # [ 0.460541] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) alice # [ 0.462444] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.536835] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) clare # [ 0.539982] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) clare # [ 0.541502] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) alice # [ 0.508997] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) alice # [ 0.511070] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) clare # [ 0.552381] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.521831] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) alice # [ 0.523556] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) clare # [ 0.563179] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) clare # [ 0.566406] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) bob # [ 0.378419] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.533383] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) clare # [ 0.576244] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) clare # [ 0.592090] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled clare # [ 0.593547] msm_serial: driver initialized clare # [ 0.593663] SuperH (H)SCI(F) driver initialized clare # [ 0.593708] STM32 USART driver initialized alice # [ 0.536303] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) clare # [ 0.621546] loop: module loaded clare # [ 0.621696] virtio_blk virtio2: 1/0/0 default/read/poll queues clare # [ 0.622446] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) bob # [ 0.501069] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.596199] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) clare # [ 0.635503] megasas: 07.734.00.00-rc1 clare # [ 0.636097] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] clare # [ 0.640746] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.640785] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.642635] Using buffer write method clare # [ 0.642683] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.608585] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled alice # [ 0.609943] msm_serial: driver initialized alice # [ 0.610046] SuperH (H)SCI(F) driver initialized alice # [ 0.610089] STM32 USART driver initialized bob # [ 0.513319] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) bob # [ 0.515157] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) bob # [ 0.540969] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) clare # [ 0.650934] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.650956] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.639417] loop: module loaded alice # [ 0.639901] virtio_blk virtio2: 1/0/0 default/read/poll queues alice # [ 0.640770] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 0.645158] megasas: 07.734.00.00-rc1 alice # [ 0.646210] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] clare # [ 0.688319] Using buffer write method clare # [ 0.688342] Concatenating MTD devices: clare # [ 0.688344] (0): "0.flash" clare # [ 0.688346] (1): "0.flash" clare # [ 0.688348] into device "0.flash" alice # [ 0.657041] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.657084] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.659016] Using buffer write method alice # [ 0.659077] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.663076] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.663097] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.544881] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) bob # [ 0.546613] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.760956] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) bob # [ 0.762806] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) bob # [ 0.765575] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) alice # [ 0.856300] Using buffer write method alice # [ 0.856322] Concatenating MTD devices: alice # [ 0.856324] (0): "0.flash" alice # [ 0.856326] (1): "0.flash" alice # [ 0.856328] into device "0.flash" clare # [ 0.898463] Freeing initrd memory: 28944K bob # [ 0.777055] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) clare # [ 0.906576] tun: Universal TUN/TAP device driver, 1.6 clare # [ 0.910053] thunder_xcv, ver 1.0 clare # [ 0.910076] thunder_bgx, ver 1.0 clare # [ 0.910085] nicpf, ver 1.0 clare # [ 0.910421] e1000: Intel(R) PRO/1000 Network Driver clare # [ 0.910425] e1000: Copyright (c) 1999-2006 Intel Corporation. bob # [ 0.788069] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.790238] msm_serial: driver initialized clare # [ 0.910438] e1000e: Intel(R) PRO/1000 Network Driver bob # [ 0.790333] SuperH (H)SCI(F) driver initialized clare # [ 0.910442] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.790373] STM32 USART driver initialized clare # [ 0.910455] igb: Intel(R) Gigabit Ethernet Network Driver clare # [ 0.910457] igb: Copyright (c) 2007-2014 Intel Corporation. clare # [ 0.910467] igbvf: Intel(R) Gigabit Virtual Function Network Driver clare # [ 0.910469] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. clare # [ 0.910563] sky2: driver version 1.30 clare # [ 0.916284] usbcore: registered new interface driver usb-storage clare # [ 0.916336] usbcore: registered new interface driver usbserial_generic clare # [ 0.916352] usbserial: USB Serial support registered for generic clare # [ 0.916670] hv_vmbus: registering driver hyperv_keyboard clare # [ 0.917795] ehci-pci 0000:00:07.0: EHCI Host Controller clare # [ 0.917827] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 clare # [ 0.918003] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 clare # [ 0.918210] rtc-pl031 9010000.pl031: registered as rtc0 clare # [ 0.918242] rtc-pl031 9010000.pl031: setting system clock to 2026-10-16T09:37:34 UTC (1792143454) clare # [ 0.918425] i2c_dev: i2c /dev entries driver clare # [ 0.925052] sdhci: Secure Digital Host Controller Interface driver clare # [ 0.925060] sdhci: Copyright(c) Pierre Ossman clare # [ 0.925221] Synopsys Designware Multimedia Card Interface Driver clare # [ 0.925414] sdhci-pltfm: SDHCI platform and OF driver helper clare # [ 0.926377] hid: raw HID events driver (C) Jiri Kosina clare # [ 0.926514] usbcore: registered new interface driver usbhid clare # [ 0.926517] usbhid: USB HID core driver clare # [ 0.927071] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 clare # [ 0.927489] hub 1-0:1.0: USB hub found clare # [ 0.927551] hub 1-0:1.0: 6 ports detected clare # [ 0.932187] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available clare # [ 0.933395] drop_monitor: Initializing network drop monitor service clare # [ 0.933554] NET: Registered PF_INET6 protocol family clare # [ 0.934431] Segment Routing with IPv6 clare # [ 0.934442] In-situ OAM (IOAM) with IPv6 clare # [ 0.934497] NET: Registered PF_PACKET protocol family clare # [ 0.934566] 9pnet: Installing 9P2000 support clare # [ 0.934613] Key type dns_resolver registered bob # [ 0.818655] loop: module loaded bob # [ 0.818820] virtio_blk virtio2: 1/0/0 default/read/poll queues bob # [ 0.819596] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) clare # [ 0.941429] registered taskstats version 1 clare # [ 0.941633] Loading compiled-in X.509 certificates clare # [ 0.947707] Demotion targets for Node 0: null clare # [ 0.948181] Key type .fscrypt registered clare # [ 0.948184] Key type fscrypt-provisioning registered clare # [ 0.948321] ima: No TPM chip found, activating TPM-bypass! clare # [ 0.948333] ima: Allocated hash algorithm: sha1 bob # [ 0.828177] megasas: 07.734.00.00-rc1 clare # [ 0.948438] ima: No architecture policies found clare # [ 0.949116] input: gpio-keys as /devices/platform/gpio-keys/input/input0 bob # [ 0.828815] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] clare # [ 0.962871] clk: Disabling unused clocks clare # [ 0.962888] PM: genpd: Disabling unused power domains bob # [ 0.840255] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.840299] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.842118] Using buffer write method bob # [ 0.842170] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] clare # [ 0.966461] Freeing unused kernel memory: 4736K clare # [ 0.966561] Run /init as init process bob # [ 0.847003] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.847027] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.857696] Using buffer write method bob # [ 0.857723] Concatenating MTD devices: bob # [ 0.857725] (0): "0.flash" bob # [ 0.857727] (1): "0.flash" bob # [ 0.857729] into device "0.flash" clare # [ 0.990039] systemd[1]: Successfully made /usr/ read-only. alice # [ 0.987366] Freeing initrd memory: 28940K alice # [ 0.995467] tun: Universal TUN/TAP device driver, 1.6 alice # [ 0.998901] thunder_xcv, ver 1.0 alice # [ 0.998922] thunder_bgx, ver 1.0 alice # [ 0.998962] nicpf, ver 1.0 alice # [ 0.999291] e1000: Intel(R) PRO/1000 Network Driver alice # [ 0.999295] e1000: Copyright (c) 1999-2006 Intel Corporation. alice # [ 0.999314] e1000e: Intel(R) PRO/1000 Network Driver alice # [ 0.999319] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. alice # [ 0.999333] igb: Intel(R) Gigabit Ethernet Network Driver alice # [ 0.999335] igb: Copyright (c) 2007-2014 Intel Corporation. alice # [ 0.999347] igbvf: Intel(R) Gigabit Virtual Function Network Driver alice # [ 0.999350] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. alice # [ 0.999422] sky2: driver version 1.30 alice # [ 1.005350] usbcore: registered new interface driver usb-storage alice # [ 1.005448] usbcore: registered new interface driver usbserial_generic alice # [ 1.005465] usbserial: USB Serial support registered for generic alice # [ 1.005856] hv_vmbus: registering driver hyperv_keyboard alice # [ 1.008348] ehci-pci 0000:00:07.0: EHCI Host Controller alice # [ 1.008381] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 alice # [ 1.008608] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 alice # [ 1.012352] rtc-pl031 9010000.pl031: registered as rtc0 alice # [ 1.012397] rtc-pl031 9010000.pl031: setting system clock to 2026-10-16T09:37:34 UTC (1792143454) alice # [ 1.012651] i2c_dev: i2c /dev entries driver alice # [ 1.014738] sdhci: Secure Digital Host Controller Interface driver alice # [ 1.014745] sdhci: Copyright(c) Pierre Ossman alice # [ 1.014902] Synopsys Designware Multimedia Card Interface Driver alice # [ 1.015088] sdhci-pltfm: SDHCI platform and OF driver helper alice # [ 1.019691] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 alice # [ 1.020178] hub 1-0:1.0: USB hub found alice # [ 1.020822] hid: raw HID events driver (C) Jiri Kosina alice # [ 1.021009] usbcore: registered new interface driver usbhid alice # [ 1.021012] usbhid: USB HID core driver alice # [ 1.022031] hub 1-0:1.0: 6 ports detected alice # [ 1.022426] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available alice # [ 1.023296] drop_monitor: Initializing network drop monitor service alice # [ 1.023459] NET: Registered PF_INET6 protocol family alice # [ 1.028341] Segment Routing with IPv6 alice # [ 1.028367] In-situ OAM (IOAM) with IPv6 alice # [ 1.028432] NET: Registered PF_PACKET protocol family alice # [ 1.028503] 9pnet: Installing 9P2000 support alice # [ 1.028551] Key type dns_resolver registered alice # [ 1.031521] registered taskstats version 1 alice # [ 1.031734] Loading compiled-in X.509 certificates alice # [ 1.039069] Demotion targets for Node 0: null alice # [ 1.039168] Key type .fscrypt registered alice # [ 1.039171] Key type fscrypt-provisioning registered alice # [ 1.039298] ima: No TPM chip found, activating TPM-bypass! alice # [ 1.039313] ima: Allocated hash algorithm: sha1 alice # [ 1.039374] ima: No architecture policies found alice # [ 1.042293] input: gpio-keys as /devices/platform/gpio-keys/input/input0 alice # [ 1.054284] clk: Disabling unused clocks alice # [ 1.054308] PM: genpd: Disabling unused power domains alice # [ 1.058057] Freeing unused kernel memory: 4736K alice # [ 1.058203] Run /init as init process bob # [ 0.979162] Freeing initrd memory: 28936K bob # [ 0.988346] tun: Universal TUN/TAP device driver, 1.6 bob # [ 0.990890] thunder_xcv, ver 1.0 bob # [ 0.990909] thunder_bgx, ver 1.0 bob # [ 0.990918] nicpf, ver 1.0 bob # [ 0.991282] e1000: Intel(R) PRO/1000 Network Driver bob # [ 0.991287] e1000: Copyright (c) 1999-2006 Intel Corporation. bob # [ 0.991302] e1000e: Intel(R) PRO/1000 Network Driver bob # [ 0.991307] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.991320] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.991322] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.991333] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.991336] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. bob # [ 0.991402] sky2: driver version 1.30 bob # [ 0.997572] usbcore: registered new interface driver usb-storage bob # [ 0.997668] usbcore: registered new interface driver usbserial_generic bob # [ 0.997687] usbserial: USB Serial support registered for generic alice # [ 1.082533] systemd[1]: Successfully made /usr/ read-only. bob # [ 0.997996] hv_vmbus: registering driver hyperv_keyboard bob # [ 0.999274] ehci-pci 0000:00:07.0: EHCI Host Controller bob # [ 0.999308] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 bob # [ 0.999593] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 bob # [ 1.003280] rtc-pl031 9010000.pl031: registered as rtc0 bob # [ 1.003320] rtc-pl031 9010000.pl031: setting system clock to 2026-10-16T09:37:34 UTC (1792143454) bob # [ 1.003594] i2c_dev: i2c /dev entries driver bob # [ 1.006833] sdhci: Secure Digital Host Controller Interface driver bob # [ 1.006839] sdhci: Copyright(c) Pierre Ossman bob # [ 1.006999] Synopsys Designware Multimedia Card Interface Driver bob # [ 1.007202] sdhci-pltfm: SDHCI platform and OF driver helper bob # [ 1.010157] hid: raw HID events driver (C) Jiri Kosina bob # [ 1.010300] usbcore: registered new interface driver usbhid bob # [ 1.010303] usbhid: USB HID core driver bob # [ 1.011131] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available bob # [ 1.011739] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 bob # [ 1.012147] hub 1-0:1.0: USB hub found bob # [ 1.012171] hub 1-0:1.0: 6 ports detected bob # [ 1.016246] drop_monitor: Initializing network drop monitor service bob # [ 1.016361] NET: Registered PF_INET6 protocol family bob # [ 1.017032] Segment Routing with IPv6 bob # [ 1.017042] In-situ OAM (IOAM) with IPv6 bob # [ 1.017094] NET: Registered PF_PACKET protocol family bob # [ 1.017150] 9pnet: Installing 9P2000 support bob # [ 1.017183] Key type dns_resolver registered bob # [ 1.022853] registered taskstats version 1 bob # [ 1.023060] Loading compiled-in X.509 certificates bob # [ 1.029599] Demotion targets for Node 0: null bob # [ 1.029710] Key type .fscrypt registered bob # [ 1.029713] Key type fscrypt-provisioning registered bob # [ 1.029830] ima: No TPM chip found, activating TPM-bypass! bob # [ 1.029841] ima: Allocated hash algorithm: sha1 bob # [ 1.029899] ima: No architecture policies found bob # [ 1.030467] input: gpio-keys as /devices/platform/gpio-keys/input/input0 bob # [ 1.044024] clk: Disabling unused clocks bob # [ 1.044038] PM: genpd: Disabling unused power domains bob # [ 1.046604] Freeing unused kernel memory: 4736K bob # [ 1.046704] Run /init as init process clare # [ 1.175095] usb 1-1: new high-speed USB device number 2 using ehci-pci bob # [ 1.071552] systemd[1]: Successfully made /usr/ read-only. alice # [ 1.267678] usb 1-1: new high-speed USB device number 2 using ehci-pci clare # [ 1.325394] 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.325428] systemd[1]: Detected virtualization qemu. clare # [ 1.325491] systemd[1]: Detected architecture arm64. clare # [ 1.325502] systemd[1]: Running in initrd. clare # [ 1.327411] 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.341185] systemd[1]: Initializing machine ID from random generator. clare # [ 1.341695] systemd[1]: Hostname set to . bob # [ 1.263857] usb 1-1: new high-speed USB device number 2 using ehci-pci clare # [ 1.414205] 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.459842] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 1.416888] 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.416904] systemd[1]: Detected virtualization qemu. alice # [ 1.416930] systemd[1]: Detected architecture arm64. alice # [ 1.416934] systemd[1]: Running in initrd. alice # [ 1.417502] systemd[1]: Initializing machine ID from random generator. alice # [ 1.417775] systemd[1]: Hostname set to . alice # [ 1.426716] 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.520465] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 1.406362] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 1.406379] systemd[1]: Detected virtualization qemu. bob # [ 1.406415] systemd[1]: Detected architecture arm64. clare # [ 1.528877] systemd[1]: Created slice Slice /system/modprobe. bob # [ 1.406419] systemd[1]: Running in initrd. bob # [ 1.407076] systemd[1]: Initializing machine ID from random generator. clare # [ 1.529046] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 1.407263] systemd[1]: Hostname set to . clare # [ 1.529084] systemd[1]: Expecting device /dev/disk/by-label/nixos... clare # [ 1.529115] systemd[1]: Reached target Path Units. clare # [ 1.529130] systemd[1]: Reached target Slice Units. clare # [ 1.529144] systemd[1]: Reached target Swaps. clare # [ 1.529164] systemd[1]: Reached target Timer Units. clare # [ 1.529320] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 1.529434] systemd[1]: Listening on Journal Socket (/dev/log). clare # [ 1.529548] systemd[1]: Listening on Journal Sockets. clare # [ 1.529618] systemd[1]: Listening on udev Control Socket. clare # [ 1.529667] systemd[1]: Listening on udev Kernel Socket. clare # [ 1.529682] systemd[1]: Reached target Socket Units. clare # [ 1.531029] usb 1-2: new high-speed USB device number 3 using ehci-pci clare # [ 1.535460] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 1.535572] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 1.419023] 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.503858] 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.509705] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 1.547147] systemd[1]: Mounting Kernel Configuration File System... clare # [ 1.551769] systemd[1]: Starting Journal Service... clare # [ 1.574283] systemd[1]: Starting Load Kernel Modules... clare # [ 1.574386] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 1.601876] systemd-journald[71]: Collecting audit messages is disabled. clare # [ 1.607178] systemd[1]: Starting Coldplug All udev Devices... clare # [ 1.609307] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 1.572559] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.616181] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 1.496717] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 1.499870] 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.581345] systemd[1]: Created slice Slice /system/modprobe. alice # [ 1.581523] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 1.581553] systemd[1]: Expecting device /dev/disk/by-label/nixos... alice # [ 1.581575] systemd[1]: Reached target Path Units. alice # [ 1.581588] systemd[1]: Reached target Slice Units. alice # [ 1.581601] systemd[1]: Reached target Swaps. alice # [ 1.581634] systemd[1]: Reached target Timer Units. alice # [ 1.581776] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 1.581897] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 1.582029] systemd[1]: Listening on Journal Sockets. alice # [ 1.582101] systemd[1]: Listening on udev Control Socket. alice # [ 1.582148] systemd[1]: Listening on udev Kernel Socket. alice # [ 1.582162] systemd[1]: Reached target Socket Units. alice # [ 1.583488] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.583551] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 1.628132] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.598978] systemd[1]: Mounting Kernel Configuration File System... alice # [ 1.605169] systemd[1]: Starting Journal Service... alice # [ 1.612395] systemd[1]: Starting Load Kernel Modules... alice # [ 1.612494] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 1.623654] usb 1-2: new high-speed USB device number 3 using ehci-pci clare # [ 1.671611] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 1.555404] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.675930] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 1.640758] systemd[1]: Starting Coldplug All udev Devices... alice # [ 1.642769] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 1.565788] systemd[1]: Created slice Slice /system/modprobe. bob # [ 1.565967] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 1.566002] systemd[1]: Expecting device /dev/disk/by-label/nixos... bob # [ 1.566027] systemd[1]: Reached target Path Units. alice # [ 1.647915] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 1.566040] systemd[1]: Reached target Slice Units. bob # [ 1.566053] systemd[1]: Reached target Swaps. bob # [ 1.566073] systemd[1]: Reached target Timer Units. bob # [ 1.566215] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 1.690105] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. bob # [ 1.566336] systemd[1]: Listening on Journal Socket (/dev/log). bob # [ 1.566417] systemd[1]: Listening on Journal Sockets. bob # [ 1.566479] systemd[1]: Listening on udev Control Socket. bob # [ 1.566559] systemd[1]: Listening on udev Kernel Socket. bob # [ 1.566579] systemd[1]: Reached target Socket Units. clare # [ 1.694758] systemd[1]: Started Journal Service. clare # [ 1.695742] 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.575799] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 1.695849] 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.575889] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 1.577377] systemd[1]: Mounting Kernel Configuration File System... alice # [ 1.666700] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. clare # [ 1.704297] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev bob # [ 1.590459] systemd[1]: Starting Journal Service... alice # [ 1.676115] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.676768] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev clare # [ 1.708463] systemd-modules-load[73]: Inserted module 'dm_mod' clare # [ 1.713481] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 clare # [ 1.714061] [drm] features: -virgl +edid -resource_blob -host_visible clare # [ 1.714064] [drm] features: -context_init clare # [ 1.714615] [drm] number of scanouts: 1 clare # [ 1.714623] [drm] number of cap sets: 0 bob # [ 1.600558] systemd[1]: Starting Load Kernel Modules... clare # [ 1.716301] systemd-modules-load[73]: Module 'virtio_balloon' is built in clare # [ 1.717040] systemd-modules-load[73]: Module 'virtio_console' is built in bob # [ 1.600653] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 1.725708] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 1.726416] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 1.696097] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 alice # [ 1.696328] [drm] features: -virgl +edid -resource_blob -host_visible alice # [ 1.696331] [drm] features: -context_init alice # [ 1.696850] [drm] number of scanouts: 1 alice # [ 1.696859] [drm] number of cap sets: 0 clare # [ 1.739359] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic clare # [ 1.739374] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 clare # [ 1.733394] systemd[1]: Reached target Local File Systems. clare # [ 1.734323] systemd[1]: Starting Create System Files and Directories... bob # [ 1.624213] usb 1-2: new high-speed USB device number 3 using ehci-pci alice # [ 1.712167] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 1.713401] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 1.717789] systemd-journald[71]: Collecting audit messages is disabled. bob # [ 1.635828] systemd[1]: Starting Coldplug All udev Devices... alice # [ 1.720635] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic alice # [ 1.720644] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 clare # [ 1.760202] Console: switching to colour frame buffer device 160x50 clare # [ 1.760873] systemd[1]: Starting Rule-based Manager for Device Events and Files... clare # [ 1.775584] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device bob # [ 1.655747] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 1.739046] Console: switching to colour frame buffer device 160x50 clare # [ 1.777028] systemd-modules-load[73]: Inserted module 'virtio_gpu' bob # [ 1.661238] systemd-journald[71]: Collecting audit messages is disabled. clare # [ 1.777744] systemd-modules-load[73]: Module 'virtio_rng' is built in clare # [ 1.784205] systemd[1]: Finished Load Kernel Modules. bob # [ 1.674373] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. bob # [ 1.676681] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... alice # [ 1.759944] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device clare # [ 1.792163] systemd[1]: Starting Apply Kernel Variables... alice # [ 1.768160] systemd[1]: Finished Load Kernel Modules. alice # [ 1.769449] systemd[1]: Starting Apply Kernel Variables... bob # [ 1.687943] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev bob # [ 1.693012] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.782268] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 1.783515] 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.786058] 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.786937] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 1.787063] systemd[1]: Reached target Local File Systems. bob # [ 1.706565] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 bob # [ 1.706811] [drm] features: -virgl +edid -resource_blob -host_visible bob # [ 1.706813] [drm] features: -context_init bob # [ 1.707371] [drm] number of scanouts: 1 bob # [ 1.707380] [drm] number of cap sets: 0 clare # [ 1.821037] systemd[1]: Finished Create System Files and Directories. alice # [ 1.804032] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 1.724137] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 1.837870] systemd[1]: Finished Apply Kernel Variables. bob # [ 1.725484] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic bob # [ 1.725496] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 bob # [ 1.729905] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 1.841541] systemd-udevd[83]: Using default interface naming scheme 'v261'. alice # [ 1.813988] systemd-modules-load[72]: Inserted module 'dm_mod' alice # [ 1.814935] systemd-modules-load[72]: Module 'virtio_balloon' is built in alice # [ 1.815742] systemd-modules-load[72]: Module 'virtio_console' is built in alice # [ 1.819008] systemd-modules-load[72]: Inserted module 'virtio_gpu' alice # [ 1.824306] systemd[1]: Started Journal Service. alice # [ 1.821452] systemd-modules-load[72]: Module 'virtio_rng' is built in alice # [ 1.829773] systemd[1]: Finished Apply Kernel Variables. alice # [ 1.832113] systemd[1]: Starting Create System Files and Directories... bob # [ 1.749969] systemd-modules-load[72]: Inserted module 'dm_mod' bob # [ 1.752349] systemd-modules-load[72]: Module 'virtio_balloon' is built in bob # [ 1.753224] systemd-modules-load[72]: Module 'virtio_console' is built in bob # [ 1.762221] systemd[1]: Started Journal Service. clare # [ 1.873202] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 1.772900] Console: switching to colour frame buffer device 160x50 bob # [ 1.773573] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device bob # [ 1.770455] systemd-modules-load[72]: Inserted module 'virtio_gpu' bob # [ 1.773380] systemd-modules-load[72]: Module 'virtio_rng' is built in bob # [ 1.775830] systemd[1]: Finished Load Kernel Modules. bob # [ 1.782096] systemd[1]: Starting Apply Kernel Variables... bob # [ 1.793318] 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.793539] 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.792223] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 1.793155] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 1.793768] systemd[1]: Reached target Local File Systems. alice # [ 1.890486] systemd-udevd[80]: Using default interface naming scheme 'v261'. alice # [ 1.896631] systemd[1]: Finished Create System Files and Directories. bob # [ 1.813954] systemd[1]: Starting Create System Files and Directories... clare # [ 1.941762] systemd[1]: Starting Virtual Console Setup... bob # [ 1.828816] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 1.916439] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 1.836933] systemd[1]: Finished Apply Kernel Variables. bob # [ 1.868511] systemd[1]: Finished Create System Files and Directories. clare # [ 2.000610] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 2.003284] systemd[1]: Finished Virtual Console Setup. bob # [ 1.895532] systemd-udevd[85]: Using default interface naming scheme 'v261'. alice # [ 1.982077] systemd[1]: Starting Virtual Console Setup... bob # [ 1.917598] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 2.032628] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 2.035500] systemd[1]: Finished Virtual Console Setup. bob # [ 1.985977] systemd[1]: Starting Virtual Console Setup... bob # [ 2.040518] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 2.043076] systemd[1]: Finished Virtual Console Setup. clare # [ 2.210578] systemd[1]: Finished Coldplug All udev Devices. clare # [ 2.211688] systemd[1]: Reached target System Initialization. clare # [ 2.216187] systemd[1]: Reached target Basic System. alice # [ 2.253140] systemd[1]: Finished Coldplug All udev Devices. alice # [ 2.253782] systemd[1]: Reached target System Initialization. alice # [ 2.254363] systemd[1]: Reached target Basic System. clare # [ 2.326323] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.353390] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.265488] systemd[1]: Finished Coldplug All udev Devices. bob # [ 2.266128] systemd[1]: Reached target System Initialization. bob # [ 2.266677] systemd[1]: Reached target Basic System. alice # [ 2.355039] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.393945] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.437565] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.442083] systemd[1]: Reached target Initrd Root Device. clare # [ 2.443264] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... clare # [ 2.481041] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 2.363834] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.465405] systemd[1]: Found device /dev/disk/by-label/nixos. alice # [ 2.469548] systemd[1]: Reached target Initrd Root Device. bob # [ 2.389718] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.472659] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... alice # [ 2.516292] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks bob # [ 2.489916] systemd[1]: Found device /dev/disk/by-label/nixos. bob # [ 2.490718] systemd[1]: Reached target Initrd Root Device. bob # [ 2.496110] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... bob # [ 2.543804] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks clare # [ 2.680296] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 2.647007] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. bob # [ 2.564918] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 2.648173] systemd[1]: Mounting /sysroot... clare # [ 2.684182] systemd[1]: Mounting /sysroot... clare # [ 2.718758] EXT4-fs (vda): mounted filesystem 56dcb38e-425e-47d8-85ec-3d528b744938 r/w with ordered data mode. Quota mode: none. clare # [ 2.713175] systemd[1]: Mounted /sysroot. clare # [ 2.714816] systemd[1]: Reached target Initrd Root File System. alice # [ 2.687069] EXT4-fs (vda): mounted filesystem 8b4716f3-2b68-4ac2-a216-c4e011e1480a r/w with ordered data mode. Quota mode: none. alice # [ 2.683030] systemd[1]: Mounted /sysroot. clare # [ 2.719266] systemd[1]: Starting Mountpoints Configured in the Real Root... alice # [ 2.685075] systemd[1]: Reached target Initrd Root File System. alice # [ 2.688169] systemd[1]: Starting Mountpoints Configured in the Real Root... clare # [ 2.739092] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload. clare # [ 2.745774] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)... clare # [ 2.746941] systemd[1]: Reloading... bob # [ 2.630117] systemd[1]: Mounting /sysroot... alice # [ 2.715716] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 2.724133] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 2.725283] systemd[1]: Reloading... bob # [ 2.667074] EXT4-fs (vda): mounted filesystem eefe96ea-986a-4dfb-8199-7760d741e2ab r/w with ordered data mode. Quota mode: none. bob # [ 2.663320] systemd[1]: Mounted /sysroot. bob # [ 2.665266] systemd[1]: Reached target Initrd Root File System. bob # [ 2.669357] systemd[1]: Starting Mountpoints Configured in the Real Root... bob # [ 2.696440] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 2.703447] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)... bob # [ 2.704702] systemd[1]: Reloading... alice # [ 2.922302] systemd[1]: Reloading finished in 198 ms. clare # [ 2.961532] systemd[1]: Reloading finished in 212 ms. alice # [ 2.940538] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... alice # [ 2.943536] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... alice # [ 2.946326] systemd[1]: initrd-parse-etc.service: Deactivated successfully. clare # [ 2.980965] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace... alice # [ 2.947754] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 2.982434] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace... alice # [ 2.949221] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. clare # [ 2.985462] systemd[1]: initrd-parse-etc.service: Deactivated successfully. clare # [ 2.987122] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 2.988414] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. bob # [ 2.911241] systemd[1]: Reloading finished in 204 ms. bob # [ 2.936128] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace... bob # [ 2.938562] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace... bob # [ 2.941223] systemd[1]: initrd-parse-etc.service: Deactivated successfully. bob # [ 2.944620] systemd[1]: Finished Mountpoints Configured in the Real Root. bob # [ 2.946078] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. clare # [ 3.257402] fuse: init (API version 7.45) clare # [ 3.304803] virtiofs virtio6: discovered new tag: nix-store clare # [ 3.305476] virtiofs virtio6: virtio_fs_setup_dax: No cache capability clare # [ 3.308247] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory clare # [ 3.314571] (udev-worker)[93]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory clare # [ 3.338778] virtiofs virtio7: discovered new tag: shared clare # [ 3.335880] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.337950] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.338562] systemd[1]: Stopping Virtual Console Setup... clare # [ 3.350597] virtiofs virtio7: virtio_fs_setup_dax: No cache capability clare # [ 3.341746] systemd[1]: Starting Virtual Console Setup... clare # [ 3.354699] virtiofs virtio8: discovered new tag: xchg clare # [ 3.361068] virtiofs virtio8: virtio_fs_setup_dax: No cache capability alice # [ 3.340143] fuse: init (API version 7.45) clare # [ 3.377692] systemd-vconsole-setup[136]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 3.379659] systemd[1]: Finished Virtual Console Setup. alice # [ 3.355788] virtiofs virtio6: discovered new tag: nix-store alice # [ 3.356320] virtiofs virtio6: virtio_fs_setup_dax: No cache capability alice # [ 3.366199] (udev-worker)[92]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory alice # [ 3.370859] (udev-worker)[92]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory alice # [ 3.402157] virtiofs virtio7: discovered new tag: shared alice # [ 3.402786] virtiofs virtio7: virtio_fs_setup_dax: No cache capability bob # [ 3.321703] fuse: init (API version 7.45) alice # [ 3.414568] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.415549] systemd[1]: Stopped Virtual Console Setup. alice # [ 3.416255] systemd[1]: Stopping Virtual Console Setup... alice # [ 3.417859] systemd[1]: Starting Virtual Console Setup... alice # [ 3.430381] virtiofs virtio8: discovered new tag: xchg alice # [ 3.430919] virtiofs virtio8: virtio_fs_setup_dax: No cache capability alice # [ 3.434937] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.436558] systemd[1]: Stopped Virtual Console Setup. alice # [ 3.441971] systemd[1]: Starting Virtual Console Setup... bob # [ 3.374821] virtiofs virtio6: discovered new tag: nix-store bob # [ 3.375379] virtiofs virtio6: virtio_fs_setup_dax: No cache capability bob # [ 3.377169] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory bob # [ 3.381166] (udev-worker)[97]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory alice # [ 3.468660] systemd-vconsole-setup[139]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 3.394212] virtiofs virtio7: discovered new tag: shared alice # [ 3.471348] systemd[1]: Finished Virtual Console Setup. bob # [ 3.394755] virtiofs virtio7: virtio_fs_setup_dax: No cache capability bob # [ 3.405420] virtiofs virtio8: discovered new tag: xchg bob # [ 3.405978] virtiofs virtio8: virtio_fs_setup_dax: No cache capability bob # [ 3.407075] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 3.409496] systemd[1]: Stopped Virtual Console Setup. bob # [ 3.410049] systemd[1]: Stopping Virtual Console Setup... bob # [ 3.412094] systemd[1]: Starting Virtual Console Setup... bob # [ 3.435646] systemd-vconsole-setup[136]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 3.438211] systemd[1]: Finished Virtual Console Setup. clare # [ 3.602380] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 3.620310] systemd[1]: Mounting /sysroot/nix/.rw-store... clare # [ 3.632240] systemd[1]: Mounting /sysroot/run... clare # [ 3.641175] systemd[1]: Mounting /sysroot/tmp/shared... clare # [ 3.664951] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 3.669199] systemd[1]: Mounted /sysroot/nix/.rw-store. alice # [ 3.639838] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 3.686383] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.653976] systemd[1]: Mounting /sysroot/nix/.rw-store... alice # [ 3.656134] systemd[1]: Mounting /sysroot/run... clare # [ 3.691765] systemd[1]: Mounted /sysroot/nix/.ro-store. clare # [ 3.692812] systemd[1]: Mounted /sysroot/run. clare # [ 3.693921] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 3.694876] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.698198] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.699846] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 3.670171] systemd[1]: Mounting /sysroot/tmp/shared... clare # [ 3.707933] systemd[1]: Mounting /sysroot/nix/store... alice # [ 3.692844] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 3.733815] systemd[1]: Mounted /sysroot/nix/store. clare # [ 3.734633] systemd[1]: Reached target Initrd File Systems. clare # [ 3.736199] systemd[1]: Starting Find NixOS closure... clare # [ 3.743588] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 3.632587] systemd[1]: Mounting /sysroot/nix/.ro-store... bob # [ 3.640105] systemd[1]: Mounting /sysroot/nix/.rw-store... alice # [ 3.725892] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 3.645045] systemd[1]: Mounting /sysroot/run... clare # [ 3.765234] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. alice # [ 3.735132] systemd[1]: Mounted /sysroot/nix/.ro-store. clare # [ 3.779578] systemd[1]: Finished Find NixOS closure. alice # [ 3.745361] systemd[1]: Mounted /sysroot/run. clare # [ 3.780240] systemd[1]: Reached target Initrd Default Target. bob # [ 3.663078] systemd[1]: Mounting /sysroot/tmp/shared... alice # [ 3.746340] systemd[1]: Mounted /sysroot/tmp/shared. bob # [ 3.665217] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 3.784541] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 3.753199] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.755285] systemd[1]: Mounted /sysroot/tmp/xchg. bob # [ 3.673043] systemd[1]: Mounted /sysroot/nix/.ro-store. bob # [ 3.675649] systemd[1]: Mounted /sysroot/nix/.rw-store. clare # [ 3.798703] systemd[1]: Stopped target Initrd Default Target. clare # [ 3.799927] systemd[1]: Stopped target Basic System. clare # [ 3.800906] systemd[1]: Stopped target Initrd Root Device. clare # [ 3.802357] systemd[1]: Stopped target Path Units. clare # [ 3.803046] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. clare # [ 3.804721] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. clare # [ 3.807044] systemd[1]: Stopped target Slice Units. clare # [ 3.807583] systemd[1]: Stopped target Socket Units. clare # [ 3.808583] systemd[1]: Stopped target System Initialization. alice # [ 3.775861] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.777166] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.812160] systemd[1]: Stopped target Swaps. clare # [ 3.812647] systemd[1]: Stopped target Timer Units. clare # [ 3.813253] systemd[1]: dbus.socket: Deactivated successfully. clare # [ 3.813739] systemd[1]: Closed D-Bus System Message Bus Socket. clare # [ 3.814278] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. clare # [ 3.814970] systemd[1]: Stopped Find NixOS closure. clare # [ 3.815438] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 3.699562] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.815987] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 3.702900] systemd[1]: Mounted /sysroot/run. bob # [ 3.704163] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 3.821434] systemd[1]: Stopped Apply Kernel Variables. clare # [ 3.823174] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 3.706925] systemd[1]: Mounted /sysroot/tmp/xchg. clare # [ 3.826057] systemd[1]: Stopped Load Kernel Modules. clare # [ 3.826943] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. clare # [ 3.830579] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. bob # [ 3.719212] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 3.720557] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.838155] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. clare # [ 3.839875] systemd[1]: Stopped Create System Files and Directories. clare # [ 3.840808] systemd[1]: Stopped target Local File Systems. clare # [ 3.841335] systemd[1]: Stopped target Preparation for Local File Systems. clare # [ 3.841943] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. clare # [ 3.842595] systemd[1]: Stopped Coldplug All udev Devices. clare # [ 3.843111] systemd[1]: Stopping Rule-based Manager for Device Events and Files... clare # [ 3.843785] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.849239] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.850519] systemd[1]: initrd-cleanup.service: Deactivated successfully. clare # [ 3.852146] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. clare # [ 3.853067] systemd[1]: systemd-udevd.service: Deactivated successfully. clare # [ 3.856451] systemd[1]: Stopped Rule-based Manager for Device Events and Files. clare # [ 3.857139] systemd[1]: systemd-udevd.service: Consumed 1.049s CPU time over 2.086s wall clock time, 24.5M memory peak. clare # [ 3.858077] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. clare # [ 3.858996] systemd[1]: Closed udev Control Socket. clare # [ 3.859556] systemd[1]: Starting Cleanup udev Database... clare # [ 3.860261] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. clare # [ 3.860977] systemd[1]: Stopped Create Static Device Nodes in /dev. clare # [ 3.861559] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. clare # [ 3.862299] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. clare # [ 3.862958] systemd[1]: kmod-static-nodes.service: Deactivated successfully. clare # [ 3.863589] systemd[1]: Stopped Create List of Static Device Nodes. clare # [ 3.867398] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.872193] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.884756] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. clare # [ 3.885576] systemd[1]: Finished Cleanup udev Database. clare # [ 3.886094] systemd[1]: Reached target Switch Root. clare # [ 3.888983] systemd[1]: Starting NixOS Activation... clare # [ 3.988167] initrd-nixos-activation-start[171]: booting system configuration /nix/store/j4fzgfjgricvyd8zav51kvpldd2mwf76-nixos-system-clare-test clare # [ 4.022688] initrd-nixos-activation-start[171]: running activation script... clare # [ 4.333523] initrd-nixos-activation-start[194]: setting up /etc... clare # [ 4.435157] initrd-nixos-activation-start[194]: setting up age secrets... clare # [ 4.496915] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. clare # [ 4.498854] systemd[1]: Finished NixOS Activation. clare # [ 4.499677] systemd[1]: Starting Switch Root... clare # [ 4.512920] systemd[1]: Switching root. clare # [ 4.681248] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). alice # [ 4.637345] systemd[1]: Mounting /sysroot/nix/store... alice # [ 4.691202] systemd[1]: Mounted /sysroot/nix/store. alice # [ 4.693430] systemd[1]: Reached target Initrd File Systems. alice # [ 4.697393] systemd[1]: Starting Find NixOS closure... alice # [ 4.708381] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... bob # [ 4.630707] systemd[1]: Mounting /sysroot/nix/store... bob # [ 4.661556] systemd[1]: Mounted /sysroot/nix/store. bob # [ 4.662322] systemd[1]: Reached target Initrd File Systems. bob # [ 4.664913] systemd[1]: Starting Find NixOS closure... bob # [ 4.672152] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... alice # [ 4.754681] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. alice # [ 4.756411] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. alice # [ 4.772875] systemd[1]: Finished Find NixOS closure. alice # [ 4.776282] systemd[1]: Reached target Initrd Default Target. bob # [ 4.695516] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. alice # [ 4.778931] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... bob # [ 4.697370] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. bob # [ 4.709116] systemd[1]: Finished Find NixOS closure. bob # [ 4.710268] systemd[1]: Reached target Initrd Default Target. bob # [ 4.712347] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 4.810963] systemd[1]: initrd-cleanup.service: Deactivated successfully. alice # [ 4.812611] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. alice # [ 4.816160] systemd[1]: Stopped target Initrd Default Target. alice # [ 4.817066] systemd[1]: Stopped target Basic System. alice # [ 4.817773] systemd[1]: Stopped target Initrd Root Device. alice # [ 4.820269] systemd[1]: Stopped target Path Units. bob # [ 4.737920] systemd[1]: initrd-cleanup.service: Deactivated successfully. alice # [ 4.820983] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. bob # [ 4.738969] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. bob # [ 4.741415] systemd[1]: Stopped target Initrd Default Target. alice # [ 4.823797] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. bob # [ 4.744200] systemd[1]: Stopped target Basic System. bob # [ 4.744822] systemd[1]: Stopped target Initrd Root Device. alice # [ 4.827174] systemd[1]: Stopped target Slice Units. bob # [ 4.745342] systemd[1]: Stopped target Path Units. bob # [ 4.745795] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 4.829605] systemd[1]: Stopped target Socket Units. bob # [ 4.747433] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. alice # [ 4.832578] systemd[1]: Stopped target System Initialization. bob # [ 4.750877] systemd[1]: Stopped target Slice Units. bob # [ 4.752683] systemd[1]: Stopped target Socket Units. alice # [ 4.835402] systemd[1]: Stopped target Swaps. bob # [ 4.756781] systemd[1]: Stopped target System Initialization. alice # [ 4.840768] systemd[1]: Stopped target Timer Units. bob # [ 4.758484] systemd[1]: Stopped target Swaps. bob # [ 4.758981] systemd[1]: Stopped target Timer Units. alice # [ 4.841404] systemd[1]: dbus.socket: Deactivated successfully. alice # [ 4.842179] systemd[1]: Closed D-Bus System Message Bus Socket. alice # [ 4.842770] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. alice # [ 4.843522] systemd[1]: Stopped Find NixOS closure. bob # [ 4.761955] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 4.762554] systemd[1]: Closed D-Bus System Message Bus Socket. bob # [ 4.763829] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 4.765392] systemd[1]: Stopped Find NixOS closure. alice # [ 4.847991] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 4.765961] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 4.848945] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 4.766502] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 4.767085] systemd[1]: Stopped Apply Kernel Variables. alice # [ 4.849617] systemd[1]: Stopped Apply Kernel Variables. bob # [ 4.767572] systemd[1]: systemd-modules-load.service: Deactivated successfully. alice # [ 4.850415] systemd[1]: systemd-modules-load.service: Deactivated successfully. alice # [ 4.851098] systemd[1]: Stopped Load Kernel Modules. alice # [ 4.851581] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 4.855160] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. alice # [ 4.855968] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 4.775492] systemd[1]: Stopped Load Kernel Modules. bob # [ 4.776332] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 4.862071] systemd[1]: Stopped Create System Files and Directories. bob # [ 4.779340] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. bob # [ 4.783743] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 4.785025] systemd[1]: Stopped Create System Files and Directories. bob # [ 4.785628] systemd[1]: Stopped target Local File Systems. alice # [ 4.868219] systemd[1]: Stopped target Local File Systems. bob # [ 4.786137] systemd[1]: Stopped target Preparation for Local File Systems. alice # [ 4.868972] systemd[1]: Stopped target Preparation for Local File Systems. bob # [ 4.786723] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. bob # [ 4.787350] systemd[1]: Stopped Coldplug All udev Devices. bob # [ 4.787809] systemd[1]: Stopping Rule-based Manager for Device Events and Files... alice # [ 4.871508] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 4.872827] systemd[1]: Stopped Coldplug All udev Devices. bob # [ 4.790279] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 4.790983] systemd[1]: Stopped Virtual Console Setup. alice # [ 4.873649] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 4.791482] systemd[1]: systemd-udevd.service: Deactivated successfully. alice # [ 4.874389] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 4.875618] systemd[1]: Stopped Virtual Console Setup. alice # [ 4.876226] systemd[1]: systemd-udevd.service: Deactivated successfully. alice # [ 4.876848] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 4.877533] systemd[1]: systemd-udevd.service: Consumed 1.082s CPU time over 3.058s wall clock time, 22.5M memory peak. bob # [ 4.795478] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 4.878480] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 4.879162] systemd[1]: Closed udev Control Socket. bob # [ 4.796315] systemd[1]: systemd-udevd.service: Consumed 1.051s CPU time over 2.945s wall clock time, 24.2M memory peak. alice # [ 4.879619] systemd[1]: Starting Cleanup udev Database... bob # [ 4.800529] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. bob # [ 4.801432] systemd[1]: Closed udev Control Socket. bob # [ 4.801927] systemd[1]: Starting Cleanup udev Database... bob # [ 4.802434] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. alice # [ 4.885414] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 4.803111] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 4.886457] systemd[1]: Stopped Create Static Device Nodes in /dev. alice # [ 4.888409] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. bob # [ 4.804435] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. alice # [ 4.892269] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. alice # [ 4.893000] systemd[1]: kmod-static-nodes.service: Deactivated successfully. alice # [ 4.893612] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 4.894217] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 4.812261] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. bob # [ 4.812936] systemd[1]: kmod-static-nodes.service: Deactivated successfully. bob # [ 4.813580] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 4.896331] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 4.814173] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 4.814786] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 4.824213] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. alice # [ 4.907124] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 4.826482] systemd[1]: Finished Cleanup udev Database. bob # [ 4.828128] systemd[1]: Reached target Switch Root. alice # [ 4.911105] systemd[1]: Finished Cleanup udev Database. bob # [ 4.828646] systemd[1]: Starting NixOS Activation... alice # [ 4.911713] systemd[1]: Reached target Switch Root. alice # [ 4.913019] systemd[1]: Starting NixOS Activation... bob # [ 4.899224] initrd-nixos-activation-start[172]: booting system configuration /nix/store/g481r9hzfzlyykghr4xgmgb8b9rc5pbb-nixos-system-bob-test alice # [ 4.997377] initrd-nixos-activation-start[174]: booting system configuration /nix/store/z9f82k8cch6hhsyq4na4bfcwhpbkripm-nixos-system-alice-test bob # [ 4.925617] initrd-nixos-activation-start[172]: running activation script... alice # [ 5.025493] initrd-nixos-activation-start[174]: running activation script... bob # [ 5.205821] initrd-nixos-activation-start[195]: setting up /etc... alice # [ 5.292791] initrd-nixos-activation-start[197]: setting up /etc... bob # [ 5.300092] initrd-nixos-activation-start[195]: setting up age secrets... alice # [ 5.388386] initrd-nixos-activation-start[197]: setting up age secrets... bob # [ 5.375686] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 5.377823] systemd[1]: Finished NixOS Activation. alice # [ 5.460256] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 5.378678] systemd[1]: Starting Switch Root... alice # [ 5.462334] systemd[1]: Finished NixOS Activation. alice # [ 5.463162] systemd[1]: Starting Switch Root... bob # [ 5.394765] systemd[1]: Switching root. alice # [ 5.488381] systemd[1]: Switching root. bob # [ 5.570087] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). alice # [ 5.666106] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). bob # [ 11.239511] 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 # [ 11.239562] systemd[1]: Detected virtualization qemu. bob # [ 11.243582] systemd[1]: Detected architecture arm64. bob # [ 11.244171] systemd[1]: Detected first boot. bob # [ 11.246145] systemd[1]: Initializing machine ID from random generator. alice # [ 11.333850] 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 # [ 11.333897] systemd[1]: Detected virtualization qemu. alice # [ 11.333943] systemd[1]: Detected architecture arm64. alice # [ 11.334029] systemd[1]: Detected first boot. clare # [ 11.371354] 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 # [ 11.371404] systemd[1]: Detected virtualization qemu. clare # [ 11.371451] systemd[1]: Detected architecture arm64. clare # [ 11.371550] systemd[1]: Detected first boot. clare # [ 11.373772] systemd[1]: Initializing machine ID from random generator. alice # [ 11.346405] systemd[1]: Initializing machine ID from random generator. clare # [ 11.474826] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 11.440077] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 11.361853] systemd[1]: bpf-restrict-fs: LSM BPF program attached bob # [ 11.472884] systemd[1]: Applying preset policy. clare # [ 11.614510] systemd[1]: Applying preset policy. alice # [ 11.592149] systemd[1]: Applying preset policy. clare # [ 11.765428] systemd[1]: Populated /etc with preset unit settings. bob # [ 11.652903] systemd[1]: Populated /etc with preset unit settings. alice # [ 11.760930] systemd[1]: Populated /etc with preset unit settings. clare # [ 11.998414] systemd[1]: initrd-switch-root.service: Deactivated successfully. clare # [ 12.000263] systemd[1]: Stopped initrd-switch-root.service. clare # [ 12.001903] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. clare # [ 12.006084] systemd[1]: Created slice Slice /system/getty. clare # [ 12.006845] systemd[1]: Created slice User and Session Slice. alice # [ 11.971907] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 11.972721] systemd[1]: Stopped initrd-switch-root.service. alice # [ 11.975147] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 11.892250] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 11.892700] systemd[1]: Stopped initrd-switch-root.service. clare # [ 12.008620] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 11.895085] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. clare # [ 12.009204] systemd[1]: Started Forward Password Requests to Wall Directory Watch. clare # [ 12.009671] systemd[1]: Expecting device /dev/hvc0... clare # [ 12.010013] systemd[1]: Expecting device /dev/ttyAMA0... clare # [ 12.010327] systemd[1]: Reached target Local Encrypted Volumes. clare # [ 12.010635] systemd[1]: Stopped target initrd-fs.target. clare # [ 12.010931] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 11.899070] systemd[1]: Created slice Slice /system/getty. alice # [ 11.980008] systemd[1]: Created slice Slice /system/getty. alice # [ 11.981502] systemd[1]: Created slice User and Session Slice. alice # [ 11.982209] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 11.982658] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 11.983050] systemd[1]: Expecting device /dev/hvc0... bob # [ 11.902080] systemd[1]: Created slice User and Session Slice. alice # [ 11.983342] systemd[1]: Expecting device /dev/ttyAMA0... clare # [ 12.021361] systemd[1]: Stopped target initrd-switch-root.target. bob # [ 11.902915] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 12.022026] systemd[1]: Reached target Virtual Machines and Containers. clare # [ 12.022466] systemd[1]: Reached target Path Units. clare # [ 12.022793] systemd[1]: Reached target Remote File Systems. bob # [ 11.905673] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 11.906423] systemd[1]: Expecting device /dev/hvc0... bob # [ 11.907243] systemd[1]: Expecting device /dev/ttyAMA0... alice # [ 11.988143] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 11.988892] systemd[1]: Stopped target initrd-fs.target. alice # [ 11.989842] systemd[1]: Stopped target initrd-root-fs.target. clare # [ 12.028874] systemd[1]: Reached target Slice Units. clare # [ 12.029500] systemd[1]: Reached target Swaps. alice # [ 11.990631] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 11.991409] systemd[1]: Reached target Virtual Machines and Containers. bob # [ 11.910456] systemd[1]: Reached target Local Encrypted Volumes. bob # [ 11.911201] systemd[1]: Stopped target initrd-fs.target. bob # [ 11.913689] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 11.914377] systemd[1]: Stopped target initrd-switch-root.target. alice # [ 11.995674] systemd[1]: Reached target Path Units. bob # [ 11.915158] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 11.996457] systemd[1]: Reached target Remote File Systems. alice # [ 11.997345] systemd[1]: Reached target Slice Units. alice # [ 11.998127] systemd[1]: Reached target Swaps. bob # [ 11.918119] systemd[1]: Reached target Path Units. bob # [ 11.918752] systemd[1]: Reached target Remote File Systems. bob # [ 11.919435] systemd[1]: Reached target Slice Units. clare # [ 12.042631] systemd[1]: Listening on Query the User Interactively for a Password. bob # [ 11.922294] systemd[1]: Reached target Swaps. alice # [ 12.003937] systemd[1]: Listening on Query the User Interactively for a Password. alice # [ 12.007284] systemd[1]: Listening on Process Core Dump Socket. clare # [ 12.046499] systemd[1]: Listening on Process Core Dump Socket. bob # [ 11.925749] systemd[1]: Listening on Query the User Interactively for a Password. alice # [ 12.011141] systemd[1]: Listening on Credential Encryption/Decryption. clare # [ 12.049898] systemd[1]: Listening on Credential Encryption/Decryption. bob # [ 11.930971] systemd[1]: Listening on Process Core Dump Socket. alice # [ 12.014311] systemd[1]: Listening on Factory Reset Management. alice # [ 12.015365] systemd[1]: Listening on Hostname Service Socket. clare # [ 12.053393] systemd[1]: Listening on Factory Reset Management. clare # [ 12.054299] systemd[1]: Listening on Hostname Service Socket. bob # [ 11.934558] systemd[1]: Listening on Credential Encryption/Decryption. bob # [ 11.938226] systemd[1]: Listening on Factory Reset Management. bob # [ 11.939188] systemd[1]: Listening on Hostname Service Socket. alice # [ 12.021299] systemd[1]: Starting Journal Log Access Socket... clare # [ 12.060743] systemd[1]: Starting Journal Log Access Socket... clare # [ 12.062816] systemd[1]: Listening on Journal Audit Socket. alice # [ 12.024311] systemd[1]: Listening on Journal Audit Socket. bob # [ 11.944437] systemd[1]: Starting Journal Log Access Socket... bob # [ 11.946615] systemd[1]: Listening on Journal Audit Socket. alice # [ 12.029320] systemd[1]: Listening on Console Output Muting Service Socket. alice # [ 12.031542] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 12.069619] systemd[1]: Listening on Console Output Muting Service Socket. bob # [ 11.951522] systemd[1]: Listening on Console Output Muting Service Socket. alice # [ 12.035211] systemd[1]: Listening on Network Management Varlink Socket. clare # [ 12.073469] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 12.074538] systemd[1]: Listening on Network Management Varlink Socket. bob # [ 11.953955] systemd[1]: Listening on Network Management Metrics Varlink Socket. alice # [ 12.037957] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 12.038995] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. clare # [ 12.077696] systemd[1]: Listening on Network Management Netlink Socket. clare # [ 12.078924] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 11.957464] systemd[1]: Listening on Network Management Varlink Socket. bob # [ 11.958488] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 11.959572] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. alice # [ 12.042127] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 12.042773] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki clare # [ 12.081450] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os bob # [ 11.962901] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os clare # [ 12.082008] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki bob # [ 11.963523] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki bob # [ 11.969867] systemd[1]: Listening on Disk Repartitioning Service Socket. bob # [ 11.970694] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 11.971397] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 12.054374] systemd[1]: Listening on Disk Repartitioning Service Socket. alice # [ 12.055221] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 11.974021] systemd[1]: Listening on udev Control Socket. bob # [ 11.974812] systemd[1]: Listening on udev Varlink Socket. alice # [ 12.057428] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 12.059163] systemd[1]: Listening on udev Control Socket. clare # [ 12.095076] systemd[1]: Listening on Disk Repartitioning Service Socket. clare # [ 12.095909] systemd[1]: Listening on Resolve Monitor Varlink Socket. clare # [ 12.096494] systemd[1]: Listening on Resolve Service Varlink Socket. clare # [ 12.096928] systemd[1]: Listening on udev Control Socket. clare # [ 12.097338] systemd[1]: Listening on udev Varlink Socket. alice # [ 12.061254] systemd[1]: Listening on udev Varlink Socket. bob # [ 11.983298] systemd[1]: Mounting Huge Pages File System... alice # [ 12.066735] systemd[1]: Mounting Huge Pages File System... clare # [ 12.109236] systemd[1]: Mounting Huge Pages File System... alice # [ 12.072156] systemd[1]: Mounting POSIX Message Queue File System... clare # [ 12.113912] systemd[1]: Mounting POSIX Message Queue File System... bob # [ 11.993133] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 12.084004] systemd[1]: Mounting Kernel Debug File System... bob # [ 12.007430] systemd[1]: Mounting Kernel Debug File System... clare # [ 12.131331] systemd[1]: Mounting Kernel Debug File System... alice # [ 12.101055] systemd[1]: Mounting Kernel Trace File System... clare # [ 12.143081] systemd[1]: Mounting Kernel Trace File System... bob # [ 12.024073] systemd[1]: Mounting Kernel Trace File System... alice # [ 12.111014] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 12.114829] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 12.153619] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 12.161154] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 12.044005] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 12.049269] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 12.131848] systemd[1]: Mounting Kernel Configuration File System... alice # [ 12.138662] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm clare # [ 12.175744] systemd[1]: Mounting Kernel Configuration File System... clare # [ 12.176253] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm alice # [ 12.139313] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 12.176592] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 12.176918] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 12.144717] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 12.064305] systemd[1]: Mounting Kernel Configuration File System... bob # [ 12.072831] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm bob # [ 12.073477] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore bob # [ 12.089992] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 12.214181] systemd[1]: Mounting FUSE Control File System... alice # [ 12.175908] systemd[1]: Mounting FUSE Control File System... alice # [ 12.176642] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 clare # [ 12.216266] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 bob # [ 12.107751] systemd[1]: Mounting FUSE Control File System... bob # [ 12.109418] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 bob # [ 12.120966] systemd[1]: Starting Journal Service... alice # [ 12.207129] systemd[1]: Starting Journal Service... clare # [ 12.247671] systemd[1]: Starting Journal Service... bob # [ 12.135255] systemd[1]: Starting Load Kernel Modules... clare # [ 12.260288] systemd[1]: Starting Load Kernel Modules... alice # [ 12.224715] systemd[1]: Starting Load Kernel Modules... bob # [ 12.149448] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 12.281226] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 12.287558] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 12.288047] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 12.252502] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 12.299809] systemd[1]: Starting Coldplug All udev Devices... bob # [ 12.186471] systemd[1]: Starting Remount Root and Kernel File Systems... bob # [ 12.186997] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 12.310525] systemd[1]: Listening on Journal Log Access Socket. alice # [ 12.273352] systemd[1]: Starting Remount Root and Kernel File Systems... alice # [ 12.273954] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 12.280047] systemd-journald[282]: Collecting audit messages is enabled. bob # [ 12.203545] systemd-journald[288]: Collecting audit messages is enabled. alice # [ 12.281488] systemd[1]: Queued start job for default target Multi-User System. alice # [ 12.283668] systemd[1]: systemd-journald.service: Deactivated successfully. alice # [ 12.293083] systemd[1]: Starting Coldplug All udev Devices... alice # [ 12.295480] systemd[1]: Started Journal Service. bob # [ 12.212588] systemd[1]: Queued start job for default target Multi-User System. bob # [ 12.213553] systemd[1]: systemd-journald.service: Deactivated successfully. clare # [ 12.344516] systemd[1]: Mounted Huge Pages File System. clare # [ 12.345178] systemd[1]: Mounted POSIX Message Queue File System. clare # [ 12.345696] systemd[1]: Mounted Kernel Debug File System. clare # [ 12.346135] systemd[1]: Mounted Kernel Trace File System. clare # [ 12.355674] systemd-journald[279]: Collecting audit messages is enabled. bob # [ 12.236017] systemd[1]: Starting Coldplug All udev Devices... bob # [ 12.238273] systemd[1]: Started Journal Service. alice # [ 12.317982] systemd[1]: Listening on Journal Log Access Socket. clare # [ 12.363678] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 12.361661] systemd[1]: Queued start job for default target Multi-User System. bob # [ 12.244747] systemd[1]: Listening on Journal Log Access Socket. alice # [ 12.330613] systemd[1]: Mounted Huge Pages File System. bob # [ 12.248548] systemd[1]: Mounted Huge Pages File System. alice # [ 12.331262] systemd[1]: Mounted POSIX Message Queue File System. alice # [ 12.331843] systemd[1]: Mounted Kernel Debug File System. clare # [ 12.367428] systemd[1]: systemd-journald.service: Deactivated successfully.[ 12.375452] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 12.376630] systemd[1]: Started Journal Service. clare # clare # [ 12.371845] systemd-modules-load[280]: Module 'atkbd' is built in alice # [ 12.338106] systemd[1]: Mounted Kernel Trace File System. alice # [ 12.338705] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 12.347110] systemd-modules-load[283]: Module 'atkbd' is built in bob # [ 12.265934] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 12.267077] systemd[1]: Mounted Kernel Debug File System. clare # [ 12.384770] systemd-modules-load[280]: Module 'loop' is built in alice # [ 12.351530] systemd-modules-load[283]: Module 'loop' is built in bob # [ 12.280227] systemd[1]: Mounted Kernel Trace File System. bob # [ 12.282114] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 12.401533] systemd[1]: Mounted Kernel Configuration File System. clare # [ 12.403329] systemd[1]: Mounted FUSE Control File System. bob # [ 12.290103] systemd-modules-load[289]: Module 'atkbd' is built in alice # [ 12.374321] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 12.410416] systemd[1]: Finished Load Kernel Modules. bob # [ 12.297898] systemd-modules-load[289]: Module 'loop' is built in alice # [ 12.386189] systemd[1]: Finished Load Kernel Modules. bob # [ 12.305181] systemd[1]: Finished Load Kernel Modules. alice # [ 12.395936] EXT4-fs (vda): re-mounted 8b4716f3-2b68-4ac2-a216-c4e011e1480a. alice # [ 12.396150] systemd[1]: Starting Firewall... bob # [ 12.325813] systemd[1]: Starting Firewall... bob # [ 12.334051] EXT4-fs (vda): re-mounted eefe96ea-986a-4dfb-8199-7760d741e2ab. alice # [ 12.411889] systemd-oomd[285]: No swap; memory pressure usage will be degraded clare # [ 12.456239] systemd[1]: Starting Firewall... bob # [ 12.340974] systemd[1]: Starting Apply Kernel Variables... alice # [ 12.424481] systemd[1]: Starting Apply Kernel Variables... clare # [ 12.462789] systemd[1]: Starting Apply Kernel Variables... alice # [ 12.428238] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. alice # [ 12.430027] systemd[1]: Mounted Kernel Configuration File System. alice # [ 12.433421] systemd[1]: Finished Remount Root and Kernel File Systems. clare # [ 12.475107] EXT4-fs (vda): re-mounted 56dcb38e-425e-47d8-85ec-3d528b744938. bob # [ 12.353524] systemd-oomd[290]: No swap; memory pressure usage will be degraded alice # [ 12.438374] systemd[1]: Listening on Disk Image Download Service Socket. clare # [ 12.480763] systemd[1]: Finished Remount Root and Kernel File Systems. alice # [ 12.446850] systemd[1]: Starting Flush Journal to Persistent Storage... alice # [ 12.447523] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 12.371513] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 12.372590] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 12.375868] systemd[1]: Finished Remount Root and Kernel File Systems. clare # [ 12.496513] systemd-oomd[281]: No swap; memory pressure usage will be degraded bob # [ 12.381610] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 12.467867] systemd[1]: Starting Load/Save OS Random Seed... clare # [ 12.504457] systemd[1]: Listening on Disk Image Download Service Socket. alice # [ 12.473326] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 12.393546] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 12.394337] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore clare # [ 12.518849] systemd[1]: Starting Flush Journal to Persistent Storage... clare # [ 12.519573] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 12.411138] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 12.411864] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 12.532506] systemd[1]: Starting Load/Save OS Random Seed... clare # [ 12.533127] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 12.534031] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 12.421052] systemd[1]: Mounted Kernel Configuration File System. alice # [ 12.514200] systemd[1]: Mounted FUSE Control File System. clare # [ 12.584312] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 12.596306] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 12.568009] systemd-journald[282]: Received client request to flush runtime journal. bob # [ 12.482569] systemd[1]: Mounted FUSE Control File System. bob # [ 12.504341] systemd[1]: Finished Apply Kernel Variables. clare # [ 12.632622] systemd-journald[279]: Received client request to flush runtime journal. bob # [ 12.516361] systemd[1]: Starting Network Name Resolution... bob # [ 12.546719] systemd-journald[288]: Received client request to flush runtime journal. alice # [ 12.639331] systemd[1]: Finished Load/Save OS Random Seed. alice # [ 12.639977] systemd[1]: Reached target First Boot Complete. alice # [ 12.645322] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 12.646174] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 12.689492] systemd[1]: Finished Apply Kernel Variables. alice # [ 12.656763] systemd[1]: Finished Apply Kernel Variables. alice # [ 12.659588] systemd[1]: Starting Network Name Resolution... clare # [ 12.695003] systemd[1]: Finished Load/Save OS Random Seed. clare # [ 12.695643] systemd[1]: Reached target First Boot Complete. alice # [ 12.664707] systemd[1]: Finished Flush Journal to Persistent Storage. clare # [ 12.702160] systemd[1]: Starting Network Name Resolution... clare # [ 12.702768] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 12.634542] systemd[1]: Finished Load/Save OS Random Seed. bob # [ 12.635163] systemd[1]: Reached target First Boot Complete. bob # [ 12.646050] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 12.657413] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 12.658180] systemd[1]: Finished Flush Journal to Persistent Storage. clare # [ 12.812185] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 12.812952] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 12.816779] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 12.704683] systemd-resolved[314]: Positive Trust Anchors: bob # [ 12.705574] systemd-resolved[314]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d bob # [ 12.706602] systemd-resolved[314]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 bob # [ 12.707526] systemd-resolved[314]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 12.795546] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 12.800539] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 12.804168] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 12.808611] systemd-resolved[316]: Positive Trust Anchors: clare # [ 12.841389] systemd-resolved[310]: Positive Trust Anchors: alice # [ 12.809263] systemd-resolved[316]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d alice # [ 12.810185] systemd-resolved[316]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 clare # [ 12.841992] systemd-resolved[310]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d clare # [ 12.842852] systemd-resolved[310]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 alice # [ 12.811071] 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 clare # [ 12.843735] systemd-resolved[310]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test bob # [ 12.836074] systemd[1]: Finished Create Static Device Nodes in /dev. bob # [ 12.836793] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 12.847669] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 12.873022] systemd-resolved[314]: Using system hostname 'bob'. clare # [ 12.997107] systemd[1]: Mounting /run/wrappers... alice # [ 12.972113] systemd[1]: Mounting /run/wrappers... bob # [ 12.891887] systemd[1]: Mounting /run/wrappers... bob # [ 12.896320] systemd[1]: Started Network Name Resolution. clare # [ 13.014886] systemd-udevd[316]: Using default interface naming scheme 'v261'. clare # [ 13.037725] systemd-resolved[310]: Using system hostname 'clare'. alice # [ 13.012951] systemd-resolved[316]: Using system hostname 'alice'. alice # [ 13.016510] systemd[1]: Started Network Name Resolution. clare # [ 13.056258] systemd[1]: Started Network Name Resolution. alice # [ 13.024712] systemd-udevd[323]: Using default interface naming scheme 'v261'. bob # [ 12.969783] systemd[1]: Mounted /run/wrappers. bob # [ 12.970331] systemd[1]: Reached target Local File Systems. clare # [ 13.090331] systemd[1]: Mounted /run/wrappers. clare # [ 13.090920] systemd[1]: Reached target Local File Systems. bob # [ 12.974430] systemd[1]: Listening on Boot Loader Control Service Socket. alice # [ 13.057116] systemd[1]: Mounted /run/wrappers. alice # [ 13.057753] systemd[1]: Reached target Local File Systems. clare # [ 13.098955] systemd[1]: Listening on Boot Loader Control Service Socket. bob # [ 12.984118] systemd[1]: Starting register-nix-paths.service... clare # [ 13.104108] systemd[1]: Starting register-nix-paths.service... alice # [ 13.069712] systemd[1]: Listening on Boot Loader Control Service Socket. bob # [ 12.988153] systemd[1]: Starting Create SUID/SGID Wrappers... bob # [ 12.990413] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. clare # [ 13.107894] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 13.073622] systemd[1]: Starting register-nix-paths.service... clare # [ 13.108915] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. bob # [ 12.994053] systemd[1]: Starting Save Transient machine-id to Disk... alice # [ 13.077740] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 13.078372] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. clare # [ 13.116172] systemd[1]: Starting Save Transient machine-id to Disk... alice # [ 13.086431] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 13.004311] systemd[1]: Starting Create System Files and Directories... alice # [ 13.088871] systemd[1]: Starting Create System Files and Directories... clare # [ 13.124928] systemd[1]: Starting Create System Files and Directories... bob # [ 13.026869] systemd-udevd[331]: Using default interface naming scheme 'v261'. clare # [ 13.202319] systemd[1]: Finished Coldplug All udev Devices. alice # [ 13.179194] systemd[1]: Finished Coldplug All udev Devices. bob # [ 13.112216] systemd[1]: Finished Coldplug All udev Devices. clare # [ 13.260762] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 13.255215] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 13.196914] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 13.231392] systemd[1]: Finished Create System Files and Directories. bob # [ 13.237791] systemd[1]: Starting Rebuild Journal Catalog... clare # [ 13.358407] systemd[1]: Finished Create System Files and Directories. bob # [ 13.241986] systemd[1]: Starting Record System Boot/Shutdown in UTMP... clare # [ 13.370477] systemd[1]: Starting Rebuild Journal Catalog... alice # [ 13.342223] systemd[1]: Finished Create System Files and Directories. clare # [ 13.377381] systemd[1]: Starting Record System Boot/Shutdown in UTMP... alice # [ 13.355700] systemd[1]: Starting Rebuild Journal Catalog... alice # [ 13.362460] systemd[1]: Starting Record System Boot/Shutdown in UTMP... bob # [ 13.375407] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 13.502824] systemd[1]: Finished Record System Boot/Shutdown in UTMP. bob # [ 13.388931] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 13.400421] systemd[1]: Starting Update is Completed... alice # [ 13.496685] systemd[1]: Finished Record System Boot/Shutdown in UTMP. bob # [ 13.435921] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. clare # [ 13.554072] systemd[1]: Finished Rebuild Journal Catalog. clare # [ 13.558916] systemd[1]: Starting Update is Completed... alice # [ 13.524654] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 13.442439] systemd[1]: Finished Save Transient machine-id to Disk. clare # [ 13.559594] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. alice # [ 13.528981] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. clare # [ 13.568304] systemd[1]: Finished Save Transient machine-id to Disk. alice # [ 13.546865] systemd[1]: Starting Update is Completed... alice # [ 13.547607] systemd[1]: Finished Save Transient machine-id to Disk. bob # [ 13.480681] systemd[1]: Finished Update is Completed. clare # [ 13.652198] systemd[1]: Finished Update is Completed. alice # [ 13.643215] systemd[1]: Finished Update is Completed. bob # [ 13.610442] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 13.746305] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 13.737352] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 13.660485] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse clare # [ 13.782839] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse alice # [ 13.767090] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse bob # [ 13.840148] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. clare # [ 13.974976] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. alice # [ 13.946342] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. bob # [ 13.871039] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. alice # [ 14.029398] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. clare # [ 14.067724] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. clare # [ 14.114987] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. clare # [ 14.119710] systemd[1]: Finished Create SUID/SGID Wrappers. alice # [ 14.158282] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. bob # [ 14.075460] (udev-worker)[410]: Network interface NamePolicy= disabled on kernel command line. bob # [ 14.082280] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. alice # [ 14.165472] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 14.089570] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 14.093980] (udev-worker)[403]: Network interface NamePolicy= disabled on kernel command line. alice # [ 14.193351] (udev-worker)[394]: Network interface NamePolicy= disabled on kernel command line. alice # [ 14.199512] (udev-worker)[403]: Network interface NamePolicy= disabled on kernel command line. clare # [ 14.267647] (udev-worker)[382]: Network interface NamePolicy= disabled on kernel command line. clare # [ 14.271520] (udev-worker)[391]: Network interface NamePolicy= disabled on kernel command line. clare # [ 14.283071] systemd[1]: Finished register-nix-paths.service. bob # [ 14.168122] systemd[1]: Finished register-nix-paths.service. clare # [ 14.286087] systemd[1]: Reached target System Initialization. clare # [ 14.286733] systemd[1]: Started Discard unused filesystem blocks once a week. clare # [ 14.287431] systemd[1]: Started Daily Cleanup of Temporary Directories. bob # [ 14.171296] systemd[1]: Reached target System Initialization. bob # [ 14.174373] systemd[1]: Started Discard unused filesystem blocks once a week. clare # [ 14.292908] systemd[1]: Reached target Timer Units. bob # [ 14.175104] systemd[1]: Started Daily Cleanup of Temporary Directories. clare # [ 14.293449] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 14.262242] systemd[1]: Finished register-nix-paths.service. alice # [ 14.262927] systemd[1]: Reached target System Initialization. alice # [ 14.263502] systemd[1]: Started Discard unused filesystem blocks once a week. bob # [ 14.175720] systemd[1]: Reached target Timer Units. bob # [ 14.182492] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 14.183131] systemd[1]: Listening on Nix Daemon Socket. bob # [ 14.183639] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 14.267285] systemd[1]: Started Daily Cleanup of Temporary Directories. alice # [ 14.267929] systemd[1]: Reached target Timer Units. clare # [ 14.302433] systemd[1]: Listening on harmonia binary cache socket. clare # [ 14.303139] systemd[1]: Listening on Nix Daemon Socket. clare # [ 14.303682] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 14.271494] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 14.191072] systemd[1]: Reached target Socket Units. bob # [ 14.191604] systemd[1]: Reached target Basic System. alice # [ 14.275263] systemd[1]: Listening on Nix Daemon Socket. clare # [ 14.310780] systemd[1]: Reached target Socket Units. clare # [ 14.311302] systemd[1]: Reached target Basic System. clare # [ 14.311787] systemd[1]: Started backdoor.service. alice # [ 14.278013] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 14.281733] systemd[1]: Reached target Socket Units. alice # [ 14.282309] systemd[1]: Reached target Basic System. alice # [ 14.282889] systemd[1]: Started backdoor.service. alice # [ 14.283347] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 14.201943] systemd[1]: Started backdoor.service. bob # [ 14.202464] systemd[1]: Starting Import lastlog data into lastlog2 database... clare # [ 14.323114] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 14.207651] systemd[1]: Starting Name Service Cache Daemon (nsncd)... alice # [ 14.292915] systemd[1]: Starting Name Service Cache Daemon (nsncd)... clare # [ 14.330761] systemd[1]: Starting Name Service Cache Daemon (nsncd)... alice # [ 14.314495] systemd[1]: Starting Post-Boot Actions... bob # [ 14.232194] systemd[1]: Starting Post-Boot Actions... clare # [ 14.354339] systemd[1]: Starting Post-Boot Actions... alice # [ 14.346709] systemd[1]: Started Reset console on configuration changes. bob # [ 14.264550] systemd[1]: Started Reset console on configuration changes. clare # [ 14.395820] systemd[1]: Started Reset console on configuration changes. alice # [ 14.400070] systemd[1]: Starting D-Bus System Message Bus... bob # [ 14.324195] systemd[1]: Starting D-Bus System Message Bus... alice # [ 14.415854] systemd[1]: Finished Post-Boot Actions. clare # [ 14.460106] systemd[1]: Starting D-Bus System Message Bus... bob # connecting to host... alice # connecting to host... bob # [ 14.394635] systemd[1]: Finished Post-Boot Actions. clare # [ 14.522677] nsncd[464]: Oct 16 09:37:48.114 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" bob # [ 14.413925] systemd[1]: Started Name Service Cache Daemon (nsncd). bob # [ 14.414891] nsncd[472]: Oct 16 09:37:47.913 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # connecting to host... clare # [ 14.536786] systemd[1]: Finished Post-Boot Actions. clare # [ 14.540221] systemd[1]: Started Name Service Cache Daemon (nsncd). alice # [ 14.509287] systemd[1]: Started Name Service Cache Daemon (nsncd). bob # [ 14.430560] systemd[1]: Reached target Host and Network Name Lookups. bob # [ 14.431405] systemd[1]: Reached target User and Group Name Lookups. alice # [ 14.520100] systemd[1]: Reached target Host and Network Name Lookups. alice # [ 14.521071] nsncd[467]: Oct 16 09:37:48.007 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" alice # [ 14.522396] systemd[1]: Reached target User and Group Name Lookups. clare # [ 14.557532] systemd[1]: Reached target Host and Network Name Lookups. clare # [ 14.568397] systemd[1]: Reached target User and Group Name Lookups. bob # [ 14.452758] systemd[1]: Starting User Login Management... alice # [ 14.541918] systemd[1]: Starting User Login Management... bob: Guest shell says: b'Spawning backdoor root shell...\n' clare # [ 14.589856] systemd[1]: Starting User Login Management... bob # [ 14.496304] systemd[1]: Finished Import lastlog data into lastlog2 database. clare # [ 14.622366] systemd[1]: Finished Import lastlog data into lastlog2 database. alice # [ 14.600109] systemd[1]: Finished Import lastlog data into lastlog2 database. alice # [ 14.660615] dbus-broker-launch[474]: Looking up NSS user entry for 'systemd-timesync'... bob # [ 14.584637] dbus-broker-launch[481]: Looking up NSS user entry for 'systemd-timesync'... bob: connected to guest root shell bob: (connecting took 14.96 seconds) bob: (finished: waiting for the VM to finish booting, in 14.96 seconds) alice # [ 14.680524] dbus-broker-launch[474]: NSS returned no entry for 'systemd-timesync' alice # [ 14.681246] dbus-broker-launch[474]: Invalid user-name in /nix/store/ah686xacysvqrh7xsqvibww49bg7wqpf-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" bob # [ 14.609646] dbus-broker-launch[481]: NSS returned no entry for 'systemd-timesync' bob # [ 14.610360] dbus-broker-launch[481]: Invalid user-name in /nix/store/xhwz9jrhs1b8k9i8nsalw3vdxy993zp4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" clare # [ 14.728802] dbus-broker-launch[471]: Looking up NSS user entry for 'systemd-timesync'... bob # [ 14.620941] systemd-logind[502]: Watching system buttons on /dev/input/event0 (gpio-keys) clare # [ 14.740725] dbus-broker-launch[471]: NSS returned no entry for 'systemd-timesync' clare # [ 14.741486] dbus-broker-launch[471]: Invalid user-name in /nix/store/q01akv3dn2hf5zpsaprwcv1lvz3q3c9f-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" bob # [ 14.625423] systemd-logind[502]: New seat seat0. bob # [ 14.628099] systemd[1]: Started User Login Management. alice # [ 14.711983] systemd-logind[497]: Watching system buttons on /dev/input/event0 (gpio-keys) alice # [ 14.715685] systemd-logind[497]: New seat seat0. alice # [ 14.719692] systemd[1]: Started D-Bus System Message Bus. bob # [ 14.638526] systemd[1]: Starting linger-users.service... clare # [ 14.758487] systemd-logind[494]: Watching system buttons on /dev/input/event0 (gpio-keys) alice # [ 14.724894] systemd[1]: Started User Login Management. clare # [ 14.761721] systemd-logind[494]: New seat seat0. alice # [ 14.730153] systemd[1]: Starting linger-users.service... clare # [ 14.767712] systemd[1]: Started User Login Management. clare # [ 14.771508] systemd[1]: Starting linger-users.service... bob # [ 14.662159] systemd[1]: Started D-Bus System Message Bus. clare # [ 14.791895] systemd[1]: Started D-Bus System Message Bus. alice # [ 14.767210] dbus-broker-launch[474]: Ready clare # [ 14.833202] dbus-broker-launch[471]: Ready bob # [ 14.716878] dbus-broker-launch[481]: Ready alice # [ 14.809181] systemd[1]: linger-users.service: Deactivated successfully. alice # [ 14.810911] systemd[1]: Finished linger-users.service. clare # [ 14.856194] mousedev: PS/2 mouse device common for all mice clare # [ 14.859674] systemd[1]: linger-users.service: Deactivated successfully. clare # [ 14.863068] systemd[1]: Finished linger-users.service. bob # [ 14.748530] systemd[1]: linger-users.service: Deactivated successfully. alice # [ 14.839464] mousedev: PS/2 mouse device common for all mice bob # [ 14.751987] systemd-logind[502]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 14.755626] systemd[1]: Finished linger-users.service. clare # [ 14.878683] systemd-logind[494]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) bob # [ 14.775440] mousedev: PS/2 mouse device common for all mice alice # [ 14.860240] systemd-logind[497]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) alice # [ 14.994859] systemd[1]: Finished Firewall. alice # [ 14.999394] systemd[1]: Reached target Preparation for Network. alice # [ 15.000188] systemd[1]: Listening on Network Management Resolve Hook Socket. alice # [ 15.005177] systemd[1]: Starting Network Management... bob # [ 14.939064] systemd[1]: Finished Firewall. clare # [ 15.056219] systemd[1]: Finished Firewall. bob # [ 14.942175] systemd[1]: Reached target Preparation for Network. clare # [ 15.060982] systemd[1]: Reached target Preparation for Network. bob # [ 14.944323] systemd[1]: Listening on Network Management Resolve Hook Socket. clare # [ 15.061606] systemd[1]: Listening on Network Management Resolve Hook Socket. bob # [ 14.950009] systemd[1]: Starting Network Management... clare # [ 15.067610] systemd[1]: Starting Network Management... alice # [ 15.205463] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 15.140815] cfg80211: Loading compiled-in X.509 certificates for regulatory database clare # [ 15.273893] cfg80211: Loading compiled-in X.509 certificates for regulatory database alice # [ 15.255462] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' alice # [ 15.256623] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 15.193998] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' bob # [ 15.194551] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' clare # [ 15.325391] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' alice # [ 15.282352] systemd-networkd[539]: lo: Link UP clare # [ 15.325984] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' alice # [ 15.282886] systemd-networkd[539]: lo: Gained carrier alice # [ 15.291779] systemd-networkd[539]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 15.302408] systemd-networkd[539]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 15.303202] systemd[1]: Started Network Management. alice # [ 15.303651] systemd[1]: Reached target Network. bob # [ 15.222169] systemd-networkd[555]: lo: Link UP bob # [ 15.224086] systemd-networkd[555]: lo: Gained carrier alice # [ 15.310463] systemd-networkd[539]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. alice # [ 15.311440] systemd-networkd[539]: eth1: Link UP alice # [ 15.311856] systemd-networkd[539]: eth1: Gained carrier clare # [ 15.349676] systemd-networkd[538]: lo: Link UP bob # [ 15.232899] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 15.234274] systemd-networkd[555]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 15.316329] systemd-networkd[539]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 15.317822] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 15.352533] systemd-networkd[538]: lo: Gained carrier bob # [ 15.235365] systemd[1]: Started Network Management. clare # [ 15.358793] systemd-networkd[538]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. alice # [ 15.325265] systemd[1]: Starting Permit User Sessions... alice # [ 15.325864] systemd-networkd[539]: eth0: Link UP alice # [ 15.326297] systemd-networkd[539]: eth0: Gained carrier clare # [ 15.363965] systemd[1]: Started Network Management. bob # [ 15.247675] systemd[1]: Reached target Network. bob # [ 15.249550] systemd[1]: Reached target Network is Online. clare # [ 15.364611] systemd[1]: Reached target Network. bob # [ 15.250109] systemd-networkd[555]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. clare # [ 15.370780] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... alice # [ 15.336942] systemd-networkd[539]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 15.259201] systemd[1]: Starting ncps binary cache proxy service... clare # [ 15.377296] systemd-networkd[538]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 15.259987] systemd-networkd[555]: eth1: Link UP bob # [ 15.262842] systemd-networkd[555]: eth1: Gained carrier bob # [ 15.263345] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 15.385273] systemd-networkd[538]: eth1: Configuring with /etc/systemd/network/40-eth1.network. clare # [ 15.386128] systemd-networkd[538]: eth0: Link UP clare # [ 15.386590] systemd-networkd[538]: eth0: Gained carrier bob # [ 15.271573] systemd-networkd[555]: eth0: Link UP bob # [ 15.275687] systemd-networkd[555]: eth0: Gained carrier clare # [ 15.393809] systemd[1]: Starting Permit User Sessions... clare # [ 15.394388] systemd-networkd[538]: eth1: Link UP clare # [ 15.394832] systemd-networkd[538]: eth1: Gained carrier clare # [ 15.395279] systemd-networkd[538]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 15.281952] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... bob # [ 15.282684] systemd-networkd[555]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 clare # [ 15.406261] systemd-networkd[538]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 15.296726] systemd[1]: Starting Permit User Sessions... alice # [ 15.402945] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. alice # [ 15.407426] systemd[1]: Finished Permit User Sessions. alice # [ 15.413247] systemd[1]: Started Getty on tty1. alice # [ 15.415137] systemd[1]: Reached target Login Prompts. alice # [ 15.415699] systemd[1]: Reached target Multi-User System. alice # [ 15.421120] systemd[1]: Startup finished in 1.058s (kernel) + 9.838s (initrd) + 4.519s (userspace) = 15.416s. clare # [ 15.469980] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 15.481907] systemd[1]: Finished Permit User Sessions. bob # [ 15.364749] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 15.487720] systemd[1]: Started Getty on tty1. clare # [ 15.490942] systemd[1]: Reached target Login Prompts. clare # [ 15.496409] systemd[1]: Reached target Multi-User System. bob # [ 15.379375] systemd[1]: Finished Permit User Sessions. clare # [ 15.500401] systemd[1]: Startup finished in 961ms (kernel) + 9.969s (initrd) + 4.568s (userspace) = 15.500s. bob # [ 15.388315] systemd[1]: Started Getty on tty1. bob # [ 15.394824] systemd[1]: Reached target Login Prompts. alice # [ 15.698001] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 clare # [ 15.777089] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 bob # [ 15.652815] ncps-pre-start[572]: {"level":"info","log_level":"info","time":"2026-10-16T09:37:49Z","message":"logger created"} bob # [ 15.655569] ncps-pre-start[572]: {"level":"info","xz-binary-path":"/nix/store/csrwf31hprwghnbyiglxa3p787lgrl67-xz-5.8.4-bin/bin/xz","time":"2026-10-16T09:37:49Z","message":"Using xz binary for xz decompression"} bob # [ 15.701236] ncps-pre-start[572]: {"level":"info","time":"2026-10-16T09:37:49Z","message":"running migrate up"} bob # [ 15.727424] ncps-pre-start[572]: {"level":"info","time":"2026-10-16T09:37:49Z","message":"migrate up complete"} bob # [ 15.847286] systemd[1]: Started ncps binary cache proxy service. bob # [ 15.851453] systemd[1]: Reached target Multi-User System. bob # [ 15.855210] systemd[1]: Startup finished in 1.046s (kernel) + 9.728s (initrd) + 5.078s (userspace) = 15.853s. bob: (finished: waiting for unit ncps, in 16.26 seconds) bob: must succeed: systemctl status ncps alice # [ 16.026001] systemd[1]: Starting Virtual Console Setup... alice # [ 16.035370] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 15.960847] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 alice # [ 16.039132] systemd[1]: Stopped Virtual Console Setup. alice # [ 16.046375] systemd[1]: Starting Virtual Console Setup... bob: (finished: must succeed: systemctl status ncps, in 0.08 seconds) clare: waiting for unit harmonia.socket clare: waiting for the VM to finish booting clare: Guest shell says: b'Spawning backdoor root shell...\n' clare: connected to guest root shell clare: (connecting took 0.00 seconds) clare: (finished: waiting for the VM to finish booting, in 0.00 seconds) alice # [ 16.118303] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. clare: (finished: waiting for unit harmonia.socket, in 0.10 seconds) alice # [ 16.151238] systemd-logind[497]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) alice: waiting for success: curl bob:8502/nix-cache-info alice: waiting for the VM to finish booting alice: Guest shell says: b'Spawning backdoor root shell...\n' alice: connected to guest root shell alice: (connecting took 0.00 seconds) alice: (finished: waiting for the VM to finish booting, in 0.00 seconds) clare # [ 16.197942] systemd-logind[494]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) clare # [ 16.202960] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. clare # [ 16.207676] systemd[1]: Starting Virtual Console Setup... bob # [ 16.095247] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","log_level":"info","time":"2026-10-16T09:37:49Z","message":"logger created"} bob # [ 16.100165] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","xz-binary-path":"/nix/store/csrwf31hprwghnbyiglxa3p787lgrl67-xz-5.8.4-bin/bin/xz","time":"2026-10-16T09:37:49Z","message":"Using xz binary for xz decompression"} bob # [ 16.103631] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","time":"2026-10-16T09:37:49Z","message":"using local locks (single-instance mode)"} bob # [ 16.112704] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-10-16T09:37:49Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"} bob # [ 16.129399] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-16T09:37:49Z","message":"setting up tracer provider to discard traces"} bob # [ 16.131079] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-16T09:37:49Z","message":"setting up meter provider to discard metrics"} bob # [ 16.140099] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-16T09:37:49Z","message":"setting up logger provider to discard logs"} bob # [ 16.153707] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-10-16T09:37:49Z","message":"Reporting anonymous metrics to the project maintainers"} bob # [ 16.155375] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-10-16T09:37:49Z","message":"failed to parse netrc file, proceeding without netrc authentication"} bob # [ 16.162144] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-10-16T09:37:49Z","message":"using local storage"} alice # [ 16.246363] systemd-vconsole-setup[558]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 16.249465] systemd[1]: Finished Virtual Console Setup. clare # [ 16.288998] systemd-vconsole-setup[570]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 16.172207] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","cdc-enabled":false,"cdc-min":16384,"cdc-avg":65536,"cdc-max":262144,"time":"2026-10-16T09:37:49Z","message":"configuring Content-Defined-Chunking (CDC)"} bob # [ 16.174090] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","lazy-cdc":false,"lazy-cdc-workers":1,"time":"2026-10-16T09:37:49Z","message":"configuring lazy Content-Defined-Chunking (CDC)"} clare # [ 16.292319] systemd[1]: Finished Virtual Console Setup. bob # [ 16.175749] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","inflight-staging-enabled":false,"distributed-lock":false,"inflight-staging-retention":300000,"inflight-staging-part-size":8388608,"time":"2026-10-16T09:37:49Z","message":"configuring in-flight NAR staging"} clare # [ 16.345036] systemd[1]: Starting harmonia binary cache service... clare # [ 16.426834] harmonia-cache[573]: 2026-10-16T09:37:50.019112Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000)) clare # [ 16.430100] harmonia-cache[573]: 2026-10-16T09:37:50.021227Z INFO actix_server::builder: starting 4 workers clare # [ 16.432250] harmonia-cache[573]: 2026-10-16T09:37:50.024050Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s clare # [ 16.433460] harmonia-cache[573]: 2026-10-16T09:37:50.024110Z INFO actix_server::server: Actix runtime found; starting in Actix runtime clare # [ 16.434612] harmonia-cache[573]: 2026-10-16T09:37:50.024193Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:5000 clare # [ 16.440347] systemd[1]: Started harmonia binary cache service. bob # [ 16.341621] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-10-16T09:37:49Z","message":"upstream became healthy and is now available for requests"} bob # [ 16.348376] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","time_zone":"UTC","time":"2026-10-16T09:37:49Z","message":"setting up the cache timezone location"} bob # [ 16.349904] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","time":"2026-10-16T09:37:49Z","message":"cron setup complete"} bob # [ 16.351112] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","time":"2026-10-16T09:37:49Z","message":"starting the cron scheduler"} bob # [ 16.356139] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-10-16T09:37:49Z","message":"Server started"} bob # [ 16.476633] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 16.485199] systemd[1]: Starting Virtual Console Setup... bob # [ 16.507362] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 16.511559] systemd[1]: Stopped Virtual Console Setup. bob # [ 16.514206] systemd[1]: Starting Virtual Console Setup... bob # [ 16.527145] systemd-logind[502]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 16.545678] systemd-networkd[555]: eth0: Gained IPv6LL clare # [ 16.673891] systemd-networkd[538]: eth1: Gained IPv6LL alice # [ 16.641777] systemd-networkd[539]: eth1: Gained IPv6LL bob # [ 16.592592] systemd-vconsole-setup[609]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 16.595791] systemd[1]: Finished Virtual Console Setup. bob # [ 16.641822] systemd-networkd[555]: eth1: Gained IPv6LL alice # [ 17.185611] systemd-networkd[539]: eth0: Gained IPv6LL bob # [ 17.169671] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: 2026/10/16 09:37:50 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host clare # [ 17.377150] systemd-networkd[538]: eth0: Gained IPv6LL alice # curl: (28) Failed to connect to bob:8502 after 134296 ms: Could not connect to server alice # % Total % Received % Xferd Average Speed Time Time Time Current alice # Dload Upload Total Spent Left Speed bob # [ 151.488710] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:43660","trace_id":"28e624f092c14770c1257f3da7002c7c","span_id":"a409cb29961d41de","status":200,"elapsed":0.08264,"bytes":50,"time":"2026-10-16T09:40:04Z","message":"handled request"} alice # 0 0 0 0 0 0 0 0 0100 50 100 50 0 0 2222 0 0100 50 100 50 0 0 1848 0 0100 50 100 50 0 0 1587 0 0 alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 135.45 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 2205 0 0100 51 100 51 0 0 1904 0 0100 51 100 51 0 0 1691 0 0 bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.09 seconds) clare: waiting for unit harmonia clare: (finished: waiting for unit harmonia, in 0.07 seconds) clare: must succeed: systemctl status harmonia clare: (finished: must succeed: systemctl status harmonia, in 0.04 seconds) clare: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 clare # [ 152.221228] systemd-resolved[310]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. clare # [ 152.227760] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 152.231393] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 152.765235] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 152.769285] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 154.027172] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 154.031258] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare # [ 156.768319] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. clare # [ 156.771656] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. clare: (finished: must succeed: nix-build --expr ' builtins.derivation { name = "hi"; builder = "/bin/sh"; args = [ "-c" "echo hi > $out" ]; system = "aarch64-linux"; } ' 2>&1 , in 5.13 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 # [ 156.921846] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:43664","trace_id":"ba746dc6e1d0a0fc50f35b4cf2fd7d78","span_id":"793aafe488ed03dc","status":200,"elapsed":0.0316,"bytes":50,"time":"2026-10-16T09:40:10Z","message":"handled request"} alice # [ 157.283052] systemd-resolved[316]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. alice # [ 157.288559] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 157.292284] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 157.974121] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 157.978984] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 159.009161] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 159.014078] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. alice # [ 161.432206] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 161.436391] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 161.362507] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","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-16T09:40:14Z","message":"download the narinfo from upstream"} bob # [ 161.393126] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-16T09:40:14Z","message":"downloading the nar from upstream"} bob # [ 161.404879] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","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-16T09:40:14Z","message":"download the nar from upstream"} bob # [ 161.432111] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":35.89712,"time":"2026-10-16T09:40:14Z","message":"download of nar complete"} bob # [ 161.449469] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-16T09:40:14Z","message":"storing narinfo and nar_file record in the database"} bob # [ 161.463153] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":100.12632,"time":"2026-10-16T09:40:14Z","message":"download of narinfo complete"} bob # [ 161.471149] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","status":200,"elapsed":104.5644,"bytes":532,"time":"2026-10-16T09:40:14Z","message":"handled request"} bob # [ 161.477749] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:43664","trace_id":"e160a1468daec2436c3859be9fcb2bc3","span_id":"0bdd619f4149749e","status":200,"elapsed":0.93268,"bytes":95,"time":"2026-10-16T09:40:14Z","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.74 seconds) (finished: run the VM test script, in 163.69 seconds) test script finished in 221.08s cleanup kill QemuMachine (pid 47) alice # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) alice # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down alice # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 49) bob # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) bob # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down bob # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down kill QemuMachine (pid 50) clare # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) clare # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down clare # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.43 seconds)