vm-test-run-ncps
checks.aarch64-linux.ncps
· build #854
· raw
1build01: building ldgrxmjs2d0ijxys73cmqqrcwm806jk6-vm-test-run-ncps.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 alice, bob, clare,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12alice: starting vm13bob: starting vm14clare: starting vm15alice # Disk image does not exist, creating the virtualisation disk image...16alice # Formatting '/build/vm-state-alice/tmp.QJFgPXhNyl', fmt=raw size=107374182417alice # mke2fs 1.47.4 (6-Mar-2025)18alice # Discarding device blocks: 0/262144 done19alice: QEMU running (pid 48)20alice # Creating filesystem with 262144 4k blocks and 65536 inodes21alice # Filesystem UUID: 7a79a265-bd73-4d5e-adc5-39ba3bc9217222bob # Disk image does not exist, creating the virtualisation disk image...23alice # Superblock backups stored on blocks:24bob: QEMU running (pid 49)25bob # Formatting '/build/vm-state-bob/tmp.H0tRlOfWK3', fmt=raw size=107374182426clare: QEMU running (pid 50)27clare # Disk image does not exist, creating the virtualisation disk image...28alice # 32768, 98304, 163840, 22937629clare # Formatting '/build/vm-state-clare/tmp.qGBfTFiU2r', fmt=raw size=107374182430alice # 31clare # mke2fs 1.47.4 (6-Mar-2025)32alice # Allocating group tables: 0/8 done33(finished: start all VMs, in 0.49 seconds)34bob # mke2fs 1.47.4 (6-Mar-2025)35clare # Discarding device blocks: 0/262144 done36clare # Creating filesystem with 262144 4k blocks and 65536 inodes37bob # Discarding device blocks: 0/262144 done38alice # Writing inode tables: 0/8 done39clare # Filesystem UUID: 25b10962-955d-47fe-b97b-0e3150546f0f40bob # Creating filesystem with 262144 4k blocks and 65536 inodes41clare # Superblock backups stored on blocks:42alice # Creating journal (8192 blocks): done43bob # Filesystem UUID: 25e77e32-f3e8-48fc-a2eb-b8ca851e413a44bob: waiting for unit ncps45clare # 32768, 98304, 163840, 22937646bob # Superblock backups stored on blocks:47bob: waiting for the VM to finish booting48alice # Writing superblocks and filesystem accounting information: 0/8 done49clare # 50bob # 32768, 98304, 163840, 22937651clare # Allocating group tables: 0/8 done52bob # 53clare # Writing inode tables: 0/8 done54alice # 55clare # Creating journal (8192 blocks): done56clare # Writing superblocks and filesystem accounting information: 0/8 done57alice # Virtualisation disk image created.58clare # 59alice # Starting virtiofs daemons...60clare # Virtualisation disk image created.61alice # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)62clare # Starting virtiofs daemons...63clare # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)64alice # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether65bob # Allocating group tables: 0/8 done66bob # Writing inode tables: 0/8 done67alice # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection...68clare # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether69clare # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)70alice # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)71clare # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether72alice # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)73clare # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection...74clare # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection...75alice # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether76bob # Creating journal (8192 blocks): done77alice # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether78bob # Writing superblocks and filesystem accounting information: 0/8 done79alice # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection...80bob # 81bob # Virtualisation disk image created.82alice # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection...83bob # Starting virtiofs daemons...84alice # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests85bob # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)86alice # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests87bob # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether88alice # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests89bob # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection...90clare # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)91bob # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)92clare # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether93bob # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether94clare # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection...95bob # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection...96clare # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests97bob # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)98clare # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests99bob # [2026-10-05T10:48:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether100clare # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests101bob # [2026-10-05T10:48:55Z INFO virtiofsd] Waiting for vhost-user socket connection...102bob # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests103bob # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests104bob # [2026-10-05T10:48:55Z INFO virtiofsd] Client connected, servicing requests105alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]106alice # [ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Oct 3 10:39:03 UTC 2026107alice # [ 0.000000] KASLR enabled108alice # [ 0.000000] random: crng init done109alice # [ 0.000000] Machine model: linux,dummy-virt110alice # [ 0.000000] efi: UEFI not found.111clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]112alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT113alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]114clare # [ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Oct 3 10:39:03 UTC 2026115clare # [ 0.000000] KASLR enabled116alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]117clare # [ 0.000000] random: crng init done118alice # [ 0.000000] Zone ranges:119clare # [ 0.000000] Machine model: linux,dummy-virt120clare # [ 0.000000] efi: UEFI not found.121alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]122alice # [ 0.000000] DMA32 empty123alice # [ 0.000000] Normal empty124clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT125alice # [ 0.000000] Device empty126alice # [ 0.000000] Movable zone start for each node127clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]128alice # [ 0.000000] Early memory node ranges129clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]130alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]131clare # [ 0.000000] Zone ranges:132clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]133alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]134clare # [ 0.000000] DMA32 empty135clare # [ 0.000000] Normal empty136alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000137clare # [ 0.000000] Device empty138alice # [ 0.000000] psci: probing for conduit method from DT.139clare # [ 0.000000] Movable zone start for each node140alice # [ 0.000000] psci: PSCIv1.3 detected in firmware.141clare # [ 0.000000] Early memory node ranges142alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs143clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]144alice # [ 0.000000] psci: Trusted OS migration not required145alice # [ 0.000000] psci: SMC Calling Convention v1.1146clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]147clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000148alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)149clare # [ 0.000000] psci: probing for conduit method from DT.150alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u311296151clare # [ 0.000000] psci: PSCIv1.3 detected in firmware.152alice # [ 0.000000] Detected PIPT I-cache on CPU0153clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs154alice # [ 0.000000] CPU features: detected: GICv3 CPU interface155clare # [ 0.000000] psci: Trusted OS migration not required156alice # [ 0.000000] CPU features: detected: Spectre-v4157clare # [ 0.000000] psci: SMC Calling Convention v1.1158alice # [ 0.000000] CPU features: detected: Spectre-BHB159clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)160alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR161clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u311296162alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)163clare # [ 0.000000] Detected PIPT I-cache on CPU0164alice # [ 0.000000] CPU features: detected: ARM erratum 1418040165clare # [ 0.000000] CPU features: detected: GICv3 CPU interface166alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion167clare # [ 0.000000] CPU features: detected: Spectre-v4168clare # [ 0.000000] CPU features: detected: Spectre-BHB169alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing170alice # [ 0.000000] alternatives: applying boot alternatives171clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR172clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)173clare # [ 0.000000] CPU features: detected: ARM erratum 1418040174clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion175clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing176clare # [ 0.000000] alternatives: applying boot alternatives177alice # [ 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/kq0j6qy8xgmdx7cbimmnsnci26v7rpzv-nixos-system-alice-test/init regInfo=/nix/.ro-store/g7d7n1bh2cxyj50ymjmx7njnidvd5sr5-closure-info/registration console=ttyAMA0,115200n8 console=tty0178alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/g7d7n1bh2cxyj50ymjmx7njnidvd5sr5-closure-info/registration", will be passed to user space.179alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes180alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)181clare # [ 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/q82yc0bv5n8fk6y1nv8g5kdp80x45p46-nixos-system-clare-test/init regInfo=/nix/.ro-store/qb537ylw1d8nfimjimmn6h2vp3dikbjj-closure-info/registration console=ttyAMA0,115200n8 console=tty0182alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)183alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB184clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/qb537ylw1d8nfimjimmn6h2vp3dikbjj-closure-info/registration", will be passed to user space.185alice # [ 0.000000] software IO TLB: area num 1.186clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes187alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)188clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)189alice # [ 0.000000] Fallback order for Node 0: 0190alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144191clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)192alice # [ 0.000000] Policy zone: DMA193clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB194alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off195clare # [ 0.000000] software IO TLB: area num 1.196alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1197clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)198alice # [ 0.000000] allocated 2097152 bytes of page_ext199clare # [ 0.000000] Fallback order for Node 0: 0200alice # [ 0.000000] ftrace: allocating 75039 entries in 294 pages201clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144202alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups203clare # [ 0.000000] Policy zone: DMA204alice # [ 0.000000] rcu: Hierarchical RCU implementation.205alice # [ 0.000000] rcu: RCU event tracing is enabled.206clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off207clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1208alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.209clare # [ 0.000000] allocated 2097152 bytes of page_ext210alice # [ 0.000000] Trampoline variant of Tasks RCU enabled.211clare # [ 0.000000] ftrace: allocating 75039 entries in 294 pages212alice # [ 0.000000] Rude variant of Tasks RCU enabled.213clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups214alice # [ 0.000000] Tracing variant of Tasks RCU enabled.215clare # [ 0.000000] rcu: Hierarchical RCU implementation.216alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.217clare # [ 0.000000] rcu: RCU event tracing is enabled.218alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1219clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.220bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]221alice # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.222clare # [ 0.000000] Trampoline variant of Tasks RCU enabled.223clare # [ 0.000000] Rude variant of Tasks RCU enabled.224clare # [ 0.000000] Tracing variant of Tasks RCU enabled.225alice # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.226bob # [ 0.000000] Linux version 6.18.55 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Oct 3 10:39:03 UTC 2026227bob # [ 0.000000] KASLR enabled228clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.229bob # [ 0.000000] random: crng init done230alice # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.231clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1232alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0233bob # [ 0.000000] Machine model: linux,dummy-virt234bob # [ 0.000000] efi: UEFI not found.235alice # [ 0.000000] GICv3: 256 SPIs implemented236clare # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.237alice # [ 0.000000] GICv3: 0 Extended SPIs implemented238bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT239alice # [ 0.000000] Root IRQ handler: gic_handle_irq240clare # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.241alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI242bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]243alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0244bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]245clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.246bob # [ 0.000000] Zone ranges:247alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000248clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0249bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]250alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]251bob # [ 0.000000] DMA32 empty252clare # [ 0.000000] GICv3: 256 SPIs implemented253bob # [ 0.000000] Normal empty254clare # [ 0.000000] GICv3: 0 Extended SPIs implemented255bob # [ 0.000000] Device empty256alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)257clare # [ 0.000000] Root IRQ handler: gic_handle_irq258bob # [ 0.000000] Movable zone start for each node259bob # [ 0.000000] Early memory node ranges260clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI261alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)262clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0263bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]264alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000265clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000266bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]267clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]268alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000269bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000270bob # [ 0.000000] psci: probing for conduit method from DT.271alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.272clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)273bob # [ 0.000000] psci: PSCIv1.3 detected in firmware.274alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns275bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs276clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)277bob # [ 0.000000] psci: Trusted OS migration not required278alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040279clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000280bob # [ 0.000000] psci: SMC Calling Convention v1.1281alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).282clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000283bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)284clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.285alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns286bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u311296287bob # [ 0.000000] Detected PIPT I-cache on CPU0288alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns289clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns290bob # [ 0.000000] CPU features: detected: GICv3 CPU interface291alice # [ 0.000056] arm-pv: using stolen time PV292clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040293bob # [ 0.000000] CPU features: detected: Spectre-v4294bob # [ 0.000000] CPU features: detected: Spectre-BHB295clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).296alice # [ 0.000330] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)297alice # [ 0.000611] Console: colour dummy device 80x25298bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR299alice # [ 0.000623] printk: legacy console [tty0] enabled300clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns301bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)302clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns303bob # [ 0.000000] CPU features: detected: ARM erratum 1418040304alice # [ 0.000844] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)305clare # [ 0.000063] arm-pv: using stolen time PV306alice # [ 0.000849] pid_max: default: 32768 minimum: 301307bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion308alice # [ 0.000934] LSM: initializing lsm=capability,landlock,yama,bpf,ima309bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing310clare # [ 0.000397] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)311alice # [ 0.001064] landlock: Up and running.312clare # [ 0.000700] Console: colour dummy device 80x25313bob # [ 0.000000] alternatives: applying boot alternatives314alice # [ 0.001066] Yama: becoming mindful.315clare # [ 0.000711] printk: legacy console [tty0] enabled316alice # [ 0.001404] LSM support for eBPF active317alice # [ 0.001590] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)318clare # [ 0.000928] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)319clare # [ 0.000933] pid_max: default: 32768 minimum: 301320alice # [ 0.001609] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)321alice # [ 0.004460] rcu: Hierarchical SRCU implementation.322clare # [ 0.001007] LSM: initializing lsm=capability,landlock,yama,bpf,ima323clare # [ 0.001147] landlock: Up and running.324alice # [ 0.004466] rcu: Max phase no-delay instances is 1000.325bob # [ 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/y6bki65bnyl7icghswfggs0rgqryrg6y-nixos-system-bob-test/init regInfo=/nix/.ro-store/qb8fazqzc1z6aw1lplcpid4zbvyzpbfn-closure-info/registration console=ttyAMA0,115200n8 console=tty0326clare # [ 0.001150] Yama: becoming mindful.327alice # [ 0.005452] fsl-mc MSI: its@8080000 domain created328clare # [ 0.001530] LSM support for eBPF active329alice # [ 0.005657] EFI services will not be available.330clare # [ 0.001707] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)331alice # [ 0.005742] smp: Bringing up secondary CPUs ...332bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/qb8fazqzc1z6aw1lplcpid4zbvyzpbfn-closure-info/registration", will be passed to user space.333alice # [ 0.005776] smp: Brought up 1 node, 1 CPU334alice # [ 0.005781] SMP: Total of 1 processors activated.335clare # [ 0.001726] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)336bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes337alice # [ 0.005782] CPU: All CPU(s) started at EL1338clare # [ 0.004570] rcu: Hierarchical SRCU implementation.339alice # [ 0.005796] CPU features: detected: 32-bit EL0 Support340clare # [ 0.004576] rcu: Max phase no-delay instances is 1000.341bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)342clare # [ 0.005520] fsl-mc MSI: its@8080000 domain created343bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)344alice # [ 0.005800] CPU features: detected: Data cache clean to the PoU not required for I/D coherence345clare # [ 0.005713] EFI services will not be available.346clare # [ 0.005796] smp: Bringing up secondary CPUs ...347alice # [ 0.005803] CPU features: detected: Common not Private translations348bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB349clare # [ 0.005826] smp: Brought up 1 node, 1 CPU350bob # [ 0.000000] software IO TLB: area num 1.351alice # [ 0.005805] CPU features: detected: CRC32 instructions352clare # [ 0.005830] SMP: Total of 1 processors activated.353alice # [ 0.005807] CPU features: detected: Data cache clean to Point of Persistence354bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)355clare # [ 0.005832] CPU: All CPU(s) started at EL1356bob # [ 0.000000] Fallback order for Node 0: 0357clare # [ 0.005844] CPU features: detected: 32-bit EL0 Support358alice # [ 0.005809] CPU features: detected: Enhanced Virtualization Traps359bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144360alice # [ 0.005820] CPU features: detected: RCpc load-acquire (LDAPR)361bob # [ 0.000000] Policy zone: DMA362clare # [ 0.005847] CPU features: detected: Data cache clean to the PoU not required for I/D coherence363alice # [ 0.005822] CPU features: detected: LSE atomic instructions364clare # [ 0.005850] CPU features: detected: Common not Private translations365bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off366alice # [ 0.005824] CPU features: detected: Privileged Access Never367clare # [ 0.005852] CPU features: detected: CRC32 instructions368alice # [ 0.005826] CPU features: detected: PMUv3369bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1370alice # [ 0.005828] CPU features: detected: RAS Extension Support371bob # [ 0.000000] allocated 2097152 bytes of page_ext372clare # [ 0.005854] CPU features: detected: Data cache clean to Point of Persistence373bob # [ 0.000000] ftrace: allocating 75039 entries in 294 pages374alice # [ 0.005831] CPU features: detected: Speculative Store Bypassing Safe (SSBS)375clare # [ 0.005857] CPU features: detected: Enhanced Virtualization Traps376bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups377clare # [ 0.005867] CPU features: detected: RCpc load-acquire (LDAPR)378alice # [ 0.005853] alternatives: applying system-wide alternatives379bob # [ 0.000000] rcu: Hierarchical RCU implementation.380clare # [ 0.005869] CPU features: detected: LSE atomic instructions381bob # [ 0.000000] rcu: RCU event tracing is enabled.382alice # [ 0.007233] CPU features: detected: Hardware dirty bit management on CPU0383clare # [ 0.005871] CPU features: detected: Privileged Access Never384bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.385clare # [ 0.005873] CPU features: detected: PMUv3386bob # [ 0.000000] Trampoline variant of Tasks RCU enabled.387clare # [ 0.005875] CPU features: detected: RAS Extension Support388alice # [ 0.007432] Memory: 891144K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116128K reserved, 32768K cma-reserved)389bob # [ 0.000000] Rude variant of Tasks RCU enabled.390alice # [ 0.007945] devtmpfs: initialized391clare # [ 0.005879] CPU features: detected: Speculative Store Bypassing Safe (SSBS)392bob # [ 0.000000] Tracing variant of Tasks RCU enabled.393alice # [ 0.009501] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)394clare # [ 0.005899] alternatives: applying system-wide alternatives395bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.396clare # [ 0.007334] CPU features: detected: Hardware dirty bit management on CPU0397alice # [ 0.009523] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).398bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1399alice # [ 0.009650] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL400alice # [ 0.009655] 0 pages in range for non-PLT usage401clare # [ 0.007537] Memory: 890924K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116132K reserved, 32768K cma-reserved)402bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.403alice # [ 0.009656] 508192 pages in range for PLT usage404clare # [ 0.008003] devtmpfs: initialized405alice # [ 0.009759] pinctrl core: initialized pinctrl subsystem406bob # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.407clare # [ 0.009517] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)408alice # [ 0.010576] DMI not present or invalid.409alice # [ 0.014010] NET: Registered PF_NETLINK/PF_ROUTE protocol family410bob # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.411clare # [ 0.009538] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).412alice # [ 0.016474] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations413bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0414clare # [ 0.009670] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL415bob # [ 0.000000] GICv3: 256 SPIs implemented416clare # [ 0.009675] 0 pages in range for non-PLT usage417alice # [ 0.016606] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations418bob # [ 0.000000] GICv3: 0 Extended SPIs implemented419clare # [ 0.009676] 508192 pages in range for PLT usage420bob # [ 0.000000] Root IRQ handler: gic_handle_irq421clare # [ 0.009762] pinctrl core: initialized pinctrl subsystem422alice # [ 0.016755] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations423bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI424clare # [ 0.010559] DMI not present or invalid.425alice # [ 0.016772] audit: initializing netlink subsys (disabled)426bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0427clare # [ 0.013816] NET: Registered PF_NETLINK/PF_ROUTE protocol family428alice # [ 0.017418] thermal_sys: Registered thermal governor 'fair_share'429bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000430clare # [ 0.016133] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations431alice # [ 0.017420] thermal_sys: Registered thermal governor 'bang_bang'432bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]433clare # [ 0.016259] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations434alice # [ 0.017423] thermal_sys: Registered thermal governor 'step_wise'435bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)436alice # [ 0.017425] thermal_sys: Registered thermal governor 'user_space'437clare # [ 0.016406] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations438alice # [ 0.017427] thermal_sys: Registered thermal governor 'power_allocator'439clare # [ 0.016421] audit: initializing netlink subsys (disabled)440bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)441clare # [ 0.017009] thermal_sys: Registered thermal governor 'fair_share'442bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000443alice # [ 0.017468] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1444clare # [ 0.017011] thermal_sys: Registered thermal governor 'bang_bang'445alice # [ 0.017477] cpuidle: using governor ladder446bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000447alice # [ 0.017482] cpuidle: using governor menu448clare # [ 0.017014] thermal_sys: Registered thermal governor 'step_wise'449bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.450alice # [ 0.017603] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.451clare # [ 0.017016] thermal_sys: Registered thermal governor 'user_space'452alice # [ 0.017624] ASID allocator initialised with 32768 entries453clare # [ 0.017018] thermal_sys: Registered thermal governor 'power_allocator'454alice # [ 0.018310] Serial: AMBA PL011 UART driver455bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns456clare # [ 0.017058] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1457bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040458alice # [ 0.021934] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1459clare # [ 0.017066] cpuidle: using governor ladder460alice # [ 0.022108] printk: console [ttyAMA0] enabled461bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).462clare # [ 0.017071] cpuidle: using governor menu463clare # [ 0.017200] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.464bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns465clare # [ 0.017222] ASID allocator initialised with 32768 entries466clare # [ 0.017884] Serial: AMBA PL011 UART driver467bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns468bob # [ 0.000050] arm-pv: using stolen time PV469clare # [ 0.021332] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1470clare # [ 0.021498] printk: console [ttyAMA0] enabled471bob # [ 0.000332] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)472bob # [ 0.000576] Console: colour dummy device 80x25473bob # [ 0.000586] printk: legacy console [tty0] enabled474bob # [ 0.000785] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)475bob # [ 0.000790] pid_max: default: 32768 minimum: 301476bob # [ 0.000862] LSM: initializing lsm=capability,landlock,yama,bpf,ima477bob # [ 0.000981] landlock: Up and running.478bob # [ 0.000983] Yama: becoming mindful.479bob # [ 0.001314] LSM support for eBPF active480bob # [ 0.001465] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)481bob # [ 0.001484] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)482bob # [ 0.003875] rcu: Hierarchical SRCU implementation.483bob # [ 0.003881] rcu: Max phase no-delay instances is 1000.484bob # [ 0.004898] fsl-mc MSI: its@8080000 domain created485bob # [ 0.005076] EFI services will not be available.486bob # [ 0.005164] smp: Bringing up secondary CPUs ...487bob # [ 0.005192] smp: Brought up 1 node, 1 CPU488bob # [ 0.005196] SMP: Total of 1 processors activated.489bob # [ 0.005198] CPU: All CPU(s) started at EL1490bob # [ 0.005209] CPU features: detected: 32-bit EL0 Support491bob # [ 0.005212] CPU features: detected: Data cache clean to the PoU not required for I/D coherence492bob # [ 0.005215] CPU features: detected: Common not Private translations493bob # [ 0.005217] CPU features: detected: CRC32 instructions494alice # [ 0.094075] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages495bob # [ 0.005219] CPU features: detected: Data cache clean to Point of Persistence496alice # [ 0.094088] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page497bob # [ 0.005221] CPU features: detected: Enhanced Virtualization Traps498alice # [ 0.094091] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages499bob # [ 0.005231] CPU features: detected: RCpc load-acquire (LDAPR)500alice # [ 0.094095] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page501bob # [ 0.005233] CPU features: detected: LSE atomic instructions502alice # [ 0.094097] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages503bob # [ 0.005234] CPU features: detected: Privileged Access Never504clare # [ 0.093698] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages505alice # [ 0.094099] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page506bob # [ 0.005236] CPU features: detected: PMUv3507bob # [ 0.005238] CPU features: detected: RAS Extension Support508clare # [ 0.093711] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page509alice # [ 0.094101] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages510bob # [ 0.005241] CPU features: detected: Speculative Store Bypassing Safe (SSBS)511alice # [ 0.094103] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page512clare # [ 0.093713] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages513alice # [ 0.095044] fbcon: Taking over console514bob # [ 0.005262] alternatives: applying system-wide alternatives515clare # [ 0.093718] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page516alice # [ 0.095054] ACPI: Interpreter disabled.517bob # [ 0.006627] CPU features: detected: Hardware dirty bit management on CPU0518alice # [ 0.095701] iommu: Default domain type: Translated519clare # [ 0.093720] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages520clare # [ 0.093722] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page521alice # [ 0.095705] iommu: DMA domain TLB invalidation policy: strict mode522alice # [ 0.096004] SCSI subsystem initialized523clare # [ 0.093724] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages524bob # [ 0.006849] Memory: 891172K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116112K reserved, 32768K cma-reserved)525alice # [ 0.096204] usbcore: registered new interface driver usbfs526bob # [ 0.007270] devtmpfs: initialized527clare # [ 0.093726] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page528alice # [ 0.096233] usbcore: registered new interface driver hub529clare # [ 0.094694] fbcon: Taking over console530bob # [ 0.008745] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)531alice # [ 0.096248] usbcore: registered new device driver usb532clare # [ 0.094703] ACPI: Interpreter disabled.533alice # [ 0.096476] pps_core: LinuxPPS API ver. 1 registered534clare # [ 0.095346] iommu: Default domain type: Translated535bob # [ 0.008767] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).536clare # [ 0.095350] iommu: DMA domain TLB invalidation policy: strict mode537bob # [ 0.008888] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL538alice # [ 0.096479] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>539clare # [ 0.095605] SCSI subsystem initialized540alice # [ 0.096484] PTP clock support registered541bob # [ 0.008893] 0 pages in range for non-PLT usage542clare # [ 0.095804] usbcore: registered new interface driver usbfs543alice # [ 0.096518] EDAC MC: Ver: 3.0.0544bob # [ 0.008894] 508192 pages in range for PLT usage545clare # [ 0.095833] usbcore: registered new interface driver hub546bob # [ 0.008994] pinctrl core: initialized pinctrl subsystem547clare # [ 0.095848] usbcore: registered new device driver usb548bob # [ 0.009756] DMI not present or invalid.549clare # [ 0.096062] pps_core: LinuxPPS API ver. 1 registered550bob # [ 0.013025] NET: Registered PF_NETLINK/PF_ROUTE protocol family551bob # [ 0.015286] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations552clare # [ 0.096065] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>553clare # [ 0.096070] PTP clock support registered554clare # [ 0.096105] EDAC MC: Ver: 3.0.0555bob # [ 0.015412] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations556bob # [ 0.015558] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations557clare # [ 0.105556] scmi_core: SCMI protocol bus registered558bob # [ 0.015574] audit: initializing netlink subsys (disabled)559bob # [ 0.016107] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1560bob # [ 0.016135] thermal_sys: Registered thermal governor 'fair_share'561alice # [ 0.105938] scmi_core: SCMI protocol bus registered562alice # [ 0.106250] FPGA manager framework563bob # [ 0.016137] thermal_sys: Registered thermal governor 'bang_bang'564alice # [ 0.109883] vgaarb: loaded565bob # [ 0.016139] thermal_sys: Registered thermal governor 'step_wise'566bob # [ 0.016141] thermal_sys: Registered thermal governor 'user_space'567bob # [ 0.016143] thermal_sys: Registered thermal governor 'power_allocator'568bob # [ 0.016177] cpuidle: using governor ladder569clare # [ 0.106288] FPGA manager framework570bob # [ 0.016183] cpuidle: using governor menu571clare # [ 0.109509] vgaarb: loaded572bob # [ 0.016292] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.573bob # [ 0.016311] ASID allocator initialised with 32768 entries574bob # [ 0.016946] Serial: AMBA PL011 UART driver575alice # [ 0.110930] clocksource: Switched to clocksource arch_sys_counter576bob # [ 0.020394] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1577bob # [ 0.020531] printk: console [ttyAMA0] enabled578clare # [ 0.110186] clocksource: Switched to clocksource arch_sys_counter579alice # [ 0.120078] VFS: Disk quotas dquot_6.6.0580alice # [ 0.120104] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)581alice # [ 0.121498] netfs: FS-Cache loaded582alice # [ 0.121609] pnp: PnP ACPI: disabled583clare # [ 0.119316] VFS: Disk quotas dquot_6.6.0584clare # [ 0.119340] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)585clare # [ 0.120796] netfs: FS-Cache loaded586clare # [ 0.120884] pnp: PnP ACPI: disabled587alice # [ 0.124860] NET: Registered PF_INET protocol family588bob # [ 0.094238] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages589alice # [ 0.125036] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)590bob # [ 0.094252] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page591bob # [ 0.094254] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages592bob # [ 0.094258] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page593clare # [ 0.124115] NET: Registered PF_INET protocol family594bob # [ 0.094261] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages595clare # [ 0.124282] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)596bob # [ 0.094262] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page597bob # [ 0.094265] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages598bob # [ 0.094267] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page599bob # [ 0.095242] fbcon: Taking over console600bob # [ 0.095252] ACPI: Interpreter disabled.601bob # [ 0.095904] iommu: Default domain type: Translated602bob # [ 0.095908] iommu: DMA domain TLB invalidation policy: strict mode603bob # [ 0.096208] SCSI subsystem initialized604bob # [ 0.096413] usbcore: registered new interface driver usbfs605bob # [ 0.096442] usbcore: registered new interface driver hub606bob # [ 0.096456] usbcore: registered new device driver usb607bob # [ 0.096662] pps_core: LinuxPPS API ver. 1 registered608bob # [ 0.096665] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>609bob # [ 0.096669] PTP clock support registered610bob # [ 0.096701] EDAC MC: Ver: 3.0.0611bob # [ 0.106479] scmi_core: SCMI protocol bus registered612bob # [ 0.107231] FPGA manager framework613bob # [ 0.110405] vgaarb: loaded614bob # [ 0.111113] clocksource: Switched to clocksource arch_sys_counter615alice # [ 0.149066] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)616alice # [ 0.149091] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)617bob # [ 0.119878] VFS: Disk quotas dquot_6.6.0618alice # [ 0.149104] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)619bob # [ 0.119907] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)620bob # [ 0.121382] netfs: FS-Cache loaded621alice # [ 0.149140] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)622bob # [ 0.121497] pnp: PnP ACPI: disabled623alice # [ 0.149206] TCP: Hash tables configured (established 8192 bind 8192)624alice # [ 0.149302] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)625alice # [ 0.149362] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)626alice # [ 0.149410] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)627alice # [ 0.149523] NET: Registered PF_UNIX/PF_LOCAL protocol family628alice # [ 0.149568] NET: Registered PF_XDP protocol family629alice # [ 0.149589] PCI: CLS 0 bytes, default 64630bob # [ 0.124763] NET: Registered PF_INET protocol family631alice # [ 0.149799] Trying to unpack rootfs image as initramfs...632bob # [ 0.124936] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)633clare # [ 0.148820] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)634clare # [ 0.148844] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)635clare # [ 0.148856] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)636clare # [ 0.148892] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)637clare # [ 0.148958] TCP: Hash tables configured (established 8192 bind 8192)638clare # [ 0.149251] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)639clare # [ 0.149305] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)640clare # [ 0.149354] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)641clare # [ 0.149459] NET: Registered PF_UNIX/PF_LOCAL protocol family642clare # [ 0.149506] NET: Registered PF_XDP protocol family643clare # [ 0.149528] PCI: CLS 0 bytes, default 64644clare # [ 0.149729] Trying to unpack rootfs image as initramfs...645alice # [ 0.162953] kvm [1]: HYP mode not available646clare # [ 0.164326] kvm [1]: HYP mode not available647bob # [ 0.149042] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)648bob # [ 0.149065] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)649bob # [ 0.149077] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)650bob # [ 0.149111] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)651bob # [ 0.149178] TCP: Hash tables configured (established 8192 bind 8192)652bob # [ 0.149279] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)653bob # [ 0.149335] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)654bob # [ 0.149357] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)655bob # [ 0.149462] NET: Registered PF_UNIX/PF_LOCAL protocol family656bob # [ 0.149489] NET: Registered PF_XDP protocol family657bob # [ 0.149508] PCI: CLS 0 bytes, default 64658bob # [ 0.149700] Trying to unpack rootfs image as initramfs...659bob # [ 0.163134] kvm [1]: HYP mode not available660clare # [ 0.226576] Initialise system trusted keyrings661clare # [ 0.226728] workingset: timestamp_bits=42 max_order=18 bucket_order=0662clare # [ 0.227058] squashfs: version 4.0 (2009/01/31) Phillip Lougher663clare # [ 0.227091] 9p: Installing v9fs 9p2000 file system support664alice # [ 0.227370] Initialise system trusted keyrings665alice # [ 0.227524] workingset: timestamp_bits=42 max_order=18 bucket_order=0666alice # [ 0.227898] squashfs: version 4.0 (2009/01/31) Phillip Lougher667alice # [ 0.227935] 9p: Installing v9fs 9p2000 file system support668clare # [ 0.245868] Key type asymmetric registered669clare # [ 0.245886] Asymmetric key parser 'x509' registered670clare # [ 0.245951] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)671alice # [ 0.256484] Key type asymmetric registered672alice # [ 0.256501] Asymmetric key parser 'x509' registered673alice # [ 0.256573] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)674alice # [ 0.256822] io scheduler mq-deadline registered675bob # [ 0.227626] Initialise system trusted keyrings676alice # [ 0.256826] io scheduler kyber registered677bob # [ 0.227765] workingset: timestamp_bits=42 max_order=18 bucket_order=0678clare # [ 0.254256] io scheduler mq-deadline registered679bob # [ 0.228109] squashfs: version 4.0 (2009/01/31) Phillip Lougher680clare # [ 0.254266] io scheduler kyber registered681bob # [ 0.228144] 9p: Installing v9fs 9p2000 file system support682clare # [ 0.256713] pl061_gpio 9030000.pl061: PL061 GPIO chip registered683clare # [ 0.257046] ledtrig-cpu: registered to indicate activity on CPUs684clare # [ 0.257387] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:685alice # [ 0.261139] pl061_gpio 9030000.pl061: PL061 GPIO chip registered686clare # [ 0.257403] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000687alice # [ 0.261483] ledtrig-cpu: registered to indicate activity on CPUs688clare # [ 0.257416] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000689alice # [ 0.261793] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:690clare # [ 0.257420] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000691alice # [ 0.261810] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000692clare # [ 0.257445] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits693alice # [ 0.261823] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000694clare # [ 0.257465] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]695alice # [ 0.261828] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000696clare # [ 0.257530] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00697alice # [ 0.261867] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits698clare # [ 0.257550] pci_bus 0000:00: root bus resource [bus 00-ff]699alice # [ 0.261887] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]700clare # [ 0.257554] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]701clare # [ 0.257556] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]702alice # [ 0.261969] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00703alice # [ 0.261990] pci_bus 0000:00: root bus resource [bus 00-ff]704clare # [ 0.257559] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]705alice # [ 0.261993] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]706clare # [ 0.257628] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint707alice # [ 0.261996] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]708clare # [ 0.258070] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint709alice # [ 0.261998] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]710alice # [ 0.262071] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint711alice # [ 0.262525] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint712alice # [ 0.262650] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]713alice # [ 0.262660] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]714alice # [ 0.262678] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]715alice # [ 0.262687] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]716clare # [ 0.274258] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]717clare # [ 0.274270] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]718clare # [ 0.274289] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]719clare # [ 0.274298] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]720clare # [ 0.274639] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint721alice # [ 0.279064] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint722clare # [ 0.274762] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]723alice # [ 0.279194] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]724clare # [ 0.274771] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]725alice # [ 0.279203] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]726bob # [ 0.246941] Key type asymmetric registered727clare # [ 0.274788] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]728alice # [ 0.279222] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]729bob # [ 0.246957] Asymmetric key parser 'x509' registered730clare # [ 0.275094] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint731bob # [ 0.247008] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)732alice # [ 0.279556] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint733clare # [ 0.275213] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]734alice # [ 0.279677] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]735clare # [ 0.275222] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]736alice # [ 0.279686] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]737clare # [ 0.275239] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]738alice # [ 0.279704] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]739clare # [ 0.275546] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint740alice # [ 0.280031] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint741clare # [ 0.275667] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]742alice # [ 0.280153] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]743clare # [ 0.275676] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]744alice # [ 0.280162] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]745clare # [ 0.275693] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]746bob # [ 0.256389] io scheduler mq-deadline registered747alice # [ 0.280181] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]748bob # [ 0.256396] io scheduler kyber registered749clare # [ 0.275701] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]750alice # [ 0.280189] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]751bob # [ 0.258707] pl061_gpio 9030000.pl061: PL061 GPIO chip registered752clare # [ 0.276008] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint753bob # [ 0.259052] ledtrig-cpu: registered to indicate activity on CPUs754alice # [ 0.280501] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint755clare # [ 0.276131] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]756alice # [ 0.280626] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]757clare # [ 0.276148] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]758alice # [ 0.280643] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]759clare # [ 0.276465] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint760alice # [ 0.280956] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint761clare # [ 0.276592] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]762alice # [ 0.281079] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]763clare # [ 0.276609] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]764alice # [ 0.281096] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]765clare # [ 0.276908] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint766bob # [ 0.260938] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:767alice # [ 0.281394] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint768clare # [ 0.277028] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]769alice # [ 0.281514] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]770bob # [ 0.260956] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000771clare # [ 0.277216] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint772alice # [ 0.281701] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint773bob # [ 0.260969] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000774clare # [ 0.277339] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]775alice # [ 0.281826] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]776clare # [ 0.277356] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]777bob # [ 0.260973] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000778alice # [ 0.281844] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]779clare # [ 0.277659] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint780bob # [ 0.261003] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits781alice # [ 0.282148] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint782clare # [ 0.277784] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]783bob # [ 0.261023] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]784alice # [ 0.282276] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]785clare # [ 0.277802] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]786bob # [ 0.261088] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00787clare # [ 0.278107] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint788alice # [ 0.282294] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]789bob # [ 0.261108] pci_bus 0000:00: root bus resource [bus 00-ff]790alice # [ 0.282597] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint791clare # [ 0.294036] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]792bob # [ 0.261111] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]793alice # [ 0.282720] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]794clare # [ 0.294056] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]795bob # [ 0.261113] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]796alice # [ 0.282737] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]797bob # [ 0.261116] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]798bob # [ 0.261182] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint799alice # [ 0.298132] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint800bob # [ 0.261617] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint801alice # [ 0.298327] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]802bob # [ 0.261743] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]803alice # [ 0.298337] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]804bob # [ 0.261752] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]805alice # [ 0.298354] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]806bob # [ 0.261771] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]807alice # [ 0.298682] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint808bob # [ 0.261780] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]809clare # [ 0.295311] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint810alice # [ 0.298803] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]811clare # [ 0.295596] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]812bob # [ 0.262105] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint813alice # [ 0.298812] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]814clare # [ 0.295606] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]815bob # [ 0.262232] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]816alice # [ 0.298830] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]817clare # [ 0.295623] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]818bob # [ 0.262241] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]819clare # [ 0.295933] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint820bob # [ 0.262259] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]821clare # [ 0.296055] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]822bob # [ 0.262571] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint823clare # [ 0.296063] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]824bob # [ 0.262696] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]825clare # [ 0.296081] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]826bob # [ 0.262704] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]827clare # [ 0.296507] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned828bob # [ 0.262723] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]829clare # [ 0.296514] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned830bob # [ 0.263050] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint831clare # [ 0.296517] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned832clare # [ 0.296549] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned833alice # [ 0.303114] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned834clare # [ 0.296580] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned835alice # [ 0.303122] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned836clare # [ 0.296610] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned837alice # [ 0.303125] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned838clare # [ 0.296642] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned839alice # [ 0.303158] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned840clare # [ 0.296671] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned841alice # [ 0.303189] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned842clare # [ 0.296703] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned843alice # [ 0.303219] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned844clare # [ 0.296733] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned845alice # [ 0.303250] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned846clare # [ 0.296762] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned847alice # [ 0.303279] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned848clare # [ 0.296791] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned849alice # [ 0.303309] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned850clare # [ 0.296873] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned851alice # [ 0.303339] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned852clare # [ 0.296921] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned853alice # [ 0.303368] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned854clare # [ 0.296934] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned855clare # [ 0.296945] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned856alice # [ 0.303398] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned857clare # [ 0.296958] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned858alice # [ 0.303452] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned859clare # [ 0.296970] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned860alice # [ 0.303481] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned861clare # [ 0.296982] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned862alice # [ 0.303495] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned863clare # [ 0.296994] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned864alice # [ 0.303523] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned865clare # [ 0.297006] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned866alice # [ 0.303535] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned867bob # [ 0.283227] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]868clare # [ 0.297018] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned869bob # [ 0.283239] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]870alice # [ 0.303547] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned871clare # [ 0.297031] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned872bob # [ 0.283258] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]873alice # [ 0.303559] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned874clare # [ 0.297042] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned875bob # [ 0.283267] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]876alice # [ 0.303571] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned877clare # [ 0.297054] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned878alice # [ 0.303583] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned879bob # [ 0.283602] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint880clare # [ 0.297066] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned881alice # [ 0.303595] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned882bob # [ 0.283731] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]883clare # [ 0.297078] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned884bob # [ 0.283749] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]885alice # [ 0.303607] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned886clare # [ 0.297090] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned887alice # [ 0.303619] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned888bob # [ 0.284075] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint889clare # [ 0.297101] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned890alice # [ 0.303630] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned891clare # [ 0.297122] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned892bob # [ 0.284202] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]893alice # [ 0.303642] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned894clare # [ 0.297134] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned895bob # [ 0.284220] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]896alice # [ 0.303654] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned897clare # [ 0.297150] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]898bob # [ 0.284488] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint899alice # [ 0.303665] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned900clare # [ 0.297154] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]901bob # [ 0.284611] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]902alice # [ 0.303677] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned903clare # [ 0.297162] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]904alice # [ 0.303688] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned905clare # [ 0.297694] pci 0000:00:07.0: enabling device (0000 -> 0002)906bob # [ 0.284792] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint907alice # [ 0.303700] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned908bob # [ 0.284918] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]909alice # [ 0.303716] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]910bob # [ 0.284936] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]911alice # [ 0.303720] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]912bob # [ 0.285242] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint913alice # [ 0.303728] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]914bob # [ 0.285370] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]915alice # [ 0.304270] pci 0000:00:07.0: enabling device (0000 -> 0002)916bob # [ 0.285387] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]917bob # [ 0.285695] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint918bob # [ 0.285821] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]919bob # [ 0.285839] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]920bob # [ 0.286151] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint921bob # [ 0.286312] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]922bob # [ 0.286322] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]923bob # [ 0.286340] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]924bob # [ 0.286766] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint925bob # [ 0.286891] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]926bob # [ 0.286899] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]927bob # [ 0.286918] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]928bob # [ 0.301939] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned929bob # [ 0.301947] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned930bob # [ 0.301950] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned931bob # [ 0.301983] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned932bob # [ 0.302013] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned933bob # [ 0.302044] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned934bob # [ 0.302075] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned935bob # [ 0.302105] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned936clare # [ 0.332284] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)937bob # [ 0.302134] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned938clare # [ 0.333797] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)939bob # [ 0.302165] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned940bob # [ 0.302195] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned941bob # [ 0.302225] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned942alice # [ 0.336544] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)943alice # [ 0.338068] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)944bob # [ 0.302283] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned945bob # [ 0.302312] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned946bob # [ 0.302323] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned947bob # [ 0.302366] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned948bob # [ 0.302379] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned949bob # [ 0.302391] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned950bob # [ 0.302403] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned951bob # [ 0.302415] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned952bob # [ 0.302428] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned953bob # [ 0.302440] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned954bob # [ 0.302452] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned955bob # [ 0.302463] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned956bob # [ 0.302475] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned957bob # [ 0.302487] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned958bob # [ 0.302499] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned959bob # [ 0.302510] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned960bob # [ 0.302522] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned961bob # [ 0.302533] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned962bob # [ 0.302545] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned963clare # [ 0.343561] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)964bob # [ 0.302560] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]965clare # [ 0.344871] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)966bob # [ 0.302564] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]967bob # [ 0.302573] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]968alice # [ 0.347677] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)969alice # [ 0.349736] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)970alice # [ 0.352377] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)971alice # [ 0.353920] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)972bob # [ 0.327196] pci 0000:00:07.0: enabling device (0000 -> 0002)973bob # [ 0.327547] pci 0000:00:07.0: quirk_usb_early_handoff+0x0/0xa60 took 23875 usecs974clare # [ 0.354310] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)975clare # [ 0.356321] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)976clare # [ 0.357801] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)977clare # [ 0.360594] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)978clare # [ 0.362003] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)979alice # [ 0.363057] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)980alice # [ 0.364670] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)981alice # [ 0.366239] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)982bob # [ 0.341873] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)983bob # [ 0.344138] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)984bob # [ 0.345556] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)985clare # [ 0.371378] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)986bob # [ 0.346903] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)987clare # [ 0.373544] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)988alice # [ 0.376226] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)989alice # [ 0.378288] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)990bob # [ 0.356137] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)991clare # [ 0.382908] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled992bob # [ 0.357891] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)993clare # [ 0.383866] msm_serial: driver initialized994clare # [ 0.383968] SuperH (H)SCI(F) driver initialized995clare # [ 0.384009] STM32 USART driver initialized996bob # [ 0.360441] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)997bob # [ 0.361882] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)998alice # [ 0.391512] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled999alice # [ 0.392539] msm_serial: driver initialized1000alice # [ 0.392621] SuperH (H)SCI(F) driver initialized1001alice # [ 0.392663] STM32 USART driver initialized1002bob # [ 0.371254] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)1003bob # [ 0.372939] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)1004bob # [ 0.375043] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)1005clare # [ 0.406616] loop: module loaded1006clare # [ 0.406749] virtio_blk virtio2: 1/0/0 default/read/poll queues1007clare # [ 0.407344] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1008bob # [ 0.386563] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled1009clare # [ 0.411933] megasas: 07.734.00.00-rc11010clare # [ 0.412380] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1011alice # [ 0.415446] loop: module loaded1012alice # [ 0.415586] virtio_blk virtio2: 1/0/0 default/read/poll queues1013alice # [ 0.416272] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1014bob # [ 0.388094] msm_serial: driver initialized1015bob # [ 0.388164] SuperH (H)SCI(F) driver initialized1016bob # [ 0.388203] STM32 USART driver initialized1017clare # [ 0.415756] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001018clare # [ 0.415812] Intel/Sharp Extended Query Table at 0x00311019clare # [ 0.417421] Using buffer write method1020clare # [ 0.417468] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1021alice # [ 0.420726] megasas: 07.734.00.00-rc11022alice # [ 0.421239] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1023clare # [ 0.427319] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001024clare # [ 0.427332] Intel/Sharp Extended Query Table at 0x00311025clare # [ 0.428963] Using buffer write method1026clare # [ 0.428974] Concatenating MTD devices:1027clare # [ 0.428977] (0): "0.flash"1028clare # [ 0.428978] (1): "0.flash"1029clare # [ 0.428980] into device "0.flash"1030alice # [ 0.432318] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001031alice # [ 0.432350] Intel/Sharp Extended Query Table at 0x00311032alice # [ 0.434390] Using buffer write method1033alice # [ 0.434456] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1034alice # [ 0.438251] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001035alice # [ 0.438264] Intel/Sharp Extended Query Table at 0x00311036bob # [ 0.412458] loop: module loaded1037bob # [ 0.412594] virtio_blk virtio2: 1/0/0 default/read/poll queues1038bob # [ 0.413166] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1039bob # [ 0.416823] megasas: 07.734.00.00-rc11040bob # [ 0.417325] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1041alice # [ 0.447986] Using buffer write method1042alice # [ 0.448000] Concatenating MTD devices:1043alice # [ 0.448002] (0): "0.flash"1044alice # [ 0.448004] (1): "0.flash"1045alice # [ 0.448005] into device "0.flash"1046bob # [ 0.421152] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001047bob # [ 0.421180] Intel/Sharp Extended Query Table at 0x00311048bob # [ 0.422862] Using buffer write method1049bob # [ 0.422891] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1050bob # [ 0.433182] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001051bob # [ 0.433231] Intel/Sharp Extended Query Table at 0x00311052bob # [ 0.434878] Using buffer write method1053bob # [ 0.434889] Concatenating MTD devices:1054bob # [ 0.434891] (0): "0.flash"1055bob # [ 0.434893] (1): "0.flash"1056bob # [ 0.434895] into device "0.flash"1057clare # [ 0.639486] Freeing initrd memory: 26948K1058clare # [ 0.646438] tun: Universal TUN/TAP device driver, 1.61059clare # [ 0.648599] thunder_xcv, ver 1.01060clare # [ 0.648615] thunder_bgx, ver 1.01061clare # [ 0.648624] nicpf, ver 1.01062clare # [ 0.648944] e1000: Intel(R) PRO/1000 Network Driver1063clare # [ 0.648949] e1000: Copyright (c) 1999-2006 Intel Corporation.1064clare # [ 0.648962] e1000e: Intel(R) PRO/1000 Network Driver1065clare # [ 0.648967] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1066clare # [ 0.648979] igb: Intel(R) Gigabit Ethernet Network Driver1067clare # [ 0.648981] igb: Copyright (c) 2007-2014 Intel Corporation.1068clare # [ 0.648991] igbvf: Intel(R) Gigabit Virtual Function Network Driver1069clare # [ 0.648995] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1070clare # [ 0.649059] sky2: driver version 1.301071clare # [ 0.649905] usbcore: registered new interface driver usb-storage1072clare # [ 0.649945] usbcore: registered new interface driver usbserial_generic1073clare # [ 0.649951] usbserial: USB Serial support registered for generic1074alice # [ 0.658560] Freeing initrd memory: 26944K1075clare # [ 0.657022] ehci-pci 0000:00:07.0: EHCI Host Controller1076clare # [ 0.657053] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 11077clare # [ 0.657283] ehci-pci 0000:00:07.0: irq 16, io mem 0x100860001078clare # [ 0.657440] hv_vmbus: registering driver hyperv_keyboard1079clare # [ 0.658017] rtc-pl031 9010000.pl031: registered as rtc01080clare # [ 0.658043] rtc-pl031 9010000.pl031: setting system clock to 2026-10-05T10:48:57 UTC (1791197337)1081clare # [ 0.661149] i2c_dev: i2c /dev entries driver1082alice # [ 0.665499] tun: Universal TUN/TAP device driver, 1.61083alice # [ 0.668285] thunder_xcv, ver 1.01084alice # [ 0.668301] thunder_bgx, ver 1.01085alice # [ 0.668310] nicpf, ver 1.01086alice # [ 0.668648] e1000: Intel(R) PRO/1000 Network Driver1087alice # [ 0.668653] e1000: Copyright (c) 1999-2006 Intel Corporation.1088alice # [ 0.668665] e1000e: Intel(R) PRO/1000 Network Driver1089alice # [ 0.668670] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1090alice # [ 0.668682] igb: Intel(R) Gigabit Ethernet Network Driver1091alice # [ 0.668684] igb: Copyright (c) 2007-2014 Intel Corporation.1092alice # [ 0.668694] igbvf: Intel(R) Gigabit Virtual Function Network Driver1093alice # [ 0.668698] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1094alice # [ 0.668768] sky2: driver version 1.301095alice # [ 0.669655] usbcore: registered new interface driver usb-storage1096alice # [ 0.669732] usbcore: registered new interface driver usbserial_generic1097alice # [ 0.669738] usbserial: USB Serial support registered for generic1098alice # [ 0.670052] hv_vmbus: registering driver hyperv_keyboard1099bob # [ 0.650648] Freeing initrd memory: 26928K1100bob # [ 0.657591] tun: Universal TUN/TAP device driver, 1.61101bob # [ 0.660277] thunder_xcv, ver 1.01102bob # [ 0.660292] thunder_bgx, ver 1.01103bob # [ 0.660302] nicpf, ver 1.01104bob # [ 0.660622] e1000: Intel(R) PRO/1000 Network Driver1105bob # [ 0.660626] e1000: Copyright (c) 1999-2006 Intel Corporation.1106bob # [ 0.660640] e1000e: Intel(R) PRO/1000 Network Driver1107bob # [ 0.660645] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1108bob # [ 0.660656] igb: Intel(R) Gigabit Ethernet Network Driver1109bob # [ 0.660658] igb: Copyright (c) 2007-2014 Intel Corporation.1110bob # [ 0.660670] igbvf: Intel(R) Gigabit Virtual Function Network Driver1111bob # [ 0.660672] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1112bob # [ 0.660740] sky2: driver version 1.301113bob # [ 0.661552] usbcore: registered new interface driver usb-storage1114bob # [ 0.661629] usbcore: registered new interface driver usbserial_generic1115bob # [ 0.661636] usbserial: USB Serial support registered for generic1116bob # [ 0.661932] hv_vmbus: registering driver hyperv_keyboard1117bob # [ 0.662994] ehci-pci 0000:00:07.0: EHCI Host Controller1118bob # [ 0.663020] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 11119bob # [ 0.670567] ehci-pci 0000:00:07.0: irq 16, io mem 0x100860001120bob # [ 0.670763] rtc-pl031 9010000.pl031: registered as rtc01121bob # [ 0.670791] rtc-pl031 9010000.pl031: setting system clock to 2026-10-05T10:48:57 UTC (1791197337)1122bob # [ 0.670990] i2c_dev: i2c /dev entries driver1123bob # [ 0.674416] sdhci: Secure Digital Host Controller Interface driver1124bob # [ 0.674422] sdhci: Copyright(c) Pierre Ossman1125bob # [ 0.674571] Synopsys Designware Multimedia Card Interface Driver1126bob # [ 0.674768] sdhci-pltfm: SDHCI platform and OF driver helper1127alice # [ 0.677449] ehci-pci 0000:00:07.0: EHCI Host Controller1128clare # [ 0.663150] sdhci: Secure Digital Host Controller Interface driver1129bob # [ 0.677432] hid: raw HID events driver (C) Jiri Kosina1130clare # [ 0.663155] sdhci: Copyright(c) Pierre Ossman1131bob # [ 0.677573] usbcore: registered new interface driver usbhid1132alice # [ 0.677477] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 11133bob # [ 0.677576] usbhid: USB HID core driver1134clare # [ 0.663309] Synopsys Designware Multimedia Card Interface Driver1135alice # [ 0.677707] ehci-pci 0000:00:07.0: irq 16, io mem 0x100860001136clare # [ 0.663487] sdhci-pltfm: SDHCI platform and OF driver helper1137alice # [ 0.677893] rtc-pl031 9010000.pl031: registered as rtc01138clare # [ 0.664358] hid: raw HID events driver (C) Jiri Kosina1139alice # [ 0.677921] rtc-pl031 9010000.pl031: setting system clock to 2026-10-05T10:48:57 UTC (1791197337)1140clare # [ 0.664493] usbcore: registered new interface driver usbhid1141clare # [ 0.664495] usbhid: USB HID core driver1142alice # [ 0.678139] i2c_dev: i2c /dev entries driver1143alice # [ 0.679812] sdhci: Secure Digital Host Controller Interface driver1144alice # [ 0.679831] sdhci: Copyright(c) Pierre Ossman1145alice # [ 0.679998] Synopsys Designware Multimedia Card Interface Driver1146alice # [ 0.680190] sdhci-pltfm: SDHCI platform and OF driver helper1147bob # [ 0.688004] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.001148alice # [ 0.681136] hid: raw HID events driver (C) Jiri Kosina1149bob # [ 0.688399] hub 1-0:1.0: USB hub found1150bob # [ 0.688418] hub 1-0:1.0: 6 ports detected1151alice # [ 0.681283] usbcore: registered new interface driver usbhid1152clare # [ 0.715986] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.001153alice # [ 0.681286] usbhid: USB HID core driver1154clare # [ 0.716418] hub 1-0:1.0: USB hub found1155bob # [ 0.688712] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1156clare # [ 0.716437] hub 1-0:1.0: 6 ports detected1157bob # [ 0.689519] drop_monitor: Initializing network drop monitor service1158clare # [ 0.716739] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1159bob # [ 0.689659] NET: Registered PF_INET6 protocol family1160clare # [ 0.717616] drop_monitor: Initializing network drop monitor service1161bob # [ 0.690291] Segment Routing with IPv61162bob # [ 0.690308] In-situ OAM (IOAM) with IPv61163clare # [ 0.717729] NET: Registered PF_INET6 protocol family1164bob # [ 0.690365] NET: Registered PF_PACKET protocol family1165alice # [ 0.721596] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.001166bob # [ 0.690429] 9pnet: Installing 9P2000 support1167bob # [ 0.690457] Key type dns_resolver registered1168alice # [ 0.721784] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1169alice # [ 0.722680] drop_monitor: Initializing network drop monitor service1170clare # [ 0.720925] Segment Routing with IPv61171clare # [ 0.720943] In-situ OAM (IOAM) with IPv61172alice # [ 0.722832] NET: Registered PF_INET6 protocol family1173alice # [ 0.723324] hub 1-0:1.0: USB hub found1174clare # [ 0.720998] NET: Registered PF_PACKET protocol family1175alice # [ 0.723356] hub 1-0:1.0: 6 ports detected1176clare # [ 0.721068] 9pnet: Installing 9P2000 support1177clare # [ 0.721111] Key type dns_resolver registered1178bob # [ 0.697593] registered taskstats version 11179bob # [ 0.697729] Loading compiled-in X.509 certificates1180clare # [ 0.725531] registered taskstats version 11181clare # [ 0.725665] Loading compiled-in X.509 certificates1182alice # [ 0.726639] Segment Routing with IPv61183alice # [ 0.726657] In-situ OAM (IOAM) with IPv61184alice # [ 0.726725] NET: Registered PF_PACKET protocol family1185alice # [ 0.726795] 9pnet: Installing 9P2000 support1186alice # [ 0.726826] Key type dns_resolver registered1187bob # [ 0.703005] Demotion targets for Node 0: null1188bob # [ 0.703080] Key type .fscrypt registered1189bob # [ 0.703082] Key type fscrypt-provisioning registered1190bob # [ 0.703199] ima: No TPM chip found, activating TPM-bypass!1191bob # [ 0.703209] ima: Allocated hash algorithm: sha11192bob # [ 0.703263] ima: No architecture policies found1193alice # [ 0.731421] registered taskstats version 11194alice # [ 0.731558] Loading compiled-in X.509 certificates1195bob # [ 0.705855] input: gpio-keys as /devices/platform/gpio-keys/input/input01196clare # [ 0.731012] Demotion targets for Node 0: null1197clare # [ 0.731092] Key type .fscrypt registered1198clare # [ 0.731098] Key type fscrypt-provisioning registered1199clare # [ 0.731197] ima: No TPM chip found, activating TPM-bypass!1200clare # [ 0.731206] ima: Allocated hash algorithm: sha11201clare # [ 0.731262] ima: No architecture policies found1202alice # [ 0.737152] Demotion targets for Node 0: null1203alice # [ 0.737231] Key type .fscrypt registered1204clare # [ 0.731727] input: gpio-keys as /devices/platform/gpio-keys/input/input01205alice # [ 0.737237] Key type fscrypt-provisioning registered1206alice # [ 0.737343] ima: No TPM chip found, activating TPM-bypass!1207alice # [ 0.737352] ima: Allocated hash algorithm: sha11208alice # [ 0.737407] ima: No architecture policies found1209alice # [ 0.737868] input: gpio-keys as /devices/platform/gpio-keys/input/input01210bob # [ 0.716478] clk: Disabling unused clocks1211bob # [ 0.716487] PM: genpd: Disabling unused power domains1212clare # [ 0.744081] clk: Disabling unused clocks1213bob # [ 0.718676] Freeing unused kernel memory: 4736K1214bob # [ 0.718770] Run /init as init process1215clare # [ 0.744090] PM: genpd: Disabling unused power domains1216alice # [ 0.750416] clk: Disabling unused clocks1217alice # [ 0.750427] PM: genpd: Disabling unused power domains1218clare # [ 0.747010] Freeing unused kernel memory: 4736K1219clare # [ 0.747095] Run /init as init process1220alice # [ 0.753521] Freeing unused kernel memory: 4736K1221alice # [ 0.753627] Run /init as init process1222bob # [ 0.741694] systemd[1]: Successfully made /usr/ read-only.1223clare # [ 0.768824] systemd[1]: Successfully made /usr/ read-only.1224alice # [ 0.777441] systemd[1]: Successfully made /usr/ read-only.1225bob # [ 0.939193] usb 1-1: new high-speed USB device number 2 using ehci-pci1226clare # [ 0.966234] usb 1-1: new high-speed USB device number 2 using ehci-pci1227alice # [ 0.970978] usb 1-1: new high-speed USB device number 2 using ehci-pci1228bob # [ 1.078158] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1229clare # [ 1.102928] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1230bob # [ 1.078188] systemd[1]: Detected virtualization qemu.1231clare # [ 1.102957] systemd[1]: Detected virtualization qemu.1232bob # [ 1.078228] systemd[1]: Detected architecture arm64.1233clare # [ 1.102995] systemd[1]: Detected architecture arm64.1234bob # [ 1.078237] systemd[1]: Running in initrd.1235clare # [ 1.103004] systemd[1]: Running in initrd.1236bob # [ 1.079087] systemd[1]: Initializing machine ID from random generator.1237clare # [ 1.103784] systemd[1]: Initializing machine ID from random generator.1238bob # [ 1.087497] systemd[1]: Hostname set to <bob>.1239clare # [ 1.104044] systemd[1]: Hostname set to <clare>.1240alice # [ 1.111532] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1241alice # [ 1.111562] systemd[1]: Detected virtualization qemu.1242alice # [ 1.111600] systemd[1]: Detected architecture arm64.1243alice # [ 1.111609] systemd[1]: Running in initrd.1244alice # [ 1.112440] systemd[1]: Initializing machine ID from random generator.1245alice # [ 1.112809] systemd[1]: Hostname set to <alice>.1246bob # [ 1.098861] 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/input11247clare # [ 1.124168] 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/input11248alice # [ 1.141941] 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/input11249bob # [ 1.187281] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input01250clare # [ 1.214351] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input01251alice # [ 1.231123] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input01252bob # [ 1.211922] systemd[1]: bpf-restrict-fs: LSM BPF program attached1253clare # [ 1.239253] systemd[1]: bpf-restrict-fs: LSM BPF program attached1254alice # [ 1.252351] systemd[1]: bpf-restrict-fs: LSM BPF program attached1255bob # [ 1.260400] systemd[1]: Queued start job for default target Initrd Default Target.1256clare # [ 1.288094] systemd[1]: Queued start job for default target Initrd Default Target.1257bob # [ 1.269698] systemd[1]: Created slice Slice /system/modprobe.1258bob # [ 1.269940] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1259bob # [ 1.269995] systemd[1]: Expecting device /dev/disk/by-label/nixos...1260clare # [ 1.295765] systemd[1]: Created slice Slice /system/modprobe.1261clare # [ 1.295902] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1262bob # [ 1.270038] systemd[1]: Reached target Path Units.1263bob # [ 1.270062] systemd[1]: Reached target Slice Units.1264clare # [ 1.295931] systemd[1]: Expecting device /dev/disk/by-label/nixos...1265bob # [ 1.270084] systemd[1]: Reached target Swaps.1266clare # [ 1.295952] systemd[1]: Reached target Path Units.1267bob # [ 1.270107] systemd[1]: Reached target Timer Units.1268clare # [ 1.295964] systemd[1]: Reached target Slice Units.1269clare # [ 1.295976] systemd[1]: Reached target Swaps.1270bob # [ 1.270325] systemd[1]: Listening on D-Bus System Message Bus Socket.1271clare # [ 1.295988] systemd[1]: Reached target Timer Units.1272alice # [ 1.300736] systemd[1]: Queued start job for default target Initrd Default Target.1273bob # [ 1.270540] systemd[1]: Listening on Journal Socket (/dev/log).1274clare # [ 1.296123] systemd[1]: Listening on D-Bus System Message Bus Socket.1275clare # [ 1.296234] systemd[1]: Listening on Journal Socket (/dev/log).1276bob # [ 1.270837] systemd[1]: Listening on Journal Sockets.1277clare # [ 1.296347] systemd[1]: Listening on Journal Sockets.1278bob # [ 1.275462] systemd[1]: Listening on udev Control Socket.1279clare # [ 1.296421] systemd[1]: Listening on udev Control Socket.1280bob # [ 1.275533] systemd[1]: Listening on udev Kernel Socket.1281bob # [ 1.275548] systemd[1]: Reached target Socket Units.1282clare # [ 1.296467] systemd[1]: Listening on udev Kernel Socket.1283alice # [ 1.305089] systemd[1]: Created slice Slice /system/modprobe.1284alice # [ 1.305228] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1285clare # [ 1.296482] systemd[1]: Reached target Socket Units.1286alice # [ 1.305260] systemd[1]: Expecting device /dev/disk/by-label/nixos...1287bob # [ 1.278385] systemd[1]: Starting Create List of Static Device Nodes...1288clare # [ 1.297788] systemd[1]: Starting Create List of Static Device Nodes...1289alice # [ 1.305278] systemd[1]: Reached target Path Units.1290alice # [ 1.305290] systemd[1]: Reached target Slice Units.1291bob # [ 1.278436] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1292alice # [ 1.305302] systemd[1]: Reached target Swaps.1293alice # [ 1.305313] systemd[1]: Reached target Timer Units.1294clare # [ 1.297839] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1295alice # [ 1.305451] systemd[1]: Listening on D-Bus System Message Bus Socket.1296alice # [ 1.305566] systemd[1]: Listening on Journal Socket (/dev/log).1297alice # [ 1.305680] systemd[1]: Listening on Journal Sockets.1298alice # [ 1.305751] systemd[1]: Listening on udev Control Socket.1299alice # [ 1.305794] systemd[1]: Listening on udev Kernel Socket.1300alice # [ 1.305808] systemd[1]: Reached target Socket Units.1301alice # [ 1.306797] systemd[1]: Starting Create List of Static Device Nodes...1302alice # [ 1.306845] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1303alice # [ 1.315770] systemd[1]: Mounting Kernel Configuration File System...1304bob # [ 1.287215] systemd[1]: Mounting Kernel Configuration File System...1305clare # [ 1.314318] systemd[1]: Mounting Kernel Configuration File System...1306bob # [ 1.295780] systemd[1]: Starting Journal Service...1307bob # [ 1.296689] systemd[1]: Starting Load Kernel Modules...1308clare # [ 1.323001] systemd[1]: Starting Journal Service...1309bob # [ 1.296751] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1310alice # [ 1.323119] systemd[1]: Starting Journal Service...1311bob # [ 1.307154] usb 1-2: new high-speed USB device number 3 using ehci-pci1312clare # [ 1.334238] usb 1-2: new high-speed USB device number 3 using ehci-pci1313clare # [ 1.334741] systemd[1]: Starting Load Kernel Modules...1314clare # [ 1.334826] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1315bob # [ 1.311836] systemd[1]: Starting Coldplug All udev Devices...1316clare # [ 1.340969] systemd[1]: Starting Coldplug All udev Devices...1317bob # [ 1.319274] systemd[1]: Finished Create List of Static Device Nodes.1318alice # [ 1.350983] usb 1-2: new high-speed USB device number 3 using ehci-pci1319alice # [ 1.351251] systemd[1]: Starting Load Kernel Modules...1320alice # [ 1.351329] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1321alice # [ 1.352186] systemd[1]: Starting Coldplug All udev Devices...1322clare # [ 1.358379] systemd[1]: Finished Create List of Static Device Nodes.1323clare # [ 1.359207] systemd[1]: Mounted Kernel Configuration File System.1324clare # [ 1.364382] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1325alice # [ 1.369586] systemd[1]: Finished Create List of Static Device Nodes.1326alice # [ 1.373471] systemd-journald[71]: Collecting audit messages is disabled.1327bob # [ 1.343286] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1328bob # [ 1.343676] systemd[1]: Mounted Kernel Configuration File System.1329alice # [ 1.380918] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1330alice # [ 1.381488] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1331alice # [ 1.381666] systemd[1]: Mounted Kernel Configuration File System.1332bob # [ 1.364865] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1333alice # [ 1.395294] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1334bob # [ 1.368058] systemd[1]: Starting Create Static Device Nodes in /dev...1335bob # [ 1.373836] systemd-journald[71]: Collecting audit messages is disabled.1336alice # [ 1.409754] [drm] pci: virtio-gpu-pci detected at 0000:00:05.01337alice # [ 1.410069] [drm] features: -virgl +edid -resource_blob -host_visible1338alice # [ 1.410072] [drm] features: -context_init1339alice # [ 1.410528] [drm] number of scanouts: 11340alice # [ 1.410537] [drm] number of cap sets: 01341bob # [ 1.384127] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1342clare # [ 1.414754] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1343clare # [ 1.415959] systemd[1]: Starting Create Static Device Nodes in /dev...1344clare # [ 1.419981] systemd-journald[71]: Collecting audit messages is disabled.1345bob # [ 1.395191] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1346clare # [ 1.425334] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1347alice # [ 1.423075] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1348alice # [ 1.424261] systemd[1]: Starting Create Static Device Nodes in /dev...1349alice # [ 1.432377] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic1350alice # [ 1.432385] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 01351clare # [ 1.430321] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1352bob # [ 1.408256] [drm] pci: virtio-gpu-pci detected at 0000:00:05.01353bob # [ 1.408471] [drm] features: -virgl +edid -resource_blob -host_visible1354bob # [ 1.408475] [drm] features: -context_init1355bob # [ 1.409064] [drm] number of scanouts: 11356bob # [ 1.409073] [drm] number of cap sets: 01357bob # [ 1.413165] systemd[1]: Finished Create Static Device Nodes in /dev.1358bob # [ 1.413329] systemd[1]: Reached target Preparation for Local File Systems.1359bob # [ 1.413350] systemd[1]: Reached target Local File Systems.1360clare # [ 1.443552] [drm] pci: virtio-gpu-pci detected at 0000:00:05.01361clare # [ 1.443768] [drm] features: -virgl +edid -resource_blob -host_visible1362clare # [ 1.443770] [drm] features: -context_init1363clare # [ 1.444227] [drm] number of scanouts: 11364clare # [ 1.444235] [drm] number of cap sets: 01365alice # [ 1.447314] Console: switching to colour frame buffer device 160x501366alice # [ 1.450775] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device1367bob # [ 1.419453] systemd[1]: Starting Rule-based Manager for Device Events and Files...1368bob # [ 1.431643] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic1369bob # [ 1.431653] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 01370clare # [ 1.458999] systemd[1]: Finished Create Static Device Nodes in /dev.1371clare # [ 1.459159] systemd[1]: Reached target Preparation for Local File Systems.1372clare # [ 1.459180] systemd[1]: Reached target Local File Systems.1373clare # [ 1.460232] systemd[1]: Starting Rule-based Manager for Device Events and Files...1374alice # [ 1.461876] systemd-modules-load[73]: Inserted module 'dm_mod'1375clare # [ 1.464663] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic1376clare # [ 1.464671] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 01377alice # [ 1.462661] systemd-modules-load[73]: Module 'virtio_balloon' is built in1378alice # [ 1.467351] systemd[1]: Started Journal Service.1379alice # [ 1.471964] systemd-modules-load[73]: Module 'virtio_console' is built in1380alice # [ 1.472992] systemd-modules-load[73]: Inserted module 'virtio_gpu'1381alice # [ 1.473715] systemd-modules-load[73]: Module 'virtio_rng' is built in1382alice # [ 1.474597] systemd[1]: Finished Load Kernel Modules.1383alice # [ 1.476355] systemd[1]: Starting Apply Kernel Variables...1384bob # [ 1.448624] Console: switching to colour frame buffer device 160x501385alice # [ 1.484504] systemd[1]: Finished Create Static Device Nodes in /dev.1386clare # [ 1.486695] Console: switching to colour frame buffer device 160x501387bob # [ 1.463533] systemd[1]: Started Journal Service.1388bob # [ 1.468209] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device1389alice # [ 1.498663] 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/input21390clare # [ 1.497211] 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/input21391alice # [ 1.498882] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01392bob # [ 1.465455] systemd-modules-load[72]: Inserted module 'dm_mod'1393clare # [ 1.497316] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01394alice # [ 1.496824] systemd[1]: Reached target Preparation for Local File Systems.1395bob # [ 1.475945] 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/input21396bob # [ 1.476184] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01397bob # [ 1.474422] systemd-modules-load[72]: Module 'virtio_balloon' is built in1398alice # [ 1.506016] systemd[1]: Reached target Local File Systems.1399alice # [ 1.508184] systemd[1]: Starting Create System Files and Directories...1400clare # [ 1.510504] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device1401bob # [ 1.481854] systemd-modules-load[72]: Module 'virtio_console' is built in1402clare # [ 1.514872] systemd[1]: Started Journal Service.1403bob # [ 1.487327] systemd[1]: Starting Create System Files and Directories...1404alice # [ 1.517282] systemd[1]: Starting Rule-based Manager for Device Events and Files...1405bob # [ 1.492703] systemd-modules-load[72]: Inserted module 'virtio_gpu'1406clare # [ 1.520471] systemd-modules-load[72]: Inserted module 'dm_mod'1407clare # [ 1.521419] systemd-modules-load[72]: Module 'virtio_balloon' is built in1408alice # [ 1.524217] systemd[1]: Finished Apply Kernel Variables.1409clare # [ 1.522217] systemd-modules-load[72]: Module 'virtio_console' is built in1410clare # [ 1.522968] systemd-modules-load[72]: Inserted module 'virtio_gpu'1411bob # [ 1.496405] systemd-modules-load[72]: Module 'virtio_rng' is built in1412clare # [ 1.523654] systemd-modules-load[72]: Module 'virtio_rng' is built in1413bob # [ 1.497143] systemd[1]: Finished Load Kernel Modules.1414clare # [ 1.532383] systemd[1]: Starting Create System Files and Directories...1415clare # [ 1.534720] systemd[1]: Finished Load Kernel Modules.1416bob # [ 1.517374] systemd-udevd[79]: Using default interface naming scheme 'v261'.1417bob # [ 1.520222] systemd[1]: Starting Apply Kernel Variables...1418clare # [ 1.552321] systemd-udevd[79]: Using default interface naming scheme 'v261'.1419clare # [ 1.556989] systemd[1]: Starting Apply Kernel Variables...1420alice # [ 1.569070] systemd[1]: Finished Create System Files and Directories.1421bob # [ 1.547130] systemd[1]: Finished Apply Kernel Variables.1422bob # [ 1.556916] systemd[1]: Finished Create System Files and Directories.1423alice # [ 1.586352] systemd-udevd[86]: Using default interface naming scheme 'v261'.1424clare # [ 1.586651] systemd[1]: Finished Create System Files and Directories.1425bob # [ 1.566701] systemd[1]: Started Rule-based Manager for Device Events and Files.1426clare # [ 1.595214] systemd[1]: Started Rule-based Manager for Device Events and Files.1427clare # [ 1.598703] systemd[1]: Finished Apply Kernel Variables.1428alice # [ 1.612101] systemd[1]: Started Rule-based Manager for Device Events and Files.1429bob # [ 1.640077] systemd[1]: Starting Virtual Console Setup...1430clare # [ 1.672106] systemd[1]: Starting Virtual Console Setup...1431alice # [ 1.689530] systemd[1]: Starting Virtual Console Setup...1432bob # [ 1.688335] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.1433bob # [ 1.691407] systemd[1]: Finished Virtual Console Setup.1434clare # [ 1.720314] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.1435clare # [ 1.722964] systemd[1]: Finished Virtual Console Setup.1436alice # [ 1.740421] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.1437alice # [ 1.743107] systemd[1]: Finished Virtual Console Setup.1438bob # [ 1.891510] systemd[1]: Finished Coldplug All udev Devices.1439bob # [ 1.892221] systemd[1]: Reached target System Initialization.1440bob # [ 1.892776] systemd[1]: Reached target Basic System.1441clare # [ 1.927681] systemd[1]: Finished Coldplug All udev Devices.1442clare # [ 1.928402] systemd[1]: Reached target System Initialization.1443clare # [ 1.928947] systemd[1]: Reached target Basic System.1444alice # [ 1.941608] systemd[1]: Finished Coldplug All udev Devices.1445alice # [ 1.942216] systemd[1]: Reached target System Initialization.1446alice # [ 1.942742] systemd[1]: Reached target Basic System.1447bob # [ 1.998196] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.1448clare # [ 2.035078] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.1449bob # [ 2.008253] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.1450alice # [ 2.055782] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.1451clare # [ 2.054232] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.1452alice # [ 2.072488] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.1453bob # [ 2.079670] systemd[1]: Found device /dev/disk/by-label/nixos.1454bob # [ 2.086477] systemd[1]: Reached target Initrd Root Device.1455bob # [ 2.090050] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1456clare # [ 2.124678] systemd[1]: Found device /dev/disk/by-label/nixos.1457alice # [ 2.127289] systemd[1]: Found device /dev/disk/by-label/nixos.1458clare # [ 2.125745] systemd[1]: Reached target Initrd Root Device.1459clare # [ 2.128337] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1460alice # [ 2.142588] systemd[1]: Reached target Initrd Root Device.1461alice # [ 2.146500] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1462bob # [ 2.127645] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks1463clare # [ 2.161732] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks1464bob # [ 2.136103] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1465clare # [ 2.168362] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1466alice # [ 2.180671] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks1467alice # [ 2.188238] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1468clare # [ 2.337069] systemd[1]: Mounting /sysroot...1469bob # [ 2.307674] systemd[1]: Mounting /sysroot...1470alice # [ 2.348099] systemd[1]: Mounting /sysroot...1471bob # [ 2.334702] systemd[1]: Mounted /sysroot.1472bob # [ 2.339974] EXT4-fs (vda): mounted filesystem 25e77e32-f3e8-48fc-a2eb-b8ca851e413a r/w with ordered data mode. Quota mode: none.1473bob # [ 2.337105] systemd[1]: Reached target Initrd Root File System.1474clare # [ 2.369815] EXT4-fs (vda): mounted filesystem 25b10962-955d-47fe-b97b-0e3150546f0f r/w with ordered data mode. Quota mode: none.1475clare # [ 2.368072] systemd[1]: Mounted /sysroot.1476bob # [ 2.341584] systemd[1]: Mounting /sysroot/nix/.ro-store...1477clare # [ 2.370888] systemd[1]: Reached target Initrd Root File System.1478alice # [ 2.375640] systemd[1]: Mounted /sysroot.[ 2.379178] EXT4-fs (vda): mounted filesystem 7a79a265-bd73-4d5e-adc5-39ba3bc92172 r/w with ordered data mode. Quota mode: none.1479alice # 1480alice # [ 2.378236] systemd[1]: Reached target Initrd Root File System.1481bob # [ 2.351468] systemd[1]: Mounting /sysroot/nix/.rw-store...1482clare # [ 2.378822] systemd[1]: Starting Mountpoints Configured in the Real Root...1483alice # [ 2.382732] systemd[1]: Mounting /sysroot/nix/.ro-store...1484clare # [ 2.390093] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.1485bob # [ 2.363564] systemd[1]: Mounting /sysroot/run...1486alice # [ 2.393025] systemd[1]: Mounting /sysroot/nix/.rw-store...1487clare # [ 2.395506] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...1488alice # [ 2.402466] systemd[1]: Mounting /sysroot/run...1489clare # [ 2.400683] systemd[1]: Reloading...1490alice # [ 2.413046] systemd[1]: Mounting /sysroot/tmp/shared...1491bob # [ 2.386745] systemd[1]: Mounting /sysroot/tmp/shared...1492alice # [ 2.425607] systemd[1]: Mounting /sysroot/tmp/xchg...1493bob # [ 2.409633] systemd[1]: Mounting /sysroot/tmp/xchg...1494bob # [ 2.412119] systemd[1]: Starting Mountpoints Configured in the Real Root...1495bob # [ 2.426245] fuse: init (API version 7.45)1496alice # [ 2.460269] systemd[1]: Starting Mountpoints Configured in the Real Root...1497alice # [ 2.461540] systemd[1]: Mounted /sysroot/run.1498bob # [ 2.435934] virtiofs virtio6: discovered new tag: nix-store1499bob # [ 2.436439] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1500alice # [ 2.474566] systemd[1]: Mounted /sysroot/nix/.rw-store.1501bob # [ 2.452820] virtiofs virtio7: discovered new tag: shared1502bob # [ 2.453399] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1503bob # [ 2.449126] systemd[1]: Mounted /sysroot/nix/.rw-store.1504bob # [ 2.458733] virtiofs virtio8: discovered new tag: xchg1505bob # [ 2.462869] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1506bob # [ 2.459972] systemd[1]: Mounted /sysroot/run.1507alice # [ 2.496740] fuse: init (API version 7.45)1508alice # [ 2.494114] systemd-sysroot-fstab-check[123]: /sysroot should be mounted in the initrd, will request daemon-reload.1509alice # [ 2.504484] virtiofs virtio6: discovered new tag: nix-store1510alice # [ 2.504994] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1511alice # [ 2.504750] systemd[1]: Starting rw-sysroot-nix-store.service...1512bob # [ 2.477355] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload.1513bob # [ 2.481476] systemd[1]: Starting rw-sysroot-nix-store.service...1514bob # [ 2.483161] systemd[1]: Mounted /sysroot/nix/.ro-store.1515alice # [ 2.520272] virtiofs virtio7: discovered new tag: shared1516alice # [ 2.520807] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1517alice # [ 2.517096] systemd[1]: Reload requested from client PID 123 ('systemd-sysroot') (unit initrd-parse-etc.service)...1518bob # [ 2.489094] systemd[1]: Mounted /sysroot/tmp/shared.1519alice # [ 2.526207] virtiofs virtio8: discovered new tag: xchg1520alice # [ 2.526694] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1521alice # [ 2.523331] systemd[1]: Reloading...1522bob # [ 2.499790] systemd[1]: Mounted /sysroot/tmp/xchg.1523bob # [ 2.504478] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)...1524bob # [ 2.507259] systemd[1]: Reloading...1525clare # [ 2.571624] systemd[1]: Reloading finished in 174 ms.1526clare # [ 2.600848] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1527clare # [ 2.602831] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1528clare # [ 2.608299] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1529clare # [ 2.610010] systemd[1]: Finished Mountpoints Configured in the Real Root.1530clare # [ 2.611322] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1531alice # [ 2.713016] systemd[1]: Reloading finished in 197 ms.1532bob # [ 2.685907] systemd[1]: Reloading finished in 182 ms.1533alice # [ 2.740682] systemd-sysroot-fstab-check[123]: Requesting initrd-fs.target/start/replace...1534alice # [ 2.743315] systemd[1]: Mounted /sysroot/nix/.ro-store.1535alice # [ 2.744830] systemd[1]: Mounted /sysroot/tmp/shared.1536alice # [ 2.746454] systemd[1]: Mounted /sysroot/tmp/xchg.1537bob # [ 2.717766] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace...1538alice # [ 2.748855] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1539bob # [ 2.720321] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1540bob # [ 2.721018] systemd[1]: Finished rw-sysroot-nix-store.service.1541alice # [ 2.751560] systemd[1]: Finished rw-sysroot-nix-store.service.1542bob # [ 2.722335] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace...1543alice # [ 2.755153] systemd-sysroot-fstab-check[123]: Requesting swap.target/start/replace...1544alice # [ 2.759188] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1545bob # [ 2.730967] systemd[1]: Starting rw-sysroot-nix-store.service...1546alice # [ 2.760548] systemd[1]: Finished Mountpoints Configured in the Real Root.1547alice # [ 2.763593] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1548bob # [ 2.734493] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1549bob # [ 2.736128] systemd[1]: Finished Mountpoints Configured in the Real Root.1550bob # [ 2.738648] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1551alice # [ 2.769638] systemd[1]: Starting rw-sysroot-nix-store.service...1552bob # [ 2.753867] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1553bob # [ 2.754857] systemd[1]: Finished rw-sysroot-nix-store.service.1554alice # [ 2.787483] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1555alice # [ 2.788571] systemd[1]: Finished rw-sysroot-nix-store.service.1556clare # [ 2.862310] fuse: init (API version 7.45)1557clare # [ 2.889704] virtiofs virtio6: discovered new tag: nix-store1558clare # [ 2.905573] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1559clare # [ 2.901371] (udev-worker)[90]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1560clare # [ 2.903375] (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 directory1561clare # [ 2.919786] virtiofs virtio7: discovered new tag: shared1562clare # [ 2.920262] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1563clare # [ 2.927728] virtiofs virtio8: discovered new tag: xchg1564clare # [ 2.928199] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1565clare # [ 2.925620] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1566clare # [ 2.927722] systemd[1]: Stopped Virtual Console Setup.1567clare # [ 2.931855] systemd[1]: Stopping Virtual Console Setup...1568clare # [ 2.933431] systemd[1]: Starting Virtual Console Setup...1569clare # [ 2.954043] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones.1570clare # [ 2.956170] systemd[1]: Finished Virtual Console Setup.1571bob # [ 2.964827] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1572bob # [ 2.966890] (udev-worker)[94]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1573bob # [ 2.987077] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1574bob # [ 2.988821] systemd[1]: Stopped Virtual Console Setup.1575bob # [ 2.992100] systemd[1]: Stopping Virtual Console Setup...1576bob # [ 2.992918] systemd[1]: Starting Virtual Console Setup...1577alice # [ 3.032330] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1578alice # [ 3.036255] (udev-worker)[99]: 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 directory1579bob # [ 3.010635] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones.1580bob # [ 3.012932] systemd[1]: Finished Virtual Console Setup.1581alice # [ 3.056590] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1582alice # [ 3.058120] systemd[1]: Stopped Virtual Console Setup.1583alice # [ 3.058908] systemd[1]: Stopping Virtual Console Setup...1584alice # [ 3.061141] systemd[1]: Starting Virtual Console Setup...1585alice # [ 3.082816] systemd-vconsole-setup[159]: Configuration of first virtual console was skipped, ignoring remaining ones.1586alice # [ 3.084947] systemd[1]: Finished Virtual Console Setup.1587bob # [ 3.308826] systemd[1]: Mounting /sysroot/nix/store...1588clare # [ 3.340137] systemd[1]: Mounting /sysroot/nix/.ro-store...1589clare # [ 3.345021] systemd[1]: Mounting /sysroot/nix/.rw-store...1590alice # [ 3.349528] systemd[1]: Mounting /sysroot/nix/store...1591clare # [ 3.360223] systemd[1]: Mounting /sysroot/run...1592clare # [ 3.368127] systemd[1]: Mounting /sysroot/tmp/shared...1593clare # [ 3.380289] systemd[1]: Mounting /sysroot/tmp/xchg...1594bob # [ 3.360776] systemd[1]: Mounted /sysroot/nix/store.1595bob # [ 3.363005] systemd[1]: Reached target Initrd File Systems.1596bob # [ 3.366823] systemd[1]: Starting Find NixOS closure...1597alice # [ 3.400357] systemd[1]: Mounted /sysroot/nix/store.1598alice # [ 3.402764] systemd[1]: Reached target Initrd File Systems.1599bob # [ 3.376301] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1600alice # [ 3.406665] systemd[1]: Starting Find NixOS closure...1601alice # [ 3.413711] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1602clare # [ 3.416260] systemd[1]: Mounted /sysroot/nix/.ro-store.1603clare # [ 3.425101] systemd[1]: Mounted /sysroot/nix/.rw-store.1604clare # [ 3.428655] systemd[1]: Mounted /sysroot/run.1605clare # [ 3.433042] systemd[1]: Mounted /sysroot/tmp/shared.1606clare # [ 3.433946] systemd[1]: Mounted /sysroot/tmp/xchg.1607clare # [ 3.437045] systemd[1]: Starting rw-sysroot-nix-store.service...1608bob # [ 3.418760] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1609bob # [ 3.422337] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1610alice # [ 3.453480] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1611clare # [ 3.455728] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1612clare # [ 3.457257] systemd[1]: Finished rw-sysroot-nix-store.service.1613alice # [ 3.459922] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1614clare # [ 3.459532] systemd[1]: Mounting /sysroot/nix/store...1615bob # [ 3.437732] systemd[1]: Finished Find NixOS closure.1616bob # [ 3.440254] systemd[1]: Reached target Initrd Default Target.1617alice # [ 3.472155] systemd[1]: Finished Find NixOS closure.1618bob # [ 3.442951] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1619alice # [ 3.474674] systemd[1]: Reached target Initrd Default Target.1620alice # [ 3.480353] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1621clare # [ 3.500397] systemd[1]: Mounted /sysroot/nix/store.1622bob # [ 3.473147] systemd[1]: Stopped target Initrd Default Target.1623clare # [ 3.501567] systemd[1]: Reached target Initrd File Systems.1624bob # [ 3.474742] systemd[1]: Stopped target Basic System.1625alice # [ 3.504701] systemd[1]: initrd-cleanup.service: Deactivated successfully.1626bob # [ 3.476313] systemd[1]: Stopped target Initrd Root Device.1627clare # [ 3.503803] systemd[1]: Starting Find NixOS closure...1628alice # [ 3.506163] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1629alice # [ 3.509575] systemd[1]: Stopped target Initrd Default Target.1630bob # [ 3.480469] systemd[1]: Stopped target Path Units.1631bob # [ 3.481524] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1632alice # [ 3.512170] systemd[1]: Stopped target Basic System.1633alice # [ 3.512988] systemd[1]: Stopped target Initrd Root Device.1634bob # [ 3.483932] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1635clare # [ 3.512214] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1636bob # [ 3.485875] systemd[1]: Stopped target Slice Units.1637alice # [ 3.515272] systemd[1]: Stopped target Path Units.1638bob # [ 3.488131] systemd[1]: Stopped target Socket Units.1639bob # [ 3.489289] systemd[1]: Stopped target System Initialization.1640alice # [ 3.520168] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1641alice # [ 3.523318] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1642bob # [ 3.494809] systemd[1]: Stopped target Swaps.1643bob # [ 3.496630] systemd[1]: Stopped target Timer Units.1644alice # [ 3.526018] systemd[1]: Stopped target Slice Units.1645alice # [ 3.527717] systemd[1]: Stopped target Socket Units.1646bob # [ 3.501211] systemd[1]: dbus.socket: Deactivated successfully.1647alice # [ 3.532234] systemd[1]: Stopped target System Initialization.1648alice # [ 3.533101] systemd[1]: Stopped target Swaps.1649alice # [ 3.533709] systemd[1]: Stopped target Timer Units.1650bob # [ 3.504219] systemd[1]: Closed D-Bus System Message Bus Socket.1651alice # [ 3.534284] systemd[1]: dbus.socket: Deactivated successfully.1652bob # [ 3.505042] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1653alice # [ 3.534959] systemd[1]: Closed D-Bus System Message Bus Socket.1654alice # [ 3.535664] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1655bob # [ 3.509002] systemd[1]: Stopped Find NixOS closure.1656bob # [ 3.509691] systemd[1]: Starting rw-sysroot-nix-store.service...1657bob # [ 3.511977] systemd[1]: systemd-sysctl.service: Deactivated successfully.1658bob # [ 3.513409] systemd[1]: Stopped Apply Kernel Variables.1659clare # [ 3.541416] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1660alice # [ 3.544202] systemd[1]: Stopped Find NixOS closure.1661alice # [ 3.544890] systemd[1]: Starting rw-sysroot-nix-store.service...1662alice # [ 3.545856] systemd[1]: systemd-sysctl.service: Deactivated successfully.1663bob # [ 3.517282] systemd[1]: systemd-modules-load.service: Deactivated successfully.1664bob # [ 3.518976] systemd[1]: Stopped Load Kernel Modules.1665bob # [ 3.519697] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1666alice # [ 3.551730] systemd[1]: Stopped Apply Kernel Variables.1667alice # [ 3.552483] systemd[1]: systemd-modules-load.service: Deactivated successfully.1668alice # [ 3.553593] systemd[1]: Stopped Load Kernel Modules.1669alice # [ 3.556138] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1670bob # [ 3.527065] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1671clare # [ 3.555398] systemd[1]: Finished Find NixOS closure.1672alice # [ 3.558808] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1673clare # [ 3.556972] systemd[1]: Reached target Initrd Default Target.1674bob # [ 3.530229] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1675bob # [ 3.532296] systemd[1]: Stopped Create System Files and Directories.1676clare # [ 3.559703] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1677alice # [ 3.562037] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1678bob # [ 3.534001] systemd[1]: Stopped target Local File Systems.1679alice # [ 3.564149] systemd[1]: Stopped Create System Files and Directories.1680bob # [ 3.534818] systemd[1]: Stopped target Preparation for Local File Systems.1681alice # [ 3.564794] systemd[1]: Stopped target Local File Systems.1682alice # [ 3.568147] systemd[1]: Stopped target Preparation for Local File Systems.1683alice # [ 3.568811] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1684bob # [ 3.539637] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1685alice # [ 3.569625] systemd[1]: Stopped Coldplug All udev Devices.1686bob # [ 3.540836] systemd[1]: Stopped Coldplug All udev Devices.1687alice # [ 3.570147] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1688bob # [ 3.541377] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1689bob # [ 3.542090] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1690bob # [ 3.542819] systemd[1]: Stopped Virtual Console Setup.1691alice # [ 3.572231] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1692alice # [ 3.573022] systemd[1]: Stopped Virtual Console Setup.1693bob # [ 3.543353] systemd[1]: initrd-cleanup.service: Deactivated successfully.1694bob # [ 3.546199] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1695bob # [ 3.546881] systemd[1]: systemd-udevd.service: Deactivated successfully.1696alice # [ 3.576506] systemd[1]: systemd-udevd.service: Deactivated successfully.1697bob # [ 3.547523] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1698alice # [ 3.577299] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1699bob # [ 3.548545] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1700alice # [ 3.577933] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1701bob # [ 3.549206] systemd[1]: Closed udev Control Socket.1702alice # [ 3.578560] systemd[1]: Closed udev Control Socket.1703alice # [ 3.579017] systemd[1]: Starting Cleanup udev Database...1704bob # [ 3.549701] systemd[1]: Starting Cleanup udev Database...1705bob # [ 3.550231] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1706bob # [ 3.550930] systemd[1]: Stopped Create Static Device Nodes in /dev.1707bob # [ 3.551532] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1708alice # [ 3.579499] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1709alice # [ 3.584189] systemd[1]: Stopped Create Static Device Nodes in /dev.1710alice # [ 3.584751] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1711alice # [ 3.585454] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1712alice # [ 3.586061] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1713alice # [ 3.586668] systemd[1]: Stopped Create List of Static Device Nodes.1714alice # [ 3.587253] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1715alice # [ 3.587884] systemd[1]: Finished rw-sysroot-nix-store.service.1716clare # [ 3.586357] systemd[1]: Stopped target Initrd Default Target.1717bob # [ 3.560258] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1718bob # [ 3.560929] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1719clare # [ 3.588370] systemd[1]: Stopped target Basic System.1720bob # [ 3.561746] systemd[1]: Stopped Create List of Static Device Nodes.1721alice # [ 3.590971] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1722bob # [ 3.562354] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1723clare # [ 3.589660] systemd[1]: Stopped target Initrd Root Device.1724alice # [ 3.593356] systemd[1]: Finished Cleanup udev Database.1725bob # [ 3.564180] systemd[1]: Finished rw-sysroot-nix-store.service.1726alice # [ 3.594170] systemd[1]: Reached target Switch Root.1727clare # [ 3.592244] systemd[1]: Stopped target Path Units.1728clare # [ 3.592908] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1729alice # [ 3.596463] systemd[1]: Starting NixOS Activation...1730clare # [ 3.594554] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1731clare # [ 3.599942] systemd[1]: Stopped target Slice Units.1732clare # [ 3.600658] systemd[1]: Stopped target Socket Units.1733bob # [ 3.573730] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1734clare # [ 3.601276] systemd[1]: Stopped target System Initialization.1735bob # [ 3.574452] systemd[1]: Finished Cleanup udev Database.1736clare # [ 3.601915] systemd[1]: Stopped target Swaps.1737clare # [ 3.602440] systemd[1]: Stopped target Timer Units.1738clare # [ 3.603183] systemd[1]: dbus.socket: Deactivated successfully.1739bob # [ 3.576645] systemd[1]: Reached target Switch Root.1740clare # [ 3.603863] systemd[1]: Closed D-Bus System Message Bus Socket.1741bob # [ 3.577165] systemd[1]: Starting NixOS Activation...1742clare # [ 3.607481] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1743clare # [ 3.616523] systemd[1]: Stopped Find NixOS closure.1744clare # [ 3.617116] systemd[1]: Starting rw-sysroot-nix-store.service...1745clare # [ 3.617843] systemd[1]: systemd-sysctl.service: Deactivated successfully.1746clare # [ 3.618541] systemd[1]: Stopped Apply Kernel Variables.1747clare # [ 3.619225] systemd[1]: systemd-modules-load.service: Deactivated successfully.1748clare # [ 3.619935] systemd[1]: Stopped Load Kernel Modules.1749clare # [ 3.627377] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1750clare # [ 3.628902] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1751clare # [ 3.629813] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1752clare # [ 3.630512] systemd[1]: Stopped Create System Files and Directories.1753clare # [ 3.631218] systemd[1]: Stopped target Local File Systems.1754clare # [ 3.631740] systemd[1]: Stopped target Preparation for Local File Systems.1755clare # [ 3.635500] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1756clare # [ 3.640216] systemd[1]: Stopped Coldplug All udev Devices.1757clare # [ 3.640725] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1758clare # [ 3.641372] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1759clare # [ 3.642020] systemd[1]: Stopped Virtual Console Setup.1760clare # [ 3.642492] systemd[1]: initrd-cleanup.service: Deactivated successfully.1761clare # [ 3.643184] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1762clare # [ 3.643750] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1763clare # [ 3.644677] systemd[1]: Finished rw-sysroot-nix-store.service.1764clare # [ 3.645211] systemd[1]: systemd-udevd.service: Deactivated successfully.1765clare # [ 3.645794] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1766clare # [ 3.646438] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1767clare # [ 3.647079] systemd[1]: Closed udev Control Socket.1768clare # [ 3.647521] systemd[1]: Starting Cleanup udev Database...1769clare # [ 3.647992] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1770clare # [ 3.648780] systemd[1]: Stopped Create Static Device Nodes in /dev.1771clare # [ 3.649348] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1772clare # [ 3.650060] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1773clare # [ 3.650672] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1774clare # [ 3.651290] systemd[1]: Stopped Create List of Static Device Nodes.1775clare # [ 3.668505] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1776alice # [ 3.670263] initrd-nixos-activation-start[182]: booting system configuration /nix/store/kq0j6qy8xgmdx7cbimmnsnci26v7rpzv-nixos-system-alice-test1777clare # [ 3.669936] systemd[1]: Finished Cleanup udev Database.1778clare # [ 3.670446] systemd[1]: Reached target Switch Root.1779clare # [ 3.672866] systemd[1]: Starting NixOS Activation...1780bob # [ 3.650157] initrd-nixos-activation-start[175]: booting system configuration /nix/store/y6bki65bnyl7icghswfggs0rgqryrg6y-nixos-system-bob-test1781alice # [ 3.698534] initrd-nixos-activation-start[182]: running activation script...1782bob # [ 3.678390] initrd-nixos-activation-start[175]: running activation script...1783clare # [ 3.744616] initrd-nixos-activation-start[172]: booting system configuration /nix/store/q82yc0bv5n8fk6y1nv8g5kdp80x45p46-nixos-system-clare-test1784clare # [ 3.771131] initrd-nixos-activation-start[172]: running activation script...1785alice # [ 3.909984] initrd-nixos-activation-start[205]: setting up /etc...1786bob # [ 3.892362] initrd-nixos-activation-start[198]: setting up /etc...1787clare # [ 3.991173] initrd-nixos-activation-start[195]: setting up /etc...1788alice # [ 3.996064] initrd-nixos-activation-start[205]: setting up age secrets...1789bob # [ 3.974906] initrd-nixos-activation-start[198]: setting up age secrets...1790alice # [ 4.058088] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1791alice # [ 4.059856] systemd[1]: Finished NixOS Activation.1792alice # [ 4.060802] systemd[1]: Starting Switch Root...1793bob # [ 4.046060] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1794bob # [ 4.047912] systemd[1]: Finished NixOS Activation.1795bob # [ 4.048855] systemd[1]: Starting Switch Root...1796clare # [ 4.076156] initrd-nixos-activation-start[195]: setting up age secrets...1797alice # [ 4.080520] systemd[1]: Switching root.1798bob # [ 4.068534] systemd[1]: Switching root.1799clare # [ 4.136267] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1800clare # [ 4.137995] systemd[1]: Finished NixOS Activation.1801clare # [ 4.138710] systemd[1]: Starting Switch Root...1802clare # [ 4.156123] systemd[1]: Switching root.1803alice # [ 4.255162] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1804bob # [ 4.235586] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1805clare # [ 4.322322] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1806alice # [ 4.800237] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1807alice # [ 4.800331] systemd[1]: Detected virtualization qemu.1808alice # [ 4.800388] systemd[1]: Detected architecture arm64.1809alice # [ 4.800502] systemd[1]: Detected first boot.1810alice # [ 4.802575] systemd[1]: Initializing machine ID from random generator.1811clare # [ 4.822770] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1812clare # [ 4.822879] systemd[1]: Detected virtualization qemu.1813clare # [ 4.822937] systemd[1]: Detected architecture arm64.1814clare # [ 4.823046] systemd[1]: Detected first boot.1815clare # [ 4.825160] systemd[1]: Initializing machine ID from random generator.1816bob # [ 4.831795] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1817bob # [ 4.831912] systemd[1]: Detected virtualization qemu.1818bob # [ 4.831970] systemd[1]: Detected architecture arm64.1819bob # [ 4.832084] systemd[1]: Detected first boot.1820bob # [ 4.834618] systemd[1]: Initializing machine ID from random generator.1821alice # [ 4.945324] systemd[1]: bpf-restrict-fs: LSM BPF program attached1822clare # [ 4.972511] systemd[1]: bpf-restrict-fs: LSM BPF program attached1823bob # [ 4.979969] systemd[1]: bpf-restrict-fs: LSM BPF program attached1824bob # [ 5.060095] systemd[1]: Applying preset policy.1825alice # [ 5.118089] systemd[1]: Applying preset policy.1826clare # [ 5.126822] systemd[1]: Applying preset policy.1827bob # [ 5.232979] systemd[1]: Populated /etc with preset unit settings.1828alice # [ 5.275694] systemd[1]: Populated /etc with preset unit settings.1829clare # [ 5.277721] systemd[1]: Populated /etc with preset unit settings.1830bob # [ 5.450535] systemd[1]: initrd-switch-root.service: Deactivated successfully.1831bob # [ 5.450825] systemd[1]: Stopped initrd-switch-root.service.1832bob # [ 5.454491] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1833bob # [ 5.457080] systemd[1]: Created slice Slice /system/getty.1834bob # [ 5.457988] systemd[1]: Created slice User and Session Slice.1835bob # [ 5.458550] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1836alice # [ 5.490019] systemd[1]: initrd-switch-root.service: Deactivated successfully.1837bob # [ 5.461290] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1838bob # [ 5.461790] systemd[1]: Expecting device /dev/hvc0...1839bob # [ 5.462372] systemd[1]: Expecting device /dev/ttyAMA0...1840bob # [ 5.462999] systemd[1]: Reached target Local Encrypted Volumes.1841alice # [ 5.490296] systemd[1]: Stopped initrd-switch-root.service.1842alice # [ 5.493929] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1843bob # [ 5.466244] systemd[1]: Stopped target initrd-fs.target.1844bob # [ 5.466681] systemd[1]: Stopped target initrd-root-fs.target.1845alice # [ 5.496366] systemd[1]: Created slice Slice /system/getty.1846clare # [ 5.497514] systemd[1]: initrd-switch-root.service: Deactivated successfully.1847bob # [ 5.468740] systemd[1]: Stopped target initrd-switch-root.target.1848clare # [ 5.497824] systemd[1]: Stopped initrd-switch-root.service.1849bob # [ 5.469208] systemd[1]: Reached target Virtual Machines and Containers.1850alice # [ 5.497348] systemd[1]: Created slice User and Session Slice.1851alice # [ 5.497916] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1852alice # [ 5.498514] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1853bob # [ 5.469792] systemd[1]: Reached target Path Units.1854bob # [ 5.470446] systemd[1]: Reached target Remote File Systems.1855bob # [ 5.471069] systemd[1]: Reached target Slice Units.1856alice # [ 5.501641] systemd[1]: Expecting device /dev/hvc0...1857alice # [ 5.502120] systemd[1]: Expecting device /dev/ttyAMA0...1858bob # [ 5.474571] systemd[1]: Reached target Swaps.1859alice # [ 5.502710] systemd[1]: Reached target Local Encrypted Volumes.1860clare # [ 5.500522] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1861clare # [ 5.501808] systemd[1]: Created slice Slice /system/getty.1862alice # [ 5.505145] systemd[1]: Stopped target initrd-fs.target.1863alice # [ 5.505631] systemd[1]: Stopped target initrd-root-fs.target.1864bob # [ 5.478109] systemd[1]: Listening on Query the User Interactively for a Password.1865alice # [ 5.506192] systemd[1]: Stopped target initrd-switch-root.target.1866alice # [ 5.506754] systemd[1]: Reached target Virtual Machines and Containers.1867clare # [ 5.504650] systemd[1]: Created slice User and Session Slice.1868clare # [ 5.505266] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1869clare # [ 5.505866] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1870alice # [ 5.509695] systemd[1]: Reached target Path Units.1871alice # [ 5.510159] systemd[1]: Reached target Remote File Systems.1872alice # [ 5.510703] systemd[1]: Reached target Slice Units.1873bob # [ 5.481008] systemd[1]: Listening on Process Core Dump Socket.1874bob # [ 5.482648] systemd[1]: Listening on Credential Encryption/Decryption.1875clare # [ 5.508813] systemd[1]: Expecting device /dev/hvc0...1876clare # [ 5.509321] systemd[1]: Expecting device /dev/ttyAMA0...1877clare # [ 5.509882] systemd[1]: Reached target Local Encrypted Volumes.1878alice # [ 5.512945] systemd[1]: Reached target Swaps.1879bob # [ 5.486135] systemd[1]: Listening on Factory Reset Management.1880bob # [ 5.486702] systemd[1]: Listening on Hostname Service Socket.1881clare # [ 5.512287] systemd[1]: Stopped target initrd-fs.target.1882clare # [ 5.512771] systemd[1]: Stopped target initrd-root-fs.target.1883clare # [ 5.513346] systemd[1]: Stopped target initrd-switch-root.target.1884alice # [ 5.515371] systemd[1]: Listening on Query the User Interactively for a Password.1885clare # [ 5.513927] systemd[1]: Reached target Virtual Machines and Containers.1886alice # [ 5.517540] systemd[1]: Listening on Process Core Dump Socket.1887clare # [ 5.516913] systemd[1]: Reached target Path Units.1888clare # [ 5.517407] systemd[1]: Reached target Remote File Systems.1889clare # [ 5.517971] systemd[1]: Reached target Slice Units.1890alice # [ 5.520158] systemd[1]: Listening on Credential Encryption/Decryption.1891bob # [ 5.491539] systemd[1]: Starting Journal Log Access Socket...1892alice # [ 5.521920] systemd[1]: Listening on Factory Reset Management.1893alice # [ 5.522472] systemd[1]: Listening on Hostname Service Socket.1894clare # [ 5.520315] systemd[1]: Reached target Swaps.1895bob # [ 5.492500] systemd[1]: Listening on Journal Audit Socket.1896bob # [ 5.494933] systemd[1]: Listening on Console Output Muting Service Socket.1897bob # [ 5.497112] systemd[1]: Listening on Network Management Metrics Varlink Socket.1898clare # [ 5.522864] systemd[1]: Listening on Query the User Interactively for a Password.1899bob # [ 5.497851] systemd[1]: Listening on Network Management Varlink Socket.1900clare # [ 5.525017] systemd[1]: Listening on Process Core Dump Socket.1901bob # [ 5.498483] systemd[1]: Listening on Network Management Netlink Socket.1902alice # [ 5.527418] systemd[1]: Starting Journal Log Access Socket...1903alice # [ 5.528352] systemd[1]: Listening on Journal Audit Socket.1904alice # [ 5.530606] systemd[1]: Listening on Console Output Muting Service Socket.1905bob # [ 5.501322] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1906clare # [ 5.527798] systemd[1]: Listening on Credential Encryption/Decryption.1907clare # [ 5.529635] systemd[1]: Listening on Factory Reset Management.1908bob # [ 5.502147] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1909bob # [ 5.502844] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1910alice # [ 5.533272] systemd[1]: Listening on Network Management Metrics Varlink Socket.1911alice # [ 5.533936] systemd[1]: Listening on Network Management Varlink Socket.1912alice # [ 5.534576] systemd[1]: Listening on Network Management Netlink Socket.1913clare # [ 5.531666] systemd[1]: Listening on Hostname Service Socket.1914alice # [ 5.537498] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1915bob # [ 5.510233] systemd[1]: Listening on Disk Repartitioning Service Socket.1916alice # [ 5.538726] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1917clare # [ 5.534966] systemd[1]: Starting Journal Log Access Socket...1918bob # [ 5.510777] systemd[1]: Listening on Resolve Monitor Varlink Socket.1919clare # [ 5.536346] systemd[1]: Listening on Journal Audit Socket.1920alice # [ 5.541205] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1921bob # [ 5.512496] systemd[1]: Listening on Resolve Service Varlink Socket.1922bob # [ 5.513141] systemd[1]: Listening on udev Control Socket.1923bob # [ 5.513692] systemd[1]: Listening on udev Varlink Socket.1924clare # [ 5.539782] systemd[1]: Listening on Console Output Muting Service Socket.1925clare # [ 5.540466] systemd[1]: Listening on Network Management Metrics Varlink Socket.1926clare # [ 5.541232] systemd[1]: Listening on Network Management Varlink Socket.1927clare # [ 5.541868] systemd[1]: Listening on Network Management Netlink Socket.1928alice # [ 5.545928] systemd[1]: Listening on Disk Repartitioning Service Socket.1929alice # [ 5.546450] systemd[1]: Listening on Resolve Monitor Varlink Socket.1930bob # [ 5.517754] systemd[1]: Mounting Huge Pages File System...1931clare # [ 5.545120] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1932clare # [ 5.546086] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1933alice # [ 5.548571] systemd[1]: Listening on Resolve Service Varlink Socket.1934alice # [ 5.549074] systemd[1]: Listening on udev Control Socket.1935alice # [ 5.549626] systemd[1]: Listening on udev Varlink Socket.1936bob # [ 5.520770] systemd[1]: Mounting POSIX Message Queue File System...1937clare # [ 5.548430] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1938alice # [ 5.553169] systemd[1]: Mounting Huge Pages File System...1939clare # [ 5.553026] systemd[1]: Listening on Disk Repartitioning Service Socket.1940clare # [ 5.553571] systemd[1]: Listening on Resolve Monitor Varlink Socket.1941clare # [ 5.554131] systemd[1]: Listening on Resolve Service Varlink Socket.1942alice # [ 5.555510] systemd[1]: Mounting POSIX Message Queue File System...1943bob # [ 5.527709] systemd[1]: Mounting Kernel Debug File System...1944clare # [ 5.556150] systemd[1]: Listening on udev Control Socket.1945clare # [ 5.556741] systemd[1]: Listening on udev Varlink Socket.1946clare # [ 5.559853] systemd[1]: Mounting Huge Pages File System...1947alice # [ 5.563225] systemd[1]: Mounting Kernel Debug File System...1948clare # [ 5.562012] systemd[1]: Mounting POSIX Message Queue File System...1949bob # [ 5.537968] systemd[1]: Mounting Kernel Trace File System...1950clare # [ 5.570591] systemd[1]: Mounting Kernel Debug File System...1951alice # [ 5.576047] systemd[1]: Mounting Kernel Trace File System...1952bob # [ 5.548214] systemd[1]: Starting Create List of Static Device Nodes...1953bob # [ 5.548627] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1954alice # [ 5.581015] systemd[1]: Starting Create List of Static Device Nodes...1955alice # [ 5.582776] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1956clare # [ 5.583310] systemd[1]: Mounting Kernel Trace File System...1957bob # [ 5.560433] systemd[1]: Mounting Kernel Configuration File System...1958bob # [ 5.561244] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1959clare # [ 5.588020] systemd[1]: Starting Create List of Static Device Nodes...1960clare # [ 5.589956] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1961alice # [ 5.598403] systemd[1]: Mounting Kernel Configuration File System...1962bob # [ 5.568057] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1963alice # [ 5.598879] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1964bob # [ 5.568670] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1965alice # [ 5.604085] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1966alice # [ 5.604757] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1967clare # [ 5.603928] systemd[1]: Mounting Kernel Configuration File System...1968clare # [ 5.604380] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1969clare # [ 5.610861] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1970clare # [ 5.611326] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1971bob # [ 5.591109] systemd[1]: Mounting FUSE Control File System...1972bob # [ 5.591692] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671973clare # [ 5.624761] systemd[1]: Mounting FUSE Control File System...1974clare # [ 5.625289] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671975alice # [ 5.628650] systemd[1]: Mounting FUSE Control File System...1976alice # [ 5.629069] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671977bob # [ 5.607593] systemd[1]: Starting Journal Service...1978bob # [ 5.622696] systemd[1]: Starting Load Kernel Modules...1979alice # [ 5.654607] systemd[1]: Starting Journal Service...1980clare # [ 5.653082] systemd[1]: Starting Journal Service...1981alice # [ 5.664857] systemd[1]: Starting Load Kernel Modules...1982bob # [ 5.646224] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1983clare # [ 5.671833] systemd[1]: Starting Load Kernel Modules...1984alice # [ 5.677273] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1985bob # [ 5.662365] systemd[1]: Starting Remount Root and Kernel File Systems...1986bob # [ 5.662760] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1987alice # [ 5.691629] systemd[1]: Starting Remount Root and Kernel File Systems...1988alice # [ 5.692041] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1989clare # [ 5.691912] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1990clare # [ 5.699233] systemd[1]: Starting Remount Root and Kernel File Systems...1991clare # [ 5.699637] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1992bob # [ 5.680749] systemd-journald[291]: Collecting audit messages is enabled.1993clare # [ 5.708501] systemd[1]: Starting Coldplug All udev Devices...1994alice # [ 5.713916] systemd[1]: Starting Coldplug All udev Devices...1995alice # [ 5.718289] systemd[1]: Listening on Journal Log Access Socket.1996clare # [ 5.716543] systemd[1]: Listening on Journal Log Access Socket.1997alice # [ 5.718791] systemd[1]: Mounted Huge Pages File System.1998bob # [ 5.685609] systemd[1]: Queued start job for default target Multi-User System.1999bob # [ 5.687679] systemd[1]: Starting Coldplug All udev Devices...2000bob # [ 5.689569] systemd[1]: Listening on Journal Log Access Socket.2001clare # [ 5.717085] systemd[1]: Mounted Huge Pages File System.2002clare # [ 5.717469] systemd[1]: Mounted POSIX Message Queue File System.2003clare # [ 5.717807] systemd[1]: Mounted Kernel Debug File System.2004clare # [ 5.718150] systemd[1]: Mounted Kernel Trace File System.2005bob # [ 5.689993] systemd[1]: Mounted Huge Pages File System.2006bob # [ 5.690363] systemd[1]: Mounted POSIX Message Queue File System.2007bob # [ 5.690737] systemd[1]: Mounted Kernel Debug File System.2008bob # [ 5.691081] systemd[1]: Mounted Kernel Trace File System.2009bob # [ 5.690043] systemd[1]: systemd-journald.service: Deactivated successfully.2010bob # [ 5.694224] systemd-modules-load[292]: Module 'atkbd' is built in2011alice # [ 5.727247] systemd[1]: Mounted POSIX Message Queue File System.2012alice # [ 5.727710] systemd[1]: Mounted Kernel Debug File System.2013alice # [ 5.728150] systemd[1]: Mounted Kernel Trace File System.2014alice # [ 5.730309] systemd[1]: Finished Create List of Static Device Nodes.2015alice # [ 5.730869] systemd[1]: Mounted Kernel Configuration File System.2016bob # [ 5.699394] systemd-modules-load[292]: Module 'loop' is built in[ 5.705949] systemd[1]: Started Journal Service.2017bob # 2018bob # [ 5.706223] systemd[1]: Finished Create List of Static Device Nodes.2019bob # [ 5.709005] systemd[1]: Finished Load Kernel Modules.2020clare # [ 5.735845] systemd[1]: Queued start job for default target Multi-User System.2021clare # [ 5.738661] systemd-journald[280]: Collecting audit messages is enabled.2022clare # [ 5.744331] systemd[1]: Started Journal Service.2023alice # [ 5.747049] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2024clare # [ 5.742332] systemd[1]: systemd-journald.service: Deactivated successfully.2025clare # [ 5.743068] systemd[1]: Finished Create List of Static Device Nodes.2026clare # [ 5.743731] systemd[1]: Mounted Kernel Configuration File System.2027clare # [ 5.758067] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2028clare # [ 5.759097] systemd-modules-load[281]: Module 'atkbd' is built in2029clare # [ 5.759758] systemd-modules-load[281]: Module 'loop' is built in2030alice # [ 5.767726] systemd-journald[290]: Collecting audit messages is enabled.2031bob # [ 5.736515] systemd[1]: Starting Firewall...2032clare # [ 5.769808] systemd[1]: Finished Load Kernel Modules.2033alice # [ 5.776710] systemd[1]: Mounted FUSE Control File System.2034clare # [ 5.774522] systemd[1]: Mounted FUSE Control File System.2035bob # [ 5.749436] systemd[1]: Starting Apply Kernel Variables...2036alice # [ 5.786792] systemd[1]: Started Journal Service.2037bob # [ 5.752232] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2038bob # [ 5.754517] systemd[1]: Mounted Kernel Configuration File System.2039alice # [ 5.783563] systemd[1]: Queued start job for default target Multi-User System.2040bob # [ 5.763203] EXT4-fs (vda): re-mounted 25e77e32-f3e8-48fc-a2eb-b8ca851e413a.2041clare # [ 5.788533] systemd[1]: Starting Firewall...2042bob # [ 5.770608] systemd-oomd[294]: No swap; memory pressure usage will be degraded2043clare # [ 5.802811] systemd[1]: Starting Apply Kernel Variables...2044bob # [ 5.777569] systemd[1]: Finished Remount Root and Kernel File Systems.2045bob # [ 5.779847] systemd[1]: Listening on Disk Image Download Service Socket.2046alice # [ 5.823052] EXT4-fs (vda): re-mounted 7a79a265-bd73-4d5e-adc5-39ba3bc92172.2047clare # [ 5.823450] EXT4-fs (vda): re-mounted 25b10962-955d-47fe-b97b-0e3150546f0f.2048bob # [ 5.799085] systemd[1]: Starting Flush Journal to Persistent Storage...2049bob # [ 5.799755] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2050clare # [ 5.831648] systemd[1]: Finished Remount Root and Kernel File Systems.2051bob # [ 5.804798] systemd[1]: Starting Load/Save OS Random Seed...2052bob # [ 5.805366] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2053bob # [ 5.806161] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2054clare # [ 5.833548] systemd[1]: Listening on Disk Image Download Service Socket.2055clare # [ 5.837830] systemd[1]: Starting Flush Journal to Persistent Storage...2056clare # [ 5.838455] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2057clare # [ 5.844736] systemd[1]: Starting Load/Save OS Random Seed...2058clare # [ 5.845295] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2059bob # [ 5.824916] systemd[1]: Mounted FUSE Control File System.2060clare # [ 5.866199] systemd-oomd[283]: No swap; memory pressure usage will be degraded2061clare # [ 5.876543] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2062bob # [ 5.884216] systemd-journald[291]: Received client request to flush runtime journal.2063clare # [ 5.916907] systemd-journald[280]: Received client request to flush runtime journal.2064clare # [ 5.952339] systemd[1]: Finished Load/Save OS Random Seed.2065clare # [ 5.952953] systemd[1]: Reached target First Boot Complete.2066clare # [ 5.960201] systemd[1]: Finished Flush Journal to Persistent Storage.2067bob # [ 5.936765] systemd[1]: Finished Apply Kernel Variables.2068clare # [ 5.964374] systemd[1]: Finished Apply Kernel Variables.2069bob # [ 5.938450] systemd[1]: Starting Network Name Resolution...2070bob # [ 5.939026] systemd[1]: Finished Load/Save OS Random Seed.2071bob # [ 5.939600] systemd[1]: Reached target First Boot Complete.2072bob # [ 5.945393] systemd[1]: Finished Flush Journal to Persistent Storage.2073clare # [ 5.973052] systemd[1]: Starting Network Name Resolution...2074clare # [ 6.001815] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2075clare # [ 6.003130] systemd[1]: Starting Create Static Device Nodes in /dev...2076bob # [ 6.073260] systemd-resolved[318]: Positive Trust Anchors:2077bob # [ 6.073880] systemd-resolved[318]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2078bob # [ 6.074695] systemd-resolved[318]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162079bob # [ 6.075505] systemd-resolved[318]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test2080clare # [ 6.144769] systemd-resolved[310]: Positive Trust Anchors:2081clare # [ 6.145353] systemd-resolved[310]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2082clare # [ 6.146232] systemd-resolved[310]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162083bob # [ 6.121035] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2084bob # [ 6.121973] systemd[1]: Starting Create Static Device Nodes in /dev...2085clare # [ 6.147106] systemd-resolved[310]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test2086clare # [ 6.280682] systemd[1]: Finished Coldplug All udev Devices.2087bob # [ 6.268925] systemd[1]: Finished Coldplug All udev Devices.2088clare # [ 6.297406] systemd[1]: Finished Create Static Device Nodes in /dev.2089clare # [ 6.298252] systemd[1]: Reached target Preparation for Local File Systems.2090bob # [ 6.272207] systemd-resolved[318]: Using system hostname 'bob'.2091clare # [ 6.300845] systemd[1]: Starting Rule-based Manager for Device Events and Files...2092bob # [ 6.274781] systemd[1]: Started Network Name Resolution.2093clare # [ 6.310029] systemd-resolved[310]: Using system hostname 'clare'.2094clare # [ 6.313835] systemd[1]: Started Network Name Resolution.2095bob # [ 6.288073] systemd[1]: Finished Create Static Device Nodes in /dev.2096bob # [ 6.288704] systemd[1]: Reached target Preparation for Local File Systems.2097bob # [ 6.289939] systemd[1]: Starting Rule-based Manager for Device Events and Files...2098clare # [ 6.358218] systemd-udevd[321]: Using default interface naming scheme 'v261'.2099bob # [ 6.343570] systemd-udevd[334]: Using default interface naming scheme 'v261'.2100alice # [ 5.785078] systemd[1]: systemd-journald.service: Deactivated successfully.2101alice # [ 6.415862] systemd-journald[290]: Received client request to flush runtime journal.2102clare # [ 6.412125] systemd[1]: Started Rule-based Manager for Device Events and Files.2103bob # [ 6.393783] systemd[1]: Started Rule-based Manager for Device Events and Files.2104alice # [ 6.446511] systemd-modules-load[291]: Module 'atkbd' is built in2105alice # [ 6.447249] systemd-modules-load[291]: Module 'loop' is built in2106alice # [ 6.447933] systemd[1]: Finished Load Kernel Modules.2107alice # [ 6.453685] systemd[1]: Starting Firewall...2108alice # [ 6.456098] systemd[1]: Starting Apply Kernel Variables...2109alice # [ 6.458525] systemd-oomd[292]: No swap; memory pressure usage will be degraded2110alice # [ 6.461660] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2111alice # [ 6.463166] systemd[1]: Finished Remount Root and Kernel File Systems.2112alice # [ 6.465561] systemd[1]: Listening on Disk Image Download Service Socket.2113alice # [ 6.467995] systemd[1]: Starting Flush Journal to Persistent Storage...2114alice # [ 6.468765] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2115alice # [ 6.470027] systemd[1]: Starting Load/Save OS Random Seed...2116alice # [ 6.470662] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2117alice # [ 6.471516] systemd[1]: Finished Load/Save OS Random Seed.2118alice # [ 6.479457] systemd[1]: Reached target First Boot Complete.2119alice # [ 6.481679] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2120bob # [ 6.452760] systemd[1]: Mounting /run/wrappers...2121alice # [ 6.484753] systemd[1]: Starting Create Static Device Nodes in /dev...2122alice # [ 6.485429] systemd[1]: Finished Apply Kernel Variables.2123alice # [ 6.485948] systemd[1]: Starting Network Name Resolution...2124alice # [ 6.486635] systemd-resolved[308]: Positive Trust Anchors:2125alice # [ 6.487156] systemd-resolved[308]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2126alice # [ 6.494540] systemd-resolved[308]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162127clare # [ 6.495272] systemd[1]: Mounting /run/wrappers...2128alice # [ 6.495456] systemd-resolved[308]: 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 test2129alice # [ 6.517785] systemd[1]: Finished Create Static Device Nodes in /dev.2130alice # [ 6.518467] systemd[1]: Reached target Preparation for Local File Systems.2131alice # [ 6.522395] systemd[1]: Starting Rule-based Manager for Device Events and Files...2132alice # [ 6.523114] systemd[1]: Finished Coldplug All udev Devices.2133alice # [ 6.523624] systemd-resolved[308]: Using system hostname 'alice'.2134alice # [ 6.526865] systemd[1]: Started Network Name Resolution.2135alice # [ 6.527614] systemd-udevd[313]: Using default interface naming scheme 'v261'.2136alice # [ 6.532461] systemd[1]: Started Rule-based Manager for Device Events and Files.2137alice # [ 6.533146] systemd[1]: Finished Flush Journal to Persistent Storage.2138alice # [ 6.533745] systemd[1]: Mounting /run/wrappers...2139alice # [ 6.534369] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2140alice # [ 6.540487] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2141clare # [ 6.539553] systemd[1]: Mounted /run/wrappers.2142clare # [ 6.540143] systemd[1]: Reached target Local File Systems.2143clare # [ 6.545082] systemd[1]: Listening on Boot Loader Control Service Socket.2144bob # [ 6.519773] systemd[1]: Mounted /run/wrappers.2145bob # [ 6.520767] systemd[1]: Reached target Local File Systems.2146clare # [ 6.550718] systemd[1]: Starting register-nix-paths.service...2147alice # [ 6.554582] systemd[1]: Mounted /run/wrappers.2148alice # [ 6.555084] systemd[1]: Reached target Local File Systems.2149bob # [ 6.525747] systemd[1]: Listening on Boot Loader Control Service Socket.2150clare # [ 6.554617] systemd[1]: Starting Create SUID/SGID Wrappers...2151clare # [ 6.555668] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2152alice # [ 6.559642] systemd[1]: Listening on Boot Loader Control Service Socket.2153bob # [ 6.530223] systemd[1]: Starting register-nix-paths.service...2154clare # [ 6.560102] systemd[1]: Starting Save Transient machine-id to Disk...2155bob # [ 6.533575] systemd[1]: Starting Create SUID/SGID Wrappers...2156alice # [ 6.563494] systemd[1]: Starting register-nix-paths.service...2157bob # [ 6.534141] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2158bob # [ 6.537593] systemd[1]: Starting Save Transient machine-id to Disk...2159alice # [ 6.567787] systemd[1]: Starting Create SUID/SGID Wrappers...2160alice # [ 6.569162] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2161alice # [ 6.572680] systemd[1]: Starting Save Transient machine-id to Disk...2162clare # [ 6.575717] systemd[1]: Starting Create System Files and Directories...2163bob # [ 6.549799] systemd[1]: Starting Create System Files and Directories...2164clare # [ 6.576980] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2165bob # [ 6.550468] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2166clare # [ 6.577916] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2167alice # [ 6.587558] systemd[1]: Starting Create System Files and Directories...2168bob # [ 6.581202] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2169clare # [ 6.675397] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2170clare # [ 6.681199] systemd[1]: Finished Save Transient machine-id to Disk.2171alice # [ 6.685190] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2172bob # [ 6.659304] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2173alice # [ 6.693167] systemd[1]: Finished Save Transient machine-id to Disk.2174bob # [ 6.668449] systemd[1]: Finished Save Transient machine-id to Disk.2175clare # [ 6.728173] systemd[1]: Finished Create System Files and Directories.2176clare # [ 6.735695] systemd[1]: Starting Rebuild Journal Catalog...2177alice # [ 6.740687] systemd[1]: Finished Create System Files and Directories.2178clare # [ 6.741396] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2179alice # [ 6.749838] systemd[1]: Starting Rebuild Journal Catalog...2180bob # [ 6.722427] systemd[1]: Finished Create System Files and Directories.2181alice # [ 6.754941] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2182bob # [ 6.729577] systemd[1]: Starting Rebuild Journal Catalog...2183bob # [ 6.737450] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2184alice # [ 6.778001] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2185alice # [ 6.843378] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2186clare # [ 6.849812] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2187alice # [ 6.866615] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2188bob # [ 6.843064] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2189clare # [ 6.879668] systemd[1]: Finished Rebuild Journal Catalog.2190clare # [ 6.887561] systemd[1]: Starting Update is Completed...2191alice # [ 6.900872] systemd[1]: Finished Rebuild Journal Catalog.2192bob # [ 6.873712] systemd[1]: Finished Rebuild Journal Catalog.2193alice # [ 6.907084] systemd[1]: Starting Update is Completed...2194clare # [ 6.906508] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2195bob # [ 6.882564] systemd[1]: Starting Update is Completed...2196clare # [ 6.942082] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2197bob # [ 6.923520] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2198clare # [ 6.958925] systemd[1]: Finished Update is Completed.2199bob # [ 6.939007] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2200alice # [ 6.969136] systemd[1]: Finished Update is Completed.2201bob # [ 6.975095] systemd[1]: Finished Update is Completed.2202alice # [ 7.114820] (udev-worker)[338]: Network interface NamePolicy= disabled on kernel command line.2203alice # [ 7.119206] (udev-worker)[345]: Network interface NamePolicy= disabled on kernel command line.2204clare # [ 7.193882] (udev-worker)[348]: Network interface NamePolicy= disabled on kernel command line.2205clare # [ 7.203598] (udev-worker)[342]: Network interface NamePolicy= disabled on kernel command line.2206bob # [ 7.189766] (udev-worker)[367]: Network interface NamePolicy= disabled on kernel command line.2207bob # [ 7.194444] (udev-worker)[365]: Network interface NamePolicy= disabled on kernel command line.2208clare # [ 7.374982] mousedev: PS/2 mouse device common for all mice2209bob # [ 7.400428] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2210bob # [ 7.403531] systemd[1]: Finished Create SUID/SGID Wrappers.2211clare # [ 7.431587] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2212clare # [ 7.432971] systemd[1]: Finished Create SUID/SGID Wrappers.2213alice # [ 7.439641] mousedev: PS/2 mouse device common for all mice2214clare # [ 7.451451] systemd[1]: Finished register-nix-paths.service.2215bob # [ 7.425773] systemd[1]: Finished register-nix-paths.service.2216bob # [ 7.427285] systemd[1]: Reached target System Initialization.2217bob # [ 7.427883] systemd[1]: Started Discard unused filesystem blocks once a week.2218clare # [ 7.456125] systemd[1]: Reached target System Initialization.2219clare # [ 7.456681] systemd[1]: Started Discard unused filesystem blocks once a week.2220clare # [ 7.457308] systemd[1]: Started Daily Cleanup of Temporary Directories.2221clare # [ 7.457887] systemd[1]: Reached target Timer Units.2222bob # [ 7.432116] systemd[1]: Started Daily Cleanup of Temporary Directories.2223clare # [ 7.459848] systemd[1]: Listening on D-Bus System Message Bus Socket.2224clare # [ 7.462188] systemd[1]: Listening on harmonia binary cache socket.2225bob # [ 7.435333] systemd[1]: Reached target Timer Units.2226clare # [ 7.462752] systemd[1]: Listening on Nix Daemon Socket.2227clare # [ 7.463251] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2228clare # [ 7.463975] systemd[1]: Reached target Socket Units.2229bob # [ 7.437412] systemd[1]: Listening on D-Bus System Message Bus Socket.2230bob # [ 7.438042] systemd[1]: Listening on Nix Daemon Socket.2231bob # [ 7.438571] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2232bob # [ 7.439346] systemd[1]: Reached target Socket Units.2233alice # [ 7.468625] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2234bob # [ 7.439876] systemd[1]: Reached target Basic System.2235alice # [ 7.469724] systemd[1]: Finished Create SUID/SGID Wrappers.2236clare # [ 7.470338] systemd[1]: Reached target Basic System.2237clare # [ 7.470882] systemd[1]: Started backdoor.service.2238clare # [ 7.471323] systemd[1]: Starting Import lastlog data into lastlog2 database...2239alice # [ 7.478782] systemd[1]: Finished register-nix-paths.service.2240alice # [ 7.479640] systemd[1]: Reached target System Initialization.2241bob # [ 7.451420] systemd[1]: Started backdoor.service.2242bob # [ 7.451922] systemd[1]: Starting Import lastlog data into lastlog2 database...2243alice # [ 7.482103] systemd[1]: Started Discard unused filesystem blocks once a week.2244clare # [ 7.480219] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2245alice # [ 7.482818] systemd[1]: Started Daily Cleanup of Temporary Directories.2246alice # [ 7.483405] systemd[1]: Reached target Timer Units.2247bob # [ 7.454211] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2248alice # [ 7.489228] systemd[1]: Listening on D-Bus System Message Bus Socket.2249clare # [ 7.487533] systemd[1]: Starting Post-Boot Actions...2250alice # [ 7.489848] systemd[1]: Listening on Nix Daemon Socket.2251alice # [ 7.490327] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2252alice # [ 7.491036] systemd[1]: Reached target Socket Units.2253alice # [ 7.491508] systemd[1]: Reached target Basic System.2254alice # [ 7.491979] systemd[1]: Started backdoor.service.2255bob # [ 7.465969] systemd[1]: Starting Post-Boot Actions...2256clare # [ 7.497018] systemd[1]: Started Reset console on configuration changes.2257alice # [ 7.503720] systemd[1]: Starting Import lastlog data into lastlog2 database...2258bob # [ 7.476160] systemd[1]: Started Reset console on configuration changes.2259alice # [ 7.505667] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2260alice # [ 7.509883] systemd[1]: Starting Post-Boot Actions...2261alice # [ 7.524281] systemd[1]: Started Reset console on configuration changes.2262clare # [ 7.536089] systemd[1]: Starting D-Bus System Message Bus...2263bob # [ 7.527057] mousedev: PS/2 mouse device common for all mice2264alice # [ 7.554992] systemd[1]: Starting D-Bus System Message Bus...2265bob # [ 7.525901] systemd[1]: Starting D-Bus System Message Bus...2266bob # [ 7.568920] systemd[1]: Finished Post-Boot Actions.2267bob # connecting to host...2268clare # connecting to host...2269clare # [ 7.626812] systemd[1]: Finished Post-Boot Actions.2270alice # connecting to host...2271clare # [ 7.635990] systemd[1]: Started Name Service Cache Daemon (nsncd).2272clare # [ 7.643164] systemd[1]: Reached target Host and Network Name Lookups.2273clare # [ 7.648135] nsncd[470]: Oct 05 10:49:04.489 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2274clare # [ 7.649417] systemd[1]: Reached target User and Group Name Lookups.2275clare # [ 7.654804] systemd[1]: Starting User Login Management...2276bob # [ 7.627803] systemd[1]: Started Name Service Cache Daemon (nsncd).2277clare # [ 7.656912] systemd[1]: Finished Import lastlog data into lastlog2 database.2278bob # [ 7.632305] systemd[1]: Reached target Host and Network Name Lookups.2279bob # [ 7.632955] systemd[1]: Reached target User and Group Name Lookups.2280alice # [ 7.662653] systemd[1]: Finished Post-Boot Actions.2281alice # [ 7.663173] systemd[1]: Started Name Service Cache Daemon (nsncd).2282bob # [ 7.638579] nsncd[483]: Oct 05 10:49:04.465 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2283alice # [ 7.671473] systemd[1]: Reached target Host and Network Name Lookups.2284bob: Guest shell says: b'Spawning backdoor root shell...\n'2285alice # [ 7.672541] nsncd[478]: Oct 05 10:49:04.497 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2286alice # [ 7.673704] systemd[1]: Reached target User and Group Name Lookups.2287bob # [ 7.652109] systemd[1]: Starting User Login Management...2288alice # [ 7.682604] systemd[1]: Starting User Login Management...2289alice # [ 7.710366] systemd[1]: Finished Import lastlog data into lastlog2 database.2290bob # [ 7.699171] systemd[1]: Finished Import lastlog data into lastlog2 database.2291clare # [ 7.761733] dbus-broker-launch[474]: Looking up NSS user entry for 'systemd-timesync'...2292clare # [ 7.774119] dbus-broker-launch[474]: NSS returned no entry for 'systemd-timesync'2293clare # [ 7.774763] dbus-broker-launch[474]: Invalid user-name in /nix/store/6h4i96sch7l32kqjsx5rqqjdnbyjanf9-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2294clare # [ 7.781695] systemd-logind[493]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2295bob # [ 7.746804] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'...2296clare # [ 7.785752] systemd-logind[493]: Watching system buttons on /dev/input/event0 (gpio-keys)2297bob: connected to guest root shell2298clare # [ 7.786531] systemd-logind[493]: New seat seat0.2299bob: (connecting took 7.99 seconds)2300clare # [ 7.790560] systemd[1]: Started User Login Management.2301bob: (finished: waiting for the VM to finish booting, in 7.99 seconds)2302alice # [ 7.793284] dbus-broker-launch[483]: Looking up NSS user entry for 'systemd-timesync'...2303bob # [ 7.764625] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync'2304clare # [ 7.792450] systemd[1]: Starting linger-users.service...2305bob # [ 7.768366] dbus-broker-launch[489]: Invalid user-name in /nix/store/fm6gxvgadz7qdl7q58a8v2dwzj2zj3va-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2306alice # [ 7.804990] dbus-broker-launch[483]: NSS returned no entry for 'systemd-timesync'2307alice # [ 7.805673] dbus-broker-launch[483]: Invalid user-name in /nix/store/z6zbsgwyph96jp8sx9668447ra6dnvjl-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2308alice # [ 7.811766] systemd-logind[502]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2309alice # [ 7.817405] systemd-logind[502]: Watching system buttons on /dev/input/event0 (gpio-keys)2310alice # [ 7.818125] systemd-logind[502]: New seat seat0.2311alice # [ 7.820943] systemd[1]: Started User Login Management.2312bob # [ 7.792264] systemd-logind[506]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2313clare # [ 7.819805] systemd[1]: Started D-Bus System Message Bus.2314alice # [ 7.823010] systemd[1]: Starting linger-users.service...2315bob # [ 7.797726] systemd-logind[506]: Watching system buttons on /dev/input/event0 (gpio-keys)2316bob # [ 7.798531] systemd-logind[506]: New seat seat0.2317bob # [ 7.804240] systemd[1]: Started User Login Management.2318bob # [ 7.806676] systemd[1]: Starting linger-users.service...2319bob # [ 7.807190] systemd[1]: Started D-Bus System Message Bus.2320alice # [ 7.847493] systemd[1]: Started D-Bus System Message Bus.2321clare # [ 7.850119] dbus-broker-launch[474]: Ready2322clare # [ 7.864934] systemd[1]: linger-users.service: Deactivated successfully.2323clare # [ 7.867176] systemd[1]: Finished linger-users.service.2324alice # [ 7.880648] dbus-broker-launch[483]: Ready2325bob # [ 7.860614] dbus-broker-launch[489]: Ready2326alice # [ 7.894959] systemd[1]: linger-users.service: Deactivated successfully.2327alice # [ 7.896915] systemd[1]: Finished linger-users.service.2328bob # [ 7.899407] systemd[1]: linger-users.service: Deactivated successfully.2329bob # [ 7.902016] systemd[1]: Finished linger-users.service.2330clare # [ 7.989251] systemd[1]: Finished Firewall.2331clare # [ 7.990554] systemd[1]: Reached target Preparation for Network.2332alice # [ 7.992894] systemd[1]: Finished Firewall.2333alice # [ 7.994287] systemd[1]: Reached target Preparation for Network.2334clare # [ 7.992944] systemd[1]: Listening on Network Management Resolve Hook Socket.2335alice # [ 7.996679] systemd[1]: Listening on Network Management Resolve Hook Socket.2336clare # [ 7.997186] systemd[1]: Starting Network Management...2337alice # [ 8.001183] systemd[1]: Starting Network Management...2338bob # [ 7.994371] systemd[1]: Finished Firewall.2339bob # [ 7.996448] systemd[1]: Reached target Preparation for Network.2340bob # [ 7.999874] systemd[1]: Listening on Network Management Resolve Hook Socket.2341bob # [ 8.003520] systemd[1]: Starting Network Management...2342alice # [ 8.178502] cfg80211: Loading compiled-in X.509 certificates for regulatory database2343clare # [ 8.177975] cfg80211: Loading compiled-in X.509 certificates for regulatory database2344bob # [ 8.182865] cfg80211: Loading compiled-in X.509 certificates for regulatory database2345alice # [ 8.221621] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2346alice # [ 8.222145] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2347clare # [ 8.220497] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2348clare # [ 8.221029] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2349alice # [ 8.224761] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22350alice # [ 8.225188] cfg80211: failed to load regulatory.db2351clare # [ 8.223679] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22352clare # [ 8.224043] cfg80211: failed to load regulatory.db2353alice # [ 8.247763] systemd-networkd[545]: lo: Link UP2354clare # [ 8.246387] systemd-networkd[539]: lo: Link UP2355clare # [ 8.246916] systemd-networkd[539]: lo: Gained carrier2356alice # [ 8.249993] systemd-networkd[545]: lo: Gained carrier2357bob # [ 8.229352] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2358bob # [ 8.229838] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2359clare # [ 8.255046] systemd[1]: Started Network Management.2360alice # [ 8.257483] systemd[1]: Started Network Management.2361clare # [ 8.255559] systemd[1]: Reached target Network.2362bob # [ 8.233254] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22363bob # [ 8.233634] cfg80211: failed to load regulatory.db2364alice # [ 8.257967] systemd-networkd[545]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2365clare # [ 8.257867] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2366alice # [ 8.263269] systemd[1]: Reached target Network.2367clare # [ 8.261174] systemd-networkd[539]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2368clare # [ 8.262072] systemd[1]: Starting Permit User Sessions...2369alice # [ 8.265584] systemd-networkd[545]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2370alice # [ 8.266904] systemd-networkd[545]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2371alice # [ 8.273993] systemd-networkd[545]: eth0: Link UP2372clare # [ 8.271318] systemd-networkd[539]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2373alice # [ 8.274464] systemd-networkd[545]: eth0: Gained carrier2374alice # [ 8.274923] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2375clare # [ 8.277333] systemd-networkd[539]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2376clare # [ 8.278074] systemd-networkd[539]: eth0: Link UP2377clare # [ 8.278486] systemd-networkd[539]: eth0: Gained carrier2378alice # [ 8.280813] systemd[1]: Starting Permit User Sessions...2379clare # [ 8.278937] systemd-networkd[539]: eth1: Link UP2380alice # [ 8.281330] systemd-networkd[545]: eth1: Link UP2381alice # [ 8.281739] systemd-networkd[545]: eth1: Gained carrier2382alice # [ 8.282173] systemd-networkd[545]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2383bob # [ 8.255032] systemd-networkd[558]: lo: Link UP2384bob # [ 8.255561] systemd-networkd[558]: lo: Gained carrier2385clare # [ 8.284456] systemd-networkd[539]: eth1: Gained carrier2386clare # [ 8.284946] systemd-networkd[539]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2387bob # [ 8.261609] systemd[1]: Started Network Management.2388bob # [ 8.264165] systemd-networkd[558]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2389alice # [ 8.293572] systemd-networkd[545]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22390bob # [ 8.269624] systemd[1]: Reached target Network.2391bob # [ 8.270094] systemd[1]: Reached target Network is Online.2392clare # [ 8.297401] systemd-networkd[539]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22393bob # [ 8.275452] systemd-networkd[558]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2394bob # [ 8.280486] systemd-networkd[558]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2395bob # [ 8.281336] systemd-networkd[558]: eth0: Link UP2396bob # [ 8.281797] systemd-networkd[558]: eth0: Gained carrier2397bob # [ 8.282279] systemd[1]: Starting ncps binary cache proxy service...2398bob # [ 8.293670] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2399bob # [ 8.294359] systemd-networkd[558]: eth1: Link UP2400bob # [ 8.294800] systemd-networkd[558]: eth1: Gained carrier2401bob # [ 8.295304] systemd-networkd[558]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2402bob # [ 8.301307] systemd-networkd[558]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22403clare # [ 8.340806] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2404alice # [ 8.343636] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2405clare # [ 8.343207] systemd[1]: Finished Permit User Sessions.2406alice # [ 8.346804] systemd[1]: Finished Permit User Sessions.2407clare # [ 8.347645] systemd[1]: Started Getty on tty1.2408clare # [ 8.349501] systemd[1]: Reached target Login Prompts.2409alice # [ 8.352336] systemd[1]: Started Getty on tty1.2410bob # [ 8.323816] systemd[1]: Starting Permit User Sessions...2411alice # [ 8.353795] systemd[1]: Reached target Login Prompts.2412clare # [ 8.352218] systemd[1]: Reached target Multi-User System.2413alice # [ 8.354290] systemd[1]: Reached target Multi-User System.2414alice # [ 8.356862] systemd[1]: Startup finished in 754ms (kernel) + 3.612s (initrd) + 3.988s (userspace) = 8.355s.2415clare # [ 8.364792] systemd[1]: Startup finished in 747ms (kernel) + 3.639s (initrd) + 3.977s (userspace) = 8.364s.2416bob # [ 8.387376] systemd[1]: Finished Permit User Sessions.2417bob # [ 8.394271] systemd[1]: Started Getty on tty1.2418bob # [ 8.397842] systemd[1]: Reached target Login Prompts.2419bob # [ 8.398336] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2420bob # [ 8.434878] ncps-pre-start[565]: /nix/store/z27dji5sjdkkdpzjlm37nq6d5cwbgl8i-ncps-0.9.4/bin/dbmate-ncps up2421alice # [ 8.490587] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input32422clare # [ 8.502424] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input32423bob # [ 8.665131] ncps-pre-start[580]: Creating: /var/lib/ncps/db/db.sqlite2424bob # [ 8.695223] ncps-pre-start[580]: Applying: 20241210054814_create-narinfos-table.sql2425bob # [ 8.710783] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input32426bob # [ 8.708277] ncps-pre-start[580]: Applied: 20241210054814_create-narinfos-table.sql in 13.07184ms2427bob # [ 8.710456] ncps-pre-start[580]: Applying: 20241210054829_create-nars-table.sql2428bob # [ 8.716385] ncps-pre-start[580]: Applied: 20241210054829_create-nars-table.sql in 8.09216ms2429bob # [ 8.717681] ncps-pre-start[580]: Applying: 20241213014846_add-query-to-nars-table.sql2430bob # [ 8.724262] ncps-pre-start[580]: Applied: 20241213014846_add-query-to-nars-table.sql in 7.85824ms2431bob # [ 8.725054] ncps-pre-start[580]: Applying: 20251230224159_add-cascade-to-nars-fk.sql2432bob # [ 8.733844] ncps-pre-start[580]: Applied: 20251230224159_add-cascade-to-nars-fk.sql in 9.55436ms2433bob # [ 8.736061] ncps-pre-start[580]: Applying: 20260101000000_add-config-table.sql2434bob # [ 8.740239] ncps-pre-start[580]: Applied: 20260101000000_add-config-table.sql in 6.38916ms2435bob # [ 8.742857] ncps-pre-start[580]: Applying: 20260105025735_remove-redundant-indexes.sql2436bob # [ 8.745752] ncps-pre-start[580]: Applied: 20260105025735_remove-redundant-indexes.sql in 5.50132ms2437bob # [ 8.749157] ncps-pre-start[580]: Applying: 20260105030513_support_shared_nars_between_narinfos.sql2438bob # [ 8.756250] ncps-pre-start[580]: Applied: 20260105030513_support_shared_nars_between_narinfos.sql in 10.46544ms2439alice # [ 8.790649] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2440bob # [ 8.764135] ncps-pre-start[580]: Applying: 20260117195000_add_narinfo_de_normalized.sql2441alice # [ 8.796981] systemd[1]: Starting Virtual Console Setup...2442clare # [ 8.798221] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2443bob # [ 8.772326] ncps-pre-start[580]: Applied: 20260117195000_add_narinfo_de_normalized.sql in 16.02888ms2444bob # [ 8.773162] ncps-pre-start[580]: Applying: 20260127223000_allow_multiple_nar_representations.sql2445clare # [ 8.803936] systemd[1]: Starting Virtual Console Setup...2446bob # [ 8.781260] ncps-pre-start[580]: Applied: 20260127223000_allow_multiple_nar_representations.sql in 8.9552ms2447bob # [ 8.782109] ncps-pre-start[580]: Applying: 20260131021850_add_chunks.sql2448bob # [ 8.792289] ncps-pre-start[580]: Applied: 20260131021850_add_chunks.sql in 10.97108ms2449alice # [ 8.822298] systemd-logind[502]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2450bob # [ 8.793070] ncps-pre-start[580]: Applying: 20260205063651_add_total_chunks_to_nar_files.sql2451clare # [ 8.820683] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.2452clare # [ 8.821382] systemd[1]: Stopped Virtual Console Setup.2453clare # [ 8.825615] systemd[1]: Starting Virtual Console Setup...2454clare # [ 8.830312] systemd-logind[493]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2455bob # [ 8.806165] ncps-pre-start[580]: Applied: 20260205063651_add_total_chunks_to_nar_files.sql in 13.84012ms2456bob # [ 8.807013] ncps-pre-start[580]: Applying: 20260217071237_add_chunking_started_at_to_nar_files.sql2457bob # [ 8.816116] ncps-pre-start[580]: Applied: 20260217071237_add_chunking_started_at_to_nar_files.sql in 9.87112ms2458bob # [ 8.817277] ncps-pre-start[580]: Applying: 20260301000000_add_verified_at_to_nar_files.sql2459bob # [ 8.826098] ncps-pre-start[580]: Applied: 20260301000000_add_verified_at_to_nar_files.sql in 9.68208ms2460clare # [ 8.900503] systemd-vconsole-setup[558]: Configuration of first virtual console was skipped, ignoring remaining ones.2461clare # [ 8.903231] systemd[1]: Finished Virtual Console Setup.2462alice # [ 8.908887] systemd-vconsole-setup[563]: Configuration of first virtual console was skipped, ignoring remaining ones.2463alice # [ 8.911634] systemd[1]: Finished Virtual Console Setup.2464bob # [ 8.985268] systemd[1]: Started ncps binary cache proxy service.2465bob # [ 8.985872] systemd[1]: Reached target Multi-User System.2466bob # [ 8.989133] systemd[1]: Startup finished in 718ms (kernel) + 3.620s (initrd) + 4.649s (userspace) = 8.988s.2467bob: (finished: waiting for unit ncps, in 9.29 seconds)2468bob: must succeed: systemctl status ncps2469bob: (finished: must succeed: systemctl status ncps, in 0.08 seconds)2470clare: waiting for unit harmonia.socket2471clare: waiting for the VM to finish booting2472clare: Guest shell says: b'Spawning backdoor root shell...\n'2473clare: connected to guest root shell2474clare: (connecting took 0.00 seconds)2475clare: (finished: waiting for the VM to finish booting, in 0.00 seconds)2476clare: (finished: waiting for unit harmonia.socket, in 0.05 seconds)2477alice: waiting for success: curl bob:8502/nix-cache-info2478alice: waiting for the VM to finish booting2479alice: Guest shell says: b'Spawning backdoor root shell...\n'2480alice: connected to guest root shell2481alice: (connecting took 0.00 seconds)2482alice: (finished: waiting for the VM to finish booting, in 0.00 seconds)2483bob # [ 9.206259] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2484bob # [ 9.215056] systemd[1]: Starting Virtual Console Setup...2485bob # [ 9.255451] systemd-logind[506]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2486bob # [ 9.360372] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","log_level":"info","time":"2026-10-05T10:49:06Z","message":"logger created"}2487bob # [ 9.361931] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","xz-binary-path":"/nix/store/csrwf31hprwghnbyiglxa3p787lgrl67-xz-5.8.4-bin/bin/xz","time":"2026-10-05T10:49:06Z","message":"Using xz binary for xz decompression"}2488bob # [ 9.370490] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-10-05T10:49:06Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"}2489bob # [ 9.381089] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","time":"2026-10-05T10:49:06Z","message":"using local locks (single-instance mode)"}2490bob # [ 9.387811] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-05T10:49:06Z","message":"setting up tracer provider to discard traces"}2491bob # [ 9.390689] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-05T10:49:06Z","message":"setting up meter provider to discard metrics"}2492bob # [ 9.393525] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-05T10:49:06Z","message":"setting up logger provider to discard logs"}2493bob # [ 9.401747] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-10-05T10:49:06Z","message":"Reporting anonymous metrics to the project maintainers"}2494bob # [ 9.404129] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-10-05T10:49:06Z","message":"failed to parse netrc file, proceeding without netrc authentication"}2495bob # [ 9.405909] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-10-05T10:49:06Z","message":"using local storage"}2496bob # [ 9.452710] systemd-vconsole-setup[604]: Configuration of first virtual console was skipped, ignoring remaining ones.2497bob # [ 9.455322] systemd[1]: Finished Virtual Console Setup.2498bob # [ 9.473413] systemd-networkd[558]: eth0: Gained IPv6LL2499alice # [ 9.665653] systemd-networkd[545]: eth1: Gained IPv6LL2500bob # [ 9.731266] systemd-networkd[558]: eth1: Gained IPv6LL2501alice # [ 9.761327] systemd-networkd[545]: eth0: Gained IPv6LL2502clare # [ 9.763792] systemd[1]: Starting harmonia binary cache service...2503clare # [ 9.905485] harmonia-cache[565]: 2026-10-05T10:49:06.752917Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000))2504clare # [ 9.910932] harmonia-cache[565]: 2026-10-05T10:49:06.757260Z INFO actix_server::builder: starting 4 workers2505clare # [ 9.918028] harmonia-cache[565]: 2026-10-05T10:49:06.762185Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s2506clare # [ 9.922268] harmonia-cache[565]: 2026-10-05T10:49:06.762269Z INFO actix_server::server: Actix runtime found; starting in Actix runtime2507clare # [ 9.931117] harmonia-cache[565]: 2026-10-05T10:49:06.762372Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:50002508clare # [ 9.935995] systemd[1]: Started harmonia binary cache service.2509clare # [ 9.952606] systemd-networkd[539]: eth1: Gained IPv6LL2510bob # [ 9.925396] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-10-05T10:49:06Z","message":"upstream became healthy and is now available for requests"}2511bob # [ 9.930482] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-10-05T10:49:06Z","message":"Server started"}2512clare # [ 10.082925] systemd-networkd[539]: eth0: Gained IPv6LL2513bob # [ 10.419174] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: 2026/10/05 10:49:07 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host2514alice # curl: (28) Failed to connect to bob:8502 after 135071 ms: Could not connect to server2515alice # % Total % Received % Xferd Average Speed Time Time Time Current2516alice # Dload Upload Total Spent Left Speed2517bob # [ 145.414287] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:40492","trace_id":"5904488f3e960de280aa1d7cf0685778","span_id":"4632bc639d8799e3","status":200,"elapsed":0.15612,"bytes":50,"time":"2026-10-05T10:51:22Z","message":"handled request"}2518alice # 0 0 0 0 0 0 0 0 0100 50 100 50 0 0 1888 0 0100 50 100 50 0 0 1635 0 0100 50 100 50 0 0 1440 0 02519alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 136.25 seconds)2520bob: waiting for success: curl clare:5000/nix-cache-info2521bob # % Total % Received % Xferd Average Speed Time Time Time Current2522bob # Dload Upload Total Spent Left Speed2523bob # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2834 0 0100 51 100 51 0 0 2354 0 0100 51 100 51 0 0 2014 0 02524bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.10 seconds)2525clare: waiting for unit harmonia2526clare: (finished: waiting for unit harmonia, in 0.07 seconds)2527clare: must succeed: systemctl status harmonia2528clare: (finished: must succeed: systemctl status harmonia, in 0.03 seconds)2529clare: must succeed: nix-build --expr '2530 builtins.derivation {2531 name = "hi";2532 builder = "/bin/sh";2533 args = [ "-c" "echo hi > $out" ];2534 system = "aarch64-linux";2535 }2536' 2>&125372538clare # [ 146.075185] systemd-resolved[310]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3.2539clare # [ 146.082379] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2540clare # [ 146.086070] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2541clare # [ 146.600469] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2542clare # [ 146.605389] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2543clare # [ 147.840064] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2544clare # [ 147.844075] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2545clare # [ 150.045274] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2546clare # [ 150.049394] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2547clare: (finished: must succeed: nix-build --expr '2548 builtins.derivation {2549 name = "hi";2550 builder = "/bin/sh";2551 args = [ "-c" "echo hi > $out" ];2552 system = "aarch64-linux";2553 }2554' 2>&12555, in 4.51 seconds)2556alice: must succeed: nix-build --expr '2557 builtins.derivation {2558 name = "hi";2559 builder = "/bin/sh";2560 args = [ "-c" "echo hi > $out" ];2561 system = "aarch64-linux";2562 }2563' 2>&125642565bob # [ 150.267899] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:40502","trace_id":"3c32776d4c45d96c587b567bcb53dcbb","span_id":"27e4dec79445f5bc","status":200,"elapsed":0.03008,"bytes":50,"time":"2026-10-05T10:51:27Z","message":"handled request"}2566alice # [ 150.583400] systemd-resolved[308]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3.2567alice # [ 151.284539] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2568alice # [ 151.289535] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2569alice # [ 152.423275] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2570alice # [ 152.427099] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2571alice # [ 154.705070] systemd-resolved[308]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2572alice # [ 154.709297] systemd-resolved[308]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2573bob # [ 154.688355] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_url":"http://clare.clan:5000/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","upstream_url":"http://clare.clan:5000","time":"2026-10-05T10:51:31Z","message":"download the narinfo from upstream"}2574bob # [ 154.723690] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-05T10:51:31Z","message":"downloading the nar from upstream"}2575bob # [ 154.735447] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_hash":"1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h","nar_compression":"none","nar_query":"hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"http://clare.clan:5000/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","upstream_url":"http://clare.clan:5000","nar_hash":"1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h","nar_compression":"none","nar_query":"hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-05T10:51:31Z","message":"download the nar from upstream"}2576bob # [ 154.772934] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-05T10:51:31Z","message":"storing narinfo and nar_file record in the database"}2577bob # [ 154.790991] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":62.86996,"time":"2026-10-05T10:51:31Z","message":"download of nar complete"}2578bob # [ 154.799182] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":101.5204,"time":"2026-10-05T10:51:31Z","message":"download of narinfo complete"}2579bob # [ 154.808991] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:40502","trace_id":"d764be0b9a9da39ff006f1cb2a6b752f","span_id":"ce0e506264be7034","status":200,"elapsed":104.87468,"bytes":532,"time":"2026-10-05T10:51:31Z","message":"handled request"}2580bob # [ 154.814166] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[587]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:40502","trace_id":"6041e0f00c9c7a18c8a58b1791d51e70","span_id":"f236034f32f0c71b","status":200,"elapsed":0.62324,"bytes":95,"time":"2026-10-05T10:51:31Z","message":"handled request"}2581alice: (finished: must succeed: nix-build --expr '2582 builtins.derivation {2583 name = "hi";2584 builder = "/bin/sh";2585 args = [ "-c" "echo hi > $out" ];2586 system = "aarch64-linux";2587 }2588' 2>&12589, in 4.69 seconds)2590(finished: run the VM test script, in 155.57 seconds)2591test script finished in 155.72s2592cleanup2593kill QemuMachine (pid 48)2594alice # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2595alice # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down2596alice # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down2597alice # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down2598kill QemuMachine (pid 49)2599bob # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2600bob # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down2601bob # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down2602bob # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down2603kill QemuMachine (pid 50)2604clare # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2605clare # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down2606clare # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down2607clare # [2026-10-05T10:51:31Z INFO virtiofsd] Client disconnected, shutting down2608(finished: cleanup, in 0.38 seconds)