Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600 seconds run the VM test script additionally exposed symbols: alice, bob, clare, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh start all VMs alice: starting vm bob: starting vm clare: starting vm bob: QEMU running (pid 49) bob # Disk image does not exist, creating the virtualisation disk image... bob # Formatting '/build/vm-state-bob/tmp.jXOEGXCiRc', fmt=raw size=1073741824 bob # mke2fs 1.47.4 (6-Mar-2025) bob # Discarding device blocks: 0/262144 done bob # Creating filesystem with 262144 4k blocks and 65536 inodes bob # Filesystem UUID: 3a6352b6-5f85-4d91-92db-ca7d2dab13ee 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 bob # bob # Virtualisation disk image created. clare # Disk image does not exist, creating the virtualisation disk image... clare # Formatting '/build/vm-state-clare/tmp.yNIgufKxMk', 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: bcec5caa-deff-4191-a2f3-cd9438fc1d91 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: QEMU running (pid 50) alice # Disk image does not exist, creating the virtualisation disk image... alice: QEMU running (pid 48) alice # Formatting '/build/vm-state-alice/tmp.pn0Kkf728r', 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: 38c8d190-9c4a-4d8f-93a2-4502c758445c 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 (finished: start all VMs, in 0.53 seconds) alice # Writing superblocks and filesystem accounting information: 0/8 done bob: waiting for unit ncps alice # bob: waiting for the VM to finish booting alice # Virtualisation disk image created. bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] bob # [ 0.000000] Linux version 6.18.42 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Aug 3 09:22:13 UTC 2026 bob # [ 0.000000] KASLR enabled bob # [ 0.000000] random: crng init done bob # [ 0.000000] Machine model: linux,dummy-virt bob # [ 0.000000] efi: UEFI not found. bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf] bob # [ 0.000000] Zone ranges: bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] DMA32 empty bob # [ 0.000000] Normal empty bob # [ 0.000000] Device empty bob # [ 0.000000] Movable zone start for each node bob # [ 0.000000] Early memory node ranges bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 bob # [ 0.000000] psci: probing for conduit method from DT. bob # [ 0.000000] psci: PSCIv1.3 detected in firmware. bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs bob # [ 0.000000] psci: Trusted OS migration not required bob # [ 0.000000] psci: SMC Calling Convention v1.1 clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296 clare # [ 0.000000] Linux version 6.18.42 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Aug 3 09:22:13 UTC 2026 clare # [ 0.000000] KASLR enabled bob # [ 0.000000] Detected PIPT I-cache on CPU0 clare # [ 0.000000] random: crng init done bob # [ 0.000000] CPU features: detected: GICv3 CPU interface clare # [ 0.000000] Machine model: linux,dummy-virt bob # [ 0.000000] CPU features: detected: Spectre-v4 clare # [ 0.000000] efi: UEFI not found. bob # [ 0.000000] CPU features: detected: Spectre-BHB clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf] bob # [ 0.000000] CPU features: detected: ARM erratum 1418040 clare # [ 0.000000] Zone ranges: bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.000000] DMA32 empty bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing clare # [ 0.000000] Normal empty clare # [ 0.000000] Device empty bob # [ 0.000000] alternatives: applying boot alternatives 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. 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/n50v5jw8ki6zbxqbm2p955bkjgnkwzdc-nixos-system-bob-test/init regInfo=/nix/store/bmahqnd1by6p1c2xzyf3m4gy6jwqvw74-closure-info/registration console=ttyAMA0,115200n8 console=tty0 clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs clare # [ 0.000000] psci: Trusted OS migration not required bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/bmahqnd1by6p1c2xzyf3m4gy6jwqvw74-closure-info/registration", will be passed to user space. clare # [ 0.000000] psci: SMC Calling Convention v1.1 bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296 clare # [ 0.000000] Detected PIPT I-cache on CPU0 bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) clare # [ 0.000000] CPU features: detected: GICv3 CPU interface bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB clare # [ 0.000000] CPU features: detected: Spectre-v4 bob # [ 0.000000] software IO TLB: area num 1. clare # [ 0.000000] CPU features: detected: Spectre-BHB bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR bob # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 clare # [ 0.000000] CPU features: detected: ARM erratum 1418040 bob # [ 0.000000] Policy zone: DMA clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 clare # [ 0.000000] alternatives: applying boot alternatives bob # [ 0.000000] allocated 2097152 bytes of page_ext bob # [ 0.000000] ftrace: allocating 74872 entries in 294 pages bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups bob # [ 0.000000] rcu: Hierarchical RCU implementation. bob # [ 0.000000] rcu: RCU event tracing is enabled. bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. clare # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/7w3nvwgwnlg68glawvsng5zlh5xmq3vi-nixos-system-clare-test/init regInfo=/nix/store/ja8gvyn91cnm8kvivb68z44zg8mc8c5a-closure-info/registration console=ttyAMA0,115200n8 console=tty0 bob # [ 0.000000] Trampoline variant of Tasks RCU enabled. bob # [ 0.000000] Rude variant of Tasks RCU enabled. bob # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/ja8gvyn91cnm8kvivb68z44zg8mc8c5a-closure-info/registration", will be passed to user space. bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. 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 bob # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. clare # [ 0.000000] software IO TLB: area num 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. clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 clare # [ 0.000000] Fallback order for Node 0: 0 bob # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 bob # [ 0.000000] GICv3: 0 Extended SPIs implemented clare # [ 0.000000] Policy zone: DMA bob # [ 0.000000] Root IRQ handler: gic_handle_irq clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 clare # [ 0.000000] allocated 2097152 bytes of page_ext bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.000000] ftrace: allocating 74872 entries in 294 pages clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) clare # [ 0.000000] rcu: Hierarchical RCU implementation. clare # [ 0.000000] rcu: RCU event tracing is enabled. bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 clare # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.000000] Rude variant of Tasks RCU enabled. bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. clare # [ 0.000000] Tracing variant of Tasks RCU enabled. clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns 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. bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 bob # [ 0.000055] arm-pv: using stolen time PV clare # [ 0.000000] GICv3: 256 SPIs implemented clare # [ 0.000000] GICv3: 0 Extended SPIs implemented bob # [ 0.000480] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) clare # [ 0.000000] Root IRQ handler: gic_handle_irq bob # [ 0.000797] Console: colour dummy device 80x25 clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI bob # [ 0.000815] printk: legacy console [tty0] enabled clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 bob # [ 0.001036] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] bob # [ 0.001041] pid_max: default: 32768 minimum: 301 bob # [ 0.001147] LSM: initializing lsm=capability,landlock,yama,bpf,ima clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) bob # [ 0.001309] landlock: Up and running. bob # [ 0.001312] Yama: becoming mindful. bob # [ 0.001825] LSM support for eBPF active clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 bob # [ 0.001989] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 bob # [ 0.002009] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.004143] cacheinfo: Unable to detect cache hierarchy for CPU 0 clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.004889] rcu: Hierarchical SRCU implementation. clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns bob # [ 0.004895] rcu: Max phase no-delay instances is 1000. bob # [ 0.005828] fsl-mc MSI: its@8080000 domain created clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 bob # [ 0.005992] EFI services will not be available. clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). bob # [ 0.006093] smp: Bringing up secondary CPUs ... bob # [ 0.006126] smp: Brought up 1 node, 1 CPU bob # [ 0.006131] SMP: Total of 1 processors activated. clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns bob # [ 0.006133] CPU: All CPU(s) started at EL1 clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns bob # [ 0.006145] CPU features: detected: 32-bit EL0 Support clare # [ 0.000058] arm-pv: using stolen time PV bob # [ 0.006148] CPU features: detected: Data cache clean to the PoU not required for I/D coherence clare # [ 0.000405] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) bob # [ 0.006150] CPU features: detected: Common not Private translations clare # [ 0.000688] Console: colour dummy device 80x25 bob # [ 0.006153] CPU features: detected: CRC32 instructions clare # [ 0.000704] printk: legacy console [tty0] enabled bob # [ 0.006155] CPU features: detected: Data cache clean to Point of Persistence clare # [ 0.000931] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) bob # [ 0.006157] CPU features: detected: Enhanced Virtualization Traps clare # [ 0.000937] pid_max: default: 32768 minimum: 301 bob # [ 0.006167] CPU features: detected: RCpc load-acquire (LDAPR) clare # [ 0.001022] LSM: initializing lsm=capability,landlock,yama,bpf,ima bob # [ 0.006170] CPU features: detected: LSE atomic instructions clare # [ 0.001168] landlock: Up and running. bob # [ 0.006171] CPU features: detected: Privileged Access Never clare # [ 0.001170] Yama: becoming mindful. bob # [ 0.006173] CPU features: detected: PMUv3 clare # [ 0.001645] LSM support for eBPF active bob # [ 0.006175] CPU features: detected: RAS Extension Support clare # [ 0.001830] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.006179] CPU features: detected: Speculative Store Bypassing Safe (SSBS) clare # [ 0.001851] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) bob # [ 0.006201] alternatives: applying system-wide alternatives clare # [ 0.003764] cacheinfo: Unable to detect cache hierarchy for CPU 0 bob # [ 0.007647] CPU features: detected: Hardware dirty bit management on CPU0 clare # [ 0.004645] rcu: Hierarchical SRCU implementation. clare # [ 0.004651] rcu: Max phase no-delay instances is 1000. clare # [ 0.005600] fsl-mc MSI: its@8080000 domain created bob # [ 0.007834] Memory: 891320K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1106K bss, 115944K reserved, 32768K cma-reserved) clare # [ 0.005775] EFI services will not be available. bob # [ 0.008325] devtmpfs: initialized clare # [ 0.005884] smp: Bringing up secondary CPUs ... bob # [ 0.009825] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) clare # [ 0.005916] smp: Brought up 1 node, 1 CPU clare # [ 0.005921] SMP: Total of 1 processors activated. bob # [ 0.009847] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). clare # [ 0.005923] CPU: All CPU(s) started at EL1 clare # [ 0.005936] CPU features: detected: 32-bit EL0 Support bob # [ 0.009978] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL bob # [ 0.009982] 0 pages in range for non-PLT usage clare # [ 0.005938] CPU features: detected: Data cache clean to the PoU not required for I/D coherence bob # [ 0.009982] 508288 pages in range for PLT usage bob # [ 0.010124] pinctrl core: initialized pinctrl subsystem clare # [ 0.005941] CPU features: detected: Common not Private translations bob # [ 0.010957] DMI not present or invalid. clare # [ 0.005943] CPU features: detected: CRC32 instructions bob # [ 0.014588] NET: Registered PF_NETLINK/PF_ROUTE protocol family clare # [ 0.005945] CPU features: detected: Data cache clean to Point of Persistence bob # [ 0.017157] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations clare # [ 0.005948] CPU features: detected: Enhanced Virtualization Traps clare # [ 0.005958] CPU features: detected: RCpc load-acquire (LDAPR) bob # [ 0.017305] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations clare # [ 0.005960] CPU features: detected: LSE atomic instructions bob # [ 0.017458] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.005962] CPU features: detected: Privileged Access Never bob # [ 0.017485] audit: initializing netlink subsys (disabled) clare # [ 0.005964] CPU features: detected: PMUv3 clare # [ 0.005966] CPU features: detected: RAS Extension Support bob # [ 0.018109] thermal_sys: Registered thermal governor 'fair_share' bob # [ 0.018112] thermal_sys: Registered thermal governor 'bang_bang' clare # [ 0.005971] CPU features: detected: Speculative Store Bypassing Safe (SSBS) clare # [ 0.005994] alternatives: applying system-wide alternatives bob # [ 0.018115] thermal_sys: Registered thermal governor 'step_wise' bob # [ 0.018117] thermal_sys: Registered thermal governor 'user_space' clare # [ 0.007580] CPU features: detected: Hardware dirty bit management on CPU0 bob # [ 0.018119] thermal_sys: Registered thermal governor 'power_allocator' bob # [ 0.018148] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 clare # [ 0.007804] Memory: 891332K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1106K bss, 115960K reserved, 32768K cma-reserved) bob # [ 0.018164] cpuidle: using governor ladder clare # [ 0.008330] devtmpfs: initialized bob # [ 0.018168] cpuidle: using governor menu clare # [ 0.009924] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) bob # [ 0.018298] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. clare # [ 0.009947] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). bob # [ 0.018319] ASID allocator initialised with 32768 entries bob # [ 0.018984] Serial: AMBA PL011 UART driver clare # [ 0.010071] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL clare # [ 0.010075] 0 pages in range for non-PLT usage bob # [ 0.022290] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 clare # [ 0.010076] 508288 pages in range for PLT usage bob # [ 0.022476] printk: console [ttyAMA0] enabled clare # [ 0.010216] pinctrl core: initialized pinctrl subsystem clare # [ 0.011021] DMI not present or invalid. clare # [ 0.014594] NET: Registered PF_NETLINK/PF_ROUTE protocol family clare # [ 0.017252] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations clare # [ 0.017392] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] clare # [ 0.017543] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations clare # [ 0.017566] audit: initializing netlink subsys (disabled) alice # [ 0.000000] Linux version 6.18.42 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Aug 3 09:22:13 UTC 2026 alice # [ 0.000000] KASLR enabled clare # [ 0.018208] thermal_sys: Registered thermal governor 'fair_share' alice # [ 0.000000] random: crng init done alice # [ 0.000000] Machine model: linux,dummy-virt clare # [ 0.018210] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 0.000000] efi: UEFI not found. clare # [ 0.018213] thermal_sys: Registered thermal governor 'step_wise' alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT clare # [ 0.018215] thermal_sys: Registered thermal governor 'user_space' alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.018218] thermal_sys: Registered thermal governor 'power_allocator' alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf] clare # [ 0.018247] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 alice # [ 0.000000] Zone ranges: clare # [ 0.018262] cpuidle: using governor ladder alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.018267] cpuidle: using governor menu alice # [ 0.000000] DMA32 empty alice # [ 0.000000] Normal empty clare # [ 0.018393] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. alice # [ 0.000000] Device empty alice # [ 0.000000] Movable zone start for each node clare # [ 0.018414] ASID allocator initialised with 32768 entries bob # [ 0.095233] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages alice # [ 0.000000] Early memory node ranges clare # [ 0.019097] Serial: AMBA PL011 UART driver bob # [ 0.095258] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.022276] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 bob # [ 0.095263] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] clare # [ 0.022430] printk: console [ttyAMA0] enabled bob # [ 0.095269] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 alice # [ 0.000000] psci: probing for conduit method from DT. bob # [ 0.095272] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages alice # [ 0.000000] psci: PSCIv1.3 detected in firmware. bob # [ 0.095275] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs bob # [ 0.095279] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages alice # [ 0.000000] psci: Trusted OS migration not required bob # [ 0.095282] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page alice # [ 0.000000] psci: SMC Calling Convention v1.1 alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296 alice # [ 0.000000] Detected PIPT I-cache on CPU0 alice # [ 0.000000] CPU features: detected: GICv3 CPU interface bob # [ 0.100367] fbcon: Taking over console bob # [ 0.100388] ACPI: Interpreter disabled. alice # [ 0.000000] CPU features: detected: Spectre-v4 alice # [ 0.000000] CPU features: detected: Spectre-BHB bob # [ 0.101657] iommu: Default domain type: Translated bob # [ 0.101668] iommu: DMA domain TLB invalidation policy: strict mode alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) bob # [ 0.102825] SCSI subsystem initialized alice # [ 0.000000] CPU features: detected: ARM erratum 1418040 alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing alice # [ 0.000000] alternatives: applying boot alternatives clare # [ 0.096318] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages clare # [ 0.096342] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page clare # [ 0.096347] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages 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/2n6lzdv6aipl0kivcm3x5jl3dyk2zz33-nixos-system-alice-test/init regInfo=/nix/store/mki918g7awmngyv1ndgbg8rvqcxkfnvp-closure-info/registration console=ttyAMA0,115200n8 console=tty0 clare # [ 0.096352] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page clare # [ 0.096356] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages clare # [ 0.096359] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/mki918g7awmngyv1ndgbg8rvqcxkfnvp-closure-info/registration", will be passed to user space. clare # [ 0.096362] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes clare # [ 0.096365] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB alice # [ 0.000000] software IO TLB: area num 1. clare # [ 0.101456] fbcon: Taking over console alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) clare # [ 0.101476] ACPI: Interpreter disabled. alice # [ 0.000000] Fallback order for Node 0: 0 clare # [ 0.102733] iommu: Default domain type: Translated alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 clare # [ 0.102744] iommu: DMA domain TLB invalidation policy: strict mode bob # [ 0.111499] usbcore: registered new interface driver usbfs alice # [ 0.000000] Policy zone: DMA bob # [ 0.111540] usbcore: registered new interface driver hub alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off clare # [ 0.103925] SCSI subsystem initialized bob # [ 0.111553] usbcore: registered new device driver usb alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 bob # [ 0.111974] pps_core: LinuxPPS API ver. 1 registered alice # [ 0.000000] allocated 2097152 bytes of page_ext clare # [ 0.104562] usbcore: registered new interface driver usbfs alice # [ 0.000000] ftrace: allocating 74872 entries in 294 pages bob # [ 0.111982] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti clare # [ 0.104602] usbcore: registered new interface driver hub bob # [ 0.111989] PTP clock support registered alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups bob # [ 0.112019] EDAC MC: Ver: 3.0.0 clare # [ 0.104622] usbcore: registered new device driver usb alice # [ 0.000000] rcu: Hierarchical RCU implementation. clare # [ 0.104830] pps_core: LinuxPPS API ver. 1 registered alice # [ 0.000000] rcu: RCU event tracing is enabled. bob # [ 0.115131] scmi_core: SCMI protocol bus registered alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. clare # [ 0.104836] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti clare # [ 0.104842] PTP clock support registered alice # [ 0.000000] Trampoline variant of Tasks RCU enabled. clare # [ 0.104868] EDAC MC: Ver: 3.0.0 alice # [ 0.000000] Rude variant of Tasks RCU enabled. bob # [ 0.115902] FPGA manager framework alice # [ 0.000000] Tracing variant of Tasks RCU enabled. bob # [ 0.117036] vgaarb: loaded alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 bob # [ 0.117476] clocksource: Switched to clocksource arch_sys_counter bob # [ 0.117901] VFS: Disk quotas dquot_6.6.0 alice # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.117928] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. bob # [ 0.119603] netfs: FS-Cache loaded bob # [ 0.119717] pnp: PnP ACPI: disabled alice # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 alice # [ 0.000000] GICv3: 256 SPIs implemented alice # [ 0.000000] GICv3: 0 Extended SPIs implemented alice # [ 0.000000] Root IRQ handler: gic_handle_irq clare # [ 0.112496] scmi_core: SCMI protocol bus registered alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 clare # [ 0.113260] FPGA manager framework alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] clare # [ 0.114270] vgaarb: loaded alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) clare # [ 0.114721] clocksource: Switched to clocksource arch_sys_counter alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) clare # [ 0.115145] VFS: Disk quotas dquot_6.6.0 alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 clare # [ 0.115174] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) bob # [ 0.122100] NET: Registered PF_INET protocol family alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 clare # [ 0.116850] netfs: FS-Cache loaded clare # [ 0.116965] pnp: PnP ACPI: disabled alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. bob # [ 0.122312] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) 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.000048] arm-pv: using stolen time PV alice # [ 0.000403] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) alice # [ 0.000684] Console: colour dummy device 80x25 alice # [ 0.000701] printk: legacy console [tty0] enabled alice # [ 0.000912] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) alice # [ 0.000917] pid_max: default: 32768 minimum: 301 alice # [ 0.001003] LSM: initializing lsm=capability,landlock,yama,bpf,ima alice # [ 0.001179] landlock: Up and running. alice # [ 0.001181] Yama: becoming mindful. alice # [ 0.001578] LSM support for eBPF active alice # [ 0.001720] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.001739] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) alice # [ 0.003495] cacheinfo: Unable to detect cache hierarchy for CPU 0 alice # [ 0.004361] rcu: Hierarchical SRCU implementation. alice # [ 0.004367] rcu: Max phase no-delay instances is 1000. alice # [ 0.005281] fsl-mc MSI: its@8080000 domain created alice # [ 0.005429] EFI services will not be available. alice # [ 0.005518] smp: Bringing up secondary CPUs ... alice # [ 0.005550] smp: Brought up 1 node, 1 CPU alice # [ 0.005554] SMP: Total of 1 processors activated. alice # [ 0.005556] CPU: All CPU(s) started at EL1 alice # [ 0.005568] CPU features: detected: 32-bit EL0 Support alice # [ 0.005571] CPU features: detected: Data cache clean to the PoU not required for I/D coherence alice # [ 0.005574] CPU features: detected: Common not Private translations clare # [ 0.124163] NET: Registered PF_INET protocol family alice # [ 0.005576] CPU features: detected: CRC32 instructions clare # [ 0.124382] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.005578] CPU features: detected: Data cache clean to Point of Persistence alice # [ 0.005580] CPU features: detected: Enhanced Virtualization Traps alice # [ 0.005590] CPU features: detected: RCpc load-acquire (LDAPR) alice # [ 0.005592] CPU features: detected: LSE atomic instructions alice # [ 0.005594] CPU features: detected: Privileged Access Never alice # [ 0.005596] CPU features: detected: PMUv3 alice # [ 0.005598] CPU features: detected: RAS Extension Support alice # [ 0.005602] CPU features: detected: Speculative Store Bypassing Safe (SSBS) alice # [ 0.005623] alternatives: applying system-wide alternatives alice # [ 0.007163] CPU features: detected: Hardware dirty bit management on CPU0 alice # [ 0.007350] Memory: 891344K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1106K bss, 115960K reserved, 32768K cma-reserved) alice # [ 0.007814] devtmpfs: initialized alice # [ 0.009312] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.009335] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). alice # [ 0.009473] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL alice # [ 0.009477] 0 pages in range for non-PLT usage alice # [ 0.009477] 508288 pages in range for PLT usage alice # [ 0.009590] pinctrl core: initialized pinctrl subsystem alice # [ 0.010362] DMI not present or invalid. alice # [ 0.013719] NET: Registered PF_NETLINK/PF_ROUTE protocol family alice # [ 0.016191] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations alice # [ 0.016327] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations alice # [ 0.016481] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations alice # [ 0.016500] audit: initializing netlink subsys (disabled) alice # [ 0.017111] thermal_sys: Registered thermal governor 'fair_share' alice # [ 0.017113] thermal_sys: Registered thermal governor 'bang_bang' alice # [ 0.017116] thermal_sys: Registered thermal governor 'step_wise' alice # [ 0.017118] thermal_sys: Registered thermal governor 'user_space' alice # [ 0.017120] thermal_sys: Registered thermal governor 'power_allocator' bob # [ 0.153001] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.017154] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 bob # [ 0.153034] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) alice # [ 0.017169] cpuidle: using governor ladder bob # [ 0.153049] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) alice # [ 0.017174] cpuidle: using governor menu bob # [ 0.153091] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.017286] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. alice # [ 0.017306] ASID allocator initialised with 32768 entries bob # [ 0.153159] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.017963] Serial: AMBA PL011 UART driver bob # [ 0.153254] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 0.020997] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 bob # [ 0.153319] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.021140] printk: console [ttyAMA0] enabled bob # [ 0.153350] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.153458] NET: Registered PF_UNIX/PF_LOCAL protocol family clare # [ 0.152508] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) clare # [ 0.152542] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) clare # [ 0.152556] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) alice # [ 0.094632] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages clare # [ 0.152598] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.094655] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page clare # [ 0.152666] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.094660] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages alice # [ 0.094665] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page clare # [ 0.152764] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) bob # [ 0.158563] NET: Registered PF_XDP protocol family alice # [ 0.094669] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages clare # [ 0.152825] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) bob # [ 0.158597] PCI: CLS 0 bytes, default 64 alice # [ 0.094672] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page bob # [ 0.158808] Trying to unpack rootfs image as initramfs... clare # [ 0.152887] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) clare # [ 0.153035] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 0.094675] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages clare # [ 0.153091] NET: Registered PF_XDP protocol family alice # [ 0.094679] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page clare # [ 0.153115] PCI: CLS 0 bytes, default 64 alice # [ 0.099903] fbcon: Taking over console bob # [ 0.166670] kvm [1]: HYP mode not available alice # [ 0.099924] ACPI: Interpreter disabled. alice # [ 0.101165] iommu: Default domain type: Translated alice # [ 0.101175] iommu: DMA domain TLB invalidation policy: strict mode alice # [ 0.102326] SCSI subsystem initialized clare # [ 0.159143] Trying to unpack rootfs image as initramfs... alice # [ 0.102923] usbcore: registered new interface driver usbfs alice # [ 0.102966] usbcore: registered new interface driver hub alice # [ 0.102975] usbcore: registered new device driver usb alice # [ 0.103183] pps_core: LinuxPPS API ver. 1 registered alice # [ 0.103189] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti alice # [ 0.103195] PTP clock support registered alice # [ 0.103223] EDAC MC: Ver: 3.0.0 clare # [ 0.163401] kvm [1]: HYP mode not available alice # [ 0.110858] scmi_core: SCMI protocol bus registered alice # [ 0.111607] FPGA manager framework alice # [ 0.112581] vgaarb: loaded alice # [ 0.113020] clocksource: Switched to clocksource arch_sys_counter alice # [ 0.113502] VFS: Disk quotas dquot_6.6.0 alice # [ 0.113531] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) alice # [ 0.115436] netfs: FS-Cache loaded alice # [ 0.115568] pnp: PnP ACPI: disabled alice # [ 0.126398] NET: Registered PF_INET protocol family alice # [ 0.126639] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) alice # [ 0.152873] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) alice # [ 0.152907] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) alice # [ 0.152921] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) alice # [ 0.152967] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) alice # [ 0.155419] TCP: Hash tables configured (established 8192 bind 8192) alice # [ 0.155528] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) alice # [ 0.155592] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.155648] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) alice # [ 0.155813] NET: Registered PF_UNIX/PF_LOCAL protocol family alice # [ 0.155899] NET: Registered PF_XDP protocol family alice # [ 0.155922] PCI: CLS 0 bytes, default 64 alice # [ 0.156128] Trying to unpack rootfs image as initramfs... clare # [ 0.230430] Initialise system trusted keyrings alice # [ 0.169081] kvm [1]: HYP mode not available bob # [ 0.241928] Initialise system trusted keyrings bob # [ 0.242447] workingset: timestamp_bits=42 max_order=18 bucket_order=0 bob # [ 0.243415] squashfs: version 4.0 (2009/01/31) Phillip Lougher bob # [ 0.243917] 9p: Installing v9fs 9p2000 file system support clare # [ 0.238802] workingset: timestamp_bits=42 max_order=18 bucket_order=0 clare # [ 0.239856] squashfs: version 4.0 (2009/01/31) Phillip Lougher clare # [ 0.240360] 9p: Installing v9fs 9p2000 file system support bob # [ 0.263242] Key type asymmetric registered bob # [ 0.263267] Asymmetric key parser 'x509' registered bob # [ 0.263340] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) clare # [ 0.259256] Key type asymmetric registered clare # [ 0.259281] Asymmetric key parser 'x509' registered clare # [ 0.259347] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) bob # [ 0.269546] io scheduler mq-deadline registered clare # [ 0.260732] io scheduler mq-deadline registered bob # [ 0.269564] io scheduler kyber registered clare # [ 0.260741] io scheduler kyber registered bob # [ 0.272461] pl061_gpio 9030000.pl061: PL061 GPIO chip registered bob # [ 0.273237] ledtrig-cpu: registered to indicate activity on CPUs clare # [ 0.266851] pl061_gpio 9030000.pl061: PL061 GPIO chip registered bob # [ 0.274055] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: bob # [ 0.274081] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 bob # [ 0.274096] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 bob # [ 0.274102] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.267701] ledtrig-cpu: registered to indicate activity on CPUs bob # [ 0.274129] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits clare # [ 0.268018] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: bob # [ 0.274154] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.268042] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 bob # [ 0.274234] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 clare # [ 0.268057] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 bob # [ 0.274251] pci_bus 0000:00: root bus resource [bus 00-ff] clare # [ 0.268063] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 bob # [ 0.274257] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] bob # [ 0.274260] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] clare # [ 0.268101] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits bob # [ 0.274264] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] clare # [ 0.268129] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] bob # [ 0.274337] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint clare # [ 0.268208] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 clare # [ 0.268225] pci_bus 0000:00: root bus resource [bus 00-ff] bob # [ 0.274790] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.274916] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.268231] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] bob # [ 0.274928] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.268235] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] bob # [ 0.274948] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.268239] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] bob # [ 0.274959] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.268308] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint bob # [ 0.275259] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.268750] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.275381] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.268876] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.275393] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.268888] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.275413] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.268908] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.275721] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint clare # [ 0.268920] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.275844] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] clare # [ 0.269224] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.275855] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.269346] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.275875] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.269357] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.269377] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.276164] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint bob # [ 0.276286] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.269672] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint bob # [ 0.276297] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.269794] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.276316] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.269804] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.276616] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint clare # [ 0.269824] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.276735] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.270120] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint bob # [ 0.276746] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.270241] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.276765] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.270252] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.277053] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint clare # [ 0.270273] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.277173] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] clare # [ 0.270573] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint bob # [ 0.277184] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.270707] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.277203] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.234580] Initialise system trusted keyrings clare # [ 0.290749] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.235146] workingset: timestamp_bits=42 max_order=18 bucket_order=0 clare # [ 0.290805] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.291154] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.236117] squashfs: version 4.0 (2009/01/31) Phillip Lougher clare # [ 0.291280] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] bob # [ 0.301578] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint clare # [ 0.291292] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.236647] 9p: Installing v9fs 9p2000 file system support bob # [ 0.301723] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.291312] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.301735] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.291613] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint bob # [ 0.301755] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.291736] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.301770] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] clare # [ 0.291746] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.302126] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.291766] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.302253] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.291781] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] bob # [ 0.302273] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.292080] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint bob # [ 0.302600] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint clare # [ 0.292203] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.302727] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.292222] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.302747] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.292528] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint bob # [ 0.303007] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint clare # [ 0.292652] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.303129] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] clare # [ 0.292672] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.303302] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint clare # [ 0.292933] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint bob # [ 0.303535] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] clare # [ 0.293052] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] bob # [ 0.303549] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.293245] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint bob # [ 0.303576] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.293411] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] bob # [ 0.303892] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint clare # [ 0.293422] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.304018] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.293443] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.304029] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.293849] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint bob # [ 0.304049] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.293974] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.304460] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned clare # [ 0.293985] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.304471] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned clare # [ 0.294005] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] bob # [ 0.304476] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned clare # [ 0.294400] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned bob # [ 0.304511] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned clare # [ 0.294410] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned bob # [ 0.304543] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned clare # [ 0.294415] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned bob # [ 0.304575] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned clare # [ 0.294449] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned bob # [ 0.304607] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned clare # [ 0.294481] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned bob # [ 0.304639] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned clare # [ 0.294513] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned bob # [ 0.304671] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned clare # [ 0.294545] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned bob # [ 0.304703] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned clare # [ 0.294577] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned bob # [ 0.304735] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned clare # [ 0.294608] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned bob # [ 0.304766] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned clare # [ 0.294649] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned bob # [ 0.304841] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned clare # [ 0.294682] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned bob # [ 0.304874] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned clare # [ 0.294715] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned bob # [ 0.304889] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned bob # [ 0.304902] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned alice # [ 0.256010] Key type asymmetric registered bob # [ 0.304916] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned alice # [ 0.256035] Asymmetric key parser 'x509' registered bob # [ 0.304931] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned alice # [ 0.256097] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) bob # [ 0.304945] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned bob # [ 0.304959] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned bob # [ 0.304973] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned bob # [ 0.304987] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned bob # [ 0.305002] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned bob # [ 0.305016] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned bob # [ 0.305031] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned bob # [ 0.305044] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.305058] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned bob # [ 0.305071] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned bob # [ 0.305085] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned bob # [ 0.305099] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned bob # [ 0.305118] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned bob # [ 0.305132] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned bob # [ 0.305146] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned bob # [ 0.305160] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned bob # [ 0.305179] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] alice # [ 0.265097] io scheduler mq-deadline registered alice # [ 0.265116] io scheduler kyber registered clare # [ 0.322892] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned bob # [ 0.305185] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] clare # [ 0.322936] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned bob # [ 0.305189] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] clare # [ 0.322953] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned clare # [ 0.322968] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned clare # [ 0.322983] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned clare # [ 0.322999] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned clare # [ 0.323013] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned clare # [ 0.323028] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned clare # [ 0.323043] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned clare # [ 0.323059] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned clare # [ 0.323074] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned clare # [ 0.323089] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned clare # [ 0.323103] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned clare # [ 0.323117] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned clare # [ 0.323130] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned clare # [ 0.323144] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned alice # [ 0.268058] pl061_gpio 9030000.pl061: PL061 GPIO chip registered clare # [ 0.323157] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.268363] ledtrig-cpu: registered to indicate activity on CPUs clare # [ 0.323171] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned alice # [ 0.268642] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: clare # [ 0.323190] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned bob # [ 0.342025] pci 0000:00:0a.0: enabling device (0000 -> 0002) alice # [ 0.268663] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 clare # [ 0.323204] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned clare # [ 0.323217] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned alice # [ 0.268687] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 clare # [ 0.323231] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned alice # [ 0.268693] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 clare # [ 0.323252] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] alice # [ 0.268718] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits clare # [ 0.323258] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] clare # [ 0.323262] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.268740] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] clare # [ 0.324007] pci 0000:00:0a.0: enabling device (0000 -> 0002) alice # [ 0.268813] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 alice # [ 0.268830] pci_bus 0000:00: root bus resource [bus 00-ff] alice # [ 0.268835] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] alice # [ 0.268839] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] alice # [ 0.268843] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] alice # [ 0.268952] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint bob # [ 0.348726] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.269421] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.269551] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.269562] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.269584] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.269597] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.269907] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.270034] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.270046] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.270075] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.270377] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint alice # [ 0.270504] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.270516] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.270536] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.270844] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint alice # [ 0.270970] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.270982] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.271001] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.271307] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint alice # [ 0.271432] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] alice # [ 0.271443] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.271463] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.271757] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint alice # [ 0.271881] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] alice # [ 0.271891] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.271912] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.272216] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint alice # [ 0.272341] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] bob # [ 0.358681] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.272352] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] bob # [ 0.361374] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) alice # [ 0.272372] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.272387] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] alice # [ 0.272721] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint clare # [ 0.346957] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) alice # [ 0.272849] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.348926] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.272869] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] clare # [ 0.350791] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) clare # [ 0.352646] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.354492] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) bob # [ 0.370737] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) bob # [ 0.372867] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) bob # [ 0.375984] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) alice # [ 0.305304] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint alice # [ 0.305449] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.305470] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.305720] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint alice # [ 0.305841] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] alice # [ 0.306021] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint alice # [ 0.306231] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] alice # [ 0.306245] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] alice # [ 0.306265] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.306597] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint alice # [ 0.306720] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] clare # [ 0.371981] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) alice # [ 0.306731] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] clare # [ 0.373833] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) alice # [ 0.306751] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] alice # [ 0.307174] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned alice # [ 0.307187] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned alice # [ 0.307192] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned bob # [ 0.381894] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) alice # [ 0.307227] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned bob # [ 0.384870] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) alice # [ 0.307259] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned alice # [ 0.307290] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned clare # [ 0.376819] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) alice # [ 0.307321] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned clare # [ 0.378646] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.307353] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned alice # [ 0.307384] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned alice # [ 0.307417] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned alice # [ 0.307448] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned alice # [ 0.307479] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned alice # [ 0.307545] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned alice # [ 0.307578] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned alice # [ 0.307592] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned alice # [ 0.307606] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned alice # [ 0.307620] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned alice # [ 0.307634] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned alice # [ 0.307647] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned alice # [ 0.307661] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned alice # [ 0.307674] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned alice # [ 0.307688] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned alice # [ 0.307702] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned alice # [ 0.307717] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned alice # [ 0.307731] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned bob # [ 0.394963] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.307745] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned bob # [ 0.397061] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) alice # [ 0.307759] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned alice # [ 0.307772] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned alice # [ 0.307785] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned alice # [ 0.307799] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned alice # [ 0.307817] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned alice # [ 0.307831] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned alice # [ 0.307845] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned alice # [ 0.307858] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned alice # [ 0.307877] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] alice # [ 0.307884] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] alice # [ 0.307888] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] alice # [ 0.308620] pci 0000:00:0a.0: enabling device (0000 -> 0002) clare # [ 0.391954] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) clare # [ 0.394031] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) clare # [ 0.398571] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.406601] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) alice # [ 0.340634] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) clare # [ 0.400119] msm_serial: driver initialized clare # [ 0.400215] SuperH (H)SCI(F) driver initialized clare # [ 0.400251] STM32 USART driver initialized bob # [ 0.414739] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.416252] msm_serial: driver initialized bob # [ 0.416340] SuperH (H)SCI(F) driver initialized bob # [ 0.416377] STM32 USART driver initialized alice # [ 0.350213] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) alice # [ 0.357257] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) alice # [ 0.359361] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) clare # [ 0.426364] loop: module loaded clare # [ 0.426515] virtio_blk virtio5: 1/0/0 default/read/poll queues bob # [ 0.436946] loop: module loaded bob # [ 0.437079] virtio_blk virtio5: 1/0/0 default/read/poll queues clare # [ 0.431200] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) bob # [ 0.441776] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 0.369199] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) alice # [ 0.371115] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) alice # [ 0.372972] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) alice # [ 0.375334] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) bob # [ 0.445942] megasas: 07.734.00.00-rc1 bob # [ 0.446402] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] clare # [ 0.435181] megasas: 07.734.00.00-rc1 clare # [ 0.435660] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] alice # [ 0.385211] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) alice # [ 0.386937] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) alice # [ 0.389008] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) bob # [ 0.458560] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 bob # [ 0.458605] Intel/Sharp Extended Query Table at 0x0031 bob # [ 0.460379] Using buffer write method bob # [ 0.460429] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.401246] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled bob # [ 0.471259] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.402749] msm_serial: driver initialized bob # [ 0.471286] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.402835] SuperH (H)SCI(F) driver initialized bob # [ 0.472921] Using buffer write method alice # [ 0.402869] STM32 USART driver initialized bob # [ 0.472944] Concatenating MTD devices: bob # [ 0.472947] (0): "0.flash" bob # [ 0.472950] (1): "0.flash" bob # [ 0.472953] into device "0.flash" clare # [ 0.463843] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.463925] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.465857] Using buffer write method clare # [ 0.465909] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] clare # [ 0.469724] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 clare # [ 0.469748] Intel/Sharp Extended Query Table at 0x0031 clare # [ 0.477044] Using buffer write method clare # [ 0.477074] Concatenating MTD devices: clare # [ 0.477076] (0): "0.flash" clare # [ 0.477080] (1): "0.flash" clare # [ 0.477082] into device "0.flash" alice # [ 0.430858] loop: module loaded alice # [ 0.431001] virtio_blk virtio5: 1/0/0 default/read/poll queues alice # [ 0.431617] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) alice # [ 0.437461] megasas: 07.734.00.00-rc1 alice # [ 0.437936] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] alice # [ 0.440470] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.440506] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.442120] Using buffer write method alice # [ 0.442158] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] alice # [ 0.443837] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 alice # [ 0.443855] Intel/Sharp Extended Query Table at 0x0031 alice # [ 0.449441] Using buffer write method alice # [ 0.449494] Concatenating MTD devices: alice # [ 0.449497] (0): "0.flash" alice # [ 0.449500] (1): "0.flash" alice # [ 0.449502] into device "0.flash" bob # [ 0.692378] Freeing initrd memory: 27144K bob # [ 0.700133] tun: Universal TUN/TAP device driver, 1.6 bob # [ 0.702900] thunder_xcv, ver 1.0 bob # [ 0.702935] thunder_bgx, ver 1.0 bob # [ 0.702946] nicpf, ver 1.0 bob # [ 0.703273] e1000: Intel(R) PRO/1000 Network Driver bob # [ 0.703282] e1000: Copyright (c) 1999-2006 Intel Corporation. bob # [ 0.703298] e1000e: Intel(R) PRO/1000 Network Driver bob # [ 0.703305] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.703318] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.703321] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.703332] igbvf: Intel(R) Gigabit Virtual Function Network Driver bob # [ 0.703336] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. bob # [ 0.703400] sky2: driver version 1.30 bob # [ 0.704206] usbcore: registered new interface driver usb-storage bob # [ 0.704289] usbcore: registered new interface driver usbserial_generic bob # [ 0.704298] usbserial: USB Serial support registered for generic bob # [ 0.704589] hv_vmbus: registering driver hyperv_keyboard clare # [ 0.699324] Freeing initrd memory: 27160K bob # [ 0.712021] ehci-pci 0000:00:0a.0: EHCI Host Controller bob # [ 0.712064] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 bob # [ 0.712346] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 bob # [ 0.714075] rtc-pl031 9010000.pl031: registered as rtc0 bob # [ 0.714116] rtc-pl031 9010000.pl031: setting system clock to 2026-08-10T11:05:55 UTC (1786359955) clare # [ 0.706708] tun: Universal TUN/TAP device driver, 1.6 bob # [ 0.714329] i2c_dev: i2c /dev entries driver bob # [ 0.717145] sdhci: Secure Digital Host Controller Interface driver bob # [ 0.717162] sdhci: Copyright(c) Pierre Ossman bob # [ 0.717298] Synopsys Designware Multimedia Card Interface Driver bob # [ 0.717465] sdhci-pltfm: SDHCI platform and OF driver helper clare # [ 0.709364] thunder_xcv, ver 1.0 clare # [ 0.709394] thunder_bgx, ver 1.0 clare # [ 0.709407] nicpf, ver 1.0 bob # [ 0.720202] hid: raw HID events driver (C) Jiri Kosina clare # [ 0.709714] e1000: Intel(R) PRO/1000 Network Driver bob # [ 0.720384] usbcore: registered new interface driver usbhid clare # [ 0.709723] e1000: Copyright (c) 1999-2006 Intel Corporation. clare # [ 0.709738] e1000e: Intel(R) PRO/1000 Network Driver bob # [ 0.720389] usbhid: USB HID core driver clare # [ 0.709744] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. bob # [ 0.721919] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 clare # [ 0.709756] igb: Intel(R) Gigabit Ethernet Network Driver bob # [ 0.722271] hub 1-0:1.0: USB hub found clare # [ 0.709760] igb: Copyright (c) 2007-2014 Intel Corporation. bob # [ 0.722290] hub 1-0:1.0: 6 ports detected clare # [ 0.709771] igbvf: Intel(R) Gigabit Virtual Function Network Driver clare # [ 0.709774] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. clare # [ 0.709837] sky2: driver version 1.30 clare # [ 0.710607] usbcore: registered new interface driver usb-storage bob # [ 0.723946] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available clare # [ 0.710677] usbcore: registered new interface driver usbserial_generic clare # [ 0.710685] usbserial: USB Serial support registered for generic bob # [ 0.724735] drop_monitor: Initializing network drop monitor service bob # [ 0.724905] NET: Registered PF_INET6 protocol family clare # [ 0.711319] ehci-pci 0000:00:0a.0: EHCI Host Controller clare # [ 0.711354] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 clare # [ 0.711587] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 bob # [ 0.727239] Segment Routing with IPv6 bob # [ 0.727266] In-situ OAM (IOAM) with IPv6 bob # [ 0.727324] NET: Registered PF_PACKET protocol family clare # [ 0.719107] hv_vmbus: registering driver hyperv_keyboard bob # [ 0.728421] 9pnet: Installing 9P2000 support clare # [ 0.720237] rtc-pl031 9010000.pl031: registered as rtc0 clare # [ 0.720275] rtc-pl031 9010000.pl031: setting system clock to 2026-08-10T11:05:55 UTC (1786359955) clare # [ 0.720446] i2c_dev: i2c /dev entries driver bob # [ 0.730425] Key type dns_resolver registered clare # [ 0.723276] sdhci: Secure Digital Host Controller Interface driver clare # [ 0.723291] sdhci: Copyright(c) Pierre Ossman clare # [ 0.723423] Synopsys Designware Multimedia Card Interface Driver clare # [ 0.723585] sdhci-pltfm: SDHCI platform and OF driver helper clare # [ 0.724480] hid: raw HID events driver (C) Jiri Kosina clare # [ 0.724651] usbcore: registered new interface driver usbhid clare # [ 0.724656] usbhid: USB HID core driver clare # [ 0.728099] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 bob # [ 0.733689] registered taskstats version 1 clare # [ 0.728910] hub 1-0:1.0: USB hub found bob # [ 0.733862] Loading compiled-in X.509 certificates clare # [ 0.729235] hub 1-0:1.0: 6 ports detected bob # [ 0.739064] Demotion targets for Node 0: null bob # [ 0.739150] Key type .fscrypt registered clare # [ 0.729851] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available bob # [ 0.739156] Key type fscrypt-provisioning registered clare # [ 0.730609] drop_monitor: Initializing network drop monitor service bob # [ 0.739265] ima: No TPM chip found, activating TPM-bypass! bob # [ 0.739277] ima: Allocated hash algorithm: sha1 clare # [ 0.731933] NET: Registered PF_INET6 protocol family bob # [ 0.739335] ima: No architecture policies found clare # [ 0.733022] Segment Routing with IPv6 clare # [ 0.733050] In-situ OAM (IOAM) with IPv6 bob # [ 0.742350] input: gpio-keys as /devices/platform/gpio-keys/input/input0 clare # [ 0.733107] NET: Registered PF_PACKET protocol family clare # [ 0.734206] 9pnet: Installing 9P2000 support clare # [ 0.736177] Key type dns_resolver registered clare # [ 0.739428] registered taskstats version 1 clare # [ 0.739584] Loading compiled-in X.509 certificates bob # [ 0.752707] clk: Disabling unused clocks bob # [ 0.752737] PM: genpd: Disabling unused power domains clare # [ 0.744889] Demotion targets for Node 0: null clare # [ 0.744986] Key type .fscrypt registered clare # [ 0.744992] Key type fscrypt-provisioning registered clare # [ 0.745105] ima: No TPM chip found, activating TPM-bypass! alice # [ 0.686940] Freeing initrd memory: 27160K clare # [ 0.745118] ima: Allocated hash algorithm: sha1 clare # [ 0.745181] ima: No architecture policies found bob # [ 0.756141] Freeing unused kernel memory: 4736K bob # [ 0.756238] Run /init as init process clare # [ 0.748400] input: gpio-keys as /devices/platform/gpio-keys/input/input0 alice # [ 0.694651] tun: Universal TUN/TAP device driver, 1.6 alice # [ 0.697342] thunder_xcv, ver 1.0 alice # [ 0.697371] thunder_bgx, ver 1.0 alice # [ 0.697382] nicpf, ver 1.0 alice # [ 0.697672] e1000: Intel(R) PRO/1000 Network Driver alice # [ 0.697681] e1000: Copyright (c) 1999-2006 Intel Corporation. clare # [ 0.758878] clk: Disabling unused clocks alice # [ 0.697696] e1000e: Intel(R) PRO/1000 Network Driver clare # [ 0.758902] PM: genpd: Disabling unused power domains alice # [ 0.697702] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. alice # [ 0.697715] igb: Intel(R) Gigabit Ethernet Network Driver alice # [ 0.697718] igb: Copyright (c) 2007-2014 Intel Corporation. clare # [ 0.762024] Freeing unused kernel memory: 4736K clare # [ 0.762112] Run /init as init process alice # [ 0.697729] igbvf: Intel(R) Gigabit Virtual Function Network Driver alice # [ 0.697733] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. alice # [ 0.697794] sky2: driver version 1.30 alice # [ 0.698557] usbcore: registered new interface driver usb-storage alice # [ 0.698667] usbcore: registered new interface driver usbserial_generic alice # [ 0.698676] usbserial: USB Serial support registered for generic alice # [ 0.698967] hv_vmbus: registering driver hyperv_keyboard alice # [ 0.706367] ehci-pci 0000:00:0a.0: EHCI Host Controller alice # [ 0.706407] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 bob # [ 0.776176] systemd[1]: Successfully made /usr/ read-only. alice # [ 0.706617] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 alice # [ 0.708308] rtc-pl031 9010000.pl031: registered as rtc0 alice # [ 0.708351] rtc-pl031 9010000.pl031: setting system clock to 2026-08-10T11:05:55 UTC (1786359955) alice # [ 0.708543] i2c_dev: i2c /dev entries driver alice # [ 0.711361] sdhci: Secure Digital Host Controller Interface driver alice # [ 0.711377] sdhci: Copyright(c) Pierre Ossman alice # [ 0.711507] Synopsys Designware Multimedia Card Interface Driver alice # [ 0.711694] sdhci-pltfm: SDHCI platform and OF driver helper alice # [ 0.712556] hid: raw HID events driver (C) Jiri Kosina alice # [ 0.712743] usbcore: registered new interface driver usbhid alice # [ 0.712749] usbhid: USB HID core driver alice # [ 0.716285] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available alice # [ 0.717757] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 alice # [ 0.718531] hub 1-0:1.0: USB hub found alice # [ 0.718870] hub 1-0:1.0: 6 ports detected alice # [ 0.719491] drop_monitor: Initializing network drop monitor service alice # [ 0.719613] NET: Registered PF_INET6 protocol family alice # [ 0.721251] Segment Routing with IPv6 clare # [ 0.781248] systemd[1]: Successfully made /usr/ read-only. alice # [ 0.721279] In-situ OAM (IOAM) with IPv6 alice # [ 0.721345] NET: Registered PF_PACKET protocol family alice # [ 0.722440] 9pnet: Installing 9P2000 support alice # [ 0.723944] Key type dns_resolver registered alice # [ 0.727499] registered taskstats version 1 alice # [ 0.727649] Loading compiled-in X.509 certificates alice # [ 0.732865] Demotion targets for Node 0: null alice # [ 0.732957] Key type .fscrypt registered alice # [ 0.732963] Key type fscrypt-provisioning registered alice # [ 0.734248] ima: No TPM chip found, activating TPM-bypass! alice # [ 0.734266] ima: Allocated hash algorithm: sha1 alice # [ 0.734324] ima: No architecture policies found alice # [ 0.736093] input: gpio-keys as /devices/platform/gpio-keys/input/input0 alice # [ 0.746457] clk: Disabling unused clocks alice # [ 0.746484] PM: genpd: Disabling unused power domains alice # [ 0.749621] Freeing unused kernel memory: 4736K alice # [ 0.749707] Run /init as init process alice # [ 0.767399] systemd[1]: Successfully made /usr/ read-only. bob # [ 0.969522] usb 1-1: new high-speed USB device number 2 using ehci-pci clare # [ 0.974786] usb 1-1: new high-speed USB device number 2 using ehci-pci alice # [ 0.969064] usb 1-1: new high-speed USB device number 2 using ehci-pci bob # [ 1.110992] systemd[1]: systemd 261.1 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.117627] systemd[1]: Detected virtualization qemu. bob # [ 1.119075] systemd[1]: Detected architecture arm64. bob # [ 1.120393] systemd[1]: Running in initrd. bob # [ 1.121187] systemd[1]: Initializing machine ID from random generator. bob # [ 1.124039] systemd[1]: Hostname set to . clare # [ 1.115882] systemd[1]: systemd 261.1 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.122491] systemd[1]: Detected virtualization qemu. clare # [ 1.123933] systemd[1]: Detected architecture arm64. clare # [ 1.124969] systemd[1]: Running in initrd. bob # [ 1.132049] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 clare # [ 1.126845] systemd[1]: Initializing machine ID from random generator. clare # [ 1.128695] systemd[1]: Hostname set to . clare # [ 1.137599] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 alice # [ 1.102048] systemd[1]: systemd 261.1 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.108686] systemd[1]: Detected virtualization qemu. alice # [ 1.109847] systemd[1]: Detected architecture arm64. alice # [ 1.111031] systemd[1]: Running in initrd. alice # [ 1.112677] systemd[1]: Initializing machine ID from random generator. alice # [ 1.114800] systemd[1]: Hostname set to . alice # [ 1.122184] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 bob # [ 1.209651] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 clare # [ 1.222979] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 alice # [ 1.209198] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 bob # [ 1.329519] usb 1-2: new high-speed USB device number 3 using ehci-pci clare # [ 1.342847] usb 1-2: new high-speed USB device number 3 using ehci-pci alice # [ 1.329062] usb 1-2: new high-speed USB device number 3 using ehci-pci bob # [ 1.398719] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 1.403761] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 1.383573] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 1.479509] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 1.492613] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 bob # [ 1.495446] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 clare # [ 1.488482] systemd[1]: Created slice Slice /system/modprobe. clare # [ 1.489310] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. clare # [ 1.490130] systemd[1]: Expecting device /dev/disk/by-label/nixos... clare # [ 1.490885] systemd[1]: Reached target Path Units. clare # [ 1.491709] systemd[1]: Reached target Slice Units. bob # [ 1.500158] systemd[1]: Queued start job for default target Initrd Default Target. clare # [ 1.492204] systemd[1]: Reached target Swaps. clare # [ 1.492638] systemd[1]: Reached target Timer Units. clare # [ 1.493330] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 1.494436] systemd[1]: Listening on Journal Socket (/dev/log). clare # [ 1.495265] systemd[1]: Listening on Journal Sockets. clare # [ 1.495351] systemd[1]: Listening on udev Control Socket. clare # [ 1.495465] systemd[1]: Listening on udev Kernel Socket. clare # [ 1.495490] systemd[1]: Reached target Socket Units. clare # [ 1.499834] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 1.501169] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 clare # [ 1.501275] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 bob # [ 1.512353] systemd[1]: Created slice Slice /system/modprobe. bob # [ 1.513814] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 1.515283] systemd[1]: Expecting device /dev/disk/by-label/nixos... bob # [ 1.516649] systemd[1]: Reached target Path Units. clare # [ 1.507931] systemd[1]: Starting Load Kernel Module 9pnet_virtio... bob # [ 1.517620] systemd[1]: Reached target Slice Units. bob # [ 1.518515] systemd[1]: Reached target Swaps. clare # [ 1.507995] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 1.519318] systemd[1]: Reached target Timer Units. bob # [ 1.520899] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 1.454098] systemd[1]: Queued start job for default target Initrd Default Target. bob # [ 1.522391] systemd[1]: Listening on Journal Socket (/dev/log). clare # [ 1.514854] systemd[1]: Mounting Kernel Configuration File System... bob # [ 1.523994] systemd[1]: Listening on Journal Sockets. bob # [ 1.525099] systemd[1]: Listening on udev Control Socket. bob # [ 1.526326] systemd[1]: Listening on udev Kernel Socket. bob # [ 1.526387] systemd[1]: Reached target Socket Units. bob # [ 1.530181] systemd[1]: Starting Create List of Static Device Nodes... alice # [ 1.463431] systemd[1]: Created slice Slice /system/modprobe. clare # [ 1.524608] systemd[1]: Starting Journal Service... alice # [ 1.464615] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 1.465784] systemd[1]: Expecting device /dev/disk/by-label/nixos... alice # [ 1.466678] systemd[1]: Reached target Path Units. alice # [ 1.467365] systemd[1]: Reached target Slice Units. alice # [ 1.468065] systemd[1]: Reached target Swaps. alice # [ 1.468677] systemd[1]: Reached target Timer Units. alice # [ 1.469727] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 1.470875] systemd[1]: Listening on Journal Socket (/dev/log). alice # [ 1.471974] systemd[1]: Listening on Journal Sockets. alice # [ 1.472817] systemd[1]: Listening on udev Control Socket. bob # [ 1.541663] systemd[1]: Starting Load Kernel Module 9pnet_virtio... alice # [ 1.473819] systemd[1]: Listening on udev Kernel Socket. alice # [ 1.474593] systemd[1]: Reached target Socket Units. bob # [ 1.542725] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.476861] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 1.546049] systemd[1]: Mounting Kernel Configuration File System... alice # [ 1.481923] systemd[1]: Starting Load Kernel Module 9pnet_virtio... alice # [ 1.483228] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 1.485384] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 alice # [ 1.485549] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 clare # [ 1.551780] systemd[1]: Starting Load Kernel Modules... clare # [ 1.552429] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 1.500204] systemd[1]: Mounting Kernel Configuration File System... bob # [ 1.569811] systemd[1]: Starting Journal Service... clare # [ 1.562948] systemd[1]: Starting Coldplug All udev Devices... bob # [ 1.581361] systemd[1]: Starting Load Kernel Modules... bob # [ 1.585727] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os clare # [ 1.580157] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 1.581051] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. clare # [ 1.581233] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 1.533255] systemd[1]: Starting Journal Service... bob # [ 1.604327] systemd[1]: Starting Coldplug All udev Devices... bob # [ 1.606154] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 1.607058] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. alice # [ 1.541247] systemd[1]: Starting Load Kernel Modules... clare # [ 1.596318] systemd-journald[73]: Collecting audit messages is disabled. alice # [ 1.541988] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 1.614380] systemd[1]: Finished Load Kernel Module 9pnet_virtio. bob # [ 1.614830] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.549184] systemd[1]: Starting Coldplug All udev Devices... alice # [ 1.552561] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 1.554106] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. bob # [ 1.622353] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. bob # [ 1.624058] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 1.615040] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 1.615526] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.561277] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 1.561699] systemd[1]: Mounted Kernel Configuration File System. alice # [ 1.565337] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 1.640925] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev clare # [ 1.630134] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. clare # [ 1.636109] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 1.639041] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 1.648106] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 bob # [ 1.648332] [drm] features: -virgl +edid -resource_blob -host_visible bob # [ 1.648337] [drm] features: -context_init bob # [ 1.648793] [drm] number of scanouts: 1 bob # [ 1.648807] [drm] number of cap sets: 0 bob # [ 1.652331] systemd-journald[73]: Collecting audit messages is disabled. clare # [ 1.646906] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev clare # [ 1.652774] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 clare # [ 1.653009] [drm] features: -virgl +edid -resource_blob -host_visible clare # [ 1.653015] [drm] features: -context_init clare # [ 1.653497] [drm] number of scanouts: 1 clare # [ 1.653515] [drm] number of cap sets: 0 alice # [ 1.604299] systemd-journald[73]: Collecting audit messages is disabled. bob # [ 1.674892] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic alice # [ 1.609160] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 1.674914] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 bob # [ 1.680805] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 1.611473] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 1.682701] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 1.671099] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic clare # [ 1.671117] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 clare # [ 1.682075] systemd[1]: Started Journal Service. alice # [ 1.622330] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. clare # [ 1.676265] systemd-modules-load[75]: Inserted module 'dm_mod' clare # [ 1.677096] systemd-modules-load[75]: Module 'virtio_balloon' is built in bob # [ 1.693889] Console: switching to colour frame buffer device 160x50 clare # [ 1.684334] systemd-modules-load[75]: Module 'virtio_console' is built in clare # [ 1.694846] Console: switching to colour frame buffer device 160x50 bob # [ 1.710138] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.637104] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev clare # [ 1.697481] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 1.642936] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 clare # [ 1.698155] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 1.643160] [drm] features: -virgl +edid -resource_blob -host_visible clare # [ 1.698742] systemd[1]: Reached target Local File Systems. alice # [ 1.643165] [drm] features: -context_init alice # [ 1.643640] [drm] number of scanouts: 1 alice # [ 1.643658] [drm] number of cap sets: 0 clare # [ 1.705363] systemd[1]: Starting Create System Files and Directories... clare # [ 1.715345] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.657513] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 1.657674] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 1.657705] systemd[1]: Reached target Local File Systems. alice # [ 1.660215] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 1.729843] systemd[1]: Finished Load Kernel Modules. bob # [ 1.731423] systemd[1]: Starting Apply Kernel Variables... bob # [ 1.724632] systemd-modules-load[74]: Inserted module 'dm_mod' bob # [ 1.734857] systemd[1]: Started Journal Service. clare # [ 1.720588] systemd-modules-load[75]: Inserted module 'virtio_gpu' clare # [ 1.721259] systemd-modules-load[75]: Module 'virtio_rng' is built in bob # [ 1.730229] systemd-modules-load[74]: Module 'virtio_balloon' is built in clare # [ 1.723955] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 1.731021] systemd-modules-load[74]: Module 'virtio_console' is built in bob # [ 1.731701] systemd-modules-load[74]: Inserted module 'virtio_gpu' alice # [ 1.673308] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic alice # [ 1.673328] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 bob # [ 1.738369] systemd-modules-load[74]: Module 'virtio_rng' is built in clare # [ 1.732124] systemd[1]: Finished Load Kernel Modules. bob # [ 1.744072] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 1.686199] Console: switching to colour frame buffer device 160x50 bob # [ 1.749381] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 1.754799] systemd[1]: Reached target Local File Systems. bob # [ 1.756142] systemd[1]: Starting Create System Files and Directories... alice # [ 1.688073] systemd-modules-load[74]: Inserted module 'dm_mod' alice # [ 1.688847] systemd-modules-load[74]: Module 'virtio_balloon' is built in alice # [ 1.689573] systemd-modules-load[74]: Module 'virtio_console' is built in alice # [ 1.699924] systemd[1]: Started Journal Service. clare # [ 1.756892] systemd[1]: Starting Apply Kernel Variables... clare # [ 1.758033] systemd[1]: Finished Create System Files and Directories. alice # [ 1.706580] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device alice # [ 1.700601] systemd-modules-load[74]: Inserted module 'virtio_gpu' alice # [ 1.701215] systemd-modules-load[74]: Module 'virtio_rng' is built in bob # [ 1.772860] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 1.705254] systemd[1]: Starting Create System Files and Directories... clare # [ 1.768795] systemd[1]: Finished Apply Kernel Variables. bob # [ 1.784267] systemd[1]: Finished Apply Kernel Variables. alice # [ 1.717506] systemd[1]: Finished Load Kernel Modules. clare # [ 1.781336] systemd-udevd[85]: Using default interface naming scheme 'v261'. bob # [ 1.788760] systemd[1]: Finished Create System Files and Directories. alice # [ 1.733678] systemd[1]: Starting Apply Kernel Variables... clare # [ 1.805175] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 1.751707] systemd-udevd[80]: Using default interface naming scheme 'v261'. alice # [ 1.756907] systemd[1]: Finished Apply Kernel Variables. bob # [ 1.826786] systemd-udevd[88]: Using default interface naming scheme 'v261'. alice # [ 1.759627] systemd[1]: Finished Create System Files and Directories. alice # [ 1.776350] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 1.852985] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 1.855967] systemd[1]: Starting Virtual Console Setup... bob # [ 1.900099] systemd[1]: Starting Virtual Console Setup... alice # [ 1.832072] systemd[1]: Starting Virtual Console Setup... clare # [ 1.904410] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 1.906773] systemd[1]: Finished Virtual Console Setup. bob # [ 1.932414] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 1.934679] systemd[1]: Finished Virtual Console Setup. alice # [ 1.876400] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 1.878777] systemd[1]: Finished Virtual Console Setup. bob # [ 2.117558] systemd[1]: Finished Coldplug All udev Devices. bob # [ 2.118160] systemd[1]: Reached target System Initialization. bob # [ 2.118649] systemd[1]: Reached target Basic System. clare # [ 2.116978] systemd[1]: Finished Coldplug All udev Devices. clare # [ 2.117573] systemd[1]: Reached target System Initialization. clare # [ 2.120087] systemd[1]: Reached target Basic System. alice # [ 2.101171] systemd[1]: Finished Coldplug All udev Devices. alice # [ 2.104063] systemd[1]: Reached target System Initialization. alice # [ 2.104646] systemd[1]: Reached target Basic System. clare # [ 2.215395] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.226172] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.236244] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line. bob # [ 2.256109] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.202061] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line. alice # [ 2.231649] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. clare # [ 2.299219] systemd[1]: Found device /dev/disk/by-label/nixos. clare # [ 2.311988] systemd[1]: Reached target Initrd Root Device. clare # [ 2.316290] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... bob # [ 2.327357] systemd[1]: Found device /dev/disk/by-label/nixos. bob # [ 2.329128] systemd[1]: Reached target Initrd Root Device. bob # [ 2.332179] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... clare # [ 2.372494] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks alice # [ 2.313585] systemd[1]: Found device /dev/disk/by-label/nixos. bob # [ 2.382762] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks clare # [ 2.377360] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 2.315868] systemd[1]: Reached target Initrd Root Device. alice # [ 2.318409] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... bob # [ 2.386945] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. alice # [ 2.365392] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks alice # [ 2.370303] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. clare # [ 2.576101] systemd[1]: Mounting /sysroot... bob # [ 2.598013] systemd[1]: Mounting /sysroot... alice # [ 2.543663] systemd[1]: Mounting /sysroot... clare # [ 2.614311] EXT4-fs (vda): mounted filesystem bcec5caa-deff-4191-a2f3-cd9438fc1d91 r/w with ordered data mode. Quota mode: none. clare # [ 2.613710] systemd[1]: Mounted /sysroot. clare # [ 2.615406] systemd[1]: Reached target Initrd Root File System. clare # [ 2.619488] systemd[1]: Starting Mountpoints Configured in the Real Root... bob # [ 2.640361] EXT4-fs (vda): mounted filesystem 3a6352b6-5f85-4d91-92db-ca7d2dab13ee r/w with ordered data mode. Quota mode: none. bob # [ 2.635969] systemd[1]: Mounted /sysroot. bob # [ 2.637915] systemd[1]: Reached target Initrd Root File System. bob # [ 2.642403] systemd[1]: Mounting /sysroot/nix/.ro-store... alice # [ 2.581523] EXT4-fs (vda): mounted filesystem 38c8d190-9c4a-4d8f-93a2-4502c758445c r/w with ordered data mode. Quota mode: none. alice # [ 2.579225] systemd[1]: Mounted /sysroot. alice # [ 2.580858] systemd[1]: Reached target Initrd Root File System. clare # [ 2.643414] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 2.582140] systemd[1]: Starting Mountpoints Configured in the Real Root... bob # [ 2.658902] systemd[1]: Mounting /sysroot/nix/.rw-store... clare # [ 2.652466] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)... clare # [ 2.654323] systemd[1]: Reloading... bob # [ 2.663548] systemd[1]: Mounting /sysroot/run... alice # [ 2.603029] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload. alice # [ 2.609152] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)... alice # [ 2.612151] systemd[1]: Reloading... bob # [ 2.687361] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 2.697958] systemd[1]: Mounting /sysroot/tmp/xchg... bob # [ 2.707622] systemd[1]: Starting Mountpoints Configured in the Real Root... bob # [ 2.719580] systemd[1]: Mounted /sysroot/nix/.ro-store. bob # [ 2.724611] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 2.746738] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload. bob # [ 2.758162] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 2.761609] systemd[1]: Mounted /sysroot/run. bob # [ 2.771757] systemd[1]: Mounted /sysroot/tmp/shared. bob # [ 2.773813] systemd[1]: Mounted /sysroot/tmp/xchg. bob # [ 2.778644] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)... bob # [ 2.780752] systemd[1]: Reloading... clare # [ 2.831187] systemd[1]: Reloading finished in 179 ms. alice # [ 2.789112] systemd[1]: Reloading finished in 177 ms. clare # [ 2.856109] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace... clare # [ 2.858232] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace... clare # [ 2.863626] systemd[1]: Starting Load Kernel Module 9pnet_virtio... clare # [ 2.868615] systemd[1]: initrd-parse-etc.service: Deactivated successfully. clare # [ 2.877943] systemd[1]: Finished Mountpoints Configured in the Real Root. clare # [ 2.878614] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.817525] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace... clare # [ 2.880731] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. alice # [ 2.820627] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace... clare # [ 2.882969] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 2.822788] systemd[1]: Starting Load Kernel Module 9pnet_virtio... alice # [ 2.829174] systemd[1]: initrd-parse-etc.service: Deactivated successfully. alice # [ 2.832096] systemd[1]: Finished Mountpoints Configured in the Real Root. alice # [ 2.833372] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. alice # [ 2.850662] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. alice # [ 2.851631] systemd[1]: Finished Load Kernel Module 9pnet_virtio. bob # [ 2.954696] systemd[1]: Reloading finished in 176 ms. bob # [ 2.977093] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace... bob # [ 2.980826] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 2.981497] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 2.982646] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace... bob # [ 2.991463] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 2.993752] systemd[1]: initrd-parse-etc.service: Deactivated successfully. bob # [ 3.001962] systemd[1]: Finished Mountpoints Configured in the Real Root. bob # [ 3.002638] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. bob # [ 3.004979] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 3.006507] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 3.145399] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/x3ma60wd3w0db1m4dr1xln72q96lnm3b-systemd-261.1/lib/udev/mtd_probe": No such file or directory clare # [ 3.149427] (udev-worker)[93]: mtd0ro: /nix/store/x3ma60wd3w0db1m4dr1xln72q96lnm3b-systemd-261.1/lib/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.160080] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 3.162548] systemd[1]: Stopped Virtual Console Setup. clare # [ 3.163252] systemd[1]: Stopping Virtual Console Setup... clare # [ 3.163755] systemd[1]: Starting Virtual Console Setup... alice # [ 3.113060] (udev-worker)[92]: mtd0ro: Failed to find and pin callout binary "/nix/store/x3ma60wd3w0db1m4dr1xln72q96lnm3b-systemd-261.1/lib/udev/mtd_probe": No such file or directory alice # [ 3.116789] (udev-worker)[92]: mtd0ro: /nix/store/x3ma60wd3w0db1m4dr1xln72q96lnm3b-systemd-261.1/lib/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.183761] systemd-vconsole-setup[141]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 3.186455] systemd[1]: Finished Virtual Console Setup. alice # [ 3.126961] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 3.128810] systemd[1]: Stopped Virtual Console Setup. alice # [ 3.130091] systemd[1]: Stopping Virtual Console Setup... alice # [ 3.132924] systemd[1]: Starting Virtual Console Setup... alice # [ 3.155572] systemd-vconsole-setup[141]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 3.223900] (udev-worker)[101]: mtd0ro: Failed to find and pin callout binary "/nix/store/x3ma60wd3w0db1m4dr1xln72q96lnm3b-systemd-261.1/lib/udev/mtd_probe": No such file or directory alice # [ 3.158465] systemd[1]: Finished Virtual Console Setup. bob # [ 3.229218] (udev-worker)[101]: mtd0ro: /nix/store/x3ma60wd3w0db1m4dr1xln72q96lnm3b-systemd-261.1/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory bob # [ 3.244887] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 3.248066] systemd[1]: Stopped Virtual Console Setup. bob # [ 3.248575] systemd[1]: Stopping Virtual Console Setup... bob # [ 3.252120] systemd[1]: Starting Virtual Console Setup... bob # [ 3.271841] systemd-vconsole-setup[153]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 3.274408] systemd[1]: Finished Virtual Console Setup. clare # [ 3.578385] systemd[1]: Mounting /sysroot/nix/.ro-store... clare # [ 3.589871] systemd[1]: Mounting /sysroot/nix/.rw-store... clare # [ 3.591739] systemd[1]: Mounting /sysroot/run... bob # [ 3.598917] systemd[1]: Mounting /sysroot/nix/store... clare # [ 3.604305] systemd[1]: Mounting /sysroot/tmp/shared... alice # [ 3.545041] systemd[1]: Mounting /sysroot/nix/.ro-store... alice # [ 3.556331] systemd[1]: Mounting /sysroot/nix/.rw-store... clare # [ 3.623832] systemd[1]: Mounting /sysroot/tmp/xchg... alice # [ 3.563031] systemd[1]: Mounting /sysroot/run... alice # [ 3.577989] systemd[1]: Mounting /sysroot/tmp/shared... bob # [ 3.652353] systemd[1]: Mounted /sysroot/nix/store. bob # [ 3.654523] systemd[1]: Reached target Initrd File Systems. bob # [ 3.658191] systemd[1]: Starting Find NixOS closure... clare # [ 3.653577] systemd[1]: Mounted /sysroot/nix/.rw-store. bob # [ 3.666013] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... alice # [ 3.600711] systemd[1]: Mounting /sysroot/tmp/xchg... clare # [ 3.669231] systemd[1]: Starting rw-sysroot-nix-store.service... clare # [ 3.672298] systemd[1]: Mounted /sysroot/nix/.ro-store. clare # [ 3.676958] systemd[1]: Mounted /sysroot/run. alice # [ 3.617061] systemd[1]: Mounted /sysroot/nix/.ro-store. clare # [ 3.681618] systemd[1]: Mounted /sysroot/tmp/shared. clare # [ 3.683133] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 3.625178] systemd[1]: Mounted /sysroot/nix/.rw-store. clare # [ 3.694853] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. clare # [ 3.695978] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 3.640149] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 3.642634] systemd[1]: Mounted /sysroot/run. bob # [ 3.715049] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. bob # [ 3.716621] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. alice # [ 3.655538] systemd[1]: Mounted /sysroot/tmp/shared. alice # [ 3.659364] systemd[1]: Mounted /sysroot/tmp/xchg. alice # [ 3.660768] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 3.661849] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 3.734968] systemd[1]: Finished Find NixOS closure. bob # [ 3.740341] systemd[1]: Reached target Initrd Default Target. bob # [ 3.741314] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... bob # [ 3.769681] systemd[1]: initrd-cleanup.service: Deactivated successfully. bob # [ 3.771256] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. bob # [ 3.774985] systemd[1]: Stopped target Initrd Default Target. bob # [ 3.775812] systemd[1]: Stopped target Basic System. bob # [ 3.776641] systemd[1]: Stopped target Initrd Root Device. bob # [ 3.779947] systemd[1]: Stopped target Path Units. bob # [ 3.780910] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. bob # [ 3.782380] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. bob # [ 3.784383] systemd[1]: Stopped target Slice Units. bob # [ 3.788154] systemd[1]: Stopped target Socket Units. bob # [ 3.788839] systemd[1]: Stopped target System Initialization. bob # [ 3.789561] systemd[1]: Stopped target Swaps. bob # [ 3.790208] systemd[1]: Stopped target Timer Units. bob # [ 3.791115] systemd[1]: dbus.socket: Deactivated successfully. bob # [ 3.791808] systemd[1]: Closed D-Bus System Message Bus Socket. bob # [ 3.797202] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. bob # [ 3.799170] systemd[1]: Stopped Find NixOS closure. bob # [ 3.800445] systemd[1]: Starting Load Kernel Module 9pnet_virtio... bob # [ 3.804343] systemd[1]: Starting rw-sysroot-nix-store.service... bob # [ 3.808241] systemd[1]: systemd-sysctl.service: Deactivated successfully. bob # [ 3.808974] systemd[1]: Stopped Apply Kernel Variables. bob # [ 3.809526] systemd[1]: systemd-modules-load.service: Deactivated successfully. bob # [ 3.811077] systemd[1]: Stopped Load Kernel Modules. bob # [ 3.811684] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. bob # [ 3.821225] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. bob # [ 3.821984] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. bob # [ 3.827568] systemd[1]: Stopped Create System Files and Directories. bob # [ 3.828437] systemd[1]: Stopped target Local File Systems. bob # [ 3.829825] systemd[1]: Stopped target Preparation for Local File Systems. bob # [ 3.832261] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. bob # [ 3.832940] systemd[1]: Stopped Coldplug All udev Devices. bob # [ 3.833880] systemd[1]: Stopping Rule-based Manager for Device Events and Files... bob # [ 3.836298] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 3.836963] systemd[1]: Stopped Virtual Console Setup. bob # [ 3.838563] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. bob # [ 3.839205] systemd[1]: Finished rw-sysroot-nix-store.service. bob # [ 3.839767] systemd[1]: systemd-udevd.service: Deactivated successfully. bob # [ 3.840565] systemd[1]: Stopped Rule-based Manager for Device Events and Files. bob # [ 3.841176] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. bob # [ 3.841777] systemd[1]: Closed udev Control Socket. bob # [ 3.842210] systemd[1]: Starting Cleanup udev Database... bob # [ 3.842688] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. bob # [ 3.843324] systemd[1]: Stopped Create Static Device Nodes in /dev. bob # [ 3.843844] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. bob # [ 3.852211] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. bob # [ 3.852860] systemd[1]: kmod-static-nodes.service: Deactivated successfully. bob # [ 3.853840] systemd[1]: Stopped Create List of Static Device Nodes. bob # [ 3.854645] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. bob # [ 3.855291] systemd[1]: Finished Load Kernel Module 9pnet_virtio. bob # [ 3.866728] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. bob # [ 3.868847] systemd[1]: Finished Cleanup udev Database. bob # [ 3.872249] systemd[1]: Reached target Switch Root. bob # [ 3.873294] systemd[1]: Starting NixOS Activation... bob # [ 4.077526] initrd-nixos-activation-start[179]: booting system configuration /nix/store/n50v5jw8ki6zbxqbm2p955bkjgnkwzdc-nixos-system-bob-test bob # [ 4.149768] initrd-nixos-activation-start[179]: running activation script... clare # [ 4.575808] systemd[1]: Mounting /sysroot/nix/store... alice # [ 4.543874] systemd[1]: Mounting /sysroot/nix/store... bob # [ 4.630696] initrd-nixos-activation-start[202]: setting up /etc... clare # [ 4.627696] systemd[1]: Mounted /sysroot/nix/store. clare # [ 4.631430] systemd[1]: Reached target Initrd File Systems. clare # [ 4.636290] systemd[1]: Starting Find NixOS closure... clare # [ 4.639349] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... alice # [ 4.595505] systemd[1]: Mounted /sysroot/nix/store. alice # [ 4.599231] systemd[1]: Reached target Initrd File Systems. alice # [ 4.604299] systemd[1]: Starting Find NixOS closure... alice # [ 4.607419] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... clare # [ 4.687621] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. clare # [ 4.694082] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. clare # [ 4.708714] systemd[1]: Finished Find NixOS closure. clare # [ 4.711078] systemd[1]: Reached target Initrd Default Target. clare # [ 4.712664] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... alice # [ 4.657144] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. alice # [ 4.659721] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. alice # [ 4.674228] systemd[1]: Finished Find NixOS closure. alice # [ 4.676251] systemd[1]: Reached target Initrd Default Target. alice # [ 4.677535] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... clare # [ 4.743188] systemd[1]: Stopped target Initrd Default Target. clare # [ 4.745383] systemd[1]: Stopped target Basic System. clare # [ 4.748534] systemd[1]: Stopped target Initrd Root Device. clare # [ 4.749369] systemd[1]: Stopped target Path Units. clare # [ 4.750647] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. clare # [ 4.753187] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. clare # [ 4.755817] systemd[1]: Stopped target Slice Units. clare # [ 4.760173] systemd[1]: Stopped target Socket Units. clare # [ 4.760859] systemd[1]: Stopped target System Initialization. clare # [ 4.761587] systemd[1]: Stopped target Swaps. clare # [ 4.762145] systemd[1]: Stopped target Timer Units. clare # [ 4.762762] systemd[1]: dbus.socket: Deactivated successfully. clare # [ 4.763493] systemd[1]: Closed D-Bus System Message Bus Socket. clare # [ 4.768155] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. clare # [ 4.769031] systemd[1]: Stopped Find NixOS closure. clare # [ 4.769603] systemd[1]: Starting Load Kernel Module 9pnet_virtio... clare # [ 4.770837] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 4.709438] systemd[1]: initrd-cleanup.service: Deactivated successfully. clare # [ 4.772505] systemd[1]: systemd-sysctl.service: Deactivated successfully. alice # [ 4.712128] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. clare # [ 4.775524] systemd[1]: Stopped Apply Kernel Variables. alice # [ 4.716158] systemd[1]: Stopped target Initrd Default Target. clare # [ 4.778414] systemd[1]: systemd-modules-load.service: Deactivated successfully. alice # [ 4.717009] systemd[1]: Stopped target Basic System. alice # [ 4.717700] systemd[1]: Stopped target Initrd Root Device. clare # [ 4.781153] systemd[1]: Stopped Load Kernel Modules. clare # [ 4.781810] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. alice # [ 4.719990] systemd[1]: Stopped target Path Units. alice # [ 4.721106] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. alice # [ 4.723179] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. alice # [ 4.728179] systemd[1]: Stopped target Slice Units. alice # [ 4.728767] systemd[1]: Stopped target Socket Units. alice # [ 4.729350] systemd[1]: Stopped target System Initialization. alice # [ 4.730065] systemd[1]: Stopped target Swaps. alice # [ 4.730593] systemd[1]: Stopped target Timer Units. alice # [ 4.731139] systemd[1]: dbus.socket: Deactivated successfully. alice # [ 4.731764] systemd[1]: Closed D-Bus System Message Bus Socket. clare # [ 4.794280] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 4.796515] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. clare # [ 4.797325] systemd[1]: Stopped Create System Files and Directories. alice # [ 4.736912] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. alice # [ 4.737790] systemd[1]: Stopped Find NixOS closure. alice # [ 4.738651] systemd[1]: Starting Load Kernel Module 9pnet_virtio... clare # [ 4.800987] systemd[1]: Stopped target Local File Systems. clare # [ 4.802441] systemd[1]: Stopped target Preparation for Local File Systems. alice # [ 4.741063] systemd[1]: Starting rw-sysroot-nix-store.service... alice # [ 4.743620] systemd[1]: systemd-sysctl.service: Deactivated successfully. clare # [ 4.806181] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. clare # [ 4.807459] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 4.746082] systemd[1]: Stopped Apply Kernel Variables. clare # [ 4.808726] systemd[1]: Stopping Rule-based Manager for Device Events and Files... alice # [ 4.747324] systemd[1]: systemd-modules-load.service: Deactivated successfully. clare # [ 4.810236] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 4.811497] systemd[1]: Stopped Virtual Console Setup. alice # [ 4.749872] systemd[1]: Stopped Load Kernel Modules. clare # [ 4.812648] systemd[1]: initrd-cleanup.service: Deactivated successfully. alice # [ 4.750570] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. clare # [ 4.813929] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. alice # [ 4.751890] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. clare # [ 4.815763] systemd[1]: systemd-udevd.service: Deactivated successfully. clare # [ 4.816671] systemd[1]: Stopped Rule-based Manager for Device Events and Files. clare # [ 4.820369] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. clare # [ 4.821006] systemd[1]: Finished Load Kernel Module 9pnet_virtio. clare # [ 4.821521] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 4.759464] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. clare # [ 4.822114] systemd[1]: Finished rw-sysroot-nix-store.service. clare # [ 4.822594] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. clare # [ 4.823181] systemd[1]: Closed udev Control Socket. clare # [ 4.823609] systemd[1]: Starting Cleanup udev Database... alice # [ 4.761705] systemd[1]: Stopped Create System Files and Directories. clare # [ 4.825489] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. clare # [ 4.826183] systemd[1]: Stopped Create Static Device Nodes in /dev. clare # [ 4.826718] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. clare # [ 4.827414] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. clare # [ 4.828132] systemd[1]: kmod-static-nodes.service: Deactivated successfully. clare # [ 4.828735] systemd[1]: Stopped Create List of Static Device Nodes. alice # [ 4.767642] systemd[1]: Stopped target Local File Systems. alice # [ 4.770163] systemd[1]: Stopped target Preparation for Local File Systems. alice # [ 4.771163] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. alice # [ 4.771905] systemd[1]: Stopped Coldplug All udev Devices. alice # [ 4.776212] systemd[1]: Stopping Rule-based Manager for Device Events and Files... alice # [ 4.776972] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. alice # [ 4.779089] systemd[1]: Stopped Virtual Console Setup. alice # [ 4.779640] systemd[1]: systemd-udevd.service: Deactivated successfully. alice # [ 4.781481] systemd[1]: Stopped Rule-based Manager for Device Events and Files. alice # [ 4.783960] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. alice # [ 4.784924] systemd[1]: Closed udev Control Socket. alice # [ 4.785581] systemd[1]: Starting Cleanup udev Database... alice # [ 4.786187] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. alice # [ 4.786908] systemd[1]: Stopped Create Static Device Nodes in /dev. clare # [ 4.849444] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. alice # [ 4.787494] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. alice # [ 4.788400] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. clare # [ 4.851118] systemd[1]: Finished Cleanup udev Database. alice # [ 4.789029] systemd[1]: kmod-static-nodes.service: Deactivated successfully. clare # [ 4.851631] systemd[1]: Reached target Switch Root. alice # [ 4.789633] systemd[1]: Stopped Create List of Static Device Nodes. clare # [ 4.852373] systemd[1]: Starting NixOS Activation... alice # [ 4.790355] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. alice # [ 4.790984] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 4.791513] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. alice # [ 4.795417] systemd[1]: Finished rw-sysroot-nix-store.service. alice # [ 4.809872] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. alice # [ 4.811592] systemd[1]: Finished Cleanup udev Database. alice # [ 4.813969] systemd[1]: Reached target Switch Root. alice # [ 4.816393] systemd[1]: Starting NixOS Activation... bob # [ 4.896264] initrd-nixos-activation-start[202]: setting up age secrets... clare # [ 5.046123] initrd-nixos-activation-start[179]: booting system configuration /nix/store/7w3nvwgwnlg68glawvsng5zlh5xmq3vi-nixos-system-clare-test alice # [ 5.011531] initrd-nixos-activation-start[179]: booting system configuration /nix/store/2n6lzdv6aipl0kivcm3x5jl3dyk2zz33-nixos-system-alice-test bob # [ 5.106703] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. bob # [ 5.108133] systemd[1]: Finished NixOS Activation. bob # [ 5.109266] systemd[1]: Starting Switch Root... clare # [ 5.112823] initrd-nixos-activation-start[179]: running activation script... bob # [ 5.125370] systemd[1]: Switching root. alice # [ 5.086559] initrd-nixos-activation-start[179]: running activation script... bob # [ 5.304501] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). clare # [ 5.584691] initrd-nixos-activation-start[202]: setting up /etc... alice # [ 5.573220] initrd-nixos-activation-start[202]: setting up /etc... clare # [ 5.825126] initrd-nixos-activation-start[202]: setting up age secrets... alice # [ 5.808491] initrd-nixos-activation-start[202]: setting up age secrets... bob # [ 5.913366] systemd[1]: systemd 261.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) bob # [ 5.920041] systemd[1]: Detected virtualization qemu. bob # [ 5.921816] systemd[1]: Detected architecture arm64. bob # [ 5.924161] systemd[1]: Detected first boot. bob # [ 5.929274] systemd[1]: Initializing machine ID from random generator. clare # [ 5.982152] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. clare # [ 5.983894] systemd[1]: Finished NixOS Activation. clare # [ 5.987761] systemd[1]: Starting Switch Root... clare # [ 6.005279] systemd[1]: Switching root. alice # [ 5.969450] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. alice # [ 5.971038] systemd[1]: Finished NixOS Activation. alice # [ 5.975015] systemd[1]: Starting Switch Root... alice # [ 5.985051] systemd[1]: Switching root. clare # [ 6.181166] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). bob # [ 6.222380] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 6.163589] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). bob # [ 6.405619] systemd[1]: Applying preset policy. clare # [ 6.792460] systemd[1]: systemd 261.1 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 # [ 6.799259] systemd[1]: Detected virtualization qemu. clare # [ 6.800830] systemd[1]: Detected architecture arm64. clare # [ 6.802918] systemd[1]: Detected first boot. clare # [ 6.807402] systemd[1]: Initializing machine ID from random generator. alice # [ 6.764980] systemd[1]: systemd 261.1 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 # [ 6.771613] systemd[1]: Detected virtualization qemu. alice # [ 6.773400] systemd[1]: Detected architecture arm64. alice # [ 6.775746] systemd[1]: Detected first boot. alice # [ 6.781327] systemd[1]: Initializing machine ID from random generator. bob # [ 6.950877] systemd[1]: Populated /etc with preset unit settings. alice # [ 7.053778] systemd[1]: bpf-restrict-fs: LSM BPF program attached clare # [ 7.127803] systemd[1]: bpf-restrict-fs: LSM BPF program attached alice # [ 7.198796] systemd[1]: Applying preset policy. clare # [ 7.298092] systemd[1]: Applying preset policy. bob # [ 7.528343] systemd[1]: initrd-switch-root.service: Deactivated successfully. bob # [ 7.529219] systemd[1]: Stopped initrd-switch-root.service. bob # [ 7.531426] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. bob # [ 7.534541] systemd[1]: Created slice Slice /system/getty. bob # [ 7.535561] systemd[1]: Created slice User and Session Slice. bob # [ 7.536306] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. bob # [ 7.538283] systemd[1]: Started Forward Password Requests to Wall Directory Watch. bob # [ 7.539040] systemd[1]: Expecting device /dev/hvc0... bob # [ 7.540018] systemd[1]: Expecting device /dev/ttyAMA0... bob # [ 7.540918] systemd[1]: Reached target Local Encrypted Volumes. bob # [ 7.542103] systemd[1]: Stopped target initrd-fs.target. bob # [ 7.543101] systemd[1]: Stopped target initrd-root-fs.target. bob # [ 7.544141] systemd[1]: Stopped target initrd-switch-root.target. bob # [ 7.545835] systemd[1]: Reached target Virtual Machines and Containers. bob # [ 7.547400] systemd[1]: Reached target Path Units. bob # [ 7.548813] systemd[1]: Reached target Remote File Systems. bob # [ 7.550366] systemd[1]: Reached target Slice Units. bob # [ 7.551222] systemd[1]: Reached target Swaps. bob # [ 7.555322] systemd[1]: Listening on Query the User Interactively for a Password. bob # [ 7.559947] systemd[1]: Listening on Process Core Dump Socket. bob # [ 7.563633] systemd[1]: Listening on Credential Encryption/Decryption. bob # [ 7.567235] systemd[1]: Listening on Factory Reset Management. bob # [ 7.568270] systemd[1]: Listening on Hostname Service Socket. bob # [ 7.573016] systemd[1]: Starting Journal Log Access Socket... bob # [ 7.574684] systemd[1]: Listening on Journal Audit Socket. bob # [ 7.578304] systemd[1]: Listening on Console Output Muting Service Socket. bob # [ 7.579460] systemd[1]: Listening on Network Management Metrics Varlink Socket. bob # [ 7.581245] systemd[1]: Listening on Network Management Varlink Socket. bob # [ 7.583167] systemd[1]: Listening on Network Management Netlink Socket. bob # [ 7.584957] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. bob # [ 7.586794] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os bob # [ 7.589078] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki bob # [ 7.599952] systemd[1]: Listening on Disk Repartitioning Service Socket. bob # [ 7.600440] systemd[1]: Listening on Resolve Monitor Varlink Socket. bob # [ 7.600782] systemd[1]: Listening on Resolve Service Varlink Socket. bob # [ 7.601112] systemd[1]: Listening on udev Control Socket. bob # [ 7.601442] systemd[1]: Listening on udev Varlink Socket. bob # [ 7.606892] systemd[1]: Mounting Huge Pages File System... bob # [ 7.610765] systemd[1]: Mounting POSIX Message Queue File System... bob # [ 7.617023] systemd[1]: Mounting Kernel Debug File System... bob # [ 7.622640] systemd[1]: Mounting Kernel Trace File System... bob # [ 7.634814] systemd[1]: Starting Create List of Static Device Nodes... bob # [ 7.646391] systemd[1]: Starting Load Kernel Module 9pnet_virtio... bob # [ 7.649143] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 7.660051] systemd[1]: Mounting Kernel Configuration File System... bob # [ 7.663242] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm bob # [ 7.667016] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore bob # [ 7.679270] systemd[1]: Starting Load Kernel Module fuse... bob # [ 7.681352] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 alice # [ 7.680769] systemd[1]: Populated /etc with preset unit settings. bob # [ 7.751485] systemd[1]: Starting Journal Service... bob # [ 7.775986] systemd[1]: Starting Load Kernel Modules... bob # [ 7.805221] fuse: init (API version 7.45) bob # [ 7.811551] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... bob # [ 7.826542] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 7.823974] systemd[1]: Populated /etc with preset unit settings. bob # [ 7.830193] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 7.851774] systemd[1]: Starting Coldplug All udev Devices... bob # [ 7.856338] systemd[1]: Listening on Journal Log Access Socket. bob # [ 7.863691] systemd[1]: Mounted Huge Pages File System. bob # [ 7.867587] systemd[1]: Mounted POSIX Message Queue File System. bob # [ 7.871512] systemd[1]: Mounted Kernel Debug File System. bob # [ 7.878875] systemd-journald[296]: Collecting audit messages is enabled. bob # [ 7.883184] systemd[1]: Mounted Kernel Trace File System. bob # [ 7.888764] systemd[1]: Finished Create List of Static Device Nodes. bob # [ 7.892440] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. bob # [ 7.888410] systemd[1]: Queued start job for default target Multi-User System. bob # [ 7.889187] systemd[1]: systemd-journald.service: Deactivated successfully. bob # [ 7.890045] systemd-modules-load[297]: Module 'atkbd' is built in bob # [ 7.890670] systemd-modules-load[297]: Module 'loop' is built in bob # [ 7.901415] systemd[1]: Finished Load Kernel Module 9pnet_virtio. bob # [ 7.903156] systemd[1]: Started Journal Service. bob # [ 7.899386] systemd[1]: Mounted Kernel Configuration File System. bob # [ 7.905807] systemd[1]: modprobe@fuse.service: Deactivated successfully. bob # [ 7.906901] systemd[1]: Finished Load Kernel Module fuse. bob # [ 7.909038] systemd[1]: Finished Load Kernel Modules. bob # [ 7.923032] systemd[1]: Mounting FUSE Control File System... bob # [ 7.933830] EXT4-fs (vda): re-mounted 3a6352b6-5f85-4d91-92db-ca7d2dab13ee. bob # [ 7.929001] systemd[1]: Starting Firewall... bob # [ 7.937007] systemd[1]: Starting Apply Kernel Variables... bob # [ 7.958744] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... bob # [ 7.971482] systemd-oomd[299]: No swap; memory pressure usage will be degraded bob # [ 7.976649] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 7.986704] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. bob # [ 7.999761] systemd[1]: Listening on Disk Image Download Service Socket. bob # [ 8.011566] systemd[1]: Starting Flush Journal to Persistent Storage... bob # [ 8.018532] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 8.031803] systemd[1]: Starting Load/Save OS Random Seed... bob # [ 8.033119] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 8.073086] systemd[1]: Mounted FUSE Control File System. bob # [ 8.088824] systemd-journald[296]: Received client request to flush runtime journal. bob # [ 8.162421] systemd[1]: Finished Apply Kernel Variables. bob # [ 8.163700] systemd[1]: Starting Network Name Resolution... bob # [ 8.167232] systemd[1]: Finished Load/Save OS Random Seed. bob # [ 8.172359] systemd[1]: Reached target First Boot Complete. bob # [ 8.174698] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. bob # [ 8.175305] systemd[1]: Starting Create Static Device Nodes in /dev... bob # [ 8.182024] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 8.263267] systemd-resolved[325]: Positive Trust Anchors: bob # [ 8.264336] systemd-resolved[325]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d bob # [ 8.268291] systemd-resolved[325]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 bob # [ 8.272242] systemd-resolved[325]: 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 # [ 8.227296] systemd[1]: initrd-switch-root.service: Deactivated successfully. alice # [ 8.228168] systemd[1]: Stopped initrd-switch-root.service. alice # [ 8.230276] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. alice # [ 8.232916] systemd[1]: Created slice Slice /system/getty. alice # [ 8.233957] systemd[1]: Created slice User and Session Slice. alice # [ 8.234775] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 8.235967] systemd[1]: Started Forward Password Requests to Wall Directory Watch. alice # [ 8.237306] systemd[1]: Expecting device /dev/hvc0... alice # [ 8.237336] systemd[1]: Expecting device /dev/ttyAMA0... alice # [ 8.237369] systemd[1]: Reached target Local Encrypted Volumes. alice # [ 8.237396] systemd[1]: Stopped target initrd-fs.target. alice # [ 8.237877] systemd[1]: Stopped target initrd-root-fs.target. alice # [ 8.238177] systemd[1]: Stopped target initrd-switch-root.target. bob # [ 8.300743] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 8.238465] systemd[1]: Reached target Virtual Machines and Containers. alice # [ 8.238779] systemd[1]: Reached target Path Units. alice # [ 8.239053] systemd[1]: Reached target Remote File Systems. alice # [ 8.239317] systemd[1]: Reached target Slice Units. alice # [ 8.239585] systemd[1]: Reached target Swaps. bob # [ 8.303454] systemd[1]: Reached target Preparation for Local File Systems. alice # [ 8.247312] systemd[1]: Listening on Query the User Interactively for a Password. bob # [ 8.309647] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 8.251797] systemd[1]: Listening on Process Core Dump Socket. alice # [ 8.255475] systemd[1]: Listening on Credential Encryption/Decryption. alice # [ 8.259093] systemd[1]: Listening on Factory Reset Management. alice # [ 8.260101] systemd[1]: Listening on Hostname Service Socket. alice # [ 8.265361] systemd[1]: Starting Journal Log Access Socket... alice # [ 8.267196] systemd[1]: Listening on Journal Audit Socket. alice # [ 8.271562] systemd[1]: Listening on Console Output Muting Service Socket. alice # [ 8.273418] systemd[1]: Listening on Network Management Metrics Varlink Socket. alice # [ 8.275301] systemd[1]: Listening on Network Management Varlink Socket. alice # [ 8.277508] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 8.279249] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. alice # [ 8.280890] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 8.282674] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki alice # [ 8.292904] systemd[1]: Listening on Disk Repartitioning Service Socket. alice # [ 8.293442] systemd[1]: Listening on Resolve Monitor Varlink Socket. alice # [ 8.293801] systemd[1]: Listening on Resolve Service Varlink Socket. alice # [ 8.294144] systemd[1]: Listening on udev Control Socket. alice # [ 8.294485] systemd[1]: Listening on udev Varlink Socket. alice # [ 8.300188] systemd[1]: Mounting Huge Pages File System... alice # [ 8.303866] systemd[1]: Mounting POSIX Message Queue File System... alice # [ 8.313183] systemd[1]: Mounting Kernel Debug File System... alice # [ 8.317886] systemd[1]: Mounting Kernel Trace File System... clare # [ 8.381299] systemd[1]: initrd-switch-root.service: Deactivated successfully. clare # [ 8.382150] systemd[1]: Stopped initrd-switch-root.service. clare # [ 8.384513] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. clare # [ 8.387648] systemd[1]: Created slice Slice /system/getty. clare # [ 8.388622] systemd[1]: Created slice User and Session Slice. clare # [ 8.389349] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. alice # [ 8.330252] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 8.391151] systemd[1]: Started Forward Password Requests to Wall Directory Watch. clare # [ 8.391771] systemd[1]: Expecting device /dev/hvc0... clare # [ 8.392167] systemd[1]: Expecting device /dev/ttyAMA0... clare # [ 8.392594] systemd[1]: Reached target Local Encrypted Volumes. clare # [ 8.393090] systemd[1]: Stopped target initrd-fs.target. clare # [ 8.394133] systemd[1]: Stopped target initrd-root-fs.target. clare # [ 8.395424] systemd[1]: Stopped target initrd-switch-root.target. clare # [ 8.396446] systemd[1]: Reached target Virtual Machines and Containers. clare # [ 8.397519] systemd[1]: Reached target Path Units. clare # [ 8.399089] systemd[1]: Reached target Remote File Systems. clare # [ 8.399977] systemd[1]: Reached target Slice Units. clare # [ 8.401504] systemd[1]: Reached target Swaps. alice # [ 8.341436] systemd[1]: Starting Load Kernel Module 9pnet_virtio... alice # [ 8.343167] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs clare # [ 8.405718] systemd[1]: Listening on Query the User Interactively for a Password. clare # [ 8.410316] systemd[1]: Listening on Process Core Dump Socket. alice # [ 8.353539] systemd[1]: Mounting Kernel Configuration File System... clare # [ 8.414202] systemd[1]: Listening on Credential Encryption/Decryption. alice # [ 8.355846] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm clare # [ 8.417990] systemd[1]: Listening on Factory Reset Management. alice # [ 8.358106] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore clare # [ 8.419040] systemd[1]: Listening on Hostname Service Socket. clare # [ 8.424043] systemd[1]: Starting Journal Log Access Socket... clare # [ 8.425624] systemd[1]: Listening on Journal Audit Socket. clare # [ 8.429480] systemd[1]: Listening on Console Output Muting Service Socket. clare # [ 8.430620] systemd[1]: Listening on Network Management Metrics Varlink Socket. clare # [ 8.431996] systemd[1]: Listening on Network Management Varlink Socket. clare # [ 8.433717] systemd[1]: Listening on Network Management Netlink Socket. alice # [ 8.374071] systemd[1]: Starting Load Kernel Module fuse... clare # [ 8.435542] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. clare # [ 8.437290] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os alice # [ 8.377187] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 clare # [ 8.439253] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki bob # [ 8.447979] systemd-udevd[338]: Using default interface naming scheme 'v261'. clare # [ 8.450083] systemd[1]: Listening on Disk Repartitioning Service Socket. clare # [ 8.450618] systemd[1]: Listening on Resolve Monitor Varlink Socket. clare # [ 8.452005] systemd[1]: Listening on Resolve Service Varlink Socket. clare # [ 8.453122] systemd[1]: Listening on udev Control Socket. clare # [ 8.454084] systemd[1]: Listening on udev Varlink Socket. clare # [ 8.457932] systemd[1]: Mounting Huge Pages File System... clare # [ 8.462050] systemd[1]: Mounting POSIX Message Queue File System... clare # [ 8.468007] systemd[1]: Mounting Kernel Debug File System... clare # [ 8.472392] systemd[1]: Mounting Kernel Trace File System... clare # [ 8.486276] systemd[1]: Starting Create List of Static Device Nodes... clare # [ 8.495532] systemd[1]: Starting Load Kernel Module 9pnet_virtio... clare # [ 8.496478] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 8.437493] systemd[1]: Starting Journal Service... clare # [ 8.504105] systemd[1]: Mounting Kernel Configuration File System... clare # [ 8.506013] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm clare # [ 8.510666] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore bob # [ 8.524590] systemd[1]: Mounting /run/wrappers... clare # [ 8.525344] systemd[1]: Starting Load Kernel Module fuse... clare # [ 8.526858] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 alice # [ 8.468474] systemd[1]: Starting Load Kernel Modules... clare # [ 8.538301] systemd[1]: Starting Journal Service... bob # [ 8.545555] systemd-resolved[325]: Using system hostname 'bob'. bob # [ 8.556803] systemd[1]: Started Network Name Resolution. alice # [ 8.502405] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... clare # [ 8.572709] systemd[1]: Starting Load Kernel Modules... alice # [ 8.524829] fuse: init (API version 7.45) clare # [ 8.590206] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... alice # [ 8.536113] systemd[1]: Starting Remount Root and Kernel File Systems... alice # [ 8.542132] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 8.606595] systemd[1]: Mounted /run/wrappers. bob # [ 8.607977] systemd[1]: Reached target Local File Systems. bob # [ 8.613370] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 8.615677] systemd[1]: Starting Remount Root and Kernel File Systems... clare # [ 8.616072] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os bob # [ 8.618252] systemd[1]: Starting register-nix-paths.service... bob # [ 8.621853] systemd[1]: Starting Create SUID/SGID Wrappers... bob # [ 8.624119] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. alice # [ 8.564288] systemd[1]: Starting Coldplug All udev Devices... bob # [ 8.627745] systemd[1]: Starting Save Transient machine-id to Disk... alice # [ 8.572515] systemd[1]: Listening on Journal Log Access Socket. alice # [ 8.579428] systemd[1]: Mounted Huge Pages File System. bob # [ 8.642494] systemd[1]: Starting Create System Files and Directories... alice # [ 8.583444] systemd[1]: Mounted POSIX Message Queue File System. alice # [ 8.587096] systemd[1]: Mounted Kernel Debug File System. alice # [ 8.583779] systemd[1]: Queued start job for default target Multi-User System. alice # [ 8.589358] systemd-journald[288]: Collecting audit messages is enabled. clare # [ 8.655602] systemd[1]: Starting Coldplug All udev Devices... alice # [ 8.595883] systemd[1]: Started Journal Service. clare # [ 8.668923] systemd-journald[288]: Collecting audit messages is enabled. alice # [ 8.599773] systemd[1]: systemd-journald.service: Deactivated successfully. alice # [ 8.603922] systemd-modules-load[289]: Module 'atkbd' is built in clare # [ 8.676656] fuse: init (API version 7.45) clare # [ 8.680253] systemd[1]: Listening on Journal Log Access Socket. alice # [ 8.611387] systemd-modules-load[289]: Module 'loop' is built in clare # [ 8.678160] systemd[1]: Queued start job for default target Multi-User System. clare # [ 8.678976] systemd[1]: systemd-journald.service: Deactivated successfully. alice # [ 8.619165] systemd[1]: Mounted Kernel Trace File System. alice # [ 8.620813] systemd[1]: Finished Create List of Static Device Nodes. alice # [ 8.623396] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. alice # [ 8.624805] systemd[1]: Finished Load Kernel Module 9pnet_virtio. alice # [ 8.632374] systemd[1]: Mounted Kernel Configuration File System. alice # [ 8.633011] systemd[1]: modprobe@fuse.service: Deactivated successfully. alice # [ 8.633679] systemd[1]: Finished Load Kernel Module fuse. alice # [ 8.634237] systemd[1]: Finished Load Kernel Modules. alice # [ 8.634754] systemd[1]: Mounting FUSE Control File System... alice # [ 8.635272] systemd[1]: Starting Firewall... alice # [ 8.637991] systemd[1]: Starting Apply Kernel Variables... alice # [ 8.661287] EXT4-fs (vda): re-mounted 38c8d190-9c4a-4d8f-93a2-4502c758445c. clare # [ 8.723571] systemd[1]: Started Journal Service. clare # [ 8.726941] EXT4-fs (vda): re-mounted bcec5caa-deff-4191-a2f3-cd9438fc1d91. clare # [ 8.726955] systemd-modules-load[289]: Module 'atkbd' is built in clare # [ 8.728715] systemd-modules-load[289]: Module 'loop' is built in alice # [ 8.669107] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 8.732241] systemd[1]: Mounted Huge Pages File System. clare # [ 8.732756] systemd[1]: Mounted POSIX Message Queue File System. clare # [ 8.733283] systemd[1]: Mounted Kernel Debug File System. clare # [ 8.733751] systemd[1]: Mounted Kernel Trace File System. bob # [ 8.745415] systemd[1]: Started Rule-based Manager for Device Events and Files. alice # [ 8.677488] systemd[1]: Finished Remount Root and Kernel File Systems. clare # [ 8.739407] systemd[1]: Finished Create List of Static Device Nodes. clare # [ 8.744510] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. clare # [ 8.747160] systemd[1]: Finished Load Kernel Module 9pnet_virtio. clare # [ 8.749861] systemd[1]: Mounted Kernel Configuration File System. clare # [ 8.753310] systemd[1]: modprobe@fuse.service: Deactivated successfully. clare # [ 8.753955] systemd[1]: Finished Load Kernel Module fuse. bob # [ 8.761897] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. clare # [ 8.755729] systemd[1]: Finished Load Kernel Modules. clare # [ 8.758349] systemd[1]: Finished Remount Root and Kernel File Systems. bob # [ 8.767010] systemd[1]: Finished Save Transient machine-id to Disk. alice # [ 8.699761] systemd-oomd[290]: No swap; memory pressure usage will be degraded clare # [ 8.764232] systemd[1]: Listening on Disk Image Download Service Socket. clare # [ 8.769188] systemd[1]: Starting Firewall... clare # [ 8.770945] systemd[1]: Starting Flush Journal to Persistent Storage... clare # [ 8.772782] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore alice # [ 8.720345] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. clare # [ 8.783401] systemd[1]: Starting Load/Save OS Random Seed... clare # [ 8.813751] systemd-oomd[291]: No swap; memory pressure usage will be degraded clare # [ 8.816123] systemd[1]: Starting Apply Kernel Variables... alice # [ 8.757932] systemd[1]: Listening on Disk Image Download Service Socket. clare # [ 8.826340] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... clare # [ 8.828541] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 8.772754] systemd[1]: Starting Flush Journal to Persistent Storage... alice # [ 8.775940] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore bob # [ 8.845059] systemd[1]: Finished Create System Files and Directories. clare # [ 8.830814] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. alice # [ 8.783020] systemd[1]: Starting Load/Save OS Random Seed... alice # [ 8.783734] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os alice # [ 8.785521] systemd[1]: Mounted FUSE Control File System. bob # [ 8.858911] systemd[1]: Starting Rebuild Journal Catalog... alice # [ 8.793622] systemd[1]: Finished Apply Kernel Variables. bob # [ 8.862198] systemd[1]: Starting Record System Boot/Shutdown in UTMP... clare # [ 8.876400] systemd-journald[288]: Received client request to flush runtime journal. alice # [ 8.833072] systemd[1]: Starting Network Name Resolution... alice # [ 8.837406] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. alice # [ 8.859087] systemd[1]: Starting Create Static Device Nodes in /dev... clare # [ 8.924687] systemd[1]: Finished Load/Save OS Random Seed. clare # [ 8.929632] systemd[1]: Reached target First Boot Complete. clare # [ 8.932840] systemd[1]: Finished Flush Journal to Persistent Storage. alice # [ 8.912418] systemd-journald[288]: Received client request to flush runtime journal. clare # [ 8.970887] systemd[1]: Finished Apply Kernel Variables. bob # [ 8.977805] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 8.979713] systemd[1]: Starting Network Name Resolution... clare # [ 8.995949] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. clare # [ 9.012211] systemd[1]: Starting Create Static Device Nodes in /dev... alice # [ 8.981364] systemd[1]: Finished Load/Save OS Random Seed. alice # [ 8.986136] systemd[1]: Reached target First Boot Complete. alice # [ 8.990103] systemd[1]: Finished Flush Journal to Persistent Storage. bob # [ 9.078643] systemd[1]: Finished Rebuild Journal Catalog. bob # [ 9.084168] systemd[1]: Finished Coldplug All udev Devices. alice # [ 9.016132] systemd[1]: Finished Create Static Device Nodes in /dev. alice # [ 9.016868] systemd[1]: Reached target Preparation for Local File Systems. bob # [ 9.090825] systemd[1]: Starting Update is Completed... alice # [ 9.022438] systemd[1]: Starting Rule-based Manager for Device Events and Files... alice # [ 9.026344] systemd-resolved[318]: Positive Trust Anchors: alice # [ 9.032235] systemd-resolved[318]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d alice # [ 9.036407] systemd-resolved[318]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 alice # [ 9.037259] systemd-resolved[318]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test clare # [ 9.131254] systemd[1]: Finished Create Static Device Nodes in /dev. clare # [ 9.132430] systemd[1]: Reached target Preparation for Local File Systems. clare # [ 9.136431] systemd[1]: Starting Rule-based Manager for Device Events and Files... bob # [ 9.152678] systemd[1]: Finished Update is Completed. clare # [ 9.139390] systemd-resolved[319]: Positive Trust Anchors: clare # [ 9.148694] systemd-resolved[319]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d clare # [ 9.149567] systemd-resolved[319]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 clare # [ 9.150372] systemd-resolved[319]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test alice # [ 9.150826] systemd-udevd[332]: Using default interface naming scheme 'v261'. clare # [ 9.250235] systemd-udevd[331]: Using default interface naming scheme 'v261'. alice # [ 9.222423] systemd[1]: Mounting /run/wrappers... bob # [ 9.298578] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs alice # [ 9.291537] systemd-resolved[318]: Using system hostname 'alice'. alice # [ 9.300071] systemd[1]: Mounted /run/wrappers. alice # [ 9.302642] systemd[1]: Started Network Name Resolution. alice # [ 9.304126] systemd[1]: Reached target Local File Systems. alice # [ 9.309825] systemd[1]: Listening on Boot Loader Control Service Socket. alice # [ 9.313848] systemd[1]: Starting register-nix-paths.service... alice # [ 9.316596] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 9.317265] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. clare # [ 9.384125] systemd[1]: Mounting /run/wrappers... clare # [ 9.387280] systemd[1]: Mounting FUSE Control File System... alice # [ 9.326905] systemd[1]: Starting Save Transient machine-id to Disk... clare # [ 9.407020] systemd-resolved[319]: Using system hostname 'clare'. alice # [ 9.347210] systemd[1]: Starting Create System Files and Directories... clare # [ 9.412508] systemd[1]: Started Network Name Resolution. clare # [ 9.477666] systemd[1]: Started Rule-based Manager for Device Events and Files. clare # [ 9.481464] systemd[1]: Mounted /run/wrappers. clare # [ 9.484741] systemd[1]: Mounted FUSE Control File System. clare # [ 9.487400] systemd[1]: Reached target Local File Systems. clare # [ 9.497437] systemd[1]: Listening on Boot Loader Control Service Socket. clare # [ 9.500853] systemd[1]: Starting register-nix-paths.service... alice # [ 9.443585] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. clare # [ 9.510664] systemd[1]: Starting Create SUID/SGID Wrappers... alice # [ 9.452942] systemd[1]: Finished Save Transient machine-id to Disk. clare # [ 9.515943] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. bob # [ 9.525809] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. clare # [ 9.520370] systemd[1]: Starting Save Transient machine-id to Disk... bob # [ 9.529074] systemd[1]: Finished Create SUID/SGID Wrappers. alice # [ 9.481120] systemd[1]: Started Rule-based Manager for Device Events and Files. bob # [ 9.564777] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. clare # [ 9.566285] systemd[1]: Starting Create System Files and Directories... alice # [ 9.534448] systemd[1]: Finished Create System Files and Directories. alice # [ 9.555911] systemd[1]: Starting Rebuild Journal Catalog... alice # [ 9.558563] systemd[1]: Starting Record System Boot/Shutdown in UTMP... bob # [ 9.629275] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. clare # [ 9.674702] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. clare # [ 9.688163] systemd[1]: Finished Save Transient machine-id to Disk. alice # [ 9.655459] systemd[1]: Finished Record System Boot/Shutdown in UTMP. clare # [ 9.735862] systemd[1]: Finished Create System Files and Directories. clare # [ 9.757310] systemd[1]: Starting Rebuild Journal Catalog... clare # [ 9.759448] systemd[1]: Starting Record System Boot/Shutdown in UTMP... clare # [ 9.783668] systemd[1]: Finished Coldplug All udev Devices. alice # [ 9.757003] systemd[1]: Finished Rebuild Journal Catalog. alice # [ 9.767995] systemd[1]: Starting Update is Completed... clare # [ 9.853364] systemd[1]: Finished Record System Boot/Shutdown in UTMP. alice # [ 9.792855] systemd[1]: Finished Coldplug All udev Devices. bob # [ 9.872752] systemd[1]: Finished register-nix-paths.service. bob # [ 9.874483] systemd[1]: Reached target System Initialization. bob # [ 9.877051] systemd[1]: Started Discard unused filesystem blocks once a week. bob # [ 9.879343] systemd[1]: Started Daily Cleanup of Temporary Directories. bob # [ 9.881162] systemd[1]: Reached target Timer Units. bob # [ 9.884205] systemd[1]: Listening on D-Bus System Message Bus Socket. bob # [ 9.886139] systemd[1]: Listening on Nix Daemon Socket. bob # [ 9.889880] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. bob # [ 9.892440] systemd[1]: Reached target Socket Units. bob # [ 9.896894] systemd[1]: Reached target Basic System. bob # [ 9.897388] systemd[1]: Started backdoor.service. bob # [ 9.897830] systemd[1]: Starting Import lastlog data into lastlog2 database... bob # [ 9.903149] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 9.909640] (udev-worker)[431]: Network interface NamePolicy= disabled on kernel command line. alice # [ 9.843797] systemd[1]: Finished Update is Completed. bob # [ 9.922830] systemd[1]: Starting Post-Boot Actions... clare # [ 9.921103] systemd[1]: Finished Rebuild Journal Catalog. clare # [ 9.928550] systemd[1]: Starting Update is Completed... bob # [ 9.942956] (udev-worker)[435]: Network interface NamePolicy= disabled on kernel command line. bob # [ 9.952480] systemd[1]: Started Reset console on configuration changes. bob # [ 10.002413] systemd[1]: Starting D-Bus System Message Bus... bob # [ 10.007979] systemd[1]: Finished Firewall. clare # [ 10.003638] systemd[1]: Finished Update is Completed. bob # [ 10.032452] systemd[1]: Reached target Preparation for Network. clare # [ 10.026490] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # [ 10.035996] systemd[1]: Listening on Network Management Resolve Hook Socket. alice # [ 9.980740] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs bob # connecting to host... bob # [ 10.065988] systemd[1]: Starting Network Management... bob # [ 10.070964] systemd[1]: Finished Post-Boot Actions. bob # [ 10.090701] nsncd[514]: Aug 10 11:06:04.884 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" bob # [ 10.097772] systemd[1]: Started Name Service Cache Daemon (nsncd). bob # [ 10.103692] systemd[1]: Reached target Host and Network Name Lookups. bob # [ 10.105933] systemd[1]: Reached target User and Group Name Lookups. bob: Guest shell says: b'Spawning backdoor root shell...\n' bob # [ 10.133665] systemd[1]: Starting User Login Management... bob # [ 10.161117] systemd[1]: Finished Import lastlog data into lastlog2 database. bob: connected to guest root shell bob: (connecting took 10.43 seconds) bob: (finished: waiting for the VM to finish booting, in 10.43 seconds) bob # [ 10.258639] dbus-broker-launch[527]: Looking up NSS user entry for 'systemd-timesync'... bob # [ 10.264570] dbus-broker-launch[527]: NSS returned no entry for 'systemd-timesync' bob # [ 10.267171] dbus-broker-launch[527]: Invalid user-name in /nix/store/4yfqbzvgm687dcck44nsm64wp3mypxic-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" clare # [ 10.279872] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. alice # [ 10.246416] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. bob # [ 10.316346] systemd[1]: Started D-Bus System Message Bus. bob # [ 10.325900] systemd-logind[546]: Watching system buttons on /dev/input/event0 (gpio-keys) bob # [ 10.337177] mousedev: PS/2 mouse device common for all mice bob # [ 10.329769] systemd-logind[546]: New seat seat0. alice # [ 10.268820] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. alice # [ 10.271005] systemd[1]: Finished Create SUID/SGID Wrappers. clare # [ 10.345211] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. bob # [ 10.367173] systemd-logind[546]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) alice # [ 10.299169] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. bob # [ 10.391986] dbus-broker-launch[527]: Ready bob # [ 10.392499] systemd[1]: Started User Login Management. clare # [ 10.424388] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. bob # [ 10.440080] cfg80211: Loading compiled-in X.509 certificates for regulatory database clare # [ 10.427272] systemd[1]: Finished Create SUID/SGID Wrappers. bob # [ 10.442737] systemd[1]: Starting linger-users.service... bob # [ 10.496035] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' bob # [ 10.496543] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 10.501833] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 bob # [ 10.502230] cfg80211: failed to load regulatory.db bob # [ 10.518511] systemd[1]: linger-users.service: Deactivated successfully. bob # [ 10.520417] systemd[1]: Finished linger-users.service. bob # [ 10.562388] systemd-networkd[531]: lo: Link UP bob # [ 10.562875] systemd-networkd[531]: lo: Gained carrier bob # [ 10.569629] systemd-networkd[531]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. bob # [ 10.573494] systemd[1]: Started Network Management. bob # [ 10.578568] systemd[1]: Reached target Network. bob # [ 10.578993] systemd[1]: Reached target Network is Online. bob # [ 10.579449] systemd-networkd[531]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 10.587003] systemd-networkd[531]: eth1: Configuring with /etc/systemd/network/40-eth1.network. bob # [ 10.590916] systemd-networkd[531]: eth0: Link UP bob # [ 10.591367] systemd-networkd[531]: eth0: Gained carrier bob # [ 10.591814] systemd[1]: Starting ncps binary cache proxy service... bob # [ 10.595584] systemd-networkd[531]: eth1: Link UP bob # [ 10.598574] systemd-networkd[531]: eth1: Gained carrier bob # [ 10.599044] systemd-networkd[531]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 10.606468] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... bob # [ 10.608852] systemd[1]: Starting Permit User Sessions... bob # [ 10.612842] systemd-networkd[531]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 alice # [ 10.573980] (udev-worker)[432]: Network interface NamePolicy= disabled on kernel command line. alice # [ 10.579693] (udev-worker)[431]: Network interface NamePolicy= disabled on kernel command line. clare # [ 10.644582] (udev-worker)[361]: Network interface NamePolicy= disabled on kernel command line. clare # [ 10.652535] (udev-worker)[362]: Network interface NamePolicy= disabled on kernel command line. alice # [ 10.593918] systemd[1]: Finished register-nix-paths.service. alice # [ 10.595817] systemd[1]: Reached target System Initialization. alice # [ 10.599186] systemd[1]: Started Discard unused filesystem blocks once a week. alice # [ 10.602370] systemd[1]: Started Daily Cleanup of Temporary Directories. alice # [ 10.606667] systemd[1]: Reached target Timer Units. alice # [ 10.607139] systemd[1]: Listening on D-Bus System Message Bus Socket. alice # [ 10.607704] systemd[1]: Listening on Nix Daemon Socket. alice # [ 10.611278] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. alice # [ 10.616474] systemd[1]: Reached target Socket Units. alice # [ 10.618533] systemd[1]: Reached target Basic System. bob # [ 10.687758] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. alice # [ 10.620930] systemd[1]: Started backdoor.service. alice # [ 10.622578] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 10.628171] systemd[1]: Starting Name Service Cache Daemon (nsncd)... bob # [ 10.705490] systemd[1]: Finished Permit User Sessions. alice # [ 10.640343] systemd[1]: Starting Post-Boot Actions... bob # [ 10.710072] systemd[1]: Started Getty on tty1. bob # [ 10.713033] systemd[1]: Reached target Login Prompts. alice # [ 10.656611] systemd[1]: Started Reset console on configuration changes. alice # [ 10.668955] systemd[1]: Finished Firewall. clare # [ 10.747862] systemd[1]: Finished register-nix-paths.service. clare # [ 10.750578] systemd[1]: Reached target System Initialization. alice # [ 10.690370] systemd[1]: Reached target Preparation for Network. clare # [ 10.752624] systemd[1]: Started Discard unused filesystem blocks once a week. bob # [ 10.759162] ncps-pre-start[573]: /nix/store/2drzhvl5yp8lnl7mpv0592gvq13cgsa6-ncps-0.9.4/bin/dbmate-ncps up alice # [ 10.694496] systemd[1]: Listening on Network Management Resolve Hook Socket. clare # [ 10.755530] systemd[1]: Started Daily Cleanup of Temporary Directories. clare # [ 10.758778] systemd[1]: Reached target Timer Units. clare # [ 10.760625] systemd[1]: Listening on D-Bus System Message Bus Socket. clare # [ 10.766132] systemd[1]: Listening on harmonia binary cache socket. clare # [ 10.767200] systemd[1]: Listening on Nix Daemon Socket. clare # [ 10.768551] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. clare # [ 10.770553] systemd[1]: Reached target Socket Units. clare # [ 10.773993] systemd[1]: Reached target Basic System. alice # [ 10.714616] systemd[1]: Starting D-Bus System Message Bus... clare # [ 10.776964] systemd[1]: Started backdoor.service. clare # [ 10.782363] systemd[1]: Starting Import lastlog data into lastlog2 database... alice # [ 10.724483] systemd[1]: Starting Network Management... clare # [ 10.796816] systemd[1]: Starting Name Service Cache Daemon (nsncd)... clare # [ 10.803857] systemd[1]: Starting Post-Boot Actions... clare # [ 10.820993] systemd[1]: Started Reset console on configuration changes. alice # connecting to host... alice # [ 10.765649] systemd[1]: Finished Post-Boot Actions. clare # [ 10.831131] systemd[1]: Finished Firewall. clare # [ 10.859248] mousedev: PS/2 mouse device common for all mice alice # [ 10.800941] nsncd[511]: Aug 10 11:06:05.599 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # [ 10.868145] systemd[1]: Reached target Preparation for Network. alice # [ 10.807286] systemd[1]: Started Name Service Cache Daemon (nsncd). alice # [ 10.812781] systemd[1]: Reached target Host and Network Name Lookups. alice # [ 10.813405] systemd[1]: Reached target User and Group Name Lookups. clare # [ 10.871342] systemd[1]: Listening on Network Management Resolve Hook Socket. alice # [ 10.823135] systemd[1]: Starting User Login Management... clare # [ 10.894690] systemd[1]: Starting D-Bus System Message Bus... clare # [ 10.914450] systemd[1]: Starting Network Management... clare # connecting to host... alice # [ 10.865007] systemd[1]: Finished Import lastlog data into lastlog2 database. clare # [ 10.939317] systemd[1]: Finished Post-Boot Actions. alice # [ 10.902392] mousedev: PS/2 mouse device common for all mice clare # [ 10.961290] systemd[1]: Finished Import lastlog data into lastlog2 database. clare # [ 10.971818] nsncd[514]: Aug 10 11:06:05.755 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" clare # [ 10.981516] systemd[1]: Started Name Service Cache Daemon (nsncd). clare # [ 10.982035] systemd[1]: Reached target Host and Network Name Lookups. clare # [ 10.982552] systemd[1]: Reached target User and Group Name Lookups. clare # [ 10.987927] systemd[1]: Starting User Login Management... alice # [ 10.956229] dbus-broker-launch[518]: Looking up NSS user entry for 'systemd-timesync'... alice # [ 10.965841] dbus-broker-launch[518]: NSS returned no entry for 'systemd-timesync' alice # [ 10.966606] dbus-broker-launch[518]: Invalid user-name in /nix/store/im35mv372xkacmc3hv72yvfq1xiqq9mb-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 10.981764] systemd-logind[536]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) alice # [ 10.985717] systemd-logind[536]: Watching system buttons on /dev/input/event0 (gpio-keys) alice # [ 10.988844] systemd-logind[536]: New seat seat0. alice # [ 10.992264] systemd[1]: Started User Login Management. alice # [ 10.994274] systemd[1]: Starting linger-users.service... alice # [ 11.026927] systemd[1]: Started D-Bus System Message Bus. clare # [ 11.140789] systemd-logind[541]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) alice # [ 11.086018] dbus-broker-launch[518]: Ready clare # [ 11.149342] systemd-logind[541]: Watching system buttons on /dev/input/event0 (gpio-keys) alice # [ 11.088385] systemd[1]: linger-users.service: Deactivated successfully. alice # [ 11.089621] systemd[1]: Finished linger-users.service. clare # [ 11.151839] systemd-logind[541]: New seat seat0. clare # [ 11.154169] systemd[1]: Started User Login Management. clare # [ 11.154861] systemd[1]: Starting linger-users.service... clare # [ 11.174238] dbus-broker-launch[523]: Looking up NSS user entry for 'systemd-timesync'... bob # [ 11.185844] ncps-pre-start[587]: Creating: /var/lib/ncps/db/db.sqlite alice # [ 11.126800] cfg80211: Loading compiled-in X.509 certificates for regulatory database clare # [ 11.209987] dbus-broker-launch[523]: NSS returned no entry for 'systemd-timesync' clare # [ 11.211228] dbus-broker-launch[523]: Invalid user-name in /nix/store/dlam97yh78gc0w7jxi7g5kar4lh73hcb-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" alice # [ 11.163100] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' alice # [ 11.163605] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 11.227634] ncps-pre-start[587]: Applying: 20241210054814_create-narinfos-table.sql alice # [ 11.168386] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 alice # [ 11.168763] cfg80211: failed to load regulatory.db clare # [ 11.228977] systemd[1]: linger-users.service: Deactivated successfully. clare # [ 11.229754] systemd[1]: Finished linger-users.service. bob # [ 11.236860] ncps-pre-start[587]: Applied: 20241210054814_create-narinfos-table.sql in 9.19276ms bob # [ 11.238753] ncps-pre-start[587]: Applying: 20241210054829_create-nars-table.sql bob # [ 11.245596] ncps-pre-start[587]: Applied: 20241210054829_create-nars-table.sql in 8.78332ms bob # [ 11.247293] ncps-pre-start[587]: Applying: 20241213014846_add-query-to-nars-table.sql bob # [ 11.254500] ncps-pre-start[587]: Applied: 20241213014846_add-query-to-nars-table.sql in 7.77312ms clare # [ 11.254990] cfg80211: Loading compiled-in X.509 certificates for regulatory database bob # [ 11.256353] ncps-pre-start[587]: Applying: 20251230224159_add-cascade-to-nars-fk.sql bob # [ 11.268942] ncps-pre-start[587]: Applied: 20251230224159_add-cascade-to-nars-fk.sql in 14.4316ms bob # [ 11.270767] ncps-pre-start[587]: Applying: 20260101000000_add-config-table.sql clare # [ 11.268118] systemd[1]: Started D-Bus System Message Bus. bob # [ 11.279824] ncps-pre-start[587]: Applied: 20260101000000_add-config-table.sql in 10.85268ms bob # [ 11.282141] ncps-pre-start[587]: Applying: 20260105025735_remove-redundant-indexes.sql alice # [ 11.217530] systemd-networkd[519]: lo: Link UP alice # [ 11.218042] systemd-networkd[519]: lo: Gained carrier bob # [ 11.288250] ncps-pre-start[587]: Applied: 20260105025735_remove-redundant-indexes.sql in 8.41112ms bob # [ 11.290130] ncps-pre-start[587]: Applying: 20260105030513_support_shared_nars_between_narinfos.sql alice # [ 11.225004] systemd[1]: Started Network Management. alice # [ 11.226323] systemd-networkd[519]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 11.227552] systemd-networkd[519]: eth1: Configuring with /etc/systemd/network/40-eth1.network. bob # [ 11.298902] ncps-pre-start[587]: Applied: 20260105030513_support_shared_nars_between_narinfos.sql in 10.65212ms clare # [ 11.299899] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' clare # [ 11.300404] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' bob # [ 11.301087] ncps-pre-start[587]: Applying: 20260117195000_add_narinfo_de_normalized.sql alice # [ 11.233065] systemd[1]: Reached target Network. alice # [ 11.236477] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 11.304917] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 clare # [ 11.305291] cfg80211: failed to load regulatory.db bob # [ 11.311463] ncps-pre-start[587]: Applied: 20260117195000_add_narinfo_de_normalized.sql in 12.59328ms alice # [ 11.244094] systemd-networkd[519]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. bob # [ 11.313035] ncps-pre-start[587]: Applying: 20260127223000_allow_multiple_nar_representations.sql clare # [ 11.311534] dbus-broker-launch[523]: Ready alice # [ 11.249972] systemd[1]: Starting Permit User Sessions... alice # [ 11.250485] systemd-networkd[519]: eth1: Link UP alice # [ 11.250876] systemd-networkd[519]: eth1: Gained carrier alice # [ 11.251306] systemd-networkd[519]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network bob # [ 11.323956] ncps-pre-start[587]: Applied: 20260127223000_allow_multiple_nar_representations.sql in 10.96144ms bob # [ 11.326465] ncps-pre-start[587]: Applying: 20260131021850_add_chunks.sql alice # [ 11.261729] systemd-networkd[519]: eth0: Link UP alice # [ 11.262168] systemd-networkd[519]: eth0: Gained carrier bob # [ 11.334018] ncps-pre-start[587]: Applied: 20260131021850_add_chunks.sql in 10.2584ms bob # [ 11.335822] ncps-pre-start[587]: Applying: 20260205063651_add_total_chunks_to_nar_files.sql bob # [ 11.342677] ncps-pre-start[587]: Applied: 20260205063651_add_total_chunks_to_nar_files.sql in 8.661ms bob # [ 11.344769] ncps-pre-start[587]: Applying: 20260217071237_add_chunking_started_at_to_nar_files.sql alice # [ 11.278604] systemd-networkd[519]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 11.352107] ncps-pre-start[587]: Applied: 20260217071237_add_chunking_started_at_to_nar_files.sql in 9.37308ms bob # [ 11.354056] ncps-pre-start[587]: Applying: 20260301000000_add_verified_at_to_nar_files.sql bob # [ 11.361927] ncps-pre-start[587]: Applied: 20260301000000_add_verified_at_to_nar_files.sql in 9.46644ms clare # [ 11.363696] systemd-networkd[524]: lo: Link UP clare # [ 11.364668] systemd-networkd[524]: lo: Gained carrier clare # [ 11.371767] systemd[1]: Started Network Management. clare # [ 11.375146] systemd[1]: Reached target Network. alice # [ 11.318167] systemd[1]: Finished Permit User Sessions. clare # [ 11.379882] systemd-networkd[524]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network. clare # [ 11.382968] systemd-networkd[524]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network alice # [ 11.325790] systemd[1]: Started Getty on tty1. alice # [ 11.326270] systemd[1]: Reached target Login Prompts. alice # [ 11.326692] systemd[1]: Reached target Multi-User System. alice # [ 11.327125] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 11.389210] systemd-networkd[524]: eth1: Configuring with /etc/systemd/network/40-eth1.network. alice # [ 11.330130] systemd[1]: Startup finished in 745ms (kernel) + 5.509s (initrd) + 5.072s (userspace) = 11.328s. bob # [ 11.407552] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 clare # [ 11.391861] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... clare # [ 11.400603] systemd-networkd[524]: eth0: Link UP clare # [ 11.401007] systemd-networkd[524]: eth0: Gained carrier clare # [ 11.401425] systemd-networkd[524]: eth1: Link UP clare # [ 11.401804] systemd-networkd[524]: eth1: Gained carrier clare # [ 11.402221] systemd-networkd[524]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network clare # [ 11.403458] systemd[1]: Starting Permit User Sessions... clare # [ 11.410842] systemd-networkd[524]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.2 bob # [ 11.463148] systemd[1]: Started ncps binary cache proxy service. bob # [ 11.464960] systemd[1]: Reached target Multi-User System. bob # [ 11.465980] systemd[1]: Startup finished in 752ms (kernel) + 4.644s (initrd) + 6.068s (userspace) = 11.465s. clare # [ 11.465789] systemd[1]: Finished Permit User Sessions. clare # [ 11.473462] systemd[1]: Started Getty on tty1. clare # [ 11.474656] systemd[1]: Reached target Login Prompts. clare # [ 11.476763] systemd[1]: Reached target Multi-User System. clare # [ 11.479932] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. clare # [ 11.489925] systemd[1]: Startup finished in 760ms (kernel) + 5.519s (initrd) + 5.209s (userspace) = 11.489s. bob: (finished: waiting for unit ncps, in 11.82 seconds) bob: must succeed: systemctl status ncps bob # [ 11.585710] systemd-networkd[531]: eth0: Gained IPv6LL bob: (finished: must succeed: systemctl status ncps, in 0.05 seconds) clare: waiting for unit harmonia.socket clare: waiting for the VM to finish booting clare: Guest shell says: b'Spawning backdoor root shell...\n' clare: connected to guest root shell clare: (connecting took 0.00 seconds) clare: (finished: waiting for the VM to finish booting, in 0.00 seconds) clare: (finished: waiting for unit harmonia.socket, in 0.09 seconds) alice: waiting for success: curl bob:8502/nix-cache-info alice: waiting for the VM to finish booting alice: Guest shell says: b'Spawning backdoor root shell...\n' alice: connected to guest root shell alice: (connecting took 0.00 seconds) alice: (finished: waiting for the VM to finish booting, in 0.00 seconds) alice # [ 11.696668] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 bob # [ 11.777868] systemd-networkd[531]: eth1: Gained IPv6LL clare # [ 11.830803] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3 bob # [ 11.966145] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 11.996888] systemd[1]: Starting Virtual Console Setup... bob # [ 12.035502] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. bob # [ 12.037591] systemd[1]: Stopped Virtual Console Setup. bob # [ 12.048843] systemd[1]: Starting Virtual Console Setup... bob # [ 12.073707] systemd-logind[546]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) bob # [ 12.076962] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","log_level":"info","time":"2026-08-10T11:06:06Z","message":"logger created"} bob # [ 12.079115] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","xz-binary-path":"/nix/store/i672h9rp9lm8rngn8r6gf8la8z691mkx-xz-5.8.3-bin/bin/xz","time":"2026-08-10T11:06:06Z","message":"Using xz binary for xz decompression"} clare # [ 12.083709] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. alice # [ 12.027067] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. bob # [ 12.098123] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-08-10T11:06:06Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"} clare # [ 12.107952] systemd[1]: Starting Virtual Console Setup... bob # [ 12.114854] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","time":"2026-08-10T11:06:06Z","message":"using local locks (single-instance mode)"} alice # [ 12.050633] systemd-logind[536]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) alice # [ 12.054289] systemd[1]: Starting Virtual Console Setup... bob # [ 12.127836] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-08-10T11:06:06Z","message":"setting up tracer provider to discard traces"} bob # [ 12.133657] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-08-10T11:06:06Z","message":"setting up meter provider to discard metrics"} clare # [ 12.131433] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. clare # [ 12.133635] systemd[1]: Stopped Virtual Console Setup. bob # [ 12.139713] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-08-10T11:06:06Z","message":"setting up logger provider to discard logs"} clare # [ 12.140335] systemd[1]: Starting Virtual Console Setup... bob # [ 12.150062] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-08-10T11:06:06Z","message":"Reporting anonymous metrics to the project maintainers"} bob # [ 12.153505] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-08-10T11:06:06Z","message":"failed to parse netrc file, proceeding without netrc authentication"} bob # [ 12.155454] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-08-10T11:06:06Z","message":"using local storage"} clare # [ 12.153742] systemd-logind[541]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) clare # [ 12.416939] systemd-networkd[524]: eth0: Gained IPv6LL alice # [ 12.417019] systemd-networkd[519]: eth0: Gained IPv6LL clare # [ 12.506619] systemd[1]: Starting harmonia binary cache service... bob # [ 12.550563] systemd-vconsole-setup[623]: Configuration of first virtual console was skipped, ignoring remaining ones. bob # [ 12.553427] systemd[1]: Finished Virtual Console Setup. alice # [ 12.508280] systemd-vconsole-setup[571]: Configuration of first virtual console was skipped, ignoring remaining ones. alice # [ 12.510959] systemd[1]: Finished Virtual Console Setup. clare # [ 12.629739] harmonia-cache[575]: 2026-08-10T11:06:07.415027Z WARN harmonia_cache::tls: /run/credentials/harmonia.service/sign-key-0 has insecure permissions 0o440; recommend 0600 clare # [ 12.639590] harmonia-cache[575]: 2026-08-10T11:06:07.425136Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000)) clare # [ 12.641951] harmonia-cache[575]: 2026-08-10T11:06:07.427364Z INFO actix_server::builder: starting 4 workers clare # [ 12.644429] harmonia-cache[575]: 2026-08-10T11:06:07.430326Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s clare # [ 12.645579] harmonia-cache[575]: 2026-08-10T11:06:07.430374Z INFO actix_server::server: Actix runtime found; starting in Actix runtime clare # [ 12.646697] systemd[1]: Started harmonia binary cache service. clare # [ 12.648878] harmonia-cache[575]: 2026-08-10T11:06:07.430390Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:5000 clare # [ 12.652374] systemd-vconsole-setup[573]: Configuration of first virtual console was skipped, ignoring remaining ones. clare # [ 12.655631] systemd[1]: Finished Virtual Console Setup. bob # [ 12.672099] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-08-10T11:06:07Z","message":"upstream became healthy and is now available for requests"} bob # [ 12.675996] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-08-10T11:06:07Z","message":"Server started"} alice # [ 12.705396] systemd-networkd[519]: eth1: Gained IPv6LL bob # [ 13.162085] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: 2026/08/10 11:06:07 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host clare # [ 13.283442] systemd-networkd[524]: eth1: Gained IPv6LL alice # curl: (28) Failed to connect to bob:8502 after 134634 ms: Could not connect to server alice # % Total % Received % Xferd Average Speed Time Time Time Current alice # Dload Upload Total Spent Left Speed alice # 0 0 0 0 0 0 0 0 0100 50 100 50 0 0 1619 0 0100 50 100 50 0 0 1471 0 0100 50 100 50 0 0 1355 0 0 bob # [ 147.637061] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:55246","trace_id":"7ec86027f5a772c7102b5af3d5abb308","span_id":"bb5711da0d3953dd","status":200,"elapsed":0.18596,"bytes":50,"time":"2026-08-10T11:08:22Z","message":"handled request"} alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 135.93 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 3518 0 0100 51 100 51 0 0 3009 0 0100 51 100 51 0 0 2686 0 0 bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.15 seconds) clare: waiting for unit harmonia clare: (finished: waiting for unit harmonia, in 0.19 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 # [ 148.225188] systemd-resolved[319]: Using degraded feature set UDP instead of UDP+EDNS0 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.08 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 # [ 153.267374] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:47324","trace_id":"9c26a0e5bc54b54862565e1f7108c8df","span_id":"188546b0ea7dfa63","status":200,"elapsed":0.02908,"bytes":50,"time":"2026-08-10T11:08:28Z","message":"handled request"} alice # [ 153.206444] systemd-resolved[318]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3. alice # [ 153.209663] systemd-resolved[318]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3. alice # [ 153.211370] systemd-resolved[318]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3. bob # [ 157.878897] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:47324","trace_id":"18f55acc364e98d61c261de5ac7c8b96","span_id":"e988627463dc571b","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-08-10T11:08:32Z","message":"download the narinfo from upstream"} bob # [ 157.942709] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:47324","trace_id":"18f55acc364e98d61c261de5ac7c8b96","span_id":"e988627463dc571b","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-08-10T11:08:32Z","message":"downloading the nar from upstream"} bob # [ 157.954344] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:47324","trace_id":"18f55acc364e98d61c261de5ac7c8b96","span_id":"e988627463dc571b","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-08-10T11:08:32Z","message":"download the nar from upstream"} bob # [ 157.992097] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:47324","trace_id":"18f55acc364e98d61c261de5ac7c8b96","span_id":"e988627463dc571b","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":46.15008,"time":"2026-08-10T11:08:32Z","message":"download of nar complete"} bob # [ 158.012861] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:47324","trace_id":"18f55acc364e98d61c261de5ac7c8b96","span_id":"e988627463dc571b","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-08-10T11:08:32Z","message":"storing narinfo and nar_file record in the database"} bob # [ 158.023647] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:47324","trace_id":"18f55acc364e98d61c261de5ac7c8b96","span_id":"e988627463dc571b","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":144.95856,"time":"2026-08-10T11:08:32Z","message":"download of narinfo complete"} bob # [ 158.030698] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:47324","trace_id":"18f55acc364e98d61c261de5ac7c8b96","span_id":"e988627463dc571b","status":200,"elapsed":160.54972,"bytes":532,"time":"2026-08-10T11:08:32Z","message":"handled request"} bob # [ 158.037528] ba17isrq6crz8rll0d5wkvidrn741px3-ncps-wrapper[592]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:47324","trace_id":"6118218d2fe87bafd8189b8746762207","span_id":"cb22a6276455f028","status":200,"elapsed":0.7254,"bytes":95,"time":"2026-08-10T11:08:32Z","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 5.10 seconds) (finished: run the VM test script, in 158.99 seconds) test script finished in 159.18s cleanup kill QemuMachine (pid 48) alice # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/41m77i1296n33p6liin8ynr6wh3h6b7m-python3-3.14.6/bin/python3.14) kill QemuMachine (pid 49) bob # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/41m77i1296n33p6liin8ynr6wh3h6b7m-python3-3.14.6/bin/python3.14) kill QemuMachine (pid 50) clare # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/41m77i1296n33p6liin8ynr6wh3h6b7m-python3-3.14.6/bin/python3.14) (finished: cleanup, in 0.49 seconds)