vm-test-run-ncps
checks.aarch64-linux.ncps
· build #1007
· raw
1build01: building 4s3lra1mshjqhjbjzvvbf7pynz9pb4j3-vm-test-run-ncps.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.07 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 alice, bob, clare,9 vlan1,10 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_ssh11start all VMs12alice: starting vm13bob: starting vm14clare: starting vm15clare # Disk image does not exist, creating the virtualisation disk image...16clare # Formatting '/build/vm-state-clare/tmp.RzIjXRUybh', fmt=raw size=107374182417clare # mke2fs 1.47.4 (6-Mar-2025)18clare # Discarding device blocks: 0/262144 done19clare # Creating filesystem with 262144 4k blocks and 65536 inodes20clare # Filesystem UUID: 56dcb38e-425e-47d8-85ec-3d528b74493821clare # Superblock backups stored on blocks:22clare # 32768, 98304, 163840, 22937623clare # 24clare # Allocating group tables: 0/8 done25clare # Writing inode tables: 0/8 done26clare # Creating journal (8192 blocks): done27clare # Writing superblocks and filesystem accounting information: 0/8 done28clare # 29clare # Virtualisation disk image created.30clare # Starting virtiofs daemons...31clare # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32clare # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33clare # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection...34clare # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)35clare # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether36clare # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection...37clare # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)38clare # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether39clare # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection...40clare # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests41clare # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests42clare # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests43clare: QEMU running (pid 50)44alice # Disk image does not exist, creating the virtualisation disk image...45alice: QEMU running (pid 47)46alice # Formatting '/build/vm-state-alice/tmp.eFvxCdozm0', fmt=raw size=107374182447alice # mke2fs 1.47.4 (6-Mar-2025)48alice # Discarding device blocks: 0/262144 done49alice # Creating filesystem with 262144 4k blocks and 65536 inodes50alice # Filesystem UUID: 8b4716f3-2b68-4ac2-a216-c4e011e1480a51alice # Superblock backups stored on blocks:52alice # 32768, 98304, 163840, 22937653alice # 54alice # Allocating group tables: 0/8 done55alice # Writing inode tables: 0/8 done56alice # Creating journal (8192 blocks): done57alice # Writing superblocks and filesystem accounting information: 0/8 done58alice # 59alice # Virtualisation disk image created.60alice # Starting virtiofs daemons...61alice # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)62alice # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether63alice # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)64alice # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether65alice # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)66alice # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether67alice # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection...68alice # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection...69bob # Disk image does not exist, creating the virtualisation disk image...70alice # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection...71bob # Formatting '/build/vm-state-bob/tmp.H894aykIAv', fmt=raw size=107374182472alice # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests73bob # mke2fs 1.47.4 (6-Mar-2025)74alice # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests75bob # Discarding device blocks: 0/262144 done76alice # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests77bob # Creating filesystem with 262144 4k blocks and 65536 inodes78bob: QEMU running (pid 49)79bob # Filesystem UUID: eefe96ea-986a-4dfb-8199-7760d741e2ab80bob # Superblock backups stored on blocks:81bob # 32768, 98304, 163840, 22937682bob # 83bob # Allocating group tables: 0/8 done84bob # Writing inode tables: 0/8 done85bob # Creating journal (8192 blocks): done86bob # Writing superblocks and filesystem accounting information: 0/8 done87(finished: start all VMs, in 1.73 seconds)88bob # 89bob: waiting for unit ncps90bob # Virtualisation disk image created.91bob: waiting for the VM to finish booting92bob # Starting virtiofs daemons...93bob # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)94bob # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether95bob # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection...96bob # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)97bob # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether98bob # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection...99bob # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)100bob # [2026-10-16T09:37:31Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether101bob # [2026-10-16T09:37:31Z INFO virtiofsd] Waiting for vhost-user socket connection...102bob # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests103bob # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests104bob # [2026-10-16T09:37:31Z INFO virtiofsd] Client connected, servicing requests105clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]106clare # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172107clare # [ 0.000000] KASLR enabled108clare # [ 0.000000] random: crng init done109clare # [ 0.000000] Machine model: linux,dummy-virt110clare # [ 0.000000] efi: UEFI not found.111clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT112clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]113clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]114clare # [ 0.000000] Zone ranges:115clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]116clare # [ 0.000000] DMA32 empty117clare # [ 0.000000] Normal empty118clare # [ 0.000000] Device empty119clare # [ 0.000000] Movable zone start for each node120clare # [ 0.000000] Early memory node ranges121clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]122clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]123clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000124clare # [ 0.000000] psci: probing for conduit method from DT.125clare # [ 0.000000] psci: PSCIv1.3 detected in firmware.126clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs127clare # [ 0.000000] psci: Trusted OS migration not required128clare # [ 0.000000] psci: SMC Calling Convention v1.1129clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)130clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296131clare # [ 0.000000] Detected PIPT I-cache on CPU0132clare # [ 0.000000] CPU features: detected: GICv3 CPU interface133clare # [ 0.000000] CPU features: detected: Spectre-v4134clare # [ 0.000000] CPU features: detected: Spectre-BHB135clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR136clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)137clare # [ 0.000000] CPU features: detected: ARM erratum 1418040138clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion139clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing140clare # [ 0.000000] alternatives: applying boot alternatives141clare # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/j4fzgfjgricvyd8zav51kvpldd2mwf76-nixos-system-clare-test/init regInfo=/nix/.ro-store/r3p01a4rnqc6f0dc0k7hzkrmyjh2q32y-closure-info/registration console=ttyAMA0,115200n8 console=tty0142clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/r3p01a4rnqc6f0dc0k7hzkrmyjh2q32y-closure-info/registration", will be passed to user space.143clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes144clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)145clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)146clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB147clare # [ 0.000000] software IO TLB: area num 1.148clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)149clare # [ 0.000000] Fallback order for Node 0: 0150clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144151clare # [ 0.000000] Policy zone: DMA152clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off153clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1154clare # [ 0.000000] allocated 2097152 bytes of page_ext155clare # [ 0.000000] ftrace: allocating 75049 entries in 294 pages156clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups157clare # [ 0.000000] rcu: Hierarchical RCU implementation.158clare # [ 0.000000] rcu: RCU event tracing is enabled.159clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.160clare # [ 0.000000] Trampoline variant of Tasks RCU enabled.161clare # [ 0.000000] Rude variant of Tasks RCU enabled.162clare # [ 0.000000] Tracing variant of Tasks RCU enabled.163clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.164clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1165clare # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.166clare # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.167clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.168clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0169clare # [ 0.000000] GICv3: 256 SPIs implemented170clare # [ 0.000000] GICv3: 0 Extended SPIs implemented171clare # [ 0.000000] Root IRQ handler: gic_handle_irq172clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI173clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0174clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000175clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]176clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)177clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)178clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000179clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000180alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]181clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.182alice # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172183clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns184alice # [ 0.000000] KASLR enabled185alice # [ 0.000000] random: crng init done186clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040187alice # [ 0.000000] Machine model: linux,dummy-virt188clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).189alice # [ 0.000000] efi: UEFI not found.190alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT191clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns192alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]193clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns194alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]195alice # [ 0.000000] Zone ranges:196alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]197alice # [ 0.000000] DMA32 empty198alice # [ 0.000000] Normal empty199alice # [ 0.000000] Device empty200alice # [ 0.000000] Movable zone start for each node201alice # [ 0.000000] Early memory node ranges202alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]203clare # [ 0.000085] arm-pv: using stolen time PV204alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]205clare # [ 0.000803] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)206alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000207clare # [ 0.001134] Console: colour dummy device 80x25208alice # [ 0.000000] psci: probing for conduit method from DT.209clare # [ 0.001148] printk: legacy console [tty0] enabled210alice # [ 0.000000] psci: PSCIv1.3 detected in firmware.211alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs212alice # [ 0.000000] psci: Trusted OS migration not required213alice # [ 0.000000] psci: SMC Calling Convention v1.1214clare # [ 0.001400] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)215clare # [ 0.001406] pid_max: default: 32768 minimum: 301216alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)217clare # [ 0.001492] LSM: initializing lsm=capability,landlock,yama,bpf,ima218alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296219clare # [ 0.001740] landlock: Up and running.220alice # [ 0.000000] Detected PIPT I-cache on CPU0221clare # [ 0.001743] Yama: becoming mindful.222clare # [ 0.002830] LSM support for eBPF active223alice # [ 0.000000] CPU features: detected: GICv3 CPU interface224alice # [ 0.000000] CPU features: detected: Spectre-v4225clare # [ 0.003074] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)226alice # [ 0.000000] CPU features: detected: Spectre-BHB227alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR228clare # [ 0.003096] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)229clare # [ 0.006450] rcu: Hierarchical SRCU implementation.230alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)231clare # [ 0.006460] rcu: Max phase no-delay instances is 1000.232alice # [ 0.000000] CPU features: detected: ARM erratum 1418040233clare # [ 0.007533] fsl-mc MSI: its@8080000 domain created234alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion235clare # [ 0.007750] EFI services will not be available.236clare # [ 0.009104] smp: Bringing up secondary CPUs ...237alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing238clare # [ 0.009145] smp: Brought up 1 node, 1 CPU239alice # [ 0.000000] alternatives: applying boot alternatives240clare # [ 0.009148] SMP: Total of 1 processors activated.241clare # [ 0.009150] CPU: All CPU(s) started at EL1242clare # [ 0.009165] CPU features: detected: 32-bit EL0 Support243clare # [ 0.009169] CPU features: detected: Data cache clean to the PoU not required for I/D coherence244clare # [ 0.009172] CPU features: detected: Common not Private translations245alice # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/z9f82k8cch6hhsyq4na4bfcwhpbkripm-nixos-system-alice-test/init regInfo=/nix/.ro-store/n1nsmb1xzg6q0jkh7hf96ky653rdfqia-closure-info/registration console=ttyAMA0,115200n8 console=tty0246clare # [ 0.009174] CPU features: detected: CRC32 instructions247clare # [ 0.009176] CPU features: detected: Data cache clean to Point of Persistence248clare # [ 0.009179] CPU features: detected: Enhanced Virtualization Traps249alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/n1nsmb1xzg6q0jkh7hf96ky653rdfqia-closure-info/registration", will be passed to user space.250clare # [ 0.009191] CPU features: detected: RCpc load-acquire (LDAPR)251alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes252clare # [ 0.009193] CPU features: detected: LSE atomic instructions253alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)254clare # [ 0.009196] CPU features: detected: Privileged Access Never255clare # [ 0.009198] CPU features: detected: PMUv3256alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)257clare # [ 0.009200] CPU features: detected: RAS Extension Support258alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB259clare # [ 0.009203] CPU features: detected: Speculative Store Bypassing Safe (SSBS)260alice # [ 0.000000] software IO TLB: area num 1.261clare # [ 0.009229] alternatives: applying system-wide alternatives262alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)263clare # [ 0.011080] CPU features: detected: Hardware dirty bit management on CPU0264alice # [ 0.000000] Fallback order for Node 0: 0265alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144266alice # [ 0.000000] Policy zone: DMA267clare # [ 0.011325] Memory: 889148K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 118128K reserved, 32768K cma-reserved)268clare # [ 0.011964] devtmpfs: initialized269alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off270alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1271clare # [ 0.013585] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)272alice # [ 0.000000] allocated 2097152 bytes of page_ext273clare # [ 0.013609] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).274alice # [ 0.000000] ftrace: allocating 75049 entries in 294 pages275alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups276clare # [ 0.013763] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL277clare # [ 0.013768] 0 pages in range for non-PLT usage278alice # [ 0.000000] rcu: Hierarchical RCU implementation.279clare # [ 0.013769] 508192 pages in range for PLT usage280alice # [ 0.000000] rcu: RCU event tracing is enabled.281clare # [ 0.013906] pinctrl core: initialized pinctrl subsystem282alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.283clare # [ 0.014731] DMI not present or invalid.284alice # [ 0.000000] Trampoline variant of Tasks RCU enabled.285clare # [ 0.019385] NET: Registered PF_NETLINK/PF_ROUTE protocol family286alice # [ 0.000000] Rude variant of Tasks RCU enabled.287alice # [ 0.000000] Tracing variant of Tasks RCU enabled.288clare # [ 0.022236] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations289alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.290clare # [ 0.022388] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations291alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1292clare # [ 0.022538] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations293clare # [ 0.022576] audit: initializing netlink subsys (disabled)294alice # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.295clare # [ 0.023444] thermal_sys: Registered thermal governor 'fair_share'296alice # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.297clare # [ 0.023447] thermal_sys: Registered thermal governor 'bang_bang'298clare # [ 0.023451] thermal_sys: Registered thermal governor 'step_wise'299alice # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.300clare # [ 0.023453] thermal_sys: Registered thermal governor 'user_space'301alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0302alice # [ 0.000000] GICv3: 256 SPIs implemented303clare # [ 0.023455] thermal_sys: Registered thermal governor 'power_allocator'304alice # [ 0.000000] GICv3: 0 Extended SPIs implemented305clare # [ 0.023508] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1306alice # [ 0.000000] Root IRQ handler: gic_handle_irq307clare # [ 0.023518] cpuidle: using governor ladder308alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI309clare # [ 0.023523] cpuidle: using governor menu310alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0311clare # [ 0.023691] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.312alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000313clare # [ 0.023721] ASID allocator initialised with 32768 entries314clare # [ 0.024429] Serial: AMBA PL011 UART driver315alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]316clare # [ 0.028897] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1317clare # [ 0.029181] printk: console [ttyAMA0] enabled318alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)319alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)320alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000321alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000322alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.323alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns324alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040325alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).326alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns327alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns328alice # [ 0.000062] arm-pv: using stolen time PV329alice # [ 0.000477] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)330alice # [ 0.000842] Console: colour dummy device 80x25331alice # [ 0.000857] printk: legacy console [tty0] enabled332alice # [ 0.001094] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)333alice # [ 0.001100] pid_max: default: 32768 minimum: 301334alice # [ 0.001192] LSM: initializing lsm=capability,landlock,yama,bpf,ima335alice # [ 0.001377] landlock: Up and running.336alice # [ 0.001380] Yama: becoming mindful.337alice # [ 0.001955] LSM support for eBPF active338alice # [ 0.002143] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)339alice # [ 0.002163] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)340alice # [ 0.006024] rcu: Hierarchical SRCU implementation.341alice # [ 0.006038] rcu: Max phase no-delay instances is 1000.342alice # [ 0.007183] fsl-mc MSI: its@8080000 domain created343clare # [ 0.111158] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages344alice # [ 0.007388] EFI services will not be available.345clare # [ 0.111179] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page346alice # [ 0.007504] smp: Bringing up secondary CPUs ...347clare # [ 0.111182] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages348alice # [ 0.007541] smp: Brought up 1 node, 1 CPU349alice # [ 0.007544] SMP: Total of 1 processors activated.350clare # [ 0.111185] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page351alice # [ 0.007546] CPU: All CPU(s) started at EL1352clare # [ 0.111187] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages353alice # [ 0.007560] CPU features: detected: 32-bit EL0 Support354clare # [ 0.111189] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page355alice # [ 0.007565] CPU features: detected: Data cache clean to the PoU not required for I/D coherence356clare # [ 0.111191] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages357alice # [ 0.007568] CPU features: detected: Common not Private translations358clare # [ 0.111193] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page359alice # [ 0.007570] CPU features: detected: CRC32 instructions360clare # [ 0.112308] fbcon: Taking over console361clare # [ 0.112329] ACPI: Interpreter disabled.362alice # [ 0.007572] CPU features: detected: Data cache clean to Point of Persistence363clare # [ 0.115248] iommu: Default domain type: Translated364alice # [ 0.007575] CPU features: detected: Enhanced Virtualization Traps365clare # [ 0.115255] iommu: DMA domain TLB invalidation policy: strict mode366alice # [ 0.007587] CPU features: detected: RCpc load-acquire (LDAPR)367clare # [ 0.115854] SCSI subsystem initialized368alice # [ 0.007589] CPU features: detected: LSE atomic instructions369alice # [ 0.007591] CPU features: detected: Privileged Access Never370alice # [ 0.007594] CPU features: detected: PMUv3371alice # [ 0.007596] CPU features: detected: RAS Extension Support372clare # [ 0.122982] usbcore: registered new interface driver usbfs373clare # [ 0.123017] usbcore: registered new interface driver hub374alice # [ 0.007599] CPU features: detected: Speculative Store Bypassing Safe (SSBS)375clare # [ 0.123029] usbcore: registered new device driver usb376alice # [ 0.007627] alternatives: applying system-wide alternatives377alice # [ 0.009284] CPU features: detected: Hardware dirty bit management on CPU0378alice # [ 0.010160] Memory: 889112K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 118124K reserved, 32768K cma-reserved)379alice # [ 0.010786] devtmpfs: initialized380alice # [ 0.012413] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)381alice # [ 0.012438] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).382clare # [ 0.124789] pps_core: LinuxPPS API ver. 1 registered383alice # [ 0.012604] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL384clare # [ 0.124796] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>385alice # [ 0.012610] 0 pages in range for non-PLT usage386clare # [ 0.124802] PTP clock support registered387alice # [ 0.012612] 508192 pages in range for PLT usage388clare # [ 0.124849] EDAC MC: Ver: 3.0.0389alice # [ 0.012755] pinctrl core: initialized pinctrl subsystem390clare # [ 0.127396] scmi_core: SCMI protocol bus registered391alice # [ 0.013731] DMI not present or invalid.392alice # [ 0.018608] NET: Registered PF_NETLINK/PF_ROUTE protocol family393alice # [ 0.021977] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations394alice # [ 0.022123] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations395clare # [ 0.129895] FPGA manager framework396clare # [ 0.130769] vgaarb: loaded397alice # [ 0.022272] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations398clare # [ 0.130968] clocksource: Switched to clocksource arch_sys_counter399alice # [ 0.022306] audit: initializing netlink subsys (disabled)400alice # [ 0.023735] thermal_sys: Registered thermal governor 'fair_share'401alice # [ 0.023738] thermal_sys: Registered thermal governor 'bang_bang'402alice # [ 0.023742] thermal_sys: Registered thermal governor 'step_wise'403alice # [ 0.023744] thermal_sys: Registered thermal governor 'user_space'404alice # [ 0.023746] thermal_sys: Registered thermal governor 'power_allocator'405alice # [ 0.023783] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1406alice # [ 0.023794] cpuidle: using governor ladder407alice # [ 0.023798] cpuidle: using governor menu408alice # [ 0.023946] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.409alice # [ 0.023971] ASID allocator initialised with 32768 entries410alice # [ 0.024664] Serial: AMBA PL011 UART driver411alice # [ 0.029277] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1412alice # [ 0.029521] printk: console [ttyAMA0] enabled413bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]414clare # [ 0.144355] VFS: Disk quotas dquot_6.6.0415bob # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172416bob # [ 0.000000] KASLR enabled417clare # [ 0.144389] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)418bob # [ 0.000000] random: crng init done419clare # [ 0.146366] netfs: FS-Cache loaded420bob # [ 0.000000] Machine model: linux,dummy-virt421clare # [ 0.146520] pnp: PnP ACPI: disabled422bob # [ 0.000000] efi: UEFI not found.423bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT424alice # [ 0.106915] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages425bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]426alice # [ 0.106935] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page427bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]428alice # [ 0.106938] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages429bob # [ 0.000000] Zone ranges:430alice # [ 0.106940] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page431bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]432bob # [ 0.000000] DMA32 empty433bob # [ 0.000000] Normal empty434alice # [ 0.106942] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages435clare # [ 0.149919] NET: Registered PF_INET protocol family436bob # [ 0.000000] Device empty437alice # [ 0.106944] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page438bob # [ 0.000000] Movable zone start for each node439clare # [ 0.150115] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)440bob # [ 0.000000] Early memory node ranges441alice # [ 0.106946] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages442bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]443alice # [ 0.106948] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page444alice # [ 0.108059] fbcon: Taking over console445bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]446alice # [ 0.108071] ACPI: Interpreter disabled.447bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000448alice # [ 0.110529] iommu: Default domain type: Translated449bob # [ 0.000000] psci: probing for conduit method from DT.450alice # [ 0.110535] iommu: DMA domain TLB invalidation policy: strict mode451bob # [ 0.000000] psci: PSCIv1.3 detected in firmware.452bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs453bob # [ 0.000000] psci: Trusted OS migration not required454bob # [ 0.000000] psci: SMC Calling Convention v1.1455bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)456alice # [ 0.116872] SCSI subsystem initialized457bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296458alice # [ 0.117443] usbcore: registered new interface driver usbfs459bob # [ 0.000000] Detected PIPT I-cache on CPU0460alice # [ 0.117483] usbcore: registered new interface driver hub461bob # [ 0.000000] CPU features: detected: GICv3 CPU interface462alice # [ 0.117491] usbcore: registered new device driver usb463bob # [ 0.000000] CPU features: detected: Spectre-v4464alice # [ 0.117773] pps_core: LinuxPPS API ver. 1 registered465bob # [ 0.000000] CPU features: detected: Spectre-BHB466bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR467alice # [ 0.117778] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>468alice # [ 0.117782] PTP clock support registered469bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)470alice # [ 0.117816] EDAC MC: Ver: 3.0.0471bob # [ 0.000000] CPU features: detected: ARM erratum 1418040472alice # [ 0.118036] scmi_core: SCMI protocol bus registered473bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion474alice # [ 0.122329] FPGA manager framework475bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing476bob # [ 0.000000] alternatives: applying boot alternatives477bob # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/g481r9hzfzlyykghr4xgmgb8b9rc5pbb-nixos-system-bob-test/init regInfo=/nix/.ro-store/fx6l16z5lkcpzd05h24bif70bw1zhd4n-closure-info/registration console=ttyAMA0,115200n8 console=tty0478bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/fx6l16z5lkcpzd05h24bif70bw1zhd4n-closure-info/registration", will be passed to user space.479bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes480bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)481bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)482bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB483bob # [ 0.000000] software IO TLB: area num 1.484bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)485bob # [ 0.000000] Fallback order for Node 0: 0486bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144487bob # [ 0.000000] Policy zone: DMA488bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off489bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1490bob # [ 0.000000] allocated 2097152 bytes of page_ext491bob # [ 0.000000] ftrace: allocating 75049 entries in 294 pages492bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups493bob # [ 0.000000] rcu: Hierarchical RCU implementation.494alice # [ 0.131308] vgaarb: loaded495bob # [ 0.000000] rcu: RCU event tracing is enabled.496alice # [ 0.131582] clocksource: Switched to clocksource arch_sys_counter497bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.498bob # [ 0.000000] Trampoline variant of Tasks RCU enabled.499bob # [ 0.000000] Rude variant of Tasks RCU enabled.500bob # [ 0.000000] Tracing variant of Tasks RCU enabled.501bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.502bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1503bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.504bob # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.505bob # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.506bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0507bob # [ 0.000000] GICv3: 256 SPIs implemented508alice # [ 0.139108] VFS: Disk quotas dquot_6.6.0509bob # [ 0.000000] GICv3: 0 Extended SPIs implemented510alice # [ 0.139139] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)511bob # [ 0.000000] Root IRQ handler: gic_handle_irq512bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI513clare # [ 0.176489] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)514bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0515clare # [ 0.176515] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)516bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000517clare # [ 0.176528] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)518bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]519clare # [ 0.176569] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)520alice # [ 0.141742] netfs: FS-Cache loaded521bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)522alice # [ 0.141913] pnp: PnP ACPI: disabled523clare # [ 0.176636] TCP: Hash tables configured (established 8192 bind 8192)524bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)525clare # [ 0.176735] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)526bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000527clare # [ 0.176813] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)528bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000529clare # [ 0.176863] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)530bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.531clare # [ 0.176969] NET: Registered PF_UNIX/PF_LOCAL protocol family532clare # [ 0.177018] NET: Registered PF_XDP protocol family533bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns534clare # [ 0.177034] PCI: CLS 0 bytes, default 64535clare # [ 0.177405] Trying to unpack rootfs image as initramfs...536bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040537bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).538alice # [ 0.144631] NET: Registered PF_INET protocol family539alice # [ 0.144862] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)540bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns541clare # [ 0.185452] kvm [1]: HYP mode not available542bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns543bob # [ 0.000052] arm-pv: using stolen time PV544bob # [ 0.000421] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)545bob # [ 0.000712] Console: colour dummy device 80x25546bob # [ 0.000724] printk: legacy console [tty0] enabled547bob # [ 0.000938] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)548bob # [ 0.000944] pid_max: default: 32768 minimum: 301549bob # [ 0.001028] LSM: initializing lsm=capability,landlock,yama,bpf,ima550bob # [ 0.001168] landlock: Up and running.551bob # [ 0.001170] Yama: becoming mindful.552bob # [ 0.001593] LSM support for eBPF active553bob # [ 0.001762] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)554bob # [ 0.001782] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)555bob # [ 0.004553] rcu: Hierarchical SRCU implementation.556bob # [ 0.004562] rcu: Max phase no-delay instances is 1000.557bob # [ 0.005548] fsl-mc MSI: its@8080000 domain created558bob # [ 0.005727] EFI services will not be available.559bob # [ 0.005866] smp: Bringing up secondary CPUs ...560bob # [ 0.005898] smp: Brought up 1 node, 1 CPU561bob # [ 0.005900] SMP: Total of 1 processors activated.562bob # [ 0.005902] CPU: All CPU(s) started at EL1563bob # [ 0.005914] CPU features: detected: 32-bit EL0 Support564bob # [ 0.005918] CPU features: detected: Data cache clean to the PoU not required for I/D coherence565bob # [ 0.005921] CPU features: detected: Common not Private translations566bob # [ 0.005923] CPU features: detected: CRC32 instructions567bob # [ 0.005925] CPU features: detected: Data cache clean to Point of Persistence568bob # [ 0.005928] CPU features: detected: Enhanced Virtualization Traps569bob # [ 0.005938] CPU features: detected: RCpc load-acquire (LDAPR)570bob # [ 0.005940] CPU features: detected: LSE atomic instructions571bob # [ 0.005942] CPU features: detected: Privileged Access Never572bob # [ 0.005943] CPU features: detected: PMUv3573bob # [ 0.005945] CPU features: detected: RAS Extension Support574bob # [ 0.005948] CPU features: detected: Speculative Store Bypassing Safe (SSBS)575bob # [ 0.005970] alternatives: applying system-wide alternatives576bob # [ 0.007611] CPU features: detected: Hardware dirty bit management on CPU0577bob # [ 0.008047] Memory: 889120K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 118120K reserved, 32768K cma-reserved)578bob # [ 0.008603] devtmpfs: initialized579bob # [ 0.010156] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)580bob # [ 0.010179] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).581bob # [ 0.010315] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL582bob # [ 0.010321] 0 pages in range for non-PLT usage583bob # [ 0.010322] 508192 pages in range for PLT usage584bob # [ 0.010454] pinctrl core: initialized pinctrl subsystem585bob # [ 0.011389] DMI not present or invalid.586bob # [ 0.016263] NET: Registered PF_NETLINK/PF_ROUTE protocol family587bob # [ 0.018956] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations588bob # [ 0.019119] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations589bob # [ 0.019270] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations590bob # [ 0.019303] audit: initializing netlink subsys (disabled)591bob # [ 0.020042] thermal_sys: Registered thermal governor 'fair_share'592bob # [ 0.020045] thermal_sys: Registered thermal governor 'bang_bang'593bob # [ 0.020049] thermal_sys: Registered thermal governor 'step_wise'594bob # [ 0.020051] thermal_sys: Registered thermal governor 'user_space'595bob # [ 0.020053] thermal_sys: Registered thermal governor 'power_allocator'596bob # [ 0.020088] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1597bob # [ 0.020096] cpuidle: using governor ladder598bob # [ 0.020101] cpuidle: using governor menu599alice # [ 0.173614] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)600bob # [ 0.020250] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.601bob # [ 0.020275] ASID allocator initialised with 32768 entries602alice # [ 0.173647] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)603bob # [ 0.020937] Serial: AMBA PL011 UART driver604alice # [ 0.173662] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)605bob # [ 0.025101] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1606alice # [ 0.173720] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)607bob # [ 0.025292] printk: console [ttyAMA0] enabled608alice # [ 0.173788] TCP: Hash tables configured (established 8192 bind 8192)609alice # [ 0.173885] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)610alice # [ 0.173948] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)611alice # [ 0.174004] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)612alice # [ 0.174123] NET: Registered PF_UNIX/PF_LOCAL protocol family613alice # [ 0.174156] NET: Registered PF_XDP protocol family614alice # [ 0.174174] PCI: CLS 0 bytes, default 64615alice # [ 0.174451] Trying to unpack rootfs image as initramfs...616bob # [ 0.102571] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages617bob # [ 0.102590] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page618bob # [ 0.102593] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages619bob # [ 0.102595] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page620bob # [ 0.102597] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages621bob # [ 0.102599] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page622bob # [ 0.102601] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages623bob # [ 0.102603] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page624bob # [ 0.103661] fbcon: Taking over console625bob # [ 0.103674] ACPI: Interpreter disabled.626bob # [ 0.106219] iommu: Default domain type: Translated627bob # [ 0.106226] iommu: DMA domain TLB invalidation policy: strict mode628bob # [ 0.112471] SCSI subsystem initialized629bob # [ 0.112755] usbcore: registered new interface driver usbfs630bob # [ 0.112803] usbcore: registered new interface driver hub631bob # [ 0.112811] usbcore: registered new device driver usb632bob # [ 0.113072] pps_core: LinuxPPS API ver. 1 registered633bob # [ 0.113075] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>634bob # [ 0.113087] PTP clock support registered635bob # [ 0.113120] EDAC MC: Ver: 3.0.0636bob # [ 0.113297] scmi_core: SCMI protocol bus registered637bob # [ 0.114693] FPGA manager framework638alice # [ 0.191635] kvm [1]: HYP mode not available639bob # [ 0.127237] vgaarb: loaded640bob # [ 0.127604] clocksource: Switched to clocksource arch_sys_counter641bob # [ 0.133584] VFS: Disk quotas dquot_6.6.0642bob # [ 0.133616] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)643bob # [ 0.135292] netfs: FS-Cache loaded644bob # [ 0.135457] pnp: PnP ACPI: disabled645bob # [ 0.139891] NET: Registered PF_INET protocol family646bob # [ 0.140115] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)647clare # [ 0.263189] Initialise system trusted keyrings648clare # [ 0.263350] workingset: timestamp_bits=42 max_order=18 bucket_order=0649clare # [ 0.263775] squashfs: version 4.0 (2009/01/31) Phillip Lougher650clare # [ 0.263822] 9p: Installing v9fs 9p2000 file system support651clare # [ 0.292724] Key type asymmetric registered652clare # [ 0.292750] Asymmetric key parser 'x509' registered653clare # [ 0.292846] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)654clare # [ 0.293012] io scheduler mq-deadline registered655clare # [ 0.293016] io scheduler kyber registered656clare # [ 0.298357] pl061_gpio 9030000.pl061: PL061 GPIO chip registered657clare # [ 0.298706] ledtrig-cpu: registered to indicate activity on CPUs658bob # [ 0.169048] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)659bob # [ 0.169076] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)660alice # [ 0.260395] Initialise system trusted keyrings661bob # [ 0.169089] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)662alice # [ 0.260525] workingset: timestamp_bits=42 max_order=18 bucket_order=0663bob # [ 0.169129] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)664alice # [ 0.260897] squashfs: version 4.0 (2009/01/31) Phillip Lougher665bob # [ 0.169195] TCP: Hash tables configured (established 8192 bind 8192)666alice # [ 0.260946] 9p: Installing v9fs 9p2000 file system support667bob # [ 0.169289] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)668clare # [ 0.300091] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:669bob # [ 0.169352] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)670clare # [ 0.300113] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000671bob # [ 0.169404] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)672clare # [ 0.300127] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000673bob # [ 0.169515] NET: Registered PF_UNIX/PF_LOCAL protocol family674bob # [ 0.169545] NET: Registered PF_XDP protocol family675clare # [ 0.300131] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000676bob # [ 0.169561] PCI: CLS 0 bytes, default 64677clare # [ 0.300158] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits678bob # [ 0.169810] Trying to unpack rootfs image as initramfs...679clare # [ 0.300188] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]680clare # [ 0.300271] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00681clare # [ 0.300288] pci_bus 0000:00: root bus resource [bus 00-ff]682bob # [ 0.186222] kvm [1]: HYP mode not available683clare # [ 0.300292] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]684clare # [ 0.300295] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]685clare # [ 0.300297] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]686clare # [ 0.300366] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint687clare # [ 0.300836] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint688clare # [ 0.300966] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]689clare # [ 0.300975] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]690clare # [ 0.300994] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]691clare # [ 0.301007] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]692clare # [ 0.301321] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint693clare # [ 0.301448] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]694clare # [ 0.301458] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]695clare # [ 0.301477] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]696clare # [ 0.301788] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint697clare # [ 0.301911] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]698clare # [ 0.301920] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]699clare # [ 0.301939] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]700clare # [ 0.302268] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint701clare # [ 0.302394] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]702clare # [ 0.302403] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]703clare # [ 0.302422] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]704clare # [ 0.302430] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]705clare # [ 0.302743] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint706clare # [ 0.302873] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]707clare # [ 0.302891] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]708alice # [ 0.288014] Key type asymmetric registered709alice # [ 0.288035] Asymmetric key parser 'x509' registered710alice # [ 0.288109] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)711alice # [ 0.288260] io scheduler mq-deadline registered712alice # [ 0.288265] io scheduler kyber registered713clare # [ 0.327389] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint714alice # [ 0.290866] pl061_gpio 9030000.pl061: PL061 GPIO chip registered715clare # [ 0.327527] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]716clare # [ 0.327544] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]717clare # [ 0.327832] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint718clare # [ 0.327955] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]719clare # [ 0.328173] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint720clare # [ 0.328298] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]721clare # [ 0.328317] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]722clare # [ 0.328629] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint723clare # [ 0.328760] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]724clare # [ 0.328779] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]725clare # [ 0.329097] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint726clare # [ 0.329225] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]727clare # [ 0.329242] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]728clare # [ 0.329554] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint729clare # [ 0.329732] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]730clare # [ 0.329742] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]731clare # [ 0.329760] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]732clare # [ 0.330147] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint733clare # [ 0.330273] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]734alice # [ 0.300184] ledtrig-cpu: registered to indicate activity on CPUs735clare # [ 0.330282] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]736alice # [ 0.300555] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:737clare # [ 0.330302] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]738alice # [ 0.300576] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000739clare # [ 0.330769] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned740alice # [ 0.300589] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000741clare # [ 0.330778] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned742clare # [ 0.330782] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned743alice # [ 0.300593] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000744clare # [ 0.330819] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned745alice # [ 0.300621] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits746clare # [ 0.330850] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned747alice # [ 0.300646] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]748clare # [ 0.330882] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned749alice # [ 0.300729] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00750alice # [ 0.300746] pci_bus 0000:00: root bus resource [bus 00-ff]751clare # [ 0.330921] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned752alice # [ 0.300749] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]753alice # [ 0.300752] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]754clare # [ 0.330952] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned755alice # [ 0.300754] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]756alice # [ 0.300846] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint757alice # [ 0.301306] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint758alice # [ 0.301436] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]759alice # [ 0.301444] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]760alice # [ 0.301463] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]761alice # [ 0.301476] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]762alice # [ 0.301823] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint763alice # [ 0.301949] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]764alice # [ 0.301959] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]765alice # [ 0.301977] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]766alice # [ 0.302313] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint767alice # [ 0.302443] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]768alice # [ 0.302453] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]769alice # [ 0.302473] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]770alice # [ 0.302800] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint771alice # [ 0.302928] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]772alice # [ 0.302937] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]773alice # [ 0.302955] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]774alice # [ 0.302964] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]775alice # [ 0.303328] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint776alice # [ 0.303458] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]777alice # [ 0.303478] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]778clare # [ 0.355054] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned779clare # [ 0.355099] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned780alice # [ 0.322733] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint781clare # [ 0.355136] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned782alice # [ 0.322895] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]783clare # [ 0.355174] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned784alice # [ 0.322917] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]785clare # [ 0.355260] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned786clare # [ 0.355340] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned787clare # [ 0.355357] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned788clare # [ 0.355372] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned789clare # [ 0.355387] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned790alice # [ 0.325603] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint791alice # [ 0.325761] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]792clare # [ 0.355402] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned793clare # [ 0.355419] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned794alice # [ 0.326075] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint795alice # [ 0.326213] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]796clare # [ 0.355432] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned797alice # [ 0.326232] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]798clare # [ 0.355447] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned799clare # [ 0.355468] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned800alice # [ 0.326550] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint801clare # [ 0.355481] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned802alice # [ 0.326687] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]803clare # [ 0.355495] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned804alice # [ 0.326705] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]805clare # [ 0.355508] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned806alice # [ 0.327020] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint807clare # [ 0.355521] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned808alice # [ 0.327153] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]809clare # [ 0.355534] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned810alice # [ 0.327172] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]811clare # [ 0.355548] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned812alice # [ 0.327504] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint813clare # [ 0.355570] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned814clare # [ 0.355584] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned815clare # [ 0.355597] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned816clare # [ 0.355623] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]817clare # [ 0.355628] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]818clare # [ 0.355630] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]819clare # [ 0.356209] pci 0000:00:07.0: enabling device (0000 -> 0002)820bob # [ 0.260106] Initialise system trusted keyrings821bob # [ 0.260246] workingset: timestamp_bits=42 max_order=18 bucket_order=0822bob # [ 0.260575] squashfs: version 4.0 (2009/01/31) Phillip Lougher823bob # [ 0.260613] 9p: Installing v9fs 9p2000 file system support824alice # [ 0.343759] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]825alice # [ 0.343779] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]826alice # [ 0.343799] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]827alice # [ 0.344238] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint828alice # [ 0.344373] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]829alice # [ 0.344382] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]830alice # [ 0.344401] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]831alice # [ 0.345158] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned832alice # [ 0.345166] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned833alice # [ 0.345170] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned834alice # [ 0.345207] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned835alice # [ 0.345238] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned836alice # [ 0.345270] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned837alice # [ 0.345302] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned838alice # [ 0.345333] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned839alice # [ 0.345367] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned840alice # [ 0.345398] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned841alice # [ 0.345428] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned842clare # [ 0.389117] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)843alice # [ 0.345459] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned844alice # [ 0.345541] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned845alice # [ 0.345573] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned846alice # [ 0.345585] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned847alice # [ 0.345597] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned848alice # [ 0.345609] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned849alice # [ 0.345621] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned850alice # [ 0.345634] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned851alice # [ 0.345646] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned852alice # [ 0.345659] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned853alice # [ 0.345685] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned854alice # [ 0.345697] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned855alice # [ 0.345709] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned856alice # [ 0.345721] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned857alice # [ 0.345733] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned858alice # [ 0.345745] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned859alice # [ 0.345757] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned860alice # [ 0.345769] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned861alice # [ 0.345780] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned862alice # [ 0.345792] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned863alice # [ 0.345808] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]864alice # [ 0.345813] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]865clare # [ 0.404496] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)866alice # [ 0.345815] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]867alice # [ 0.346402] pci 0000:00:07.0: enabling device (0000 -> 0002)868bob # [ 0.290861] Key type asymmetric registered869bob # [ 0.290880] Asymmetric key parser 'x509' registered870bob # [ 0.290957] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)871bob # [ 0.291126] io scheduler mq-deadline registered872bob # [ 0.291130] io scheduler kyber registered873bob # [ 0.304378] pl061_gpio 9030000.pl061: PL061 GPIO chip registered874bob # [ 0.304783] ledtrig-cpu: registered to indicate activity on CPUs875bob # [ 0.305135] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:876bob # [ 0.305153] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000877bob # [ 0.305166] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000878bob # [ 0.305171] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000879bob # [ 0.305199] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits880bob # [ 0.305223] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]881bob # [ 0.305320] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00882bob # [ 0.305338] pci_bus 0000:00: root bus resource [bus 00-ff]883bob # [ 0.305341] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]884bob # [ 0.305344] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]885bob # [ 0.305347] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]886bob # [ 0.305439] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint887bob # [ 0.305938] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint888bob # [ 0.306081] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]889alice # [ 0.377563] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)890bob # [ 0.306091] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]891bob # [ 0.306111] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]892bob # [ 0.306124] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]893bob # [ 0.306460] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint894bob # [ 0.306600] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]895bob # [ 0.306610] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]896bob # [ 0.306630] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]897bob # [ 0.306948] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint898bob # [ 0.307092] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]899bob # [ 0.307102] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]900bob # [ 0.307119] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]901bob # [ 0.307442] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint902bob # [ 0.307582] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]903bob # [ 0.307591] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]904bob # [ 0.324365] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]905bob # [ 0.324381] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]906bob # [ 0.324782] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint907bob # [ 0.324913] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]908bob # [ 0.324932] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]909bob # [ 0.325252] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint910bob # [ 0.325378] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]911bob # [ 0.325397] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]912bob # [ 0.325678] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint913bob # [ 0.325798] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]914bob # [ 0.326014] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint915bob # [ 0.326141] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]916bob # [ 0.326159] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]917bob # [ 0.326465] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint918bob # [ 0.326591] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]919bob # [ 0.326609] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]920bob # [ 0.326913] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint921bob # [ 0.327037] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]922bob # [ 0.327055] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]923bob # [ 0.327382] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint924bob # [ 0.327555] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]925bob # [ 0.327565] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]926bob # [ 0.327584] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]927bob # [ 0.340651] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint928bob # [ 0.340794] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]929bob # [ 0.340804] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]930bob # [ 0.340823] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]931bob # [ 0.341291] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned932bob # [ 0.341299] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned933bob # [ 0.341303] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned934bob # [ 0.341340] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned935bob # [ 0.341373] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned936bob # [ 0.341404] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned937bob # [ 0.341438] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned938bob # [ 0.341470] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned939bob # [ 0.341506] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned940bob # [ 0.341538] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned941bob # [ 0.341569] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned942bob # [ 0.341599] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned943bob # [ 0.341664] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned944bob # [ 0.341694] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned945bob # [ 0.341707] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned946bob # [ 0.341721] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned947bob # [ 0.341733] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned948bob # [ 0.341839] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned949bob # [ 0.341853] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned950bob # [ 0.341865] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned951bob # [ 0.341879] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned952bob # [ 0.341898] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned953bob # [ 0.341910] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned954bob # [ 0.341922] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned955bob # [ 0.341935] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned956bob # [ 0.341947] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned957bob # [ 0.341958] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned958alice # [ 0.417032] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)959bob # [ 0.341971] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned960bob # [ 0.341983] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned961bob # [ 0.341994] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned962bob # [ 0.342006] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned963bob # [ 0.342025] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]964bob # [ 0.342030] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]965bob # [ 0.342032] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]966bob # [ 0.342575] pci 0000:00:07.0: enabling device (0000 -> 0002)967clare # [ 0.415177] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)968clare # [ 0.417447] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)969alice # [ 0.460541] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)970alice # [ 0.462444] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)971clare # [ 0.536835] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)972clare # [ 0.539982] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)973clare # [ 0.541502] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)974alice # [ 0.508997] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)975alice # [ 0.511070] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)976clare # [ 0.552381] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)977alice # [ 0.521831] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)978alice # [ 0.523556] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)979clare # [ 0.563179] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)980clare # [ 0.566406] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)981bob # [ 0.378419] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)982alice # [ 0.533383] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)983clare # [ 0.576244] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)984clare # [ 0.592090] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled985clare # [ 0.593547] msm_serial: driver initialized986clare # [ 0.593663] SuperH (H)SCI(F) driver initialized987clare # [ 0.593708] STM32 USART driver initialized988alice # [ 0.536303] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)989clare # [ 0.621546] loop: module loaded990clare # [ 0.621696] virtio_blk virtio2: 1/0/0 default/read/poll queues991clare # [ 0.622446] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)992bob # [ 0.501069] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)993alice # [ 0.596199] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)994clare # [ 0.635503] megasas: 07.734.00.00-rc1995clare # [ 0.636097] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]996clare # [ 0.640746] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000997clare # [ 0.640785] Intel/Sharp Extended Query Table at 0x0031998clare # [ 0.642635] Using buffer write method999clare # [ 0.642683] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1000alice # [ 0.608585] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled1001alice # [ 0.609943] msm_serial: driver initialized1002alice # [ 0.610046] SuperH (H)SCI(F) driver initialized1003alice # [ 0.610089] STM32 USART driver initialized1004bob # [ 0.513319] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)1005bob # [ 0.515157] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)1006bob # [ 0.540969] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)1007clare # [ 0.650934] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001008clare # [ 0.650956] Intel/Sharp Extended Query Table at 0x00311009alice # [ 0.639417] loop: module loaded1010alice # [ 0.639901] virtio_blk virtio2: 1/0/0 default/read/poll queues1011alice # [ 0.640770] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1012alice # [ 0.645158] megasas: 07.734.00.00-rc11013alice # [ 0.646210] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1014clare # [ 0.688319] Using buffer write method1015clare # [ 0.688342] Concatenating MTD devices:1016clare # [ 0.688344] (0): "0.flash"1017clare # [ 0.688346] (1): "0.flash"1018clare # [ 0.688348] into device "0.flash"1019alice # [ 0.657041] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001020alice # [ 0.657084] Intel/Sharp Extended Query Table at 0x00311021alice # [ 0.659016] Using buffer write method1022alice # [ 0.659077] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1023alice # [ 0.663076] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001024alice # [ 0.663097] Intel/Sharp Extended Query Table at 0x00311025bob # [ 0.544881] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)1026bob # [ 0.546613] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)1027bob # [ 0.760956] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)1028bob # [ 0.762806] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)1029bob # [ 0.765575] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)1030alice # [ 0.856300] Using buffer write method1031alice # [ 0.856322] Concatenating MTD devices:1032alice # [ 0.856324] (0): "0.flash"1033alice # [ 0.856326] (1): "0.flash"1034alice # [ 0.856328] into device "0.flash"1035clare # [ 0.898463] Freeing initrd memory: 28944K1036bob # [ 0.777055] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)1037clare # [ 0.906576] tun: Universal TUN/TAP device driver, 1.61038clare # [ 0.910053] thunder_xcv, ver 1.01039clare # [ 0.910076] thunder_bgx, ver 1.01040clare # [ 0.910085] nicpf, ver 1.01041clare # [ 0.910421] e1000: Intel(R) PRO/1000 Network Driver1042clare # [ 0.910425] e1000: Copyright (c) 1999-2006 Intel Corporation.1043bob # [ 0.788069] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled1044bob # [ 0.790238] msm_serial: driver initialized1045clare # [ 0.910438] e1000e: Intel(R) PRO/1000 Network Driver1046bob # [ 0.790333] SuperH (H)SCI(F) driver initialized1047clare # [ 0.910442] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1048bob # [ 0.790373] STM32 USART driver initialized1049clare # [ 0.910455] igb: Intel(R) Gigabit Ethernet Network Driver1050clare # [ 0.910457] igb: Copyright (c) 2007-2014 Intel Corporation.1051clare # [ 0.910467] igbvf: Intel(R) Gigabit Virtual Function Network Driver1052clare # [ 0.910469] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1053clare # [ 0.910563] sky2: driver version 1.301054clare # [ 0.916284] usbcore: registered new interface driver usb-storage1055clare # [ 0.916336] usbcore: registered new interface driver usbserial_generic1056clare # [ 0.916352] usbserial: USB Serial support registered for generic1057clare # [ 0.916670] hv_vmbus: registering driver hyperv_keyboard1058clare # [ 0.917795] ehci-pci 0000:00:07.0: EHCI Host Controller1059clare # [ 0.917827] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 11060clare # [ 0.918003] ehci-pci 0000:00:07.0: irq 16, io mem 0x100860001061clare # [ 0.918210] rtc-pl031 9010000.pl031: registered as rtc01062clare # [ 0.918242] rtc-pl031 9010000.pl031: setting system clock to 2026-10-16T09:37:34 UTC (1792143454)1063clare # [ 0.918425] i2c_dev: i2c /dev entries driver1064clare # [ 0.925052] sdhci: Secure Digital Host Controller Interface driver1065clare # [ 0.925060] sdhci: Copyright(c) Pierre Ossman1066clare # [ 0.925221] Synopsys Designware Multimedia Card Interface Driver1067clare # [ 0.925414] sdhci-pltfm: SDHCI platform and OF driver helper1068clare # [ 0.926377] hid: raw HID events driver (C) Jiri Kosina1069clare # [ 0.926514] usbcore: registered new interface driver usbhid1070clare # [ 0.926517] usbhid: USB HID core driver1071clare # [ 0.927071] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.001072clare # [ 0.927489] hub 1-0:1.0: USB hub found1073clare # [ 0.927551] hub 1-0:1.0: 6 ports detected1074clare # [ 0.932187] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1075clare # [ 0.933395] drop_monitor: Initializing network drop monitor service1076clare # [ 0.933554] NET: Registered PF_INET6 protocol family1077clare # [ 0.934431] Segment Routing with IPv61078clare # [ 0.934442] In-situ OAM (IOAM) with IPv61079clare # [ 0.934497] NET: Registered PF_PACKET protocol family1080clare # [ 0.934566] 9pnet: Installing 9P2000 support1081clare # [ 0.934613] Key type dns_resolver registered1082bob # [ 0.818655] loop: module loaded1083bob # [ 0.818820] virtio_blk virtio2: 1/0/0 default/read/poll queues1084bob # [ 0.819596] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1085clare # [ 0.941429] registered taskstats version 11086clare # [ 0.941633] Loading compiled-in X.509 certificates1087clare # [ 0.947707] Demotion targets for Node 0: null1088clare # [ 0.948181] Key type .fscrypt registered1089clare # [ 0.948184] Key type fscrypt-provisioning registered1090clare # [ 0.948321] ima: No TPM chip found, activating TPM-bypass!1091clare # [ 0.948333] ima: Allocated hash algorithm: sha11092bob # [ 0.828177] megasas: 07.734.00.00-rc11093clare # [ 0.948438] ima: No architecture policies found1094clare # [ 0.949116] input: gpio-keys as /devices/platform/gpio-keys/input/input01095bob # [ 0.828815] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1096clare # [ 0.962871] clk: Disabling unused clocks1097clare # [ 0.962888] PM: genpd: Disabling unused power domains1098bob # [ 0.840255] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001099bob # [ 0.840299] Intel/Sharp Extended Query Table at 0x00311100bob # [ 0.842118] Using buffer write method1101bob # [ 0.842170] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1102clare # [ 0.966461] Freeing unused kernel memory: 4736K1103clare # [ 0.966561] Run /init as init process1104bob # [ 0.847003] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001105bob # [ 0.847027] Intel/Sharp Extended Query Table at 0x00311106bob # [ 0.857696] Using buffer write method1107bob # [ 0.857723] Concatenating MTD devices:1108bob # [ 0.857725] (0): "0.flash"1109bob # [ 0.857727] (1): "0.flash"1110bob # [ 0.857729] into device "0.flash"1111clare # [ 0.990039] systemd[1]: Successfully made /usr/ read-only.1112alice # [ 0.987366] Freeing initrd memory: 28940K1113alice # [ 0.995467] tun: Universal TUN/TAP device driver, 1.61114alice # [ 0.998901] thunder_xcv, ver 1.01115alice # [ 0.998922] thunder_bgx, ver 1.01116alice # [ 0.998962] nicpf, ver 1.01117alice # [ 0.999291] e1000: Intel(R) PRO/1000 Network Driver1118alice # [ 0.999295] e1000: Copyright (c) 1999-2006 Intel Corporation.1119alice # [ 0.999314] e1000e: Intel(R) PRO/1000 Network Driver1120alice # [ 0.999319] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1121alice # [ 0.999333] igb: Intel(R) Gigabit Ethernet Network Driver1122alice # [ 0.999335] igb: Copyright (c) 2007-2014 Intel Corporation.1123alice # [ 0.999347] igbvf: Intel(R) Gigabit Virtual Function Network Driver1124alice # [ 0.999350] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1125alice # [ 0.999422] sky2: driver version 1.301126alice # [ 1.005350] usbcore: registered new interface driver usb-storage1127alice # [ 1.005448] usbcore: registered new interface driver usbserial_generic1128alice # [ 1.005465] usbserial: USB Serial support registered for generic1129alice # [ 1.005856] hv_vmbus: registering driver hyperv_keyboard1130alice # [ 1.008348] ehci-pci 0000:00:07.0: EHCI Host Controller1131alice # [ 1.008381] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 11132alice # [ 1.008608] ehci-pci 0000:00:07.0: irq 16, io mem 0x100860001133alice # [ 1.012352] rtc-pl031 9010000.pl031: registered as rtc01134alice # [ 1.012397] rtc-pl031 9010000.pl031: setting system clock to 2026-10-16T09:37:34 UTC (1792143454)1135alice # [ 1.012651] i2c_dev: i2c /dev entries driver1136alice # [ 1.014738] sdhci: Secure Digital Host Controller Interface driver1137alice # [ 1.014745] sdhci: Copyright(c) Pierre Ossman1138alice # [ 1.014902] Synopsys Designware Multimedia Card Interface Driver1139alice # [ 1.015088] sdhci-pltfm: SDHCI platform and OF driver helper1140alice # [ 1.019691] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.001141alice # [ 1.020178] hub 1-0:1.0: USB hub found1142alice # [ 1.020822] hid: raw HID events driver (C) Jiri Kosina1143alice # [ 1.021009] usbcore: registered new interface driver usbhid1144alice # [ 1.021012] usbhid: USB HID core driver1145alice # [ 1.022031] hub 1-0:1.0: 6 ports detected1146alice # [ 1.022426] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1147alice # [ 1.023296] drop_monitor: Initializing network drop monitor service1148alice # [ 1.023459] NET: Registered PF_INET6 protocol family1149alice # [ 1.028341] Segment Routing with IPv61150alice # [ 1.028367] In-situ OAM (IOAM) with IPv61151alice # [ 1.028432] NET: Registered PF_PACKET protocol family1152alice # [ 1.028503] 9pnet: Installing 9P2000 support1153alice # [ 1.028551] Key type dns_resolver registered1154alice # [ 1.031521] registered taskstats version 11155alice # [ 1.031734] Loading compiled-in X.509 certificates1156alice # [ 1.039069] Demotion targets for Node 0: null1157alice # [ 1.039168] Key type .fscrypt registered1158alice # [ 1.039171] Key type fscrypt-provisioning registered1159alice # [ 1.039298] ima: No TPM chip found, activating TPM-bypass!1160alice # [ 1.039313] ima: Allocated hash algorithm: sha11161alice # [ 1.039374] ima: No architecture policies found1162alice # [ 1.042293] input: gpio-keys as /devices/platform/gpio-keys/input/input01163alice # [ 1.054284] clk: Disabling unused clocks1164alice # [ 1.054308] PM: genpd: Disabling unused power domains1165alice # [ 1.058057] Freeing unused kernel memory: 4736K1166alice # [ 1.058203] Run /init as init process1167bob # [ 0.979162] Freeing initrd memory: 28936K1168bob # [ 0.988346] tun: Universal TUN/TAP device driver, 1.61169bob # [ 0.990890] thunder_xcv, ver 1.01170bob # [ 0.990909] thunder_bgx, ver 1.01171bob # [ 0.990918] nicpf, ver 1.01172bob # [ 0.991282] e1000: Intel(R) PRO/1000 Network Driver1173bob # [ 0.991287] e1000: Copyright (c) 1999-2006 Intel Corporation.1174bob # [ 0.991302] e1000e: Intel(R) PRO/1000 Network Driver1175bob # [ 0.991307] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1176bob # [ 0.991320] igb: Intel(R) Gigabit Ethernet Network Driver1177bob # [ 0.991322] igb: Copyright (c) 2007-2014 Intel Corporation.1178bob # [ 0.991333] igbvf: Intel(R) Gigabit Virtual Function Network Driver1179bob # [ 0.991336] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1180bob # [ 0.991402] sky2: driver version 1.301181bob # [ 0.997572] usbcore: registered new interface driver usb-storage1182bob # [ 0.997668] usbcore: registered new interface driver usbserial_generic1183bob # [ 0.997687] usbserial: USB Serial support registered for generic1184alice # [ 1.082533] systemd[1]: Successfully made /usr/ read-only.1185bob # [ 0.997996] hv_vmbus: registering driver hyperv_keyboard1186bob # [ 0.999274] ehci-pci 0000:00:07.0: EHCI Host Controller1187bob # [ 0.999308] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 11188bob # [ 0.999593] ehci-pci 0000:00:07.0: irq 16, io mem 0x100860001189bob # [ 1.003280] rtc-pl031 9010000.pl031: registered as rtc01190bob # [ 1.003320] rtc-pl031 9010000.pl031: setting system clock to 2026-10-16T09:37:34 UTC (1792143454)1191bob # [ 1.003594] i2c_dev: i2c /dev entries driver1192bob # [ 1.006833] sdhci: Secure Digital Host Controller Interface driver1193bob # [ 1.006839] sdhci: Copyright(c) Pierre Ossman1194bob # [ 1.006999] Synopsys Designware Multimedia Card Interface Driver1195bob # [ 1.007202] sdhci-pltfm: SDHCI platform and OF driver helper1196bob # [ 1.010157] hid: raw HID events driver (C) Jiri Kosina1197bob # [ 1.010300] usbcore: registered new interface driver usbhid1198bob # [ 1.010303] usbhid: USB HID core driver1199bob # [ 1.011131] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1200bob # [ 1.011739] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.001201bob # [ 1.012147] hub 1-0:1.0: USB hub found1202bob # [ 1.012171] hub 1-0:1.0: 6 ports detected1203bob # [ 1.016246] drop_monitor: Initializing network drop monitor service1204bob # [ 1.016361] NET: Registered PF_INET6 protocol family1205bob # [ 1.017032] Segment Routing with IPv61206bob # [ 1.017042] In-situ OAM (IOAM) with IPv61207bob # [ 1.017094] NET: Registered PF_PACKET protocol family1208bob # [ 1.017150] 9pnet: Installing 9P2000 support1209bob # [ 1.017183] Key type dns_resolver registered1210bob # [ 1.022853] registered taskstats version 11211bob # [ 1.023060] Loading compiled-in X.509 certificates1212bob # [ 1.029599] Demotion targets for Node 0: null1213bob # [ 1.029710] Key type .fscrypt registered1214bob # [ 1.029713] Key type fscrypt-provisioning registered1215bob # [ 1.029830] ima: No TPM chip found, activating TPM-bypass!1216bob # [ 1.029841] ima: Allocated hash algorithm: sha11217bob # [ 1.029899] ima: No architecture policies found1218bob # [ 1.030467] input: gpio-keys as /devices/platform/gpio-keys/input/input01219bob # [ 1.044024] clk: Disabling unused clocks1220bob # [ 1.044038] PM: genpd: Disabling unused power domains1221bob # [ 1.046604] Freeing unused kernel memory: 4736K1222bob # [ 1.046704] Run /init as init process1223clare # [ 1.175095] usb 1-1: new high-speed USB device number 2 using ehci-pci1224bob # [ 1.071552] systemd[1]: Successfully made /usr/ read-only.1225alice # [ 1.267678] usb 1-1: new high-speed USB device number 2 using ehci-pci1226clare # [ 1.325394] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1227clare # [ 1.325428] systemd[1]: Detected virtualization qemu.1228clare # [ 1.325491] systemd[1]: Detected architecture arm64.1229clare # [ 1.325502] systemd[1]: Running in initrd.1230clare # [ 1.327411] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input11231clare # [ 1.341185] systemd[1]: Initializing machine ID from random generator.1232clare # [ 1.341695] systemd[1]: Hostname set to <clare>.1233bob # [ 1.263857] usb 1-1: new high-speed USB device number 2 using ehci-pci1234clare # [ 1.414205] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input01235clare # [ 1.459842] systemd[1]: bpf-restrict-fs: LSM BPF program attached1236alice # [ 1.416888] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1237alice # [ 1.416904] systemd[1]: Detected virtualization qemu.1238alice # [ 1.416930] systemd[1]: Detected architecture arm64.1239alice # [ 1.416934] systemd[1]: Running in initrd.1240alice # [ 1.417502] systemd[1]: Initializing machine ID from random generator.1241alice # [ 1.417775] systemd[1]: Hostname set to <alice>.1242alice # [ 1.426716] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input11243clare # [ 1.520465] systemd[1]: Queued start job for default target Initrd Default Target.1244bob # [ 1.406362] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1245bob # [ 1.406379] systemd[1]: Detected virtualization qemu.1246bob # [ 1.406415] systemd[1]: Detected architecture arm64.1247clare # [ 1.528877] systemd[1]: Created slice Slice /system/modprobe.1248bob # [ 1.406419] systemd[1]: Running in initrd.1249bob # [ 1.407076] systemd[1]: Initializing machine ID from random generator.1250clare # [ 1.529046] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1251bob # [ 1.407263] systemd[1]: Hostname set to <bob>.1252clare # [ 1.529084] systemd[1]: Expecting device /dev/disk/by-label/nixos...1253clare # [ 1.529115] systemd[1]: Reached target Path Units.1254clare # [ 1.529130] systemd[1]: Reached target Slice Units.1255clare # [ 1.529144] systemd[1]: Reached target Swaps.1256clare # [ 1.529164] systemd[1]: Reached target Timer Units.1257clare # [ 1.529320] systemd[1]: Listening on D-Bus System Message Bus Socket.1258clare # [ 1.529434] systemd[1]: Listening on Journal Socket (/dev/log).1259clare # [ 1.529548] systemd[1]: Listening on Journal Sockets.1260clare # [ 1.529618] systemd[1]: Listening on udev Control Socket.1261clare # [ 1.529667] systemd[1]: Listening on udev Kernel Socket.1262clare # [ 1.529682] systemd[1]: Reached target Socket Units.1263clare # [ 1.531029] usb 1-2: new high-speed USB device number 3 using ehci-pci1264clare # [ 1.535460] systemd[1]: Starting Create List of Static Device Nodes...1265clare # [ 1.535572] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1266bob # [ 1.419023] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input11267alice # [ 1.503858] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input01268alice # [ 1.509705] systemd[1]: bpf-restrict-fs: LSM BPF program attached1269clare # [ 1.547147] systemd[1]: Mounting Kernel Configuration File System...1270clare # [ 1.551769] systemd[1]: Starting Journal Service...1271clare # [ 1.574283] systemd[1]: Starting Load Kernel Modules...1272clare # [ 1.574386] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1273clare # [ 1.601876] systemd-journald[71]: Collecting audit messages is disabled.1274clare # [ 1.607178] systemd[1]: Starting Coldplug All udev Devices...1275clare # [ 1.609307] systemd[1]: Finished Create List of Static Device Nodes.1276alice # [ 1.572559] systemd[1]: Queued start job for default target Initrd Default Target.1277clare # [ 1.616181] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1278bob # [ 1.496717] systemd[1]: bpf-restrict-fs: LSM BPF program attached1279bob # [ 1.499870] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input01280alice # [ 1.581345] systemd[1]: Created slice Slice /system/modprobe.1281alice # [ 1.581523] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1282alice # [ 1.581553] systemd[1]: Expecting device /dev/disk/by-label/nixos...1283alice # [ 1.581575] systemd[1]: Reached target Path Units.1284alice # [ 1.581588] systemd[1]: Reached target Slice Units.1285alice # [ 1.581601] systemd[1]: Reached target Swaps.1286alice # [ 1.581634] systemd[1]: Reached target Timer Units.1287alice # [ 1.581776] systemd[1]: Listening on D-Bus System Message Bus Socket.1288alice # [ 1.581897] systemd[1]: Listening on Journal Socket (/dev/log).1289alice # [ 1.582029] systemd[1]: Listening on Journal Sockets.1290alice # [ 1.582101] systemd[1]: Listening on udev Control Socket.1291alice # [ 1.582148] systemd[1]: Listening on udev Kernel Socket.1292alice # [ 1.582162] systemd[1]: Reached target Socket Units.1293alice # [ 1.583488] systemd[1]: Starting Create List of Static Device Nodes...1294alice # [ 1.583551] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1295clare # [ 1.628132] systemd[1]: Mounted Kernel Configuration File System.1296alice # [ 1.598978] systemd[1]: Mounting Kernel Configuration File System...1297alice # [ 1.605169] systemd[1]: Starting Journal Service...1298alice # [ 1.612395] systemd[1]: Starting Load Kernel Modules...1299alice # [ 1.612494] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1300alice # [ 1.623654] usb 1-2: new high-speed USB device number 3 using ehci-pci1301clare # [ 1.671611] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1302bob # [ 1.555404] systemd[1]: Queued start job for default target Initrd Default Target.1303clare # [ 1.675930] systemd[1]: Starting Create Static Device Nodes in /dev...1304alice # [ 1.640758] systemd[1]: Starting Coldplug All udev Devices...1305alice # [ 1.642769] systemd[1]: Finished Create List of Static Device Nodes.1306bob # [ 1.565788] systemd[1]: Created slice Slice /system/modprobe.1307bob # [ 1.565967] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1308bob # [ 1.566002] systemd[1]: Expecting device /dev/disk/by-label/nixos...1309bob # [ 1.566027] systemd[1]: Reached target Path Units.1310alice # [ 1.647915] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1311bob # [ 1.566040] systemd[1]: Reached target Slice Units.1312bob # [ 1.566053] systemd[1]: Reached target Swaps.1313bob # [ 1.566073] systemd[1]: Reached target Timer Units.1314bob # [ 1.566215] systemd[1]: Listening on D-Bus System Message Bus Socket.1315clare # [ 1.690105] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1316bob # [ 1.566336] systemd[1]: Listening on Journal Socket (/dev/log).1317bob # [ 1.566417] systemd[1]: Listening on Journal Sockets.1318bob # [ 1.566479] systemd[1]: Listening on udev Control Socket.1319bob # [ 1.566559] systemd[1]: Listening on udev Kernel Socket.1320bob # [ 1.566579] systemd[1]: Reached target Socket Units.1321clare # [ 1.694758] systemd[1]: Started Journal Service.1322clare # [ 1.695742] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input21323bob # [ 1.575799] systemd[1]: Starting Create List of Static Device Nodes...1324clare # [ 1.695849] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01325bob # [ 1.575889] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1326bob # [ 1.577377] systemd[1]: Mounting Kernel Configuration File System...1327alice # [ 1.666700] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1328clare # [ 1.704297] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1329bob # [ 1.590459] systemd[1]: Starting Journal Service...1330alice # [ 1.676115] systemd[1]: Mounted Kernel Configuration File System.1331alice # [ 1.676768] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1332clare # [ 1.708463] systemd-modules-load[73]: Inserted module 'dm_mod'1333clare # [ 1.713481] [drm] pci: virtio-gpu-pci detected at 0000:00:05.01334clare # [ 1.714061] [drm] features: -virgl +edid -resource_blob -host_visible1335clare # [ 1.714064] [drm] features: -context_init1336clare # [ 1.714615] [drm] number of scanouts: 11337clare # [ 1.714623] [drm] number of cap sets: 01338bob # [ 1.600558] systemd[1]: Starting Load Kernel Modules...1339clare # [ 1.716301] systemd-modules-load[73]: Module 'virtio_balloon' is built in1340clare # [ 1.717040] systemd-modules-load[73]: Module 'virtio_console' is built in1341bob # [ 1.600653] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1342clare # [ 1.725708] systemd[1]: Finished Create Static Device Nodes in /dev.1343clare # [ 1.726416] systemd[1]: Reached target Preparation for Local File Systems.1344alice # [ 1.696097] [drm] pci: virtio-gpu-pci detected at 0000:00:05.01345alice # [ 1.696328] [drm] features: -virgl +edid -resource_blob -host_visible1346alice # [ 1.696331] [drm] features: -context_init1347alice # [ 1.696850] [drm] number of scanouts: 11348alice # [ 1.696859] [drm] number of cap sets: 01349clare # [ 1.739359] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic1350clare # [ 1.739374] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 01351clare # [ 1.733394] systemd[1]: Reached target Local File Systems.1352clare # [ 1.734323] systemd[1]: Starting Create System Files and Directories...1353bob # [ 1.624213] usb 1-2: new high-speed USB device number 3 using ehci-pci1354alice # [ 1.712167] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1355alice # [ 1.713401] systemd[1]: Starting Create Static Device Nodes in /dev...1356alice # [ 1.717789] systemd-journald[71]: Collecting audit messages is disabled.1357bob # [ 1.635828] systemd[1]: Starting Coldplug All udev Devices...1358alice # [ 1.720635] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic1359alice # [ 1.720644] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 01360clare # [ 1.760202] Console: switching to colour frame buffer device 160x501361clare # [ 1.760873] systemd[1]: Starting Rule-based Manager for Device Events and Files...1362clare # [ 1.775584] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device1363bob # [ 1.655747] systemd[1]: Finished Create List of Static Device Nodes.1364alice # [ 1.739046] Console: switching to colour frame buffer device 160x501365clare # [ 1.777028] systemd-modules-load[73]: Inserted module 'virtio_gpu'1366bob # [ 1.661238] systemd-journald[71]: Collecting audit messages is disabled.1367clare # [ 1.777744] systemd-modules-load[73]: Module 'virtio_rng' is built in1368clare # [ 1.784205] systemd[1]: Finished Load Kernel Modules.1369bob # [ 1.674373] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1370bob # [ 1.676681] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1371alice # [ 1.759944] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device1372clare # [ 1.792163] systemd[1]: Starting Apply Kernel Variables...1373alice # [ 1.768160] systemd[1]: Finished Load Kernel Modules.1374alice # [ 1.769449] systemd[1]: Starting Apply Kernel Variables...1375bob # [ 1.687943] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1376bob # [ 1.693012] systemd[1]: Mounted Kernel Configuration File System.1377alice # [ 1.782268] systemd[1]: Finished Create Static Device Nodes in /dev.1378alice # [ 1.783515] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input21379alice # [ 1.786058] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01380alice # [ 1.786937] systemd[1]: Reached target Preparation for Local File Systems.1381alice # [ 1.787063] systemd[1]: Reached target Local File Systems.1382bob # [ 1.706565] [drm] pci: virtio-gpu-pci detected at 0000:00:05.01383bob # [ 1.706811] [drm] features: -virgl +edid -resource_blob -host_visible1384bob # [ 1.706813] [drm] features: -context_init1385bob # [ 1.707371] [drm] number of scanouts: 11386bob # [ 1.707380] [drm] number of cap sets: 01387clare # [ 1.821037] systemd[1]: Finished Create System Files and Directories.1388alice # [ 1.804032] systemd[1]: Starting Rule-based Manager for Device Events and Files...1389bob # [ 1.724137] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1390clare # [ 1.837870] systemd[1]: Finished Apply Kernel Variables.1391bob # [ 1.725484] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic1392bob # [ 1.725496] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 01393bob # [ 1.729905] systemd[1]: Starting Create Static Device Nodes in /dev...1394clare # [ 1.841541] systemd-udevd[83]: Using default interface naming scheme 'v261'.1395alice # [ 1.813988] systemd-modules-load[72]: Inserted module 'dm_mod'1396alice # [ 1.814935] systemd-modules-load[72]: Module 'virtio_balloon' is built in1397alice # [ 1.815742] systemd-modules-load[72]: Module 'virtio_console' is built in1398alice # [ 1.819008] systemd-modules-load[72]: Inserted module 'virtio_gpu'1399alice # [ 1.824306] systemd[1]: Started Journal Service.1400alice # [ 1.821452] systemd-modules-load[72]: Module 'virtio_rng' is built in1401alice # [ 1.829773] systemd[1]: Finished Apply Kernel Variables.1402alice # [ 1.832113] systemd[1]: Starting Create System Files and Directories...1403bob # [ 1.749969] systemd-modules-load[72]: Inserted module 'dm_mod'1404bob # [ 1.752349] systemd-modules-load[72]: Module 'virtio_balloon' is built in1405bob # [ 1.753224] systemd-modules-load[72]: Module 'virtio_console' is built in1406bob # [ 1.762221] systemd[1]: Started Journal Service.1407clare # [ 1.873202] systemd[1]: Started Rule-based Manager for Device Events and Files.1408bob # [ 1.772900] Console: switching to colour frame buffer device 160x501409bob # [ 1.773573] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device1410bob # [ 1.770455] systemd-modules-load[72]: Inserted module 'virtio_gpu'1411bob # [ 1.773380] systemd-modules-load[72]: Module 'virtio_rng' is built in1412bob # [ 1.775830] systemd[1]: Finished Load Kernel Modules.1413bob # [ 1.782096] systemd[1]: Starting Apply Kernel Variables...1414bob # [ 1.793318] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input21415bob # [ 1.793539] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01416bob # [ 1.792223] systemd[1]: Finished Create Static Device Nodes in /dev.1417bob # [ 1.793155] systemd[1]: Reached target Preparation for Local File Systems.1418bob # [ 1.793768] systemd[1]: Reached target Local File Systems.1419alice # [ 1.890486] systemd-udevd[80]: Using default interface naming scheme 'v261'.1420alice # [ 1.896631] systemd[1]: Finished Create System Files and Directories.1421bob # [ 1.813954] systemd[1]: Starting Create System Files and Directories...1422clare # [ 1.941762] systemd[1]: Starting Virtual Console Setup...1423bob # [ 1.828816] systemd[1]: Starting Rule-based Manager for Device Events and Files...1424alice # [ 1.916439] systemd[1]: Started Rule-based Manager for Device Events and Files.1425bob # [ 1.836933] systemd[1]: Finished Apply Kernel Variables.1426bob # [ 1.868511] systemd[1]: Finished Create System Files and Directories.1427clare # [ 2.000610] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.1428clare # [ 2.003284] systemd[1]: Finished Virtual Console Setup.1429bob # [ 1.895532] systemd-udevd[85]: Using default interface naming scheme 'v261'.1430alice # [ 1.982077] systemd[1]: Starting Virtual Console Setup...1431bob # [ 1.917598] systemd[1]: Started Rule-based Manager for Device Events and Files.1432alice # [ 2.032628] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.1433alice # [ 2.035500] systemd[1]: Finished Virtual Console Setup.1434bob # [ 1.985977] systemd[1]: Starting Virtual Console Setup...1435bob # [ 2.040518] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.1436bob # [ 2.043076] systemd[1]: Finished Virtual Console Setup.1437clare # [ 2.210578] systemd[1]: Finished Coldplug All udev Devices.1438clare # [ 2.211688] systemd[1]: Reached target System Initialization.1439clare # [ 2.216187] systemd[1]: Reached target Basic System.1440alice # [ 2.253140] systemd[1]: Finished Coldplug All udev Devices.1441alice # [ 2.253782] systemd[1]: Reached target System Initialization.1442alice # [ 2.254363] systemd[1]: Reached target Basic System.1443clare # [ 2.326323] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.1444clare # [ 2.353390] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.1445bob # [ 2.265488] systemd[1]: Finished Coldplug All udev Devices.1446bob # [ 2.266128] systemd[1]: Reached target System Initialization.1447bob # [ 2.266677] systemd[1]: Reached target Basic System.1448alice # [ 2.355039] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.1449alice # [ 2.393945] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.1450clare # [ 2.437565] systemd[1]: Found device /dev/disk/by-label/nixos.1451clare # [ 2.442083] systemd[1]: Reached target Initrd Root Device.1452clare # [ 2.443264] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1453clare # [ 2.481041] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks1454bob # [ 2.363834] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.1455alice # [ 2.465405] systemd[1]: Found device /dev/disk/by-label/nixos.1456alice # [ 2.469548] systemd[1]: Reached target Initrd Root Device.1457bob # [ 2.389718] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.1458alice # [ 2.472659] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1459alice # [ 2.516292] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks1460bob # [ 2.489916] systemd[1]: Found device /dev/disk/by-label/nixos.1461bob # [ 2.490718] systemd[1]: Reached target Initrd Root Device.1462bob # [ 2.496110] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1463bob # [ 2.543804] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks1464clare # [ 2.680296] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1465alice # [ 2.647007] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1466bob # [ 2.564918] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1467alice # [ 2.648173] systemd[1]: Mounting /sysroot...1468clare # [ 2.684182] systemd[1]: Mounting /sysroot...1469clare # [ 2.718758] EXT4-fs (vda): mounted filesystem 56dcb38e-425e-47d8-85ec-3d528b744938 r/w with ordered data mode. Quota mode: none.1470clare # [ 2.713175] systemd[1]: Mounted /sysroot.1471clare # [ 2.714816] systemd[1]: Reached target Initrd Root File System.1472alice # [ 2.687069] EXT4-fs (vda): mounted filesystem 8b4716f3-2b68-4ac2-a216-c4e011e1480a r/w with ordered data mode. Quota mode: none.1473alice # [ 2.683030] systemd[1]: Mounted /sysroot.1474clare # [ 2.719266] systemd[1]: Starting Mountpoints Configured in the Real Root...1475alice # [ 2.685075] systemd[1]: Reached target Initrd Root File System.1476alice # [ 2.688169] systemd[1]: Starting Mountpoints Configured in the Real Root...1477clare # [ 2.739092] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.1478clare # [ 2.745774] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...1479clare # [ 2.746941] systemd[1]: Reloading...1480bob # [ 2.630117] systemd[1]: Mounting /sysroot...1481alice # [ 2.715716] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.1482alice # [ 2.724133] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...1483alice # [ 2.725283] systemd[1]: Reloading...1484bob # [ 2.667074] EXT4-fs (vda): mounted filesystem eefe96ea-986a-4dfb-8199-7760d741e2ab r/w with ordered data mode. Quota mode: none.1485bob # [ 2.663320] systemd[1]: Mounted /sysroot.1486bob # [ 2.665266] systemd[1]: Reached target Initrd Root File System.1487bob # [ 2.669357] systemd[1]: Starting Mountpoints Configured in the Real Root...1488bob # [ 2.696440] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.1489bob # [ 2.703447] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...1490bob # [ 2.704702] systemd[1]: Reloading...1491alice # [ 2.922302] systemd[1]: Reloading finished in 198 ms.1492clare # [ 2.961532] systemd[1]: Reloading finished in 212 ms.1493alice # [ 2.940538] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1494alice # [ 2.943536] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1495alice # [ 2.946326] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1496clare # [ 2.980965] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1497alice # [ 2.947754] systemd[1]: Finished Mountpoints Configured in the Real Root.1498clare # [ 2.982434] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1499alice # [ 2.949221] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1500clare # [ 2.985462] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1501clare # [ 2.987122] systemd[1]: Finished Mountpoints Configured in the Real Root.1502clare # [ 2.988414] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1503bob # [ 2.911241] systemd[1]: Reloading finished in 204 ms.1504bob # [ 2.936128] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1505bob # [ 2.938562] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1506bob # [ 2.941223] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1507bob # [ 2.944620] systemd[1]: Finished Mountpoints Configured in the Real Root.1508bob # [ 2.946078] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1509clare # [ 3.257402] fuse: init (API version 7.45)1510clare # [ 3.304803] virtiofs virtio6: discovered new tag: nix-store1511clare # [ 3.305476] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1512clare # [ 3.308247] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1513clare # [ 3.314571] (udev-worker)[93]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1514clare # [ 3.338778] virtiofs virtio7: discovered new tag: shared1515clare # [ 3.335880] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1516clare # [ 3.337950] systemd[1]: Stopped Virtual Console Setup.1517clare # [ 3.338562] systemd[1]: Stopping Virtual Console Setup...1518clare # [ 3.350597] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1519clare # [ 3.341746] systemd[1]: Starting Virtual Console Setup...1520clare # [ 3.354699] virtiofs virtio8: discovered new tag: xchg1521clare # [ 3.361068] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1522alice # [ 3.340143] fuse: init (API version 7.45)1523clare # [ 3.377692] systemd-vconsole-setup[136]: Configuration of first virtual console was skipped, ignoring remaining ones.1524clare # [ 3.379659] systemd[1]: Finished Virtual Console Setup.1525alice # [ 3.355788] virtiofs virtio6: discovered new tag: nix-store1526alice # [ 3.356320] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1527alice # [ 3.366199] (udev-worker)[92]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1528alice # [ 3.370859] (udev-worker)[92]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1529alice # [ 3.402157] virtiofs virtio7: discovered new tag: shared1530alice # [ 3.402786] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1531bob # [ 3.321703] fuse: init (API version 7.45)1532alice # [ 3.414568] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1533alice # [ 3.415549] systemd[1]: Stopped Virtual Console Setup.1534alice # [ 3.416255] systemd[1]: Stopping Virtual Console Setup...1535alice # [ 3.417859] systemd[1]: Starting Virtual Console Setup...1536alice # [ 3.430381] virtiofs virtio8: discovered new tag: xchg1537alice # [ 3.430919] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1538alice # [ 3.434937] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1539alice # [ 3.436558] systemd[1]: Stopped Virtual Console Setup.1540alice # [ 3.441971] systemd[1]: Starting Virtual Console Setup...1541bob # [ 3.374821] virtiofs virtio6: discovered new tag: nix-store1542bob # [ 3.375379] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1543bob # [ 3.377169] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1544bob # [ 3.381166] (udev-worker)[97]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1545alice # [ 3.468660] systemd-vconsole-setup[139]: Configuration of first virtual console was skipped, ignoring remaining ones.1546bob # [ 3.394212] virtiofs virtio7: discovered new tag: shared1547alice # [ 3.471348] systemd[1]: Finished Virtual Console Setup.1548bob # [ 3.394755] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1549bob # [ 3.405420] virtiofs virtio8: discovered new tag: xchg1550bob # [ 3.405978] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1551bob # [ 3.407075] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1552bob # [ 3.409496] systemd[1]: Stopped Virtual Console Setup.1553bob # [ 3.410049] systemd[1]: Stopping Virtual Console Setup...1554bob # [ 3.412094] systemd[1]: Starting Virtual Console Setup...1555bob # [ 3.435646] systemd-vconsole-setup[136]: Configuration of first virtual console was skipped, ignoring remaining ones.1556bob # [ 3.438211] systemd[1]: Finished Virtual Console Setup.1557clare # [ 3.602380] systemd[1]: Mounting /sysroot/nix/.ro-store...1558clare # [ 3.620310] systemd[1]: Mounting /sysroot/nix/.rw-store...1559clare # [ 3.632240] systemd[1]: Mounting /sysroot/run...1560clare # [ 3.641175] systemd[1]: Mounting /sysroot/tmp/shared...1561clare # [ 3.664951] systemd[1]: Mounting /sysroot/tmp/xchg...1562clare # [ 3.669199] systemd[1]: Mounted /sysroot/nix/.rw-store.1563alice # [ 3.639838] systemd[1]: Mounting /sysroot/nix/.ro-store...1564clare # [ 3.686383] systemd[1]: Starting rw-sysroot-nix-store.service...1565alice # [ 3.653976] systemd[1]: Mounting /sysroot/nix/.rw-store...1566alice # [ 3.656134] systemd[1]: Mounting /sysroot/run...1567clare # [ 3.691765] systemd[1]: Mounted /sysroot/nix/.ro-store.1568clare # [ 3.692812] systemd[1]: Mounted /sysroot/run.1569clare # [ 3.693921] systemd[1]: Mounted /sysroot/tmp/shared.1570clare # [ 3.694876] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1571clare # [ 3.698198] systemd[1]: Finished rw-sysroot-nix-store.service.1572clare # [ 3.699846] systemd[1]: Mounted /sysroot/tmp/xchg.1573alice # [ 3.670171] systemd[1]: Mounting /sysroot/tmp/shared...1574clare # [ 3.707933] systemd[1]: Mounting /sysroot/nix/store...1575alice # [ 3.692844] systemd[1]: Mounting /sysroot/tmp/xchg...1576clare # [ 3.733815] systemd[1]: Mounted /sysroot/nix/store.1577clare # [ 3.734633] systemd[1]: Reached target Initrd File Systems.1578clare # [ 3.736199] systemd[1]: Starting Find NixOS closure...1579clare # [ 3.743588] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1580bob # [ 3.632587] systemd[1]: Mounting /sysroot/nix/.ro-store...1581bob # [ 3.640105] systemd[1]: Mounting /sysroot/nix/.rw-store...1582alice # [ 3.725892] systemd[1]: Mounted /sysroot/nix/.rw-store.1583bob # [ 3.645045] systemd[1]: Mounting /sysroot/run...1584clare # [ 3.765234] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1585alice # [ 3.735132] systemd[1]: Mounted /sysroot/nix/.ro-store.1586clare # [ 3.779578] systemd[1]: Finished Find NixOS closure.1587alice # [ 3.745361] systemd[1]: Mounted /sysroot/run.1588clare # [ 3.780240] systemd[1]: Reached target Initrd Default Target.1589bob # [ 3.663078] systemd[1]: Mounting /sysroot/tmp/shared...1590alice # [ 3.746340] systemd[1]: Mounted /sysroot/tmp/shared.1591bob # [ 3.665217] systemd[1]: Mounting /sysroot/tmp/xchg...1592clare # [ 3.784541] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1593alice # [ 3.753199] systemd[1]: Starting rw-sysroot-nix-store.service...1594alice # [ 3.755285] systemd[1]: Mounted /sysroot/tmp/xchg.1595bob # [ 3.673043] systemd[1]: Mounted /sysroot/nix/.ro-store.1596bob # [ 3.675649] systemd[1]: Mounted /sysroot/nix/.rw-store.1597clare # [ 3.798703] systemd[1]: Stopped target Initrd Default Target.1598clare # [ 3.799927] systemd[1]: Stopped target Basic System.1599clare # [ 3.800906] systemd[1]: Stopped target Initrd Root Device.1600clare # [ 3.802357] systemd[1]: Stopped target Path Units.1601clare # [ 3.803046] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1602clare # [ 3.804721] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1603clare # [ 3.807044] systemd[1]: Stopped target Slice Units.1604clare # [ 3.807583] systemd[1]: Stopped target Socket Units.1605clare # [ 3.808583] systemd[1]: Stopped target System Initialization.1606alice # [ 3.775861] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1607alice # [ 3.777166] systemd[1]: Finished rw-sysroot-nix-store.service.1608clare # [ 3.812160] systemd[1]: Stopped target Swaps.1609clare # [ 3.812647] systemd[1]: Stopped target Timer Units.1610clare # [ 3.813253] systemd[1]: dbus.socket: Deactivated successfully.1611clare # [ 3.813739] systemd[1]: Closed D-Bus System Message Bus Socket.1612clare # [ 3.814278] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1613clare # [ 3.814970] systemd[1]: Stopped Find NixOS closure.1614clare # [ 3.815438] systemd[1]: Starting rw-sysroot-nix-store.service...1615bob # [ 3.699562] systemd[1]: Starting rw-sysroot-nix-store.service...1616clare # [ 3.815987] systemd[1]: systemd-sysctl.service: Deactivated successfully.1617bob # [ 3.702900] systemd[1]: Mounted /sysroot/run.1618bob # [ 3.704163] systemd[1]: Mounted /sysroot/tmp/shared.1619clare # [ 3.821434] systemd[1]: Stopped Apply Kernel Variables.1620clare # [ 3.823174] systemd[1]: systemd-modules-load.service: Deactivated successfully.1621bob # [ 3.706925] systemd[1]: Mounted /sysroot/tmp/xchg.1622clare # [ 3.826057] systemd[1]: Stopped Load Kernel Modules.1623clare # [ 3.826943] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1624clare # [ 3.830579] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1625bob # [ 3.719212] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1626bob # [ 3.720557] systemd[1]: Finished rw-sysroot-nix-store.service.1627clare # [ 3.838155] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1628clare # [ 3.839875] systemd[1]: Stopped Create System Files and Directories.1629clare # [ 3.840808] systemd[1]: Stopped target Local File Systems.1630clare # [ 3.841335] systemd[1]: Stopped target Preparation for Local File Systems.1631clare # [ 3.841943] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1632clare # [ 3.842595] systemd[1]: Stopped Coldplug All udev Devices.1633clare # [ 3.843111] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1634clare # [ 3.843785] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1635clare # [ 3.849239] systemd[1]: Stopped Virtual Console Setup.1636clare # [ 3.850519] systemd[1]: initrd-cleanup.service: Deactivated successfully.1637clare # [ 3.852146] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1638clare # [ 3.853067] systemd[1]: systemd-udevd.service: Deactivated successfully.1639clare # [ 3.856451] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1640clare # [ 3.857139] systemd[1]: systemd-udevd.service: Consumed 1.049s CPU time over 2.086s wall clock time, 24.5M memory peak.1641clare # [ 3.858077] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1642clare # [ 3.858996] systemd[1]: Closed udev Control Socket.1643clare # [ 3.859556] systemd[1]: Starting Cleanup udev Database...1644clare # [ 3.860261] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1645clare # [ 3.860977] systemd[1]: Stopped Create Static Device Nodes in /dev.1646clare # [ 3.861559] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1647clare # [ 3.862299] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1648clare # [ 3.862958] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1649clare # [ 3.863589] systemd[1]: Stopped Create List of Static Device Nodes.1650clare # [ 3.867398] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1651clare # [ 3.872193] systemd[1]: Finished rw-sysroot-nix-store.service.1652clare # [ 3.884756] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1653clare # [ 3.885576] systemd[1]: Finished Cleanup udev Database.1654clare # [ 3.886094] systemd[1]: Reached target Switch Root.1655clare # [ 3.888983] systemd[1]: Starting NixOS Activation...1656clare # [ 3.988167] initrd-nixos-activation-start[171]: booting system configuration /nix/store/j4fzgfjgricvyd8zav51kvpldd2mwf76-nixos-system-clare-test1657clare # [ 4.022688] initrd-nixos-activation-start[171]: running activation script...1658clare # [ 4.333523] initrd-nixos-activation-start[194]: setting up /etc...1659clare # [ 4.435157] initrd-nixos-activation-start[194]: setting up age secrets...1660clare # [ 4.496915] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1661clare # [ 4.498854] systemd[1]: Finished NixOS Activation.1662clare # [ 4.499677] systemd[1]: Starting Switch Root...1663clare # [ 4.512920] systemd[1]: Switching root.1664clare # [ 4.681248] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1665alice # [ 4.637345] systemd[1]: Mounting /sysroot/nix/store...1666alice # [ 4.691202] systemd[1]: Mounted /sysroot/nix/store.1667alice # [ 4.693430] systemd[1]: Reached target Initrd File Systems.1668alice # [ 4.697393] systemd[1]: Starting Find NixOS closure...1669alice # [ 4.708381] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1670bob # [ 4.630707] systemd[1]: Mounting /sysroot/nix/store...1671bob # [ 4.661556] systemd[1]: Mounted /sysroot/nix/store.1672bob # [ 4.662322] systemd[1]: Reached target Initrd File Systems.1673bob # [ 4.664913] systemd[1]: Starting Find NixOS closure...1674bob # [ 4.672152] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1675alice # [ 4.754681] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1676alice # [ 4.756411] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1677alice # [ 4.772875] systemd[1]: Finished Find NixOS closure.1678alice # [ 4.776282] systemd[1]: Reached target Initrd Default Target.1679bob # [ 4.695516] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1680alice # [ 4.778931] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1681bob # [ 4.697370] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1682bob # [ 4.709116] systemd[1]: Finished Find NixOS closure.1683bob # [ 4.710268] systemd[1]: Reached target Initrd Default Target.1684bob # [ 4.712347] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1685alice # [ 4.810963] systemd[1]: initrd-cleanup.service: Deactivated successfully.1686alice # [ 4.812611] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1687alice # [ 4.816160] systemd[1]: Stopped target Initrd Default Target.1688alice # [ 4.817066] systemd[1]: Stopped target Basic System.1689alice # [ 4.817773] systemd[1]: Stopped target Initrd Root Device.1690alice # [ 4.820269] systemd[1]: Stopped target Path Units.1691bob # [ 4.737920] systemd[1]: initrd-cleanup.service: Deactivated successfully.1692alice # [ 4.820983] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1693bob # [ 4.738969] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1694bob # [ 4.741415] systemd[1]: Stopped target Initrd Default Target.1695alice # [ 4.823797] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1696bob # [ 4.744200] systemd[1]: Stopped target Basic System.1697bob # [ 4.744822] systemd[1]: Stopped target Initrd Root Device.1698alice # [ 4.827174] systemd[1]: Stopped target Slice Units.1699bob # [ 4.745342] systemd[1]: Stopped target Path Units.1700bob # [ 4.745795] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1701alice # [ 4.829605] systemd[1]: Stopped target Socket Units.1702bob # [ 4.747433] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1703alice # [ 4.832578] systemd[1]: Stopped target System Initialization.1704bob # [ 4.750877] systemd[1]: Stopped target Slice Units.1705bob # [ 4.752683] systemd[1]: Stopped target Socket Units.1706alice # [ 4.835402] systemd[1]: Stopped target Swaps.1707bob # [ 4.756781] systemd[1]: Stopped target System Initialization.1708alice # [ 4.840768] systemd[1]: Stopped target Timer Units.1709bob # [ 4.758484] systemd[1]: Stopped target Swaps.1710bob # [ 4.758981] systemd[1]: Stopped target Timer Units.1711alice # [ 4.841404] systemd[1]: dbus.socket: Deactivated successfully.1712alice # [ 4.842179] systemd[1]: Closed D-Bus System Message Bus Socket.1713alice # [ 4.842770] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1714alice # [ 4.843522] systemd[1]: Stopped Find NixOS closure.1715bob # [ 4.761955] systemd[1]: dbus.socket: Deactivated successfully.1716bob # [ 4.762554] systemd[1]: Closed D-Bus System Message Bus Socket.1717bob # [ 4.763829] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1718bob # [ 4.765392] systemd[1]: Stopped Find NixOS closure.1719alice # [ 4.847991] systemd[1]: Starting rw-sysroot-nix-store.service...1720bob # [ 4.765961] systemd[1]: Starting rw-sysroot-nix-store.service...1721alice # [ 4.848945] systemd[1]: systemd-sysctl.service: Deactivated successfully.1722bob # [ 4.766502] systemd[1]: systemd-sysctl.service: Deactivated successfully.1723bob # [ 4.767085] systemd[1]: Stopped Apply Kernel Variables.1724alice # [ 4.849617] systemd[1]: Stopped Apply Kernel Variables.1725bob # [ 4.767572] systemd[1]: systemd-modules-load.service: Deactivated successfully.1726alice # [ 4.850415] systemd[1]: systemd-modules-load.service: Deactivated successfully.1727alice # [ 4.851098] systemd[1]: Stopped Load Kernel Modules.1728alice # [ 4.851581] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1729alice # [ 4.855160] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1730alice # [ 4.855968] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1731bob # [ 4.775492] systemd[1]: Stopped Load Kernel Modules.1732bob # [ 4.776332] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1733alice # [ 4.862071] systemd[1]: Stopped Create System Files and Directories.1734bob # [ 4.779340] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1735bob # [ 4.783743] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1736bob # [ 4.785025] systemd[1]: Stopped Create System Files and Directories.1737bob # [ 4.785628] systemd[1]: Stopped target Local File Systems.1738alice # [ 4.868219] systemd[1]: Stopped target Local File Systems.1739bob # [ 4.786137] systemd[1]: Stopped target Preparation for Local File Systems.1740alice # [ 4.868972] systemd[1]: Stopped target Preparation for Local File Systems.1741bob # [ 4.786723] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1742bob # [ 4.787350] systemd[1]: Stopped Coldplug All udev Devices.1743bob # [ 4.787809] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1744alice # [ 4.871508] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1745alice # [ 4.872827] systemd[1]: Stopped Coldplug All udev Devices.1746bob # [ 4.790279] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1747bob # [ 4.790983] systemd[1]: Stopped Virtual Console Setup.1748alice # [ 4.873649] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1749bob # [ 4.791482] systemd[1]: systemd-udevd.service: Deactivated successfully.1750alice # [ 4.874389] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1751alice # [ 4.875618] systemd[1]: Stopped Virtual Console Setup.1752alice # [ 4.876226] systemd[1]: systemd-udevd.service: Deactivated successfully.1753alice # [ 4.876848] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1754alice # [ 4.877533] systemd[1]: systemd-udevd.service: Consumed 1.082s CPU time over 3.058s wall clock time, 22.5M memory peak.1755bob # [ 4.795478] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1756alice # [ 4.878480] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1757alice # [ 4.879162] systemd[1]: Closed udev Control Socket.1758bob # [ 4.796315] systemd[1]: systemd-udevd.service: Consumed 1.051s CPU time over 2.945s wall clock time, 24.2M memory peak.1759alice # [ 4.879619] systemd[1]: Starting Cleanup udev Database...1760bob # [ 4.800529] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1761bob # [ 4.801432] systemd[1]: Closed udev Control Socket.1762bob # [ 4.801927] systemd[1]: Starting Cleanup udev Database...1763bob # [ 4.802434] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1764alice # [ 4.885414] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1765bob # [ 4.803111] systemd[1]: Stopped Create Static Device Nodes in /dev.1766alice # [ 4.886457] systemd[1]: Stopped Create Static Device Nodes in /dev.1767alice # [ 4.888409] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1768bob # [ 4.804435] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1769alice # [ 4.892269] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1770alice # [ 4.893000] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1771alice # [ 4.893612] systemd[1]: Stopped Create List of Static Device Nodes.1772alice # [ 4.894217] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1773bob # [ 4.812261] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1774bob # [ 4.812936] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1775bob # [ 4.813580] systemd[1]: Stopped Create List of Static Device Nodes.1776alice # [ 4.896331] systemd[1]: Finished rw-sysroot-nix-store.service.1777bob # [ 4.814173] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1778bob # [ 4.814786] systemd[1]: Finished rw-sysroot-nix-store.service.1779bob # [ 4.824213] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1780alice # [ 4.907124] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1781bob # [ 4.826482] systemd[1]: Finished Cleanup udev Database.1782bob # [ 4.828128] systemd[1]: Reached target Switch Root.1783alice # [ 4.911105] systemd[1]: Finished Cleanup udev Database.1784bob # [ 4.828646] systemd[1]: Starting NixOS Activation...1785alice # [ 4.911713] systemd[1]: Reached target Switch Root.1786alice # [ 4.913019] systemd[1]: Starting NixOS Activation...1787bob # [ 4.899224] initrd-nixos-activation-start[172]: booting system configuration /nix/store/g481r9hzfzlyykghr4xgmgb8b9rc5pbb-nixos-system-bob-test1788alice # [ 4.997377] initrd-nixos-activation-start[174]: booting system configuration /nix/store/z9f82k8cch6hhsyq4na4bfcwhpbkripm-nixos-system-alice-test1789bob # [ 4.925617] initrd-nixos-activation-start[172]: running activation script...1790alice # [ 5.025493] initrd-nixos-activation-start[174]: running activation script...1791bob # [ 5.205821] initrd-nixos-activation-start[195]: setting up /etc...1792alice # [ 5.292791] initrd-nixos-activation-start[197]: setting up /etc...1793bob # [ 5.300092] initrd-nixos-activation-start[195]: setting up age secrets...1794alice # [ 5.388386] initrd-nixos-activation-start[197]: setting up age secrets...1795bob # [ 5.375686] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1796bob # [ 5.377823] systemd[1]: Finished NixOS Activation.1797alice # [ 5.460256] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1798bob # [ 5.378678] systemd[1]: Starting Switch Root...1799alice # [ 5.462334] systemd[1]: Finished NixOS Activation.1800alice # [ 5.463162] systemd[1]: Starting Switch Root...1801bob # [ 5.394765] systemd[1]: Switching root.1802alice # [ 5.488381] systemd[1]: Switching root.1803bob # [ 5.570087] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1804alice # [ 5.666106] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1805bob # [ 11.239511] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1806bob # [ 11.239562] systemd[1]: Detected virtualization qemu.1807bob # [ 11.243582] systemd[1]: Detected architecture arm64.1808bob # [ 11.244171] systemd[1]: Detected first boot.1809bob # [ 11.246145] systemd[1]: Initializing machine ID from random generator.1810alice # [ 11.333850] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1811alice # [ 11.333897] systemd[1]: Detected virtualization qemu.1812alice # [ 11.333943] systemd[1]: Detected architecture arm64.1813alice # [ 11.334029] systemd[1]: Detected first boot.1814clare # [ 11.371354] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1815clare # [ 11.371404] systemd[1]: Detected virtualization qemu.1816clare # [ 11.371451] systemd[1]: Detected architecture arm64.1817clare # [ 11.371550] systemd[1]: Detected first boot.1818clare # [ 11.373772] systemd[1]: Initializing machine ID from random generator.1819alice # [ 11.346405] systemd[1]: Initializing machine ID from random generator.1820clare # [ 11.474826] systemd[1]: bpf-restrict-fs: LSM BPF program attached1821alice # [ 11.440077] systemd[1]: bpf-restrict-fs: LSM BPF program attached1822bob # [ 11.361853] systemd[1]: bpf-restrict-fs: LSM BPF program attached1823bob # [ 11.472884] systemd[1]: Applying preset policy.1824clare # [ 11.614510] systemd[1]: Applying preset policy.1825alice # [ 11.592149] systemd[1]: Applying preset policy.1826clare # [ 11.765428] systemd[1]: Populated /etc with preset unit settings.1827bob # [ 11.652903] systemd[1]: Populated /etc with preset unit settings.1828alice # [ 11.760930] systemd[1]: Populated /etc with preset unit settings.1829clare # [ 11.998414] systemd[1]: initrd-switch-root.service: Deactivated successfully.1830clare # [ 12.000263] systemd[1]: Stopped initrd-switch-root.service.1831clare # [ 12.001903] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1832clare # [ 12.006084] systemd[1]: Created slice Slice /system/getty.1833clare # [ 12.006845] systemd[1]: Created slice User and Session Slice.1834alice # [ 11.971907] systemd[1]: initrd-switch-root.service: Deactivated successfully.1835alice # [ 11.972721] systemd[1]: Stopped initrd-switch-root.service.1836alice # [ 11.975147] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1837bob # [ 11.892250] systemd[1]: initrd-switch-root.service: Deactivated successfully.1838bob # [ 11.892700] systemd[1]: Stopped initrd-switch-root.service.1839clare # [ 12.008620] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1840bob # [ 11.895085] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1841clare # [ 12.009204] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1842clare # [ 12.009671] systemd[1]: Expecting device /dev/hvc0...1843clare # [ 12.010013] systemd[1]: Expecting device /dev/ttyAMA0...1844clare # [ 12.010327] systemd[1]: Reached target Local Encrypted Volumes.1845clare # [ 12.010635] systemd[1]: Stopped target initrd-fs.target.1846clare # [ 12.010931] systemd[1]: Stopped target initrd-root-fs.target.1847bob # [ 11.899070] systemd[1]: Created slice Slice /system/getty.1848alice # [ 11.980008] systemd[1]: Created slice Slice /system/getty.1849alice # [ 11.981502] systemd[1]: Created slice User and Session Slice.1850alice # [ 11.982209] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1851alice # [ 11.982658] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1852alice # [ 11.983050] systemd[1]: Expecting device /dev/hvc0...1853bob # [ 11.902080] systemd[1]: Created slice User and Session Slice.1854alice # [ 11.983342] systemd[1]: Expecting device /dev/ttyAMA0...1855clare # [ 12.021361] systemd[1]: Stopped target initrd-switch-root.target.1856bob # [ 11.902915] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1857clare # [ 12.022026] systemd[1]: Reached target Virtual Machines and Containers.1858clare # [ 12.022466] systemd[1]: Reached target Path Units.1859clare # [ 12.022793] systemd[1]: Reached target Remote File Systems.1860bob # [ 11.905673] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1861bob # [ 11.906423] systemd[1]: Expecting device /dev/hvc0...1862bob # [ 11.907243] systemd[1]: Expecting device /dev/ttyAMA0...1863alice # [ 11.988143] systemd[1]: Reached target Local Encrypted Volumes.1864alice # [ 11.988892] systemd[1]: Stopped target initrd-fs.target.1865alice # [ 11.989842] systemd[1]: Stopped target initrd-root-fs.target.1866clare # [ 12.028874] systemd[1]: Reached target Slice Units.1867clare # [ 12.029500] systemd[1]: Reached target Swaps.1868alice # [ 11.990631] systemd[1]: Stopped target initrd-switch-root.target.1869alice # [ 11.991409] systemd[1]: Reached target Virtual Machines and Containers.1870bob # [ 11.910456] systemd[1]: Reached target Local Encrypted Volumes.1871bob # [ 11.911201] systemd[1]: Stopped target initrd-fs.target.1872bob # [ 11.913689] systemd[1]: Stopped target initrd-root-fs.target.1873bob # [ 11.914377] systemd[1]: Stopped target initrd-switch-root.target.1874alice # [ 11.995674] systemd[1]: Reached target Path Units.1875bob # [ 11.915158] systemd[1]: Reached target Virtual Machines and Containers.1876alice # [ 11.996457] systemd[1]: Reached target Remote File Systems.1877alice # [ 11.997345] systemd[1]: Reached target Slice Units.1878alice # [ 11.998127] systemd[1]: Reached target Swaps.1879bob # [ 11.918119] systemd[1]: Reached target Path Units.1880bob # [ 11.918752] systemd[1]: Reached target Remote File Systems.1881bob # [ 11.919435] systemd[1]: Reached target Slice Units.1882clare # [ 12.042631] systemd[1]: Listening on Query the User Interactively for a Password.1883bob # [ 11.922294] systemd[1]: Reached target Swaps.1884alice # [ 12.003937] systemd[1]: Listening on Query the User Interactively for a Password.1885alice # [ 12.007284] systemd[1]: Listening on Process Core Dump Socket.1886clare # [ 12.046499] systemd[1]: Listening on Process Core Dump Socket.1887bob # [ 11.925749] systemd[1]: Listening on Query the User Interactively for a Password.1888alice # [ 12.011141] systemd[1]: Listening on Credential Encryption/Decryption.1889clare # [ 12.049898] systemd[1]: Listening on Credential Encryption/Decryption.1890bob # [ 11.930971] systemd[1]: Listening on Process Core Dump Socket.1891alice # [ 12.014311] systemd[1]: Listening on Factory Reset Management.1892alice # [ 12.015365] systemd[1]: Listening on Hostname Service Socket.1893clare # [ 12.053393] systemd[1]: Listening on Factory Reset Management.1894clare # [ 12.054299] systemd[1]: Listening on Hostname Service Socket.1895bob # [ 11.934558] systemd[1]: Listening on Credential Encryption/Decryption.1896bob # [ 11.938226] systemd[1]: Listening on Factory Reset Management.1897bob # [ 11.939188] systemd[1]: Listening on Hostname Service Socket.1898alice # [ 12.021299] systemd[1]: Starting Journal Log Access Socket...1899clare # [ 12.060743] systemd[1]: Starting Journal Log Access Socket...1900clare # [ 12.062816] systemd[1]: Listening on Journal Audit Socket.1901alice # [ 12.024311] systemd[1]: Listening on Journal Audit Socket.1902bob # [ 11.944437] systemd[1]: Starting Journal Log Access Socket...1903bob # [ 11.946615] systemd[1]: Listening on Journal Audit Socket.1904alice # [ 12.029320] systemd[1]: Listening on Console Output Muting Service Socket.1905alice # [ 12.031542] systemd[1]: Listening on Network Management Metrics Varlink Socket.1906clare # [ 12.069619] systemd[1]: Listening on Console Output Muting Service Socket.1907bob # [ 11.951522] systemd[1]: Listening on Console Output Muting Service Socket.1908alice # [ 12.035211] systemd[1]: Listening on Network Management Varlink Socket.1909clare # [ 12.073469] systemd[1]: Listening on Network Management Metrics Varlink Socket.1910clare # [ 12.074538] systemd[1]: Listening on Network Management Varlink Socket.1911bob # [ 11.953955] systemd[1]: Listening on Network Management Metrics Varlink Socket.1912alice # [ 12.037957] systemd[1]: Listening on Network Management Netlink Socket.1913alice # [ 12.038995] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1914clare # [ 12.077696] systemd[1]: Listening on Network Management Netlink Socket.1915clare # [ 12.078924] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1916bob # [ 11.957464] systemd[1]: Listening on Network Management Varlink Socket.1917bob # [ 11.958488] systemd[1]: Listening on Network Management Netlink Socket.1918bob # [ 11.959572] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1919alice # [ 12.042127] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1920alice # [ 12.042773] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1921clare # [ 12.081450] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1922bob # [ 11.962901] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1923clare # [ 12.082008] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1924bob # [ 11.963523] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1925bob # [ 11.969867] systemd[1]: Listening on Disk Repartitioning Service Socket.1926bob # [ 11.970694] systemd[1]: Listening on Resolve Monitor Varlink Socket.1927bob # [ 11.971397] systemd[1]: Listening on Resolve Service Varlink Socket.1928alice # [ 12.054374] systemd[1]: Listening on Disk Repartitioning Service Socket.1929alice # [ 12.055221] systemd[1]: Listening on Resolve Monitor Varlink Socket.1930bob # [ 11.974021] systemd[1]: Listening on udev Control Socket.1931bob # [ 11.974812] systemd[1]: Listening on udev Varlink Socket.1932alice # [ 12.057428] systemd[1]: Listening on Resolve Service Varlink Socket.1933alice # [ 12.059163] systemd[1]: Listening on udev Control Socket.1934clare # [ 12.095076] systemd[1]: Listening on Disk Repartitioning Service Socket.1935clare # [ 12.095909] systemd[1]: Listening on Resolve Monitor Varlink Socket.1936clare # [ 12.096494] systemd[1]: Listening on Resolve Service Varlink Socket.1937clare # [ 12.096928] systemd[1]: Listening on udev Control Socket.1938clare # [ 12.097338] systemd[1]: Listening on udev Varlink Socket.1939alice # [ 12.061254] systemd[1]: Listening on udev Varlink Socket.1940bob # [ 11.983298] systemd[1]: Mounting Huge Pages File System...1941alice # [ 12.066735] systemd[1]: Mounting Huge Pages File System...1942clare # [ 12.109236] systemd[1]: Mounting Huge Pages File System...1943alice # [ 12.072156] systemd[1]: Mounting POSIX Message Queue File System...1944clare # [ 12.113912] systemd[1]: Mounting POSIX Message Queue File System...1945bob # [ 11.993133] systemd[1]: Mounting POSIX Message Queue File System...1946alice # [ 12.084004] systemd[1]: Mounting Kernel Debug File System...1947bob # [ 12.007430] systemd[1]: Mounting Kernel Debug File System...1948clare # [ 12.131331] systemd[1]: Mounting Kernel Debug File System...1949alice # [ 12.101055] systemd[1]: Mounting Kernel Trace File System...1950clare # [ 12.143081] systemd[1]: Mounting Kernel Trace File System...1951bob # [ 12.024073] systemd[1]: Mounting Kernel Trace File System...1952alice # [ 12.111014] systemd[1]: Starting Create List of Static Device Nodes...1953alice # [ 12.114829] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1954clare # [ 12.153619] systemd[1]: Starting Create List of Static Device Nodes...1955clare # [ 12.161154] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1956bob # [ 12.044005] systemd[1]: Starting Create List of Static Device Nodes...1957bob # [ 12.049269] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1958alice # [ 12.131848] systemd[1]: Mounting Kernel Configuration File System...1959alice # [ 12.138662] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1960clare # [ 12.175744] systemd[1]: Mounting Kernel Configuration File System...1961clare # [ 12.176253] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1962alice # [ 12.139313] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1963clare # [ 12.176592] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1964clare # [ 12.176918] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1965alice # [ 12.144717] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1966bob # [ 12.064305] systemd[1]: Mounting Kernel Configuration File System...1967bob # [ 12.072831] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1968bob # [ 12.073477] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1969bob # [ 12.089992] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1970clare # [ 12.214181] systemd[1]: Mounting FUSE Control File System...1971alice # [ 12.175908] systemd[1]: Mounting FUSE Control File System...1972alice # [ 12.176642] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671973clare # [ 12.216266] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671974bob # [ 12.107751] systemd[1]: Mounting FUSE Control File System...1975bob # [ 12.109418] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671976bob # [ 12.120966] systemd[1]: Starting Journal Service...1977alice # [ 12.207129] systemd[1]: Starting Journal Service...1978clare # [ 12.247671] systemd[1]: Starting Journal Service...1979bob # [ 12.135255] systemd[1]: Starting Load Kernel Modules...1980clare # [ 12.260288] systemd[1]: Starting Load Kernel Modules...1981alice # [ 12.224715] systemd[1]: Starting Load Kernel Modules...1982bob # [ 12.149448] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1983clare # [ 12.281226] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1984clare # [ 12.287558] systemd[1]: Starting Remount Root and Kernel File Systems...1985clare # [ 12.288047] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1986alice # [ 12.252502] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1987clare # [ 12.299809] systemd[1]: Starting Coldplug All udev Devices...1988bob # [ 12.186471] systemd[1]: Starting Remount Root and Kernel File Systems...1989bob # [ 12.186997] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1990clare # [ 12.310525] systemd[1]: Listening on Journal Log Access Socket.1991alice # [ 12.273352] systemd[1]: Starting Remount Root and Kernel File Systems...1992alice # [ 12.273954] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1993alice # [ 12.280047] systemd-journald[282]: Collecting audit messages is enabled.1994bob # [ 12.203545] systemd-journald[288]: Collecting audit messages is enabled.1995alice # [ 12.281488] systemd[1]: Queued start job for default target Multi-User System.1996alice # [ 12.283668] systemd[1]: systemd-journald.service: Deactivated successfully.1997alice # [ 12.293083] systemd[1]: Starting Coldplug All udev Devices...1998alice # [ 12.295480] systemd[1]: Started Journal Service.1999bob # [ 12.212588] systemd[1]: Queued start job for default target Multi-User System.2000bob # [ 12.213553] systemd[1]: systemd-journald.service: Deactivated successfully.2001clare # [ 12.344516] systemd[1]: Mounted Huge Pages File System.2002clare # [ 12.345178] systemd[1]: Mounted POSIX Message Queue File System.2003clare # [ 12.345696] systemd[1]: Mounted Kernel Debug File System.2004clare # [ 12.346135] systemd[1]: Mounted Kernel Trace File System.2005clare # [ 12.355674] systemd-journald[279]: Collecting audit messages is enabled.2006bob # [ 12.236017] systemd[1]: Starting Coldplug All udev Devices...2007bob # [ 12.238273] systemd[1]: Started Journal Service.2008alice # [ 12.317982] systemd[1]: Listening on Journal Log Access Socket.2009clare # [ 12.363678] systemd[1]: Finished Create List of Static Device Nodes.2010clare # [ 12.361661] systemd[1]: Queued start job for default target Multi-User System.2011bob # [ 12.244747] systemd[1]: Listening on Journal Log Access Socket.2012alice # [ 12.330613] systemd[1]: Mounted Huge Pages File System.2013bob # [ 12.248548] systemd[1]: Mounted Huge Pages File System.2014alice # [ 12.331262] systemd[1]: Mounted POSIX Message Queue File System.2015alice # [ 12.331843] systemd[1]: Mounted Kernel Debug File System.2016clare # [ 12.367428] systemd[1]: systemd-journald.service: Deactivated successfully.[ 12.375452] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2017clare # [ 12.376630] systemd[1]: Started Journal Service.2018clare # 2019clare # [ 12.371845] systemd-modules-load[280]: Module 'atkbd' is built in2020alice # [ 12.338106] systemd[1]: Mounted Kernel Trace File System.2021alice # [ 12.338705] systemd[1]: Finished Create List of Static Device Nodes.2022alice # [ 12.347110] systemd-modules-load[283]: Module 'atkbd' is built in2023bob # [ 12.265934] systemd[1]: Mounted POSIX Message Queue File System.2024bob # [ 12.267077] systemd[1]: Mounted Kernel Debug File System.2025clare # [ 12.384770] systemd-modules-load[280]: Module 'loop' is built in2026alice # [ 12.351530] systemd-modules-load[283]: Module 'loop' is built in2027bob # [ 12.280227] systemd[1]: Mounted Kernel Trace File System.2028bob # [ 12.282114] systemd[1]: Finished Create List of Static Device Nodes.2029clare # [ 12.401533] systemd[1]: Mounted Kernel Configuration File System.2030clare # [ 12.403329] systemd[1]: Mounted FUSE Control File System.2031bob # [ 12.290103] systemd-modules-load[289]: Module 'atkbd' is built in2032alice # [ 12.374321] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2033clare # [ 12.410416] systemd[1]: Finished Load Kernel Modules.2034bob # [ 12.297898] systemd-modules-load[289]: Module 'loop' is built in2035alice # [ 12.386189] systemd[1]: Finished Load Kernel Modules.2036bob # [ 12.305181] systemd[1]: Finished Load Kernel Modules.2037alice # [ 12.395936] EXT4-fs (vda): re-mounted 8b4716f3-2b68-4ac2-a216-c4e011e1480a.2038alice # [ 12.396150] systemd[1]: Starting Firewall...2039bob # [ 12.325813] systemd[1]: Starting Firewall...2040bob # [ 12.334051] EXT4-fs (vda): re-mounted eefe96ea-986a-4dfb-8199-7760d741e2ab.2041alice # [ 12.411889] systemd-oomd[285]: No swap; memory pressure usage will be degraded2042clare # [ 12.456239] systemd[1]: Starting Firewall...2043bob # [ 12.340974] systemd[1]: Starting Apply Kernel Variables...2044alice # [ 12.424481] systemd[1]: Starting Apply Kernel Variables...2045clare # [ 12.462789] systemd[1]: Starting Apply Kernel Variables...2046alice # [ 12.428238] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2047alice # [ 12.430027] systemd[1]: Mounted Kernel Configuration File System.2048alice # [ 12.433421] systemd[1]: Finished Remount Root and Kernel File Systems.2049clare # [ 12.475107] EXT4-fs (vda): re-mounted 56dcb38e-425e-47d8-85ec-3d528b744938.2050bob # [ 12.353524] systemd-oomd[290]: No swap; memory pressure usage will be degraded2051alice # [ 12.438374] systemd[1]: Listening on Disk Image Download Service Socket.2052clare # [ 12.480763] systemd[1]: Finished Remount Root and Kernel File Systems.2053alice # [ 12.446850] systemd[1]: Starting Flush Journal to Persistent Storage...2054alice # [ 12.447523] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2055bob # [ 12.371513] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2056bob # [ 12.372590] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2057bob # [ 12.375868] systemd[1]: Finished Remount Root and Kernel File Systems.2058clare # [ 12.496513] systemd-oomd[281]: No swap; memory pressure usage will be degraded2059bob # [ 12.381610] systemd[1]: Listening on Disk Image Download Service Socket.2060alice # [ 12.467867] systemd[1]: Starting Load/Save OS Random Seed...2061clare # [ 12.504457] systemd[1]: Listening on Disk Image Download Service Socket.2062alice # [ 12.473326] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2063bob # [ 12.393546] systemd[1]: Starting Flush Journal to Persistent Storage...2064bob # [ 12.394337] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2065clare # [ 12.518849] systemd[1]: Starting Flush Journal to Persistent Storage...2066clare # [ 12.519573] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2067bob # [ 12.411138] systemd[1]: Starting Load/Save OS Random Seed...2068bob # [ 12.411864] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2069clare # [ 12.532506] systemd[1]: Starting Load/Save OS Random Seed...2070clare # [ 12.533127] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2071clare # [ 12.534031] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2072bob # [ 12.421052] systemd[1]: Mounted Kernel Configuration File System.2073alice # [ 12.514200] systemd[1]: Mounted FUSE Control File System.2074clare # [ 12.584312] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2075clare # [ 12.596306] systemd[1]: Starting Create Static Device Nodes in /dev...2076alice # [ 12.568009] systemd-journald[282]: Received client request to flush runtime journal.2077bob # [ 12.482569] systemd[1]: Mounted FUSE Control File System.2078bob # [ 12.504341] systemd[1]: Finished Apply Kernel Variables.2079clare # [ 12.632622] systemd-journald[279]: Received client request to flush runtime journal.2080bob # [ 12.516361] systemd[1]: Starting Network Name Resolution...2081bob # [ 12.546719] systemd-journald[288]: Received client request to flush runtime journal.2082alice # [ 12.639331] systemd[1]: Finished Load/Save OS Random Seed.2083alice # [ 12.639977] systemd[1]: Reached target First Boot Complete.2084alice # [ 12.645322] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2085alice # [ 12.646174] systemd[1]: Starting Create Static Device Nodes in /dev...2086clare # [ 12.689492] systemd[1]: Finished Apply Kernel Variables.2087alice # [ 12.656763] systemd[1]: Finished Apply Kernel Variables.2088alice # [ 12.659588] systemd[1]: Starting Network Name Resolution...2089clare # [ 12.695003] systemd[1]: Finished Load/Save OS Random Seed.2090clare # [ 12.695643] systemd[1]: Reached target First Boot Complete.2091alice # [ 12.664707] systemd[1]: Finished Flush Journal to Persistent Storage.2092clare # [ 12.702160] systemd[1]: Starting Network Name Resolution...2093clare # [ 12.702768] systemd[1]: Finished Flush Journal to Persistent Storage.2094bob # [ 12.634542] systemd[1]: Finished Load/Save OS Random Seed.2095bob # [ 12.635163] systemd[1]: Reached target First Boot Complete.2096bob # [ 12.646050] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2097bob # [ 12.657413] systemd[1]: Starting Create Static Device Nodes in /dev...2098bob # [ 12.658180] systemd[1]: Finished Flush Journal to Persistent Storage.2099clare # [ 12.812185] systemd[1]: Finished Create Static Device Nodes in /dev.2100clare # [ 12.812952] systemd[1]: Reached target Preparation for Local File Systems.2101clare # [ 12.816779] systemd[1]: Starting Rule-based Manager for Device Events and Files...2102bob # [ 12.704683] systemd-resolved[314]: Positive Trust Anchors:2103bob # [ 12.705574] systemd-resolved[314]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2104bob # [ 12.706602] systemd-resolved[314]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162105bob # [ 12.707526] systemd-resolved[314]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test2106alice # [ 12.795546] systemd[1]: Finished Create Static Device Nodes in /dev.2107alice # [ 12.800539] systemd[1]: Reached target Preparation for Local File Systems.2108alice # [ 12.804168] systemd[1]: Starting Rule-based Manager for Device Events and Files...2109alice # [ 12.808611] systemd-resolved[316]: Positive Trust Anchors:2110clare # [ 12.841389] systemd-resolved[310]: Positive Trust Anchors:2111alice # [ 12.809263] systemd-resolved[316]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2112alice # [ 12.810185] systemd-resolved[316]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162113clare # [ 12.841992] systemd-resolved[310]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2114clare # [ 12.842852] systemd-resolved[310]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162115alice # [ 12.811071] systemd-resolved[316]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test2116clare # [ 12.843735] systemd-resolved[310]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test2117bob # [ 12.836074] systemd[1]: Finished Create Static Device Nodes in /dev.2118bob # [ 12.836793] systemd[1]: Reached target Preparation for Local File Systems.2119bob # [ 12.847669] systemd[1]: Starting Rule-based Manager for Device Events and Files...2120bob # [ 12.873022] systemd-resolved[314]: Using system hostname 'bob'.2121clare # [ 12.997107] systemd[1]: Mounting /run/wrappers...2122alice # [ 12.972113] systemd[1]: Mounting /run/wrappers...2123bob # [ 12.891887] systemd[1]: Mounting /run/wrappers...2124bob # [ 12.896320] systemd[1]: Started Network Name Resolution.2125clare # [ 13.014886] systemd-udevd[316]: Using default interface naming scheme 'v261'.2126clare # [ 13.037725] systemd-resolved[310]: Using system hostname 'clare'.2127alice # [ 13.012951] systemd-resolved[316]: Using system hostname 'alice'.2128alice # [ 13.016510] systemd[1]: Started Network Name Resolution.2129clare # [ 13.056258] systemd[1]: Started Network Name Resolution.2130alice # [ 13.024712] systemd-udevd[323]: Using default interface naming scheme 'v261'.2131bob # [ 12.969783] systemd[1]: Mounted /run/wrappers.2132bob # [ 12.970331] systemd[1]: Reached target Local File Systems.2133clare # [ 13.090331] systemd[1]: Mounted /run/wrappers.2134clare # [ 13.090920] systemd[1]: Reached target Local File Systems.2135bob # [ 12.974430] systemd[1]: Listening on Boot Loader Control Service Socket.2136alice # [ 13.057116] systemd[1]: Mounted /run/wrappers.2137alice # [ 13.057753] systemd[1]: Reached target Local File Systems.2138clare # [ 13.098955] systemd[1]: Listening on Boot Loader Control Service Socket.2139bob # [ 12.984118] systemd[1]: Starting register-nix-paths.service...2140clare # [ 13.104108] systemd[1]: Starting register-nix-paths.service...2141alice # [ 13.069712] systemd[1]: Listening on Boot Loader Control Service Socket.2142bob # [ 12.988153] systemd[1]: Starting Create SUID/SGID Wrappers...2143bob # [ 12.990413] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2144clare # [ 13.107894] systemd[1]: Starting Create SUID/SGID Wrappers...2145alice # [ 13.073622] systemd[1]: Starting register-nix-paths.service...2146clare # [ 13.108915] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2147bob # [ 12.994053] systemd[1]: Starting Save Transient machine-id to Disk...2148alice # [ 13.077740] systemd[1]: Starting Create SUID/SGID Wrappers...2149alice # [ 13.078372] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2150clare # [ 13.116172] systemd[1]: Starting Save Transient machine-id to Disk...2151alice # [ 13.086431] systemd[1]: Starting Save Transient machine-id to Disk...2152bob # [ 13.004311] systemd[1]: Starting Create System Files and Directories...2153alice # [ 13.088871] systemd[1]: Starting Create System Files and Directories...2154clare # [ 13.124928] systemd[1]: Starting Create System Files and Directories...2155bob # [ 13.026869] systemd-udevd[331]: Using default interface naming scheme 'v261'.2156clare # [ 13.202319] systemd[1]: Finished Coldplug All udev Devices.2157alice # [ 13.179194] systemd[1]: Finished Coldplug All udev Devices.2158bob # [ 13.112216] systemd[1]: Finished Coldplug All udev Devices.2159clare # [ 13.260762] systemd[1]: Started Rule-based Manager for Device Events and Files.2160alice # [ 13.255215] systemd[1]: Started Rule-based Manager for Device Events and Files.2161bob # [ 13.196914] systemd[1]: Started Rule-based Manager for Device Events and Files.2162bob # [ 13.231392] systemd[1]: Finished Create System Files and Directories.2163bob # [ 13.237791] systemd[1]: Starting Rebuild Journal Catalog...2164clare # [ 13.358407] systemd[1]: Finished Create System Files and Directories.2165bob # [ 13.241986] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2166clare # [ 13.370477] systemd[1]: Starting Rebuild Journal Catalog...2167alice # [ 13.342223] systemd[1]: Finished Create System Files and Directories.2168clare # [ 13.377381] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2169alice # [ 13.355700] systemd[1]: Starting Rebuild Journal Catalog...2170alice # [ 13.362460] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2171bob # [ 13.375407] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2172clare # [ 13.502824] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2173bob # [ 13.388931] systemd[1]: Finished Rebuild Journal Catalog.2174bob # [ 13.400421] systemd[1]: Starting Update is Completed...2175alice # [ 13.496685] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2176bob # [ 13.435921] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2177clare # [ 13.554072] systemd[1]: Finished Rebuild Journal Catalog.2178clare # [ 13.558916] systemd[1]: Starting Update is Completed...2179alice # [ 13.524654] systemd[1]: Finished Rebuild Journal Catalog.2180bob # [ 13.442439] systemd[1]: Finished Save Transient machine-id to Disk.2181clare # [ 13.559594] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2182alice # [ 13.528981] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2183clare # [ 13.568304] systemd[1]: Finished Save Transient machine-id to Disk.2184alice # [ 13.546865] systemd[1]: Starting Update is Completed...2185alice # [ 13.547607] systemd[1]: Finished Save Transient machine-id to Disk.2186bob # [ 13.480681] systemd[1]: Finished Update is Completed.2187clare # [ 13.652198] systemd[1]: Finished Update is Completed.2188alice # [ 13.643215] systemd[1]: Finished Update is Completed.2189bob # [ 13.610442] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2190clare # [ 13.746305] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2191alice # [ 13.737352] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2192bob # [ 13.660485] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2193clare # [ 13.782839] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2194alice # [ 13.767090] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2195bob # [ 13.840148] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2196clare # [ 13.974976] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2197alice # [ 13.946342] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2198bob # [ 13.871039] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2199alice # [ 14.029398] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2200clare # [ 14.067724] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2201clare # [ 14.114987] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2202clare # [ 14.119710] systemd[1]: Finished Create SUID/SGID Wrappers.2203alice # [ 14.158282] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2204bob # [ 14.075460] (udev-worker)[410]: Network interface NamePolicy= disabled on kernel command line.2205bob # [ 14.082280] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2206alice # [ 14.165472] systemd[1]: Finished Create SUID/SGID Wrappers.2207bob # [ 14.089570] systemd[1]: Finished Create SUID/SGID Wrappers.2208bob # [ 14.093980] (udev-worker)[403]: Network interface NamePolicy= disabled on kernel command line.2209alice # [ 14.193351] (udev-worker)[394]: Network interface NamePolicy= disabled on kernel command line.2210alice # [ 14.199512] (udev-worker)[403]: Network interface NamePolicy= disabled on kernel command line.2211clare # [ 14.267647] (udev-worker)[382]: Network interface NamePolicy= disabled on kernel command line.2212clare # [ 14.271520] (udev-worker)[391]: Network interface NamePolicy= disabled on kernel command line.2213clare # [ 14.283071] systemd[1]: Finished register-nix-paths.service.2214bob # [ 14.168122] systemd[1]: Finished register-nix-paths.service.2215clare # [ 14.286087] systemd[1]: Reached target System Initialization.2216clare # [ 14.286733] systemd[1]: Started Discard unused filesystem blocks once a week.2217clare # [ 14.287431] systemd[1]: Started Daily Cleanup of Temporary Directories.2218bob # [ 14.171296] systemd[1]: Reached target System Initialization.2219bob # [ 14.174373] systemd[1]: Started Discard unused filesystem blocks once a week.2220clare # [ 14.292908] systemd[1]: Reached target Timer Units.2221bob # [ 14.175104] systemd[1]: Started Daily Cleanup of Temporary Directories.2222clare # [ 14.293449] systemd[1]: Listening on D-Bus System Message Bus Socket.2223alice # [ 14.262242] systemd[1]: Finished register-nix-paths.service.2224alice # [ 14.262927] systemd[1]: Reached target System Initialization.2225alice # [ 14.263502] systemd[1]: Started Discard unused filesystem blocks once a week.2226bob # [ 14.175720] systemd[1]: Reached target Timer Units.2227bob # [ 14.182492] systemd[1]: Listening on D-Bus System Message Bus Socket.2228bob # [ 14.183131] systemd[1]: Listening on Nix Daemon Socket.2229bob # [ 14.183639] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2230alice # [ 14.267285] systemd[1]: Started Daily Cleanup of Temporary Directories.2231alice # [ 14.267929] systemd[1]: Reached target Timer Units.2232clare # [ 14.302433] systemd[1]: Listening on harmonia binary cache socket.2233clare # [ 14.303139] systemd[1]: Listening on Nix Daemon Socket.2234clare # [ 14.303682] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2235alice # [ 14.271494] systemd[1]: Listening on D-Bus System Message Bus Socket.2236bob # [ 14.191072] systemd[1]: Reached target Socket Units.2237bob # [ 14.191604] systemd[1]: Reached target Basic System.2238alice # [ 14.275263] systemd[1]: Listening on Nix Daemon Socket.2239clare # [ 14.310780] systemd[1]: Reached target Socket Units.2240clare # [ 14.311302] systemd[1]: Reached target Basic System.2241clare # [ 14.311787] systemd[1]: Started backdoor.service.2242alice # [ 14.278013] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2243alice # [ 14.281733] systemd[1]: Reached target Socket Units.2244alice # [ 14.282309] systemd[1]: Reached target Basic System.2245alice # [ 14.282889] systemd[1]: Started backdoor.service.2246alice # [ 14.283347] systemd[1]: Starting Import lastlog data into lastlog2 database...2247bob # [ 14.201943] systemd[1]: Started backdoor.service.2248bob # [ 14.202464] systemd[1]: Starting Import lastlog data into lastlog2 database...2249clare # [ 14.323114] systemd[1]: Starting Import lastlog data into lastlog2 database...2250bob # [ 14.207651] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2251alice # [ 14.292915] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2252clare # [ 14.330761] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2253alice # [ 14.314495] systemd[1]: Starting Post-Boot Actions...2254bob # [ 14.232194] systemd[1]: Starting Post-Boot Actions...2255clare # [ 14.354339] systemd[1]: Starting Post-Boot Actions...2256alice # [ 14.346709] systemd[1]: Started Reset console on configuration changes.2257bob # [ 14.264550] systemd[1]: Started Reset console on configuration changes.2258clare # [ 14.395820] systemd[1]: Started Reset console on configuration changes.2259alice # [ 14.400070] systemd[1]: Starting D-Bus System Message Bus...2260bob # [ 14.324195] systemd[1]: Starting D-Bus System Message Bus...2261alice # [ 14.415854] systemd[1]: Finished Post-Boot Actions.2262clare # [ 14.460106] systemd[1]: Starting D-Bus System Message Bus...2263bob # connecting to host...2264alice # connecting to host...2265bob # [ 14.394635] systemd[1]: Finished Post-Boot Actions.2266clare # [ 14.522677] nsncd[464]: Oct 16 09:37:48.114 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2267bob # [ 14.413925] systemd[1]: Started Name Service Cache Daemon (nsncd).2268bob # [ 14.414891] nsncd[472]: Oct 16 09:37:47.913 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2269clare # connecting to host...2270clare # [ 14.536786] systemd[1]: Finished Post-Boot Actions.2271clare # [ 14.540221] systemd[1]: Started Name Service Cache Daemon (nsncd).2272alice # [ 14.509287] systemd[1]: Started Name Service Cache Daemon (nsncd).2273bob # [ 14.430560] systemd[1]: Reached target Host and Network Name Lookups.2274bob # [ 14.431405] systemd[1]: Reached target User and Group Name Lookups.2275alice # [ 14.520100] systemd[1]: Reached target Host and Network Name Lookups.2276alice # [ 14.521071] nsncd[467]: Oct 16 09:37:48.007 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2277alice # [ 14.522396] systemd[1]: Reached target User and Group Name Lookups.2278clare # [ 14.557532] systemd[1]: Reached target Host and Network Name Lookups.2279clare # [ 14.568397] systemd[1]: Reached target User and Group Name Lookups.2280bob # [ 14.452758] systemd[1]: Starting User Login Management...2281alice # [ 14.541918] systemd[1]: Starting User Login Management...2282bob: Guest shell says: b'Spawning backdoor root shell...\n'2283clare # [ 14.589856] systemd[1]: Starting User Login Management...2284bob # [ 14.496304] systemd[1]: Finished Import lastlog data into lastlog2 database.2285clare # [ 14.622366] systemd[1]: Finished Import lastlog data into lastlog2 database.2286alice # [ 14.600109] systemd[1]: Finished Import lastlog data into lastlog2 database.2287alice # [ 14.660615] dbus-broker-launch[474]: Looking up NSS user entry for 'systemd-timesync'...2288bob # [ 14.584637] dbus-broker-launch[481]: Looking up NSS user entry for 'systemd-timesync'...2289bob: connected to guest root shell2290bob: (connecting took 14.96 seconds)2291bob: (finished: waiting for the VM to finish booting, in 14.96 seconds)2292alice # [ 14.680524] dbus-broker-launch[474]: NSS returned no entry for 'systemd-timesync'2293alice # [ 14.681246] dbus-broker-launch[474]: Invalid user-name in /nix/store/ah686xacysvqrh7xsqvibww49bg7wqpf-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2294bob # [ 14.609646] dbus-broker-launch[481]: NSS returned no entry for 'systemd-timesync'2295bob # [ 14.610360] dbus-broker-launch[481]: Invalid user-name in /nix/store/xhwz9jrhs1b8k9i8nsalw3vdxy993zp4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2296clare # [ 14.728802] dbus-broker-launch[471]: Looking up NSS user entry for 'systemd-timesync'...2297bob # [ 14.620941] systemd-logind[502]: Watching system buttons on /dev/input/event0 (gpio-keys)2298clare # [ 14.740725] dbus-broker-launch[471]: NSS returned no entry for 'systemd-timesync'2299clare # [ 14.741486] dbus-broker-launch[471]: Invalid user-name in /nix/store/q01akv3dn2hf5zpsaprwcv1lvz3q3c9f-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2300bob # [ 14.625423] systemd-logind[502]: New seat seat0.2301bob # [ 14.628099] systemd[1]: Started User Login Management.2302alice # [ 14.711983] systemd-logind[497]: Watching system buttons on /dev/input/event0 (gpio-keys)2303alice # [ 14.715685] systemd-logind[497]: New seat seat0.2304alice # [ 14.719692] systemd[1]: Started D-Bus System Message Bus.2305bob # [ 14.638526] systemd[1]: Starting linger-users.service...2306clare # [ 14.758487] systemd-logind[494]: Watching system buttons on /dev/input/event0 (gpio-keys)2307alice # [ 14.724894] systemd[1]: Started User Login Management.2308clare # [ 14.761721] systemd-logind[494]: New seat seat0.2309alice # [ 14.730153] systemd[1]: Starting linger-users.service...2310clare # [ 14.767712] systemd[1]: Started User Login Management.2311clare # [ 14.771508] systemd[1]: Starting linger-users.service...2312bob # [ 14.662159] systemd[1]: Started D-Bus System Message Bus.2313clare # [ 14.791895] systemd[1]: Started D-Bus System Message Bus.2314alice # [ 14.767210] dbus-broker-launch[474]: Ready2315clare # [ 14.833202] dbus-broker-launch[471]: Ready2316bob # [ 14.716878] dbus-broker-launch[481]: Ready2317alice # [ 14.809181] systemd[1]: linger-users.service: Deactivated successfully.2318alice # [ 14.810911] systemd[1]: Finished linger-users.service.2319clare # [ 14.856194] mousedev: PS/2 mouse device common for all mice2320clare # [ 14.859674] systemd[1]: linger-users.service: Deactivated successfully.2321clare # [ 14.863068] systemd[1]: Finished linger-users.service.2322bob # [ 14.748530] systemd[1]: linger-users.service: Deactivated successfully.2323alice # [ 14.839464] mousedev: PS/2 mouse device common for all mice2324bob # [ 14.751987] systemd-logind[502]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2325bob # [ 14.755626] systemd[1]: Finished linger-users.service.2326clare # [ 14.878683] systemd-logind[494]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2327bob # [ 14.775440] mousedev: PS/2 mouse device common for all mice2328alice # [ 14.860240] systemd-logind[497]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2329alice # [ 14.994859] systemd[1]: Finished Firewall.2330alice # [ 14.999394] systemd[1]: Reached target Preparation for Network.2331alice # [ 15.000188] systemd[1]: Listening on Network Management Resolve Hook Socket.2332alice # [ 15.005177] systemd[1]: Starting Network Management...2333bob # [ 14.939064] systemd[1]: Finished Firewall.2334clare # [ 15.056219] systemd[1]: Finished Firewall.2335bob # [ 14.942175] systemd[1]: Reached target Preparation for Network.2336clare # [ 15.060982] systemd[1]: Reached target Preparation for Network.2337bob # [ 14.944323] systemd[1]: Listening on Network Management Resolve Hook Socket.2338clare # [ 15.061606] systemd[1]: Listening on Network Management Resolve Hook Socket.2339bob # [ 14.950009] systemd[1]: Starting Network Management...2340clare # [ 15.067610] systemd[1]: Starting Network Management...2341alice # [ 15.205463] cfg80211: Loading compiled-in X.509 certificates for regulatory database2342bob # [ 15.140815] cfg80211: Loading compiled-in X.509 certificates for regulatory database2343clare # [ 15.273893] cfg80211: Loading compiled-in X.509 certificates for regulatory database2344alice # [ 15.255462] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2345alice # [ 15.256623] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2346bob # [ 15.193998] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2347bob # [ 15.194551] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2348clare # [ 15.325391] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2349alice # [ 15.282352] systemd-networkd[539]: lo: Link UP2350clare # [ 15.325984] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2351alice # [ 15.282886] systemd-networkd[539]: lo: Gained carrier2352alice # [ 15.291779] systemd-networkd[539]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2353alice # [ 15.302408] systemd-networkd[539]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2354alice # [ 15.303202] systemd[1]: Started Network Management.2355alice # [ 15.303651] systemd[1]: Reached target Network.2356bob # [ 15.222169] systemd-networkd[555]: lo: Link UP2357bob # [ 15.224086] systemd-networkd[555]: lo: Gained carrier2358alice # [ 15.310463] systemd-networkd[539]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2359alice # [ 15.311440] systemd-networkd[539]: eth1: Link UP2360alice # [ 15.311856] systemd-networkd[539]: eth1: Gained carrier2361clare # [ 15.349676] systemd-networkd[538]: lo: Link UP2362bob # [ 15.232899] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2363bob # [ 15.234274] systemd-networkd[555]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2364alice # [ 15.316329] systemd-networkd[539]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2365alice # [ 15.317822] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2366clare # [ 15.352533] systemd-networkd[538]: lo: Gained carrier2367bob # [ 15.235365] systemd[1]: Started Network Management.2368clare # [ 15.358793] systemd-networkd[538]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2369alice # [ 15.325265] systemd[1]: Starting Permit User Sessions...2370alice # [ 15.325864] systemd-networkd[539]: eth0: Link UP2371alice # [ 15.326297] systemd-networkd[539]: eth0: Gained carrier2372clare # [ 15.363965] systemd[1]: Started Network Management.2373bob # [ 15.247675] systemd[1]: Reached target Network.2374bob # [ 15.249550] systemd[1]: Reached target Network is Online.2375clare # [ 15.364611] systemd[1]: Reached target Network.2376bob # [ 15.250109] systemd-networkd[555]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2377clare # [ 15.370780] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2378alice # [ 15.336942] systemd-networkd[539]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22379bob # [ 15.259201] systemd[1]: Starting ncps binary cache proxy service...2380clare # [ 15.377296] systemd-networkd[538]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2381bob # [ 15.259987] systemd-networkd[555]: eth1: Link UP2382bob # [ 15.262842] systemd-networkd[555]: eth1: Gained carrier2383bob # [ 15.263345] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2384clare # [ 15.385273] systemd-networkd[538]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2385clare # [ 15.386128] systemd-networkd[538]: eth0: Link UP2386clare # [ 15.386590] systemd-networkd[538]: eth0: Gained carrier2387bob # [ 15.271573] systemd-networkd[555]: eth0: Link UP2388bob # [ 15.275687] systemd-networkd[555]: eth0: Gained carrier2389clare # [ 15.393809] systemd[1]: Starting Permit User Sessions...2390clare # [ 15.394388] systemd-networkd[538]: eth1: Link UP2391clare # [ 15.394832] systemd-networkd[538]: eth1: Gained carrier2392clare # [ 15.395279] systemd-networkd[538]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2393bob # [ 15.281952] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2394bob # [ 15.282684] systemd-networkd[555]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22395clare # [ 15.406261] systemd-networkd[538]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22396bob # [ 15.296726] systemd[1]: Starting Permit User Sessions...2397alice # [ 15.402945] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2398alice # [ 15.407426] systemd[1]: Finished Permit User Sessions.2399alice # [ 15.413247] systemd[1]: Started Getty on tty1.2400alice # [ 15.415137] systemd[1]: Reached target Login Prompts.2401alice # [ 15.415699] systemd[1]: Reached target Multi-User System.2402alice # [ 15.421120] systemd[1]: Startup finished in 1.058s (kernel) + 9.838s (initrd) + 4.519s (userspace) = 15.416s.2403clare # [ 15.469980] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2404clare # [ 15.481907] systemd[1]: Finished Permit User Sessions.2405bob # [ 15.364749] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2406clare # [ 15.487720] systemd[1]: Started Getty on tty1.2407clare # [ 15.490942] systemd[1]: Reached target Login Prompts.2408clare # [ 15.496409] systemd[1]: Reached target Multi-User System.2409bob # [ 15.379375] systemd[1]: Finished Permit User Sessions.2410clare # [ 15.500401] systemd[1]: Startup finished in 961ms (kernel) + 9.969s (initrd) + 4.568s (userspace) = 15.500s.2411bob # [ 15.388315] systemd[1]: Started Getty on tty1.2412bob # [ 15.394824] systemd[1]: Reached target Login Prompts.2413alice # [ 15.698001] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input32414clare # [ 15.777089] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input32415bob # [ 15.652815] ncps-pre-start[572]: {"level":"info","log_level":"info","time":"2026-10-16T09:37:49Z","message":"logger created"}2416bob # [ 15.655569] ncps-pre-start[572]: {"level":"info","xz-binary-path":"/nix/store/csrwf31hprwghnbyiglxa3p787lgrl67-xz-5.8.4-bin/bin/xz","time":"2026-10-16T09:37:49Z","message":"Using xz binary for xz decompression"}2417bob # [ 15.701236] ncps-pre-start[572]: {"level":"info","time":"2026-10-16T09:37:49Z","message":"running migrate up"}2418bob # [ 15.727424] ncps-pre-start[572]: {"level":"info","time":"2026-10-16T09:37:49Z","message":"migrate up complete"}2419bob # [ 15.847286] systemd[1]: Started ncps binary cache proxy service.2420bob # [ 15.851453] systemd[1]: Reached target Multi-User System.2421bob # [ 15.855210] systemd[1]: Startup finished in 1.046s (kernel) + 9.728s (initrd) + 5.078s (userspace) = 15.853s.2422bob: (finished: waiting for unit ncps, in 16.26 seconds)2423bob: must succeed: systemctl status ncps2424alice # [ 16.026001] systemd[1]: Starting Virtual Console Setup...2425alice # [ 16.035370] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.2426bob # [ 15.960847] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input32427alice # [ 16.039132] systemd[1]: Stopped Virtual Console Setup.2428alice # [ 16.046375] systemd[1]: Starting Virtual Console Setup...2429bob: (finished: must succeed: systemctl status ncps, in 0.08 seconds)2430clare: waiting for unit harmonia.socket2431clare: waiting for the VM to finish booting2432clare: Guest shell says: b'Spawning backdoor root shell...\n'2433clare: connected to guest root shell2434clare: (connecting took 0.00 seconds)2435clare: (finished: waiting for the VM to finish booting, in 0.00 seconds)2436alice # [ 16.118303] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2437clare: (finished: waiting for unit harmonia.socket, in 0.10 seconds)2438alice # [ 16.151238] systemd-logind[497]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2439alice: waiting for success: curl bob:8502/nix-cache-info2440alice: waiting for the VM to finish booting2441alice: Guest shell says: b'Spawning backdoor root shell...\n'2442alice: connected to guest root shell2443alice: (connecting took 0.00 seconds)2444alice: (finished: waiting for the VM to finish booting, in 0.00 seconds)2445clare # [ 16.197942] systemd-logind[494]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2446clare # [ 16.202960] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2447clare # [ 16.207676] systemd[1]: Starting Virtual Console Setup...2448bob # [ 16.095247] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","log_level":"info","time":"2026-10-16T09:37:49Z","message":"logger created"}2449bob # [ 16.100165] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","xz-binary-path":"/nix/store/csrwf31hprwghnbyiglxa3p787lgrl67-xz-5.8.4-bin/bin/xz","time":"2026-10-16T09:37:49Z","message":"Using xz binary for xz decompression"}2450bob # [ 16.103631] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","time":"2026-10-16T09:37:49Z","message":"using local locks (single-instance mode)"}2451bob # [ 16.112704] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-10-16T09:37:49Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"}2452bob # [ 16.129399] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-16T09:37:49Z","message":"setting up tracer provider to discard traces"}2453bob # [ 16.131079] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-16T09:37:49Z","message":"setting up meter provider to discard metrics"}2454bob # [ 16.140099] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-16T09:37:49Z","message":"setting up logger provider to discard logs"}2455bob # [ 16.153707] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-10-16T09:37:49Z","message":"Reporting anonymous metrics to the project maintainers"}2456bob # [ 16.155375] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-10-16T09:37:49Z","message":"failed to parse netrc file, proceeding without netrc authentication"}2457bob # [ 16.162144] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-10-16T09:37:49Z","message":"using local storage"}2458alice # [ 16.246363] systemd-vconsole-setup[558]: Configuration of first virtual console was skipped, ignoring remaining ones.2459alice # [ 16.249465] systemd[1]: Finished Virtual Console Setup.2460clare # [ 16.288998] systemd-vconsole-setup[570]: Configuration of first virtual console was skipped, ignoring remaining ones.2461bob # [ 16.172207] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","cdc-enabled":false,"cdc-min":16384,"cdc-avg":65536,"cdc-max":262144,"time":"2026-10-16T09:37:49Z","message":"configuring Content-Defined-Chunking (CDC)"}2462bob # [ 16.174090] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","lazy-cdc":false,"lazy-cdc-workers":1,"time":"2026-10-16T09:37:49Z","message":"configuring lazy Content-Defined-Chunking (CDC)"}2463clare # [ 16.292319] systemd[1]: Finished Virtual Console Setup.2464bob # [ 16.175749] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","inflight-staging-enabled":false,"distributed-lock":false,"inflight-staging-retention":300000,"inflight-staging-part-size":8388608,"time":"2026-10-16T09:37:49Z","message":"configuring in-flight NAR staging"}2465clare # [ 16.345036] systemd[1]: Starting harmonia binary cache service...2466clare # [ 16.426834] harmonia-cache[573]: 2026-10-16T09:37:50.019112Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000))2467clare # [ 16.430100] harmonia-cache[573]: 2026-10-16T09:37:50.021227Z INFO actix_server::builder: starting 4 workers2468clare # [ 16.432250] harmonia-cache[573]: 2026-10-16T09:37:50.024050Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s2469clare # [ 16.433460] harmonia-cache[573]: 2026-10-16T09:37:50.024110Z INFO actix_server::server: Actix runtime found; starting in Actix runtime2470clare # [ 16.434612] harmonia-cache[573]: 2026-10-16T09:37:50.024193Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:50002471clare # [ 16.440347] systemd[1]: Started harmonia binary cache service.2472bob # [ 16.341621] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-10-16T09:37:49Z","message":"upstream became healthy and is now available for requests"}2473bob # [ 16.348376] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","time_zone":"UTC","time":"2026-10-16T09:37:49Z","message":"setting up the cache timezone location"}2474bob # [ 16.349904] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","time":"2026-10-16T09:37:49Z","message":"cron setup complete"}2475bob # [ 16.351112] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","time":"2026-10-16T09:37:49Z","message":"starting the cron scheduler"}2476bob # [ 16.356139] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-10-16T09:37:49Z","message":"Server started"}2477bob # [ 16.476633] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2478bob # [ 16.485199] systemd[1]: Starting Virtual Console Setup...2479bob # [ 16.507362] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.2480bob # [ 16.511559] systemd[1]: Stopped Virtual Console Setup.2481bob # [ 16.514206] systemd[1]: Starting Virtual Console Setup...2482bob # [ 16.527145] systemd-logind[502]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2483bob # [ 16.545678] systemd-networkd[555]: eth0: Gained IPv6LL2484clare # [ 16.673891] systemd-networkd[538]: eth1: Gained IPv6LL2485alice # [ 16.641777] systemd-networkd[539]: eth1: Gained IPv6LL2486bob # [ 16.592592] systemd-vconsole-setup[609]: Configuration of first virtual console was skipped, ignoring remaining ones.2487bob # [ 16.595791] systemd[1]: Finished Virtual Console Setup.2488bob # [ 16.641822] systemd-networkd[555]: eth1: Gained IPv6LL2489alice # [ 17.185611] systemd-networkd[539]: eth0: Gained IPv6LL2490bob # [ 17.169671] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: 2026/10/16 09:37:50 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host2491clare # [ 17.377150] systemd-networkd[538]: eth0: Gained IPv6LL2492alice # curl: (28) Failed to connect to bob:8502 after 134296 ms: Could not connect to server2493alice # % Total % Received % Xferd Average Speed Time Time Time Current2494alice # Dload Upload Total Spent Left Speed2495bob # [ 151.488710] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:43660","trace_id":"28e624f092c14770c1257f3da7002c7c","span_id":"a409cb29961d41de","status":200,"elapsed":0.08264,"bytes":50,"time":"2026-10-16T09:40:04Z","message":"handled request"}2496alice # 0 0 0 0 0 0 0 0 0100 50 100 50 0 0 2222 0 0100 50 100 50 0 0 1848 0 0100 50 100 50 0 0 1587 0 02497alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 135.45 seconds)2498bob: waiting for success: curl clare:5000/nix-cache-info2499bob # % Total % Received % Xferd Average Speed Time Time Time Current2500bob # Dload Upload Total Spent Left Speed2501bob # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2205 0 0100 51 100 51 0 0 1904 0 0100 51 100 51 0 0 1691 0 02502bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.09 seconds)2503clare: waiting for unit harmonia2504clare: (finished: waiting for unit harmonia, in 0.07 seconds)2505clare: must succeed: systemctl status harmonia2506clare: (finished: must succeed: systemctl status harmonia, in 0.04 seconds)2507clare: must succeed: nix-build --expr '2508 builtins.derivation {2509 name = "hi";2510 builder = "/bin/sh";2511 args = [ "-c" "echo hi > $out" ];2512 system = "aarch64-linux";2513 }2514' 2>&125152516clare # [ 152.221228] systemd-resolved[310]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3.2517clare # [ 152.227760] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2518clare # [ 152.231393] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2519clare # [ 152.765235] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2520clare # [ 152.769285] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2521clare # [ 154.027172] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2522clare # [ 154.031258] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2523clare # [ 156.768319] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2524clare # [ 156.771656] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2525clare: (finished: must succeed: nix-build --expr '2526 builtins.derivation {2527 name = "hi";2528 builder = "/bin/sh";2529 args = [ "-c" "echo hi > $out" ];2530 system = "aarch64-linux";2531 }2532' 2>&12533, in 5.13 seconds)2534alice: must succeed: nix-build --expr '2535 builtins.derivation {2536 name = "hi";2537 builder = "/bin/sh";2538 args = [ "-c" "echo hi > $out" ];2539 system = "aarch64-linux";2540 }2541' 2>&125422543bob # [ 156.921846] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:43664","trace_id":"ba746dc6e1d0a0fc50f35b4cf2fd7d78","span_id":"793aafe488ed03dc","status":200,"elapsed":0.0316,"bytes":50,"time":"2026-10-16T09:40:10Z","message":"handled request"}2544alice # [ 157.283052] systemd-resolved[316]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3.2545alice # [ 157.288559] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2546alice # [ 157.292284] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2547alice # [ 157.974121] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2548alice # [ 157.978984] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2549alice # [ 159.009161] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2550alice # [ 159.014078] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2551alice # [ 161.432206] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2552alice # [ 161.436391] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2553bob # [ 161.362507] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_url":"http://clare.clan:5000/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","upstream_url":"http://clare.clan:5000","time":"2026-10-16T09:40:14Z","message":"download the narinfo from upstream"}2554bob # [ 161.393126] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-16T09:40:14Z","message":"downloading the nar from upstream"}2555bob # [ 161.404879] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_hash":"1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h","nar_compression":"none","nar_query":"hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"http://clare.clan:5000/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","upstream_url":"http://clare.clan:5000","nar_hash":"1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h","nar_compression":"none","nar_query":"hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-16T09:40:14Z","message":"download the nar from upstream"}2556bob # [ 161.432111] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":35.89712,"time":"2026-10-16T09:40:14Z","message":"download of nar complete"}2557bob # [ 161.449469] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-16T09:40:14Z","message":"storing narinfo and nar_file record in the database"}2558bob # [ 161.463153] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":100.12632,"time":"2026-10-16T09:40:14Z","message":"download of narinfo complete"}2559bob # [ 161.471149] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:43664","trace_id":"68f6f2658625f1ea2056654536ddeaeb","span_id":"6a84a35ee41df3ec","status":200,"elapsed":104.5644,"bytes":532,"time":"2026-10-16T09:40:14Z","message":"handled request"}2560bob # [ 161.477749] b68h1l35m9p3l1z6k8icpy34zgnwihzb-ncps-wrapper[578]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:43664","trace_id":"e160a1468daec2436c3859be9fcb2bc3","span_id":"0bdd619f4149749e","status":200,"elapsed":0.93268,"bytes":95,"time":"2026-10-16T09:40:14Z","message":"handled request"}2561alice: (finished: must succeed: nix-build --expr '2562 builtins.derivation {2563 name = "hi";2564 builder = "/bin/sh";2565 args = [ "-c" "echo hi > $out" ];2566 system = "aarch64-linux";2567 }2568' 2>&12569, in 4.74 seconds)2570(finished: run the VM test script, in 163.69 seconds)2571test script finished in 221.08s2572cleanup2573kill QemuMachine (pid 47)2574alice # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2575alice # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down2576alice # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down2577alice # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down2578kill QemuMachine (pid 49)2579bob # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2580bob # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down2581bob # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down2582bob # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down2583kill QemuMachine (pid 50)2584clare # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2585clare # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down2586clare # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down2587clare # [2026-10-16T09:41:12Z INFO virtiofsd] Client disconnected, shutting down2588(finished: cleanup, in 0.43 seconds)