vm-test-run-ncps
checks.aarch64-linux.ncps
· build #964
· raw
1build01: building jwy8d4qaffsflsfbkisrzskg277p9747-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 vm15clare # Disk image does not exist, creating the virtualisation disk image...16bob # Disk image does not exist, creating the virtualisation disk image...17clare # Formatting '/build/vm-state-clare/tmp.c54g5NGZ6M', fmt=raw size=107374182418clare: QEMU running (pid 50)19bob: QEMU running (pid 49)20clare # mke2fs 1.47.4 (6-Mar-2025)21alice: QEMU running (pid 48)22bob # Formatting '/build/vm-state-bob/tmp.sHunfCcOVj', fmt=raw size=107374182423alice # Disk image does not exist, creating the virtualisation disk image...24bob # mke2fs 1.47.4 (6-Mar-2025)25alice # Formatting '/build/vm-state-alice/tmp.zgRFng862k', fmt=raw size=107374182426alice # mke2fs 1.47.4 (6-Mar-2025)27(finished: start all VMs, in 0.47 seconds)28bob # Discarding device blocks: 0/262144 done29clare # Discarding device blocks: 0/262144 done30alice # Discarding device blocks: 0/262144 done31bob # Creating filesystem with 262144 4k blocks and 65536 inodes32bob: waiting for unit ncps33alice # Creating filesystem with 262144 4k blocks and 65536 inodes34bob # Filesystem UUID: e2358b9f-7e50-47e7-82c4-752cc1ceae8635clare # Creating filesystem with 262144 4k blocks and 65536 inodes36bob: waiting for the VM to finish booting37alice # Filesystem UUID: 011a7ca5-9375-41bd-8c7e-00a713a29a2e38bob # Superblock backups stored on blocks:39alice # Superblock backups stored on blocks:40bob # 32768, 98304, 163840, 22937641alice # 32768, 98304, 163840, 22937642bob # 43alice # 44alice # Allocating group tables: 0/8 done45bob # Allocating group tables: 0/8 done46alice # Writing inode tables: 0/8 done47bob # Writing inode tables: 0/8 done48alice # Creating journal (8192 blocks): done49bob # Creating journal (8192 blocks): done50alice # Writing superblocks and filesystem accounting information: 0/8 done51bob # Writing superblocks and filesystem accounting information: 0/8 done52alice # 53bob # 54alice # Virtualisation disk image created.55bob # Virtualisation disk image created.56alice # Starting virtiofs daemons...57bob # Starting virtiofs daemons...58alice # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)59bob # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)60alice # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether61bob # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether62alice # [2026-10-13T15:41:10Z INFO virtiofsd] Waiting for vhost-user socket connection...63bob # [2026-10-13T15:41:10Z INFO virtiofsd] Waiting for vhost-user socket connection...64alice # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)65bob # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)66alice # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether67bob # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68alice # [2026-10-13T15:41:10Z INFO virtiofsd] Waiting for vhost-user socket connection...69bob # [2026-10-13T15:41:10Z INFO virtiofsd] Waiting for vhost-user socket connection...70alice # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)71bob # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)72alice # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether73bob # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether74alice # [2026-10-13T15:41:10Z INFO virtiofsd] Waiting for vhost-user socket connection...75bob # [2026-10-13T15:41:10Z INFO virtiofsd] Waiting for vhost-user socket connection...76alice # [2026-10-13T15:41:10Z INFO virtiofsd] Client connected, servicing requests77bob # [2026-10-13T15:41:10Z INFO virtiofsd] Client connected, servicing requests78alice # [2026-10-13T15:41:10Z INFO virtiofsd] Client connected, servicing requests79bob # [2026-10-13T15:41:10Z INFO virtiofsd] Client connected, servicing requests80alice # [2026-10-13T15:41:10Z INFO virtiofsd] Client connected, servicing requests81bob # [2026-10-13T15:41:10Z INFO virtiofsd] Client connected, servicing requests82clare # Filesystem UUID: efb71bc4-ad16-42e7-b2b8-03f630a846cc83clare # Superblock backups stored on blocks:84clare # 32768, 98304, 163840, 22937685clare # 86clare # Allocating group tables: 0/8 done87clare # Writing inode tables: 0/8 done88clare # Creating journal (8192 blocks): done89clare # Writing superblocks and filesystem accounting information: 0/8 done90clare # 91clare # Virtualisation disk image created.92clare # Starting virtiofs daemons...93clare # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)94clare # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether95clare # [2026-10-13T15:41:10Z INFO virtiofsd] Waiting for vhost-user socket connection...96clare # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)97clare # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether98clare # [2026-10-13T15:41:10Z INFO virtiofsd] Waiting for vhost-user socket connection...99clare # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)100clare # [2026-10-13T15:41:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether101clare # [2026-10-13T15:41:10Z INFO virtiofsd] Waiting for vhost-user socket connection...102clare # [2026-10-13T15:41:10Z INFO virtiofsd] Client connected, servicing requests103clare # [2026-10-13T15:41:10Z INFO virtiofsd] Client connected, servicing requests104clare # [2026-10-13T15:41:10Z INFO virtiofsd] Client connected, servicing requests105clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]106clare # [ 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 2026107clare # [ 0.000000] KASLR enabled108clare # [ 0.000000] random: crng init done109clare # [ 0.000000] Machine model: linux,dummy-virt110clare # [ 0.000000] efi: UEFI not found.111clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT112clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]113clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]114clare # [ 0.000000] Zone ranges:115clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]116clare # [ 0.000000] DMA32 empty117clare # [ 0.000000] Normal empty118clare # [ 0.000000] Device empty119clare # [ 0.000000] Movable zone start for each node120clare # [ 0.000000] Early memory node ranges121clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]122clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]123clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000124clare # [ 0.000000] psci: probing for conduit method from DT.125clare # [ 0.000000] psci: PSCIv1.3 detected in firmware.126clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs127clare # [ 0.000000] psci: Trusted OS migration not required128clare # [ 0.000000] psci: SMC Calling Convention v1.1129clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)130alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]131clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u311296132clare # [ 0.000000] Detected PIPT I-cache on CPU0133alice # [ 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 2026134clare # [ 0.000000] CPU features: detected: GICv3 CPU interface135alice # [ 0.000000] KASLR enabled136clare # [ 0.000000] CPU features: detected: Spectre-v4137alice # [ 0.000000] random: crng init done138clare # [ 0.000000] CPU features: detected: Spectre-BHB139alice # [ 0.000000] Machine model: linux,dummy-virt140alice # [ 0.000000] efi: UEFI not found.141clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR142alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT143clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)144alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]145clare # [ 0.000000] CPU features: detected: ARM erratum 1418040146alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]147clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion148alice # [ 0.000000] Zone ranges:149clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing150alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]151alice # [ 0.000000] DMA32 empty152clare # [ 0.000000] alternatives: applying boot alternatives153alice # [ 0.000000] Normal empty154bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]155alice # [ 0.000000] Device empty156alice # [ 0.000000] Movable zone start for each node157alice # [ 0.000000] Early memory node ranges158bob # [ 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 2026159bob # [ 0.000000] KASLR enabled160alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]161bob # [ 0.000000] random: crng init done162bob # [ 0.000000] Machine model: linux,dummy-virt163alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]164bob # [ 0.000000] efi: UEFI not found.165clare # [ 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/wv719ask1gnl2x4bid3r2qbvmadrzhvn-nixos-system-clare-test/init regInfo=/nix/.ro-store/1qz7kfznidcj4hv5vgvv29pzfi887awx-closure-info/registration console=ttyAMA0,115200n8 console=tty0166alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000167bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT168alice # [ 0.000000] psci: probing for conduit method from DT.169alice # [ 0.000000] psci: PSCIv1.3 detected in firmware.170bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]171clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/1qz7kfznidcj4hv5vgvv29pzfi887awx-closure-info/registration", will be passed to user space.172alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs173bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]174alice # [ 0.000000] psci: Trusted OS migration not required175clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes176bob # [ 0.000000] Zone ranges:177alice # [ 0.000000] psci: SMC Calling Convention v1.1178bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]179bob # [ 0.000000] DMA32 empty180clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)181bob # [ 0.000000] Normal empty182alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)183bob # [ 0.000000] Device empty184clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)185bob # [ 0.000000] Movable zone start for each node186alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u311296187bob # [ 0.000000] Early memory node ranges188clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB189alice # [ 0.000000] Detected PIPT I-cache on CPU0190clare # [ 0.000000] software IO TLB: area num 1.191alice # [ 0.000000] CPU features: detected: GICv3 CPU interface192bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]193alice # [ 0.000000] CPU features: detected: Spectre-v4194clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)195bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]196alice # [ 0.000000] CPU features: detected: Spectre-BHB197clare # [ 0.000000] Fallback order for Node 0: 0198bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000199alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR200clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144201bob # [ 0.000000] psci: probing for conduit method from DT.202clare # [ 0.000000] Policy zone: DMA203alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)204bob # [ 0.000000] psci: PSCIv1.3 detected in firmware.205clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off206bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs207alice # [ 0.000000] CPU features: detected: ARM erratum 1418040208bob # [ 0.000000] psci: Trusted OS migration not required209clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1210alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion211bob # [ 0.000000] psci: SMC Calling Convention v1.1212clare # [ 0.000000] allocated 2097152 bytes of page_ext213alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing214clare # [ 0.000000] ftrace: allocating 75039 entries in 294 pages215alice # [ 0.000000] alternatives: applying boot alternatives216bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)217clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups218clare # [ 0.000000] rcu: Hierarchical RCU implementation.219bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186840 r8192 d116264 u311296220bob # [ 0.000000] Detected PIPT I-cache on CPU0221clare # [ 0.000000] rcu: RCU event tracing is enabled.222bob # [ 0.000000] CPU features: detected: GICv3 CPU interface223clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.224bob # [ 0.000000] CPU features: detected: Spectre-v4225clare # [ 0.000000] Trampoline variant of Tasks RCU enabled.226bob # [ 0.000000] CPU features: detected: Spectre-BHB227clare # [ 0.000000] Rude variant of Tasks RCU enabled.228bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR229clare # [ 0.000000] Tracing variant of Tasks RCU enabled.230alice # [ 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/4j3663pi8glzya4gqg9yiznbj392dxjl-nixos-system-alice-test/init regInfo=/nix/.ro-store/mwq85s3vd6i52i7x6mm6cn2c8m8psxhp-closure-info/registration console=ttyAMA0,115200n8 console=tty0231bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)232clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.233bob # [ 0.000000] CPU features: detected: ARM erratum 1418040234clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1235alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/mwq85s3vd6i52i7x6mm6cn2c8m8psxhp-closure-info/registration", will be passed to user space.236bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion237clare # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.238alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes239bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing240bob # [ 0.000000] alternatives: applying boot alternatives241alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)242clare # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.243alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)244clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.245alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB246clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0247alice # [ 0.000000] software IO TLB: area num 1.248clare # [ 0.000000] GICv3: 256 SPIs implemented249clare # [ 0.000000] GICv3: 0 Extended SPIs implemented250alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)251clare # [ 0.000000] Root IRQ handler: gic_handle_irq252bob # [ 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/vqvs2qvk1gadrr955gg6703kgn8cjw7p-nixos-system-bob-test/init regInfo=/nix/.ro-store/lrg15r21h8hj5ccl44qbwk95l44cf65b-closure-info/registration console=ttyAMA0,115200n8 console=tty0253alice # [ 0.000000] Fallback order for Node 0: 0254clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI255alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144256clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0257alice # [ 0.000000] Policy zone: DMA258bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/lrg15r21h8hj5ccl44qbwk95l44cf65b-closure-info/registration", will be passed to user space.259clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000260alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off261clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]262bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes263alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1264alice # [ 0.000000] allocated 2097152 bytes of page_ext265bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)266clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)267alice # [ 0.000000] ftrace: allocating 75039 entries in 294 pages268bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)269alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups270clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)271bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB272alice # [ 0.000000] rcu: Hierarchical RCU implementation.273clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000274bob # [ 0.000000] software IO TLB: area num 1.275alice # [ 0.000000] rcu: RCU event tracing is enabled.276clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000277bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)278alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.279bob # [ 0.000000] Fallback order for Node 0: 0280alice # [ 0.000000] Trampoline variant of Tasks RCU enabled.281clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.282alice # [ 0.000000] Rude variant of Tasks RCU enabled.283bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144284bob # [ 0.000000] Policy zone: DMA285alice # [ 0.000000] Tracing variant of Tasks RCU enabled.286clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns287bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off288clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040289alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.290bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1291clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).292alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1293bob # [ 0.000000] allocated 2097152 bytes of page_ext294bob # [ 0.000000] ftrace: allocating 75039 entries in 294 pages295clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns296alice # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.297bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups298clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns299bob # [ 0.000000] rcu: Hierarchical RCU implementation.300alice # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.301clare # [ 0.000058] arm-pv: using stolen time PV302bob # [ 0.000000] rcu: RCU event tracing is enabled.303alice # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.304bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.305clare # [ 0.000377] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)306alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0307bob # [ 0.000000] Trampoline variant of Tasks RCU enabled.308clare # [ 0.000672] Console: colour dummy device 80x25309alice # [ 0.000000] GICv3: 256 SPIs implemented310bob # [ 0.000000] Rude variant of Tasks RCU enabled.311clare # [ 0.000684] printk: legacy console [tty0] enabled312alice # [ 0.000000] GICv3: 0 Extended SPIs implemented313bob # [ 0.000000] Tracing variant of Tasks RCU enabled.314alice # [ 0.000000] Root IRQ handler: gic_handle_irq315alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI316clare # [ 0.000905] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)317bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.318clare # [ 0.000910] pid_max: default: 32768 minimum: 301319alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0320bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1321clare # [ 0.000989] LSM: initializing lsm=capability,landlock,yama,bpf,ima322alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000323clare # [ 0.001131] landlock: Up and running.324bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.325alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]326clare # [ 0.001133] Yama: becoming mindful.327clare # [ 0.001507] LSM support for eBPF active328bob # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.329alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)330clare # [ 0.001705] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)331bob # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.332alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)333clare # [ 0.001726] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)334bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0335alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000336clare # [ 0.004538] rcu: Hierarchical SRCU implementation.337bob # [ 0.000000] GICv3: 256 SPIs implemented338bob # [ 0.000000] GICv3: 0 Extended SPIs implemented339clare # [ 0.004544] rcu: Max phase no-delay instances is 1000.340alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000341bob # [ 0.000000] Root IRQ handler: gic_handle_irq342clare # [ 0.005492] fsl-mc MSI: its@8080000 domain created343alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.344bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI345clare # [ 0.005685] EFI services will not be available.346bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0347clare # [ 0.005817] smp: Bringing up secondary CPUs ...348alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns349clare # [ 0.005848] smp: Brought up 1 node, 1 CPU350bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000351clare # [ 0.005852] SMP: Total of 1 processors activated.352alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040353bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]354clare # [ 0.005854] CPU: All CPU(s) started at EL1355alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).356clare # [ 0.005865] CPU features: detected: 32-bit EL0 Support357bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)358alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns359clare # [ 0.005868] CPU features: detected: Data cache clean to the PoU not required for I/D coherence360bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)361clare # [ 0.005871] CPU features: detected: Common not Private translations362alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns363bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000364clare # [ 0.005873] CPU features: detected: CRC32 instructions365alice # [ 0.000059] arm-pv: using stolen time PV366bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000367clare # [ 0.005875] CPU features: detected: Data cache clean to Point of Persistence368alice # [ 0.000337] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)369bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.370clare # [ 0.005878] CPU features: detected: Enhanced Virtualization Traps371alice # [ 0.000648] Console: colour dummy device 80x25372alice # [ 0.000659] printk: legacy console [tty0] enabled373bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns374clare # [ 0.005888] CPU features: detected: RCpc load-acquire (LDAPR)375clare # [ 0.005890] CPU features: detected: LSE atomic instructions376bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040377alice # [ 0.000864] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)378clare # [ 0.005892] CPU features: detected: Privileged Access Never379bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).380alice # [ 0.000869] pid_max: default: 32768 minimum: 301381clare # [ 0.005894] CPU features: detected: PMUv3382alice # [ 0.000973] LSM: initializing lsm=capability,landlock,yama,bpf,ima383clare # [ 0.005895] CPU features: detected: RAS Extension Support384bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns385alice # [ 0.001113] landlock: Up and running.386alice # [ 0.001115] Yama: becoming mindful.387clare # [ 0.005899] CPU features: detected: Speculative Store Bypassing Safe (SSBS)388bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns389alice # [ 0.001460] LSM support for eBPF active390clare # [ 0.005919] alternatives: applying system-wide alternatives391bob # [ 0.000050] arm-pv: using stolen time PV392alice # [ 0.001621] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)393clare # [ 0.007307] CPU features: detected: Hardware dirty bit management on CPU0394bob # [ 0.000328] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)395alice # [ 0.001642] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)396bob # [ 0.000612] Console: colour dummy device 80x25397alice # [ 0.004423] rcu: Hierarchical SRCU implementation.398bob # [ 0.000624] printk: legacy console [tty0] enabled399clare # [ 0.007536] Memory: 891156K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116132K reserved, 32768K cma-reserved)400alice # [ 0.004429] rcu: Max phase no-delay instances is 1000.401clare # [ 0.007988] devtmpfs: initialized402alice # [ 0.005386] fsl-mc MSI: its@8080000 domain created403bob # [ 0.000835] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)404clare # [ 0.009475] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)405alice # [ 0.005590] EFI services will not be available.406bob # [ 0.000840] pid_max: default: 32768 minimum: 301407alice # [ 0.005676] smp: Bringing up secondary CPUs ...408clare # [ 0.009498] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).409alice # [ 0.005704] smp: Brought up 1 node, 1 CPU410bob # [ 0.000924] LSM: initializing lsm=capability,landlock,yama,bpf,ima411bob # [ 0.001072] landlock: Up and running.412alice # [ 0.005709] SMP: Total of 1 processors activated.413clare # [ 0.009623] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL414bob # [ 0.001074] Yama: becoming mindful.415alice # [ 0.005711] CPU: All CPU(s) started at EL1416bob # [ 0.001521] LSM support for eBPF active417clare # [ 0.009629] 0 pages in range for non-PLT usage418alice # [ 0.005725] CPU features: detected: 32-bit EL0 Support419clare # [ 0.009629] 508192 pages in range for PLT usage420bob # [ 0.001716] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)421clare # [ 0.009731] pinctrl core: initialized pinctrl subsystem422alice # [ 0.005728] CPU features: detected: Data cache clean to the PoU not required for I/D coherence423clare # [ 0.010525] DMI not present or invalid.424bob # [ 0.001736] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)425alice # [ 0.005731] CPU features: detected: Common not Private translations426clare # [ 0.013987] NET: Registered PF_NETLINK/PF_ROUTE protocol family427bob # [ 0.004472] rcu: Hierarchical SRCU implementation.428alice # [ 0.005733] CPU features: detected: CRC32 instructions429bob # [ 0.004478] rcu: Max phase no-delay instances is 1000.430clare # [ 0.016642] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations431bob # [ 0.005437] fsl-mc MSI: its@8080000 domain created432alice # [ 0.005735] CPU features: detected: Data cache clean to Point of Persistence433clare # [ 0.016772] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations434bob # [ 0.005649] EFI services will not be available.435alice # [ 0.005738] CPU features: detected: Enhanced Virtualization Traps436bob # [ 0.005739] smp: Bringing up secondary CPUs ...437clare # [ 0.016919] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations438alice # [ 0.005748] CPU features: detected: RCpc load-acquire (LDAPR)439bob # [ 0.005770] smp: Brought up 1 node, 1 CPU440clare # [ 0.016935] audit: initializing netlink subsys (disabled)441alice # [ 0.005750] CPU features: detected: LSE atomic instructions442bob # [ 0.005774] SMP: Total of 1 processors activated.443bob # [ 0.005776] CPU: All CPU(s) started at EL1444clare # [ 0.017601] thermal_sys: Registered thermal governor 'fair_share'445alice # [ 0.005752] CPU features: detected: Privileged Access Never446bob # [ 0.005788] CPU features: detected: 32-bit EL0 Support447alice # [ 0.005754] CPU features: detected: PMUv3448clare # [ 0.017604] thermal_sys: Registered thermal governor 'bang_bang'449alice # [ 0.005755] CPU features: detected: RAS Extension Support450clare # [ 0.017607] thermal_sys: Registered thermal governor 'step_wise'451bob # [ 0.005791] CPU features: detected: Data cache clean to the PoU not required for I/D coherence452alice # [ 0.005760] CPU features: detected: Speculative Store Bypassing Safe (SSBS)453clare # [ 0.017609] thermal_sys: Registered thermal governor 'user_space'454bob # [ 0.005794] CPU features: detected: Common not Private translations455alice # [ 0.005780] alternatives: applying system-wide alternatives456clare # [ 0.017610] thermal_sys: Registered thermal governor 'power_allocator'457bob # [ 0.005796] CPU features: detected: CRC32 instructions458alice # [ 0.007313] CPU features: detected: Hardware dirty bit management on CPU0459bob # [ 0.005798] CPU features: detected: Data cache clean to Point of Persistence460clare # [ 0.017661] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1461clare # [ 0.017669] cpuidle: using governor ladder462bob # [ 0.005801] CPU features: detected: Enhanced Virtualization Traps463clare # [ 0.017674] cpuidle: using governor menu464bob # [ 0.005810] CPU features: detected: RCpc load-acquire (LDAPR)465alice # [ 0.007517] Memory: 891184K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116128K reserved, 32768K cma-reserved)466alice # [ 0.008032] devtmpfs: initialized467bob # [ 0.005813] CPU features: detected: LSE atomic instructions468clare # [ 0.017801] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.469clare # [ 0.017826] ASID allocator initialised with 32768 entries470bob # [ 0.005814] CPU features: detected: Privileged Access Never471alice # [ 0.009535] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)472clare # [ 0.018489] Serial: AMBA PL011 UART driver473bob # [ 0.005816] CPU features: detected: PMUv3474alice # [ 0.009558] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).475bob # [ 0.005818] CPU features: detected: RAS Extension Support476clare # [ 0.021961] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1477alice # [ 0.009693] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL478clare # [ 0.022132] printk: console [ttyAMA0] enabled479bob # [ 0.005822] CPU features: detected: Speculative Store Bypassing Safe (SSBS)480alice # [ 0.009698] 0 pages in range for non-PLT usage481bob # [ 0.005840] alternatives: applying system-wide alternatives482alice # [ 0.009699] 508192 pages in range for PLT usage483bob # [ 0.007201] CPU features: detected: Hardware dirty bit management on CPU0484alice # [ 0.009801] pinctrl core: initialized pinctrl subsystem485alice # [ 0.010576] DMI not present or invalid.486alice # [ 0.013878] NET: Registered PF_NETLINK/PF_ROUTE protocol family487bob # [ 0.007420] Memory: 891168K/1048576K available (24576K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116112K reserved, 32768K cma-reserved)488bob # [ 0.007849] devtmpfs: initialized489alice # [ 0.016612] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations490bob # [ 0.009302] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)491alice # [ 0.016743] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations492bob # [ 0.009325] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).493alice # [ 0.016891] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations494alice # [ 0.016907] audit: initializing netlink subsys (disabled)495bob # [ 0.009452] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL496bob # [ 0.009457] 0 pages in range for non-PLT usage497alice # [ 0.017488] thermal_sys: Registered thermal governor 'fair_share'498bob # [ 0.009458] 508192 pages in range for PLT usage499alice # [ 0.017490] thermal_sys: Registered thermal governor 'bang_bang'500bob # [ 0.009575] pinctrl core: initialized pinctrl subsystem501bob # [ 0.010330] DMI not present or invalid.502alice # [ 0.017493] thermal_sys: Registered thermal governor 'step_wise'503bob # [ 0.013601] NET: Registered PF_NETLINK/PF_ROUTE protocol family504alice # [ 0.017495] thermal_sys: Registered thermal governor 'user_space'505alice # [ 0.017497] thermal_sys: Registered thermal governor 'power_allocator'506bob # [ 0.016237] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations507alice # [ 0.017536] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1508bob # [ 0.016362] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations509alice # [ 0.017545] cpuidle: using governor ladder510bob # [ 0.016507] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations511alice # [ 0.017549] cpuidle: using governor menu512bob # [ 0.016523] audit: initializing netlink subsys (disabled)513alice # [ 0.017668] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.514bob # [ 0.017096] thermal_sys: Registered thermal governor 'fair_share'515alice # [ 0.017689] ASID allocator initialised with 32768 entries516alice # [ 0.018349] Serial: AMBA PL011 UART driver517bob # [ 0.017098] thermal_sys: Registered thermal governor 'bang_bang'518bob # [ 0.017101] thermal_sys: Registered thermal governor 'step_wise'519alice # [ 0.021865] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1520bob # [ 0.017103] thermal_sys: Registered thermal governor 'user_space'521alice # [ 0.022049] printk: console [ttyAMA0] enabled522bob # [ 0.017105] thermal_sys: Registered thermal governor 'power_allocator'523clare # [ 0.092419] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages524bob # [ 0.017142] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1525clare # [ 0.092432] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page526bob # [ 0.017151] cpuidle: using governor ladder527clare # [ 0.092434] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages528bob # [ 0.017156] cpuidle: using governor menu529clare # [ 0.092439] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page530bob # [ 0.017268] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.531bob # [ 0.017287] ASID allocator initialised with 32768 entries532clare # [ 0.092441] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages533bob # [ 0.018010] Serial: AMBA PL011 UART driver534clare # [ 0.092443] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page535clare # [ 0.092445] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages536bob # [ 0.021659] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1537bob # [ 0.021829] printk: console [ttyAMA0] enabled538clare # [ 0.092447] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page539clare # [ 0.093421] fbcon: Taking over console540clare # [ 0.093432] ACPI: Interpreter disabled.541clare # [ 0.096089] iommu: Default domain type: Translated542clare # [ 0.096094] iommu: DMA domain TLB invalidation policy: strict mode543clare # [ 0.096410] SCSI subsystem initialized544clare # [ 0.102313] usbcore: registered new interface driver usbfs545clare # [ 0.102350] usbcore: registered new interface driver hub546clare # [ 0.102360] usbcore: registered new device driver usb547clare # [ 0.102591] pps_core: LinuxPPS API ver. 1 registered548clare # [ 0.102595] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>549clare # [ 0.102599] PTP clock support registered550clare # [ 0.102632] EDAC MC: Ver: 3.0.0551clare # [ 0.102803] scmi_core: SCMI protocol bus registered552clare # [ 0.103087] FPGA manager framework553alice # [ 0.094588] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages554clare # [ 0.107895] vgaarb: loaded555alice # [ 0.094601] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page556clare # [ 0.108101] clocksource: Switched to clocksource arch_sys_counter557alice # [ 0.094604] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages558alice # [ 0.094608] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page559alice # [ 0.094610] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages560alice # [ 0.094612] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page561alice # [ 0.094614] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages562alice # [ 0.094616] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page563alice # [ 0.095584] fbcon: Taking over console564bob # [ 0.093484] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages565alice # [ 0.095594] ACPI: Interpreter disabled.566bob # [ 0.093497] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page567alice # [ 0.096249] iommu: Default domain type: Translated568alice # [ 0.096254] iommu: DMA domain TLB invalidation policy: strict mode569bob # [ 0.093500] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages570alice # [ 0.096556] SCSI subsystem initialized571bob # [ 0.093504] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page572alice # [ 0.096773] usbcore: registered new interface driver usbfs573bob # [ 0.093506] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages574alice # [ 0.096808] usbcore: registered new interface driver hub575alice # [ 0.096817] usbcore: registered new device driver usb576bob # [ 0.093508] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page577alice # [ 0.097025] pps_core: LinuxPPS API ver. 1 registered578bob # [ 0.093510] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages579bob # [ 0.093512] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page580alice # [ 0.097028] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>581bob # [ 0.094499] fbcon: Taking over console582alice # [ 0.097032] PTP clock support registered583bob # [ 0.094509] ACPI: Interpreter disabled.584alice # [ 0.097070] EDAC MC: Ver: 3.0.0585bob # [ 0.095174] iommu: Default domain type: Translated586bob # [ 0.095178] iommu: DMA domain TLB invalidation policy: strict mode587bob # [ 0.095476] SCSI subsystem initialized588bob # [ 0.095688] usbcore: registered new interface driver usbfs589bob # [ 0.095719] usbcore: registered new interface driver hub590bob # [ 0.095728] usbcore: registered new device driver usb591bob # [ 0.095955] pps_core: LinuxPPS API ver. 1 registered592bob # [ 0.095959] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>593bob # [ 0.095963] PTP clock support registered594bob # [ 0.095996] EDAC MC: Ver: 3.0.0595alice # [ 0.106794] scmi_core: SCMI protocol bus registered596bob # [ 0.105673] scmi_core: SCMI protocol bus registered597alice # [ 0.107112] FPGA manager framework598alice # [ 0.110715] vgaarb: loaded599clare # [ 0.120969] VFS: Disk quotas dquot_6.6.0600bob # [ 0.106410] FPGA manager framework601bob # [ 0.109626] vgaarb: loaded602clare # [ 0.120994] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)603alice # [ 0.111770] clocksource: Switched to clocksource arch_sys_counter604clare # [ 0.122349] netfs: FS-Cache loaded605clare # [ 0.122457] pnp: PnP ACPI: disabled606clare # [ 0.125694] NET: Registered PF_INET protocol family607bob # [ 0.110306] clocksource: Switched to clocksource arch_sys_counter608clare # [ 0.125875] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)609alice # [ 0.120755] VFS: Disk quotas dquot_6.6.0610alice # [ 0.120781] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)611alice # [ 0.122191] netfs: FS-Cache loaded612alice # [ 0.122301] pnp: PnP ACPI: disabled613bob # [ 0.119999] VFS: Disk quotas dquot_6.6.0614bob # [ 0.120024] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)615alice # [ 0.125606] NET: Registered PF_INET protocol family616bob # [ 0.121502] netfs: FS-Cache loaded617bob # [ 0.121616] pnp: PnP ACPI: disabled618alice # [ 0.125793] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)619bob # [ 0.124886] NET: Registered PF_INET protocol family620bob # [ 0.125061] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)621clare # [ 0.150723] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)622clare # [ 0.150745] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)623clare # [ 0.150757] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)624clare # [ 0.150791] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)625clare # [ 0.150857] TCP: Hash tables configured (established 8192 bind 8192)626clare # [ 0.150952] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)627clare # [ 0.150979] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)628clare # [ 0.151032] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)629clare # [ 0.151136] NET: Registered PF_UNIX/PF_LOCAL protocol family630clare # [ 0.151196] NET: Registered PF_XDP protocol family631clare # [ 0.151217] PCI: CLS 0 bytes, default 64632clare # [ 0.151411] Trying to unpack rootfs image as initramfs...633clare # [ 0.164149] kvm [1]: HYP mode not available634alice # [ 0.150937] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)635alice # [ 0.150961] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)636alice # [ 0.150973] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)637alice # [ 0.151008] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)638alice # [ 0.151075] TCP: Hash tables configured (established 8192 bind 8192)639alice # [ 0.151184] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)640alice # [ 0.151242] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)641alice # [ 0.151295] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)642alice # [ 0.151402] NET: Registered PF_UNIX/PF_LOCAL protocol family643alice # [ 0.151430] NET: Registered PF_XDP protocol family644alice # [ 0.151451] PCI: CLS 0 bytes, default 64645alice # [ 0.151642] Trying to unpack rootfs image as initramfs...646bob # [ 0.150483] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)647alice # [ 0.166206] kvm [1]: HYP mode not available648bob # [ 0.150506] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)649bob # [ 0.150520] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)650bob # [ 0.150555] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)651bob # [ 0.150621] TCP: Hash tables configured (established 8192 bind 8192)652bob # [ 0.150716] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)653bob # [ 0.150742] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)654bob # [ 0.150797] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)655bob # [ 0.150901] NET: Registered PF_UNIX/PF_LOCAL protocol family656bob # [ 0.150978] NET: Registered PF_XDP protocol family657bob # [ 0.150998] PCI: CLS 0 bytes, default 64658bob # [ 0.151216] Trying to unpack rootfs image as initramfs...659bob # [ 0.154418] kvm [1]: HYP mode not available660clare # [ 0.228590] Initialise system trusted keyrings661clare # [ 0.228742] workingset: timestamp_bits=42 max_order=18 bucket_order=0662clare # [ 0.229082] squashfs: version 4.0 (2009/01/31) Phillip Lougher663clare # [ 0.229116] 9p: Installing v9fs 9p2000 file system support664alice # [ 0.228207] Initialise system trusted keyrings665alice # [ 0.228342] workingset: timestamp_bits=42 max_order=18 bucket_order=0666alice # [ 0.228669] squashfs: version 4.0 (2009/01/31) Phillip Lougher667alice # [ 0.228704] 9p: Installing v9fs 9p2000 file system support668clare # [ 0.247930] Key type asymmetric registered669clare # [ 0.247946] Asymmetric key parser 'x509' registered670clare # [ 0.248007] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)671bob # [ 0.231761] Initialise system trusted keyrings672bob # [ 0.231915] workingset: timestamp_bits=42 max_order=18 bucket_order=0673bob # [ 0.232245] squashfs: version 4.0 (2009/01/31) Phillip Lougher674bob # [ 0.232278] 9p: Installing v9fs 9p2000 file system support675alice # [ 0.247488] Key type asymmetric registered676alice # [ 0.247505] Asymmetric key parser 'x509' registered677alice # [ 0.247573] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)678clare # [ 0.256162] io scheduler mq-deadline registered679clare # [ 0.256171] io scheduler kyber registered680clare # [ 0.258564] pl061_gpio 9030000.pl061: PL061 GPIO chip registered681clare # [ 0.258898] ledtrig-cpu: registered to indicate activity on CPUs682clare # [ 0.259222] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:683clare # [ 0.259238] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000684clare # [ 0.259250] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000685clare # [ 0.259255] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000686clare # [ 0.259279] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits687clare # [ 0.259299] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]688clare # [ 0.259365] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00689clare # [ 0.259386] pci_bus 0000:00: root bus resource [bus 00-ff]690clare # [ 0.259389] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]691clare # [ 0.259392] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]692clare # [ 0.259394] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]693clare # [ 0.259467] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint694bob # [ 0.252817] Key type asymmetric registered695clare # [ 0.259912] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint696bob # [ 0.252834] Asymmetric key parser 'x509' registered697clare # [ 0.260037] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]698alice # [ 0.255820] io scheduler mq-deadline registered699alice # [ 0.255830] io scheduler kyber registered700clare # [ 0.260047] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]701bob # [ 0.252899] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)702alice # [ 0.258332] pl061_gpio 9030000.pl061: PL061 GPIO chip registered703bob # [ 0.252991] io scheduler mq-deadline registered704clare # [ 0.260065] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]705bob # [ 0.252994] io scheduler kyber registered706alice # [ 0.258684] ledtrig-cpu: registered to indicate activity on CPUs707clare # [ 0.260074] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]708alice # [ 0.259011] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:709alice # [ 0.259027] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000710alice # [ 0.259040] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000711alice # [ 0.259044] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000712alice # [ 0.259071] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits713alice # [ 0.259090] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]714alice # [ 0.259158] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00715alice # [ 0.259178] pci_bus 0000:00: root bus resource [bus 00-ff]716alice # [ 0.259181] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]717alice # [ 0.259184] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]718alice # [ 0.259186] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]719alice # [ 0.259258] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint720alice # [ 0.259703] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint721clare # [ 0.276482] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint722clare # [ 0.276611] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]723bob # [ 0.263606] pl061_gpio 9030000.pl061: PL061 GPIO chip registered724clare # [ 0.276621] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]725bob # [ 0.263933] ledtrig-cpu: registered to indicate activity on CPUs726clare # [ 0.276640] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]727bob # [ 0.264255] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:728clare # [ 0.276954] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint729clare # [ 0.277077] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]730bob # [ 0.264271] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000731clare # [ 0.277086] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]732bob # [ 0.264285] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000733clare # [ 0.277104] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]734bob # [ 0.264289] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000735clare # [ 0.277411] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint736bob # [ 0.264315] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits737clare # [ 0.277536] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]738clare # [ 0.277545] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]739bob # [ 0.264335] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]740clare # [ 0.277563] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]741bob # [ 0.264411] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00742clare # [ 0.277571] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]743bob # [ 0.264431] pci_bus 0000:00: root bus resource [bus 00-ff]744clare # [ 0.277885] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint745bob # [ 0.264435] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]746clare # [ 0.278010] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]747bob # [ 0.264437] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]748clare # [ 0.278027] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]749bob # [ 0.264440] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]750clare # [ 0.278340] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint751bob # [ 0.264550] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint752clare # [ 0.278464] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]753bob # [ 0.264994] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint754clare # [ 0.278482] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]755alice # [ 0.275906] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]756bob # [ 0.265117] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]757alice # [ 0.275920] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]758clare # [ 0.278744] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint759bob # [ 0.265126] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]760clare # [ 0.278864] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]761alice # [ 0.275940] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]762bob # [ 0.265144] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]763bob # [ 0.265152] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]764alice # [ 0.275949] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]765clare # [ 0.279075] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint766clare # [ 0.279199] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]767alice # [ 0.276309] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint768bob # [ 0.265474] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint769bob # [ 0.265595] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]770alice # [ 0.276437] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]771clare # [ 0.279216] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]772alice # [ 0.276446] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]773bob # [ 0.265604] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]774clare # [ 0.279516] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint775alice # [ 0.276464] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]776bob # [ 0.265622] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]777clare # [ 0.279645] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]778alice # [ 0.276783] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint779bob # [ 0.265921] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint780clare # [ 0.279663] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]781alice # [ 0.276908] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]782bob # [ 0.266043] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]783clare # [ 0.279964] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint784alice # [ 0.276917] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]785bob # [ 0.266052] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]786clare # [ 0.280089] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]787alice # [ 0.276935] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]788bob # [ 0.266071] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]789alice # [ 0.277245] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint790alice # [ 0.277371] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]791clare # [ 0.295024] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]792alice # [ 0.277380] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]793alice # [ 0.277398] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]794clare # [ 0.295397] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint795clare # [ 0.295620] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]796alice # [ 0.277407] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]797clare # [ 0.295629] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]798alice # [ 0.277723] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint799clare # [ 0.295647] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]800alice # [ 0.277850] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]801clare # [ 0.295968] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint802alice # [ 0.277868] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]803clare # [ 0.296092] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]804alice # [ 0.278185] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint805alice # [ 0.278324] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]806alice # [ 0.278342] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]807alice # [ 0.278618] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint808alice # [ 0.278741] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]809alice # [ 0.278925] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint810alice # [ 0.279050] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]811alice # [ 0.279068] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]812alice # [ 0.279375] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint813alice # [ 0.279508] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]814alice # [ 0.279526] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]815bob # [ 0.286445] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint816bob # [ 0.286577] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]817alice # [ 0.295673] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint818bob # [ 0.286586] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]819bob # [ 0.286604] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]820bob # [ 0.286612] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]821bob # [ 0.286940] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint822bob # [ 0.287066] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]823bob # [ 0.287084] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]824bob # [ 0.287400] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint825alice # [ 0.296443] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]826bob # [ 0.287526] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]827alice # [ 0.296462] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]828bob # [ 0.287544] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]829alice # [ 0.296806] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint830bob # [ 0.287807] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint831alice # [ 0.297014] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]832bob # [ 0.287927] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]833alice # [ 0.297024] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]834bob # [ 0.288139] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint835alice # [ 0.297041] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]836clare # [ 0.308165] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]837bob # [ 0.288263] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]838clare # [ 0.308210] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]839alice # [ 0.297357] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint840bob # [ 0.288280] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]841alice # [ 0.297480] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]842clare # [ 0.308656] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned843alice # [ 0.297489] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]844bob # [ 0.288580] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint845clare # [ 0.308663] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned846alice # [ 0.297507] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]847bob # [ 0.288708] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]848clare # [ 0.308666] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned849alice # [ 0.297925] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned850bob # [ 0.288725] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]851clare # [ 0.308701] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned852alice # [ 0.297932] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned853bob # [ 0.289032] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint854clare # [ 0.308731] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned855bob # [ 0.289157] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]856alice # [ 0.297935] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned857bob # [ 0.289174] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]858clare # [ 0.308761] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned859alice # [ 0.297969] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned860bob # [ 0.289510] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint861clare # [ 0.308792] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned862alice # [ 0.297999] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned863bob # [ 0.289723] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]864clare # [ 0.308821] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned865alice # [ 0.298029] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned866bob # [ 0.289733] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]867clare # [ 0.308852] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned868alice # [ 0.298060] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned869bob # [ 0.289751] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]870clare # [ 0.308881] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned871bob # [ 0.290061] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint872alice # [ 0.298089] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned873bob # [ 0.290185] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]874clare # [ 0.308911] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned875alice # [ 0.298119] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned876bob # [ 0.290193] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]877clare # [ 0.308941] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned878alice # [ 0.298148] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned879bob # [ 0.290212] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]880clare # [ 0.309019] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned881alice # [ 0.298177] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned882clare # [ 0.309049] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned883alice # [ 0.298206] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned884bob # [ 0.305968] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned885clare # [ 0.309061] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned886alice # [ 0.298291] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned887bob # [ 0.305976] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned888clare # [ 0.309073] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned889alice # [ 0.298480] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned890clare # [ 0.309086] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned891bob # [ 0.305980] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned892alice # [ 0.298522] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned893clare # [ 0.309098] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned894bob # [ 0.306014] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned895clare # [ 0.309111] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned896alice # [ 0.298556] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned897bob # [ 0.306044] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned898alice # [ 0.298589] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned899clare # [ 0.309123] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned900bob # [ 0.306074] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned901alice # [ 0.298623] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned902clare # [ 0.309136] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned903bob # [ 0.306105] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned904alice # [ 0.298875] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned905clare # [ 0.309147] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned906alice # [ 0.298929] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned907bob # [ 0.306135] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned908clare # [ 0.309159] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned909alice # [ 0.298953] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned910bob # [ 0.306164] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned911clare # [ 0.309171] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned912alice # [ 0.298965] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned913clare # [ 0.309183] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned914bob # [ 0.306194] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned915alice # [ 0.298978] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned916clare # [ 0.309195] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned917bob # [ 0.306223] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned918alice # [ 0.298990] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned919clare # [ 0.309207] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned920bob # [ 0.306252] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned921alice # [ 0.299002] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned922clare # [ 0.309219] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned923alice # [ 0.299014] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned924clare # [ 0.309231] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned925alice # [ 0.299026] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned926clare # [ 0.309242] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned927alice # [ 0.299038] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned928clare # [ 0.309254] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned929bob # [ 0.313185] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned930clare # [ 0.309270] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]931alice # [ 0.299050] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned932bob # [ 0.313223] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned933clare # [ 0.309274] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]934alice # [ 0.299061] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned935bob # [ 0.313236] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned936clare # [ 0.309281] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]937alice # [ 0.299073] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned938bob # [ 0.313248] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned939clare # [ 0.309808] pci 0000:00:07.0: enabling device (0000 -> 0002)940alice # [ 0.299090] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]941bob # [ 0.313260] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned942alice # [ 0.299094] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]943bob # [ 0.313272] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned944alice # [ 0.299103] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]945bob # [ 0.313285] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned946alice # [ 0.299632] pci 0000:00:07.0: enabling device (0000 -> 0002)947bob # [ 0.313298] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned948bob # [ 0.313310] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned949bob # [ 0.313323] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned950bob # [ 0.313335] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned951bob # [ 0.313367] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned952bob # [ 0.313380] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned953bob # [ 0.313392] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned954bob # [ 0.313404] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned955clare # [ 0.335647] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)956bob # [ 0.313415] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned957bob # [ 0.313427] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned958bob # [ 0.313438] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned959bob # [ 0.313450] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned960bob # [ 0.313466] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]961bob # [ 0.313470] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]962bob # [ 0.313480] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]963bob # [ 0.314005] pci 0000:00:07.0: enabling device (0000 -> 0002)964alice # [ 0.327900] pci 0000:00:07.0: quirk_usb_early_handoff+0x0/0xa60 took 27615 usecs965clare # [ 0.345290] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)966clare # [ 0.347053] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)967clare # [ 0.349941] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)968clare # [ 0.351686] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)969bob # [ 0.336944] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)970alice # [ 0.342350] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)971alice # [ 0.344506] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)972alice # [ 0.345928] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)973alice # [ 0.347268] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)974clare # [ 0.361733] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)975clare # [ 0.363332] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)976bob # [ 0.346550] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)977bob # [ 0.348672] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)978clare # [ 0.366056] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)979clare # [ 0.367766] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)980alice # [ 0.357044] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)981alice # [ 0.359022] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)982alice # [ 0.361883] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)983alice # [ 0.363500] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)984bob # [ 0.358856] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)985bob # [ 0.361016] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)986clare # [ 0.377328] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)987bob # [ 0.364156] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)988clare # [ 0.379752] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)989bob # [ 0.365555] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)990alice # [ 0.372899] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)991alice # [ 0.374645] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)992alice # [ 0.377825] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)993bob # [ 0.374774] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)994bob # [ 0.376526] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)995bob # [ 0.378252] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)996clare # [ 0.392147] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled997clare # [ 0.393152] msm_serial: driver initialized998clare # [ 0.393222] SuperH (H)SCI(F) driver initialized999clare # [ 0.393263] STM32 USART driver initialized1000alice # [ 0.389419] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled1001alice # [ 0.390395] msm_serial: driver initialized1002alice # [ 0.390467] SuperH (H)SCI(F) driver initialized1003alice # [ 0.390509] STM32 USART driver initialized1004bob # [ 0.388480] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)1005bob # [ 0.398624] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled1006bob # [ 0.399637] msm_serial: driver initialized1007bob # [ 0.399719] SuperH (H)SCI(F) driver initialized1008bob # [ 0.399764] STM32 USART driver initialized1009clare # [ 0.418414] loop: module loaded1010clare # [ 0.418557] virtio_blk virtio2: 1/0/0 default/read/poll queues1011clare # [ 0.419189] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1012alice # [ 0.414124] loop: module loaded1013alice # [ 0.414252] virtio_blk virtio2: 1/0/0 default/read/poll queues1014alice # [ 0.414928] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1015clare # [ 0.424543] megasas: 07.734.00.00-rc11016clare # [ 0.425032] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1017clare # [ 0.427206] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001018clare # [ 0.427239] Intel/Sharp Extended Query Table at 0x00311019alice # [ 0.420178] megasas: 07.734.00.00-rc11020alice # [ 0.420642] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1021clare # [ 0.436668] Using buffer write method1022clare # [ 0.436722] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1023bob # [ 0.423208] loop: module loaded1024bob # [ 0.423344] virtio_blk virtio2: 1/0/0 default/read/poll queues1025clare # [ 0.439370] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001026clare # [ 0.439420] Intel/Sharp Extended Query Table at 0x00311027bob # [ 0.423994] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1028clare # [ 0.443114] Using buffer write method1029clare # [ 0.443150] Concatenating MTD devices:1030clare # [ 0.443152] (0): "0.flash"1031clare # [ 0.443154] (1): "0.flash"1032clare # [ 0.443155] into device "0.flash"1033bob # [ 0.427977] megasas: 07.734.00.00-rc11034bob # [ 0.428515] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1035alice # [ 0.432258] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001036alice # [ 0.432309] Intel/Sharp Extended Query Table at 0x00311037alice # [ 0.434055] Using buffer write method1038alice # [ 0.434109] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1039alice # [ 0.441713] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001040alice # [ 0.441725] Intel/Sharp Extended Query Table at 0x00311041alice # [ 0.443322] Using buffer write method1042alice # [ 0.443338] Concatenating MTD devices:1043alice # [ 0.443340] (0): "0.flash"1044alice # [ 0.443342] (1): "0.flash"1045alice # [ 0.443343] into device "0.flash"1046bob # [ 0.438883] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001047bob # [ 0.438912] Intel/Sharp Extended Query Table at 0x00311048bob # [ 0.440603] Using buffer write method1049bob # [ 0.440652] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1050bob # [ 0.445330] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001051bob # [ 0.445350] Intel/Sharp Extended Query Table at 0x00311052bob # [ 0.448048] Using buffer write method1053bob # [ 0.448059] Concatenating MTD devices:1054bob # [ 0.448061] (0): "0.flash"1055bob # [ 0.448062] (1): "0.flash"1056bob # [ 0.448064] into device "0.flash"1057clare # [ 0.666281] Freeing initrd memory: 26948K1058clare # [ 0.673075] tun: Universal TUN/TAP device driver, 1.61059clare # [ 0.675330] thunder_xcv, ver 1.01060clare # [ 0.675346] thunder_bgx, ver 1.01061clare # [ 0.675355] nicpf, ver 1.01062bob # [ 0.660384] Freeing initrd memory: 26928K1063clare # [ 0.675684] e1000: Intel(R) PRO/1000 Network Driver1064clare # [ 0.675688] e1000: Copyright (c) 1999-2006 Intel Corporation.1065clare # [ 0.675701] e1000e: Intel(R) PRO/1000 Network Driver1066clare # [ 0.675706] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1067clare # [ 0.675717] igb: Intel(R) Gigabit Ethernet Network Driver1068clare # [ 0.675719] igb: Copyright (c) 2007-2014 Intel Corporation.1069clare # [ 0.675729] igbvf: Intel(R) Gigabit Virtual Function Network Driver1070clare # [ 0.675734] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1071clare # [ 0.675801] sky2: driver version 1.301072clare # [ 0.681832] usbcore: registered new interface driver usb-storage1073clare # [ 0.681875] usbcore: registered new interface driver usbserial_generic1074bob # [ 0.667059] tun: Universal TUN/TAP device driver, 1.61075clare # [ 0.681881] usbserial: USB Serial support registered for generic1076bob # [ 0.669322] thunder_xcv, ver 1.01077bob # [ 0.669355] thunder_bgx, ver 1.01078alice # [ 0.674218] Freeing initrd memory: 26944K1079clare # [ 0.682190] hv_vmbus: registering driver hyperv_keyboard1080bob # [ 0.669365] nicpf, ver 1.01081clare # [ 0.683338] ehci-pci 0000:00:07.0: EHCI Host Controller1082bob # [ 0.669685] e1000: Intel(R) PRO/1000 Network Driver1083clare # [ 0.683365] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 11084bob # [ 0.669688] e1000: Copyright (c) 1999-2006 Intel Corporation.1085bob # [ 0.669700] e1000e: Intel(R) PRO/1000 Network Driver1086clare # [ 0.683620] ehci-pci 0000:00:07.0: irq 16, io mem 0x100860001087clare # [ 0.683792] rtc-pl031 9010000.pl031: registered as rtc01088bob # [ 0.669705] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1089bob # [ 0.669718] igb: Intel(R) Gigabit Ethernet Network Driver1090clare # [ 0.683818] rtc-pl031 9010000.pl031: setting system clock to 2026-10-13T15:41:11 UTC (1791906071)1091bob # [ 0.669720] igb: Copyright (c) 2007-2014 Intel Corporation.1092clare # [ 0.683988] i2c_dev: i2c /dev entries driver1093bob # [ 0.669729] igbvf: Intel(R) Gigabit Virtual Function Network Driver1094bob # [ 0.669734] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1095bob # [ 0.669798] sky2: driver version 1.301096clare # [ 0.690392] sdhci: Secure Digital Host Controller Interface driver1097clare # [ 0.690397] sdhci: Copyright(c) Pierre Ossman1098alice # [ 0.680971] tun: Universal TUN/TAP device driver, 1.61099clare # [ 0.690551] Synopsys Designware Multimedia Card Interface Driver1100alice # [ 0.683174] thunder_xcv, ver 1.01101alice # [ 0.683194] thunder_bgx, ver 1.01102clare # [ 0.690732] sdhci-pltfm: SDHCI platform and OF driver helper1103alice # [ 0.683203] nicpf, ver 1.01104clare # [ 0.691608] hid: raw HID events driver (C) Jiri Kosina1105alice # [ 0.683527] e1000: Intel(R) PRO/1000 Network Driver1106clare # [ 0.691742] usbcore: registered new interface driver usbhid1107alice # [ 0.683531] e1000: Copyright (c) 1999-2006 Intel Corporation.1108clare # [ 0.691745] usbhid: USB HID core driver1109alice # [ 0.683545] e1000e: Intel(R) PRO/1000 Network Driver1110clare # [ 0.692163] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.001111alice # [ 0.683550] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1112clare # [ 0.692565] hub 1-0:1.0: USB hub found1113clare # [ 0.692584] hub 1-0:1.0: 6 ports detected1114alice # [ 0.683561] igb: Intel(R) Gigabit Ethernet Network Driver1115alice # [ 0.683563] igb: Copyright (c) 2007-2014 Intel Corporation.1116alice # [ 0.683575] igbvf: Intel(R) Gigabit Virtual Function Network Driver1117alice # [ 0.683577] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1118alice # [ 0.683643] sky2: driver version 1.301119alice # [ 0.689475] usbcore: registered new interface driver usb-storage1120alice # [ 0.689580] usbcore: registered new interface driver usbserial_generic1121alice # [ 0.689586] usbserial: USB Serial support registered for generic1122alice # [ 0.689901] hv_vmbus: registering driver hyperv_keyboard1123alice # [ 0.691169] ehci-pci 0000:00:07.0: EHCI Host Controller1124alice # [ 0.691195] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 11125alice # [ 0.691446] ehci-pci 0000:00:07.0: irq 16, io mem 0x100860001126alice # [ 0.691640] rtc-pl031 9010000.pl031: registered as rtc01127alice # [ 0.691665] rtc-pl031 9010000.pl031: setting system clock to 2026-10-13T15:41:11 UTC (1791906071)1128alice # [ 0.691881] i2c_dev: i2c /dev entries driver1129alice # [ 0.698511] sdhci: Secure Digital Host Controller Interface driver1130alice # [ 0.698517] sdhci: Copyright(c) Pierre Ossman1131alice # [ 0.698672] Synopsys Designware Multimedia Card Interface Driver1132alice # [ 0.698858] sdhci-pltfm: SDHCI platform and OF driver helper1133alice # [ 0.699742] hid: raw HID events driver (C) Jiri Kosina1134alice # [ 0.699822] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.001135alice # [ 0.700174] hub 1-0:1.0: USB hub found1136alice # [ 0.700206] hub 1-0:1.0: 6 ports detected1137alice # [ 0.703869] usbcore: registered new interface driver usbhid1138bob # [ 0.675604] usbcore: registered new interface driver usb-storage1139alice # [ 0.703874] usbhid: USB HID core driver1140bob # [ 0.675646] usbcore: registered new interface driver usbserial_generic1141bob # [ 0.675652] usbserial: USB Serial support registered for generic1142alice # [ 0.731482] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1143bob # [ 0.675964] hv_vmbus: registering driver hyperv_keyboard1144bob # [ 0.677043] ehci-pci 0000:00:07.0: EHCI Host Controller1145bob # [ 0.677071] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 11146bob # [ 0.677320] ehci-pci 0000:00:07.0: irq 16, io mem 0x100860001147bob # [ 0.677498] rtc-pl031 9010000.pl031: registered as rtc01148bob # [ 0.677526] rtc-pl031 9010000.pl031: setting system clock to 2026-10-13T15:41:11 UTC (1791906071)1149clare # [ 0.740368] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1150bob # [ 0.677690] i2c_dev: i2c /dev entries driver1151clare # [ 0.741222] drop_monitor: Initializing network drop monitor service1152bob # [ 0.679270] sdhci: Secure Digital Host Controller Interface driver1153clare # [ 0.741381] NET: Registered PF_INET6 protocol family1154bob # [ 0.679275] sdhci: Copyright(c) Pierre Ossman1155clare # [ 0.742157] Segment Routing with IPv61156alice # [ 0.733133] drop_monitor: Initializing network drop monitor service1157clare # [ 0.742175] In-situ OAM (IOAM) with IPv61158bob # [ 0.679437] Synopsys Designware Multimedia Card Interface Driver1159alice # [ 0.733285] NET: Registered PF_INET6 protocol family1160clare # [ 0.742230] NET: Registered PF_PACKET protocol family1161alice # [ 0.733904] Segment Routing with IPv61162bob # [ 0.679622] sdhci-pltfm: SDHCI platform and OF driver helper1163clare # [ 0.742295] 9pnet: Installing 9P2000 support1164alice # [ 0.733921] In-situ OAM (IOAM) with IPv61165bob # [ 0.680493] hid: raw HID events driver (C) Jiri Kosina1166clare # [ 0.742325] Key type dns_resolver registered1167alice # [ 0.733975] NET: Registered PF_PACKET protocol family1168bob # [ 0.680625] usbcore: registered new interface driver usbhid1169alice # [ 0.734035] 9pnet: Installing 9P2000 support1170bob # [ 0.680627] usbhid: USB HID core driver1171alice # [ 0.734064] Key type dns_resolver registered1172bob # [ 0.725458] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.001173alice # [ 0.739330] registered taskstats version 11174alice # [ 0.739486] Loading compiled-in X.509 certificates1175bob # [ 0.733553] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1176bob # [ 0.734490] drop_monitor: Initializing network drop monitor service1177bob # [ 0.734597] NET: Registered PF_INET6 protocol family1178bob # [ 0.735260] hub 1-0:1.0: USB hub found1179bob # [ 0.735279] hub 1-0:1.0: 6 ports detected1180clare # [ 0.748382] registered taskstats version 11181clare # [ 0.748531] Loading compiled-in X.509 certificates1182bob # [ 0.738166] Segment Routing with IPv61183bob # [ 0.738193] In-situ OAM (IOAM) with IPv61184clare # [ 0.753873] Demotion targets for Node 0: null1185bob # [ 0.738248] NET: Registered PF_PACKET protocol family1186clare # [ 0.753946] Key type .fscrypt registered1187bob # [ 0.738326] 9pnet: Installing 9P2000 support1188clare # [ 0.753952] Key type fscrypt-provisioning registered1189bob # [ 0.738356] Key type dns_resolver registered1190clare # [ 0.754055] ima: No TPM chip found, activating TPM-bypass!1191clare # [ 0.754064] ima: Allocated hash algorithm: sha11192alice # [ 0.744916] Demotion targets for Node 0: null1193clare # [ 0.754118] ima: No architecture policies found1194alice # [ 0.745003] Key type .fscrypt registered1195alice # [ 0.745006] Key type fscrypt-provisioning registered1196clare # [ 0.754545] input: gpio-keys as /devices/platform/gpio-keys/input/input01197alice # [ 0.745110] ima: No TPM chip found, activating TPM-bypass!1198alice # [ 0.745120] ima: Allocated hash algorithm: sha11199alice # [ 0.745175] ima: No architecture policies found1200alice # [ 0.745608] input: gpio-keys as /devices/platform/gpio-keys/input/input01201bob # [ 0.742826] registered taskstats version 11202bob # [ 0.743009] Loading compiled-in X.509 certificates1203bob # [ 0.748444] Demotion targets for Node 0: null1204bob # [ 0.748523] Key type .fscrypt registered1205bob # [ 0.748529] Key type fscrypt-provisioning registered1206bob # [ 0.748629] ima: No TPM chip found, activating TPM-bypass!1207bob # [ 0.748639] ima: Allocated hash algorithm: sha11208bob # [ 0.748696] ima: No architecture policies found1209bob # [ 0.749143] input: gpio-keys as /devices/platform/gpio-keys/input/input01210clare # [ 0.766726] clk: Disabling unused clocks1211clare # [ 0.766735] PM: genpd: Disabling unused power domains1212alice # [ 0.757882] clk: Disabling unused clocks1213alice # [ 0.757891] PM: genpd: Disabling unused power domains1214clare # [ 0.769754] Freeing unused kernel memory: 4736K1215clare # [ 0.769831] Run /init as init process1216alice # [ 0.760902] Freeing unused kernel memory: 4736K1217alice # [ 0.760991] Run /init as init process1218bob # [ 0.761541] clk: Disabling unused clocks1219bob # [ 0.761551] PM: genpd: Disabling unused power domains1220bob # [ 0.764531] Freeing unused kernel memory: 4736K1221bob # [ 0.764634] Run /init as init process1222clare # [ 0.790657] systemd[1]: Successfully made /usr/ read-only.1223alice # [ 0.784361] systemd[1]: Successfully made /usr/ read-only.1224bob # [ 0.784696] systemd[1]: Successfully made /usr/ read-only.1225alice # [ 0.951836] usb 1-1: new high-speed USB device number 2 using ehci-pci1226clare # [ 0.964211] usb 1-1: new high-speed USB device number 2 using ehci-pci1227bob # [ 0.982357] usb 1-1: new high-speed USB device number 2 using ehci-pci1228alice # [ 1.102031] 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/input11229clare # [ 1.114383] 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/input11230clare # [ 1.124730] 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)1231clare # [ 1.124759] systemd[1]: Detected virtualization qemu.1232alice # [ 1.118443] 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)1233alice # [ 1.118472] systemd[1]: Detected virtualization qemu.1234clare # [ 1.124796] systemd[1]: Detected architecture arm64.1235alice # [ 1.118511] systemd[1]: Detected architecture arm64.1236clare # [ 1.124805] systemd[1]: Running in initrd.1237alice # [ 1.118520] systemd[1]: Running in initrd.1238clare # [ 1.125606] systemd[1]: Initializing machine ID from random generator.1239alice # [ 1.119308] systemd[1]: Initializing machine ID from random generator.1240clare # [ 1.125873] systemd[1]: Hostname set to <clare>.1241alice # [ 1.119572] systemd[1]: Hostname set to <alice>.1242bob # [ 1.118774] 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)1243bob # [ 1.118804] systemd[1]: Detected virtualization qemu.1244bob # [ 1.118843] systemd[1]: Detected architecture arm64.1245bob # [ 1.118852] systemd[1]: Running in initrd.1246bob # [ 1.119629] systemd[1]: Initializing machine ID from random generator.1247bob # [ 1.119891] systemd[1]: Hostname set to <bob>.1248bob # [ 1.148970] 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/input11249alice # [ 1.189927] 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.200460] 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.206259] systemd[1]: bpf-restrict-fs: LSM BPF program attached1252bob # [ 1.238479] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input01253alice # [ 1.252775] systemd[1]: Queued start job for default target Initrd Default Target.1254clare # [ 1.266886] systemd[1]: bpf-restrict-fs: LSM BPF program attached1255alice # [ 1.257890] systemd[1]: Created slice Slice /system/modprobe.1256alice # [ 1.258035] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1257alice # [ 1.258068] systemd[1]: Expecting device /dev/disk/by-label/nixos...1258alice # [ 1.258088] systemd[1]: Reached target Path Units.1259alice # [ 1.258102] systemd[1]: Reached target Slice Units.1260alice # [ 1.258114] systemd[1]: Reached target Swaps.1261alice # [ 1.258127] systemd[1]: Reached target Timer Units.1262alice # [ 1.258263] systemd[1]: Listening on D-Bus System Message Bus Socket.1263bob # [ 1.258188] systemd[1]: bpf-restrict-fs: LSM BPF program attached1264alice # [ 1.258396] systemd[1]: Listening on Journal Socket (/dev/log).1265alice # [ 1.258516] systemd[1]: Listening on Journal Sockets.1266alice # [ 1.258588] systemd[1]: Listening on udev Control Socket.1267alice # [ 1.258631] systemd[1]: Listening on udev Kernel Socket.1268alice # [ 1.258645] systemd[1]: Reached target Socket Units.1269alice # [ 1.265554] systemd[1]: Starting Create List of Static Device Nodes...1270alice # [ 1.265623] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1271alice # [ 1.271900] systemd[1]: Mounting Kernel Configuration File System...1272alice # [ 1.276475] systemd[1]: Starting Journal Service...1273alice # [ 1.297153] systemd[1]: Starting Load Kernel Modules...1274alice # [ 1.297224] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1275clare # [ 1.319967] systemd[1]: Queued start job for default target Initrd Default Target.1276clare # [ 1.320187] usb 1-2: new high-speed USB device number 3 using ehci-pci1277alice # [ 1.307827] usb 1-2: new high-speed USB device number 3 using ehci-pci1278alice # [ 1.308473] systemd[1]: Starting Coldplug All udev Devices...1279alice # [ 1.313821] systemd-journald[71]: Collecting audit messages is disabled.1280bob # [ 1.312072] systemd[1]: Queued start job for default target Initrd Default Target.1281clare # [ 1.329645] systemd[1]: Created slice Slice /system/modprobe.1282clare # [ 1.329781] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1283clare # [ 1.329811] systemd[1]: Expecting device /dev/disk/by-label/nixos...1284clare # [ 1.329832] systemd[1]: Reached target Path Units.1285bob # [ 1.319774] systemd[1]: Created slice Slice /system/modprobe.1286clare # [ 1.329845] systemd[1]: Reached target Slice Units.1287clare # [ 1.329857] systemd[1]: Reached target Swaps.1288bob # [ 1.319908] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1289clare # [ 1.329869] systemd[1]: Reached target Timer Units.1290bob # [ 1.319937] systemd[1]: Expecting device /dev/disk/by-label/nixos...1291bob # [ 1.319953] systemd[1]: Reached target Path Units.1292clare # [ 1.330000] systemd[1]: Listening on D-Bus System Message Bus Socket.1293bob # [ 1.319964] systemd[1]: Reached target Slice Units.1294clare # [ 1.330109] systemd[1]: Listening on Journal Socket (/dev/log).1295bob # [ 1.319976] systemd[1]: Reached target Swaps.1296clare # [ 1.330190] systemd[1]: Listening on Journal Sockets.1297bob # [ 1.319987] systemd[1]: Reached target Timer Units.1298clare # [ 1.330254] systemd[1]: Listening on udev Control Socket.1299bob # [ 1.320121] systemd[1]: Listening on D-Bus System Message Bus Socket.1300clare # [ 1.330331] systemd[1]: Listening on udev Kernel Socket.1301alice # [ 1.323860] systemd[1]: Finished Create List of Static Device Nodes.1302clare # [ 1.330349] systemd[1]: Reached target Socket Units.1303bob # [ 1.320231] systemd[1]: Listening on Journal Socket (/dev/log).1304alice # [ 1.324563] systemd[1]: Mounted Kernel Configuration File System.1305bob # [ 1.320341] systemd[1]: Listening on Journal Sockets.1306clare # [ 1.331393] systemd[1]: Starting Create List of Static Device Nodes...1307alice # [ 1.328034] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1308clare # [ 1.331441] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1309bob # [ 1.320405] systemd[1]: Listening on udev Control Socket.1310bob # [ 1.320445] systemd[1]: Listening on udev Kernel Socket.1311bob # [ 1.320459] systemd[1]: Reached target Socket Units.1312bob # [ 1.321453] systemd[1]: Starting Create List of Static Device Nodes...1313bob # [ 1.321500] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1314bob # [ 1.332968] systemd[1]: Mounting Kernel Configuration File System...1315clare # [ 1.348247] systemd[1]: Mounting Kernel Configuration File System...1316bob # [ 1.338576] systemd[1]: Starting Journal Service...1317clare # [ 1.356232] systemd[1]: Starting Journal Service...1318clare # [ 1.363221] systemd[1]: Starting Load Kernel Modules...1319clare # [ 1.363288] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1320bob # [ 1.350447] systemd[1]: Starting Load Kernel Modules...1321bob # [ 1.350512] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1322bob # [ 1.358374] usb 1-2: new high-speed USB device number 3 using ehci-pci1323alice # [ 1.364179] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1324alice # [ 1.365244] systemd[1]: Starting Create Static Device Nodes in /dev...1325bob # [ 1.361111] systemd[1]: Starting Coldplug All udev Devices...1326alice # [ 1.372590] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1327clare # [ 1.388364] systemd[1]: Starting Coldplug All udev Devices...1328clare # [ 1.389301] systemd[1]: Finished Create List of Static Device Nodes.1329bob # [ 1.378393] systemd[1]: Finished Create List of Static Device Nodes.1330bob # [ 1.379135] systemd[1]: Mounted Kernel Configuration File System.1331alice # [ 1.383861] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1332bob # [ 1.380027] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1333clare # [ 1.400320] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1334clare # [ 1.400676] systemd[1]: Mounted Kernel Configuration File System.1335alice # [ 1.394715] systemd-modules-load[73]: Inserted module 'dm_mod'1336alice # [ 1.402097] systemd[1]: Started Journal Service.1337alice # [ 1.405408] [drm] pci: virtio-gpu-pci detected at 0000:00:05.01338alice # [ 1.405641] [drm] features: -virgl +edid -resource_blob -host_visible1339alice # [ 1.405643] [drm] features: -context_init1340alice # [ 1.406103] [drm] number of scanouts: 11341alice # [ 1.406112] [drm] number of cap sets: 01342alice # [ 1.402955] systemd-modules-load[73]: Module 'virtio_balloon' is built in1343alice # [ 1.403770] systemd-modules-load[73]: Module 'virtio_console' is built in1344bob # [ 1.400990] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1345clare # [ 1.420812] systemd-journald[71]: Collecting audit messages is disabled.1346clare # [ 1.424841] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1347alice # [ 1.411368] systemd[1]: Finished Create Static Device Nodes in /dev.1348clare # [ 1.424995] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1349bob # [ 1.410369] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1350bob # [ 1.418073] systemd-journald[71]: Collecting audit messages is disabled.1351alice # [ 1.420064] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic1352alice # [ 1.420075] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 01353alice # [ 1.417289] systemd[1]: Reached target Preparation for Local File Systems.1354alice # [ 1.418082] systemd[1]: Reached target Local File Systems.1355clare # [ 1.434582] [drm] pci: virtio-gpu-pci detected at 0000:00:05.01356alice # [ 1.418722] systemd[1]: Starting Create System Files and Directories...1357clare # [ 1.434795] [drm] features: -virgl +edid -resource_blob -host_visible1358clare # [ 1.434796] [drm] features: -context_init1359clare # [ 1.435375] [drm] number of scanouts: 11360clare # [ 1.435384] [drm] number of cap sets: 01361bob # [ 1.432745] [drm] pci: virtio-gpu-pci detected at 0000:00:05.01362bob # [ 1.432972] [drm] features: -virgl +edid -resource_blob -host_visible1363bob # [ 1.432975] [drm] features: -context_init1364bob # [ 1.433445] [drm] number of scanouts: 11365bob # [ 1.433453] [drm] number of cap sets: 01366bob # [ 1.434205] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1367clare # [ 1.448712] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1368alice # [ 1.437326] Console: switching to colour frame buffer device 160x501369clare # [ 1.449879] systemd[1]: Starting Create Static Device Nodes in /dev...1370bob # [ 1.437975] systemd[1]: Starting Create Static Device Nodes in /dev...1371alice # [ 1.441547] systemd[1]: Starting Rule-based Manager for Device Events and Files...1372clare # [ 1.456431] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic1373clare # [ 1.456441] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 01374bob # [ 1.446674] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic1375bob # [ 1.446684] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 01376alice # [ 1.456472] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device1377clare # [ 1.473753] Console: switching to colour frame buffer device 160x501378alice # [ 1.469140] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input21379alice # [ 1.469320] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01380alice # [ 1.468525] systemd-modules-load[73]: Inserted module 'virtio_gpu'1381bob # [ 1.466683] Console: switching to colour frame buffer device 160x501382alice # [ 1.472312] systemd-modules-load[73]: Module 'virtio_rng' is built in1383alice # [ 1.473204] systemd[1]: Finished Load Kernel Modules.1384alice # [ 1.473714] systemd[1]: Starting Apply Kernel Variables...1385clare # [ 1.493383] 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/input21386clare # [ 1.493457] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01387clare # [ 1.496038] systemd[1]: Started Journal Service.1388clare # [ 1.499141] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device1389bob # [ 1.484885] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device1390bob # [ 1.485411] systemd[1]: Finished Create Static Device Nodes in /dev.1391bob # [ 1.485592] systemd[1]: Reached target Preparation for Local File Systems.1392bob # [ 1.485624] systemd[1]: Reached target Local File Systems.1393clare # [ 1.496755] systemd-modules-load[72]: Inserted module 'dm_mod'1394bob # [ 1.489637] systemd[1]: Starting Rule-based Manager for Device Events and Files...1395clare # [ 1.500408] systemd-modules-load[72]: Module 'virtio_balloon' is built in1396clare # [ 1.501326] systemd-modules-load[72]: Module 'virtio_console' is built in1397clare # [ 1.502074] systemd[1]: Finished Create Static Device Nodes in /dev.1398alice # [ 1.492857] systemd[1]: Finished Create System Files and Directories.1399clare # [ 1.502866] systemd[1]: Reached target Preparation for Local File Systems.1400clare # [ 1.503541] systemd[1]: Reached target Local File Systems.1401bob # [ 1.495474] systemd-modules-load[72]: Inserted module 'dm_mod'1402clare # [ 1.512236] systemd[1]: Starting Create System Files and Directories...1403bob # [ 1.502596] systemd[1]: Finished Load Kernel Modules.1404bob # [ 1.502810] systemd[1]: Started Journal Service.1405bob # [ 1.501710] systemd-modules-load[72]: Module 'virtio_balloon' is built in1406alice # [ 1.506280] systemd[1]: Finished Apply Kernel Variables.1407clare # [ 1.516107] systemd-modules-load[72]: Inserted module 'virtio_gpu'1408clare # [ 1.516672] systemd-modules-load[72]: Module 'virtio_rng' is built in1409bob # [ 1.503637] systemd-modules-load[72]: Module 'virtio_console' is built in1410clare # [ 1.518164] systemd[1]: Starting Rule-based Manager for Device Events and Files...1411clare # [ 1.519959] systemd[1]: Finished Load Kernel Modules.1412bob # [ 1.506681] systemd-modules-load[72]: Inserted module 'virtio_gpu'1413alice # [ 1.511169] systemd-udevd[79]: Using default interface naming scheme 'v261'.1414bob # [ 1.511662] systemd-modules-load[72]: Module 'virtio_rng' is built in[ 1.516426] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input21415bob # [ 1.516628] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input01416bob # 1417bob # [ 1.520321] systemd[1]: Starting Apply Kernel Variables...1418bob # [ 1.521218] systemd[1]: Starting Create System Files and Directories...1419alice # [ 1.533004] systemd[1]: Started Rule-based Manager for Device Events and Files.1420clare # [ 1.548556] systemd[1]: Starting Apply Kernel Variables...1421clare # [ 1.554646] systemd[1]: Finished Create System Files and Directories.1422clare # [ 1.564737] systemd[1]: Finished Apply Kernel Variables.1423bob # [ 1.560628] systemd[1]: Finished Apply Kernel Variables.1424clare # [ 1.583286] systemd-udevd[80]: Using default interface naming scheme 'v261'.1425bob # [ 1.572945] systemd[1]: Finished Create System Files and Directories.1426bob # [ 1.574656] systemd-udevd[79]: Using default interface naming scheme 'v261'.1427alice # [ 1.582147] systemd[1]: Starting Virtual Console Setup...1428clare # [ 1.605175] systemd[1]: Started Rule-based Manager for Device Events and Files.1429bob # [ 1.597506] systemd[1]: Started Rule-based Manager for Device Events and Files.1430alice # [ 1.628353] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.1431alice # [ 1.630722] systemd[1]: Finished Virtual Console Setup.1432bob # [ 1.641572] systemd[1]: Starting Virtual Console Setup...1433clare # [ 1.655583] systemd[1]: Starting Virtual Console Setup...1434clare # [ 1.696325] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.1435clare # [ 1.698309] systemd[1]: Finished Virtual Console Setup.1436bob # [ 1.688353] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.1437bob # [ 1.690470] systemd[1]: Finished Virtual Console Setup.1438alice # [ 1.840586] systemd[1]: Finished Coldplug All udev Devices.1439alice # [ 1.841210] systemd[1]: Reached target System Initialization.1440alice # [ 1.841754] systemd[1]: Reached target Basic System.1441clare # [ 1.904902] systemd[1]: Finished Coldplug All udev Devices.1442clare # [ 1.905754] systemd[1]: Reached target System Initialization.1443clare # [ 1.908088] systemd[1]: Reached target Basic System.1444bob # [ 1.896357] systemd[1]: Finished Coldplug All udev Devices.1445bob # [ 1.896946] systemd[1]: Reached target System Initialization.1446bob # [ 1.897490] systemd[1]: Reached target Basic System.1447alice # [ 1.936555] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.1448alice # [ 1.950342] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.1449clare # [ 1.999777] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.1450bob # [ 1.993837] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.1451alice # [ 2.004804] systemd[1]: Found device /dev/disk/by-label/nixos.1452alice # [ 2.008078] systemd[1]: Reached target Initrd Root Device.1453alice # [ 2.009949] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1454bob # [ 2.012270] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.1455clare # [ 2.029058] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.1456alice # [ 2.054077] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks1457clare # [ 2.073632] systemd[1]: Found device /dev/disk/by-label/nixos.1458bob # [ 2.064875] systemd[1]: Found device /dev/disk/by-label/nixos.1459clare # [ 2.088651] systemd[1]: Reached target Initrd Root Device.1460bob # [ 2.078446] systemd[1]: Reached target Initrd Root Device.1461clare # [ 2.091631] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1462bob # [ 2.081294] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1463alice # [ 2.109231] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1464clare # [ 2.129810] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks1465bob # [ 2.121918] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks1466clare # [ 2.137239] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1467bob # [ 2.128707] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1468alice # [ 2.314272] systemd[1]: Mounting /sysroot...1469alice # [ 2.343140] systemd[1]: Mounted /sysroot.1470alice # [ 2.348107] EXT4-fs (vda): mounted filesystem 011a7ca5-9375-41bd-8c7e-00a713a29a2e r/w with ordered data mode. Quota mode: none.1471alice # [ 2.345616] systemd[1]: Reached target Initrd Root File System.1472alice # [ 2.349964] systemd[1]: Mounting /sysroot/nix/.ro-store...1473bob # [ 2.357788] systemd[1]: Mounting /sysroot...1474alice # [ 2.362226] systemd[1]: Mounting /sysroot/nix/.rw-store...1475alice # [ 2.366908] systemd[1]: Mounting /sysroot/run...1476clare # [ 2.381720] systemd[1]: Mounting /sysroot...1477alice # [ 2.387201] systemd[1]: Mounting /sysroot/tmp/shared...1478bob # [ 2.388824] EXT4-fs (vda): mounted filesystem e2358b9f-7e50-47e7-82c4-752cc1ceae86 r/w with ordered data mode. Quota mode: none.1479bob # [ 2.387069] systemd[1]: Mounted /sysroot.1480bob # [ 2.387525] systemd[1]: Reached target Initrd Root File System.1481bob # [ 2.391337] systemd[1]: Starting Mountpoints Configured in the Real Root...1482clare # [ 2.414971] EXT4-fs (vda): mounted filesystem efb71bc4-ad16-42e7-b2b8-03f630a846cc r/w with ordered data mode. Quota mode: none.1483clare # [ 2.411143] systemd[1]: Mounted /sysroot.1484clare # [ 2.412514] systemd[1]: Reached target Initrd Root File System.1485clare # [ 2.416194] systemd[1]: Starting Mountpoints Configured in the Real Root...1486bob # [ 2.405355] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.1487alice # [ 2.411241] systemd[1]: Mounting /sysroot/tmp/xchg...1488bob # [ 2.409139] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...1489bob # [ 2.412050] systemd[1]: Reloading...1490alice # [ 2.425364] fuse: init (API version 7.45)1491clare # [ 2.429827] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.1492clare # [ 2.434469] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...1493clare # [ 2.436045] systemd[1]: Reloading...1494alice # [ 2.434646] virtiofs virtio6: discovered new tag: nix-store1495alice # [ 2.435142] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1496alice # [ 2.438306] systemd[1]: Starting Mountpoints Configured in the Real Root...1497alice # [ 2.449203] virtiofs virtio7: discovered new tag: shared1498alice # [ 2.449720] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1499alice # [ 2.451535] virtiofs virtio8: discovered new tag: xchg1500alice # [ 2.447665] systemd[1]: Mounted /sysroot/nix/.rw-store.1501alice # [ 2.458701] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1502alice # [ 2.453720] systemd[1]: Mounted /sysroot/run.1503alice # [ 2.461366] systemd[1]: Starting rw-sysroot-nix-store.service...1504alice # [ 2.473490] systemd[1]: Mounted /sysroot/nix/.ro-store.1505alice # [ 2.479039] systemd[1]: Mounted /sysroot/tmp/shared.1506alice # [ 2.489661] systemd-sysroot-fstab-check[123]: /sysroot should be mounted in the initrd, will request daemon-reload.1507alice # [ 2.492402] systemd[1]: Mounted /sysroot/tmp/xchg.1508alice # [ 2.498423] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1509alice # [ 2.499822] systemd[1]: Finished rw-sysroot-nix-store.service.1510alice # [ 2.504662] systemd[1]: Reload requested from client PID 123 ('systemd-sysroot') (unit initrd-parse-etc.service)...1511alice # [ 2.506620] systemd[1]: Reloading...1512bob # [ 2.575143] systemd[1]: Reloading finished in 163 ms.1513clare # [ 2.602928] systemd[1]: Reloading finished in 165 ms.1514bob # [ 2.597419] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1515bob # [ 2.600851] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1516bob # [ 2.605416] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1517bob # [ 2.607055] systemd[1]: Finished Mountpoints Configured in the Real Root.1518bob # [ 2.608297] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1519clare # [ 2.631962] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1520clare # [ 2.634745] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1521clare # [ 2.636853] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1522clare # [ 2.638263] systemd[1]: Finished Mountpoints Configured in the Real Root.1523clare # [ 2.639769] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1524alice # [ 2.672371] systemd[1]: Reloading finished in 169 ms.1525alice # [ 2.700965] systemd-sysroot-fstab-check[123]: Requesting initrd-fs.target/start/replace...1526alice # [ 2.703555] systemd-sysroot-fstab-check[123]: Requesting swap.target/start/replace...1527alice # [ 2.706794] systemd[1]: Starting rw-sysroot-nix-store.service...1528alice # [ 2.711558] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1529alice # [ 2.715420] systemd[1]: Finished Mountpoints Configured in the Real Root.1530alice # [ 2.716200] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1531alice # [ 2.725428] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1532alice # [ 2.726122] systemd[1]: Finished rw-sysroot-nix-store.service.1533clare # [ 2.798447] fuse: init (API version 7.45)1534clare # [ 2.835370] virtiofs virtio6: discovered new tag: nix-store1535clare # [ 2.835856] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1536bob # [ 2.819461] fuse: init (API version 7.45)1537bob # [ 2.825601] virtiofs virtio6: discovered new tag: nix-store1538bob # [ 2.826080] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1539clare # [ 2.851647] virtiofs virtio7: discovered new tag: shared1540clare # [ 2.849125] (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 directory1541clare # [ 2.850582] (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 directory1542bob # [ 2.844962] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1543bob # [ 2.848623] (udev-worker)[97]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1544clare # [ 2.870667] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1545bob # [ 2.857296] virtiofs virtio7: discovered new tag: shared1546bob # [ 2.857802] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1547bob # [ 2.862209] virtiofs virtio8: discovered new tag: xchg1548clare # [ 2.877894] virtiofs virtio8: discovered new tag: xchg1549clare # [ 2.878367] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1550clare # [ 2.873576] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1551clare # [ 2.874490] systemd[1]: Stopped Virtual Console Setup.1552clare # [ 2.875838] systemd[1]: Stopping Virtual Console Setup...1553bob # [ 2.863480] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1554bob # [ 2.867466] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1555bob # [ 2.865687] systemd[1]: Stopped Virtual Console Setup.1556clare # [ 2.878730] systemd[1]: Starting Virtual Console Setup...1557bob # [ 2.866606] systemd[1]: Stopping Virtual Console Setup...1558bob # [ 2.868825] systemd[1]: Starting Virtual Console Setup...1559clare # [ 2.885416] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1560clare # [ 2.887103] systemd[1]: Stopped Virtual Console Setup.1561alice # [ 2.878977] (udev-worker)[89]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1562alice # [ 2.884306] (udev-worker)[89]: 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 directory1563clare # [ 2.896176] systemd[1]: Starting Virtual Console Setup...1564bob # [ 2.891247] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones.1565bob # [ 2.892912] systemd[1]: Finished Virtual Console Setup.1566clare # [ 2.914509] systemd-vconsole-setup[139]: Configuration of first virtual console was skipped, ignoring remaining ones.1567alice # [ 2.905692] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1568alice # [ 2.906502] systemd[1]: Stopped Virtual Console Setup.1569clare # [ 2.916762] systemd[1]: Finished Virtual Console Setup.1570alice # [ 2.907686] systemd[1]: Stopping Virtual Console Setup...1571alice # [ 2.908579] systemd[1]: Starting Virtual Console Setup...1572alice # [ 2.926769] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones.1573alice # [ 2.929106] systemd[1]: Finished Virtual Console Setup.1574alice # [ 3.316430] systemd[1]: Mounting /sysroot/nix/store...1575bob # [ 3.359992] systemd[1]: Mounting /sysroot/nix/.ro-store...1576alice # [ 3.368807] systemd[1]: Mounted /sysroot/nix/store.1577alice # [ 3.372154] systemd[1]: Reached target Initrd File Systems.1578alice # [ 3.373845] systemd[1]: Starting Find NixOS closure...1579clare # [ 3.385048] systemd[1]: Mounting /sysroot/nix/.ro-store...1580bob # [ 3.373697] systemd[1]: Mounting /sysroot/nix/.rw-store...1581bob # [ 3.375655] systemd[1]: Mounting /sysroot/run...1582alice # [ 3.380952] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1583clare # [ 3.396278] systemd[1]: Mounting /sysroot/nix/.rw-store...1584clare # [ 3.399586] systemd[1]: Mounting /sysroot/run...1585bob # [ 3.386818] systemd[1]: Mounting /sysroot/tmp/shared...1586clare # [ 3.410746] systemd[1]: Mounting /sysroot/tmp/shared...1587bob # [ 3.410496] systemd[1]: Mounting /sysroot/tmp/xchg...1588alice # [ 3.418595] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1589alice # [ 3.420065] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1590alice # [ 3.434473] systemd[1]: Finished Find NixOS closure.1591clare # [ 3.444643] systemd[1]: Mounting /sysroot/tmp/xchg...1592alice # [ 3.436441] systemd[1]: Reached target Initrd Default Target.1593alice # [ 3.438735] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1594bob # [ 3.436717] systemd[1]: Mounted /sysroot/nix/.rw-store.1595bob # [ 3.447426] systemd[1]: Starting rw-sysroot-nix-store.service...1596bob # [ 3.450110] systemd[1]: Mounted /sysroot/nix/.ro-store.1597clare # [ 3.464540] systemd[1]: Mounted /sysroot/nix/.ro-store.1598clare # [ 3.469176] systemd[1]: Mounted /sysroot/nix/.rw-store.1599clare # [ 3.471334] systemd[1]: Mounted /sysroot/run.1600alice # [ 3.466163] systemd[1]: Stopped target Initrd Default Target.1601clare # [ 3.476578] systemd[1]: Mounted /sysroot/tmp/shared.1602alice # [ 3.467963] systemd[1]: Stopped target Basic System.1603bob # [ 3.465919] systemd[1]: Mounted /sysroot/run.1604alice # [ 3.469987] systemd[1]: Stopped target Initrd Root Device.1605bob # [ 3.466817] systemd[1]: Mounted /sysroot/tmp/shared.1606bob # [ 3.467710] systemd[1]: Mounted /sysroot/tmp/xchg.1607alice # [ 3.472084] systemd[1]: Stopped target Path Units.1608bob # [ 3.469778] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1609alice # [ 3.473783] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1610bob # [ 3.471103] systemd[1]: Finished rw-sysroot-nix-store.service.1611clare # [ 3.483920] systemd[1]: Starting rw-sysroot-nix-store.service...1612alice # [ 3.476391] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1613alice # [ 3.479020] systemd[1]: Stopped target Slice Units.1614clare # [ 3.488831] systemd[1]: Mounted /sysroot/tmp/xchg.1615alice # [ 3.484148] systemd[1]: Stopped target Socket Units.1616alice # [ 3.485008] systemd[1]: Stopped target System Initialization.1617alice # [ 3.485904] systemd[1]: Stopped target Swaps.1618alice # [ 3.486879] systemd[1]: Stopped target Timer Units.1619alice # [ 3.488134] systemd[1]: dbus.socket: Deactivated successfully.1620clare # [ 3.499121] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1621alice # [ 3.490263] systemd[1]: Closed D-Bus System Message Bus Socket.1622clare # [ 3.500502] systemd[1]: Finished rw-sysroot-nix-store.service.1623alice # [ 3.492502] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1624alice # [ 3.496642] systemd[1]: Stopped Find NixOS closure.1625alice # [ 3.499227] systemd[1]: Starting rw-sysroot-nix-store.service...1626alice # [ 3.500523] systemd[1]: systemd-sysctl.service: Deactivated successfully.1627alice # [ 3.505474] systemd[1]: Stopped Apply Kernel Variables.1628alice # [ 3.506791] systemd[1]: systemd-modules-load.service: Deactivated successfully.1629alice # [ 3.515486] systemd[1]: Stopped Load Kernel Modules.1630alice # [ 3.516363] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1631alice # [ 3.517365] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1632alice # [ 3.518238] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1633alice # [ 3.519159] systemd[1]: Stopped Create System Files and Directories.1634alice # [ 3.519843] systemd[1]: Stopped target Local File Systems.1635alice # [ 3.523691] systemd[1]: Stopped target Preparation for Local File Systems.1636alice # [ 3.524688] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1637alice # [ 3.525484] systemd[1]: Stopped Coldplug All udev Devices.1638alice # [ 3.526102] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1639alice # [ 3.527078] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1640alice # [ 3.527832] systemd[1]: Stopped Virtual Console Setup.1641alice # [ 3.535314] systemd[1]: initrd-cleanup.service: Deactivated successfully.1642alice # [ 3.536089] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1643alice # [ 3.536767] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1644alice # [ 3.537484] systemd[1]: Finished rw-sysroot-nix-store.service.1645alice # [ 3.538086] systemd[1]: systemd-udevd.service: Deactivated successfully.1646alice # [ 3.538871] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1647alice # [ 3.539553] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1648alice # [ 3.540622] systemd[1]: Closed udev Control Socket.1649alice # [ 3.541112] systemd[1]: Starting Cleanup udev Database...1650alice # [ 3.541644] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1651alice # [ 3.542357] systemd[1]: Stopped Create Static Device Nodes in /dev.1652alice # [ 3.543065] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1653alice # [ 3.543803] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1654alice # [ 3.544594] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1655alice # [ 3.545228] systemd[1]: Stopped Create List of Static Device Nodes.1656alice # [ 3.564613] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1657alice # [ 3.566172] systemd[1]: Finished Cleanup udev Database.1658alice # [ 3.566752] systemd[1]: Reached target Switch Root.1659alice # [ 3.568735] systemd[1]: Starting NixOS Activation...1660alice # [ 3.640191] initrd-nixos-activation-start[175]: booting system configuration /nix/store/4j3663pi8glzya4gqg9yiznbj392dxjl-nixos-system-alice-test1661alice # [ 3.667379] initrd-nixos-activation-start[175]: running activation script...1662alice # [ 3.900867] initrd-nixos-activation-start[198]: setting up /etc...1663alice # [ 3.987467] initrd-nixos-activation-start[198]: setting up age secrets...1664alice # [ 4.047695] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1665alice # [ 4.048567] systemd[1]: Finished NixOS Activation.1666alice # [ 4.052085] systemd[1]: Starting Switch Root...1667alice # [ 4.068101] systemd[1]: Switching root.1668alice # [ 4.255852] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1669bob # [ 4.358357] systemd[1]: Mounting /sysroot/nix/store...1670clare # [ 4.383252] systemd[1]: Mounting /sysroot/nix/store...1671bob # [ 4.405202] systemd[1]: Mounted /sysroot/nix/store.1672bob # [ 4.408168] systemd[1]: Reached target Initrd File Systems.1673bob # [ 4.411400] systemd[1]: Starting Find NixOS closure...1674bob # [ 4.420255] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1675clare # [ 4.434634] systemd[1]: Mounted /sysroot/nix/store.1676clare # [ 4.438086] systemd[1]: Reached target Initrd File Systems.1677clare # [ 4.440597] systemd[1]: Starting Find NixOS closure...1678clare # [ 4.446061] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1679bob # [ 4.456125] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1680bob # [ 4.459674] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1681clare # [ 4.488079] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1682clare # [ 4.489531] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1683bob # [ 4.475669] systemd[1]: Finished Find NixOS closure.1684bob # [ 4.478760] systemd[1]: Reached target Initrd Default Target.1685bob # [ 4.484482] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1686clare # [ 4.506178] systemd[1]: Finished Find NixOS closure.1687clare # [ 4.508300] systemd[1]: Reached target Initrd Default Target.1688clare # [ 4.512327] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1689bob # [ 4.507604] systemd[1]: Stopped target Initrd Default Target.1690bob # [ 4.508953] systemd[1]: Stopped target Basic System.1691bob # [ 4.510101] systemd[1]: Stopped target Initrd Root Device.1692bob # [ 4.512265] systemd[1]: Stopped target Path Units.1693bob # [ 4.514108] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1694bob # [ 4.516451] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1695bob # [ 4.518737] systemd[1]: Stopped target Slice Units.1696bob # [ 4.520446] systemd[1]: Stopped target Socket Units.1697bob # [ 4.524610] systemd[1]: Stopped target System Initialization.1698bob # [ 4.525410] systemd[1]: Stopped target Swaps.1699bob # [ 4.526026] systemd[1]: Stopped target Timer Units.1700bob # [ 4.526699] systemd[1]: dbus.socket: Deactivated successfully.1701bob # [ 4.527498] systemd[1]: Closed D-Bus System Message Bus Socket.1702clare # [ 4.541850] systemd[1]: Stopped target Initrd Default Target.1703clare # [ 4.543426] systemd[1]: Stopped target Basic System.1704clare # [ 4.548465] systemd[1]: Stopped target Initrd Root Device.1705clare # [ 4.549338] systemd[1]: Stopped target Path Units.1706clare # [ 4.550116] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1707bob # [ 4.538494] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1708clare # [ 4.551277] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1709bob # [ 4.539544] systemd[1]: Stopped Find NixOS closure.1710bob # [ 4.540639] systemd[1]: Starting rw-sysroot-nix-store.service...1711clare # [ 4.554853] systemd[1]: Stopped target Slice Units.1712bob # [ 4.542331] systemd[1]: systemd-sysctl.service: Deactivated successfully.1713clare # [ 4.556596] systemd[1]: Stopped target Socket Units.1714bob # [ 4.544252] systemd[1]: Stopped Apply Kernel Variables.1715clare # [ 4.558386] systemd[1]: Stopped target System Initialization.1716bob # [ 4.546864] systemd[1]: systemd-modules-load.service: Deactivated successfully.1717clare # [ 4.561421] systemd[1]: Stopped target Swaps.1718clare # [ 4.564590] systemd[1]: Stopped target Timer Units.1719bob # [ 4.553934] systemd[1]: Stopped Load Kernel Modules.1720clare # [ 4.567030] systemd[1]: dbus.socket: Deactivated successfully.1721bob # [ 4.555592] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1722bob # [ 4.558333] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1723bob # [ 4.559347] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1724clare # [ 4.572968] systemd[1]: Closed D-Bus System Message Bus Socket.1725clare # [ 4.574498] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1726bob # [ 4.561920] systemd[1]: Stopped Create System Files and Directories.1727clare # [ 4.577254] systemd[1]: Stopped Find NixOS closure.1728bob # [ 4.564312] systemd[1]: Stopped target Local File Systems.1729clare # [ 4.577898] systemd[1]: Starting rw-sysroot-nix-store.service...1730bob # [ 4.564967] systemd[1]: Stopped target Preparation for Local File Systems.1731clare # [ 4.578581] systemd[1]: systemd-sysctl.service: Deactivated successfully.1732bob # [ 4.566298] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1733bob # [ 4.568276] systemd[1]: Stopped Coldplug All udev Devices.1734clare # [ 4.581572] systemd[1]: Stopped Apply Kernel Variables.1735clare # [ 4.582254] systemd[1]: systemd-modules-load.service: Deactivated successfully.1736bob # [ 4.570535] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1737clare # [ 4.583824] systemd[1]: Stopped Load Kernel Modules.1738bob # [ 4.571514] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1739bob # [ 4.572393] systemd[1]: Stopped Virtual Console Setup.1740bob # [ 4.572927] systemd[1]: initrd-cleanup.service: Deactivated successfully.1741bob # [ 4.573576] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1742clare # [ 4.586314] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1743bob # [ 4.574239] systemd[1]: systemd-udevd.service: Deactivated successfully.1744bob # [ 4.574879] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1745clare # [ 4.588308] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1746bob # [ 4.575684] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1747clare # [ 4.589401] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1748clare # [ 4.590269] systemd[1]: Stopped Create System Files and Directories.1749clare # [ 4.591007] systemd[1]: Stopped target Local File Systems.1750clare # [ 4.591671] systemd[1]: Stopped target Preparation for Local File Systems.1751bob # [ 4.579431] systemd[1]: Closed udev Control Socket.1752clare # [ 4.592615] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1753bob # [ 4.579936] systemd[1]: Starting Cleanup udev Database...1754clare # [ 4.593423] systemd[1]: Stopped Coldplug All udev Devices.1755clare # [ 4.594067] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1756clare # [ 4.594927] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1757clare # [ 4.595796] systemd[1]: Stopped Virtual Console Setup.1758bob # [ 4.584245] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1759bob # [ 4.584931] systemd[1]: Stopped Create Static Device Nodes in /dev.1760bob # [ 4.585483] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1761bob # [ 4.586181] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1762bob # [ 4.586808] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1763bob # [ 4.587434] systemd[1]: Stopped Create List of Static Device Nodes.1764clare # [ 4.604160] systemd[1]: initrd-cleanup.service: Deactivated successfully.1765clare # [ 4.604993] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1766bob # [ 4.587992] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1767bob # [ 4.592876] systemd[1]: Finished rw-sysroot-nix-store.service.1768clare # [ 4.606692] systemd[1]: systemd-udevd.service: Deactivated successfully.1769clare # [ 4.608254] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1770clare # [ 4.609854] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1771clare # [ 4.611919] systemd[1]: Closed udev Control Socket.1772clare # [ 4.613140] systemd[1]: Starting Cleanup udev Database...1773bob # [ 4.599768] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1774clare # [ 4.613924] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1775clare # [ 4.614730] systemd[1]: Stopped Create Static Device Nodes in /dev.1776clare # [ 4.615380] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1777clare # [ 4.616431] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1778bob # [ 4.604147] systemd[1]: Finished Cleanup udev Database.1779clare # [ 4.617148] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1780bob # [ 4.604631] systemd[1]: Reached target Switch Root.1781clare # [ 4.617827] systemd[1]: Stopped Create List of Static Device Nodes.1782bob # [ 4.605060] systemd[1]: Starting NixOS Activation...1783clare # [ 4.618438] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1784clare # [ 4.619118] systemd[1]: Finished rw-sysroot-nix-store.service.1785clare # [ 4.639166] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1786clare # [ 4.644688] systemd[1]: Finished Cleanup udev Database.1787clare # [ 4.645284] systemd[1]: Reached target Switch Root.1788clare # [ 4.645815] systemd[1]: Starting NixOS Activation...1789bob # [ 4.672313] initrd-nixos-activation-start[172]: booting system configuration /nix/store/vqvs2qvk1gadrr955gg6703kgn8cjw7p-nixos-system-bob-test1790bob # [ 4.697391] initrd-nixos-activation-start[172]: running activation script...1791clare # [ 4.713695] initrd-nixos-activation-start[175]: booting system configuration /nix/store/wv719ask1gnl2x4bid3r2qbvmadrzhvn-nixos-system-clare-test1792clare # [ 4.738801] initrd-nixos-activation-start[175]: running activation script...1793alice # [ 4.845466] 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)1794alice # [ 4.845559] systemd[1]: Detected virtualization qemu.1795alice # [ 4.845615] systemd[1]: Detected architecture arm64.1796alice # [ 4.845730] systemd[1]: Detected first boot.1797alice # [ 4.847694] systemd[1]: Initializing machine ID from random generator.1798bob # [ 4.906741] initrd-nixos-activation-start[195]: setting up /etc...1799clare # [ 4.968550] initrd-nixos-activation-start[198]: setting up /etc...1800alice # [ 4.993706] systemd[1]: bpf-restrict-fs: LSM BPF program attached1801bob # [ 4.998315] initrd-nixos-activation-start[195]: setting up age secrets...1802clare # [ 5.054312] initrd-nixos-activation-start[198]: setting up age secrets...1803bob # [ 5.071969] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1804bob # [ 5.073773] systemd[1]: Finished NixOS Activation.1805bob # [ 5.074529] systemd[1]: Starting Switch Root...1806alice # [ 5.093504] systemd[1]: Applying preset policy.1807bob # [ 5.087921] systemd[1]: Switching root.1808clare # [ 5.113347] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1809clare # [ 5.115045] systemd[1]: Finished NixOS Activation.1810clare # [ 5.115794] systemd[1]: Starting Switch Root...1811clare # [ 5.131679] systemd[1]: Switching root.1812alice # [ 5.261645] systemd[1]: Populated /etc with preset unit settings.1813bob # [ 5.254609] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1814clare # [ 5.312384] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1815alice # [ 5.474974] systemd[1]: initrd-switch-root.service: Deactivated successfully.1816alice # [ 5.475242] systemd[1]: Stopped initrd-switch-root.service.1817alice # [ 5.478220] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1818alice # [ 5.479607] systemd[1]: Created slice Slice /system/getty.1819alice # [ 5.482316] systemd[1]: Created slice User and Session Slice.1820alice # [ 5.482897] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1821alice # [ 5.483489] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1822alice # [ 5.486335] systemd[1]: Expecting device /dev/hvc0...1823alice # [ 5.486832] systemd[1]: Expecting device /dev/ttyAMA0...1824alice # [ 5.487419] systemd[1]: Reached target Local Encrypted Volumes.1825alice # [ 5.489820] systemd[1]: Stopped target initrd-fs.target.1826alice # [ 5.490331] systemd[1]: Stopped target initrd-root-fs.target.1827alice # [ 5.490884] systemd[1]: Stopped target initrd-switch-root.target.1828alice # [ 5.491448] systemd[1]: Reached target Virtual Machines and Containers.1829alice # [ 5.494394] systemd[1]: Reached target Path Units.1830alice # [ 5.494879] systemd[1]: Reached target Remote File Systems.1831alice # [ 5.495413] systemd[1]: Reached target Slice Units.1832alice # [ 5.497670] systemd[1]: Reached target Swaps.1833alice # [ 5.499543] systemd[1]: Listening on Query the User Interactively for a Password.1834alice # [ 5.502521] systemd[1]: Listening on Process Core Dump Socket.1835alice # [ 5.504562] systemd[1]: Listening on Credential Encryption/Decryption.1836alice # [ 5.506280] systemd[1]: Listening on Factory Reset Management.1837alice # [ 5.506822] systemd[1]: Listening on Hostname Service Socket.1838alice # [ 5.510622] systemd[1]: Starting Journal Log Access Socket...1839alice # [ 5.511704] systemd[1]: Listening on Journal Audit Socket.1840alice # [ 5.515275] systemd[1]: Listening on Console Output Muting Service Socket.1841alice # [ 5.517867] systemd[1]: Listening on Network Management Metrics Varlink Socket.1842alice # [ 5.519256] systemd[1]: Listening on Network Management Varlink Socket.1843alice # [ 5.521362] systemd[1]: Listening on Network Management Netlink Socket.1844alice # [ 5.521904] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1845alice # [ 5.522200] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1846alice # [ 5.522476] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1847alice # [ 5.528245] systemd[1]: Listening on Disk Repartitioning Service Socket.1848alice # [ 5.528782] systemd[1]: Listening on Resolve Monitor Varlink Socket.1849alice # [ 5.529313] systemd[1]: Listening on Resolve Service Varlink Socket.1850alice # [ 5.529853] systemd[1]: Listening on udev Control Socket.1851alice # [ 5.530405] systemd[1]: Listening on udev Varlink Socket.1852alice # [ 5.534828] systemd[1]: Mounting Huge Pages File System...1853alice # [ 5.537247] systemd[1]: Mounting POSIX Message Queue File System...1854alice # [ 5.547672] systemd[1]: Mounting Kernel Debug File System...1855alice # [ 5.553826] systemd[1]: Mounting Kernel Trace File System...1856alice # [ 5.559889] systemd[1]: Starting Create List of Static Device Nodes...1857alice # [ 5.560289] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1858alice # [ 5.567929] systemd[1]: Mounting Kernel Configuration File System...1859alice # [ 5.569328] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1860alice # [ 5.575722] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1861alice # [ 5.578712] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1862alice # [ 5.592064] systemd[1]: Mounting FUSE Control File System...1863alice # [ 5.592432] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671864alice # [ 5.613281] systemd[1]: Starting Journal Service...1865alice # [ 5.625755] systemd[1]: Starting Load Kernel Modules...1866alice # [ 5.648508] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1867alice # [ 5.657034] systemd[1]: Starting Remount Root and Kernel File Systems...1868alice # [ 5.657403] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1869alice # [ 5.661723] systemd[1]: Starting Coldplug All udev Devices...1870alice # [ 5.663498] systemd[1]: Listening on Journal Log Access Socket.1871alice # [ 5.669241] systemd[1]: Mounted Huge Pages File System.1872alice # [ 5.669713] systemd[1]: Mounted POSIX Message Queue File System.1873alice # [ 5.670075] systemd[1]: Mounted Kernel Debug File System.1874alice # [ 5.676026] systemd[1]: Finished Create List of Static Device Nodes.1875alice # [ 5.692500] systemd-journald[283]: Collecting audit messages is enabled.1876alice # [ 5.696393] systemd[1]: Queued start job for default target Multi-User System.1877alice # [ 5.697270] systemd[1]: systemd-journald.service: Deactivated successfully.1878alice # [ 5.709382] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1879alice # [ 5.709842] systemd[1]: Started Journal Service.1880alice # [ 5.727846] systemd-modules-load[284]: Module 'atkbd' is built in1881alice # [ 5.732157] systemd-modules-load[284]: Module 'loop' is built in1882alice # [ 5.736993] systemd[1]: Mounted Kernel Trace File System.1883alice # [ 5.741880] systemd[1]: Finished Load Kernel Modules.1884alice # [ 5.747667] systemd[1]: Starting Firewall...1885alice # [ 5.756378] systemd[1]: Starting Apply Kernel Variables...1886clare # [ 5.772615] 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)1887clare # [ 5.772710] systemd[1]: Detected virtualization qemu.1888clare # [ 5.772765] systemd[1]: Detected architecture arm64.1889clare # [ 5.772877] systemd[1]: Detected first boot.1890clare # [ 5.775399] systemd[1]: Initializing machine ID from random generator.1891alice # [ 5.777249] systemd-oomd[285]: No swap; memory pressure usage will be degraded1892alice # [ 5.791963] EXT4-fs (vda): re-mounted 011a7ca5-9375-41bd-8c7e-00a713a29a2e.1893alice # [ 5.788761] systemd[1]: Mounted Kernel Configuration File System.1894alice # [ 5.794909] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1895alice # [ 5.803097] systemd[1]: Finished Remount Root and Kernel File Systems.1896alice # [ 5.803710] systemd[1]: Listening on Disk Image Download Service Socket.1897alice # [ 5.806612] systemd[1]: Starting Flush Journal to Persistent Storage...1898alice # [ 5.807248] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1899bob # [ 5.804334] 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)1900bob # [ 5.804426] systemd[1]: Detected virtualization qemu.1901bob # [ 5.804482] systemd[1]: Detected architecture arm64.1902alice # [ 5.812484] systemd[1]: Starting Load/Save OS Random Seed...1903alice # [ 5.813009] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1904bob # [ 5.804612] systemd[1]: Detected first boot.1905alice # [ 5.813752] systemd[1]: Mounted FUSE Control File System.1906bob # [ 5.816940] systemd[1]: Initializing machine ID from random generator.1907alice # [ 5.864269] systemd-journald[283]: Received client request to flush runtime journal.1908clare # [ 5.915738] systemd[1]: bpf-restrict-fs: LSM BPF program attached1909alice # [ 5.912355] systemd[1]: Finished Apply Kernel Variables.1910alice # [ 5.912959] systemd[1]: Finished Load/Save OS Random Seed.1911alice # [ 5.913469] systemd[1]: Reached target First Boot Complete.1912alice # [ 5.913967] systemd[1]: Starting Network Name Resolution...1913alice # [ 5.924757] systemd[1]: Finished Flush Journal to Persistent Storage.1914alice # [ 5.934329] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1915alice # [ 5.935626] systemd[1]: Starting Create Static Device Nodes in /dev...1916bob # [ 5.946800] systemd[1]: bpf-restrict-fs: LSM BPF program attached1917bob # [ 6.027091] systemd[1]: Applying preset policy.1918clare # [ 6.048342] systemd[1]: Applying preset policy.1919alice # [ 6.072568] systemd-resolved[310]: Positive Trust Anchors:1920alice # [ 6.073150] systemd-resolved[310]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1921alice # [ 6.074017] systemd-resolved[310]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161922alice # [ 6.074921] 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 test1923bob # [ 6.195765] systemd[1]: Populated /etc with preset unit settings.1924clare # [ 6.215502] systemd[1]: Populated /etc with preset unit settings.1925alice # [ 6.201675] systemd[1]: Finished Create Static Device Nodes in /dev.1926alice # [ 6.202306] systemd[1]: Reached target Preparation for Local File Systems.1927alice # [ 6.208690] systemd[1]: Starting Rule-based Manager for Device Events and Files...1928alice # [ 6.258674] systemd[1]: Finished Coldplug All udev Devices.1929alice # [ 6.262580] systemd-resolved[310]: Using system hostname 'alice'.1930alice # [ 6.267577] systemd[1]: Started Network Name Resolution.1931alice # [ 6.296869] systemd-udevd[323]: Using default interface naming scheme 'v261'.1932alice # [ 6.346946] systemd[1]: Started Rule-based Manager for Device Events and Files.1933clare # [ 6.431446] systemd[1]: initrd-switch-root.service: Deactivated successfully.1934clare # [ 6.431734] systemd[1]: Stopped initrd-switch-root.service.1935bob # [ 6.417824] systemd[1]: initrd-switch-root.service: Deactivated successfully.1936clare # [ 6.434398] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1937bob # [ 6.419719] systemd[1]: Stopped initrd-switch-root.service.1938clare # [ 6.435638] systemd[1]: Created slice Slice /system/getty.1939bob # [ 6.421349] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1940clare # [ 6.438390] systemd[1]: Created slice User and Session Slice.1941clare # [ 6.438991] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1942clare # [ 6.439610] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1943bob # [ 6.423887] systemd[1]: Created slice Slice /system/getty.1944bob # [ 6.424907] systemd[1]: Created slice User and Session Slice.1945bob # [ 6.425516] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1946bob # [ 6.426119] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1947clare # [ 6.442399] systemd[1]: Expecting device /dev/hvc0...1948clare # [ 6.442903] systemd[1]: Expecting device /dev/ttyAMA0...1949bob # [ 6.429338] systemd[1]: Expecting device /dev/hvc0...1950clare # [ 6.443470] systemd[1]: Reached target Local Encrypted Volumes.1951bob # [ 6.429820] systemd[1]: Expecting device /dev/ttyAMA0...1952clare # [ 6.444063] systemd[1]: Stopped target initrd-fs.target.1953clare # [ 6.446882] systemd[1]: Stopped target initrd-root-fs.target.1954clare # [ 6.447380] systemd[1]: Stopped target initrd-switch-root.target.1955bob # [ 6.431800] systemd[1]: Reached target Local Encrypted Volumes.1956clare # [ 6.447960] systemd[1]: Reached target Virtual Machines and Containers.1957bob # [ 6.432291] systemd[1]: Stopped target initrd-fs.target.1958bob # [ 6.432872] systemd[1]: Stopped target initrd-root-fs.target.1959bob # [ 6.433449] systemd[1]: Stopped target initrd-switch-root.target.1960bob # [ 6.434031] systemd[1]: Reached target Virtual Machines and Containers.1961clare # [ 6.450500] systemd[1]: Reached target Path Units.1962clare # [ 6.450982] systemd[1]: Reached target Remote File Systems.1963clare # [ 6.451544] systemd[1]: Reached target Slice Units.1964bob # [ 6.437424] systemd[1]: Reached target Path Units.1965bob # [ 6.437923] systemd[1]: Reached target Remote File Systems.1966clare # [ 6.453916] systemd[1]: Reached target Swaps.1967clare # [ 6.455952] systemd[1]: Listening on Query the User Interactively for a Password.1968bob # [ 6.439846] systemd[1]: Reached target Slice Units.1969bob # [ 6.440326] systemd[1]: Reached target Swaps.1970clare # [ 6.459041] systemd[1]: Listening on Process Core Dump Socket.1971bob # [ 6.443268] systemd[1]: Listening on Query the User Interactively for a Password.1972bob # [ 6.445414] systemd[1]: Listening on Process Core Dump Socket.1973clare # [ 6.461188] systemd[1]: Listening on Credential Encryption/Decryption.1974clare # [ 6.462995] systemd[1]: Listening on Factory Reset Management.1975clare # [ 6.463550] systemd[1]: Listening on Hostname Service Socket.1976bob # [ 6.448146] systemd[1]: Listening on Credential Encryption/Decryption.1977bob # [ 6.449911] systemd[1]: Listening on Factory Reset Management.1978bob # [ 6.451950] systemd[1]: Listening on Hostname Service Socket.1979clare # [ 6.468514] systemd[1]: Starting Journal Log Access Socket...1980clare # [ 6.469491] systemd[1]: Listening on Journal Audit Socket.1981clare # [ 6.471746] systemd[1]: Listening on Console Output Muting Service Socket.1982bob # [ 6.455115] systemd[1]: Starting Journal Log Access Socket...1983clare # [ 6.474433] systemd[1]: Listening on Network Management Metrics Varlink Socket.1984bob # [ 6.456405] systemd[1]: Listening on Journal Audit Socket.1985clare # [ 6.475120] systemd[1]: Listening on Network Management Varlink Socket.1986bob # [ 6.458669] systemd[1]: Listening on Console Output Muting Service Socket.1987clare # [ 6.475777] systemd[1]: Listening on Network Management Netlink Socket.1988bob # [ 6.459282] systemd[1]: Listening on Network Management Metrics Varlink Socket.1989bob # [ 6.459753] systemd[1]: Listening on Network Management Varlink Socket.1990bob # [ 6.460199] systemd[1]: Listening on Network Management Netlink Socket.1991bob # [ 6.460720] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1992alice # [ 6.462126] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1993bob # [ 6.461054] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1994clare # [ 6.478599] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1995bob # [ 6.461345] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1996clare # [ 6.479668] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1997clare # [ 6.482305] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1998clare # [ 6.486991] systemd[1]: Listening on Disk Repartitioning Service Socket.1999clare # [ 6.487499] systemd[1]: Listening on Resolve Monitor Varlink Socket.2000bob # [ 6.470733] systemd[1]: Listening on Disk Repartitioning Service Socket.2001clare # [ 6.488045] systemd[1]: Listening on Resolve Service Varlink Socket.2002bob # [ 6.471201] systemd[1]: Listening on Resolve Monitor Varlink Socket.2003alice # [ 6.472596] systemd[1]: Mounting /run/wrappers...2004bob # [ 6.471526] systemd[1]: Listening on Resolve Service Varlink Socket.2005bob # [ 6.471838] systemd[1]: Listening on udev Control Socket.2006bob # [ 6.472157] systemd[1]: Listening on udev Varlink Socket.2007bob # [ 6.474031] systemd[1]: Mounting Huge Pages File System...2008clare # [ 6.490129] systemd[1]: Listening on udev Control Socket.2009clare # [ 6.490684] systemd[1]: Listening on udev Varlink Socket.2010clare # [ 6.493777] systemd[1]: Mounting Huge Pages File System...2011clare # [ 6.495859] systemd[1]: Mounting POSIX Message Queue File System...2012bob # [ 6.479058] systemd[1]: Mounting POSIX Message Queue File System...2013bob # [ 6.486677] systemd[1]: Mounting Kernel Debug File System...2014alice # [ 6.487686] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2015clare # [ 6.504504] systemd[1]: Mounting Kernel Debug File System...2016bob # [ 6.499306] systemd[1]: Mounting Kernel Trace File System...2017clare # [ 6.517117] systemd[1]: Mounting Kernel Trace File System...2018bob # [ 6.503785] systemd[1]: Starting Create List of Static Device Nodes...2019bob # [ 6.506931] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2020clare # [ 6.524413] systemd[1]: Starting Create List of Static Device Nodes...2021clare # [ 6.524830] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2022bob # [ 6.515438] systemd[1]: Mounting Kernel Configuration File System...2023alice # [ 6.516204] systemd[1]: Mounted /run/wrappers.2024bob # [ 6.517174] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm2025alice # [ 6.516710] systemd[1]: Reached target Local File Systems.2026clare # [ 6.534282] systemd[1]: Mounting Kernel Configuration File System...2027alice # [ 6.522065] systemd[1]: Listening on Boot Loader Control Service Socket.2028clare # [ 6.536723] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm2029alice # [ 6.525013] systemd[1]: Starting register-nix-paths.service...2030clare # [ 6.543856] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2031bob # [ 6.526957] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2032bob # [ 6.527338] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2033alice # [ 6.528490] systemd[1]: Starting Create SUID/SGID Wrappers...2034alice # [ 6.529567] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2035clare # [ 6.545876] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2036bob # [ 6.537884] systemd[1]: Mounting FUSE Control File System...2037alice # [ 6.540160] systemd[1]: Starting Save Transient machine-id to Disk...2038bob # [ 6.541195] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672039alice # [ 6.548123] systemd[1]: Starting Create System Files and Directories...2040clare # [ 6.565605] systemd[1]: Mounting FUSE Control File System...2041clare # [ 6.565991] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672042bob # [ 6.566206] systemd[1]: Starting Journal Service...2043clare # [ 6.589005] systemd[1]: Starting Journal Service...2044bob # [ 6.586528] systemd[1]: Starting Load Kernel Modules...2045clare # [ 6.605827] systemd[1]: Starting Load Kernel Modules...2046bob # [ 6.596501] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...2047clare # [ 6.621979] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...2048bob # [ 6.611272] systemd-journald[288]: Collecting audit messages is enabled.2049bob # [ 6.613029] systemd[1]: Queued start job for default target Multi-User System.2050bob # [ 6.614060] systemd[1]: systemd-journald.service: Deactivated successfully.2051clare # [ 6.632725] systemd-journald[283]: Collecting audit messages is enabled.2052clare # [ 6.636468] systemd[1]: Starting Remount Root and Kernel File Systems...2053clare # [ 6.636789] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2054bob # [ 6.624035] systemd[1]: Starting Remount Root and Kernel File Systems...2055bob # [ 6.624518] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2056clare # [ 6.627993] systemd[1]: Queued start job for default target Multi-User System.2057clare # [ 6.636534] systemd[1]: systemd-journald.service: Deactivated successfully.2058clare # [ 6.647399] systemd[1]: Starting Coldplug All udev Devices...2059bob # [ 6.640577] systemd[1]: Starting Coldplug All udev Devices...2060bob # [ 6.645549] systemd[1]: Started Journal Service.2061clare # [ 6.667471] systemd[1]: Started Journal Service.2062bob # [ 6.648845] systemd[1]: Listening on Journal Log Access Socket.2063clare # [ 6.665000] systemd[1]: Listening on Journal Log Access Socket.2064bob # [ 6.652819] systemd[1]: Mounted Huge Pages File System.2065bob # [ 6.653372] systemd[1]: Mounted POSIX Message Queue File System.2066alice # [ 6.658512] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2067clare # [ 6.669856] systemd[1]: Mounted Huge Pages File System.2068clare # [ 6.670398] systemd[1]: Mounted POSIX Message Queue File System.2069clare # [ 6.670918] systemd[1]: Mounted Kernel Debug File System.2070bob # [ 6.658964] systemd[1]: Mounted Kernel Debug File System.2071bob # [ 6.659505] systemd[1]: Mounted Kernel Trace File System.2072clare # [ 6.675237] systemd[1]: Finished Create List of Static Device Nodes.2073alice # [ 6.667329] systemd[1]: Finished Save Transient machine-id to Disk.2074bob # [ 6.664154] systemd[1]: Finished Create List of Static Device Nodes.2075bob # [ 6.664762] systemd[1]: Mounted Kernel Configuration File System.2076bob # [ 6.678263] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2077bob # [ 6.683271] systemd-modules-load[290]: Module 'atkbd' is built in2078clare # [ 6.696758] systemd-modules-load[285]: Module 'atkbd' is built in2079bob # [ 6.683878] systemd-modules-load[290]: Module 'loop' is built in2080clare # [ 6.699507] systemd-modules-load[285]: Module 'loop' is built in2081clare # [ 6.700160] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2082clare # [ 6.704603] systemd[1]: Mounted Kernel Trace File System.2083bob # [ 6.694487] EXT4-fs (vda): re-mounted e2358b9f-7e50-47e7-82c4-752cc1ceae86.2084bob # [ 6.702041] systemd[1]: Finished Load Kernel Modules.2085bob # [ 6.704123] systemd[1]: Starting Firewall...2086clare # [ 6.717576] systemd[1]: Finished Load Kernel Modules.2087bob # [ 6.710116] systemd[1]: Starting Apply Kernel Variables...2088alice # [ 6.716423] systemd[1]: Finished Create System Files and Directories.2089bob # [ 6.713722] systemd[1]: Finished Remount Root and Kernel File Systems.2090bob # [ 6.714348] systemd[1]: Listening on Disk Image Download Service Socket.2091clare # [ 6.728069] systemd[1]: Starting Firewall...2092clare # [ 6.730975] systemd-oomd[286]: No swap; memory pressure usage will be degraded2093clare # [ 6.740681] EXT4-fs (vda): re-mounted efb71bc4-ad16-42e7-b2b8-03f630a846cc.2094alice # [ 6.728115] systemd[1]: Starting Rebuild Journal Catalog...2095bob # [ 6.725181] systemd[1]: Starting Flush Journal to Persistent Storage...2096bob # [ 6.725824] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2097clare # [ 6.742765] systemd[1]: Starting Apply Kernel Variables...2098alice # [ 6.733445] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2099clare # [ 6.749794] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2100clare # [ 6.751437] systemd[1]: Mounted Kernel Configuration File System.2101alice # [ 6.742657] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2102clare # [ 6.756554] systemd[1]: Finished Remount Root and Kernel File Systems.2103clare # [ 6.759071] systemd[1]: Listening on Disk Image Download Service Socket.2104clare # [ 6.761204] systemd[1]: Starting Flush Journal to Persistent Storage...2105clare # [ 6.761808] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2106bob # [ 6.763409] systemd[1]: Starting Load/Save OS Random Seed...2107bob # [ 6.765124] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2108clare # [ 6.779805] systemd[1]: Starting Load/Save OS Random Seed...2109clare # [ 6.780496] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2110bob # [ 6.772627] systemd[1]: Mounted FUSE Control File System.2111alice # [ 6.779262] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2112bob # [ 6.786361] systemd-oomd[291]: No swap; memory pressure usage will be degraded2113bob # [ 6.795365] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2114clare # [ 6.810258] systemd[1]: Mounted FUSE Control File System.2115clare # [ 6.848307] systemd-journald[283]: Received client request to flush runtime journal.2116bob # [ 6.837168] systemd-journald[288]: Received client request to flush runtime journal.2117alice # [ 6.859448] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2118alice # [ 6.886840] systemd[1]: Finished Rebuild Journal Catalog.2119alice # [ 6.894732] systemd[1]: Starting Update is Completed...2120bob # [ 6.897213] systemd[1]: Finished Load/Save OS Random Seed.2121bob # [ 6.897852] systemd[1]: Reached target First Boot Complete.2122bob # [ 6.898383] systemd[1]: Finished Apply Kernel Variables.2123bob # [ 6.898882] systemd[1]: Starting Network Name Resolution...2124clare # [ 6.912648] systemd[1]: Finished Load/Save OS Random Seed.2125clare # [ 6.913240] systemd[1]: Reached target First Boot Complete.2126clare # [ 6.913740] systemd[1]: Finished Apply Kernel Variables.2127clare # [ 6.914211] systemd[1]: Starting Network Name Resolution...2128clare # [ 6.920778] systemd[1]: Finished Flush Journal to Persistent Storage.2129bob # [ 6.910347] systemd[1]: Finished Flush Journal to Persistent Storage.2130clare # [ 6.932135] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2131clare # [ 6.936446] systemd[1]: Starting Create Static Device Nodes in /dev...2132bob # [ 6.929199] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2133bob # [ 6.931939] systemd[1]: Starting Create Static Device Nodes in /dev...2134alice # [ 7.012417] systemd[1]: Finished Update is Completed.2135alice # [ 7.038410] (udev-worker)[351]: Network interface NamePolicy= disabled on kernel command line.2136bob # [ 7.056869] systemd-resolved[317]: Positive Trust Anchors:2137bob # [ 7.057506] systemd-resolved[317]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2138bob # [ 7.058399] systemd-resolved[317]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162139clare # [ 7.073049] systemd-resolved[309]: Positive Trust Anchors:2140clare # [ 7.073651] systemd-resolved[309]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2141clare # [ 7.074544] systemd-resolved[309]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162142bob # [ 7.059300] systemd-resolved[317]: 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 test2143clare # [ 7.075407] systemd-resolved[309]: 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 test2144alice # [ 7.089310] (udev-worker)[353]: Network interface NamePolicy= disabled on kernel command line.2145clare # [ 7.237898] systemd[1]: Finished Create Static Device Nodes in /dev.2146clare # [ 7.238574] systemd[1]: Reached target Preparation for Local File Systems.2147clare # [ 7.240631] systemd[1]: Starting Rule-based Manager for Device Events and Files...2148bob # [ 7.228085] systemd[1]: Finished Create Static Device Nodes in /dev.2149bob # [ 7.228772] systemd[1]: Reached target Preparation for Local File Systems.2150bob # [ 7.230893] systemd-resolved[317]: Using system hostname 'bob'.2151bob # [ 7.235556] systemd[1]: Starting Rule-based Manager for Device Events and Files...2152bob # [ 7.240315] systemd[1]: Started Network Name Resolution.2153clare # [ 7.261384] systemd-resolved[309]: Using system hostname 'clare'.2154bob # [ 7.252108] systemd[1]: Finished Coldplug All udev Devices.2155clare # [ 7.265624] systemd[1]: Started Network Name Resolution.2156clare # [ 7.299256] systemd[1]: Finished Coldplug All udev Devices.2157bob # [ 7.297921] systemd-udevd[330]: Using default interface naming scheme 'v261'.2158clare # [ 7.331905] systemd-udevd[322]: Using default interface naming scheme 'v261'.2159bob # [ 7.351289] systemd[1]: Started Rule-based Manager for Device Events and Files.2160clare # [ 7.384546] systemd[1]: Started Rule-based Manager for Device Events and Files.2161alice # [ 7.384351] mousedev: PS/2 mouse device common for all mice2162alice # [ 7.412458] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2163alice # [ 7.414820] systemd[1]: Finished Create SUID/SGID Wrappers.2164bob # [ 7.413945] systemd[1]: Mounting /run/wrappers...2165clare # [ 7.432829] systemd[1]: Mounting /run/wrappers...2166alice # [ 7.435695] systemd[1]: Finished register-nix-paths.service.2167alice # [ 7.439112] systemd[1]: Reached target System Initialization.2168alice # [ 7.439700] systemd[1]: Started Discard unused filesystem blocks once a week.2169alice # [ 7.443935] systemd[1]: Started Daily Cleanup of Temporary Directories.2170alice # [ 7.445835] systemd[1]: Reached target Timer Units.2171alice # [ 7.446323] systemd[1]: Listening on D-Bus System Message Bus Socket.2172alice # [ 7.446908] systemd[1]: Listening on Nix Daemon Socket.2173alice # [ 7.447393] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2174alice # [ 7.454248] systemd[1]: Reached target Socket Units.2175alice # [ 7.454786] systemd[1]: Reached target Basic System.2176alice # [ 7.455266] systemd[1]: Started backdoor.service.2177alice # [ 7.455716] systemd[1]: Starting Import lastlog data into lastlog2 database...2178alice # [ 7.466965] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2179alice # [ 7.485640] systemd[1]: Starting Post-Boot Actions...2180bob # [ 7.483337] systemd[1]: Mounted /run/wrappers.2181bob # [ 7.485274] systemd[1]: Reached target Local File Systems.2182bob # [ 7.488414] systemd[1]: Listening on Boot Loader Control Service Socket.2183bob # [ 7.493793] systemd[1]: Starting register-nix-paths.service...2184bob # [ 7.497115] systemd[1]: Starting Create SUID/SGID Wrappers...2185alice # [ 7.502476] systemd[1]: Started Reset console on configuration changes.2186bob # [ 7.498804] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2187clare # [ 7.513813] systemd[1]: Mounted /run/wrappers.2188clare # [ 7.514805] systemd[1]: Reached target Local File Systems.2189clare # [ 7.519227] systemd[1]: Listening on Boot Loader Control Service Socket.2190bob # [ 7.507526] systemd[1]: Starting Save Transient machine-id to Disk...2191clare # [ 7.523748] systemd[1]: Starting register-nix-paths.service...2192clare # [ 7.528388] systemd[1]: Starting Create SUID/SGID Wrappers...2193clare # [ 7.529023] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2194bob # [ 7.519951] systemd[1]: Starting Create System Files and Directories...2195bob # [ 7.522515] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2196clare # [ 7.536085] systemd[1]: Starting Save Transient machine-id to Disk...2197clare # [ 7.551089] systemd[1]: Starting Create System Files and Directories...2198clare # [ 7.551786] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2199bob # [ 7.546197] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2200alice # [ 7.550533] systemd[1]: Starting D-Bus System Message Bus...2201alice # connecting to host...2202clare # [ 7.588255] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse2203alice # [ 7.590474] systemd[1]: Finished Post-Boot Actions.2204alice # [ 7.664936] systemd[1]: Started Name Service Cache Daemon (nsncd).2205alice # [ 7.671623] systemd[1]: Reached target Host and Network Name Lookups.2206alice # [ 7.672698] nsncd[477]: Oct 13 15:41:18.484 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2207alice # [ 7.673947] systemd[1]: Reached target User and Group Name Lookups.2208alice # [ 7.684735] systemd[1]: Starting User Login Management...2209alice # [ 7.687773] systemd[1]: Finished Import lastlog data into lastlog2 database.2210bob # [ 7.698173] systemd[1]: Finished Create System Files and Directories.2211clare # [ 7.724131] systemd[1]: Finished Create System Files and Directories.2212bob # [ 7.713398] systemd[1]: Starting Rebuild Journal Catalog...2213clare # [ 7.733796] systemd[1]: Starting Rebuild Journal Catalog...2214bob # [ 7.719552] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2215clare # [ 7.748428] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2216alice # [ 7.794471] dbus-broker-launch[486]: Looking up NSS user entry for 'systemd-timesync'...2217alice # [ 7.806472] dbus-broker-launch[486]: NSS returned no entry for 'systemd-timesync'2218alice # [ 7.807152] dbus-broker-launch[486]: Invalid user-name in /nix/store/fw8zafkw97d3ikcjlws1jwnwvgl2zxyz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2219bob # [ 7.809698] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2220clare # [ 7.826445] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2221alice # [ 7.817627] systemd-logind[510]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2222alice # [ 7.819312] systemd-logind[510]: Watching system buttons on /dev/input/event0 (gpio-keys)2223alice # [ 7.824082] systemd-logind[510]: New seat seat0.2224alice # [ 7.824794] systemd[1]: Started User Login Management.2225bob # [ 7.821664] systemd[1]: Finished Save Transient machine-id to Disk.2226clare # [ 7.837000] systemd[1]: Finished Save Transient machine-id to Disk.2227alice # [ 7.829672] systemd[1]: Starting linger-users.service...2228alice # [ 7.851732] systemd[1]: Started D-Bus System Message Bus.2229bob # [ 7.849030] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2230clare # [ 7.870227] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2231bob # [ 7.864415] systemd[1]: Finished Rebuild Journal Catalog.2232bob # [ 7.871695] systemd[1]: Starting Update is Completed...2233alice # [ 7.884259] dbus-broker-launch[486]: Ready2234clare # [ 7.896136] systemd[1]: Finished Rebuild Journal Catalog.2235clare # [ 7.901067] systemd[1]: Starting Update is Completed...2236bob # [ 7.891586] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2237alice # [ 7.901879] systemd[1]: linger-users.service: Deactivated successfully.2238alice # [ 7.903525] systemd[1]: Finished linger-users.service.2239bob # [ 7.920658] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2240alice # [ 7.951479] systemd[1]: Finished Firewall.2241alice # [ 7.953729] systemd[1]: Reached target Preparation for Network.2242alice # [ 7.956226] systemd[1]: Listening on Network Management Resolve Hook Socket.2243clare # [ 7.966197] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2244alice # [ 7.960822] systemd[1]: Starting Network Management...2245clare # [ 7.971160] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2246clare # [ 7.975099] systemd[1]: Finished Update is Completed.2247bob # [ 8.009505] systemd[1]: Finished Update is Completed.2248alice # [ 8.150857] cfg80211: Loading compiled-in X.509 certificates for regulatory database2249alice # [ 8.186197] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2250alice # [ 8.186761] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2251alice # [ 8.189684] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22252alice # [ 8.190076] cfg80211: failed to load regulatory.db2253bob # [ 8.194771] (udev-worker)[359]: Network interface NamePolicy= disabled on kernel command line.2254alice # [ 8.212191] systemd-networkd[540]: lo: Link UP2255alice # [ 8.212706] systemd-networkd[540]: lo: Gained carrier2256bob # [ 8.211989] (udev-worker)[360]: Network interface NamePolicy= disabled on kernel command line.2257alice # [ 8.219001] systemd[1]: Started Network Management.2258alice # [ 8.219558] systemd[1]: Reached target Network.2259alice # [ 8.221908] systemd-networkd[540]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2260alice # [ 8.228107] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2261alice # [ 8.232626] systemd-networkd[540]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2262alice # [ 8.233988] systemd-networkd[540]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2263alice # [ 8.240540] systemd[1]: Starting Permit User Sessions...2264alice # [ 8.241102] systemd-networkd[540]: eth0: Link UP2265alice # [ 8.241547] systemd-networkd[540]: eth0: Gained carrier2266alice # [ 8.246229] systemd-networkd[540]: eth1: Link UP2267alice # [ 8.246756] systemd-networkd[540]: eth1: Gained carrier2268alice # [ 8.247208] systemd-networkd[540]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2269alice # [ 8.261311] systemd-networkd[540]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22270clare # [ 8.320077] (udev-worker)[348]: Network interface NamePolicy= disabled on kernel command line.2271alice # [ 8.312160] systemd[1]: Finished Permit User Sessions.2272clare # [ 8.323823] (udev-worker)[344]: Network interface NamePolicy= disabled on kernel command line.2273alice # [ 8.314953] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2274alice # [ 8.321318] systemd[1]: Started Getty on tty1.2275alice # [ 8.323181] systemd[1]: Reached target Login Prompts.2276alice # [ 8.323716] systemd[1]: Reached target Multi-User System.2277alice # [ 8.329208] systemd[1]: Startup finished in 759ms (kernel) + 3.653s (initrd) + 3.912s (userspace) = 8.325s.2278bob # [ 8.433361] mousedev: PS/2 mouse device common for all mice2279clare # [ 8.489635] mousedev: PS/2 mouse device common for all mice2280bob # [ 8.475715] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2281bob # [ 8.478398] systemd[1]: Finished Create SUID/SGID Wrappers.2282clare # [ 8.493994] systemd[1]: Finished register-nix-paths.service.2283bob # [ 8.489742] systemd[1]: Finished register-nix-paths.service.2284bob # [ 8.493002] systemd[1]: Reached target System Initialization.2285bob # [ 8.493670] systemd[1]: Started Discard unused filesystem blocks once a week.2286bob # [ 8.494305] systemd[1]: Started Daily Cleanup of Temporary Directories.2287bob # [ 8.494877] systemd[1]: Reached target Timer Units.2288bob # [ 8.502360] systemd[1]: Listening on D-Bus System Message Bus Socket.2289bob # [ 8.503105] systemd[1]: Listening on Nix Daemon Socket.2290clare # [ 8.516157] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2291clare # [ 8.516915] systemd[1]: Finished Create SUID/SGID Wrappers.2292bob # [ 8.503618] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2293clare # [ 8.519089] systemd[1]: Reached target System Initialization.2294clare # [ 8.519673] systemd[1]: Started Discard unused filesystem blocks once a week.2295bob # [ 8.510111] systemd[1]: Reached target Socket Units.2296bob # [ 8.510695] systemd[1]: Reached target Basic System.2297bob # [ 8.511190] systemd[1]: Started backdoor.service.2298clare # [ 8.524411] systemd[1]: Started Daily Cleanup of Temporary Directories.2299bob # [ 8.511638] systemd[1]: Starting Import lastlog data into lastlog2 database...2300clare # [ 8.525055] systemd[1]: Reached target Timer Units.2301clare # [ 8.525512] systemd[1]: Listening on D-Bus System Message Bus Socket.2302bob # [ 8.519779] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2303clare # [ 8.538547] systemd[1]: Listening on harmonia binary cache socket.2304clare # [ 8.539253] systemd[1]: Listening on Nix Daemon Socket.2305bob # [ 8.526381] systemd[1]: Starting Post-Boot Actions...2306clare # [ 8.539752] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2307clare # [ 8.545497] systemd[1]: Reached target Socket Units.2308clare # [ 8.546024] systemd[1]: Reached target Basic System.2309clare # [ 8.546490] systemd[1]: Started backdoor.service.2310clare # [ 8.551725] systemd[1]: Starting Import lastlog data into lastlog2 database...2311clare # [ 8.556298] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2312bob # [ 8.544643] systemd[1]: Started Reset console on configuration changes.2313alice # [ 8.553222] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input32314clare # [ 8.581456] systemd[1]: Starting Post-Boot Actions...2315clare # [ 8.598132] systemd[1]: Started Reset console on configuration changes.2316bob # [ 8.609775] systemd[1]: Starting D-Bus System Message Bus...2317clare # [ 8.651544] systemd[1]: Starting D-Bus System Message Bus...2318bob # [ 8.672238] systemd[1]: Started Name Service Cache Daemon (nsncd).2319bob # [ 8.673235] nsncd[480]: Oct 13 15:41:19.500 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2320bob # [ 8.674512] systemd[1]: Reached target Host and Network Name Lookups.2321bob # [ 8.675131] systemd[1]: Reached target User and Group Name Lookups.2322bob # [ 8.691345] systemd[1]: Starting User Login Management...2323bob # connecting to host...2324clare # [ 8.725305] systemd[1]: Finished Post-Boot Actions.2325clare # connecting to host...2326clare # [ 8.750752] systemd[1]: Started Name Service Cache Daemon (nsncd).2327clare # [ 8.754290] systemd[1]: Reached target Host and Network Name Lookups.2328clare # [ 8.755197] nsncd[472]: Oct 13 15:41:19.578 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2329clare # [ 8.771754] systemd[1]: Reached target User and Group Name Lookups.2330clare # [ 8.777126] systemd[1]: Starting User Login Management...2331bob # [ 8.767548] systemd[1]: Finished Post-Boot Actions.2332bob: Guest shell says: b'Spawning backdoor root shell...\n'2333clare # [ 8.821702] systemd[1]: Finished Import lastlog data into lastlog2 database.2334bob # [ 8.840258] systemd[1]: Finished Import lastlog data into lastlog2 database.2335bob: connected to guest root shell2336bob: (connecting took 9.09 seconds)2337bob: (finished: waiting for the VM to finish booting, in 9.09 seconds)2338alice # [ 8.861388] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2339alice # [ 8.866489] systemd[1]: Starting Virtual Console Setup...2340bob # [ 8.863181] dbus-broker-launch[486]: Looking up NSS user entry for 'systemd-timesync'...2341bob # [ 8.873704] systemd-logind[495]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2342clare # [ 8.890472] dbus-broker-launch[477]: Looking up NSS user entry for 'systemd-timesync'...2343bob # [ 8.878871] systemd-logind[495]: Watching system buttons on /dev/input/event0 (gpio-keys)2344bob # [ 8.879658] systemd-logind[495]: New seat seat0.2345alice # [ 8.888924] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.2346alice # [ 8.889649] systemd[1]: Stopped Virtual Console Setup.2347bob # [ 8.886364] systemd[1]: Started User Login Management.2348clare # [ 8.903483] dbus-broker-launch[477]: NSS returned no entry for 'systemd-timesync'2349alice # [ 8.895009] systemd[1]: Starting Virtual Console Setup...2350bob # [ 8.891657] systemd[1]: Starting linger-users.service...2351clare # [ 8.905418] dbus-broker-launch[477]: Invalid user-name in /nix/store/i93zg9gq7f614x42cnzk9ahs5xlj4ixc-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2352bob # [ 8.896699] dbus-broker-launch[486]: NSS returned no entry for 'systemd-timesync'2353bob # [ 8.897405] dbus-broker-launch[486]: Invalid user-name in /nix/store/1n1m956aayck8lzlfcpgz5j951b941w3-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2354alice # [ 8.909682] systemd-logind[510]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2355clare # [ 8.924335] systemd-logind[495]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2356clare # [ 8.928074] systemd-logind[495]: Watching system buttons on /dev/input/event0 (gpio-keys)2357clare # [ 8.929602] systemd-logind[495]: New seat seat0.2358clare # [ 8.934458] systemd[1]: Started User Login Management.2359clare # [ 8.935046] systemd[1]: Starting linger-users.service...2360bob # [ 8.933374] systemd[1]: Started D-Bus System Message Bus.2361clare # [ 8.950950] systemd[1]: Started D-Bus System Message Bus.2362bob # [ 8.968467] systemd[1]: linger-users.service: Deactivated successfully.2363clare # [ 8.985177] dbus-broker-launch[477]: Ready2364bob # [ 8.975040] systemd[1]: Finished linger-users.service.2365bob # [ 8.978171] dbus-broker-launch[486]: Ready2366alice # [ 9.000760] systemd-vconsole-setup[559]: Configuration of first virtual console was skipped, ignoring remaining ones.2367alice # [ 9.003459] systemd[1]: Finished Virtual Console Setup.2368clare # [ 9.013120] systemd[1]: linger-users.service: Deactivated successfully.2369clare # [ 9.015043] systemd[1]: Finished linger-users.service.2370bob # [ 9.104361] systemd[1]: Finished Firewall.2371bob # [ 9.105992] systemd[1]: Reached target Preparation for Network.2372bob # [ 9.108637] systemd[1]: Listening on Network Management Resolve Hook Socket.2373bob # [ 9.112753] systemd[1]: Starting Network Management...2374clare # [ 9.130364] systemd[1]: Finished Firewall.2375clare # [ 9.132181] systemd[1]: Reached target Preparation for Network.2376clare # [ 9.135699] systemd[1]: Listening on Network Management Resolve Hook Socket.2377clare # [ 9.138634] systemd[1]: Starting Network Management...2378clare # [ 9.315791] cfg80211: Loading compiled-in X.509 certificates for regulatory database2379bob # [ 9.306208] cfg80211: Loading compiled-in X.509 certificates for regulatory database2380clare # [ 9.358384] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2381clare # [ 9.358883] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2382alice # [ 9.345561] systemd-networkd[540]: eth1: Gained IPv6LL2383clare # [ 9.362135] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22384clare # [ 9.362503] cfg80211: failed to load regulatory.db2385bob # [ 9.348670] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2386bob # [ 9.349190] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2387bob # [ 9.352088] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22388bob # [ 9.352475] cfg80211: failed to load regulatory.db2389clare # [ 9.385493] systemd-networkd[541]: lo: Link UP2390clare # [ 9.385998] systemd-networkd[541]: lo: Gained carrier2391bob # [ 9.375620] systemd-networkd[556]: lo: Link UP2392bob # [ 9.380195] systemd-networkd[556]: lo: Gained carrier2393clare # [ 9.393844] systemd[1]: Started Network Management.2394bob # [ 9.383658] systemd[1]: Started Network Management.2395clare # [ 9.398395] systemd[1]: Reached target Network.2396clare # [ 9.398855] systemd-networkd[541]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2397bob # [ 9.388805] systemd[1]: Reached target Network.2398bob # [ 9.389292] systemd[1]: Reached target Network is Online.2399clare # [ 9.405976] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2400clare # [ 9.407301] systemd-networkd[541]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2401bob # [ 9.395067] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2402clare # [ 9.411896] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2403bob # [ 9.400089] systemd-networkd[556]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2404bob # [ 9.400889] systemd-networkd[556]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2405bob # [ 9.401724] systemd-networkd[556]: eth1: Link UP2406bob # [ 9.402147] systemd-networkd[556]: eth1: Gained carrier2407clare # [ 9.416138] systemd-networkd[541]: eth0: Link UP2408bob # [ 9.402585] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2409clare # [ 9.416590] systemd-networkd[541]: eth0: Gained carrier2410clare # [ 9.417030] systemd[1]: Starting Permit User Sessions...2411clare # [ 9.417477] systemd-networkd[541]: eth1: Link UP2412clare # [ 9.417874] systemd-networkd[541]: eth1: Gained carrier2413clare # [ 9.418316] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2414bob # [ 9.415386] systemd[1]: Starting ncps binary cache proxy service...2415bob # [ 9.420585] systemd-networkd[556]: eth0: Link UP2416clare # [ 9.433317] systemd-networkd[541]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22417bob # [ 9.421067] systemd-networkd[556]: eth0: Gained carrier2418bob # [ 9.421521] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2419bob # [ 9.422140] systemd-networkd[556]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22420bob # [ 9.430812] systemd[1]: Starting Permit User Sessions...2421clare # [ 9.483426] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2422clare # [ 9.490206] systemd[1]: Finished Permit User Sessions.2423clare # [ 9.495009] systemd[1]: Started Getty on tty1.2424clare # [ 9.496382] systemd[1]: Reached target Login Prompts.2425clare # [ 9.502907] systemd[1]: Reached target Multi-User System.2426clare # [ 9.505069] systemd[1]: Startup finished in 767ms (kernel) + 4.584s (initrd) + 4.152s (userspace) = 9.503s.2427bob # [ 9.509069] systemd[1]: Finished Permit User Sessions.2428bob # [ 9.518870] systemd[1]: Started Getty on tty1.2429bob # [ 9.524652] systemd[1]: Reached target Login Prompts.2430bob # [ 9.533936] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2431bob # [ 9.552431] ncps-pre-start[563]: /nix/store/z27dji5sjdkkdpzjlm37nq6d5cwbgl8i-ncps-0.9.4/bin/dbmate-ncps up2432alice # [ 9.634133] systemd-networkd[540]: eth0: Gained IPv6LL2433clare # [ 9.692043] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input32434bob # [ 9.779136] ncps-pre-start[578]: Creating: /var/lib/ncps/db/db.sqlite2435bob # [ 9.794012] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input32436bob # [ 9.837284] ncps-pre-start[578]: Applying: 20241210054814_create-narinfos-table.sql2437bob # [ 9.847011] ncps-pre-start[578]: Applied: 20241210054814_create-narinfos-table.sql in 9.68132ms2438bob # [ 9.847917] ncps-pre-start[578]: Applying: 20241210054829_create-nars-table.sql2439bob # [ 9.858951] ncps-pre-start[578]: Applied: 20241210054829_create-nars-table.sql in 11.92136ms2440bob # [ 9.859744] ncps-pre-start[578]: Applying: 20241213014846_add-query-to-nars-table.sql2441bob # [ 9.871279] ncps-pre-start[578]: Applied: 20241213014846_add-query-to-nars-table.sql in 12.2874ms2442bob # [ 9.872120] ncps-pre-start[578]: Applying: 20251230224159_add-cascade-to-nars-fk.sql2443bob # [ 9.884350] ncps-pre-start[578]: Applied: 20251230224159_add-cascade-to-nars-fk.sql in 13.03488ms2444bob # [ 9.887256] ncps-pre-start[578]: Applying: 20260101000000_add-config-table.sql2445bob # [ 9.896365] ncps-pre-start[578]: Applied: 20260101000000_add-config-table.sql in 11.98836ms2446bob # [ 9.900832] ncps-pre-start[578]: Applying: 20260105025735_remove-redundant-indexes.sql2447bob # [ 9.907850] ncps-pre-start[578]: Applied: 20260105025735_remove-redundant-indexes.sql in 11.47528ms2448bob # [ 9.908879] ncps-pre-start[578]: Applying: 20260105030513_support_shared_nars_between_narinfos.sql2449bob # [ 9.920333] ncps-pre-start[578]: Applied: 20260105030513_support_shared_nars_between_narinfos.sql in 12.45468ms2450bob # [ 9.921224] ncps-pre-start[578]: Applying: 20260117195000_add_narinfo_de_normalized.sql2451bob # [ 9.936331] ncps-pre-start[578]: Applied: 20260117195000_add_narinfo_de_normalized.sql in 15.975ms2452bob # [ 9.937131] ncps-pre-start[578]: Applying: 20260127223000_allow_multiple_nar_representations.sql2453bob # [ 9.950814] ncps-pre-start[578]: Applied: 20260127223000_allow_multiple_nar_representations.sql in 14.45364ms2454bob # [ 9.951703] ncps-pre-start[578]: Applying: 20260131021850_add_chunks.sql2455bob # [ 9.967013] ncps-pre-start[578]: Applied: 20260131021850_add_chunks.sql in 16.18024ms2456bob # [ 9.967747] ncps-pre-start[578]: Applying: 20260205063651_add_total_chunks_to_nar_files.sql2457bob # [ 9.976339] ncps-pre-start[578]: Applied: 20260205063651_add_total_chunks_to_nar_files.sql in 9.2962ms2458bob # [ 9.977165] ncps-pre-start[578]: Applying: 20260217071237_add_chunking_started_at_to_nar_files.sql2459clare # [ 9.990675] systemd[1]: Starting Virtual Console Setup...2460bob # [ 9.987193] ncps-pre-start[578]: Applied: 20260217071237_add_chunking_started_at_to_nar_files.sql in 10.84104ms2461bob # [ 9.992124] ncps-pre-start[578]: Applying: 20260301000000_add_verified_at_to_nar_files.sql2462clare # [ 10.005835] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2463bob # [ 10.002997] ncps-pre-start[578]: Applied: 20260301000000_add_verified_at_to_nar_files.sql in 15.369ms2464clare # [ 10.017850] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.2465clare # [ 10.020529] systemd[1]: Stopped Virtual Console Setup.2466clare # [ 10.028389] systemd[1]: Starting Virtual Console Setup...2467clare # [ 10.047831] systemd-logind[495]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2468clare # [ 10.109845] systemd-vconsole-setup[561]: Configuration of first virtual console was skipped, ignoring remaining ones.2469clare # [ 10.112600] systemd[1]: Finished Virtual Console Setup.2470bob # [ 10.122393] systemd[1]: Started ncps binary cache proxy service.2471bob # [ 10.135429] systemd[1]: Reached target Multi-User System.2472bob # [ 10.138321] systemd[1]: Startup finished in 764ms (kernel) + 4.565s (initrd) + 4.806s (userspace) = 10.136s.2473bob: (finished: waiting for unit ncps, in 10.40 seconds)2474bob: must succeed: systemctl status ncps2475bob: (finished: must succeed: systemctl status ncps, in 0.09 seconds)2476clare: waiting for unit harmonia.socket2477clare: waiting for the VM to finish booting2478clare: Guest shell says: b'Spawning backdoor root shell...\n'2479clare: connected to guest root shell2480clare: (connecting took 0.00 seconds)2481clare: (finished: waiting for the VM to finish booting, in 0.00 seconds)2482clare: (finished: waiting for unit harmonia.socket, in 0.05 seconds)2483alice: waiting for success: curl bob:8502/nix-cache-info2484alice: waiting for the VM to finish booting2485alice: Guest shell says: b'Spawning backdoor root shell...\n'2486alice: connected to guest root shell2487alice: (connecting took 0.00 seconds)2488alice: (finished: waiting for the VM to finish booting, in 0.00 seconds)2489bob # [ 10.299805] systemd[1]: Starting Virtual Console Setup...2490bob # [ 10.333503] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.2491bob # [ 10.335462] systemd[1]: Stopped Virtual Console Setup.2492bob # [ 10.353588] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2493bob # [ 10.358228] systemd[1]: Starting Virtual Console Setup...2494clare # [ 10.435375] systemd-networkd[541]: eth0: Gained IPv6LL2495bob # [ 10.518826] systemd-logind[495]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2496bob # [ 10.523859] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","log_level":"info","time":"2026-10-13T15:41:21Z","message":"logger created"}2497bob # [ 10.525764] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","xz-binary-path":"/nix/store/csrwf31hprwghnbyiglxa3p787lgrl67-xz-5.8.4-bin/bin/xz","time":"2026-10-13T15:41:21Z","message":"Using xz binary for xz decompression"}2498bob # [ 10.534775] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-10-13T15:41:21Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"}2499bob # [ 10.545593] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","time":"2026-10-13T15:41:21Z","message":"using local locks (single-instance mode)"}2500bob # [ 10.551640] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-13T15:41:21Z","message":"setting up tracer provider to discard traces"}2501bob # [ 10.554476] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-13T15:41:21Z","message":"setting up meter provider to discard metrics"}2502bob # [ 10.557538] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-10-13T15:41:21Z","message":"setting up logger provider to discard logs"}2503bob # [ 10.563831] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-10-13T15:41:21Z","message":"Reporting anonymous metrics to the project maintainers"}2504bob # [ 10.567575] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-10-13T15:41:21Z","message":"failed to parse netrc file, proceeding without netrc authentication"}2505bob # [ 10.570004] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-10-13T15:41:21Z","message":"using local storage"}2506clare # [ 10.610231] systemd[1]: Starting harmonia binary cache service...2507bob # [ 10.632042] systemd-vconsole-setup[603]: Configuration of first virtual console was skipped, ignoring remaining ones.2508bob # [ 10.634666] systemd[1]: Finished Virtual Console Setup.2509clare # [ 10.748660] harmonia-cache[568]: 2026-10-13T15:41:21.572110Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000))2510clare # [ 10.754019] harmonia-cache[568]: 2026-10-13T15:41:21.576456Z INFO actix_server::builder: starting 4 workers2511clare # [ 10.761240] harmonia-cache[568]: 2026-10-13T15:41:21.581255Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s2512clare # [ 10.765547] harmonia-cache[568]: 2026-10-13T15:41:21.581338Z INFO actix_server::server: Actix runtime found; starting in Actix runtime2513clare # [ 10.774107] harmonia-cache[568]: 2026-10-13T15:41:21.581439Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:50002514clare # [ 10.778434] systemd[1]: Started harmonia binary cache service.2515bob # [ 10.786125] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-10-13T15:41:21Z","message":"upstream became healthy and is now available for requests"}2516bob # [ 10.790507] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-10-13T15:41:21Z","message":"Server started"}2517bob # [ 10.914100] systemd-networkd[556]: eth0: Gained IPv6LL2518clare # [ 10.944828] systemd-networkd[541]: eth1: Gained IPv6LL2519alice # % Total % Received % Xferd Average Speed Time Time Time Current2520alice # Dload Upload Total Spent Left Speed2521bob # [ 11.393084] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:37042","trace_id":"ee700b5f69da125bd52b816712ade30f","span_id":"94055b88b86a8b74","status":200,"elapsed":0.0678,"bytes":50,"time":"2026-10-13T15:41:22Z","message":"handled request"}2522alice # 0 0 0 0 0 0 0 0 00:01 0100 50 100 50 0 0 47 0 00:01 00:01 0100 50 100 50 0 0 47 0 00:01 00:01 0100 50 100 50 0 0 47 0 00:01 00:01 02523alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 1.13 seconds)2524bob: waiting for success: curl clare:5000/nix-cache-info2525bob # [ 11.428147] systemd-networkd[556]: eth1: Gained IPv6LL2526bob # % Total % Received % Xferd Average Speed Time Time Time Current2527bob # Dload Upload Total Spent Left Speed2528bob # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 3027 0 0100 51 100 51 0 0 2625 0 0100 51 100 51 0 0 2321 0 02529bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.10 seconds)2530clare: waiting for unit harmonia2531bob # [ 11.570615] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: 2026/10/13 15:41:22 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host2532clare: (finished: waiting for unit harmonia, in 0.06 seconds)2533clare: must succeed: systemctl status harmonia2534clare: (finished: must succeed: systemctl status harmonia, in 0.02 seconds)2535clare: must succeed: nix-build --expr '2536 builtins.derivation {2537 name = "hi";2538 builder = "/bin/sh";2539 args = [ "-c" "echo hi > $out" ];2540 system = "aarch64-linux";2541 }2542' 2>&125432544clare # [ 12.004548] systemd-resolved[309]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3.2545clare # [ 12.009119] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2546clare # [ 12.011673] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2547clare # [ 12.565597] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2548clare # [ 12.569698] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2549clare # [ 13.615681] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2550clare # [ 13.619654] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2551clare # [ 16.329656] systemd-resolved[309]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2552clare # [ 16.332882] systemd-resolved[309]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2553clare: (finished: must succeed: nix-build --expr '2554 builtins.derivation {2555 name = "hi";2556 builder = "/bin/sh";2557 args = [ "-c" "echo hi > $out" ];2558 system = "aarch64-linux";2559 }2560' 2>&12561, in 4.85 seconds)2562alice: must succeed: nix-build --expr '2563 builtins.derivation {2564 name = "hi";2565 builder = "/bin/sh";2566 args = [ "-c" "echo hi > $out" ];2567 system = "aarch64-linux";2568 }2569' 2>&125702571bob # [ 16.573244] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:38088","trace_id":"577a70a294a30c199e9eaa34da489abb","span_id":"41067f4c107de6f9","status":200,"elapsed":0.03088,"bytes":50,"time":"2026-10-13T15:41:27Z","message":"handled request"}2572alice # [ 16.582297] systemd-resolved[310]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3.2573alice # [ 16.845626] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2574alice # [ 16.849755] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2575alice # [ 17.362386] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2576alice # [ 17.366749] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2577alice # [ 18.667664] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2578alice # [ 18.669683] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2579alice # [ 20.909979] systemd-resolved[310]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2580alice # [ 20.914496] systemd-resolved[310]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2581bob # [ 20.916680] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:38088","trace_id":"3b45ad0ad03ff6f331adc604fea3081b","span_id":"83aa9e37228c86e6","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-13T15:41:31Z","message":"download the narinfo from upstream"}2582bob # [ 20.936510] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:38088","trace_id":"3b45ad0ad03ff6f331adc604fea3081b","span_id":"83aa9e37228c86e6","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-13T15:41:31Z","message":"downloading the nar from upstream"}2583bob # [ 20.944651] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:38088","trace_id":"3b45ad0ad03ff6f331adc604fea3081b","span_id":"83aa9e37228c86e6","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-13T15:41:31Z","message":"download the nar from upstream"}2584bob # [ 20.957976] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:38088","trace_id":"3b45ad0ad03ff6f331adc604fea3081b","span_id":"83aa9e37228c86e6","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":7.56676,"time":"2026-10-13T15:41:31Z","message":"download of nar complete"}2585bob # [ 20.962713] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:38088","trace_id":"3b45ad0ad03ff6f331adc604fea3081b","span_id":"83aa9e37228c86e6","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-10-13T15:41:31Z","message":"storing narinfo and nar_file record in the database"}2586bob # [ 20.968102] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:38088","trace_id":"3b45ad0ad03ff6f331adc604fea3081b","span_id":"83aa9e37228c86e6","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":41.1296,"time":"2026-10-13T15:41:31Z","message":"download of narinfo complete"}2587bob # [ 20.972228] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:38088","trace_id":"3b45ad0ad03ff6f331adc604fea3081b","span_id":"83aa9e37228c86e6","status":200,"elapsed":42.48192,"bytes":532,"time":"2026-10-13T15:41:31Z","message":"handled request"}2588bob # [ 20.975168] 70ya7n6byxa3fdhyxaknx3sks1f7mmwi-ncps-wrapper[585]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:38088","trace_id":"24f2f7b30edb57201b5d5b9b99e4d8ef","span_id":"65c0bccd7f13b411","status":200,"elapsed":0.42092,"bytes":95,"time":"2026-10-13T15:41:31Z","message":"handled request"}2589alice: (finished: must succeed: nix-build --expr '2590 builtins.derivation {2591 name = "hi";2592 builder = "/bin/sh";2593 args = [ "-c" "echo hi > $out" ];2594 system = "aarch64-linux";2595 }2596' 2>&12597, in 4.54 seconds)2598(finished: run the VM test script, in 21.71 seconds)2599test script finished in 24.51s2600cleanup2601kill QemuMachine (pid 48)2602alice # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2603alice # [2026-10-13T15:41:34Z INFO virtiofsd] Client disconnected, shutting down2604alice # [2026-10-13T15:41:34Z INFO virtiofsd] Client disconnected, shutting down2605alice # [2026-10-13T15:41:34Z INFO virtiofsd] Client disconnected, shutting down2606kill QemuMachine (pid 49)2607bob # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2608bob # [2026-10-13T15:41:34Z INFO virtiofsd] Client disconnected, shutting down2609bob # [2026-10-13T15:41:34Z INFO virtiofsd] Client disconnected, shutting down2610bob # [2026-10-13T15:41:34Z INFO virtiofsd] Client disconnected, shutting down2611kill QemuMachine (pid 50)2612clare # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)2613clare # [2026-10-13T15:41:34Z INFO virtiofsd] Client disconnected, shutting down2614clare # [2026-10-13T15:41:34Z INFO virtiofsd] Client disconnected, shutting down2615clare # [2026-10-13T15:41:34Z INFO virtiofsd] Client disconnected, shutting down2616(finished: cleanup, in 0.41 seconds)