vm-test-run-ncps
checks.aarch64-linux.ncps
· build #742
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600.0 seconds5run the VM test script6additionally exposed symbols:7 alice, bob, clare,8 vlan1,9 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_ssh10start all VMs11alice: starting vm12bob: starting vm13clare: starting vm14alice # Disk image does not exist, creating the virtualisation disk image...15alice # Formatting '/build/vm-state-alice/tmp.Y6gktiUXXF', fmt=raw size=107374182416alice: QEMU running (pid 48)17alice # mke2fs 1.47.4 (6-Mar-2025)18bob: QEMU running (pid 49)19alice # Discarding device blocks: 0/262144 done20bob # Disk image does not exist, creating the virtualisation disk image...21alice # Creating filesystem with 262144 4k blocks and 65536 inodes22bob # Formatting '/build/vm-state-bob/tmp.J2ZueMwQE2', fmt=raw size=107374182423alice # Filesystem UUID: 58a30e0a-f96c-42d0-ae38-0862f6802fe924bob # mke2fs 1.47.4 (6-Mar-2025)25alice # Superblock backups stored on blocks:26bob # Discarding device blocks: 0/262144 done27alice # 32768, 98304, 163840, 22937628bob # Creating filesystem with 262144 4k blocks and 65536 inodes29alice # 30bob # Filesystem UUID: 8e51f2ba-7b6a-436e-89fa-572b3da529ec31alice # Allocating group tables: 0/8 done32bob # Superblock backups stored on blocks:33alice # Writing inode tables: 0/8 done34bob # 32768, 98304, 163840, 22937635alice # Creating journal (8192 blocks): done36bob # 37alice # Writing superblocks and filesystem accounting information: 0/8 done38bob # Allocating group tables: 0/8 done39alice # 40bob # Writing inode tables: 0/8 done41alice # Virtualisation disk image created.42clare # Disk image does not exist, creating the virtualisation disk image...43clare: QEMU running (pid 50)44bob # Creating journal (8192 blocks): done45alice # Starting virtiofs daemons...46bob # Writing superblocks and filesystem accounting information: 0/8 done47clare # Formatting '/build/vm-state-clare/tmp.eV1iHRfMK6', fmt=raw size=107374182448(finished: start all VMs, in 0.64 seconds)49alice # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)50bob # 51clare # mke2fs 1.47.4 (6-Mar-2025)52bob: waiting for unit ncps53alice # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether54bob # Virtualisation disk image created.55clare # Discarding device blocks: 0/262144 done56alice # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection...57bob: waiting for the VM to finish booting58bob # Starting virtiofs daemons...59clare # Creating filesystem with 262144 4k blocks and 65536 inodes60bob # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)61clare # Filesystem UUID: eec86074-08cc-4b2f-b215-04f44adf23c862alice # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)63clare # Superblock backups stored on blocks:64clare # 32768, 98304, 163840, 22937665alice # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether66clare # 67bob # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68alice # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection...69bob # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection...70alice # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)71bob # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)72alice # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether73bob # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether74alice # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection...75bob # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection...76alice # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests77bob # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)78alice # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests79bob # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether80alice # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests81bob # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection...82clare # Allocating group tables: 0/8 done83clare # Writing inode tables: 0/8 done84bob # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests85clare # Creating journal (8192 blocks): done86clare # Writing superblocks and filesystem accounting information: 0/8 done87bob # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests88clare # 89bob # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests90clare # Virtualisation disk image created.91clare # Starting virtiofs daemons...92clare # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)93clare # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether94clare # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection...95clare # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)96clare # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether97clare # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection...98clare # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)99clare # [2026-09-21T10:07:18Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether100clare # [2026-09-21T10:07:18Z INFO virtiofsd] Waiting for vhost-user socket connection...101clare # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests102clare # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests103clare # [2026-09-21T10:07:18Z INFO virtiofsd] Client connected, servicing requests104clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]105clare # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 2026106clare # [ 0.000000] KASLR enabled107clare # [ 0.000000] random: crng init done108clare # [ 0.000000] Machine model: linux,dummy-virt109clare # [ 0.000000] efi: UEFI not found.110clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT111clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]112clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f]113clare # [ 0.000000] Zone ranges:114clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]115clare # [ 0.000000] DMA32 empty116clare # [ 0.000000] Normal empty117clare # [ 0.000000] Device empty118clare # [ 0.000000] Movable zone start for each node119clare # [ 0.000000] Early memory node ranges120clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]121clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]122clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000123clare # [ 0.000000] psci: probing for conduit method from DT.124clare # [ 0.000000] psci: PSCIv1.3 detected in firmware.125clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs126clare # [ 0.000000] psci: Trusted OS migration not required127clare # [ 0.000000] psci: SMC Calling Convention v1.1128clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)129clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296130clare # [ 0.000000] Detected PIPT I-cache on CPU0131clare # [ 0.000000] CPU features: detected: GICv3 CPU interface132clare # [ 0.000000] CPU features: detected: Spectre-v4133clare # [ 0.000000] CPU features: detected: Spectre-BHB134clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR135clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)136clare # [ 0.000000] CPU features: detected: ARM erratum 1418040137clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion138clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing139clare # [ 0.000000] alternatives: applying boot alternatives140clare # [ 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/48d43zfi2vli8nvl465idklqygs42ym9-nixos-system-clare-test/init regInfo=/nix/store/bwi1h48qy42jgjwb9y277m5jr7ppf76g-closure-info/registration console=ttyAMA0,115200n8 console=tty0141clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/bwi1h48qy42jgjwb9y277m5jr7ppf76g-closure-info/registration", will be passed to user space.142clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes143clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)144clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)145clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB146clare # [ 0.000000] software IO TLB: area num 1.147clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)148clare # [ 0.000000] Fallback order for Node 0: 0149clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144150clare # [ 0.000000] Policy zone: DMA151clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off152clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1153clare # [ 0.000000] allocated 2097152 bytes of page_ext154clare # [ 0.000000] ftrace: allocating 74950 entries in 294 pages155clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups156clare # [ 0.000000] rcu: Hierarchical RCU implementation.157clare # [ 0.000000] rcu: RCU event tracing is enabled.158clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.159clare # [ 0.000000] Trampoline variant of Tasks RCU enabled.160clare # [ 0.000000] Rude variant of Tasks RCU enabled.161clare # [ 0.000000] Tracing variant of Tasks RCU enabled.162clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.163clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1164clare # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.165clare # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.166clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.167clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0168clare # [ 0.000000] GICv3: 256 SPIs implemented169clare # [ 0.000000] GICv3: 0 Extended SPIs implemented170clare # [ 0.000000] Root IRQ handler: gic_handle_irq171clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI172clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0173clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000174clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]175clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)176bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]177clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)178bob # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 2026179clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000180bob # [ 0.000000] KASLR enabled181bob # [ 0.000000] random: crng init done182clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000183bob # [ 0.000000] Machine model: linux,dummy-virt184bob # [ 0.000000] efi: UEFI not found.185clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.186bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT187clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns188bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]189clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040190bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f]191bob # [ 0.000000] Zone ranges:192clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).193bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]194bob # [ 0.000000] DMA32 empty195bob # [ 0.000000] Normal empty196clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns197bob # [ 0.000000] Device empty198bob # [ 0.000000] Movable zone start for each node199clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns200bob # [ 0.000000] Early memory node ranges201clare # [ 0.000062] arm-pv: using stolen time PV202alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]203bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]204clare # [ 0.000433] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)205bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]206alice # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 2026207clare # [ 0.000720] Console: colour dummy device 80x25208bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000209alice # [ 0.000000] KASLR enabled210clare # [ 0.000741] printk: legacy console [tty0] enabled211alice # [ 0.000000] random: crng init done212bob # [ 0.000000] psci: probing for conduit method from DT.213alice # [ 0.000000] Machine model: linux,dummy-virt214bob # [ 0.000000] psci: PSCIv1.3 detected in firmware.215alice # [ 0.000000] efi: UEFI not found.216clare # [ 0.000945] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)217bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs218clare # [ 0.000951] pid_max: default: 32768 minimum: 301219alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT220bob # [ 0.000000] psci: Trusted OS migration not required221clare # [ 0.001035] LSM: initializing lsm=capability,landlock,yama,bpf,ima222bob # [ 0.000000] psci: SMC Calling Convention v1.1223alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]224clare # [ 0.001189] landlock: Up and running.225clare # [ 0.001191] Yama: becoming mindful.226alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f]227bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)228alice # [ 0.000000] Zone ranges:229clare # [ 0.001669] LSM support for eBPF active230bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296231alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]232clare # [ 0.001873] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)233bob # [ 0.000000] Detected PIPT I-cache on CPU0234alice # [ 0.000000] DMA32 empty235alice # [ 0.000000] Normal empty236bob # [ 0.000000] CPU features: detected: GICv3 CPU interface237alice # [ 0.000000] Device empty238clare # [ 0.001894] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)239bob # [ 0.000000] CPU features: detected: Spectre-v4240alice # [ 0.000000] Movable zone start for each node241clare # [ 0.004980] rcu: Hierarchical SRCU implementation.242alice # [ 0.000000] Early memory node ranges243bob # [ 0.000000] CPU features: detected: Spectre-BHB244clare # [ 0.004990] rcu: Max phase no-delay instances is 1000.245alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]246clare # [ 0.006087] fsl-mc MSI: its@8080000 domain created247bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR248clare # [ 0.006259] EFI services will not be available.249bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)250alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]251clare # [ 0.006368] smp: Bringing up secondary CPUs ...252bob # [ 0.000000] CPU features: detected: ARM erratum 1418040253alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000254clare # [ 0.006401] smp: Brought up 1 node, 1 CPU255alice # [ 0.000000] psci: probing for conduit method from DT.256bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion257clare # [ 0.006404] SMP: Total of 1 processors activated.258alice # [ 0.000000] psci: PSCIv1.3 detected in firmware.259clare # [ 0.006405] CPU: All CPU(s) started at EL1260bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing261alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs262bob # [ 0.000000] alternatives: applying boot alternatives263clare # [ 0.006419] CPU features: detected: 32-bit EL0 Support264alice # [ 0.000000] psci: Trusted OS migration not required265alice # [ 0.000000] psci: SMC Calling Convention v1.1266clare # [ 0.006422] CPU features: detected: Data cache clean to the PoU not required for I/D coherence267clare # [ 0.006425] CPU features: detected: Common not Private translations268alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)269clare # [ 0.006427] CPU features: detected: CRC32 instructions270alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296271clare # [ 0.006429] CPU features: detected: Data cache clean to Point of Persistence272alice # [ 0.000000] Detected PIPT I-cache on CPU0273clare # [ 0.006431] CPU features: detected: Enhanced Virtualization Traps274alice # [ 0.000000] CPU features: detected: GICv3 CPU interface275bob # [ 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/d8kcs93jcill7b2299xbjmwnzczl6sbz-nixos-system-bob-test/init regInfo=/nix/store/0xnfd3i8ajvb831sc6z38byc3242fa29-closure-info/registration console=ttyAMA0,115200n8 console=tty0276clare # [ 0.006442] CPU features: detected: RCpc load-acquire (LDAPR)277alice # [ 0.000000] CPU features: detected: Spectre-v4278alice # [ 0.000000] CPU features: detected: Spectre-BHB279clare # [ 0.006445] CPU features: detected: LSE atomic instructions280bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/0xnfd3i8ajvb831sc6z38byc3242fa29-closure-info/registration", will be passed to user space.281alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR282clare # [ 0.006447] CPU features: detected: Privileged Access Never283clare # [ 0.006449] CPU features: detected: PMUv3284bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes285alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)286clare # [ 0.006451] CPU features: detected: RAS Extension Support287alice # [ 0.000000] CPU features: detected: ARM erratum 1418040288bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)289clare # [ 0.006455] CPU features: detected: Speculative Store Bypassing Safe (SSBS)290alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion291bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)292clare # [ 0.006481] alternatives: applying system-wide alternatives293alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing294bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB295clare # [ 0.008041] CPU features: detected: Hardware dirty bit management on CPU0296alice # [ 0.000000] alternatives: applying boot alternatives297bob # [ 0.000000] software IO TLB: area num 1.298bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)299clare # [ 0.008298] Memory: 891436K/1048576K available (24448K kernel code, 7094K rwdata, 26372K rodata, 4736K init, 1107K bss, 115836K reserved, 32768K cma-reserved)300bob # [ 0.000000] Fallback order for Node 0: 0301clare # [ 0.008834] devtmpfs: initialized302bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144303clare # [ 0.010517] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)304bob # [ 0.000000] Policy zone: DMA305bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off306alice # [ 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/mkf3xhkpx4y16h0rmmhhsxkxj82z03ph-nixos-system-alice-test/init regInfo=/nix/store/31y0dxlvvbsip4jp7qnl3a271bngvbvr-closure-info/registration console=ttyAMA0,115200n8 console=tty0307clare # [ 0.010543] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).308bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1309clare # [ 0.010683] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL310bob # [ 0.000000] allocated 2097152 bytes of page_ext311clare # [ 0.010688] 0 pages in range for non-PLT usage312alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/31y0dxlvvbsip4jp7qnl3a271bngvbvr-closure-info/registration", will be passed to user space.313bob # [ 0.000000] ftrace: allocating 74950 entries in 294 pages314clare # [ 0.010688] 508272 pages in range for PLT usage315bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups316clare # [ 0.010809] pinctrl core: initialized pinctrl subsystem317alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes318clare # [ 0.011704] DMI not present or invalid.319bob # [ 0.000000] rcu: Hierarchical RCU implementation.320bob # [ 0.000000] rcu: RCU event tracing is enabled.321clare # [ 0.015696] NET: Registered PF_NETLINK/PF_ROUTE protocol family322alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)323bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.324clare # [ 0.018669] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations325alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)326bob # [ 0.000000] Trampoline variant of Tasks RCU enabled.327alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB328clare # [ 0.018817] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations329bob # [ 0.000000] Rude variant of Tasks RCU enabled.330alice # [ 0.000000] software IO TLB: area num 1.331bob # [ 0.000000] Tracing variant of Tasks RCU enabled.332clare # [ 0.018970] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations333alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)334clare # [ 0.018998] audit: initializing netlink subsys (disabled)335bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.336alice # [ 0.000000] Fallback order for Node 0: 0337clare # [ 0.019756] thermal_sys: Registered thermal governor 'fair_share'338bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1339alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144340alice # [ 0.000000] Policy zone: DMA341clare # [ 0.019758] thermal_sys: Registered thermal governor 'bang_bang'342bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.343alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off344clare # [ 0.019762] thermal_sys: Registered thermal governor 'step_wise'345bob # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.346clare # [ 0.019763] thermal_sys: Registered thermal governor 'user_space'347alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1348alice # [ 0.000000] allocated 2097152 bytes of page_ext349clare # [ 0.019765] thermal_sys: Registered thermal governor 'power_allocator'350bob # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.351alice # [ 0.000000] ftrace: allocating 74950 entries in 294 pages352bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0353clare # [ 0.019798] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1354alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups355bob # [ 0.000000] GICv3: 256 SPIs implemented356clare # [ 0.019817] cpuidle: using governor ladder357alice # [ 0.000000] rcu: Hierarchical RCU implementation.358clare # [ 0.019823] cpuidle: using governor menu359bob # [ 0.000000] GICv3: 0 Extended SPIs implemented360alice # [ 0.000000] rcu: RCU event tracing is enabled.361bob # [ 0.000000] Root IRQ handler: gic_handle_irq362clare # [ 0.019959] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.363alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.364bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI365clare # [ 0.019984] ASID allocator initialised with 32768 entries366bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0367alice # [ 0.000000] Trampoline variant of Tasks RCU enabled.368clare # [ 0.020724] Serial: AMBA PL011 UART driver369alice # [ 0.000000] Rude variant of Tasks RCU enabled.370bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000371alice # [ 0.000000] Tracing variant of Tasks RCU enabled.372bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]373clare # [ 0.024217] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1374clare # [ 0.024436] printk: console [ttyAMA0] enabled375alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.376bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)377alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1378bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)379alice # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.380bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000381alice # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.382bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000383bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.384alice # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.385alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0386bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns387alice # [ 0.000000] GICv3: 256 SPIs implemented388alice # [ 0.000000] GICv3: 0 Extended SPIs implemented389bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040390alice # [ 0.000000] Root IRQ handler: gic_handle_irq391bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).392alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI393alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0394bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns395alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000396bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns397alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]398bob # [ 0.000053] arm-pv: using stolen time PV399alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)400bob # [ 0.000387] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)401bob # [ 0.000676] Console: colour dummy device 80x25402alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)403bob # [ 0.000693] printk: legacy console [tty0] enabled404alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000405bob # [ 0.000884] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)406clare # [ 0.098624] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages407alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000408bob # [ 0.000889] pid_max: default: 32768 minimum: 301409clare # [ 0.098651] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page410alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.411bob # [ 0.000960] LSM: initializing lsm=capability,landlock,yama,bpf,ima412clare # [ 0.098656] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages413bob # [ 0.001126] landlock: Up and running.414bob # [ 0.001128] Yama: becoming mindful.415alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns416clare # [ 0.098662] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page417bob # [ 0.001541] LSM support for eBPF active418alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040419clare # [ 0.098665] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages420bob # [ 0.001708] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)421alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).422clare # [ 0.098668] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page423bob # [ 0.001727] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)424clare # [ 0.098671] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages425bob # [ 0.004348] rcu: Hierarchical SRCU implementation.426alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns427clare # [ 0.098674] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page428bob # [ 0.004357] rcu: Max phase no-delay instances is 1000.429alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns430bob # [ 0.005375] fsl-mc MSI: its@8080000 domain created431alice # [ 0.000065] arm-pv: using stolen time PV432bob # [ 0.005554] EFI services will not be available.433bob # [ 0.005658] smp: Bringing up secondary CPUs ...434bob # [ 0.005688] smp: Brought up 1 node, 1 CPU435alice # [ 0.000485] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)436bob # [ 0.005691] SMP: Total of 1 processors activated.437alice # [ 0.000757] Console: colour dummy device 80x25438bob # [ 0.005693] CPU: All CPU(s) started at EL1439alice # [ 0.000773] printk: legacy console [tty0] enabled440clare # [ 0.103863] fbcon: Taking over console441clare # [ 0.103887] ACPI: Interpreter disabled.442bob # [ 0.005706] CPU features: detected: 32-bit EL0 Support443clare # [ 0.105101] iommu: Default domain type: Translated444alice # [ 0.000975] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)445bob # [ 0.005708] CPU features: detected: Data cache clean to the PoU not required for I/D coherence446clare # [ 0.105112] iommu: DMA domain TLB invalidation policy: strict mode447alice # [ 0.000980] pid_max: default: 32768 minimum: 301448bob # [ 0.005711] CPU features: detected: Common not Private translations449alice # [ 0.001057] LSM: initializing lsm=capability,landlock,yama,bpf,ima450bob # [ 0.005714] CPU features: detected: CRC32 instructions451clare # [ 0.106273] SCSI subsystem initialized452alice # [ 0.001225] landlock: Up and running.453alice # [ 0.001227] Yama: becoming mindful.454bob # [ 0.005715] CPU features: detected: Data cache clean to Point of Persistence455alice # [ 0.001773] LSM support for eBPF active456bob # [ 0.005718] CPU features: detected: Enhanced Virtualization Traps457alice # [ 0.001962] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)458bob # [ 0.005729] CPU features: detected: RCpc load-acquire (LDAPR)459bob # [ 0.005732] CPU features: detected: LSE atomic instructions460alice # [ 0.001981] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)461bob # [ 0.005734] CPU features: detected: Privileged Access Never462alice # [ 0.004755] rcu: Hierarchical SRCU implementation.463bob # [ 0.005736] CPU features: detected: PMUv3464alice # [ 0.004763] rcu: Max phase no-delay instances is 1000.465bob # [ 0.005738] CPU features: detected: RAS Extension Support466alice # [ 0.005800] fsl-mc MSI: its@8080000 domain created467bob # [ 0.005742] CPU features: detected: Speculative Store Bypassing Safe (SSBS)468alice # [ 0.005955] EFI services will not be available.469alice # [ 0.006048] smp: Bringing up secondary CPUs ...470bob # [ 0.005765] alternatives: applying system-wide alternatives471alice # [ 0.006075] smp: Brought up 1 node, 1 CPU472alice # [ 0.006078] SMP: Total of 1 processors activated.473bob # [ 0.007329] CPU features: detected: Hardware dirty bit management on CPU0474alice # [ 0.006080] CPU: All CPU(s) started at EL1475alice # [ 0.006091] CPU features: detected: 32-bit EL0 Support476bob # [ 0.007527] Memory: 891432K/1048576K available (24448K kernel code, 7094K rwdata, 26372K rodata, 4736K init, 1107K bss, 115816K reserved, 32768K cma-reserved)477alice # [ 0.006094] CPU features: detected: Data cache clean to the PoU not required for I/D coherence478bob # [ 0.008003] devtmpfs: initialized479alice # [ 0.006097] CPU features: detected: Common not Private translations480bob # [ 0.009579] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)481alice # [ 0.006100] CPU features: detected: CRC32 instructions482bob # [ 0.009603] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).483alice # [ 0.006102] CPU features: detected: Data cache clean to Point of Persistence484bob # [ 0.009735] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL485alice # [ 0.006104] CPU features: detected: Enhanced Virtualization Traps486bob # [ 0.009739] 0 pages in range for non-PLT usage487alice # [ 0.006114] CPU features: detected: RCpc load-acquire (LDAPR)488bob # [ 0.009739] 508272 pages in range for PLT usage489bob # [ 0.009850] pinctrl core: initialized pinctrl subsystem490alice # [ 0.006116] CPU features: detected: LSE atomic instructions491bob # [ 0.010679] DMI not present or invalid.492alice # [ 0.006118] CPU features: detected: Privileged Access Never493clare # [ 0.114893] usbcore: registered new interface driver usbfs494alice # [ 0.006121] CPU features: detected: PMUv3495bob # [ 0.014339] NET: Registered PF_NETLINK/PF_ROUTE protocol family496clare # [ 0.114957] usbcore: registered new interface driver hub497alice # [ 0.006123] CPU features: detected: RAS Extension Support498bob # [ 0.017021] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations499clare # [ 0.114971] usbcore: registered new device driver usb500alice # [ 0.006127] CPU features: detected: Speculative Store Bypassing Safe (SSBS)501bob # [ 0.017164] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations502clare # [ 0.115231] pps_core: LinuxPPS API ver. 1 registered503alice # [ 0.006149] alternatives: applying system-wide alternatives504bob # [ 0.017315] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations505clare # [ 0.115238] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>506alice # [ 0.007695] CPU features: detected: Hardware dirty bit management on CPU0507clare # [ 0.115244] PTP clock support registered508bob # [ 0.017339] audit: initializing netlink subsys (disabled)509clare # [ 0.115279] EDAC MC: Ver: 3.0.0510bob # [ 0.017944] thermal_sys: Registered thermal governor 'fair_share'511alice # [ 0.007890] Memory: 891448K/1048576K available (24448K kernel code, 7094K rwdata, 26372K rodata, 4736K init, 1107K bss, 115832K reserved, 32768K cma-reserved)512bob # [ 0.017945] thermal_sys: Registered thermal governor 'bang_bang'513clare # [ 0.118335] scmi_core: SCMI protocol bus registered514alice # [ 0.008437] devtmpfs: initialized515bob # [ 0.017949] thermal_sys: Registered thermal governor 'step_wise'516alice # [ 0.009999] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)517bob # [ 0.017951] thermal_sys: Registered thermal governor 'user_space'518clare # [ 0.119157] FPGA manager framework519bob # [ 0.017953] thermal_sys: Registered thermal governor 'power_allocator'520alice # [ 0.010024] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).521clare # [ 0.120224] vgaarb: loaded522alice # [ 0.010160] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL523bob # [ 0.017977] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1524bob # [ 0.017996] cpuidle: using governor ladder525clare # [ 0.120668] clocksource: Switched to clocksource arch_sys_counter526alice # [ 0.010164] 0 pages in range for non-PLT usage527bob # [ 0.018001] cpuidle: using governor menu528alice # [ 0.010165] 508272 pages in range for PLT usage529alice # [ 0.010279] pinctrl core: initialized pinctrl subsystem530bob # [ 0.018125] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.531alice # [ 0.011052] DMI not present or invalid.532bob # [ 0.018148] ASID allocator initialised with 32768 entries533alice # [ 0.014519] NET: Registered PF_NETLINK/PF_ROUTE protocol family534bob # [ 0.018810] Serial: AMBA PL011 UART driver535alice # [ 0.016998] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations536bob # [ 0.022101] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1537bob # [ 0.022255] printk: console [ttyAMA0] enabled538alice # [ 0.017136] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations539alice # [ 0.017288] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations540alice # [ 0.017310] audit: initializing netlink subsys (disabled)541alice # [ 0.018116] thermal_sys: Registered thermal governor 'fair_share'542alice # [ 0.018119] thermal_sys: Registered thermal governor 'bang_bang'543alice # [ 0.018123] thermal_sys: Registered thermal governor 'step_wise'544clare # [ 0.125758] VFS: Disk quotas dquot_6.6.0545alice # [ 0.018125] thermal_sys: Registered thermal governor 'user_space'546clare # [ 0.125799] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)547alice # [ 0.018127] thermal_sys: Registered thermal governor 'power_allocator'548alice # [ 0.018150] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1549alice # [ 0.018170] cpuidle: using governor ladder550alice # [ 0.018175] cpuidle: using governor menu551clare # [ 0.128201] netfs: FS-Cache loaded552alice # [ 0.018306] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.553clare # [ 0.128348] pnp: PnP ACPI: disabled554alice # [ 0.018328] ASID allocator initialised with 32768 entries555alice # [ 0.018981] Serial: AMBA PL011 UART driver556alice # [ 0.022225] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1557alice # [ 0.022412] printk: console [ttyAMA0] enabled558bob # [ 0.094268] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages559bob # [ 0.094292] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page560bob # [ 0.094296] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages561bob # [ 0.094302] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page562clare # [ 0.130839] NET: Registered PF_INET protocol family563bob # [ 0.094306] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages564clare # [ 0.131043] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)565bob # [ 0.094309] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page566bob # [ 0.094312] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages567bob # [ 0.094315] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page568bob # [ 0.099518] fbcon: Taking over console569bob # [ 0.099542] ACPI: Interpreter disabled.570bob # [ 0.100790] iommu: Default domain type: Translated571bob # [ 0.100801] iommu: DMA domain TLB invalidation policy: strict mode572bob # [ 0.102018] SCSI subsystem initialized573alice # [ 0.096728] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages574alice # [ 0.096753] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page575alice # [ 0.096757] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages576alice # [ 0.096764] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page577alice # [ 0.096767] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages578alice # [ 0.096770] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page579alice # [ 0.096773] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages580alice # [ 0.096776] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page581alice # [ 0.102096] fbcon: Taking over console582alice # [ 0.102121] ACPI: Interpreter disabled.583alice # [ 0.103370] iommu: Default domain type: Translated584alice # [ 0.103382] iommu: DMA domain TLB invalidation policy: strict mode585alice # [ 0.104603] SCSI subsystem initialized586bob # [ 0.110240] usbcore: registered new interface driver usbfs587bob # [ 0.110282] usbcore: registered new interface driver hub588alice # [ 0.105231] usbcore: registered new interface driver usbfs589bob # [ 0.110297] usbcore: registered new device driver usb590alice # [ 0.105272] usbcore: registered new interface driver hub591bob # [ 0.110537] pps_core: LinuxPPS API ver. 1 registered592alice # [ 0.105283] usbcore: registered new device driver usb593alice # [ 0.105506] pps_core: LinuxPPS API ver. 1 registered594bob # [ 0.110544] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>595bob # [ 0.110550] PTP clock support registered596alice # [ 0.105513] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>597bob # [ 0.110580] EDAC MC: Ver: 3.0.0598alice # [ 0.105519] PTP clock support registered599alice # [ 0.105550] EDAC MC: Ver: 3.0.0600bob # [ 0.113646] scmi_core: SCMI protocol bus registered601bob # [ 0.114555] FPGA manager framework602bob # [ 0.115664] vgaarb: loaded603bob # [ 0.116104] clocksource: Switched to clocksource arch_sys_counter604alice # [ 0.112753] scmi_core: SCMI protocol bus registered605alice # [ 0.113643] FPGA manager framework606alice # [ 0.114740] vgaarb: loaded607clare # [ 0.156225] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)608alice # [ 0.115203] clocksource: Switched to clocksource arch_sys_counter609clare # [ 0.156261] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)610clare # [ 0.156276] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)611bob # [ 0.122001] VFS: Disk quotas dquot_6.6.0612clare # [ 0.156316] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)613clare # [ 0.156384] TCP: Hash tables configured (established 8192 bind 8192)614bob # [ 0.122043] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)615clare # [ 0.156480] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)616clare # [ 0.156521] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)617clare # [ 0.156580] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)618bob # [ 0.124463] netfs: FS-Cache loaded619bob # [ 0.124598] pnp: PnP ACPI: disabled620bob # [ 0.127052] NET: Registered PF_INET protocol family621bob # [ 0.127247] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)622alice # [ 0.124100] VFS: Disk quotas dquot_6.6.0623alice # [ 0.124143] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)624alice # [ 0.126647] netfs: FS-Cache loaded625clare # [ 0.161450] NET: Registered PF_UNIX/PF_LOCAL protocol family626alice # [ 0.126797] pnp: PnP ACPI: disabled627clare # [ 0.161533] NET: Registered PF_XDP protocol family628clare # [ 0.161558] PCI: CLS 0 bytes, default 64629clare # [ 0.161770] Trying to unpack rootfs image as initramfs...630clare # [ 0.170262] kvm [1]: HYP mode not available631alice # [ 0.129388] NET: Registered PF_INET protocol family632alice # [ 0.129607] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)633bob # [ 0.152594] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)634alice # [ 0.154941] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)635bob # [ 0.152628] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)636bob # [ 0.152642] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)637alice # [ 0.154977] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)638bob # [ 0.152684] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)639alice # [ 0.154992] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)640bob # [ 0.152752] TCP: Hash tables configured (established 8192 bind 8192)641alice # [ 0.155034] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)642bob # [ 0.152847] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)643alice # [ 0.155103] TCP: Hash tables configured (established 8192 bind 8192)644bob # [ 0.152918] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)645bob # [ 0.152975] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)646bob # [ 0.153113] NET: Registered PF_UNIX/PF_LOCAL protocol family647bob # [ 0.153146] NET: Registered PF_XDP protocol family648bob # [ 0.153168] PCI: CLS 0 bytes, default 64649bob # [ 0.153402] Trying to unpack rootfs image as initramfs...650bob # [ 0.166218] kvm [1]: HYP mode not available651alice # [ 0.158270] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)652alice # [ 0.158325] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)653alice # [ 0.158351] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)654alice # [ 0.158515] NET: Registered PF_UNIX/PF_LOCAL protocol family655alice # [ 0.158566] NET: Registered PF_XDP protocol family656alice # [ 0.158592] PCI: CLS 0 bytes, default 64657alice # [ 0.158805] Trying to unpack rootfs image as initramfs...658alice # [ 0.170422] kvm [1]: HYP mode not available659clare # [ 0.245593] Initialise system trusted keyrings660clare # [ 0.246143] workingset: timestamp_bits=42 max_order=18 bucket_order=0661clare # [ 0.247198] squashfs: version 4.0 (2009/01/31) Phillip Lougher662clare # [ 0.247733] 9p: Installing v9fs 9p2000 file system support663clare # [ 0.267195] Key type asymmetric registered664clare # [ 0.267234] Asymmetric key parser 'x509' registered665clare # [ 0.267332] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)666clare # [ 0.272754] io scheduler mq-deadline registered667clare # [ 0.272777] io scheduler kyber registered668clare # [ 0.275882] pl061_gpio 9030000.pl061: PL061 GPIO chip registered669bob # [ 0.244595] Initialise system trusted keyrings670bob # [ 0.245156] workingset: timestamp_bits=42 max_order=18 bucket_order=0671bob # [ 0.246199] squashfs: version 4.0 (2009/01/31) Phillip Lougher672bob # [ 0.246747] 9p: Installing v9fs 9p2000 file system support673clare # [ 0.280730] ledtrig-cpu: registered to indicate activity on CPUs674clare # [ 0.281229] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:675clare # [ 0.281257] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000676clare # [ 0.281274] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000677clare # [ 0.281280] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000678clare # [ 0.281320] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits679clare # [ 0.281353] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]680clare # [ 0.281452] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00681clare # [ 0.281471] pci_bus 0000:00: root bus resource [bus 00-ff]682clare # [ 0.281475] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]683clare # [ 0.281479] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]684clare # [ 0.281483] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]685clare # [ 0.281579] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint686clare # [ 0.282058] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint687clare # [ 0.282198] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]688clare # [ 0.282210] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]689alice # [ 0.255738] Initialise system trusted keyrings690clare # [ 0.282233] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]691alice # [ 0.256289] workingset: timestamp_bits=42 max_order=18 bucket_order=0692clare # [ 0.282245] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]693clare # [ 0.282584] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint694alice # [ 0.257290] squashfs: version 4.0 (2009/01/31) Phillip Lougher695clare # [ 0.282718] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]696clare # [ 0.282730] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]697clare # [ 0.282752] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]698alice # [ 0.257868] 9p: Installing v9fs 9p2000 file system support699clare # [ 0.283084] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint700clare # [ 0.283218] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]701clare # [ 0.283230] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]702clare # [ 0.283251] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]703bob # [ 0.266173] Key type asymmetric registered704bob # [ 0.266208] Asymmetric key parser 'x509' registered705clare # [ 0.283600] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint706bob # [ 0.266300] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)707clare # [ 0.283732] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]708clare # [ 0.283744] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]709clare # [ 0.283766] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]710clare # [ 0.283778] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]711clare # [ 0.284105] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint712clare # [ 0.284238] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]713clare # [ 0.284261] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]714clare # [ 0.284594] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint715clare # [ 0.284770] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]716bob # [ 0.272227] io scheduler mq-deadline registered717clare # [ 0.284792] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]718bob # [ 0.272254] io scheduler kyber registered719clare # [ 0.285126] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint720clare # [ 0.285256] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]721clare # [ 0.285450] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint722clare # [ 0.285584] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]723clare # [ 0.285606] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]724clare # [ 0.285940] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint725bob # [ 0.275660] pl061_gpio 9030000.pl061: PL061 GPIO chip registered726clare # [ 0.286071] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]727clare # [ 0.286092] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]728clare # [ 0.286414] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint729clare # [ 0.286545] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]730clare # [ 0.286566] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]731clare # [ 0.286900] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint732clare # [ 0.287128] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]733clare # [ 0.287142] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]734bob # [ 0.276639] ledtrig-cpu: registered to indicate activity on CPUs735clare # [ 0.287163] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]736bob # [ 0.277047] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:737clare # [ 0.287513] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint738bob # [ 0.277076] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000739clare # [ 0.287647] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]740bob # [ 0.277092] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000741clare # [ 0.287659] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]742bob # [ 0.277099] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000743clare # [ 0.287680] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]744alice # [ 0.277375] Key type asymmetric registered745alice # [ 0.277416] Asymmetric key parser 'x509' registered746clare # [ 0.288112] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned747bob # [ 0.277133] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits748alice # [ 0.277506] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)749clare # [ 0.288125] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned750bob # [ 0.277174] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]751clare # [ 0.288130] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned752bob # [ 0.277263] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00753clare # [ 0.288166] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned754bob # [ 0.277283] pci_bus 0000:00: root bus resource [bus 00-ff]755bob # [ 0.277288] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]756clare # [ 0.288200] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned757bob # [ 0.277292] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]758clare # [ 0.288233] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned759bob # [ 0.277296] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]760clare # [ 0.288267] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned761bob # [ 0.277398] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint762clare # [ 0.288299] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned763bob # [ 0.277896] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint764clare # [ 0.288332] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned765alice # [ 0.283299] io scheduler mq-deadline registered766bob # [ 0.278041] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]767alice # [ 0.283322] io scheduler kyber registered768clare # [ 0.288365] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned769bob # [ 0.278054] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]770bob # [ 0.278077] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]771clare # [ 0.288397] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned772bob # [ 0.278089] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]773clare # [ 0.288428] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned774bob # [ 0.278439] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint775clare # [ 0.288506] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned776clare # [ 0.288545] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned777bob # [ 0.278585] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]778bob # [ 0.278598] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]779alice # [ 0.286392] pl061_gpio 9030000.pl061: PL061 GPIO chip registered780clare # [ 0.288559] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned781bob # [ 0.278621] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]782clare # [ 0.288573] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned783bob # [ 0.278989] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint784clare # [ 0.288587] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned785bob # [ 0.279129] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]786bob # [ 0.279140] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]787clare # [ 0.288601] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned788clare # [ 0.288615] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned789bob # [ 0.279163] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]790clare # [ 0.288629] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned791bob # [ 0.279516] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint792clare # [ 0.288644] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned793bob # [ 0.279663] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]794clare # [ 0.288658] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned795bob # [ 0.279676] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]796alice # [ 0.287302] ledtrig-cpu: registered to indicate activity on CPUs797bob # [ 0.279700] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]798bob # [ 0.279712] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]799alice # [ 0.287639] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:800bob # [ 0.280073] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint801alice # [ 0.287664] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000802alice # [ 0.287680] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000803alice # [ 0.287686] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000804alice # [ 0.287724] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits805alice # [ 0.287755] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]806alice # [ 0.287838] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00807alice # [ 0.287857] pci_bus 0000:00: root bus resource [bus 00-ff]808alice # [ 0.287862] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]809alice # [ 0.287866] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]810alice # [ 0.287870] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]811alice # [ 0.287970] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint812alice # [ 0.288466] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint813alice # [ 0.288607] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]814alice # [ 0.288620] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]815alice # [ 0.288643] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]816alice # [ 0.288654] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]817alice # [ 0.289010] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint818alice # [ 0.289151] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]819alice # [ 0.289164] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]820bob # [ 0.304310] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]821alice # [ 0.289189] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]822bob # [ 0.304351] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]823alice # [ 0.289557] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint824bob # [ 0.304766] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint825alice # [ 0.289698] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]826bob # [ 0.304908] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]827alice # [ 0.289710] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]828bob # [ 0.304930] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]829clare # [ 0.344771] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned830alice # [ 0.289731] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]831bob # [ 0.305220] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint832clare # [ 0.344811] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned833alice # [ 0.290079] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint834bob # [ 0.305351] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]835clare # [ 0.344828] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned836alice # [ 0.290216] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]837clare # [ 0.344845] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned838bob # [ 0.305545] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint839alice # [ 0.290228] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]840clare # [ 0.344861] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned841bob # [ 0.305681] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]842alice # [ 0.290250] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]843clare # [ 0.344876] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned844bob # [ 0.305703] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]845alice # [ 0.290263] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]846clare # [ 0.344892] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned847bob # [ 0.306045] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint848alice # [ 0.290608] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint849clare # [ 0.344908] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned850bob # [ 0.306184] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]851alice # [ 0.290751] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]852clare # [ 0.344924] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned853bob # [ 0.306206] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]854alice # [ 0.290774] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]855clare # [ 0.344947] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]856bob # [ 0.306531] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint857clare # [ 0.344959] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]858alice # [ 0.291121] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint859bob # [ 0.306666] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]860clare # [ 0.344963] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]861bob # [ 0.306687] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]862clare # [ 0.345570] pci 0000:00:07.0: enabling device (0000 -> 0002)863bob # [ 0.307061] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint864bob # [ 0.307285] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]865bob # [ 0.307299] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]866bob # [ 0.307321] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]867bob # [ 0.307659] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint868bob # [ 0.307793] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]869bob # [ 0.307804] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]870bob # [ 0.307827] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]871clare # [ 0.359595] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)872alice # [ 0.315359] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]873alice # [ 0.315399] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]874alice # [ 0.315779] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint875alice # [ 0.315920] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]876alice # [ 0.316171] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint877alice # [ 0.316312] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]878alice # [ 0.316334] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]879alice # [ 0.316685] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint880alice # [ 0.316825] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]881alice # [ 0.316846] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]882alice # [ 0.317186] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint883alice # [ 0.317344] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]884bob # [ 0.328440] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned885alice # [ 0.317368] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]886bob # [ 0.328467] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned887alice # [ 0.317728] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint888bob # [ 0.328472] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned889alice # [ 0.317971] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]890alice # [ 0.317986] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]891bob # [ 0.328516] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned892alice # [ 0.318008] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]893bob # [ 0.328552] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned894alice # [ 0.318360] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint895bob # [ 0.328588] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned896alice # [ 0.318499] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]897bob # [ 0.328624] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned898alice # [ 0.318511] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]899bob # [ 0.328659] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned900alice # [ 0.318534] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]901alice # [ 0.318995] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned902bob # [ 0.328694] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned903clare # [ 0.370295] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)904clare # [ 0.372324] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)905alice # [ 0.319007] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned906alice # [ 0.319012] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned907bob # [ 0.328729] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned908bob # [ 0.328764] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned909alice # [ 0.319052] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned910alice # [ 0.319090] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned911bob # [ 0.328798] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned912bob # [ 0.328879] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned913bob # [ 0.328944] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned914alice # [ 0.319127] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned915bob # [ 0.328961] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned916alice # [ 0.319165] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned917bob # [ 0.328976] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned918bob # [ 0.328990] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned919bob # [ 0.329006] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned920bob # [ 0.329021] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned921bob # [ 0.329037] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned922bob # [ 0.329053] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned923bob # [ 0.329068] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned924bob # [ 0.329083] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned925bob # [ 0.329098] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned926bob # [ 0.329113] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned927bob # [ 0.329128] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned928bob # [ 0.329144] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned929bob # [ 0.329158] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned930bob # [ 0.329173] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned931alice # [ 0.339264] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned932bob # [ 0.329187] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned933alice # [ 0.339353] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned934bob # [ 0.329203] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned935clare # [ 0.382575] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)936bob # [ 0.329230] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]937alice # [ 0.339391] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned938clare # [ 0.384562] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)939bob # [ 0.329243] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]940alice # [ 0.339427] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned941bob # [ 0.329247] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]942alice # [ 0.339462] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned943bob # [ 0.329824] pci 0000:00:07.0: enabling device (0000 -> 0002)944alice # [ 0.339580] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned945alice # [ 0.339618] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned946alice # [ 0.339634] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned947alice # [ 0.339651] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned948clare # [ 0.387636] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)949alice # [ 0.339668] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned950alice # [ 0.339685] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned951alice # [ 0.339702] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned952alice # [ 0.339717] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned953alice # [ 0.339732] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned954alice # [ 0.339747] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned955alice # [ 0.339761] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned956alice # [ 0.339776] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned957alice # [ 0.339791] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned958alice # [ 0.339805] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned959alice # [ 0.339820] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned960clare # [ 0.393228] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)961clare # [ 0.395436] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)962alice # [ 0.339834] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned963alice # [ 0.339849] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned964alice # [ 0.339863] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned965alice # [ 0.339877] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned966alice # [ 0.339902] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]967alice # [ 0.339917] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]968alice # [ 0.339921] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]969alice # [ 0.340539] pci 0000:00:07.0: enabling device (0000 -> 0002)970bob # [ 0.360703] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)971bob # [ 0.362934] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)972bob # [ 0.373186] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)973clare # [ 0.405308] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)974bob # [ 0.375107] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)975clare # [ 0.407205] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)976clare # [ 0.409607] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)977alice # [ 0.368724] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)978alice # [ 0.371081] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)979bob # [ 0.378172] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)980clare # [ 0.421670] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled981clare # [ 0.423612] msm_serial: driver initialized982clare # [ 0.423732] SuperH (H)SCI(F) driver initialized983clare # [ 0.423769] STM32 USART driver initialized984alice # [ 0.380932] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)985alice # [ 0.383061] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)986bob # [ 0.388368] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)987bob # [ 0.390266] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)988bob # [ 0.392039] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)989bob # [ 0.395431] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)990alice # [ 0.393207] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)991alice # [ 0.395190] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)992bob # [ 0.401380] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)993bob # [ 0.403568] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)994alice # [ 0.405120] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)995alice # [ 0.406863] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)996clare # [ 0.450544] loop: module loaded997bob # [ 0.415329] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled998clare # [ 0.450720] virtio_blk virtio2: 1/0/0 default/read/poll queues999clare # [ 0.451436] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1000alice # [ 0.409930] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)1001clare # [ 0.457297] megasas: 07.734.00.00-rc11002clare # [ 0.457794] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1003alice # [ 0.415782] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)1004alice # [ 0.418335] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)1005bob # [ 0.420938] msm_serial: driver initialized1006bob # [ 0.421062] SuperH (H)SCI(F) driver initialized1007bob # [ 0.421102] STM32 USART driver initialized1008alice # [ 0.428083] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled1009clare # [ 0.468738] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001010clare # [ 0.468785] Intel/Sharp Extended Query Table at 0x00311011clare # [ 0.471188] Using buffer write method1012clare # [ 0.471284] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1013clare # [ 0.473078] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001014clare # [ 0.473105] Intel/Sharp Extended Query Table at 0x00311015clare # [ 0.474896] Using buffer write method1016clare # [ 0.474931] Concatenating MTD devices:1017clare # [ 0.474934] (0): "0.flash"1018clare # [ 0.474938] (1): "0.flash"1019clare # [ 0.474940] into device "0.flash"1020bob # [ 0.445769] loop: module loaded1021bob # [ 0.445944] virtio_blk virtio2: 1/0/0 default/read/poll queues1022bob # [ 0.446677] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1023alice # [ 0.436069] msm_serial: driver initialized1024alice # [ 0.436176] SuperH (H)SCI(F) driver initialized1025alice # [ 0.436214] STM32 USART driver initialized1026bob # [ 0.456645] megasas: 07.734.00.00-rc11027bob # [ 0.457200] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1028bob # [ 0.459289] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001029bob # [ 0.459330] Intel/Sharp Extended Query Table at 0x00311030alice # [ 0.459489] loop: module loaded1031bob # [ 0.461015] Using buffer write method1032alice # [ 0.459650] virtio_blk virtio2: 1/0/0 default/read/poll queues1033bob # [ 0.461074] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1034alice # [ 0.460313] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1035bob # [ 0.462892] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001036bob # [ 0.462918] Intel/Sharp Extended Query Table at 0x00311037alice # [ 0.463683] megasas: 07.734.00.00-rc11038bob # [ 0.472933] Using buffer write method1039bob # [ 0.472973] Concatenating MTD devices:1040bob # [ 0.472977] (0): "0.flash"1041alice # [ 0.464193] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1042bob # [ 0.472980] (1): "0.flash"1043bob # [ 0.472982] into device "0.flash"1044alice # [ 0.476349] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001045alice # [ 0.476403] Intel/Sharp Extended Query Table at 0x00311046alice # [ 0.478357] Using buffer write method1047alice # [ 0.478422] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1048alice # [ 0.482298] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001049alice # [ 0.482334] Intel/Sharp Extended Query Table at 0x00311050alice # [ 0.487862] Using buffer write method1051alice # [ 0.487899] Concatenating MTD devices:1052alice # [ 0.487902] (0): "0.flash"1053alice # [ 0.487906] (1): "0.flash"1054alice # [ 0.487908] into device "0.flash"1055clare # [ 0.742975] Freeing initrd memory: 26968K1056clare # [ 0.750899] tun: Universal TUN/TAP device driver, 1.61057clare # [ 0.753888] thunder_xcv, ver 1.01058clare # [ 0.753926] thunder_bgx, ver 1.01059clare # [ 0.753938] nicpf, ver 1.01060clare # [ 0.754268] e1000: Intel(R) PRO/1000 Network Driver1061clare # [ 0.754277] e1000: Copyright (c) 1999-2006 Intel Corporation.1062clare # [ 0.754292] e1000e: Intel(R) PRO/1000 Network Driver1063clare # [ 0.754298] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1064clare # [ 0.754312] igb: Intel(R) Gigabit Ethernet Network Driver1065clare # [ 0.754315] igb: Copyright (c) 2007-2014 Intel Corporation.1066clare # [ 0.754329] igbvf: Intel(R) Gigabit Virtual Function Network Driver1067clare # [ 0.754333] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1068clare # [ 0.754398] sky2: driver version 1.301069clare # [ 0.755178] usbcore: registered new interface driver usb-storage1070clare # [ 0.755281] usbcore: registered new interface driver usbserial_generic1071clare # [ 0.755302] usbserial: USB Serial support registered for generic1072clare # [ 0.755607] hv_vmbus: registering driver hyperv_keyboard1073clare # [ 0.756632] ehci-pci 0000:00:07.0: EHCI Host Controller1074clare # [ 0.756732] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 11075clare # [ 0.757022] ehci-pci 0000:00:07.0: irq 16, io mem 0x100860001076clare # [ 0.765392] rtc-pl031 9010000.pl031: registered as rtc01077clare # [ 0.765446] rtc-pl031 9010000.pl031: setting system clock to 2026-09-21T10:07:19 UTC (1789985239)1078clare # [ 0.765717] i2c_dev: i2c /dev entries driver1079bob # [ 0.741083] Freeing initrd memory: 26948K1080bob # [ 0.748783] tun: Universal TUN/TAP device driver, 1.61081bob # [ 0.751807] thunder_xcv, ver 1.01082bob # [ 0.751845] thunder_bgx, ver 1.01083bob # [ 0.751858] nicpf, ver 1.01084bob # [ 0.753097] e1000: Intel(R) PRO/1000 Network Driver1085bob # [ 0.753114] e1000: Copyright (c) 1999-2006 Intel Corporation.1086bob # [ 0.753133] e1000e: Intel(R) PRO/1000 Network Driver1087bob # [ 0.753140] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1088alice # [ 0.747095] Freeing initrd memory: 26964K1089bob # [ 0.753155] igb: Intel(R) Gigabit Ethernet Network Driver1090bob # [ 0.753158] igb: Copyright (c) 2007-2014 Intel Corporation.1091bob # [ 0.753172] igbvf: Intel(R) Gigabit Virtual Function Network Driver1092bob # [ 0.753176] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1093bob # [ 0.753287] sky2: driver version 1.301094bob # [ 0.754133] usbcore: registered new interface driver usb-storage1095bob # [ 0.754230] usbcore: registered new interface driver usbserial_generic1096bob # [ 0.754256] usbserial: USB Serial support registered for generic1097alice # [ 0.754844] tun: Universal TUN/TAP device driver, 1.61098bob # [ 0.754553] hv_vmbus: registering driver hyperv_keyboard1099bob # [ 0.761718] ehci-pci 0000:00:07.0: EHCI Host Controller1100bob # [ 0.761787] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 11101bob # [ 0.762042] ehci-pci 0000:00:07.0: irq 16, io mem 0x100860001102bob # [ 0.763838] rtc-pl031 9010000.pl031: registered as rtc01103bob # [ 0.763888] rtc-pl031 9010000.pl031: setting system clock to 2026-09-21T10:07:19 UTC (1789985239)1104alice # [ 0.757843] thunder_xcv, ver 1.01105bob # [ 0.765349] i2c_dev: i2c /dev entries driver1106alice # [ 0.757882] thunder_bgx, ver 1.01107alice # [ 0.757894] nicpf, ver 1.01108alice # [ 0.758207] e1000: Intel(R) PRO/1000 Network Driver1109alice # [ 0.758217] e1000: Copyright (c) 1999-2006 Intel Corporation.1110alice # [ 0.758233] e1000e: Intel(R) PRO/1000 Network Driver1111alice # [ 0.758239] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1112bob # [ 0.767440] sdhci: Secure Digital Host Controller Interface driver1113alice # [ 0.758253] igb: Intel(R) Gigabit Ethernet Network Driver1114bob # [ 0.767465] sdhci: Copyright(c) Pierre Ossman1115alice # [ 0.758256] igb: Copyright (c) 2007-2014 Intel Corporation.1116bob # [ 0.767610] Synopsys Designware Multimedia Card Interface Driver1117alice # [ 0.758270] igbvf: Intel(R) Gigabit Virtual Function Network Driver1118bob # [ 0.767787] sdhci-pltfm: SDHCI platform and OF driver helper1119alice # [ 0.758274] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1120alice # [ 0.758368] sky2: driver version 1.301121alice # [ 0.759161] usbcore: registered new interface driver usb-storage1122alice # [ 0.760037] ehci-pci 0000:00:07.0: EHCI Host Controller1123clare # [ 0.769105] sdhci: Secure Digital Host Controller Interface driver1124clare # [ 0.769128] sdhci: Copyright(c) Pierre Ossman1125bob # [ 0.770679] hid: raw HID events driver (C) Jiri Kosina1126alice # [ 0.760086] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 11127alice # [ 0.760277] ehci-pci 0000:00:07.0: irq 16, io mem 0x100860001128bob # [ 0.770861] usbcore: registered new interface driver usbhid1129clare # [ 0.769259] Synopsys Designware Multimedia Card Interface Driver1130bob # [ 0.770868] usbhid: USB HID core driver1131clare # [ 0.769424] sdhci-pltfm: SDHCI platform and OF driver helper1132clare # [ 0.770320] hid: raw HID events driver (C) Jiri Kosina1133alice # [ 0.766920] usbcore: registered new interface driver usbserial_generic1134clare # [ 0.770468] usbcore: registered new interface driver usbhid1135alice # [ 0.766981] usbserial: USB Serial support registered for generic1136clare # [ 0.770473] usbhid: USB HID core driver1137bob # [ 0.772712] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.001138bob # [ 0.773261] hub 1-0:1.0: USB hub found1139bob # [ 0.773295] hub 1-0:1.0: 6 ports detected1140alice # [ 0.768540] hv_vmbus: registering driver hyperv_keyboard1141alice # [ 0.769704] rtc-pl031 9010000.pl031: registered as rtc01142clare # [ 0.810550] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.001143bob # [ 0.774793] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1144alice # [ 0.769745] rtc-pl031 9010000.pl031: setting system clock to 2026-09-21T10:07:19 UTC (1789985239)1145bob # [ 0.775681] drop_monitor: Initializing network drop monitor service1146clare # [ 0.810950] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1147alice # [ 0.769930] i2c_dev: i2c /dev entries driver1148bob # [ 0.775825] NET: Registered PF_INET6 protocol family1149clare # [ 0.811846] drop_monitor: Initializing network drop monitor service1150clare # [ 0.811993] NET: Registered PF_INET6 protocol family1151clare # [ 0.812390] hub 1-0:1.0: USB hub found1152alice # [ 0.771978] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.001153bob # [ 0.778407] Segment Routing with IPv61154clare # [ 0.812425] hub 1-0:1.0: 6 ports detected1155alice # [ 0.772480] hub 1-0:1.0: USB hub found1156bob # [ 0.778447] In-situ OAM (IOAM) with IPv61157alice # [ 0.772512] hub 1-0:1.0: 6 ports detected1158bob # [ 0.778503] NET: Registered PF_PACKET protocol family1159clare # [ 0.816112] Segment Routing with IPv61160clare # [ 0.816149] In-situ OAM (IOAM) with IPv61161bob # [ 0.779596] 9pnet: Installing 9P2000 support1162bob # [ 0.779661] Key type dns_resolver registered1163clare # [ 0.816224] NET: Registered PF_PACKET protocol family1164alice # [ 0.775291] sdhci: Secure Digital Host Controller Interface driver1165alice # [ 0.775309] sdhci: Copyright(c) Pierre Ossman1166alice # [ 0.775449] Synopsys Designware Multimedia Card Interface Driver1167clare # [ 0.817441] 9pnet: Installing 9P2000 support1168bob # [ 0.783430] registered taskstats version 11169alice # [ 0.775622] sdhci-pltfm: SDHCI platform and OF driver helper1170clare # [ 0.817511] Key type dns_resolver registered1171bob # [ 0.783608] Loading compiled-in X.509 certificates1172alice # [ 0.776626] hid: raw HID events driver (C) Jiri Kosina1173alice # [ 0.776779] usbcore: registered new interface driver usbhid1174alice # [ 0.776784] usbhid: USB HID core driver1175alice # [ 0.780530] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1176clare # [ 0.821378] registered taskstats version 11177alice # [ 0.781454] drop_monitor: Initializing network drop monitor service1178clare # [ 0.821597] Loading compiled-in X.509 certificates1179alice # [ 0.781682] NET: Registered PF_INET6 protocol family1180alice # [ 0.784255] Segment Routing with IPv61181bob # [ 0.789323] Demotion targets for Node 0: null1182alice # [ 0.784288] In-situ OAM (IOAM) with IPv61183bob # [ 0.789440] Key type .fscrypt registered1184alice # [ 0.784347] NET: Registered PF_PACKET protocol family1185bob # [ 0.789446] Key type fscrypt-provisioning registered1186bob # [ 0.789581] ima: No TPM chip found, activating TPM-bypass!1187bob # [ 0.789595] ima: Allocated hash algorithm: sha11188clare # [ 0.827342] Demotion targets for Node 0: null1189clare # [ 0.827464] Key type .fscrypt registered1190bob # [ 0.789657] ima: No architecture policies found1191clare # [ 0.827470] Key type fscrypt-provisioning registered1192clare # [ 0.827602] ima: No TPM chip found, activating TPM-bypass!1193alice # [ 0.785497] 9pnet: Installing 9P2000 support1194clare # [ 0.827618] ima: Allocated hash algorithm: sha11195alice # [ 0.785561] Key type dns_resolver registered1196bob # [ 0.792930] input: gpio-keys as /devices/platform/gpio-keys/input/input01197clare # [ 0.827679] ima: No architecture policies found1198clare # [ 0.830960] input: gpio-keys as /devices/platform/gpio-keys/input/input01199alice # [ 0.789424] registered taskstats version 11200alice # [ 0.789592] Loading compiled-in X.509 certificates1201alice # [ 0.795762] Demotion targets for Node 0: null1202alice # [ 0.795892] Key type .fscrypt registered1203alice # [ 0.795899] Key type fscrypt-provisioning registered1204alice # [ 0.796020] ima: No TPM chip found, activating TPM-bypass!1205alice # [ 0.796036] ima: Allocated hash algorithm: sha11206alice # [ 0.796103] ima: No architecture policies found1207alice # [ 0.799025] input: gpio-keys as /devices/platform/gpio-keys/input/input01208bob # [ 0.804423] clk: Disabling unused clocks1209bob # [ 0.804454] PM: genpd: Disabling unused power domains1210clare # [ 0.842699] clk: Disabling unused clocks1211bob # [ 0.807857] Freeing unused kernel memory: 4736K1212clare # [ 0.842735] PM: genpd: Disabling unused power domains1213bob # [ 0.807982] Run /init as init process1214clare # [ 0.846209] Freeing unused kernel memory: 4736K1215clare # [ 0.846366] Run /init as init process1216alice # [ 0.811106] clk: Disabling unused clocks1217alice # [ 0.811138] PM: genpd: Disabling unused power domains1218alice # [ 0.814530] Freeing unused kernel memory: 4736K1219alice # [ 0.814662] Run /init as init process1220bob # [ 0.831871] systemd[1]: Successfully made /usr/ read-only.1221clare # [ 0.868650] systemd[1]: Successfully made /usr/ read-only.1222alice # [ 0.838805] systemd[1]: Successfully made /usr/ read-only.1223bob # [ 1.020177] usb 1-1: new high-speed USB device number 2 using ehci-pci1224clare # [ 1.056720] usb 1-1: new high-speed USB device number 2 using ehci-pci1225alice # [ 1.019260] usb 1-1: new high-speed USB device number 2 using ehci-pci1226bob # [ 1.166714] systemd[1]: systemd 261.2 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)1227bob # [ 1.173162] systemd[1]: Detected virtualization qemu.1228bob # [ 1.173624] systemd[1]: Detected architecture arm64.1229clare # [ 1.203510] systemd[1]: systemd 261.2 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)1230bob # [ 1.174348] systemd[1]: Running in initrd.1231clare # [ 1.211351] systemd[1]: Detected virtualization qemu.1232bob # [ 1.175494] systemd[1]: Initializing machine ID from random generator.1233bob # [ 1.176308] systemd[1]: Hostname set to <bob>.1234clare # [ 1.212555] systemd[1]: Detected architecture arm64.1235clare # [ 1.213848] systemd[1]: Running in initrd.1236alice # [ 1.169524] 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/input11237bob # [ 1.177532] 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/input11238clare # [ 1.216271] systemd[1]: Initializing machine ID from random generator.1239clare # [ 1.218603] systemd[1]: Hostname set to <clare>.1240alice # [ 1.173609] systemd[1]: systemd 261.2 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)1241alice # [ 1.181049] systemd[1]: Detected virtualization qemu.1242alice # [ 1.182322] systemd[1]: Detected architecture arm64.1243clare # [ 1.222482] 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/input11244alice # [ 1.183595] systemd[1]: Running in initrd.1245alice # [ 1.185578] systemd[1]: Initializing machine ID from random generator.1246alice # [ 1.187528] systemd[1]: Hostname set to <alice>.1247alice # [ 1.247440] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input01248bob # [ 1.256412] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input01249clare # [ 1.304913] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input01250alice # [ 1.367290] usb 1-2: new high-speed USB device number 3 using ehci-pci1251bob # [ 1.376175] usb 1-2: new high-speed USB device number 3 using ehci-pci1252bob # [ 1.380059] systemd[1]: bpf-restrict-fs: LSM BPF program attached1253clare # [ 1.424717] usb 1-2: new high-speed USB device number 3 using ehci-pci1254bob # [ 1.431255] systemd[1]: Queued start job for default target Initrd Default Target.1255bob # [ 1.442093] systemd[1]: Created slice Slice /system/modprobe.1256bob # [ 1.442826] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1257bob # [ 1.443520] systemd[1]: Expecting device /dev/disk/by-label/nixos...1258bob # [ 1.444066] systemd[1]: Reached target Path Units.1259bob # [ 1.444638] systemd[1]: Reached target Slice Units.1260bob # [ 1.445058] systemd[1]: Reached target Swaps.1261bob # [ 1.445406] systemd[1]: Reached target Timer Units.1262bob # [ 1.446010] systemd[1]: Listening on D-Bus System Message Bus Socket.1263bob # [ 1.446724] systemd[1]: Listening on Journal Socket (/dev/log).1264bob # [ 1.447355] systemd[1]: Listening on Journal Sockets.1265bob # [ 1.447877] systemd[1]: Listening on udev Control Socket.1266bob # [ 1.448555] systemd[1]: Listening on udev Kernel Socket.1267bob # [ 1.449026] systemd[1]: Reached target Socket Units.1268bob # [ 1.450687] systemd[1]: Starting Create List of Static Device Nodes...1269bob # [ 1.451302] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1270alice # [ 1.447060] systemd[1]: bpf-restrict-fs: LSM BPF program attached1271bob # [ 1.460287] systemd[1]: Mounting Kernel Configuration File System...1272clare # [ 1.499202] systemd[1]: bpf-restrict-fs: LSM BPF program attached1273bob # [ 1.468294] systemd[1]: Starting Journal Service...1274bob # [ 1.476508] systemd[1]: Starting Load Kernel Modules...1275bob # [ 1.477089] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1276bob # [ 1.483470] systemd[1]: Starting Coldplug All udev Devices...1277bob # [ 1.496238] systemd[1]: Finished Create List of Static Device Nodes.1278bob # [ 1.497177] systemd[1]: Mounted Kernel Configuration File System.1279alice # [ 1.506459] systemd[1]: Queued start job for default target Initrd Default Target.1280bob # [ 1.512448] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1281alice # [ 1.519266] 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/input21282alice # [ 1.522006] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01283alice # [ 1.523734] systemd[1]: Created slice Slice /system/modprobe.1284alice # [ 1.524609] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1285alice # [ 1.526376] systemd[1]: Expecting device /dev/disk/by-label/nixos...1286alice # [ 1.526985] systemd[1]: Reached target Path Units.1287alice # [ 1.527502] systemd[1]: Reached target Slice Units.1288alice # [ 1.527960] systemd[1]: Reached target Swaps.1289alice # [ 1.528372] systemd[1]: Reached target Timer Units.1290alice # [ 1.529188] systemd[1]: Listening on D-Bus System Message Bus Socket.1291alice # [ 1.529962] systemd[1]: Listening on Journal Socket (/dev/log).1292alice # [ 1.530836] systemd[1]: Listening on Journal Sockets.1293alice # [ 1.531468] systemd[1]: Listening on udev Control Socket.1294alice # [ 1.532083] systemd[1]: Listening on udev Kernel Socket.1295alice # [ 1.532598] systemd[1]: Reached target Socket Units.1296alice # [ 1.534506] systemd[1]: Starting Create List of Static Device Nodes...1297alice # [ 1.535162] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1298bob # [ 1.547253] systemd-journald[72]: Collecting audit messages is disabled.1299alice # [ 1.543057] systemd[1]: Mounting Kernel Configuration File System...1300bob # [ 1.548542] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1301bob # [ 1.550572] systemd[1]: Starting Create Static Device Nodes in /dev...1302alice # [ 1.551560] systemd[1]: Starting Journal Service...1303clare # [ 1.588945] 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/input21304clare # [ 1.590933] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01305clare # [ 1.592863] systemd[1]: Queued start job for default target Initrd Default Target.1306clare # [ 1.599137] systemd[1]: Created slice Slice /system/modprobe.1307clare # [ 1.600649] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1308clare # [ 1.601954] systemd[1]: Expecting device /dev/disk/by-label/nixos...1309clare # [ 1.603012] systemd[1]: Reached target Path Units.1310clare # [ 1.603768] systemd[1]: Reached target Slice Units.1311clare # [ 1.604553] systemd[1]: Reached target Swaps.1312clare # [ 1.605457] systemd[1]: Reached target Timer Units.1313clare # [ 1.606636] systemd[1]: Listening on D-Bus System Message Bus Socket.1314bob # [ 1.570300] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1315clare # [ 1.607866] systemd[1]: Listening on Journal Socket (/dev/log).1316bob # [ 1.571904] 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/input21317clare # [ 1.608970] systemd[1]: Listening on Journal Sockets.1318alice # [ 1.567395] systemd[1]: Starting Load Kernel Modules...1319bob # [ 1.572001] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01320alice # [ 1.567990] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1321clare # [ 1.610064] systemd[1]: Listening on udev Control Socket.1322clare # [ 1.611057] systemd[1]: Listening on udev Kernel Socket.1323clare # [ 1.611993] systemd[1]: Reached target Socket Units.1324alice # [ 1.570387] systemd[1]: Starting Coldplug All udev Devices...1325bob # [ 1.580199] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1326clare # [ 1.614719] systemd[1]: Starting Create List of Static Device Nodes...1327alice # [ 1.575795] systemd[1]: Finished Create List of Static Device Nodes.1328clare # [ 1.614819] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1329clare # [ 1.624890] systemd[1]: Mounting Kernel Configuration File System...1330bob # [ 1.593872] [drm] pci: virtio-gpu-pci detected at 0000:00:05.01331bob # [ 1.594104] [drm] features: -virgl +edid -resource_blob -host_visible1332bob # [ 1.594111] [drm] features: -context_init1333bob # [ 1.594672] [drm] number of scanouts: 11334bob # [ 1.594691] [drm] number of cap sets: 01335alice # [ 1.592762] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1336alice # [ 1.593245] systemd[1]: Mounted Kernel Configuration File System.1337clare # [ 1.636894] systemd[1]: Starting Journal Service...1338clare # [ 1.640979] systemd[1]: Starting Load Kernel Modules...1339clare # [ 1.641771] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1340bob # [ 1.608674] systemd[1]: Finished Create Static Device Nodes in /dev.1341bob # [ 1.608852] systemd[1]: Reached target Preparation for Local File Systems.1342bob # [ 1.608883] systemd[1]: Reached target Local File Systems.1343bob # [ 1.616478] systemd[1]: Starting Rule-based Manager for Device Events and Files...1344bob # [ 1.621073] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic1345bob # [ 1.621096] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 01346clare # [ 1.669121] systemd[1]: Starting Coldplug All udev Devices...1347alice # [ 1.635484] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1348alice # [ 1.637198] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1349bob # [ 1.640525] Console: switching to colour frame buffer device 160x501350bob # [ 1.643468] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device1351clare # [ 1.684872] systemd[1]: Finished Create List of Static Device Nodes.1352bob # [ 1.650921] systemd[1]: Started Journal Service.1353bob # [ 1.646356] systemd-modules-load[73]: Inserted module 'dm_mod'1354alice # [ 1.643597] systemd[1]: Starting Create Static Device Nodes in /dev...1355alice # [ 1.650872] systemd-journald[72]: Collecting audit messages is disabled.1356alice # [ 1.652563] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1357bob # [ 1.660381] systemd-modules-load[73]: Module 'virtio_balloon' is built in1358bob # [ 1.661264] systemd-modules-load[73]: Module 'virtio_console' is built in1359bob # [ 1.661988] systemd[1]: Starting Create System Files and Directories...1360bob # [ 1.662670] systemd-modules-load[73]: Inserted module 'virtio_gpu'1361bob # [ 1.663335] systemd-modules-load[73]: Module 'virtio_rng' is built in1362alice # [ 1.668774] [drm] pci: virtio-gpu-pci detected at 0000:00:05.01363alice # [ 1.669017] [drm] features: -virgl +edid -resource_blob -host_visible1364alice # [ 1.669023] [drm] features: -context_init1365clare # [ 1.708970] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1366alice # [ 1.669519] [drm] number of scanouts: 11367alice # [ 1.669535] [drm] number of cap sets: 01368clare # [ 1.713347] systemd-journald[72]: Collecting audit messages is disabled.1369bob # [ 1.673186] systemd[1]: Finished Load Kernel Modules.1370clare # [ 1.725166] systemd[1]: Mounted Kernel Configuration File System.1371alice # [ 1.683741] systemd[1]: Finished Create Static Device Nodes in /dev.1372alice # [ 1.683917] systemd[1]: Reached target Preparation for Local File Systems.1373alice # [ 1.683949] systemd[1]: Reached target Local File Systems.1374alice # [ 1.686548] systemd[1]: Starting Rule-based Manager for Device Events and Files...1375clare # [ 1.728734] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1376bob # [ 1.689961] systemd[1]: Finished Create System Files and Directories.1377bob # [ 1.696203] systemd[1]: Starting Apply Kernel Variables...1378clare # [ 1.740754] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1379alice # [ 1.695542] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic1380alice # [ 1.695563] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 01381clare # [ 1.742955] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1382bob # [ 1.702442] systemd-udevd[79]: Using default interface naming scheme 'v261'.1383clare # [ 1.757150] systemd[1]: Starting Create Static Device Nodes in /dev...1384alice # [ 1.711800] Console: switching to colour frame buffer device 160x501385clare # [ 1.762228] [drm] pci: virtio-gpu-pci detected at 0000:00:05.01386clare # [ 1.762454] [drm] features: -virgl +edid -resource_blob -host_visible1387clare # [ 1.762460] [drm] features: -context_init1388clare # [ 1.762922] [drm] number of scanouts: 11389clare # [ 1.762937] [drm] number of cap sets: 01390bob # [ 1.724467] systemd[1]: Finished Apply Kernel Variables.1391alice # [ 1.731760] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device1392bob # [ 1.736162] systemd[1]: Started Rule-based Manager for Device Events and Files.1393clare # [ 1.777030] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic1394clare # [ 1.777051] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 01395alice # [ 1.737081] systemd-modules-load[73]: Inserted module 'dm_mod'1396alice # [ 1.745179] systemd[1]: Started Journal Service.1397alice # [ 1.740763] systemd-modules-load[73]: Module 'virtio_balloon' is built in1398alice # [ 1.741554] systemd-modules-load[73]: Module 'virtio_console' is built in1399alice # [ 1.742270] systemd-modules-load[73]: Inserted module 'virtio_gpu'1400alice # [ 1.742907] systemd-modules-load[73]: Module 'virtio_rng' is built in1401alice # [ 1.743614] systemd[1]: Finished Load Kernel Modules.1402clare # [ 1.793858] systemd[1]: Finished Create Static Device Nodes in /dev.1403clare # [ 1.794048] systemd[1]: Reached target Preparation for Local File Systems.1404clare # [ 1.794079] systemd[1]: Reached target Local File Systems.1405clare # [ 1.797073] systemd[1]: Starting Rule-based Manager for Device Events and Files...1406alice # [ 1.756355] systemd[1]: Starting Apply Kernel Variables...1407clare # [ 1.801095] Console: switching to colour frame buffer device 160x501408clare # [ 1.801626] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device1409clare # [ 1.804905] systemd-modules-load[73]: Inserted module 'dm_mod'1410clare # [ 1.814425] systemd[1]: Started Journal Service.1411clare # [ 1.807053] systemd-modules-load[73]: Module 'virtio_balloon' is built in1412clare # [ 1.812364] systemd-modules-load[73]: Module 'virtio_console' is built in1413alice # [ 1.773001] systemd-udevd[79]: Using default interface naming scheme 'v261'.1414clare # [ 1.814975] systemd-modules-load[73]: Inserted module 'virtio_gpu'1415alice # [ 1.775361] systemd[1]: Starting Create System Files and Directories...1416clare # [ 1.816356] systemd-modules-load[73]: Module 'virtio_rng' is built in1417clare # [ 1.817715] systemd[1]: Finished Load Kernel Modules.1418clare # [ 1.830064] systemd[1]: Starting Apply Kernel Variables...1419bob # [ 1.799548] systemd[1]: Starting Virtual Console Setup...1420alice # [ 1.798438] systemd[1]: Finished Apply Kernel Variables.1421alice # [ 1.810855] systemd[1]: Finished Create System Files and Directories.1422clare # [ 1.852118] systemd[1]: Starting Create System Files and Directories...1423alice # [ 1.812119] systemd[1]: Started Rule-based Manager for Device Events and Files.1424bob # [ 1.840482] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.1425clare # [ 1.877903] systemd[1]: Finished Apply Kernel Variables.1426bob # [ 1.843030] systemd[1]: Finished Virtual Console Setup.1427clare # [ 1.882092] systemd-udevd[79]: Using default interface naming scheme 'v261'.1428clare # [ 1.891144] systemd[1]: Finished Create System Files and Directories.1429alice # [ 1.856234] systemd[1]: Starting Virtual Console Setup...1430clare # [ 1.912965] systemd[1]: Started Rule-based Manager for Device Events and Files.1431alice # [ 1.892409] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.1432alice # [ 1.894928] systemd[1]: Finished Virtual Console Setup.1433clare # [ 1.963967] systemd[1]: Starting Virtual Console Setup...1434clare # [ 2.000617] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.1435clare # [ 2.002936] systemd[1]: Finished Virtual Console Setup.1436bob # [ 2.061565] systemd[1]: Finished Coldplug All udev Devices.1437bob # [ 2.062187] systemd[1]: Reached target System Initialization.1438bob # [ 2.062712] systemd[1]: Reached target Basic System.1439alice # [ 2.115668] systemd[1]: Finished Coldplug All udev Devices.1440alice # [ 2.116349] systemd[1]: Reached target System Initialization.1441alice # [ 2.116871] systemd[1]: Reached target Basic System.1442bob # [ 2.180365] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.1443clare # [ 2.219206] systemd[1]: Finished Coldplug All udev Devices.1444clare # [ 2.219825] systemd[1]: Reached target System Initialization.1445clare # [ 2.220501] systemd[1]: Reached target Basic System.1446bob # [ 2.195462] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.1447alice # [ 2.210509] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.1448alice # [ 2.241660] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.1449bob # [ 2.265663] systemd[1]: Found device /dev/disk/by-label/nixos.1450bob # [ 2.268821] systemd[1]: Reached target Initrd Root Device.1451bob # [ 2.275714] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1452clare # [ 2.318995] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.1453bob # [ 2.307503] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks1454clare # [ 2.344194] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.1455bob # [ 2.311309] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1456alice # [ 2.315241] systemd[1]: Found device /dev/disk/by-label/nixos.1457alice # [ 2.319712] systemd[1]: Reached target Initrd Root Device.1458alice # [ 2.321585] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1459alice # [ 2.366261] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks1460alice # [ 2.370696] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1461clare # [ 2.423659] systemd[1]: Found device /dev/disk/by-label/nixos.1462clare # [ 2.427392] systemd[1]: Reached target Initrd Root Device.1463clare # [ 2.430784] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1464clare # [ 2.469949] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks1465clare # [ 2.474447] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1466bob # [ 2.487311] systemd[1]: Mounting /sysroot...1467bob # [ 2.521435] EXT4-fs (vda): mounted filesystem 8e51f2ba-7b6a-436e-89fa-572b3da529ec r/w with ordered data mode. Quota mode: none.1468bob # [ 2.517220] systemd[1]: Mounted /sysroot.1469bob # [ 2.518805] systemd[1]: Reached target Initrd Root File System.1470bob # [ 2.522397] systemd[1]: Starting Mountpoints Configured in the Real Root...1471bob # [ 2.543801] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.1472bob # [ 2.550826] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...1473bob # [ 2.554113] systemd[1]: Reloading...1474alice # [ 2.566168] systemd[1]: Mounting /sysroot...1475alice # [ 2.601199] EXT4-fs (vda): mounted filesystem 58a30e0a-f96c-42d0-ae38-0862f6802fe9 r/w with ordered data mode. Quota mode: none.1476alice # [ 2.598376] systemd[1]: Mounted /sysroot.1477alice # [ 2.599107] systemd[1]: Reached target Initrd Root File System.1478alice # [ 2.601194] systemd[1]: Starting Mountpoints Configured in the Real Root...1479alice # [ 2.623087] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.1480clare # [ 2.663997] systemd[1]: Mounting /sysroot...1481alice # [ 2.628881] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...1482alice # [ 2.632133] systemd[1]: Reloading...1483clare # [ 2.701559] EXT4-fs (vda): mounted filesystem eec86074-08cc-4b2f-b215-04f44adf23c8 r/w with ordered data mode. Quota mode: none.1484clare # [ 2.696604] systemd[1]: Mounted /sysroot.1485clare # [ 2.697613] systemd[1]: Reached target Initrd Root File System.1486clare # [ 2.702686] systemd[1]: Mounting /sysroot/nix/.ro-store...1487clare # [ 2.716340] systemd[1]: Mounting /sysroot/nix/.rw-store...1488clare # [ 2.722486] systemd[1]: Mounting /sysroot/run...1489clare # [ 2.732864] systemd[1]: Mounting /sysroot/tmp/shared...1490clare # [ 2.754589] systemd[1]: Mounting /sysroot/tmp/xchg...1491bob # [ 2.730962] systemd[1]: Reloading finished in 177 ms.1492clare # [ 2.792520] fuse: init (API version 7.45)1493clare # [ 2.788343] systemd[1]: Starting Mountpoints Configured in the Real Root...1494bob # [ 2.760982] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1495bob # [ 2.762664] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1496clare # [ 2.809574] virtiofs virtio6: discovered new tag: nix-store1497bob # [ 2.767982] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1498clare # [ 2.810159] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1499bob # [ 2.770646] systemd[1]: Finished Mountpoints Configured in the Real Root.1500bob # [ 2.771913] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1501clare # [ 2.827224] virtiofs virtio7: discovered new tag: shared1502clare # [ 2.827821] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1503clare # [ 2.836041] virtiofs virtio8: discovered new tag: xchg1504clare # [ 2.836627] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1505clare # [ 2.841546] systemd-sysroot-fstab-check[123]: /sysroot should be mounted in the initrd, will request daemon-reload.1506alice # [ 2.813231] systemd[1]: Reloading finished in 181 ms.1507clare # [ 2.863997] systemd[1]: Mounted /sysroot/nix/.rw-store.1508clare # [ 2.872353] systemd[1]: Mounted /sysroot/nix/.ro-store.1509clare # [ 2.872975] systemd[1]: Mounted /sysroot/run.1510clare # [ 2.874302] systemd[1]: Mounted /sysroot/tmp/shared.1511clare # [ 2.874857] systemd[1]: Mounted /sysroot/tmp/xchg.1512alice # [ 2.836554] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1513clare # [ 2.877086] systemd[1]: Reload requested from client PID 123 ('systemd-sysroot') (unit initrd-parse-etc.service)...1514clare # [ 2.879990] systemd[1]: Reloading...1515alice # [ 2.839703] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1516alice # [ 2.843235] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1517alice # [ 2.845430] systemd[1]: Finished Mountpoints Configured in the Real Root.1518alice # [ 2.846779] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1519clare # [ 3.076397] systemd[1]: Reloading finished in 203 ms.1520clare # [ 3.107857] systemd-sysroot-fstab-check[123]: Requesting initrd-fs.target/start/replace...1521clare # [ 3.113683] systemd-sysroot-fstab-check[123]: Requesting swap.target/start/replace...1522clare # [ 3.117497] systemd[1]: Starting rw-sysroot-nix-store.service...1523clare # [ 3.119006] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1524clare # [ 3.126829] systemd[1]: Finished Mountpoints Configured in the Real Root.1525clare # [ 3.127629] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1526clare # [ 3.139237] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1527clare # [ 3.140380] systemd[1]: Finished rw-sysroot-nix-store.service.1528alice # [ 3.109016] fuse: init (API version 7.45)1529bob # [ 3.115432] fuse: init (API version 7.45)1530bob # [ 3.120066] virtiofs virtio6: discovered new tag: nix-store1531bob # [ 3.126673] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory1532bob # [ 3.138016] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1533alice # [ 3.133849] virtiofs virtio6: discovered new tag: nix-store1534alice # [ 3.134381] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1535bob # [ 3.132816] (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 directory1536alice # [ 3.141595] (udev-worker)[100]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory1537bob # [ 3.154867] virtiofs virtio7: discovered new tag: shared1538bob # [ 3.155401] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1539alice # [ 3.143954] (udev-worker)[100]: 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 directory1540bob # [ 3.152768] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1541bob # [ 3.162202] virtiofs virtio8: discovered new tag: xchg1542bob # [ 3.162726] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1543bob # [ 3.156486] systemd[1]: Stopped Virtual Console Setup.1544alice # [ 3.159069] virtiofs virtio7: discovered new tag: shared1545bob # [ 3.157174] systemd[1]: Stopping Virtual Console Setup...1546bob # [ 3.159308] systemd[1]: Starting Virtual Console Setup...1547alice # [ 3.165879] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1548alice # [ 3.169405] virtiofs virtio8: discovered new tag: xchg1549alice # [ 3.169913] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1550alice # [ 3.170037] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1551alice # [ 3.170950] systemd[1]: Stopped Virtual Console Setup.1552alice # [ 3.171550] systemd[1]: Stopping Virtual Console Setup...1553alice # [ 3.173467] systemd[1]: Starting Virtual Console Setup...1554bob # [ 3.188382] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones.1555bob # [ 3.190581] systemd[1]: Finished Virtual Console Setup.1556alice # [ 3.199647] systemd-vconsole-setup[136]: Configuration of first virtual console was skipped, ignoring remaining ones.1557alice # [ 3.202219] systemd[1]: Finished Virtual Console Setup.1558clare # [ 3.400131] (udev-worker)[90]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory1559clare # [ 3.403745] (udev-worker)[90]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1560clare # [ 3.423408] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1561clare # [ 3.424364] systemd[1]: Stopped Virtual Console Setup.1562clare # [ 3.428584] systemd[1]: Stopping Virtual Console Setup...1563clare # [ 3.429128] systemd[1]: Starting Virtual Console Setup...1564clare # [ 3.452234] systemd-vconsole-setup[153]: Configuration of first virtual console was skipped, ignoring remaining ones.1565clare # [ 3.454456] systemd[1]: Finished Virtual Console Setup.1566bob # [ 3.489556] systemd[1]: Mounting /sysroot/nix/.ro-store...1567bob # [ 3.497539] systemd[1]: Mounting /sysroot/nix/.rw-store...1568bob # [ 3.511400] systemd[1]: Mounting /sysroot/run...1569bob # [ 3.529830] systemd[1]: Mounting /sysroot/tmp/shared...1570bob # [ 3.557220] systemd[1]: Mounting /sysroot/tmp/xchg...1571bob # [ 3.565861] systemd[1]: Mounted /sysroot/nix/.ro-store.1572alice # [ 3.567289] systemd[1]: Mounting /sysroot/nix/.ro-store...1573alice # [ 3.576212] systemd[1]: Mounting /sysroot/nix/.rw-store...1574bob # [ 3.583997] systemd[1]: Mounted /sysroot/run.1575alice # [ 3.581218] systemd[1]: Mounting /sysroot/run...1576bob # [ 3.589046] systemd[1]: Mounted /sysroot/nix/.rw-store.1577bob # [ 3.594249] systemd[1]: Starting rw-sysroot-nix-store.service...1578alice # [ 3.599197] systemd[1]: Mounting /sysroot/tmp/shared...1579bob # [ 3.605456] systemd[1]: Mounted /sysroot/tmp/shared.1580alice # [ 3.601197] systemd[1]: Mounting /sysroot/tmp/xchg...1581bob # [ 3.609831] systemd[1]: Mounted /sysroot/tmp/xchg.1582alice # [ 3.610535] systemd[1]: Mounted /sysroot/nix/.ro-store.1583alice # [ 3.613164] systemd[1]: Mounted /sysroot/nix/.rw-store.1584bob # [ 3.623911] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1585bob # [ 3.625245] systemd[1]: Finished rw-sysroot-nix-store.service.1586clare # [ 3.663472] systemd[1]: Mounting /sysroot/nix/store...1587alice # [ 3.633688] systemd[1]: Starting rw-sysroot-nix-store.service...1588alice # [ 3.638716] systemd[1]: Mounted /sysroot/run.1589alice # [ 3.643915] systemd[1]: Mounted /sysroot/tmp/shared.1590clare # [ 3.686445] systemd[1]: Mounted /sysroot/nix/store.1591alice # [ 3.646763] systemd[1]: Mounted /sysroot/tmp/xchg.1592clare # [ 3.687680] systemd[1]: Reached target Initrd File Systems.1593clare # [ 3.689749] systemd[1]: Starting Find NixOS closure...1594alice # [ 3.655341] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1595clare # [ 3.695685] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1596alice # [ 3.656498] systemd[1]: Finished rw-sysroot-nix-store.service.1597clare # [ 3.719381] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1598clare # [ 3.721048] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1599clare # [ 3.732965] systemd[1]: Finished Find NixOS closure.1600clare # [ 3.734196] systemd[1]: Reached target Initrd Default Target.1601clare # [ 3.736162] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1602clare # [ 3.759857] systemd[1]: Stopped target Initrd Default Target.1603clare # [ 3.761153] systemd[1]: Stopped target Basic System.1604clare # [ 3.761942] systemd[1]: Stopped target Initrd Root Device.1605clare # [ 3.764151] systemd[1]: Stopped target Path Units.1606clare # [ 3.764891] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1607clare # [ 3.765931] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1608clare # [ 3.768207] systemd[1]: Stopped target Slice Units.1609clare # [ 3.768733] systemd[1]: Stopped target Socket Units.1610clare # [ 3.769636] systemd[1]: Stopped target System Initialization.1611clare # [ 3.771822] systemd[1]: Stopped target Swaps.1612clare # [ 3.773484] systemd[1]: Stopped target Timer Units.1613clare # [ 3.776189] systemd[1]: dbus.socket: Deactivated successfully.1614clare # [ 3.778355] systemd[1]: Closed D-Bus System Message Bus Socket.1615clare # [ 3.778955] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1616clare # [ 3.780897] systemd[1]: Stopped Find NixOS closure.1617clare # [ 3.782277] systemd[1]: Starting rw-sysroot-nix-store.service...1618clare # [ 3.786870] systemd[1]: systemd-sysctl.service: Deactivated successfully.1619clare # [ 3.788689] systemd[1]: Stopped Apply Kernel Variables.1620clare # [ 3.789219] systemd[1]: systemd-modules-load.service: Deactivated successfully.1621clare # [ 3.791838] systemd[1]: Stopped Load Kernel Modules.1622clare # [ 3.792525] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1623clare # [ 3.793192] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1624clare # [ 3.793831] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1625clare # [ 3.794425] systemd[1]: Stopped Create System Files and Directories.1626clare # [ 3.794959] systemd[1]: Stopped target Local File Systems.1627clare # [ 3.795469] systemd[1]: Stopped target Preparation for Local File Systems.1628clare # [ 3.797870] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1629clare # [ 3.798692] systemd[1]: Stopped Coldplug All udev Devices.1630clare # [ 3.799224] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1631clare # [ 3.799901] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1632clare # [ 3.800714] systemd[1]: Stopped Virtual Console Setup.1633clare # [ 3.801204] systemd[1]: initrd-cleanup.service: Deactivated successfully.1634clare # [ 3.811043] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1635clare # [ 3.811870] systemd[1]: systemd-udevd.service: Deactivated successfully.1636clare # [ 3.812635] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1637clare # [ 3.815028] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1638clare # [ 3.816589] systemd[1]: Closed udev Control Socket.1639clare # [ 3.817965] systemd[1]: Starting Cleanup udev Database...1640clare # [ 3.819342] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1641clare # [ 3.823910] systemd[1]: Stopped Create Static Device Nodes in /dev.1642clare # [ 3.824970] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1643clare # [ 3.825691] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1644clare # [ 3.826315] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1645clare # [ 3.826948] systemd[1]: Stopped Create List of Static Device Nodes.1646clare # [ 3.827507] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1647clare # [ 3.829331] systemd[1]: Finished rw-sysroot-nix-store.service.1648clare # [ 3.851427] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1649clare # [ 3.852353] systemd[1]: Finished Cleanup udev Database.1650clare # [ 3.852856] systemd[1]: Reached target Switch Root.1651clare # [ 3.857701] systemd[1]: Starting NixOS Activation...1652clare # [ 3.943745] initrd-nixos-activation-start[177]: booting system configuration /nix/store/48d43zfi2vli8nvl465idklqygs42ym9-nixos-system-clare-test1653clare # [ 3.973210] initrd-nixos-activation-start[177]: running activation script...1654clare # [ 4.219107] initrd-nixos-activation-start[200]: setting up /etc...1655clare # [ 4.302846] initrd-nixos-activation-start[200]: setting up age secrets...1656clare # [ 4.382478] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1657clare # [ 4.384194] systemd[1]: Finished NixOS Activation.1658clare # [ 4.385194] systemd[1]: Starting Switch Root...1659clare # [ 4.405313] systemd[1]: Switching root.1660bob # [ 4.486373] systemd[1]: Mounting /sysroot/nix/store...1661bob # [ 4.515396] systemd[1]: Mounted /sysroot/nix/store.1662bob # [ 4.516901] systemd[1]: Reached target Initrd File Systems.1663bob # [ 4.518288] systemd[1]: Starting Find NixOS closure...1664bob # [ 4.523373] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1665clare # [ 4.570753] systemd-journald[72]: Received SIGTERM from PID 1 (systemd).1666bob # [ 4.536262] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1667bob # [ 4.537084] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1668bob # [ 4.558269] systemd[1]: Finished Find NixOS closure.1669bob # [ 4.558863] systemd[1]: Reached target Initrd Default Target.1670bob # [ 4.560163] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1671alice # [ 4.566251] systemd[1]: Mounting /sysroot/nix/store...1672bob # [ 4.582086] systemd[1]: Stopped target Initrd Default Target.1673bob # [ 4.583269] systemd[1]: Stopped target Basic System.1674bob # [ 4.587299] systemd[1]: Stopped target Initrd Root Device.1675bob # [ 4.588224] systemd[1]: Stopped target Path Units.1676bob # [ 4.588722] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1677bob # [ 4.589419] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1678bob # [ 4.590468] systemd[1]: Stopped target Slice Units.1679bob # [ 4.591441] systemd[1]: Stopped target Socket Units.1680bob # [ 4.592473] systemd[1]: Stopped target System Initialization.1681bob # [ 4.593736] systemd[1]: Stopped target Swaps.1682bob # [ 4.594860] systemd[1]: Stopped target Timer Units.1683bob # [ 4.596854] systemd[1]: dbus.socket: Deactivated successfully.1684bob # [ 4.600410] systemd[1]: Closed D-Bus System Message Bus Socket.1685alice # [ 4.596453] systemd[1]: Mounted /sysroot/nix/store.1686bob # [ 4.601071] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1687alice # [ 4.598004] systemd[1]: Reached target Initrd File Systems.1688bob # [ 4.603547] systemd[1]: Stopped Find NixOS closure.1689alice # [ 4.598945] systemd[1]: Starting Find NixOS closure...1690bob # [ 4.607868] systemd[1]: Starting rw-sysroot-nix-store.service...1691bob # [ 4.609710] systemd[1]: systemd-sysctl.service: Deactivated successfully.1692bob # [ 4.610368] systemd[1]: Stopped Apply Kernel Variables.1693bob # [ 4.610869] systemd[1]: systemd-modules-load.service: Deactivated successfully.1694bob # [ 4.611518] systemd[1]: Stopped Load Kernel Modules.1695alice # [ 4.608544] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1696bob # [ 4.614512] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1697bob # [ 4.615344] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1698bob # [ 4.616183] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1699bob # [ 4.616864] systemd[1]: Stopped Create System Files and Directories.1700bob # [ 4.617457] systemd[1]: Stopped target Local File Systems.1701bob # [ 4.617985] systemd[1]: Stopped target Preparation for Local File Systems.1702bob # [ 4.618622] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1703bob # [ 4.619320] systemd[1]: Stopped Coldplug All udev Devices.1704bob # [ 4.619852] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1705bob # [ 4.628600] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1706bob # [ 4.631506] systemd[1]: Stopped Virtual Console Setup.1707bob # [ 4.634496] systemd[1]: initrd-cleanup.service: Deactivated successfully.1708bob # [ 4.635234] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1709bob # [ 4.635812] systemd[1]: systemd-udevd.service: Deactivated successfully.1710bob # [ 4.637571] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1711bob # [ 4.640470] systemd[1]: systemd-udevd.service: Consumed 1.029s CPU time over 3.021s wall clock time, 20.7M memory peak.1712bob # [ 4.641540] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1713alice # [ 4.637446] systemd[1]: Finished Find NixOS closure.1714bob # [ 4.643432] systemd[1]: Closed udev Control Socket.1715bob # [ 4.643994] systemd[1]: Starting Cleanup udev Database...1716alice # [ 4.640389] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1717bob # [ 4.645331] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1718alice # [ 4.642087] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1719bob # [ 4.648218] systemd[1]: Stopped Create Static Device Nodes in /dev.1720alice # [ 4.644358] systemd[1]: Reached target Initrd Default Target.1721bob # [ 4.648880] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1722bob # [ 4.650541] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1723alice # [ 4.646152] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1724bob # [ 4.651311] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1725bob # [ 4.652386] systemd[1]: Stopped Create List of Static Device Nodes.1726bob # [ 4.653199] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1727bob # [ 4.653839] systemd[1]: Finished rw-sysroot-nix-store.service.1728alice # [ 4.669212] systemd[1]: Stopped target Initrd Default Target.1729alice # [ 4.670708] systemd[1]: Stopped target Basic System.1730alice # [ 4.672265] systemd[1]: Stopped target Initrd Root Device.1731alice # [ 4.673780] systemd[1]: Stopped target Path Units.1732bob # [ 4.678616] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1733bob # [ 4.679457] systemd[1]: Finished Cleanup udev Database.1734alice # [ 4.676242] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1735alice # [ 4.677004] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1736alice # [ 4.678356] systemd[1]: Stopped target Slice Units.1737bob # [ 4.679984] systemd[1]: Reached target Switch Root.1738alice # [ 4.680161] systemd[1]: Stopped target Socket Units.1739bob # [ 4.684977] systemd[1]: Starting NixOS Activation...1740alice # [ 4.680701] systemd[1]: Stopped target System Initialization.1741alice # [ 4.681792] systemd[1]: Stopped target Swaps.1742alice # [ 4.683139] systemd[1]: Stopped target Timer Units.1743alice # [ 4.684194] systemd[1]: dbus.socket: Deactivated successfully.1744alice # [ 4.687867] systemd[1]: Closed D-Bus System Message Bus Socket.1745alice # [ 4.688599] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1746alice # [ 4.689289] systemd[1]: Stopped Find NixOS closure.1747alice # [ 4.689876] systemd[1]: Starting rw-sysroot-nix-store.service...1748alice # [ 4.690458] systemd[1]: systemd-sysctl.service: Deactivated successfully.1749alice # [ 4.692764] systemd[1]: Stopped Apply Kernel Variables.1750alice # [ 4.693669] systemd[1]: systemd-modules-load.service: Deactivated successfully.1751alice # [ 4.701355] systemd[1]: Stopped Load Kernel Modules.1752alice # [ 4.701981] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1753alice # [ 4.702880] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1754alice # [ 4.703600] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1755alice # [ 4.712320] systemd[1]: Stopped Create System Files and Directories.1756alice # [ 4.712984] systemd[1]: Stopped target Local File Systems.1757alice # [ 4.713502] systemd[1]: Stopped target Preparation for Local File Systems.1758alice # [ 4.714125] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1759alice # [ 4.714787] systemd[1]: Stopped Coldplug All udev Devices.1760alice # [ 4.715288] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1761alice # [ 4.715942] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1762alice # [ 4.719764] systemd[1]: Stopped Virtual Console Setup.1763alice # [ 4.720552] systemd[1]: initrd-cleanup.service: Deactivated successfully.1764alice # [ 4.721160] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1765alice # [ 4.721770] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1766alice # [ 4.722422] systemd[1]: Finished rw-sysroot-nix-store.service.1767alice # [ 4.727547] systemd[1]: systemd-udevd.service: Deactivated successfully.1768alice # [ 4.728492] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1769alice # [ 4.729860] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1770alice # [ 4.732128] systemd[1]: Closed udev Control Socket.1771alice # [ 4.732639] systemd[1]: Starting Cleanup udev Database...1772alice # [ 4.735536] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1773alice # [ 4.736851] systemd[1]: Stopped Create Static Device Nodes in /dev.1774alice # [ 4.737409] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1775alice # [ 4.738122] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1776alice # [ 4.738747] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1777alice # [ 4.739358] systemd[1]: Stopped Create List of Static Device Nodes.1778bob # [ 4.759203] initrd-nixos-activation-start[174]: booting system configuration /nix/store/d8kcs93jcill7b2299xbjmwnzczl6sbz-nixos-system-bob-test1779alice # [ 4.763294] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1780alice # [ 4.768340] systemd[1]: Finished Cleanup udev Database.1781alice # [ 4.773783] systemd[1]: Reached target Switch Root.1782alice # [ 4.777956] systemd[1]: Starting NixOS Activation...1783bob # [ 4.821517] initrd-nixos-activation-start[174]: running activation script...1784alice # [ 5.200384] initrd-nixos-activation-start[171]: booting system configuration /nix/store/mkf3xhkpx4y16h0rmmhhsxkxj82z03ph-nixos-system-alice-test1785alice # [ 5.249012] initrd-nixos-activation-start[171]: running activation script...1786bob # [ 5.255112] initrd-nixos-activation-start[197]: setting up /etc...1787bob # [ 5.413647] initrd-nixos-activation-start[197]: setting up age secrets...1788bob # [ 5.550583] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1789bob # [ 5.552200] systemd[1]: Finished NixOS Activation.1790bob # [ 5.554031] systemd[1]: Starting Switch Root...1791bob # [ 5.586170] systemd[1]: Switching root.1792alice # [ 5.707589] initrd-nixos-activation-start[194]: setting up /etc...1793bob # [ 5.779983] systemd-journald[72]: Received SIGTERM from PID 1 (systemd).1794alice # [ 5.967812] initrd-nixos-activation-start[194]: setting up age secrets...1795alice # [ 6.109360] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1796alice # [ 6.111673] systemd[1]: Finished NixOS Activation.1797alice # [ 6.112721] systemd[1]: Starting Switch Root...1798alice # [ 6.134666] systemd[1]: Switching root.1799alice # [ 6.305676] systemd-journald[72]: Received SIGTERM from PID 1 (systemd).1800clare # [ 6.382365] systemd[1]: systemd 261.2 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)1801clare # [ 6.385471] systemd[1]: Detected virtualization qemu.1802clare # [ 6.386646] systemd[1]: Detected architecture arm64.1803clare # [ 6.388286] systemd[1]: Detected first boot.1804clare # [ 6.390316] systemd[1]: Initializing machine ID from random generator.1805bob # [ 6.479386] systemd[1]: systemd 261.2 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 # [ 6.482626] systemd[1]: Detected virtualization qemu.1807bob # [ 6.483813] systemd[1]: Detected architecture arm64.1808bob # [ 6.485563] systemd[1]: Detected first boot.1809bob # [ 6.488090] systemd[1]: Initializing machine ID from random generator.1810clare # [ 6.631451] systemd[1]: bpf-restrict-fs: LSM BPF program attached1811clare # [ 6.759601] systemd[1]: Applying preset policy.1812bob # [ 6.724550] systemd[1]: bpf-restrict-fs: LSM BPF program attached1813bob # [ 6.853072] systemd[1]: Applying preset policy.1814clare # [ 6.924501] systemd[1]: Populated /etc with preset unit settings.1815alice # [ 6.981016] systemd[1]: systemd 261.2 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)1816alice # [ 6.983948] systemd[1]: Detected virtualization qemu.1817alice # [ 6.984869] systemd[1]: Detected architecture arm64.1818alice # [ 6.986204] systemd[1]: Detected first boot.1819alice # [ 6.988457] systemd[1]: Initializing machine ID from random generator.1820bob # [ 7.045588] systemd[1]: Populated /etc with preset unit settings.1821clare # [ 7.137336] systemd[1]: initrd-switch-root.service: Deactivated successfully.1822clare # [ 7.138306] systemd[1]: Stopped initrd-switch-root.service.1823clare # [ 7.141449] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1824clare # [ 7.143607] systemd[1]: Created slice Slice /system/getty.1825clare # [ 7.145585] systemd[1]: Created slice User and Session Slice.1826clare # [ 7.146765] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1827clare # [ 7.148110] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1828clare # [ 7.150071] systemd[1]: Expecting device /dev/hvc0...1829clare # [ 7.151604] systemd[1]: Expecting device /dev/ttyAMA0...1830clare # [ 7.153412] systemd[1]: Reached target Local Encrypted Volumes.1831clare # [ 7.154408] systemd[1]: Stopped target initrd-fs.target.1832clare # [ 7.155510] systemd[1]: Stopped target initrd-root-fs.target.1833clare # [ 7.157353] systemd[1]: Stopped target initrd-switch-root.target.1834clare # [ 7.159018] systemd[1]: Reached target Virtual Machines and Containers.1835clare # [ 7.160834] systemd[1]: Reached target Path Units.1836clare # [ 7.162482] systemd[1]: Reached target Remote File Systems.1837clare # [ 7.164084] systemd[1]: Reached target Slice Units.1838clare # [ 7.165660] systemd[1]: Reached target Swaps.1839clare # [ 7.168127] systemd[1]: Listening on Query the User Interactively for a Password.1840clare # [ 7.173353] systemd[1]: Listening on Process Core Dump Socket.1841clare # [ 7.177933] systemd[1]: Listening on Credential Encryption/Decryption.1842clare # [ 7.180649] systemd[1]: Listening on Factory Reset Management.1843clare # [ 7.181883] systemd[1]: Listening on Hostname Service Socket.1844clare # [ 7.185537] systemd[1]: Starting Journal Log Access Socket...1845clare # [ 7.187757] systemd[1]: Listening on Journal Audit Socket.1846clare # [ 7.191861] systemd[1]: Listening on Console Output Muting Service Socket.1847clare # [ 7.194078] systemd[1]: Listening on Network Management Metrics Varlink Socket.1848clare # [ 7.195553] systemd[1]: Listening on Network Management Varlink Socket.1849clare # [ 7.197672] systemd[1]: Listening on Network Management Netlink Socket.1850clare # [ 7.199511] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1851clare # [ 7.201027] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1852clare # [ 7.202697] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1853clare # [ 7.206848] systemd[1]: Listening on Disk Repartitioning Service Socket.1854clare # [ 7.208027] systemd[1]: Listening on Resolve Monitor Varlink Socket.1855clare # [ 7.209214] systemd[1]: Listening on Resolve Service Varlink Socket.1856clare # [ 7.210706] systemd[1]: Listening on udev Control Socket.1857clare # [ 7.212137] systemd[1]: Listening on udev Varlink Socket.1858clare # [ 7.214973] systemd[1]: Mounting Huge Pages File System...1859clare # [ 7.217747] systemd[1]: Mounting POSIX Message Queue File System...1860clare # [ 7.225099] systemd[1]: Mounting Kernel Debug File System...1861clare # [ 7.238364] systemd[1]: Mounting Kernel Trace File System...1862clare # [ 7.250880] systemd[1]: Starting Create List of Static Device Nodes...1863clare # [ 7.253313] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1864clare # [ 7.268210] systemd[1]: Mounting Kernel Configuration File System...1865clare # [ 7.272844] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1866clare # [ 7.277488] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1867alice # [ 7.237394] systemd[1]: bpf-restrict-fs: LSM BPF program attached1868clare # [ 7.283352] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1869bob # [ 7.267452] systemd[1]: initrd-switch-root.service: Deactivated successfully.1870bob # [ 7.268442] systemd[1]: Stopped initrd-switch-root.service.1871clare # [ 7.307891] systemd[1]: Mounting FUSE Control File System...1872bob # [ 7.271557] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1873clare # [ 7.308374] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671874bob # [ 7.274895] systemd[1]: Created slice Slice /system/getty.1875bob # [ 7.276649] systemd[1]: Created slice User and Session Slice.1876bob # [ 7.277720] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1877bob # [ 7.278948] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1878bob # [ 7.280683] systemd[1]: Expecting device /dev/hvc0...1879bob # [ 7.282029] systemd[1]: Expecting device /dev/ttyAMA0...1880bob # [ 7.283453] systemd[1]: Reached target Local Encrypted Volumes.1881bob # [ 7.285133] systemd[1]: Stopped target initrd-fs.target.1882bob # [ 7.286048] systemd[1]: Stopped target initrd-root-fs.target.1883bob # [ 7.287324] systemd[1]: Stopped target initrd-switch-root.target.1884bob # [ 7.288785] systemd[1]: Reached target Virtual Machines and Containers.1885bob # [ 7.290417] systemd[1]: Reached target Path Units.1886bob # [ 7.291868] systemd[1]: Reached target Remote File Systems.1887clare # [ 7.328928] systemd[1]: Starting Journal Service...1888bob # [ 7.293476] systemd[1]: Reached target Slice Units.1889bob # [ 7.294892] systemd[1]: Reached target Swaps.1890bob # [ 7.297608] systemd[1]: Listening on Query the User Interactively for a Password.1891bob # [ 7.300809] systemd[1]: Listening on Process Core Dump Socket.1892bob # [ 7.303146] systemd[1]: Listening on Credential Encryption/Decryption.1893clare # [ 7.340452] systemd[1]: Starting Load Kernel Modules...1894bob # [ 7.305763] systemd[1]: Listening on Factory Reset Management.1895bob # [ 7.306878] systemd[1]: Listening on Hostname Service Socket.1896bob # [ 7.310544] systemd[1]: Starting Journal Log Access Socket...1897bob # [ 7.312424] systemd[1]: Listening on Journal Audit Socket.1898bob # [ 7.315383] systemd[1]: Listening on Console Output Muting Service Socket.1899bob # [ 7.317376] systemd[1]: Listening on Network Management Metrics Varlink Socket.1900bob # [ 7.319775] systemd[1]: Listening on Network Management Varlink Socket.1901bob # [ 7.321824] systemd[1]: Listening on Network Management Netlink Socket.1902bob # [ 7.324279] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1903bob # [ 7.326103] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1904bob # [ 7.327775] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1905clare # [ 7.367861] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1906bob # [ 7.335895] systemd[1]: Listening on Disk Repartitioning Service Socket.1907bob # [ 7.337196] systemd[1]: Listening on Resolve Monitor Varlink Socket.1908bob # [ 7.338374] systemd[1]: Listening on Resolve Service Varlink Socket.1909bob # [ 7.340020] systemd[1]: Listening on udev Control Socket.1910bob # [ 7.341233] systemd[1]: Listening on udev Varlink Socket.1911clare # [ 7.375080] systemd[1]: Starting Remount Root and Kernel File Systems...1912clare # [ 7.381190] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1913bob # [ 7.344624] systemd[1]: Mounting Huge Pages File System...1914bob # [ 7.347627] systemd[1]: Mounting POSIX Message Queue File System...1915bob # [ 7.364385] systemd[1]: Mounting Kernel Debug File System...1916bob # [ 7.369041] systemd[1]: Mounting Kernel Trace File System...1917clare # [ 7.414264] systemd[1]: Starting Coldplug All udev Devices...1918clare # [ 7.420090] systemd[1]: Listening on Journal Log Access Socket.1919bob # [ 7.383827] systemd[1]: Starting Create List of Static Device Nodes...1920bob # [ 7.385104] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1921alice # [ 7.383805] systemd[1]: Applying preset policy.1922clare # [ 7.428538] systemd[1]: Mounted Huge Pages File System.1923bob # [ 7.389964] systemd[1]: Mounting Kernel Configuration File System...1924bob # [ 7.391403] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1925bob # [ 7.394453] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1926clare # [ 7.430808] systemd[1]: Mounted POSIX Message Queue File System.1927clare # [ 7.435174] systemd-journald[285]: Collecting audit messages is enabled.1928clare # [ 7.437244] systemd[1]: Mounted Kernel Debug File System.1929bob # [ 7.402367] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1930clare # [ 7.443275] systemd[1]: Queued start job for default target Multi-User System.1931clare # [ 7.446376] systemd[1]: systemd-journald.service: Deactivated successfully.1932bob # [ 7.418040] systemd[1]: Mounting FUSE Control File System...1933bob # [ 7.420963] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671934clare # [ 7.458677] systemd[1]: Started Journal Service.1935clare # [ 7.453326] systemd[1]: Mounted Kernel Trace File System.1936clare # [ 7.456178] systemd[1]: Finished Create List of Static Device Nodes.1937clare # [ 7.475105] systemd-modules-load[286]: Module 'atkbd' is built in1938clare # [ 7.475763] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1939bob # [ 7.453471] systemd[1]: Starting Journal Service...1940clare # [ 7.484322] systemd-modules-load[286]: Module 'loop' is built in1941clare # [ 7.498178] systemd[1]: Finished Load Kernel Modules.1942clare # [ 7.507510] EXT4-fs (vda): re-mounted eec86074-08cc-4b2f-b215-04f44adf23c8.1943bob # [ 7.474819] systemd[1]: Starting Load Kernel Modules...1944clare # [ 7.507976] systemd[1]: Starting Firewall...1945clare # [ 7.518065] systemd[1]: Starting Apply Kernel Variables...1946bob # [ 7.489793] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1947clare # [ 7.524298] systemd[1]: Finished Remount Root and Kernel File Systems.1948clare # [ 7.524981] systemd[1]: Mounted Kernel Configuration File System.1949clare # [ 7.534496] systemd-oomd[288]: No swap; memory pressure usage will be degraded1950bob # [ 7.513075] systemd[1]: Starting Remount Root and Kernel File Systems...1951bob # [ 7.513553] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1952clare # [ 7.547602] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1953bob # [ 7.521670] systemd[1]: Starting Coldplug All udev Devices...1954bob # [ 7.523982] systemd[1]: Listening on Journal Log Access Socket.1955bob # [ 7.528486] systemd[1]: Mounted Huge Pages File System.1956clare # [ 7.556659] systemd[1]: Listening on Disk Image Download Service Socket.1957bob # [ 7.529869] systemd[1]: Mounted POSIX Message Queue File System.1958clare # [ 7.569656] systemd[1]: Starting Flush Journal to Persistent Storage...1959bob # [ 7.541901] systemd[1]: Mounted Kernel Debug File System.1960clare # [ 7.570455] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1961bob # [ 7.545208] systemd-journald[289]: Collecting audit messages is enabled.1962clare # [ 7.580135] systemd[1]: Starting Load/Save OS Random Seed...1963clare # [ 7.580789] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1964bob # [ 7.555684] systemd[1]: Mounted Kernel Trace File System.1965clare # [ 7.588493] systemd[1]: Mounted FUSE Control File System.1966bob # [ 7.556795] systemd[1]: Queued start job for default target Multi-User System.1967bob # [ 7.557713] systemd[1]: systemd-journald.service: Deactivated successfully.1968bob # [ 7.561911] systemd-modules-load[290]: Module 'atkbd' is built in1969bob # [ 7.564292] systemd-modules-load[290]: Module 'loop' is built in1970bob # [ 7.573640] systemd[1]: Finished Create List of Static Device Nodes.1971bob # [ 7.576839] systemd[1]: Started Journal Service.1972alice # [ 7.579951] systemd[1]: Populated /etc with preset unit settings.1973bob # [ 7.593618] systemd[1]: Finished Load Kernel Modules.1974bob # [ 7.594307] systemd[1]: Mounted Kernel Configuration File System.1975bob # [ 7.594906] systemd[1]: Mounted FUSE Control File System.1976bob # [ 7.616584] systemd[1]: Starting Firewall...1977bob # [ 7.632324] EXT4-fs (vda): re-mounted 8e51f2ba-7b6a-436e-89fa-572b3da529ec.1978bob # [ 7.627506] systemd[1]: Starting Apply Kernel Variables...1979clare # [ 7.680145] systemd-journald[285]: Received client request to flush runtime journal.1980bob # [ 7.637558] systemd-oomd[291]: No swap; memory pressure usage will be degraded1981bob # [ 7.642681] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1982bob # [ 7.652530] systemd[1]: Finished Remount Root and Kernel File Systems.1983bob # [ 7.655012] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1984bob # [ 7.674458] systemd[1]: Listening on Disk Image Download Service Socket.1985bob # [ 7.689852] systemd[1]: Starting Flush Journal to Persistent Storage...1986bob # [ 7.690558] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1987bob # [ 7.698727] systemd[1]: Starting Load/Save OS Random Seed...1988bob # [ 7.699391] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1989clare # [ 7.742860] systemd[1]: Finished Load/Save OS Random Seed.1990clare # [ 7.743501] systemd[1]: Reached target First Boot Complete.1991clare # [ 7.752464] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1992clare # [ 7.753275] systemd[1]: Starting Create Static Device Nodes in /dev...1993clare # [ 7.763399] systemd[1]: Finished Apply Kernel Variables.1994clare # [ 7.763995] systemd[1]: Finished Flush Journal to Persistent Storage.1995clare # [ 7.769867] systemd[1]: Starting Network Name Resolution...1996bob # [ 7.802622] systemd-journald[289]: Received client request to flush runtime journal.1997alice # [ 7.839441] systemd[1]: initrd-switch-root.service: Deactivated successfully.1998alice # [ 7.840440] systemd[1]: Stopped initrd-switch-root.service.1999alice # [ 7.842878] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.2000alice # [ 7.845682] systemd[1]: Created slice Slice /system/getty.2001alice # [ 7.847671] systemd[1]: Created slice User and Session Slice.2002alice # [ 7.848852] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.2003alice # [ 7.850677] systemd[1]: Started Forward Password Requests to Wall Directory Watch.2004alice # [ 7.852436] systemd[1]: Expecting device /dev/hvc0...2005alice # [ 7.853771] systemd[1]: Expecting device /dev/ttyAMA0...2006alice # [ 7.855089] systemd[1]: Reached target Local Encrypted Volumes.2007alice # [ 7.856563] systemd[1]: Stopped target initrd-fs.target.2008alice # [ 7.857907] systemd[1]: Stopped target initrd-root-fs.target.2009alice # [ 7.859334] systemd[1]: Stopped target initrd-switch-root.target.2010alice # [ 7.860296] systemd[1]: Reached target Virtual Machines and Containers.2011alice # [ 7.861328] systemd[1]: Reached target Path Units.2012alice # [ 7.862211] systemd[1]: Reached target Remote File Systems.2013alice # [ 7.863128] systemd[1]: Reached target Slice Units.2014alice # [ 7.864027] systemd[1]: Reached target Swaps.2015alice # [ 7.867403] systemd[1]: Listening on Query the User Interactively for a Password.2016alice # [ 7.870817] systemd[1]: Listening on Process Core Dump Socket.2017alice # [ 7.873191] systemd[1]: Listening on Credential Encryption/Decryption.2018alice # [ 7.875877] systemd[1]: Listening on Factory Reset Management.2019alice # [ 7.877086] systemd[1]: Listening on Hostname Service Socket.2020clare # [ 7.917983] systemd-resolved[316]: Positive Trust Anchors:2021alice # [ 7.884701] systemd[1]: Starting Journal Log Access Socket...2022bob # [ 7.882842] systemd[1]: Finished Apply Kernel Variables.2023clare # [ 7.918845] systemd-resolved[316]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2024clare # [ 7.919728] systemd-resolved[316]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162025alice # [ 7.886976] systemd[1]: Listening on Journal Audit Socket.2026bob # [ 7.888844] systemd[1]: Starting Network Name Resolution...2027bob # [ 7.889520] systemd[1]: Finished Load/Save OS Random Seed.2028bob # [ 7.890012] systemd[1]: Reached target First Boot Complete.2029alice # [ 7.892804] systemd[1]: Listening on Console Output Muting Service Socket.2030alice # [ 7.894268] systemd[1]: Listening on Network Management Metrics Varlink Socket.2031alice # [ 7.896752] systemd[1]: Listening on Network Management Varlink Socket.2032alice # [ 7.898430] systemd[1]: Listening on Network Management Netlink Socket.2033bob # [ 7.897814] systemd[1]: Finished Flush Journal to Persistent Storage.2034alice # [ 7.900209] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.2035alice # [ 7.902000] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2036alice # [ 7.903864] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2037clare # [ 7.928272] 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 test2038alice # [ 7.908541] systemd[1]: Listening on Disk Repartitioning Service Socket.2039alice # [ 7.909810] systemd[1]: Listening on Resolve Monitor Varlink Socket.2040alice # [ 7.910931] systemd[1]: Listening on Resolve Service Varlink Socket.2041alice # [ 7.912066] systemd[1]: Listening on udev Control Socket.2042alice # [ 7.913065] systemd[1]: Listening on udev Varlink Socket.2043alice # [ 7.916772] systemd[1]: Mounting Huge Pages File System...2044alice # [ 7.919898] systemd[1]: Mounting POSIX Message Queue File System...2045alice # [ 7.935313] systemd[1]: Mounting Kernel Debug File System...2046alice # [ 7.940776] systemd[1]: Mounting Kernel Trace File System...2047bob # [ 7.940776] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2048bob # [ 7.950198] systemd[1]: Starting Create Static Device Nodes in /dev...2049alice # [ 7.957576] systemd[1]: Starting Create List of Static Device Nodes...2050alice # [ 7.958645] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2051clare # [ 7.997025] systemd[1]: Finished Create Static Device Nodes in /dev.2052clare # [ 7.998347] systemd[1]: Reached target Preparation for Local File Systems.2053clare # [ 8.006175] systemd[1]: Starting Rule-based Manager for Device Events and Files...2054alice # [ 7.975153] systemd[1]: Mounting Kernel Configuration File System...2055alice # [ 7.979438] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm2056alice # [ 7.984900] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2057alice # [ 7.989076] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2058bob # [ 7.992346] systemd-resolved[314]: Positive Trust Anchors:2059bob # [ 7.993001] systemd-resolved[314]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2060bob # [ 7.993831] systemd-resolved[314]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162061bob # [ 7.994672] 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 test2062alice # [ 8.010244] systemd[1]: Mounting FUSE Control File System...2063alice # [ 8.015445] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672064alice # [ 8.036959] systemd[1]: Starting Journal Service...2065alice # [ 8.062267] systemd[1]: Starting Load Kernel Modules...2066alice # [ 8.071563] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...2067alice # [ 8.088560] systemd[1]: Starting Remount Root and Kernel File Systems...2068alice # [ 8.090147] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2069clare # [ 8.133226] systemd[1]: Mounting /run/wrappers...2070clare # [ 8.151914] systemd-resolved[316]: Using system hostname 'clare'.2071clare # [ 8.154499] systemd[1]: Started Network Name Resolution.2072alice # [ 8.119428] systemd[1]: Starting Coldplug All udev Devices...2073alice # [ 8.121860] systemd[1]: Listening on Journal Log Access Socket.2074alice # [ 8.122389] systemd[1]: Mounted Huge Pages File System.2075alice # [ 8.122847] systemd[1]: Mounted POSIX Message Queue File System.2076clare # [ 8.161918] systemd[1]: Finished Coldplug All udev Devices.2077alice # [ 8.135772] systemd[1]: Mounted Kernel Debug File System.2078alice # [ 8.137287] systemd[1]: Mounted Kernel Trace File System.2079alice # [ 8.140149] systemd[1]: Finished Create List of Static Device Nodes.2080alice # [ 8.148535] systemd[1]: Finished Load Kernel Modules.2081clare # [ 8.189422] systemd-udevd[324]: Using default interface naming scheme 'v261'.2082alice # [ 8.165088] systemd[1]: Starting Firewall...2083clare # [ 8.199684] systemd[1]: Mounted /run/wrappers.2084clare # [ 8.200397] systemd[1]: Reached target Local File Systems.2085clare # [ 8.204821] systemd[1]: Listening on Boot Loader Control Service Socket.2086clare # [ 8.211043] systemd[1]: Starting register-nix-paths.service...2087alice # [ 8.178657] systemd-journald[279]: Collecting audit messages is enabled.2088bob # [ 8.178837] systemd[1]: Finished Create Static Device Nodes in /dev.2089bob # [ 8.179542] systemd[1]: Reached target Preparation for Local File Systems.2090clare # [ 8.218232] systemd[1]: Starting Create SUID/SGID Wrappers...2091clare # [ 8.218923] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2092clare # [ 8.222884] systemd[1]: Starting Save Transient machine-id to Disk...2093alice # [ 8.189759] systemd[1]: Starting Apply Kernel Variables...2094bob # [ 8.189186] systemd[1]: Starting Rule-based Manager for Device Events and Files...2095clare # [ 8.230953] systemd[1]: Starting Create System Files and Directories...2096alice # [ 8.194570] systemd[1]: Queued start job for default target Multi-User System.2097bob # [ 8.204794] systemd-resolved[314]: Using system hostname 'bob'.2098alice # [ 8.203129] systemd[1]: systemd-journald.service: Deactivated successfully.2099alice # [ 8.214352] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2100bob # [ 8.214035] systemd[1]: Started Network Name Resolution.2101alice # [ 8.210565] systemd-modules-load[280]: Module 'atkbd' is built in2102alice # [ 8.211488] systemd-modules-load[280]: Module 'loop' is built in2103alice # [ 8.220267] systemd[1]: Started Journal Service.2104alice # [ 8.221237] systemd-oomd[281]: No swap; memory pressure usage will be degraded2105alice # [ 8.230707] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2106alice # [ 8.243380] EXT4-fs (vda): re-mounted 58a30e0a-f96c-42d0-ae38-0862f6802fe9.2107alice # [ 8.249096] systemd[1]: Mounted Kernel Configuration File System.2108bob # [ 8.273628] systemd[1]: Mounting /run/wrappers...2109alice # [ 8.270709] systemd[1]: Finished Remount Root and Kernel File Systems.2110bob # [ 8.285026] systemd[1]: Finished Coldplug All udev Devices.2111alice # [ 8.307147] systemd[1]: Listening on Disk Image Download Service Socket.2112alice # [ 8.315771] systemd[1]: Starting Flush Journal to Persistent Storage...2113bob # [ 8.324450] systemd[1]: Mounted /run/wrappers.2114alice # [ 8.320443] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2115bob # [ 8.325778] systemd[1]: Reached target Local File Systems.2116alice # [ 8.326041] systemd[1]: Starting Load/Save OS Random Seed...2117bob # [ 8.330784] systemd[1]: Listening on Boot Loader Control Service Socket.2118alice # [ 8.326757] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2119bob # [ 8.333186] systemd[1]: Starting register-nix-paths.service...2120bob # [ 8.337188] systemd-udevd[331]: Using default interface naming scheme 'v261'.2121bob # [ 8.341368] systemd[1]: Starting Create SUID/SGID Wrappers...2122bob # [ 8.344666] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2123clare # [ 8.393663] systemd[1]: Started Rule-based Manager for Device Events and Files.2124bob # [ 8.358814] systemd[1]: Starting Save Transient machine-id to Disk...2125bob # [ 8.376384] systemd[1]: Starting Create System Files and Directories...2126clare # [ 8.418708] systemd[1]: Finished Create System Files and Directories.2127clare # [ 8.425483] systemd[1]: Starting Rebuild Journal Catalog...2128alice # [ 8.391191] systemd[1]: Mounted FUSE Control File System.2129clare # [ 8.441000] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2130alice # [ 8.427771] systemd-journald[279]: Received client request to flush runtime journal.2131alice # [ 8.529736] systemd[1]: Finished Apply Kernel Variables.2132alice # [ 8.540193] systemd[1]: Starting Network Name Resolution...2133alice # [ 8.540908] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2134bob # [ 8.547929] systemd[1]: Started Rule-based Manager for Device Events and Files.2135alice # [ 8.548311] systemd[1]: Starting Create Static Device Nodes in /dev...2136alice # [ 8.548998] systemd[1]: Finished Load/Save OS Random Seed.2137alice # [ 8.549638] systemd[1]: Reached target First Boot Complete.2138bob # [ 8.566471] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2139alice # [ 8.564229] systemd[1]: Finished Flush Journal to Persistent Storage.2140bob # [ 8.575315] systemd[1]: Finished Save Transient machine-id to Disk.2141clare # [ 8.628094] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2142clare # [ 8.632635] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2143clare # [ 8.646393] systemd[1]: Finished Save Transient machine-id to Disk.2144bob # [ 8.620221] systemd[1]: Finished Create System Files and Directories.2145bob # [ 8.624277] systemd[1]: Starting Rebuild Journal Catalog...2146bob # [ 8.637309] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2147clare # [ 8.671831] systemd[1]: Finished Rebuild Journal Catalog.2148clare # [ 8.682959] systemd[1]: Starting Update is Completed...2149alice # [ 8.675729] systemd-resolved[306]: Positive Trust Anchors:2150alice # [ 8.679640] systemd-resolved[306]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2151alice # [ 8.685999] systemd-resolved[306]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162152alice # [ 8.687032] systemd-resolved[306]: 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 test2153alice # [ 8.757975] systemd[1]: Finished Create Static Device Nodes in /dev.2154alice # [ 8.759224] systemd[1]: Reached target Preparation for Local File Systems.2155clare # [ 8.801350] systemd[1]: Finished Update is Completed.2156alice # [ 8.763415] systemd[1]: Starting Rule-based Manager for Device Events and Files...2157clare # [ 8.805237] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2158bob # [ 8.813251] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2159clare # [ 8.865871] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2160alice # [ 8.846127] systemd[1]: Mounting /run/wrappers...2161bob # [ 8.866674] systemd[1]: Finished Rebuild Journal Catalog.2162bob # [ 8.879352] systemd[1]: Starting Update is Completed...2163alice # [ 8.913118] systemd-resolved[306]: Using system hostname 'alice'.2164alice # [ 8.921394] systemd[1]: Started Network Name Resolution.2165bob # [ 8.950144] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2166alice # [ 8.947384] systemd[1]: Mounted /run/wrappers.2167alice # [ 8.947963] systemd[1]: Reached target Local File Systems.2168alice # [ 8.956148] systemd[1]: Listening on Boot Loader Control Service Socket.2169bob # [ 8.962899] systemd[1]: Finished Update is Completed.2170alice # [ 8.965602] systemd[1]: Starting register-nix-paths.service...2171alice # [ 8.970139] systemd[1]: Starting Create SUID/SGID Wrappers...2172alice # [ 8.970850] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2173alice # [ 8.983117] systemd[1]: Starting Save Transient machine-id to Disk...2174bob # [ 8.993391] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2175alice # [ 9.005057] systemd[1]: Starting Create System Files and Directories...2176alice # [ 9.046096] systemd-udevd[319]: Using default interface naming scheme 'v261'.2177clare # [ 9.106409] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2178alice # [ 9.076766] systemd[1]: Finished Coldplug All udev Devices.2179clare # [ 9.242804] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2180bob # [ 9.216590] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2181alice # [ 9.228957] systemd[1]: Finished Create System Files and Directories.2182bob # [ 9.255109] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2183alice # [ 9.253028] systemd[1]: Starting Rebuild Journal Catalog...2184alice # [ 9.261498] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2185alice # [ 9.268824] systemd[1]: Started Rule-based Manager for Device Events and Files.2186alice # [ 9.291945] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2187alice # [ 9.314044] systemd[1]: Finished Save Transient machine-id to Disk.2188clare # [ 9.437791] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2189clare # [ 9.446274] systemd[1]: Finished Create SUID/SGID Wrappers.2190clare # [ 9.462085] (udev-worker)[391]: Network interface NamePolicy= disabled on kernel command line.2191alice # [ 9.424119] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2192clare # [ 9.474876] (udev-worker)[390]: Network interface NamePolicy= disabled on kernel command line.2193alice # [ 9.435090] systemd[1]: Finished Rebuild Journal Catalog.2194alice # [ 9.443837] systemd[1]: Starting Update is Completed...2195clare # [ 9.527847] systemd[1]: Finished register-nix-paths.service.2196clare # [ 9.530217] systemd[1]: Reached target System Initialization.2197clare # [ 9.530846] systemd[1]: Started Discard unused filesystem blocks once a week.2198clare # [ 9.531462] systemd[1]: Started Daily Cleanup of Temporary Directories.2199clare # [ 9.537111] systemd[1]: Reached target Timer Units.2200clare # [ 9.537720] systemd[1]: Listening on D-Bus System Message Bus Socket.2201clare # [ 9.548471] systemd[1]: Listening on harmonia binary cache socket.2202clare # [ 9.549276] systemd[1]: Listening on Nix Daemon Socket.2203clare # [ 9.551884] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2204clare # [ 9.556661] systemd[1]: Reached target Socket Units.2205clare # [ 9.560350] systemd[1]: Reached target Basic System.2206clare # [ 9.560909] systemd[1]: Started backdoor.service.2207clare # [ 9.576316] systemd[1]: Starting Import lastlog data into lastlog2 database...2208bob # [ 9.547153] (udev-worker)[384]: Network interface NamePolicy= disabled on kernel command line.2209alice # [ 9.545234] systemd[1]: Finished Update is Completed.2210clare # [ 9.586110] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2211bob # [ 9.554510] (udev-worker)[389]: Network interface NamePolicy= disabled on kernel command line.2212clare # [ 9.610079] systemd[1]: Starting Post-Boot Actions...2213clare # [ 9.617183] systemd[1]: Started Reset console on configuration changes.2214clare # [ 9.673303] systemd[1]: Starting D-Bus System Message Bus...2215bob # [ 9.650741] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2216alice # [ 9.648583] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2217bob # [ 9.654725] systemd[1]: Finished Create SUID/SGID Wrappers.2218bob # [ 9.690497] systemd[1]: Finished register-nix-paths.service.2219bob # [ 9.691198] systemd[1]: Reached target System Initialization.2220bob # [ 9.702927] systemd[1]: Started Discard unused filesystem blocks once a week.2221bob # [ 9.705085] systemd[1]: Started Daily Cleanup of Temporary Directories.2222alice # [ 9.701267] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2223bob # [ 9.707985] systemd[1]: Reached target Timer Units.2224bob # [ 9.708587] systemd[1]: Listening on D-Bus System Message Bus Socket.2225bob # [ 9.709174] systemd[1]: Listening on Nix Daemon Socket.2226bob # [ 9.709679] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2227bob # [ 9.710417] systemd[1]: Reached target Socket Units.2228bob # [ 9.710907] systemd[1]: Reached target Basic System.2229clare # connecting to host...2230bob # [ 9.715803] systemd[1]: Started backdoor.service.2231bob # [ 9.720671] systemd[1]: Starting Import lastlog data into lastlog2 database...2232bob # [ 9.726021] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2233bob # [ 9.739470] systemd[1]: Starting Post-Boot Actions...2234bob # [ 9.761400] systemd[1]: Started Reset console on configuration changes.2235clare # [ 9.806978] mousedev: PS/2 mouse device common for all mice2236clare # [ 9.839124] systemd[1]: Finished Post-Boot Actions.2237clare # [ 9.845000] nsncd[470]: Sep 21 10:07:28.585 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2238clare # [ 9.846333] systemd[1]: Started Name Service Cache Daemon (nsncd).2239clare # [ 9.846918] systemd[1]: Reached target Host and Network Name Lookups.2240clare # [ 9.847472] systemd[1]: Reached target User and Group Name Lookups.2241clare # [ 9.857710] systemd[1]: Starting User Login Management...2242bob # [ 9.819693] systemd[1]: Starting D-Bus System Message Bus...2243clare # [ 9.908183] systemd[1]: Finished Import lastlog data into lastlog2 database.2244alice # [ 9.922345] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2245bob # [ 9.956095] systemd[1]: Finished Post-Boot Actions.2246bob # connecting to host...2247clare # [ 10.021425] systemd-logind[500]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2248clare # [ 10.024777] systemd-logind[500]: Watching system buttons on /dev/input/event0 (gpio-keys)2249clare # [ 10.026583] systemd-logind[500]: New seat seat0.2250clare # [ 10.031280] systemd[1]: Started User Login Management.2251clare # [ 10.034788] dbus-broker-launch[475]: Looking up NSS user entry for 'systemd-timesync'...2252clare # [ 10.039688] systemd[1]: Starting linger-users.service...2253bob # [ 10.010527] systemd[1]: Started Name Service Cache Daemon (nsncd).2254alice # [ 10.009519] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2255bob # [ 10.015926] nsncd[473]: Sep 21 10:07:28.755 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2256clare # [ 10.054099] dbus-broker-launch[475]: NSS returned no entry for 'systemd-timesync'2257clare # [ 10.055877] dbus-broker-launch[475]: Invalid user-name in /nix/store/qbpalghp756r6i8828kqv4b1w9v4pj5k-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2258bob # [ 10.023373] systemd[1]: Finished Import lastlog data into lastlog2 database.2259bob # [ 10.025507] systemd[1]: Reached target Host and Network Name Lookups.2260bob # [ 10.026148] systemd[1]: Reached target User and Group Name Lookups.2261bob # [ 10.036773] mousedev: PS/2 mouse device common for all mice2262bob: Guest shell says: b'Spawning backdoor root shell...\n'2263bob # [ 10.037433] systemd[1]: Starting User Login Management...2264clare # [ 10.084848] systemd[1]: Started D-Bus System Message Bus.2265bob: connected to guest root shell2266bob: (connecting took 10.41 seconds)2267bob: (finished: waiting for the VM to finish booting, in 10.41 seconds)2268bob # [ 10.087777] dbus-broker-launch[477]: Looking up NSS user entry for 'systemd-timesync'...2269clare # [ 10.132741] dbus-broker-launch[475]: Ready2270clare # [ 10.144099] systemd[1]: linger-users.service: Deactivated successfully.2271clare # [ 10.147106] systemd[1]: Finished linger-users.service.2272bob # [ 10.113872] dbus-broker-launch[477]: NSS returned no entry for 'systemd-timesync'2273bob # [ 10.114654] dbus-broker-launch[477]: Invalid user-name in /nix/store/v0ibjj1v9niigv3kglsa2hsp7vpjna5p-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2274bob # [ 10.167196] systemd[1]: Started D-Bus System Message Bus.2275alice # [ 10.189871] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2276alice # [ 10.190677] systemd[1]: Finished Create SUID/SGID Wrappers.2277bob # [ 10.229138] systemd-logind[505]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2278bob # [ 10.236767] systemd-logind[505]: Watching system buttons on /dev/input/event0 (gpio-keys)2279bob # [ 10.237580] systemd-logind[505]: New seat seat0.2280alice # [ 10.234346] (udev-worker)[387]: Network interface NamePolicy= disabled on kernel command line.2281alice # [ 10.240967] (udev-worker)[397]: Network interface NamePolicy= disabled on kernel command line.2282bob # [ 10.247230] systemd[1]: Started User Login Management.2283bob # [ 10.253107] dbus-broker-launch[477]: Ready2284bob # [ 10.260284] systemd[1]: Starting linger-users.service...2285alice # [ 10.278891] systemd[1]: Finished register-nix-paths.service.2286alice # [ 10.281419] systemd[1]: Reached target System Initialization.2287alice # [ 10.282089] systemd[1]: Started Discard unused filesystem blocks once a week.2288alice # [ 10.282782] systemd[1]: Started Daily Cleanup of Temporary Directories.2289alice # [ 10.283409] systemd[1]: Reached target Timer Units.2290alice # [ 10.297357] systemd[1]: Listening on D-Bus System Message Bus Socket.2291alice # [ 10.298144] systemd[1]: Listening on Nix Daemon Socket.2292alice # [ 10.298667] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2293alice # [ 10.299470] systemd[1]: Reached target Socket Units.2294alice # [ 10.299983] systemd[1]: Reached target Basic System.2295alice # [ 10.306380] systemd[1]: Started backdoor.service.2296alice # [ 10.312193] systemd[1]: Starting Import lastlog data into lastlog2 database...2297clare # [ 10.360690] systemd[1]: Finished Firewall.2298clare # [ 10.362195] systemd[1]: Reached target Preparation for Network.2299clare # [ 10.364864] systemd[1]: Listening on Network Management Resolve Hook Socket.2300alice # [ 10.326026] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2301clare # [ 10.371188] systemd[1]: Starting Network Management...2302alice # [ 10.347421] systemd[1]: Starting Post-Boot Actions...2303alice # [ 10.356150] systemd[1]: Started Reset console on configuration changes.2304bob # [ 10.372660] systemd[1]: linger-users.service: Deactivated successfully.2305bob # [ 10.375669] systemd[1]: Finished linger-users.service.2306alice # [ 10.388321] systemd[1]: Starting D-Bus System Message Bus...2307alice # connecting to host...2308alice # [ 10.531524] systemd[1]: Finished Post-Boot Actions.2309alice # [ 10.546067] systemd[1]: Started Name Service Cache Daemon (nsncd).2310alice # [ 10.552682] nsncd[463]: Sep 21 10:07:29.287 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2311clare # [ 10.606822] cfg80211: Loading compiled-in X.509 certificates for regulatory database2312bob # [ 10.567123] systemd[1]: Finished Firewall.2313alice # [ 10.564296] systemd[1]: Reached target Host and Network Name Lookups.2314alice # [ 10.565093] systemd[1]: Reached target User and Group Name Lookups.2315bob # [ 10.571728] systemd[1]: Reached target Preparation for Network.2316bob # [ 10.572491] systemd[1]: Listening on Network Management Resolve Hook Socket.2317bob # [ 10.577084] systemd[1]: Starting Network Management...2318alice # [ 10.580756] systemd[1]: Starting User Login Management...2319clare # [ 10.656242] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2320clare # [ 10.657927] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2321clare # [ 10.661096] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22322clare # [ 10.661514] cfg80211: failed to load regulatory.db2323alice # [ 10.624297] systemd[1]: Finished Import lastlog data into lastlog2 database.2324clare # [ 10.687210] systemd-networkd[544]: lo: Link UP2325clare # [ 10.687732] systemd-networkd[544]: lo: Gained carrier2326clare # [ 10.692727] systemd[1]: Started Network Management.2327clare # [ 10.693314] systemd[1]: Reached target Network.2328clare # [ 10.696895] systemd-networkd[544]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2329alice # [ 10.662434] dbus-broker-launch[467]: Looking up NSS user entry for 'systemd-timesync'...2330clare # [ 10.704667] systemd-networkd[544]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2331clare # [ 10.710031] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2332clare # [ 10.710827] systemd-networkd[544]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2333clare # [ 10.716087] systemd-networkd[544]: eth1: Link UP2334clare # [ 10.716629] systemd-networkd[544]: eth1: Gained carrier2335clare # [ 10.717125] systemd-networkd[544]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2336clare # [ 10.718477] systemd[1]: Starting Permit User Sessions...2337alice # [ 10.682090] dbus-broker-launch[467]: NSS returned no entry for 'systemd-timesync'2338alice # [ 10.682769] dbus-broker-launch[467]: Invalid user-name in /nix/store/nnbavp86sdcc05f1v79mqcwi08cvshd7-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2339clare # [ 10.731347] systemd-networkd[544]: eth0: Link UP2340clare # [ 10.731953] systemd-networkd[544]: eth0: Gained carrier2341clare # [ 10.736939] systemd-networkd[544]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22342alice # [ 10.741571] systemd[1]: Started D-Bus System Message Bus.2343alice # [ 10.747982] systemd-logind[492]: Watching system buttons on /dev/input/event0 (gpio-keys)2344alice # [ 10.751827] systemd-logind[492]: New seat seat0.2345bob # [ 10.767794] cfg80211: Loading compiled-in X.509 certificates for regulatory database2346alice # [ 10.757622] systemd[1]: Started User Login Management.2347clare # [ 10.799488] systemd[1]: Finished Permit User Sessions.2348clare # [ 10.807216] systemd[1]: Started Getty on tty1.2349alice # [ 10.768843] systemd[1]: Starting linger-users.service...2350clare # [ 10.809864] systemd[1]: Reached target Login Prompts.2351clare # [ 10.810470] systemd[1]: Reached target Multi-User System.2352alice # [ 10.779427] mousedev: PS/2 mouse device common for all mice2353alice # [ 10.778994] systemd-logind[492]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2354clare # [ 10.823891] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2355clare # [ 10.826678] systemd[1]: Startup finished in 844ms (kernel) + 5.089s (initrd) + 4.892s (userspace) = 10.825s.2356alice # [ 10.796584] dbus-broker-launch[467]: Ready2357bob # [ 10.810810] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2358bob # [ 10.811426] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2359bob # [ 10.815242] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22360bob # [ 10.815668] cfg80211: failed to load regulatory.db2361bob # [ 10.838800] systemd-networkd[556]: lo: Link UP2362bob # [ 10.839761] systemd-networkd[556]: lo: Gained carrier2363alice # [ 10.836104] systemd[1]: linger-users.service: Deactivated successfully.2364alice # [ 10.837829] systemd[1]: Finished linger-users.service.2365bob # [ 10.847913] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2366bob # [ 10.857461] systemd[1]: Started Network Management.2367bob # [ 10.857979] systemd-networkd[556]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2368bob # [ 10.858738] systemd[1]: Reached target Network.2369bob # [ 10.859170] systemd[1]: Reached target Network is Online.2370bob # [ 10.868208] systemd-networkd[556]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2371bob # [ 10.869166] systemd-networkd[556]: eth1: Link UP2372bob # [ 10.869625] systemd-networkd[556]: eth1: Gained carrier2373bob # [ 10.870064] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2374bob # [ 10.871351] systemd[1]: Starting ncps binary cache proxy service...2375bob # [ 10.877990] systemd-networkd[556]: eth0: Link UP2376bob # [ 10.878485] systemd-networkd[556]: eth0: Gained carrier2377bob # [ 10.885273] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2378bob # [ 10.888613] systemd-networkd[556]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22379bob # [ 10.903357] systemd[1]: Starting Permit User Sessions...2380clare # [ 10.986762] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input32381bob # [ 10.976627] systemd[1]: Finished Permit User Sessions.2382bob # [ 10.989445] systemd[1]: Started Getty on tty1.2383bob # [ 10.989915] systemd[1]: Reached target Login Prompts.2384bob # [ 10.992156] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2385alice # [ 11.012570] systemd[1]: Finished Firewall.2386alice # [ 11.014112] systemd[1]: Reached target Preparation for Network.2387alice # [ 11.016862] systemd[1]: Listening on Network Management Resolve Hook Socket.2388alice # [ 11.021865] systemd[1]: Starting Network Management...2389bob # [ 11.033198] ncps-pre-start[563]: /nix/store/0m8la0b5a7q97yz1mc9c2x9xb5mc67vz-ncps-0.9.4/bin/dbmate-ncps up2390bob # [ 11.200927] ncps-pre-start[578]: Creating: /var/lib/ncps/db/db.sqlite2391alice # [ 11.225223] cfg80211: Loading compiled-in X.509 certificates for regulatory database2392bob # [ 11.242801] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input32393alice # [ 11.269090] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2394alice # [ 11.269648] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2395alice # [ 11.273037] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22396alice # [ 11.273502] cfg80211: failed to load regulatory.db2397alice # [ 11.299851] systemd-networkd[535]: lo: Link UP2398alice # [ 11.300554] systemd-networkd[535]: lo: Gained carrier2399alice # [ 11.308186] systemd[1]: Started Network Management.2400alice # [ 11.312091] systemd[1]: Reached target Network.2401clare # [ 11.355352] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2402bob # [ 11.320361] ncps-pre-start[578]: Applying: 20241210054814_create-narinfos-table.sql2403alice # [ 11.315756] systemd-networkd[535]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2404alice # [ 11.319912] systemd-networkd[535]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2405bob # [ 11.326560] ncps-pre-start[578]: Applied: 20241210054814_create-narinfos-table.sql in 6.27068ms2406clare # [ 11.363079] systemd[1]: Starting Virtual Console Setup...2407bob # [ 11.327347] ncps-pre-start[578]: Applying: 20241210054829_create-nars-table.sql2408alice # [ 11.328467] systemd-networkd[535]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2409alice # [ 11.329281] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2410alice # [ 11.329947] systemd-networkd[535]: eth0: Link UP2411alice # [ 11.330397] systemd-networkd[535]: eth0: Gained carrier2412bob # [ 11.336366] ncps-pre-start[578]: Applied: 20241210054829_create-nars-table.sql in 9.73956ms2413bob # [ 11.337524] ncps-pre-start[578]: Applying: 20241213014846_add-query-to-nars-table.sql2414bob # [ 11.344864] ncps-pre-start[578]: Applied: 20241213014846_add-query-to-nars-table.sql in 8.179ms2415bob # [ 11.345662] ncps-pre-start[578]: Applying: 20251230224159_add-cascade-to-nars-fk.sql2416clare # [ 11.381171] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.2417alice # [ 11.342698] systemd[1]: Starting Permit User Sessions...2418alice # [ 11.343300] systemd-networkd[535]: eth1: Link UP2419alice # [ 11.343757] systemd-networkd[535]: eth1: Gained carrier2420clare # [ 11.386008] systemd[1]: Stopped Virtual Console Setup.2421alice # [ 11.346062] systemd-networkd[535]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2422clare # [ 11.387186] systemd-logind[500]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2423clare # [ 11.390393] systemd[1]: Starting Virtual Console Setup...2424alice # [ 11.351078] systemd-networkd[535]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22425bob # [ 11.359949] ncps-pre-start[578]: Applied: 20251230224159_add-cascade-to-nars-fk.sql in 15.2688ms2426bob # [ 11.363773] ncps-pre-start[578]: Applying: 20260101000000_add-config-table.sql2427bob # [ 11.366549] ncps-pre-start[578]: Applied: 20260101000000_add-config-table.sql in 6.66728ms2428bob # [ 11.367366] ncps-pre-start[578]: Applying: 20260105025735_remove-redundant-indexes.sql2429bob # [ 11.383410] ncps-pre-start[578]: Applied: 20260105025735_remove-redundant-indexes.sql in 15.45092ms2430bob # [ 11.386757] ncps-pre-start[578]: Applying: 20260105030513_support_shared_nars_between_narinfos.sql2431bob # [ 11.392454] ncps-pre-start[578]: Applied: 20260105030513_support_shared_nars_between_narinfos.sql in 10.4112ms2432bob # [ 11.397074] ncps-pre-start[578]: Applying: 20260117195000_add_narinfo_de_normalized.sql2433bob # [ 11.411121] ncps-pre-start[578]: Applied: 20260117195000_add_narinfo_de_normalized.sql in 18.62444ms2434bob # [ 11.411944] ncps-pre-start[578]: Applying: 20260127223000_allow_multiple_nar_representations.sql2435alice # [ 11.410422] systemd[1]: Finished Permit User Sessions.2436alice # [ 11.417254] systemd[1]: Started Getty on tty1.2437alice # [ 11.418948] systemd[1]: Reached target Login Prompts.2438bob # [ 11.425168] ncps-pre-start[578]: Applied: 20260127223000_allow_multiple_nar_representations.sql in 14.127ms2439alice # [ 11.421645] systemd[1]: Reached target Multi-User System.2440bob # [ 11.426357] ncps-pre-start[578]: Applying: 20260131021850_add_chunks.sql2441alice # [ 11.427312] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2442alice # [ 11.430240] systemd[1]: Startup finished in 813ms (kernel) + 5.733s (initrd) + 4.880s (userspace) = 11.428s.2443bob # [ 11.439921] ncps-pre-start[578]: Applied: 20260131021850_add_chunks.sql in 13.60184ms2444bob # [ 11.443659] ncps-pre-start[578]: Applying: 20260205063651_add_total_chunks_to_nar_files.sql2445bob # [ 11.455199] ncps-pre-start[578]: Applied: 20260205063651_add_total_chunks_to_nar_files.sql in 15.3196ms2446bob # [ 11.456181] ncps-pre-start[578]: Applying: 20260217071237_add_chunking_started_at_to_nar_files.sql2447clare # [ 11.501614] systemd-vconsole-setup[573]: Configuration of first virtual console was skipped, ignoring remaining ones.2448bob # [ 11.468373] ncps-pre-start[578]: Applied: 20260217071237_add_chunking_started_at_to_nar_files.sql in 13.146ms2449clare # [ 11.504787] systemd[1]: Finished Virtual Console Setup.2450bob # [ 11.469305] ncps-pre-start[578]: Applying: 20260301000000_add_verified_at_to_nar_files.sql2451bob # [ 11.486143] ncps-pre-start[578]: Applied: 20260301000000_add_verified_at_to_nar_files.sql in 17.20068ms2452bob # [ 11.626662] systemd[1]: Started ncps binary cache proxy service.2453bob # [ 11.627270] systemd[1]: Reached target Multi-User System.2454bob # [ 11.631161] systemd[1]: Startup finished in 806ms (kernel) + 5.197s (initrd) + 5.624s (userspace) = 11.628s.2455alice # [ 11.667360] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input32456bob # [ 11.845212] systemd[1]: Starting Virtual Console Setup...2457bob # [ 11.893459] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2458bob # [ 11.934025] systemd-logind[505]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2459bob # [ 11.968765] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","log_level":"info","time":"2026-09-21T10:07:30Z","message":"logger created"}2460bob # [ 11.969973] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","xz-binary-path":"/nix/store/hrvsv5knsgy3kjqykgh75d0yfxm92j8g-xz-5.8.3-bin/bin/xz","time":"2026-09-21T10:07:30Z","message":"Using xz binary for xz decompression"}2461bob # [ 11.979530] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-09-21T10:07:30Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"}2462alice # [ 11.991165] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2463alice # [ 12.000350] systemd[1]: Starting Virtual Console Setup...2464bob # [ 12.016090] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","time":"2026-09-21T10:07:30Z","message":"using local locks (single-instance mode)"}2465bob # [ 12.026618] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-09-21T10:07:30Z","message":"setting up tracer provider to discard traces"}2466bob # [ 12.029915] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-09-21T10:07:30Z","message":"setting up meter provider to discard metrics"}2467alice # [ 12.028583] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.2468bob # [ 12.033356] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-09-21T10:07:30Z","message":"setting up logger provider to discard logs"}2469alice # [ 12.031186] systemd[1]: Stopped Virtual Console Setup.2470alice # [ 12.037762] systemd[1]: Starting Virtual Console Setup...2471bob # [ 12.041844] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-09-21T10:07:30Z","message":"Reporting anonymous metrics to the project maintainers"}2472bob # [ 12.047020] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-09-21T10:07:30Z","message":"failed to parse netrc file, proceeding without netrc authentication"}2473bob # [ 12.051063] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-09-21T10:07:30Z","message":"using local storage"}2474clare # [ 12.108631] systemd[1]: Starting harmonia binary cache service...2475bob # [ 12.074788] systemd-vconsole-setup[610]: Configuration of first virtual console was skipped, ignoring remaining ones.2476bob # [ 12.077480] systemd[1]: Finished Virtual Console Setup.2477alice # [ 12.098753] systemd-logind[492]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2478alice # [ 12.149537] systemd-vconsole-setup[555]: Configuration of first virtual console was skipped, ignoring remaining ones.2479alice # [ 12.152797] systemd[1]: Finished Virtual Console Setup.2480clare # [ 12.193035] harmonia-cache[576]: 2026-09-21T10:07:30.935777Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000))2481clare # [ 12.195691] harmonia-cache[576]: 2026-09-21T10:07:30.937955Z INFO actix_server::builder: starting 4 workers2482clare # [ 12.198684] harmonia-cache[576]: 2026-09-21T10:07:30.938596Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s2483clare # [ 12.199865] harmonia-cache[576]: 2026-09-21T10:07:30.938651Z INFO actix_server::server: Actix runtime found; starting in Actix runtime2484clare # [ 12.201601] harmonia-cache[576]: 2026-09-21T10:07:30.938672Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:50002485clare # [ 12.203542] systemd[1]: Started harmonia binary cache service.2486bob # [ 12.180158] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-09-21T10:07:30Z","message":"upstream became healthy and is now available for requests"}2487bob # [ 12.181750] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-09-21T10:07:30Z","message":"Server started"}2488clare # [ 12.225775] systemd-networkd[544]: eth1: Gained IPv6LL2489bob # [ 12.227977] systemd-networkd[556]: eth1: Gained IPv6LL2490bob # [ 12.229507] systemd-networkd[556]: eth0: Gained IPv6LL2491bob: (finished: waiting for unit ncps, in 12.78 seconds)2492bob: must succeed: systemctl status ncps2493bob: (finished: must succeed: systemctl status ncps, in 0.02 seconds)2494clare: waiting for unit harmonia.socket2495clare: waiting for the VM to finish booting2496clare: Guest shell says: b'Spawning backdoor root shell...\n'2497clare: connected to guest root shell2498clare: (connecting took 0.00 seconds)2499clare: (finished: waiting for the VM to finish booting, in 0.00 seconds)2500clare: (finished: waiting for unit harmonia.socket, in 0.03 seconds)2501alice: waiting for success: curl bob:8502/nix-cache-info2502alice: waiting for the VM to finish booting2503alice: Guest shell says: b'Spawning backdoor root shell...\n'2504alice: connected to guest root shell2505alice: (connecting took 0.00 seconds)2506alice: (finished: waiting for the VM to finish booting, in 0.00 seconds)2507clare # [ 12.577036] systemd-networkd[544]: eth0: Gained IPv6LL2508alice # [ 12.578571] systemd-networkd[535]: eth0: Gained IPv6LL2509alice # % Total % Received % Xferd Average Speed Time Time Time Current2510alice # Dload Upload Total Spent Left Speed2511bob # [ 12.782670] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"192.168.1.1:47032","trace_id":"3fcf970a3559761667b6f05741fe021e","span_id":"9c2d4615ed74f2d5","status":200,"elapsed":0.07236,"bytes":50,"time":"2026-09-21T10:07:31Z","message":"handled request"}2512alice # 0 0 0 0 0 0 0 0 0100 50 100 50 0 0 222 0 0100 50 100 50 0 0 220 0 0100 50 100 50 0 0 219 0 02513alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 0.29 seconds)2514bob: waiting for success: curl clare:5000/nix-cache-info2515alice # [ 12.833314] systemd-networkd[535]: eth1: Gained IPv6LL2516bob # % Total % Received % Xferd Average Speed Time Time Time Current2517bob # Dload Upload Total Spent Left Speed2518bob # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 4254 0 0100 51 100 51 0 0 3636 0 0100 51 100 51 0 0 3211 0 02519bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.06 seconds)2520clare: waiting for unit harmonia2521clare: (finished: waiting for unit harmonia, in 0.03 seconds)2522clare: must succeed: systemctl status harmonia2523clare: (finished: must succeed: systemctl status harmonia, in 0.02 seconds)2524clare: must succeed: nix-build --expr '2525 builtins.derivation {2526 name = "hi";2527 builder = "/bin/sh";2528 args = [ "-c" "echo hi > $out" ];2529 system = "aarch64-linux";2530 }2531' 2>&125322533bob # [ 13.048516] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: 2026/09/21 10:07:31 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host2534clare # [ 13.116380] systemd-resolved[316]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3.2535clare # [ 13.119056] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2536clare # [ 13.120540] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2537clare # [ 13.387133] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2538clare # [ 13.389519] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2539clare # [ 13.390633] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2540clare # [ 13.392060] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2541clare # [ 13.988760] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2542clare # [ 13.991567] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2543clare # [ 13.996391] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2544clare # [ 13.998183] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2545clare # [ 15.119148] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2546clare # [ 15.121743] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2547clare # [ 15.128531] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2548clare # [ 15.130438] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2549clare # [ 17.682037] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2550clare # [ 17.683829] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2551clare # [ 17.686456] systemd-resolved[316]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2552clare # [ 17.687500] systemd-resolved[316]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2553clare: (finished: must succeed: nix-build --expr '2554 builtins.derivation {2555 name = "hi";2556 builder = "/bin/sh";2557 args = [ "-c" "echo hi > $out" ];2558 system = "aarch64-linux";2559 }2560' 2>&12561, in 4.89 seconds)2562alice: must succeed: nix-build --expr '2563 builtins.derivation {2564 name = "hi";2565 builder = "/bin/sh";2566 args = [ "-c" "echo hi > $out" ];2567 system = "aarch64-linux";2568 }2569' 2>&125702571bob # [ 17.917811] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:50766","trace_id":"18e0e4cfb4277561c41eac1841174c9e","span_id":"ca20beb7a2b27ccb","status":200,"elapsed":0.02728,"bytes":50,"time":"2026-09-21T10:07:36Z","message":"handled request"}2572alice # [ 17.918823] systemd-resolved[306]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3.2573alice # [ 17.921758] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2574alice # [ 17.923294] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2575alice # [ 18.263194] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2576alice # [ 18.267094] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2577alice # [ 18.270356] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2578alice # [ 18.272649] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2579alice # [ 18.912284] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2580alice # [ 18.913912] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2581alice # [ 18.915891] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2582alice # [ 18.917462] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2583alice # [ 19.997995] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2584alice # [ 19.999750] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2585alice # [ 20.002003] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2586alice # [ 20.003682] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2587alice # [ 22.228535] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2588alice # [ 22.230321] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2589alice # [ 22.232741] systemd-resolved[306]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2590alice # [ 22.234450] systemd-resolved[306]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2591bob # [ 22.243130] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","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-09-21T10:07:40Z","message":"download the narinfo from upstream"}2592bob # [ 22.261199] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-09-21T10:07:41Z","message":"downloading the nar from upstream"}2593bob # [ 22.267711] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","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-09-21T10:07:41Z","message":"download the nar from upstream"}2594bob # [ 22.282105] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-09-21T10:07:41Z","message":"storing narinfo and nar_file record in the database"}2595bob # [ 22.290044] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":46.00584,"time":"2026-09-21T10:07:41Z","message":"download of narinfo complete"}2596bob # [ 22.295895] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","status":200,"elapsed":47.46312,"bytes":532,"time":"2026-09-21T10:07:41Z","message":"handled request"}2597bob # [ 22.299078] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:50766","trace_id":"cc31c88ab75789e078c20bd0c60a55f4","span_id":"7bf3e29f1de90b26","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":28.5062,"time":"2026-09-21T10:07:41Z","message":"download of nar complete"}2598bob # [ 22.303379] 3s19njv5lirrqabc42ybnlkqr47z9dsh-ncps-wrapper[599]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:50766","trace_id":"8ed13db46e1e2f7da14f7b9c217e660b","span_id":"d6017e3f6a4e5632","status":200,"elapsed":0.66152,"bytes":95,"time":"2026-09-21T10:07:41Z","message":"handled request"}2599alice: (finished: must succeed: nix-build --expr '2600 builtins.derivation {2601 name = "hi";2602 builder = "/bin/sh";2603 args = [ "-c" "echo hi > $out" ];2604 system = "aarch64-linux";2605 }2606' 2>&12607, in 4.51 seconds)2608(finished: run the VM test script, in 23.28 seconds)2609test script finished in 23.82s2610cleanup2611kill QemuMachine (pid 48)2612alice # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2613alice # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down2614alice # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down2615alice # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down2616kill QemuMachine (pid 49)2617bob # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2618bob # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down2619bob # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down2620bob # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down2621kill QemuMachine (pid 50)2622clare # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)2623clare # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down2624clare # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down2625clare # [2026-09-21T10:07:42Z INFO virtiofsd] Client disconnected, shutting down2626(finished: cleanup, in 0.45 seconds)