vm-test-run-ncps
default.checks.aarch64-linux.ncps
· build #354
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600.0 seconds5run the VM test script6additionally exposed symbols:7 alice, bob, clare,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10start all VMs11alice: starting vm12bob: starting vm13clare: starting vm14bob # Disk image does not exist, creating the virtualisation disk image...15bob: QEMU running (pid 50)16clare # Disk image does not exist, creating the virtualisation disk image...17clare: QEMU running (pid 49)18bob # Formatting '/build/vm-state-bob/tmp.dObabFZcwr', fmt=raw size=107374182419clare # Formatting '/build/vm-state-clare/tmp.muP51TNl3Q', fmt=raw size=107374182420alice: QEMU running (pid 48)21alice # Disk image does not exist, creating the virtualisation disk image...22bob # mke2fs 1.47.4 (6-Mar-2025)23alice # Formatting '/build/vm-state-alice/tmp.z80iPwJgWi', fmt=raw size=107374182424bob # Discarding device blocks: 0/262144 done25(finished: start all VMs, in 0.71 seconds)26clare # mke2fs 1.47.4 (6-Mar-2025)27bob # Creating filesystem with 262144 4k blocks and 65536 inodes28bob: waiting for unit ncps29alice # mke2fs 1.47.4 (6-Mar-2025)30clare # Discarding device blocks: 0/262144 done31bob # Filesystem UUID: 69b6f673-0dd1-4751-8d10-fd92a340c62d32bob: waiting for the VM to finish booting33alice # Discarding device blocks: 0/262144 done34clare # Creating filesystem with 262144 4k blocks and 65536 inodes35alice # Creating filesystem with 262144 4k blocks and 65536 inodes36clare # Filesystem UUID: 910c745b-bf59-4342-b6c9-01ce2fbc03bf37alice # Filesystem UUID: 0f86b075-5202-4980-84fa-7f8536b8263138clare # Superblock backups stored on blocks:39alice # Superblock backups stored on blocks:40clare # 32768, 98304, 163840, 22937641alice # 32768, 98304, 163840, 22937642clare # 43alice # 44clare # Allocating group tables: 0/8 done45alice # Allocating group tables: 0/8 done46clare # Writing inode tables: 0/8 done47alice # Writing inode tables: 0/8 done48clare # Creating journal (8192 blocks): done49alice # Creating journal (8192 blocks): done50clare # Writing superblocks and filesystem accounting information: 0/8 done51alice # Writing superblocks and filesystem accounting information: 0/8 done52clare # 53alice # 54clare # Virtualisation disk image created.55alice # Virtualisation disk image created.56bob # Superblock backups stored on blocks:57bob # 32768, 98304, 163840, 22937658bob # 59bob # Allocating group tables: 0/8 done60bob # Writing inode tables: 0/8 done61bob # Creating journal (8192 blocks): done62bob # Writing superblocks and filesystem accounting information: 0/8 done63bob # 64bob # Virtualisation disk image created.65bob # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]66bob # [ 0.000000] Linux version 6.18.44 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sun Aug 9 18:25:30 UTC 202667bob # [ 0.000000] KASLR enabled68bob # [ 0.000000] random: crng init done69bob # [ 0.000000] Machine model: linux,dummy-virt70bob # [ 0.000000] efi: UEFI not found.71bob # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT72bob # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]73bob # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]74bob # [ 0.000000] Zone ranges:75bob # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]76bob # [ 0.000000] DMA32 empty77bob # [ 0.000000] Normal empty78bob # [ 0.000000] Device empty79bob # [ 0.000000] Movable zone start for each node80bob # [ 0.000000] Early memory node ranges81bob # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]82bob # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]83bob # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000084bob # [ 0.000000] psci: probing for conduit method from DT.85bob # [ 0.000000] psci: PSCIv1.3 detected in firmware.86bob # [ 0.000000] psci: Using standard PSCI v0.2 function IDs87bob # [ 0.000000] psci: Trusted OS migration not required88bob # [ 0.000000] psci: SMC Calling Convention v1.189bob # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)90bob # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u31129691bob # [ 0.000000] Detected PIPT I-cache on CPU092bob # [ 0.000000] CPU features: detected: GICv3 CPU interface93bob # [ 0.000000] CPU features: detected: Spectre-v494bob # [ 0.000000] CPU features: detected: Spectre-BHB95bob # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR96bob # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)97bob # [ 0.000000] CPU features: detected: ARM erratum 141804098bob # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion99bob # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing100bob # [ 0.000000] alternatives: applying boot alternatives101bob # [ 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/sf5arp0mfbv548i83h2xqs2pa3hzimr3-nixos-system-bob-test/init regInfo=/nix/store/96znmjcanh79wqlk72kpnphb2kp5zg03-closure-info/registration console=ttyAMA0,115200n8 console=tty0102bob # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/96znmjcanh79wqlk72kpnphb2kp5zg03-closure-info/registration", will be passed to user space.103bob # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes104bob # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)105bob # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)106bob # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB107bob # [ 0.000000] software IO TLB: area num 1.108bob # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)109bob # [ 0.000000] Fallback order for Node 0: 0110bob # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144111bob # [ 0.000000] Policy zone: DMA112bob # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off113bob # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1114bob # [ 0.000000] allocated 2097152 bytes of page_ext115bob # [ 0.000000] ftrace: allocating 74867 entries in 294 pages116bob # [ 0.000000] ftrace: allocated 294 pages with 4 groups117bob # [ 0.000000] rcu: Hierarchical RCU implementation.118bob # [ 0.000000] rcu: RCU event tracing is enabled.119bob # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.120bob # [ 0.000000] Trampoline variant of Tasks RCU enabled.121bob # [ 0.000000] Rude variant of Tasks RCU enabled.122bob # [ 0.000000] Tracing variant of Tasks RCU enabled.123bob # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.124bob # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1125bob # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.126clare # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]127bob # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.128bob # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.129clare # [ 0.000000] Linux version 6.18.44 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sun Aug 9 18:25:30 UTC 2026130clare # [ 0.000000] KASLR enabled131bob # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0132clare # [ 0.000000] random: crng init done133bob # [ 0.000000] GICv3: 256 SPIs implemented134clare # [ 0.000000] Machine model: linux,dummy-virt135bob # [ 0.000000] GICv3: 0 Extended SPIs implemented136clare # [ 0.000000] efi: UEFI not found.137bob # [ 0.000000] Root IRQ handler: gic_handle_irq138clare # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT139bob # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI140bob # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0141clare # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]142bob # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000143clare # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]144clare # [ 0.000000] Zone ranges:145bob # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]146clare # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]147clare # [ 0.000000] DMA32 empty148bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)149clare # [ 0.000000] Normal empty150clare # [ 0.000000] Device empty151bob # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)152clare # [ 0.000000] Movable zone start for each node153clare # [ 0.000000] Early memory node ranges154bob # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000155clare # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]156bob # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000157clare # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]158bob # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.159clare # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000160clare # [ 0.000000] psci: probing for conduit method from DT.161bob # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns162clare # [ 0.000000] psci: PSCIv1.3 detected in firmware.163bob # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040164clare # [ 0.000000] psci: Using standard PSCI v0.2 function IDs165bob # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).166clare # [ 0.000000] psci: Trusted OS migration not required167clare # [ 0.000000] psci: SMC Calling Convention v1.1168bob # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns169clare # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)170bob # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns171clare # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296172bob # [ 0.000071] arm-pv: using stolen time PV173clare # [ 0.000000] Detected PIPT I-cache on CPU0174clare # [ 0.000000] CPU features: detected: GICv3 CPU interface175bob # [ 0.000457] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)176clare # [ 0.000000] CPU features: detected: Spectre-v4177bob # [ 0.000754] Console: colour dummy device 80x25178clare # [ 0.000000] CPU features: detected: Spectre-BHB179bob # [ 0.000772] printk: legacy console [tty0] enabled180clare # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR181bob # [ 0.000998] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)182clare # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)183bob # [ 0.001004] pid_max: default: 32768 minimum: 301184clare # [ 0.000000] CPU features: detected: ARM erratum 1418040185bob # [ 0.001103] LSM: initializing lsm=capability,landlock,yama,bpf,ima186bob # [ 0.001243] landlock: Up and running.187clare # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion188bob # [ 0.001245] Yama: becoming mindful.189bob # [ 0.001685] LSM support for eBPF active190clare # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing191clare # [ 0.000000] alternatives: applying boot alternatives192bob # [ 0.001860] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)193bob # [ 0.001879] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)194bob # [ 0.004268] cacheinfo: Unable to detect cache hierarchy for CPU 0195bob # [ 0.005035] rcu: Hierarchical SRCU implementation.196bob # [ 0.005039] rcu: Max phase no-delay instances is 1000.197bob # [ 0.006000] fsl-mc MSI: its@8080000 domain created198clare # [ 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/4qnx8r25v3a5p5minkr499yps30grib6-nixos-system-clare-test/init regInfo=/nix/store/g1yf0b6383bpgh8wfxfq3r1ci7jr9bp7-closure-info/registration console=ttyAMA0,115200n8 console=tty0199bob # [ 0.006180] EFI services will not be available.200bob # [ 0.006276] smp: Bringing up secondary CPUs ...201bob # [ 0.006306] smp: Brought up 1 node, 1 CPU202clare # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/g1yf0b6383bpgh8wfxfq3r1ci7jr9bp7-closure-info/registration", will be passed to user space.203bob # [ 0.006310] SMP: Total of 1 processors activated.204bob # [ 0.006312] CPU: All CPU(s) started at EL1205clare # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes206bob # [ 0.006325] CPU features: detected: 32-bit EL0 Support207clare # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)208bob # [ 0.006328] CPU features: detected: Data cache clean to the PoU not required for I/D coherence209clare # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)210bob # [ 0.006331] CPU features: detected: Common not Private translations211alice # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]212clare # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB213bob # [ 0.006333] CPU features: detected: CRC32 instructions214clare # [ 0.000000] software IO TLB: area num 1.215bob # [ 0.006335] CPU features: detected: Data cache clean to Point of Persistence216clare # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)217alice # [ 0.000000] Linux version 6.18.44 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sun Aug 9 18:25:30 UTC 2026218bob # [ 0.006338] CPU features: detected: Enhanced Virtualization Traps219alice # [ 0.000000] KASLR enabled220clare # [ 0.000000] Fallback order for Node 0: 0221bob # [ 0.006349] CPU features: detected: RCpc load-acquire (LDAPR)222alice # [ 0.000000] random: crng init done223clare # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144224alice # [ 0.000000] Machine model: linux,dummy-virt225bob # [ 0.006351] CPU features: detected: LSE atomic instructions226clare # [ 0.000000] Policy zone: DMA227alice # [ 0.000000] efi: UEFI not found.228bob # [ 0.006353] CPU features: detected: Privileged Access Never229clare # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off230alice # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT231bob # [ 0.006355] CPU features: detected: PMUv3232bob # [ 0.006357] CPU features: detected: RAS Extension Support233clare # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1234alice # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]235clare # [ 0.000000] allocated 2097152 bytes of page_ext236bob # [ 0.006361] CPU features: detected: Speculative Store Bypassing Safe (SSBS)237alice # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]238clare # [ 0.000000] ftrace: allocating 74867 entries in 294 pages239alice # [ 0.000000] Zone ranges:240bob # [ 0.006384] alternatives: applying system-wide alternatives241clare # [ 0.000000] ftrace: allocated 294 pages with 4 groups242alice # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]243alice # [ 0.000000] DMA32 empty244bob # [ 0.007851] CPU features: detected: Hardware dirty bit management on CPU0245clare # [ 0.000000] rcu: Hierarchical RCU implementation.246alice # [ 0.000000] Normal empty247clare # [ 0.000000] rcu: RCU event tracing is enabled.248alice # [ 0.000000] Device empty249alice # [ 0.000000] Movable zone start for each node250clare # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.251alice # [ 0.000000] Early memory node ranges252bob # [ 0.008055] Memory: 891472K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 115796K reserved, 32768K cma-reserved)253clare # [ 0.000000] Trampoline variant of Tasks RCU enabled.254bob # [ 0.008578] devtmpfs: initialized255alice # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]256clare # [ 0.000000] Rude variant of Tasks RCU enabled.257bob # [ 0.010286] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)258clare # [ 0.000000] Tracing variant of Tasks RCU enabled.259alice # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]260alice # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000261bob # [ 0.010310] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).262clare # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.263alice # [ 0.000000] psci: probing for conduit method from DT.264bob # [ 0.010439] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL265clare # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1266alice # [ 0.000000] psci: PSCIv1.3 detected in firmware.267bob # [ 0.010443] 0 pages in range for non-PLT usage268alice # [ 0.000000] psci: Using standard PSCI v0.2 function IDs269bob # [ 0.010444] 508288 pages in range for PLT usage270clare # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.271alice # [ 0.000000] psci: Trusted OS migration not required272bob # [ 0.010577] pinctrl core: initialized pinctrl subsystem273bob # [ 0.011386] DMI not present or invalid.274alice # [ 0.000000] psci: SMC Calling Convention v1.1275clare # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.276bob # [ 0.015039] NET: Registered PF_NETLINK/PF_ROUTE protocol family277alice # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)278clare # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.279bob # [ 0.017525] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations280alice # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296281clare # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0282alice # [ 0.000000] Detected PIPT I-cache on CPU0283bob # [ 0.017670] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations284clare # [ 0.000000] GICv3: 256 SPIs implemented285alice # [ 0.000000] CPU features: detected: GICv3 CPU interface286clare # [ 0.000000] GICv3: 0 Extended SPIs implemented287alice # [ 0.000000] CPU features: detected: Spectre-v4288bob # [ 0.017822] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations289clare # [ 0.000000] Root IRQ handler: gic_handle_irq290alice # [ 0.000000] CPU features: detected: Spectre-BHB291bob # [ 0.017846] audit: initializing netlink subsys (disabled)292clare # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI293alice # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR294clare # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0295bob # [ 0.018565] thermal_sys: Registered thermal governor 'fair_share'296alice # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)297bob # [ 0.018567] thermal_sys: Registered thermal governor 'bang_bang'298clare # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000299alice # [ 0.000000] CPU features: detected: ARM erratum 1418040300clare # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]301bob # [ 0.018571] thermal_sys: Registered thermal governor 'step_wise'302alice # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion303bob # [ 0.018573] thermal_sys: Registered thermal governor 'user_space'304clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)305alice # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing306bob # [ 0.018575] thermal_sys: Registered thermal governor 'power_allocator'307alice # [ 0.000000] alternatives: applying boot alternatives308clare # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)309bob # [ 0.018617] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1310bob # [ 0.018635] cpuidle: using governor ladder311clare # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000312bob # [ 0.018640] cpuidle: using governor menu313clare # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000314bob # [ 0.018782] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.315clare # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.316bob # [ 0.018808] ASID allocator initialised with 32768 entries317bob # [ 0.019522] Serial: AMBA PL011 UART driver318alice # [ 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/hlkiw87fx747c4krnfxw0h114c74haci-nixos-system-alice-test/init regInfo=/nix/store/w1lkkn57159qcqy8l40c2ycz5yq4i21g-closure-info/registration console=ttyAMA0,115200n8 console=tty0319clare # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns320bob # [ 0.022898] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1321bob # [ 0.023090] printk: console [ttyAMA0] enabled322clare # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040323alice # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/w1lkkn57159qcqy8l40c2ycz5yq4i21g-closure-info/registration", will be passed to user space.324clare # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).325alice # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes326clare # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns327alice # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)328clare # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns329alice # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)330clare # [ 0.000080] arm-pv: using stolen time PV331alice # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB332alice # [ 0.000000] software IO TLB: area num 1.333clare # [ 0.000518] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)334clare # [ 0.000831] Console: colour dummy device 80x25335alice # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)336clare # [ 0.000849] printk: legacy console [tty0] enabled337alice # [ 0.000000] Fallback order for Node 0: 0338alice # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144339clare # [ 0.001085] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)340alice # [ 0.000000] Policy zone: DMA341clare # [ 0.001090] pid_max: default: 32768 minimum: 301342alice # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off343clare # [ 0.001166] LSM: initializing lsm=capability,landlock,yama,bpf,ima344alice # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1345clare # [ 0.001320] landlock: Up and running.346clare # [ 0.001323] Yama: becoming mindful.347alice # [ 0.000000] allocated 2097152 bytes of page_ext348clare # [ 0.001776] LSM support for eBPF active349alice # [ 0.000000] ftrace: allocating 74867 entries in 294 pages350bob # [ 0.092467] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages351clare # [ 0.001945] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)352alice # [ 0.000000] ftrace: allocated 294 pages with 4 groups353bob # [ 0.092492] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page354alice # [ 0.000000] rcu: Hierarchical RCU implementation.355clare # [ 0.001965] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)356alice # [ 0.000000] rcu: RCU event tracing is enabled.357bob # [ 0.092496] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages358clare # [ 0.004114] cacheinfo: Unable to detect cache hierarchy for CPU 0359bob # [ 0.092502] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page360alice # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.361clare # [ 0.004880] rcu: Hierarchical SRCU implementation.362alice # [ 0.000000] Trampoline variant of Tasks RCU enabled.363bob # [ 0.092505] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages364clare # [ 0.004885] rcu: Max phase no-delay instances is 1000.365alice # [ 0.000000] Rude variant of Tasks RCU enabled.366bob # [ 0.092508] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page367clare # [ 0.005900] fsl-mc MSI: its@8080000 domain created368alice # [ 0.000000] Tracing variant of Tasks RCU enabled.369bob # [ 0.092511] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages370clare # [ 0.006055] EFI services will not be available.371alice # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.372clare # [ 0.006153] smp: Bringing up secondary CPUs ...373bob # [ 0.092514] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page374clare # [ 0.006182] smp: Brought up 1 node, 1 CPU375alice # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1376clare # [ 0.006186] SMP: Total of 1 processors activated.377clare # [ 0.006188] CPU: All CPU(s) started at EL1378alice # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.379clare # [ 0.006202] CPU features: detected: 32-bit EL0 Support380alice # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.381bob # [ 0.097268] fbcon: Taking over console382clare # [ 0.006205] CPU features: detected: Data cache clean to the PoU not required for I/D coherence383bob # [ 0.097288] ACPI: Interpreter disabled.384alice # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.385bob # [ 0.098501] iommu: Default domain type: Translated386clare # [ 0.006208] CPU features: detected: Common not Private translations387alice # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0388bob # [ 0.098511] iommu: DMA domain TLB invalidation policy: strict mode389clare # [ 0.006210] CPU features: detected: CRC32 instructions390alice # [ 0.000000] GICv3: 256 SPIs implemented391alice # [ 0.000000] GICv3: 0 Extended SPIs implemented392clare # [ 0.006212] CPU features: detected: Data cache clean to Point of Persistence393bob # [ 0.099598] SCSI subsystem initialized394alice # [ 0.000000] Root IRQ handler: gic_handle_irq395clare # [ 0.006215] CPU features: detected: Enhanced Virtualization Traps396alice # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI397alice # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0398clare # [ 0.006225] CPU features: detected: RCpc load-acquire (LDAPR)399clare # [ 0.006228] CPU features: detected: LSE atomic instructions400alice # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000401alice # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]402clare # [ 0.006230] CPU features: detected: Privileged Access Never403clare # [ 0.006231] CPU features: detected: PMUv3404alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)405clare # [ 0.006233] CPU features: detected: RAS Extension Support406clare # [ 0.006237] CPU features: detected: Speculative Store Bypassing Safe (SSBS)407alice # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)408clare # [ 0.006261] alternatives: applying system-wide alternatives409alice # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000410clare # [ 0.007819] CPU features: detected: Hardware dirty bit management on CPU0411alice # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000412alice # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.413clare # [ 0.008131] Memory: 891460K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 115792K reserved, 32768K cma-reserved)414clare # [ 0.008608] devtmpfs: initialized415bob # [ 0.104524] usbcore: registered new interface driver usbfs416alice # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns417bob # [ 0.104566] usbcore: registered new interface driver hub418clare # [ 0.010168] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)419alice # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040420bob # [ 0.104577] usbcore: registered new device driver usb421clare # [ 0.010192] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).422bob # [ 0.104794] pps_core: LinuxPPS API ver. 1 registered423alice # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).424clare # [ 0.010340] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL425alice # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns426bob # [ 0.104801] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>427clare # [ 0.010344] 0 pages in range for non-PLT usage428bob # [ 0.104815] PTP clock support registered429bob # [ 0.104847] EDAC MC: Ver: 3.0.0430clare # [ 0.010344] 508288 pages in range for PLT usage431alice # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns432alice # [ 0.000069] arm-pv: using stolen time PV433clare # [ 0.010474] pinctrl core: initialized pinctrl subsystem434clare # [ 0.011296] DMI not present or invalid.435bob # [ 0.107788] scmi_core: SCMI protocol bus registered436alice # [ 0.000367] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)437clare # [ 0.014943] NET: Registered PF_NETLINK/PF_ROUTE protocol family438alice # [ 0.000624] Console: colour dummy device 80x25439clare # [ 0.017347] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations440alice # [ 0.000641] printk: legacy console [tty0] enabled441bob # [ 0.108519] FPGA manager framework442bob # [ 0.109521] vgaarb: loaded443clare # [ 0.017485] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations444alice # [ 0.000857] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)445clare # [ 0.017638] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations446alice # [ 0.000862] pid_max: default: 32768 minimum: 301447clare # [ 0.017660] audit: initializing netlink subsys (disabled)448bob # [ 0.109927] clocksource: Switched to clocksource arch_sys_counter449alice # [ 0.000935] LSM: initializing lsm=capability,landlock,yama,bpf,ima450alice # [ 0.001089] landlock: Up and running.451clare # [ 0.018263] thermal_sys: Registered thermal governor 'fair_share'452bob # [ 0.110495] VFS: Disk quotas dquot_6.6.0453alice # [ 0.001092] Yama: becoming mindful.454alice # [ 0.001491] LSM support for eBPF active455bob # [ 0.110525] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)456clare # [ 0.018265] thermal_sys: Registered thermal governor 'bang_bang'457clare # [ 0.018268] thermal_sys: Registered thermal governor 'step_wise'458alice # [ 0.001662] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)459clare # [ 0.018270] thermal_sys: Registered thermal governor 'user_space'460bob # [ 0.112211] netfs: FS-Cache loaded461alice # [ 0.001681] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)462bob # [ 0.112343] pnp: PnP ACPI: disabled463clare # [ 0.018272] thermal_sys: Registered thermal governor 'power_allocator'464alice # [ 0.003425] cacheinfo: Unable to detect cache hierarchy for CPU 0465alice # [ 0.004238] rcu: Hierarchical SRCU implementation.466clare # [ 0.018306] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1467clare # [ 0.018321] cpuidle: using governor ladder468alice # [ 0.004243] rcu: Max phase no-delay instances is 1000.469clare # [ 0.018326] cpuidle: using governor menu470alice # [ 0.005167] fsl-mc MSI: its@8080000 domain created471alice # [ 0.005313] EFI services will not be available.472clare # [ 0.018462] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.473alice # [ 0.005403] smp: Bringing up secondary CPUs ...474clare # [ 0.018484] ASID allocator initialised with 32768 entries475alice # [ 0.005450] smp: Brought up 1 node, 1 CPU476clare # [ 0.019177] Serial: AMBA PL011 UART driver477alice # [ 0.005455] SMP: Total of 1 processors activated.478alice # [ 0.005457] CPU: All CPU(s) started at EL1479clare # [ 0.022474] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1480alice # [ 0.005468] CPU features: detected: 32-bit EL0 Support481clare # [ 0.022660] printk: console [ttyAMA0] enabled482alice # [ 0.005471] CPU features: detected: Data cache clean to the PoU not required for I/D coherence483alice # [ 0.005474] CPU features: detected: Common not Private translations484alice # [ 0.005476] CPU features: detected: CRC32 instructions485alice # [ 0.005478] CPU features: detected: Data cache clean to Point of Persistence486alice # [ 0.005481] CPU features: detected: Enhanced Virtualization Traps487alice # [ 0.005490] CPU features: detected: RCpc load-acquire (LDAPR)488alice # [ 0.005493] CPU features: detected: LSE atomic instructions489alice # [ 0.005494] CPU features: detected: Privileged Access Never490alice # [ 0.005496] CPU features: detected: PMUv3491alice # [ 0.005498] CPU features: detected: RAS Extension Support492alice # [ 0.005502] CPU features: detected: Speculative Store Bypassing Safe (SSBS)493alice # [ 0.005523] alternatives: applying system-wide alternatives494alice # [ 0.006956] CPU features: detected: Hardware dirty bit management on CPU0495alice # [ 0.007196] Memory: 891444K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 115796K reserved, 32768K cma-reserved)496alice # [ 0.007648] devtmpfs: initialized497alice # [ 0.009143] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)498alice # [ 0.009165] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).499alice # [ 0.009287] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL500alice # [ 0.009290] 0 pages in range for non-PLT usage501clare # [ 0.096816] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages502alice # [ 0.009291] 508288 pages in range for PLT usage503clare # [ 0.096840] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page504alice # [ 0.009430] pinctrl core: initialized pinctrl subsystem505alice # [ 0.010202] DMI not present or invalid.506clare # [ 0.096845] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages507bob # [ 0.119789] NET: Registered PF_INET protocol family508alice # [ 0.013543] NET: Registered PF_NETLINK/PF_ROUTE protocol family509clare # [ 0.096851] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page510bob # [ 0.119981] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)511alice # [ 0.015820] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations512clare # [ 0.096854] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages513alice # [ 0.015956] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations514clare # [ 0.096857] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page515alice # [ 0.016135] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations516clare # [ 0.096860] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages517alice # [ 0.016154] audit: initializing netlink subsys (disabled)518clare # [ 0.096863] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page519alice # [ 0.016720] thermal_sys: Registered thermal governor 'fair_share'520alice # [ 0.016723] thermal_sys: Registered thermal governor 'bang_bang'521alice # [ 0.016726] thermal_sys: Registered thermal governor 'step_wise'522clare # [ 0.102020] fbcon: Taking over console523alice # [ 0.016728] thermal_sys: Registered thermal governor 'user_space'524clare # [ 0.102041] ACPI: Interpreter disabled.525clare # [ 0.103339] iommu: Default domain type: Translated526alice # [ 0.016730] thermal_sys: Registered thermal governor 'power_allocator'527clare # [ 0.103349] iommu: DMA domain TLB invalidation policy: strict mode528alice # [ 0.016765] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1529alice # [ 0.016780] cpuidle: using governor ladder530clare # [ 0.104545] SCSI subsystem initialized531alice # [ 0.016785] cpuidle: using governor menu532alice # [ 0.016929] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.533alice # [ 0.016950] ASID allocator initialised with 32768 entries534clare # [ 0.105123] usbcore: registered new interface driver usbfs535alice # [ 0.017638] Serial: AMBA PL011 UART driver536clare # [ 0.105168] usbcore: registered new interface driver hub537clare # [ 0.105177] usbcore: registered new device driver usb538alice # [ 0.020755] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1539alice # [ 0.020907] printk: console [ttyAMA0] enabled540clare # [ 0.105367] pps_core: LinuxPPS API ver. 1 registered541clare # [ 0.105373] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>542clare # [ 0.105379] PTP clock support registered543clare # [ 0.105409] EDAC MC: Ver: 3.0.0544clare # [ 0.112723] scmi_core: SCMI protocol bus registered545clare # [ 0.113458] FPGA manager framework546alice # [ 0.092912] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages547clare # [ 0.114545] vgaarb: loaded548alice # [ 0.092936] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page549alice # [ 0.092940] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages550alice # [ 0.092946] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page551clare # [ 0.114993] clocksource: Switched to clocksource arch_sys_counter552alice # [ 0.092949] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages553clare # [ 0.115575] VFS: Disk quotas dquot_6.6.0554alice # [ 0.092953] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page555clare # [ 0.115608] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)556alice # [ 0.092956] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages557alice # [ 0.092959] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page558clare # [ 0.117481] netfs: FS-Cache loaded559clare # [ 0.117614] pnp: PnP ACPI: disabled560alice # [ 0.097879] fbcon: Taking over console561alice # [ 0.097900] ACPI: Interpreter disabled.562alice # [ 0.099094] iommu: Default domain type: Translated563alice # [ 0.099104] iommu: DMA domain TLB invalidation policy: strict mode564alice # [ 0.100217] SCSI subsystem initialized565alice # [ 0.100749] usbcore: registered new interface driver usbfs566alice # [ 0.100789] usbcore: registered new interface driver hub567alice # [ 0.100804] usbcore: registered new device driver usb568bob # [ 0.148477] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)569bob # [ 0.148507] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)570bob # [ 0.148522] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)571bob # [ 0.148558] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)572bob # [ 0.148627] TCP: Hash tables configured (established 8192 bind 8192)573bob # [ 0.148724] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)574bob # [ 0.148788] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)575bob # [ 0.148840] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)576alice # [ 0.109094] pps_core: LinuxPPS API ver. 1 registered577bob # [ 0.148961] NET: Registered PF_UNIX/PF_LOCAL protocol family578bob # [ 0.149020] NET: Registered PF_XDP protocol family579alice # [ 0.109109] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>580bob # [ 0.149039] PCI: CLS 0 bytes, default 64581clare # [ 0.124871] NET: Registered PF_INET protocol family582alice # [ 0.109124] PTP clock support registered583alice # [ 0.109157] EDAC MC: Ver: 3.0.0584bob # [ 0.149258] Trying to unpack rootfs image as initramfs...585clare # [ 0.125073] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)586alice # [ 0.110911] scmi_core: SCMI protocol bus registered587alice # [ 0.111591] FPGA manager framework588alice # [ 0.112639] vgaarb: loaded589alice # [ 0.113085] clocksource: Switched to clocksource arch_sys_counter590alice # [ 0.113666] VFS: Disk quotas dquot_6.6.0591alice # [ 0.113698] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)592alice # [ 0.115148] netfs: FS-Cache loaded593alice # [ 0.115271] pnp: PnP ACPI: disabled594bob # [ 0.159617] kvm [1]: HYP mode not available595alice # [ 0.123519] NET: Registered PF_INET protocol family596alice # [ 0.123723] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)597clare # [ 0.154107] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)598clare # [ 0.154144] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)599clare # [ 0.154160] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)600clare # [ 0.154198] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)601clare # [ 0.154267] TCP: Hash tables configured (established 8192 bind 8192)602clare # [ 0.154397] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)603clare # [ 0.154463] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)604clare # [ 0.154517] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)605clare # [ 0.154643] NET: Registered PF_UNIX/PF_LOCAL protocol family606clare # [ 0.154724] NET: Registered PF_XDP protocol family607clare # [ 0.154743] PCI: CLS 0 bytes, default 64608clare # [ 0.160376] Trying to unpack rootfs image as initramfs...609clare # [ 0.163609] kvm [1]: HYP mode not available610alice # [ 0.149586] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)611alice # [ 0.149621] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)612alice # [ 0.149636] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)613alice # [ 0.149677] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)614alice # [ 0.149745] TCP: Hash tables configured (established 8192 bind 8192)615alice # [ 0.149883] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)616alice # [ 0.149915] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)617alice # [ 0.149946] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)618alice # [ 0.150078] NET: Registered PF_UNIX/PF_LOCAL protocol family619alice # [ 0.150124] NET: Registered PF_XDP protocol family620alice # [ 0.150141] PCI: CLS 0 bytes, default 64621alice # [ 0.155763] Trying to unpack rootfs image as initramfs...622alice # [ 0.157857] kvm [1]: HYP mode not available623bob # [ 0.226048] Initialise system trusted keyrings624bob # [ 0.226576] workingset: timestamp_bits=42 max_order=18 bucket_order=0625bob # [ 0.227490] squashfs: version 4.0 (2009/01/31) Phillip Lougher626bob # [ 0.233992] 9p: Installing v9fs 9p2000 file system support627bob # [ 0.252980] Key type asymmetric registered628bob # [ 0.253012] Asymmetric key parser 'x509' registered629bob # [ 0.253083] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)630bob # [ 0.254486] io scheduler mq-deadline registered631bob # [ 0.254497] io scheduler kyber registered632clare # [ 0.239624] Initialise system trusted keyrings633clare # [ 0.240180] workingset: timestamp_bits=42 max_order=18 bucket_order=0634clare # [ 0.241179] squashfs: version 4.0 (2009/01/31) Phillip Lougher635bob # [ 0.262170] pl061_gpio 9030000.pl061: PL061 GPIO chip registered636bob # [ 0.262558] ledtrig-cpu: registered to indicate activity on CPUs637clare # [ 0.241691] 9p: Installing v9fs 9p2000 file system support638bob # [ 0.262862] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:639bob # [ 0.262882] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000640bob # [ 0.262902] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000641bob # [ 0.262908] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000642bob # [ 0.262943] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits643bob # [ 0.262973] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]644bob # [ 0.263047] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00645bob # [ 0.263066] pci_bus 0000:00: root bus resource [bus 00-ff]646bob # [ 0.263071] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]647bob # [ 0.263075] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]648bob # [ 0.263079] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]649bob # [ 0.263212] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint650bob # [ 0.263627] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint651bob # [ 0.263749] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]652bob # [ 0.263760] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]653bob # [ 0.263782] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]654bob # [ 0.263798] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]655bob # [ 0.264093] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint656bob # [ 0.264210] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]657bob # [ 0.264221] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]658bob # [ 0.264241] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]659bob # [ 0.264524] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint660alice # [ 0.233486] Initialise system trusted keyrings661bob # [ 0.264646] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]662alice # [ 0.233994] workingset: timestamp_bits=42 max_order=18 bucket_order=0663bob # [ 0.264657] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]664bob # [ 0.264678] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]665alice # [ 0.234940] squashfs: version 4.0 (2009/01/31) Phillip Lougher666bob # [ 0.264961] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint667bob # [ 0.265076] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]668bob # [ 0.265087] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]669bob # [ 0.265108] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]670alice # [ 0.235433] 9p: Installing v9fs 9p2000 file system support671bob # [ 0.265396] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint672bob # [ 0.265513] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]673bob # [ 0.265524] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]674bob # [ 0.265544] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]675bob # [ 0.265827] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint676bob # [ 0.265966] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]677bob # [ 0.265977] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]678clare # [ 0.261103] Key type asymmetric registered679bob # [ 0.265997] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]680clare # [ 0.261129] Asymmetric key parser 'x509' registered681bob # [ 0.266294] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint682clare # [ 0.261201] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)683bob # [ 0.266414] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]684bob # [ 0.266425] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]685bob # [ 0.266445] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]686bob # [ 0.266459] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]687bob # [ 0.266785] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint688bob # [ 0.266904] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]689bob # [ 0.266924] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]690bob # [ 0.267229] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint691bob # [ 0.267349] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]692bob # [ 0.267368] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]693clare # [ 0.267159] io scheduler mq-deadline registered694bob # [ 0.267625] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint695clare # [ 0.267180] io scheduler kyber registered696bob # [ 0.267742] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]697bob # [ 0.267907] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint698bob # [ 0.268114] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]699bob # [ 0.268127] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]700bob # [ 0.268148] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]701bob # [ 0.268438] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint702clare # [ 0.270170] pl061_gpio 9030000.pl061: PL061 GPIO chip registered703bob # [ 0.268556] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]704clare # [ 0.270981] ledtrig-cpu: registered to indicate activity on CPUs705bob # [ 0.268567] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]706bob # [ 0.268586] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]707bob # [ 0.268995] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned708bob # [ 0.269006] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned709bob # [ 0.269011] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned710bob # [ 0.269045] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned711bob # [ 0.269078] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned712bob # [ 0.269109] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned713clare # [ 0.271788] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:714bob # [ 0.269140] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned715alice # [ 0.254803] Key type asymmetric registered716clare # [ 0.271815] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000717alice # [ 0.254829] Asymmetric key parser 'x509' registered718bob # [ 0.269170] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned719clare # [ 0.271836] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000720alice # [ 0.254902] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)721bob # [ 0.269202] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned722clare # [ 0.271842] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000723bob # [ 0.269234] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned724clare # [ 0.271878] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits725bob # [ 0.269265] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned726clare # [ 0.271906] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]727bob # [ 0.269296] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned728clare # [ 0.271985] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00729bob # [ 0.269368] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned730clare # [ 0.272004] pci_bus 0000:00: root bus resource [bus 00-ff]731bob # [ 0.269400] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned732clare # [ 0.272009] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]733bob # [ 0.269414] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned734clare # [ 0.272013] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]735bob # [ 0.269427] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned736clare # [ 0.272017] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]737bob # [ 0.269440] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned738bob # [ 0.269454] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned739clare # [ 0.272118] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint740bob # [ 0.269467] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned741clare # [ 0.272565] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint742alice # [ 0.261240] io scheduler mq-deadline registered743alice # [ 0.261259] io scheduler kyber registered744bob # [ 0.269481] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned745clare # [ 0.272703] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]746clare # [ 0.272715] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]747bob # [ 0.269494] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned748bob # [ 0.269508] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned749clare # [ 0.272736] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]750clare # [ 0.272748] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]751bob # [ 0.269521] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned752bob # [ 0.269535] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned753clare # [ 0.273064] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint754clare # [ 0.273190] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]755bob # [ 0.269548] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned756alice # [ 0.264097] pl061_gpio 9030000.pl061: PL061 GPIO chip registered757clare # [ 0.273202] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]758bob # [ 0.269561] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned759bob # [ 0.269575] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned760clare # [ 0.273223] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]761alice # [ 0.264857] ledtrig-cpu: registered to indicate activity on CPUs762bob # [ 0.269588] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned763clare # [ 0.273537] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint764bob # [ 0.269602] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned765clare # [ 0.273662] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]766bob # [ 0.269615] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned767clare # [ 0.273674] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]768bob # [ 0.269633] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned769clare # [ 0.273694] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]770bob # [ 0.269646] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned771bob # [ 0.269659] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned772clare # [ 0.273997] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint773clare # [ 0.274121] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]774bob # [ 0.269673] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned775bob # [ 0.269690] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]776clare # [ 0.274132] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]777bob # [ 0.269696] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]778clare # [ 0.274154] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]779alice # [ 0.265673] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:780bob # [ 0.269700] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]781clare # [ 0.274484] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint782alice # [ 0.265698] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000783clare # [ 0.274610] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]784clare # [ 0.274622] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]785alice # [ 0.265719] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000786clare # [ 0.274643] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]787alice # [ 0.265725] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000788clare # [ 0.274957] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint789alice # [ 0.265758] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits790alice # [ 0.265786] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]791alice # [ 0.265858] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00792alice # [ 0.265876] pci_bus 0000:00: root bus resource [bus 00-ff]793alice # [ 0.265881] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]794alice # [ 0.265885] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]795alice # [ 0.265889] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]796alice # [ 0.265973] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint797alice # [ 0.266415] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint798alice # [ 0.266544] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]799alice # [ 0.266556] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]800alice # [ 0.266577] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]801alice # [ 0.266588] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]802alice # [ 0.266895] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint803alice # [ 0.267019] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]804bob # [ 0.326562] pci 0000:00:0a.0: enabling device (0000 -> 0002)805alice # [ 0.267030] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]806alice # [ 0.267051] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]807alice # [ 0.267356] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint808alice # [ 0.267481] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]809alice # [ 0.267492] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]810alice # [ 0.267513] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]811clare # [ 0.299185] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]812clare # [ 0.299212] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]813alice # [ 0.267823] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint814alice # [ 0.267946] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]815clare # [ 0.299234] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]816alice # [ 0.267958] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]817clare # [ 0.299620] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint818alice # [ 0.267979] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]819clare # [ 0.299748] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]820clare # [ 0.299761] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]821alice # [ 0.268290] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint822alice # [ 0.268413] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]823clare # [ 0.299782] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]824alice # [ 0.268425] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]825clare # [ 0.299799] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]826bob # [ 0.333043] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)827alice # [ 0.268446] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]828clare # [ 0.300158] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint829alice # [ 0.268742] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint830clare # [ 0.300288] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]831alice # [ 0.268865] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]832clare # [ 0.300310] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]833alice # [ 0.268876] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]834alice # [ 0.268897] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]835clare # [ 0.300635] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint836clare # [ 0.300765] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]837clare # [ 0.300787] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]838clare # [ 0.301055] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint839clare # [ 0.301177] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]840clare # [ 0.301373] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint841clare # [ 0.301602] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]842clare # [ 0.301617] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]843clare # [ 0.301637] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]844clare # [ 0.301967] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint845clare # [ 0.302094] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]846clare # [ 0.302105] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]847clare # [ 0.302126] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]848clare # [ 0.302560] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned849clare # [ 0.302572] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned850clare # [ 0.302577] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned851clare # [ 0.302613] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned852clare # [ 0.302646] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned853clare # [ 0.302678] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned854alice # [ 0.293358] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint855bob # [ 0.343063] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)856clare # [ 0.302710] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned857alice # [ 0.293516] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]858bob # [ 0.344875] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)859alice # [ 0.293529] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]860clare # [ 0.302743] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned861alice # [ 0.293550] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]862alice # [ 0.293567] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]863clare # [ 0.302774] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned864clare # [ 0.302807] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned865alice # [ 0.293882] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint866clare # [ 0.302838] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned867alice # [ 0.294010] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]868clare # [ 0.302869] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned869alice # [ 0.294032] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]870clare # [ 0.302952] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned871alice # [ 0.294353] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint872clare # [ 0.302986] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned873alice # [ 0.294482] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]874alice # [ 0.294504] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]875alice # [ 0.294773] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint876alice # [ 0.294893] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]877alice # [ 0.295068] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint878alice # [ 0.295344] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]879alice # [ 0.295359] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]880alice # [ 0.295379] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]881alice # [ 0.295693] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint882alice # [ 0.295817] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]883bob # [ 0.350906] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)884alice # [ 0.295829] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]885bob # [ 0.353135] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)886alice # [ 0.295849] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]887alice # [ 0.296241] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned888alice # [ 0.296252] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned889alice # [ 0.296257] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned890alice # [ 0.296291] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned891alice # [ 0.296324] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned892alice # [ 0.296356] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned893alice # [ 0.296388] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned894alice # [ 0.296420] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned895alice # [ 0.296452] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned896clare # [ 0.331111] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned897alice # [ 0.296483] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned898clare # [ 0.331140] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned899alice # [ 0.296514] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned900clare # [ 0.331156] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned901alice # [ 0.296545] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned902clare # [ 0.331174] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned903alice # [ 0.296626] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned904clare # [ 0.331190] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned905alice # [ 0.296657] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned906clare # [ 0.331206] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned907alice # [ 0.296671] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned908clare # [ 0.331222] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned909alice # [ 0.296686] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned910clare # [ 0.331238] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned911alice # [ 0.296701] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned912clare # [ 0.331254] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned913alice # [ 0.296715] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned914clare # [ 0.331269] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned915alice # [ 0.296729] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned916clare # [ 0.331285] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned917alice # [ 0.296743] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned918bob # [ 0.363076] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)919clare # [ 0.331301] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned920alice # [ 0.296758] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned921clare # [ 0.331315] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned922bob # [ 0.365427] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)923alice # [ 0.296771] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned924clare # [ 0.331329] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned925alice # [ 0.296786] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned926clare # [ 0.331344] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned927alice # [ 0.296801] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned928clare # [ 0.331358] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned929alice # [ 0.296815] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned930clare # [ 0.331378] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned931alice # [ 0.296829] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned932clare # [ 0.331392] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned933alice # [ 0.296843] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned934clare # [ 0.331406] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned935alice # [ 0.296857] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned936clare # [ 0.331419] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned937alice # [ 0.296871] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned938clare # [ 0.331445] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]939alice # [ 0.296886] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned940clare # [ 0.331452] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]941alice # [ 0.296906] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned942clare # [ 0.331456] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]943alice # [ 0.296920] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned944clare # [ 0.332222] pci 0000:00:0a.0: enabling device (0000 -> 0002)945alice # [ 0.296934] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned946alice # [ 0.296949] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned947alice # [ 0.296967] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]948alice # [ 0.296974] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]949alice # [ 0.296977] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]950bob # [ 0.375138] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)951bob # [ 0.377246] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)952clare # [ 0.349949] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)953bob # [ 0.380125] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)954alice # [ 0.333845] pci 0000:00:0a.0: enabling device (0000 -> 0002)955alice # [ 0.340234] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)956clare # [ 0.360119] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)957clare # [ 0.362941] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)958bob # [ 0.390163] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)959bob # [ 0.394233] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled960alice # [ 0.350384] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)961alice # [ 0.352338] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)962bob # [ 0.395749] msm_serial: driver initialized963clare # [ 0.372141] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)964bob # [ 0.395853] SuperH (H)SCI(F) driver initialized965bob # [ 0.395891] STM32 USART driver initialized966alice # [ 0.355247] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)967clare # [ 0.375664] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)968clare # [ 0.377484] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)969alice # [ 0.361227] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)970alice # [ 0.363076] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)971alice # [ 0.364871] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)972clare # [ 0.387582] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)973clare # [ 0.390101] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)974bob # [ 0.420469] loop: module loaded975bob # [ 0.420632] virtio_blk virtio5: 1/0/0 default/read/poll queues976bob # [ 0.421266] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)977clare # [ 0.393075] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)978clare # [ 0.394868] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)979alice # [ 0.374297] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)980alice # [ 0.376214] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)981bob # [ 0.426470] megasas: 07.734.00.00-rc1982bob # [ 0.426986] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]983bob # [ 0.428841] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000984bob # [ 0.428876] Intel/Sharp Extended Query Table at 0x0031985clare # [ 0.404585] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)986alice # [ 0.382071] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)987alice # [ 0.384482] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)988clare # [ 0.412518] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled989bob # [ 0.439152] Using buffer write method990bob # [ 0.439240] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]991clare # [ 0.414159] msm_serial: driver initialized992bob # [ 0.441025] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000993clare # [ 0.414238] SuperH (H)SCI(F) driver initialized994bob # [ 0.441052] Intel/Sharp Extended Query Table at 0x0031995clare # [ 0.414273] STM32 USART driver initialized996bob # [ 0.445009] Using buffer write method997bob # [ 0.445039] Concatenating MTD devices:998bob # [ 0.445042] (0): "0.flash"999bob # [ 0.445045] (1): "0.flash"1000bob # [ 0.445048] into device "0.flash"1001alice # [ 0.401881] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled1002alice # [ 0.403513] msm_serial: driver initialized1003alice # [ 0.403592] SuperH (H)SCI(F) driver initialized1004alice # [ 0.403628] STM32 USART driver initialized1005clare # [ 0.437773] loop: module loaded1006clare # [ 0.437931] virtio_blk virtio5: 1/0/0 default/read/poll queues1007clare # [ 0.438803] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1008alice # [ 0.426377] loop: module loaded1009alice # [ 0.426538] virtio_blk virtio5: 1/0/0 default/read/poll queues1010alice # [ 0.427167] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1011clare # [ 0.451504] megasas: 07.734.00.00-rc11012clare # [ 0.452055] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1013clare # [ 0.456376] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001014clare # [ 0.456416] Intel/Sharp Extended Query Table at 0x00311015clare # [ 0.458034] Using buffer write method1016clare # [ 0.458081] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1017alice # [ 0.433605] megasas: 07.734.00.00-rc11018alice # [ 0.434117] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1019alice # [ 0.438910] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001020alice # [ 0.438945] Intel/Sharp Extended Query Table at 0x00311021alice # [ 0.440699] Using buffer write method1022alice # [ 0.440766] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1023clare # [ 0.468745] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001024clare # [ 0.468779] Intel/Sharp Extended Query Table at 0x00311025clare # [ 0.470495] Using buffer write method1026clare # [ 0.470525] Concatenating MTD devices:1027clare # [ 0.470528] (0): "0.flash"1028clare # [ 0.470531] (1): "0.flash"1029clare # [ 0.470534] into device "0.flash"1030alice # [ 0.450059] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001031alice # [ 0.450090] Intel/Sharp Extended Query Table at 0x00311032alice # [ 0.451605] Using buffer write method1033alice # [ 0.451631] Concatenating MTD devices:1034alice # [ 0.451634] (0): "0.flash"1035alice # [ 0.451637] (1): "0.flash"1036alice # [ 0.451640] into device "0.flash"1037bob # [ 0.691039] Freeing initrd memory: 26996K1038bob # [ 0.698612] tun: Universal TUN/TAP device driver, 1.61039bob # [ 0.701433] thunder_xcv, ver 1.01040bob # [ 0.701470] thunder_bgx, ver 1.01041bob # [ 0.701482] nicpf, ver 1.01042bob # [ 0.701783] e1000: Intel(R) PRO/1000 Network Driver1043bob # [ 0.701793] e1000: Copyright (c) 1999-2006 Intel Corporation.1044bob # [ 0.701808] e1000e: Intel(R) PRO/1000 Network Driver1045bob # [ 0.701815] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1046bob # [ 0.701829] igb: Intel(R) Gigabit Ethernet Network Driver1047bob # [ 0.701833] igb: Copyright (c) 2007-2014 Intel Corporation.1048bob # [ 0.701845] igbvf: Intel(R) Gigabit Virtual Function Network Driver1049bob # [ 0.701848] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1050bob # [ 0.701913] sky2: driver version 1.301051bob # [ 0.707064] usbcore: registered new interface driver usb-storage1052bob # [ 0.707129] usbcore: registered new interface driver usbserial_generic1053bob # [ 0.707137] usbserial: USB Serial support registered for generic1054bob # [ 0.707431] hv_vmbus: registering driver hyperv_keyboard1055bob # [ 0.710376] ehci-pci 0000:00:0a.0: EHCI Host Controller1056bob # [ 0.710415] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 11057bob # [ 0.710592] ehci-pci 0000:00:0a.0: irq 15, io mem 0x100890001058bob # [ 0.712199] rtc-pl031 9010000.pl031: registered as rtc01059bob # [ 0.712242] rtc-pl031 9010000.pl031: setting system clock to 2026-08-14T10:05:21 UTC (1786701921)1060bob # [ 0.712460] i2c_dev: i2c /dev entries driver1061bob # [ 0.715292] sdhci: Secure Digital Host Controller Interface driver1062bob # [ 0.715310] sdhci: Copyright(c) Pierre Ossman1063bob # [ 0.715447] Synopsys Designware Multimedia Card Interface Driver1064bob # [ 0.715615] sdhci-pltfm: SDHCI platform and OF driver helper1065bob # [ 0.716577] hid: raw HID events driver (C) Jiri Kosina1066bob # [ 0.716728] usbcore: registered new interface driver usbhid1067bob # [ 0.716733] usbhid: USB HID core driver1068bob # [ 0.720218] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1069bob # [ 0.721068] drop_monitor: Initializing network drop monitor service1070bob # [ 0.721238] NET: Registered PF_INET6 protocol family1071bob # [ 0.723035] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.001072bob # [ 0.723847] hub 1-0:1.0: USB hub found1073bob # [ 0.724167] hub 1-0:1.0: 6 ports detected1074bob # [ 0.725447] Segment Routing with IPv61075bob # [ 0.725487] In-situ OAM (IOAM) with IPv61076bob # [ 0.725537] NET: Registered PF_PACKET protocol family1077bob # [ 0.726645] 9pnet: Installing 9P2000 support1078bob # [ 0.728265] Key type dns_resolver registered1079bob # [ 0.731913] registered taskstats version 11080bob # [ 0.732096] Loading compiled-in X.509 certificates1081bob # [ 0.737529] Demotion targets for Node 0: null1082bob # [ 0.737635] Key type .fscrypt registered1083bob # [ 0.737642] Key type fscrypt-provisioning registered1084bob # [ 0.737761] ima: No TPM chip found, activating TPM-bypass!1085bob # [ 0.737774] ima: Allocated hash algorithm: sha11086bob # [ 0.737824] ima: No architecture policies found1087bob # [ 0.740863] input: gpio-keys as /devices/platform/gpio-keys/input/input01088clare # [ 0.722523] Freeing initrd memory: 26992K1089alice # [ 0.703692] Freeing initrd memory: 26996K1090bob # [ 0.752052] clk: Disabling unused clocks1091bob # [ 0.752092] PM: genpd: Disabling unused power domains1092bob # [ 0.755427] Freeing unused kernel memory: 4736K1093bob # [ 0.755570] Run /init as init process1094clare # [ 0.731124] tun: Universal TUN/TAP device driver, 1.61095alice # [ 0.712641] tun: Universal TUN/TAP device driver, 1.61096clare # [ 0.734159] thunder_xcv, ver 1.01097clare # [ 0.734201] thunder_bgx, ver 1.01098clare # [ 0.734214] nicpf, ver 1.01099clare # [ 0.734573] e1000: Intel(R) PRO/1000 Network Driver1100clare # [ 0.734584] e1000: Copyright (c) 1999-2006 Intel Corporation.1101clare # [ 0.734601] e1000e: Intel(R) PRO/1000 Network Driver1102clare # [ 0.734608] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1103clare # [ 0.734622] igb: Intel(R) Gigabit Ethernet Network Driver1104clare # [ 0.734626] igb: Copyright (c) 2007-2014 Intel Corporation.1105alice # [ 0.715646] thunder_xcv, ver 1.01106alice # [ 0.715686] thunder_bgx, ver 1.01107clare # [ 0.734637] igbvf: Intel(R) Gigabit Virtual Function Network Driver1108alice # [ 0.715702] nicpf, ver 1.01109alice # [ 0.716011] e1000: Intel(R) PRO/1000 Network Driver1110clare # [ 0.734641] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1111clare # [ 0.734707] sky2: driver version 1.301112alice # [ 0.716019] e1000: Copyright (c) 1999-2006 Intel Corporation.1113alice # [ 0.716035] e1000e: Intel(R) PRO/1000 Network Driver1114alice # [ 0.716042] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1115alice # [ 0.716056] igb: Intel(R) Gigabit Ethernet Network Driver1116alice # [ 0.716060] igb: Copyright (c) 2007-2014 Intel Corporation.1117alice # [ 0.716070] igbvf: Intel(R) Gigabit Virtual Function Network Driver1118alice # [ 0.716074] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1119alice # [ 0.716137] sky2: driver version 1.301120clare # [ 0.740245] usbcore: registered new interface driver usb-storage1121alice # [ 0.716934] usbcore: registered new interface driver usb-storage1122clare # [ 0.740352] usbcore: registered new interface driver usbserial_generic1123alice # [ 0.716996] usbcore: registered new interface driver usbserial_generic1124clare # [ 0.740361] usbserial: USB Serial support registered for generic1125alice # [ 0.717004] usbserial: USB Serial support registered for generic1126clare # [ 0.740658] hv_vmbus: registering driver hyperv_keyboard1127clare # [ 0.741793] ehci-pci 0000:00:0a.0: EHCI Host Controller1128clare # [ 0.741834] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 11129alice # [ 0.723629] ehci-pci 0000:00:0a.0: EHCI Host Controller1130clare # [ 0.742051] ehci-pci 0000:00:0a.0: irq 15, io mem 0x100890001131alice # [ 0.723678] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 11132alice # [ 0.723928] ehci-pci 0000:00:0a.0: irq 15, io mem 0x100890001133clare # [ 0.745574] rtc-pl031 9010000.pl031: registered as rtc01134clare # [ 0.745617] rtc-pl031 9010000.pl031: setting system clock to 2026-08-14T10:05:21 UTC (1786701921)1135alice # [ 0.725714] hv_vmbus: registering driver hyperv_keyboard1136clare # [ 0.745857] i2c_dev: i2c /dev entries driver1137alice # [ 0.726784] rtc-pl031 9010000.pl031: registered as rtc01138alice # [ 0.726825] rtc-pl031 9010000.pl031: setting system clock to 2026-08-14T10:05:21 UTC (1786701921)1139bob # [ 0.776656] systemd[1]: Successfully made /usr/ read-only.1140alice # [ 0.727006] i2c_dev: i2c /dev entries driver1141clare # [ 0.748846] sdhci: Secure Digital Host Controller Interface driver1142clare # [ 0.748866] sdhci: Copyright(c) Pierre Ossman1143clare # [ 0.749003] Synopsys Designware Multimedia Card Interface Driver1144clare # [ 0.749166] sdhci-pltfm: SDHCI platform and OF driver helper1145alice # [ 0.729771] sdhci: Secure Digital Host Controller Interface driver1146clare # [ 0.750169] hid: raw HID events driver (C) Jiri Kosina1147alice # [ 0.729788] sdhci: Copyright(c) Pierre Ossman1148clare # [ 0.750357] usbcore: registered new interface driver usbhid1149clare # [ 0.750364] usbhid: USB HID core driver1150alice # [ 0.729915] Synopsys Designware Multimedia Card Interface Driver1151alice # [ 0.730075] sdhci-pltfm: SDHCI platform and OF driver helper1152alice # [ 0.731024] hid: raw HID events driver (C) Jiri Kosina1153clare # [ 0.753928] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.001154alice # [ 0.731166] usbcore: registered new interface driver usbhid1155clare # [ 0.754372] hub 1-0:1.0: USB hub found1156alice # [ 0.731171] usbhid: USB HID core driver1157clare # [ 0.754416] hub 1-0:1.0: 6 ports detected1158alice # [ 0.734649] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.001159alice # [ 0.735054] hub 1-0:1.0: USB hub found1160alice # [ 0.735080] hub 1-0:1.0: 6 ports detected1161clare # [ 0.755847] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1162clare # [ 0.756722] drop_monitor: Initializing network drop monitor service1163clare # [ 0.756855] NET: Registered PF_INET6 protocol family1164alice # [ 0.736417] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1165clare # [ 0.759211] Segment Routing with IPv61166alice # [ 0.737994] drop_monitor: Initializing network drop monitor service1167clare # [ 0.759239] In-situ OAM (IOAM) with IPv61168alice # [ 0.738184] NET: Registered PF_INET6 protocol family1169clare # [ 0.759295] NET: Registered PF_PACKET protocol family1170alice # [ 0.739779] Segment Routing with IPv61171alice # [ 0.739811] In-situ OAM (IOAM) with IPv61172alice # [ 0.739867] NET: Registered PF_PACKET protocol family1173alice # [ 0.740943] 9pnet: Installing 9P2000 support1174clare # [ 0.760392] 9pnet: Installing 9P2000 support1175clare # [ 0.762411] Key type dns_resolver registered1176alice # [ 0.742967] Key type dns_resolver registered1177clare # [ 0.766139] registered taskstats version 11178clare # [ 0.766314] Loading compiled-in X.509 certificates1179alice # [ 0.746492] registered taskstats version 11180alice # [ 0.746665] Loading compiled-in X.509 certificates1181clare # [ 0.771972] Demotion targets for Node 0: null1182clare # [ 0.772093] Key type .fscrypt registered1183clare # [ 0.772099] Key type fscrypt-provisioning registered1184clare # [ 0.772219] ima: No TPM chip found, activating TPM-bypass!1185alice # [ 0.752180] Demotion targets for Node 0: null1186clare # [ 0.772233] ima: Allocated hash algorithm: sha11187alice # [ 0.752285] Key type .fscrypt registered1188clare # [ 0.772288] ima: No architecture policies found1189alice # [ 0.752290] Key type fscrypt-provisioning registered1190alice # [ 0.752405] ima: No TPM chip found, activating TPM-bypass!1191alice # [ 0.752419] ima: Allocated hash algorithm: sha11192alice # [ 0.752467] ima: No architecture policies found1193clare # [ 0.775129] input: gpio-keys as /devices/platform/gpio-keys/input/input01194alice # [ 0.755587] input: gpio-keys as /devices/platform/gpio-keys/input/input01195clare # [ 0.786785] clk: Disabling unused clocks1196clare # [ 0.786822] PM: genpd: Disabling unused power domains1197alice # [ 0.766942] clk: Disabling unused clocks1198alice # [ 0.766980] PM: genpd: Disabling unused power domains1199clare # [ 0.790106] Freeing unused kernel memory: 4736K1200clare # [ 0.790220] Run /init as init process1201alice # [ 0.770343] Freeing unused kernel memory: 4736K1202alice # [ 0.770459] Run /init as init process1203alice # [ 0.791961] systemd[1]: Successfully made /usr/ read-only.1204clare # [ 0.813962] systemd[1]: Successfully made /usr/ read-only.1205bob # [ 0.970023] usb 1-1: new high-speed USB device number 2 using ehci-pci1206alice # [ 0.981135] usb 1-1: new high-speed USB device number 2 using ehci-pci1207clare # [ 1.003042] usb 1-1: new high-speed USB device number 2 using ehci-pci1208bob # [ 1.111431] systemd[1]: systemd 261.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1209bob # [ 1.118060] systemd[1]: Detected virtualization qemu.1210bob # [ 1.119388] systemd[1]: Detected architecture arm64.1211bob # [ 1.120594] systemd[1]: Running in initrd.1212bob # [ 1.122647] systemd[1]: Initializing machine ID from random generator.1213bob # [ 1.124529] systemd[1]: Hostname set to <bob>.1214bob # [ 1.129912] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input11215clare # [ 1.148657] systemd[1]: systemd 261.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1216alice # [ 1.127090] systemd[1]: systemd 261.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1217clare # [ 1.152239] systemd[1]: Detected virtualization qemu.1218clare # [ 1.152772] systemd[1]: Detected architecture arm64.1219clare # [ 1.153261] systemd[1]: Running in initrd.1220alice # [ 1.131921] systemd[1]: Detected virtualization qemu.1221alice # [ 1.133510] systemd[1]: Detected architecture arm64.1222clare # [ 1.154354] systemd[1]: Initializing machine ID from random generator.1223alice # [ 1.133924] systemd[1]: Running in initrd.1224clare # [ 1.155613] systemd[1]: Hostname set to <clare>.1225alice # [ 1.134802] systemd[1]: Initializing machine ID from random generator.1226alice # [ 1.135573] systemd[1]: Hostname set to <alice>.1227clare # [ 1.157699] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input11228alice # [ 1.140665] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input11229bob # [ 1.214302] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input01230clare # [ 1.235267] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input01231alice # [ 1.221253] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input01232bob # [ 1.333994] usb 1-2: new high-speed USB device number 3 using ehci-pci1233bob # [ 1.347634] systemd[1]: bpf-restrict-fs: LSM BPF program attached1234clare # [ 1.357050] usb 1-2: new high-speed USB device number 3 using ehci-pci1235alice # [ 1.341127] usb 1-2: new high-speed USB device number 3 using ehci-pci1236bob # [ 1.405319] systemd[1]: Queued start job for default target Initrd Default Target.1237clare # [ 1.380144] systemd[1]: bpf-restrict-fs: LSM BPF program attached1238alice # [ 1.365831] systemd[1]: bpf-restrict-fs: LSM BPF program attached1239bob # [ 1.415620] systemd[1]: Created slice Slice /system/modprobe.1240bob # [ 1.416332] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1241bob # [ 1.417014] systemd[1]: Expecting device /dev/disk/by-label/nixos...1242bob # [ 1.417578] systemd[1]: Reached target Path Units.1243bob # [ 1.418047] systemd[1]: Reached target Slice Units.1244bob # [ 1.418464] systemd[1]: Reached target Swaps.1245bob # [ 1.418833] systemd[1]: Reached target Timer Units.1246bob # [ 1.419419] systemd[1]: Listening on D-Bus System Message Bus Socket.1247bob # [ 1.420119] systemd[1]: Listening on Journal Socket (/dev/log).1248bob # [ 1.420728] systemd[1]: Listening on Journal Sockets.1249bob # [ 1.421241] systemd[1]: Listening on udev Control Socket.1250bob # [ 1.421803] systemd[1]: Listening on udev Kernel Socket.1251bob # [ 1.422345] systemd[1]: Reached target Socket Units.1252bob # [ 1.423952] systemd[1]: Starting Create List of Static Device Nodes...1253bob # [ 1.429651] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1254bob # [ 1.437907] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1255bob # [ 1.444100] systemd[1]: Mounting Kernel Configuration File System...1256bob # [ 1.454439] systemd[1]: Starting Journal Service...1257bob # [ 1.458109] systemd[1]: Starting Load Kernel Modules...1258bob # [ 1.458698] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1259clare # [ 1.442065] systemd[1]: Queued start job for default target Initrd Default Target.1260clare # [ 1.453020] systemd[1]: Created slice Slice /system/modprobe.1261clare # [ 1.453765] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1262clare # [ 1.454537] systemd[1]: Expecting device /dev/disk/by-label/nixos...1263clare # [ 1.455167] systemd[1]: Reached target Path Units.1264clare # [ 1.455606] systemd[1]: Reached target Slice Units.1265clare # [ 1.456016] systemd[1]: Reached target Swaps.1266clare # [ 1.456403] systemd[1]: Reached target Timer Units.1267clare # [ 1.457026] systemd[1]: Listening on D-Bus System Message Bus Socket.1268clare # [ 1.457754] systemd[1]: Listening on Journal Socket (/dev/log).1269clare # [ 1.458384] systemd[1]: Listening on Journal Sockets.1270bob # [ 1.486213] systemd[1]: Starting Coldplug All udev Devices...1271clare # [ 1.459137] systemd[1]: Listening on udev Control Socket.1272clare # [ 1.459743] systemd[1]: Listening on udev Kernel Socket.1273clare # [ 1.460242] systemd[1]: Reached target Socket Units.1274clare # [ 1.461942] systemd[1]: Starting Create List of Static Device Nodes...1275bob # [ 1.490765] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input21276bob # [ 1.490867] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input01277clare # [ 1.471736] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1278bob # [ 1.498185] systemd[1]: Finished Create List of Static Device Nodes.1279clare # [ 1.472397] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1280alice # [ 1.451809] systemd[1]: Queued start job for default target Initrd Default Target.1281bob # [ 1.499159] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1282bob # [ 1.499367] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1283alice # [ 1.458986] systemd[1]: Created slice Slice /system/modprobe.1284alice # [ 1.459777] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1285clare # [ 1.481347] systemd[1]: Mounting Kernel Configuration File System...1286alice # [ 1.460506] systemd[1]: Expecting device /dev/disk/by-label/nixos...1287alice # [ 1.461081] systemd[1]: Reached target Path Units.1288alice # [ 1.461629] systemd[1]: Reached target Slice Units.1289alice # [ 1.462226] systemd[1]: Reached target Swaps.1290alice # [ 1.462625] systemd[1]: Reached target Timer Units.1291alice # [ 1.463299] systemd[1]: Listening on D-Bus System Message Bus Socket.1292alice # [ 1.464042] systemd[1]: Listening on Journal Socket (/dev/log).1293alice # [ 1.464701] systemd[1]: Listening on Journal Sockets.1294alice # [ 1.465305] systemd[1]: Listening on udev Control Socket.1295alice # [ 1.465922] systemd[1]: Listening on udev Kernel Socket.1296alice # [ 1.466419] systemd[1]: Reached target Socket Units.1297alice # [ 1.468181] systemd[1]: Starting Create List of Static Device Nodes...1298clare # [ 1.491356] systemd[1]: Starting Journal Service...1299bob # [ 1.524018] systemd-journald[73]: Collecting audit messages is disabled.1300alice # [ 1.478202] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1301alice # [ 1.478842] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1302bob # [ 1.529051] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1303clare # [ 1.503517] systemd[1]: Starting Load Kernel Modules...1304clare # [ 1.504419] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1305bob # [ 1.534565] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1306alice # [ 1.488846] systemd[1]: Mounting Kernel Configuration File System...1307clare # [ 1.512519] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input21308clare # [ 1.513634] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input01309alice # [ 1.494965] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input21310alice # [ 1.496170] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input01311bob # [ 1.546439] systemd[1]: Mounted Kernel Configuration File System.1312clare # [ 1.522395] systemd[1]: Starting Coldplug All udev Devices...1313alice # [ 1.501409] systemd[1]: Starting Journal Service...1314bob # [ 1.550092] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1315bob # [ 1.555217] [drm] pci: virtio-gpu-pci detected at 0000:00:08.01316bob # [ 1.555452] [drm] features: -virgl +edid -resource_blob -host_visible1317alice # [ 1.509050] systemd[1]: Starting Load Kernel Modules...1318bob # [ 1.555458] [drm] features: -context_init1319bob # [ 1.555974] [drm] number of scanouts: 11320bob # [ 1.555990] [drm] number of cap sets: 01321alice # [ 1.509808] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1322bob # [ 1.559514] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1323bob # [ 1.561516] systemd[1]: Starting Create Static Device Nodes in /dev...1324clare # [ 1.533889] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1325clare # [ 1.551196] systemd[1]: Finished Create List of Static Device Nodes.1326clare # [ 1.552439] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1327clare # [ 1.552635] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1328bob # [ 1.578352] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic1329bob # [ 1.578378] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 01330alice # [ 1.537276] systemd[1]: Starting Coldplug All udev Devices...1331clare # [ 1.559096] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1332clare # [ 1.564400] [drm] pci: virtio-gpu-pci detected at 0000:00:08.01333alice # [ 1.545373] systemd[1]: Finished Create List of Static Device Nodes.1334clare # [ 1.564660] [drm] features: -virgl +edid -resource_blob -host_visible1335clare # [ 1.564666] [drm] features: -context_init1336clare # [ 1.565156] [drm] number of scanouts: 11337clare # [ 1.565173] [drm] number of cap sets: 01338alice # [ 1.547201] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1339clare # [ 1.568599] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1340clare # [ 1.568855] systemd[1]: Mounted Kernel Configuration File System.1341bob # [ 1.598505] systemd[1]: Finished Create Static Device Nodes in /dev.1342bob # [ 1.598737] systemd[1]: Reached target Preparation for Local File Systems.1343bob # [ 1.598770] systemd[1]: Reached target Local File Systems.1344bob # [ 1.602308] systemd[1]: Starting Rule-based Manager for Device Events and Files...1345alice # [ 1.553540] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1346alice # [ 1.553881] systemd[1]: Mounted Kernel Configuration File System.1347alice # [ 1.560548] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1348bob # [ 1.610545] Console: switching to colour frame buffer device 160x501349alice # [ 1.567864] systemd-journald[73]: Collecting audit messages is disabled.1350alice # [ 1.568621] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1351clare # [ 1.587392] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic1352clare # [ 1.587412] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 01353clare # [ 1.596023] systemd-journald[73]: Collecting audit messages is disabled.1354alice # [ 1.570726] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1355bob # [ 1.626430] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device1356bob # [ 1.632212] systemd-modules-load[74]: Inserted module 'dm_mod'1357bob # [ 1.633665] systemd-modules-load[74]: Module 'virtio_balloon' is built in1358bob # [ 1.640375] systemd[1]: Started Journal Service.1359alice # [ 1.592055] [drm] pci: virtio-gpu-pci detected at 0000:00:08.01360clare # [ 1.611255] Console: switching to colour frame buffer device 160x501361alice # [ 1.592299] [drm] features: -virgl +edid -resource_blob -host_visible1362bob # [ 1.636301] systemd-modules-load[74]: Module 'virtio_console' is built in1363alice # [ 1.592306] [drm] features: -context_init1364clare # [ 1.614022] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device1365alice # [ 1.592799] [drm] number of scanouts: 11366alice # [ 1.592817] [drm] number of cap sets: 01367bob # [ 1.638553] systemd-modules-load[74]: Inserted module 'virtio_gpu'1368bob # [ 1.639269] systemd-modules-load[74]: Module 'virtio_rng' is built in1369clare # [ 1.616180] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1370clare # [ 1.618663] systemd[1]: Starting Create Static Device Nodes in /dev...1371bob # [ 1.644415] systemd[1]: Finished Load Kernel Modules.1372bob # [ 1.653478] systemd[1]: Starting Apply Kernel Variables...1373alice # [ 1.605450] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic1374alice # [ 1.605474] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 01375bob # [ 1.656878] systemd[1]: Starting Create System Files and Directories...1376alice # [ 1.617650] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1377clare # [ 1.639277] systemd[1]: Finished Load Kernel Modules.1378alice # [ 1.621467] systemd[1]: Starting Create Static Device Nodes in /dev...1379clare # [ 1.643310] systemd[1]: Starting Apply Kernel Variables...1380alice # [ 1.629667] Console: switching to colour frame buffer device 160x501381alice # [ 1.632261] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device1382alice # [ 1.638747] systemd[1]: Finished Load Kernel Modules.1383clare # [ 1.659192] systemd[1]: Finished Create Static Device Nodes in /dev.1384clare # [ 1.659393] systemd[1]: Reached target Preparation for Local File Systems.1385clare # [ 1.659434] systemd[1]: Reached target Local File Systems.1386alice # [ 1.640781] systemd[1]: Starting Apply Kernel Variables...1387bob # [ 1.687997] systemd-udevd[79]: Using default interface naming scheme 'v261'.1388clare # [ 1.662768] systemd[1]: Starting Rule-based Manager for Device Events and Files...1389bob # [ 1.701051] systemd[1]: Finished Create System Files and Directories.1390clare # [ 1.675362] systemd-modules-load[74]: Inserted module 'dm_mod'1391clare # [ 1.683266] systemd[1]: Started Journal Service.1392bob # [ 1.709135] systemd[1]: Finished Apply Kernel Variables.1393alice # [ 1.667204] systemd[1]: Started Journal Service.1394alice # [ 1.664326] systemd-modules-load[74]: Inserted module 'dm_mod'1395alice # [ 1.665212] systemd-modules-load[74]: Module 'virtio_balloon' is built in1396clare # [ 1.689949] systemd-modules-load[74]: Module 'virtio_balloon' is built in1397clare # [ 1.690735] systemd-modules-load[74]: Module 'virtio_console' is built in1398clare # [ 1.691447] systemd-modules-load[74]: Inserted module 'virtio_gpu'1399alice # [ 1.672375] systemd-modules-load[74]: Module 'virtio_console' is built in1400clare # [ 1.692219] systemd-modules-load[74]: Module 'virtio_rng' is built in1401alice # [ 1.677637] systemd-modules-load[74]: Inserted module 'virtio_gpu'1402bob # [ 1.726051] systemd[1]: Started Rule-based Manager for Device Events and Files.1403alice # [ 1.678381] systemd-modules-load[74]: Module 'virtio_rng' is built in1404alice # [ 1.680255] systemd[1]: Finished Create Static Device Nodes in /dev.1405alice # [ 1.681014] systemd[1]: Reached target Preparation for Local File Systems.1406clare # [ 1.700282] systemd[1]: Starting Create System Files and Directories...1407alice # [ 1.682818] systemd[1]: Reached target Local File Systems.1408alice # [ 1.687086] systemd[1]: Starting Create System Files and Directories...1409clare # [ 1.708149] systemd[1]: Finished Apply Kernel Variables.1410alice # [ 1.700862] systemd[1]: Starting Rule-based Manager for Device Events and Files...1411alice # [ 1.701782] systemd[1]: Finished Apply Kernel Variables.1412clare # [ 1.726820] systemd[1]: Finished Create System Files and Directories.1413clare # [ 1.745369] systemd-udevd[80]: Using default interface naming scheme 'v261'.1414alice # [ 1.732641] systemd[1]: Finished Create System Files and Directories.1415bob # [ 1.784162] systemd[1]: Starting Virtual Console Setup...1416clare # [ 1.768634] systemd[1]: Started Rule-based Manager for Device Events and Files.1417alice # [ 1.753356] systemd-udevd[83]: Using default interface naming scheme 'v261'.1418alice # [ 1.777583] systemd[1]: Started Rule-based Manager for Device Events and Files.1419bob # [ 1.832485] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.1420bob # [ 1.834593] systemd[1]: Finished Virtual Console Setup.1421clare # [ 1.824111] systemd[1]: Starting Virtual Console Setup...1422alice # [ 1.834670] systemd[1]: Starting Virtual Console Setup...1423clare # [ 1.868392] systemd-vconsole-setup[102]: Configuration of first virtual console was skipped, ignoring remaining ones.1424clare # [ 1.870136] systemd[1]: Finished Virtual Console Setup.1425alice # [ 1.880421] systemd-vconsole-setup[105]: Configuration of first virtual console was skipped, ignoring remaining ones.1426alice # [ 1.882771] systemd[1]: Finished Virtual Console Setup.1427bob # [ 2.052501] systemd[1]: Finished Coldplug All udev Devices.1428bob # [ 2.053074] systemd[1]: Reached target System Initialization.1429bob # [ 2.053567] systemd[1]: Reached target Basic System.1430clare # [ 2.084581] systemd[1]: Finished Coldplug All udev Devices.1431clare # [ 2.085157] systemd[1]: Reached target System Initialization.1432clare # [ 2.085665] systemd[1]: Reached target Basic System.1433alice # [ 2.086477] systemd[1]: Finished Coldplug All udev Devices.1434alice # [ 2.087069] systemd[1]: Reached target System Initialization.1435alice # [ 2.087566] systemd[1]: Reached target Basic System.1436bob # [ 2.143145] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.1437bob # [ 2.168808] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.1438bob # [ 2.216249] systemd[1]: Found device /dev/disk/by-label/nixos.1439bob # [ 2.219149] systemd[1]: Reached target Initrd Root Device.1440bob # [ 2.223772] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1441clare # [ 2.195624] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.1442alice # [ 2.190944] (udev-worker)[103]: Network interface NamePolicy= disabled on kernel command line.1443clare # [ 2.211246] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.1444alice # [ 2.212345] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.1445bob # [ 2.265253] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks1446bob # [ 2.268954] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1447clare # [ 2.288315] systemd[1]: Found device /dev/disk/by-label/nixos.1448clare # [ 2.290902] systemd[1]: Reached target Initrd Root Device.1449clare # [ 2.293857] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1450alice # [ 2.288660] systemd[1]: Found device /dev/disk/by-label/nixos.1451alice # [ 2.291280] systemd[1]: Reached target Initrd Root Device.1452alice # [ 2.294350] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1453clare # [ 2.330939] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks1454clare # [ 2.335031] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1455alice # [ 2.329830] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks1456alice # [ 2.334843] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1457bob # [ 2.483776] systemd[1]: Mounting /sysroot...1458bob # [ 2.514565] EXT4-fs (vda): mounted filesystem 69b6f673-0dd1-4751-8d10-fd92a340c62d r/w with ordered data mode. Quota mode: none.1459bob # [ 2.512740] systemd[1]: Mounted /sysroot.1460bob # [ 2.513891] systemd[1]: Reached target Initrd Root File System.1461bob # [ 2.517220] systemd[1]: Starting Mountpoints Configured in the Real Root...1462bob # [ 2.539063] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.1463bob # [ 2.544766] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...1464bob # [ 2.547976] systemd[1]: Reloading...1465clare # [ 2.538749] systemd[1]: Mounting /sysroot...1466alice # [ 2.533808] systemd[1]: Mounting /sysroot...1467clare # [ 2.567251] EXT4-fs (vda): mounted filesystem 910c745b-bf59-4342-b6c9-01ce2fbc03bf r/w with ordered data mode. Quota mode: none.1468clare # [ 2.564704] systemd[1]: Mounted /sysroot.1469clare # [ 2.566186] systemd[1]: Reached target Initrd Root File System.1470clare # [ 2.571636] systemd[1]: Mounting /sysroot/nix/.ro-store...1471alice # [ 2.564241] EXT4-fs (vda): mounted filesystem 0f86b075-5202-4980-84fa-7f8536b82631 r/w with ordered data mode. Quota mode: none.1472alice # [ 2.564761] systemd[1]: Mounted /sysroot.1473alice # [ 2.565762] systemd[1]: Reached target Initrd Root File System.1474clare # [ 2.586249] systemd[1]: Mounting /sysroot/nix/.rw-store...1475alice # [ 2.570323] systemd[1]: Mounting /sysroot/nix/.ro-store...1476clare # [ 2.592106] systemd[1]: Mounting /sysroot/run...1477alice # [ 2.579059] systemd[1]: Mounting /sysroot/nix/.rw-store...1478alice # [ 2.586970] systemd[1]: Mounting /sysroot/run...1479clare # [ 2.609798] systemd[1]: Mounting /sysroot/tmp/shared...1480clare # [ 2.625412] systemd[1]: Mounting /sysroot/tmp/xchg...1481alice # [ 2.613188] systemd[1]: Mounting /sysroot/tmp/shared...1482clare # [ 2.634258] systemd[1]: Starting Mountpoints Configured in the Real Root...1483clare # [ 2.636184] systemd[1]: Mounted /sysroot/nix/.rw-store.1484alice # [ 2.622948] systemd[1]: Mounting /sysroot/tmp/xchg...1485clare # [ 2.648110] systemd[1]: Mounted /sysroot/nix/.ro-store.1486alice # [ 2.638091] systemd[1]: Starting Mountpoints Configured in the Real Root...1487alice # [ 2.640105] systemd[1]: Mounted /sysroot/nix/.rw-store.1488clare # [ 2.663757] systemd[1]: Mounted /sysroot/run.1489alice # [ 2.655097] systemd[1]: Mounted /sysroot/nix/.ro-store.1490clare # [ 2.674438] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload.1491bob # [ 2.712127] systemd[1]: Reloading finished in 165 ms.1492clare # [ 2.688110] systemd[1]: Mounted /sysroot/tmp/shared.1493alice # [ 2.671697] systemd-sysroot-fstab-check[126]: /sysroot should be mounted in the initrd, will request daemon-reload.1494clare # [ 2.693023] systemd[1]: Mounted /sysroot/tmp/xchg.1495alice # [ 2.676196] systemd[1]: Starting rw-sysroot-nix-store.service...1496clare # [ 2.696115] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)...1497alice # [ 2.678835] systemd[1]: Mounted /sysroot/run.1498clare # [ 2.698017] systemd[1]: Reloading...1499bob # [ 2.728210] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...1500bob # [ 2.729653] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...1501bob # [ 2.732334] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1502bob # [ 2.734956] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1503bob # [ 2.737627] systemd[1]: Finished Mountpoints Configured in the Real Root.1504bob # [ 2.739241] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1505alice # [ 2.702192] systemd[1]: Mounted /sysroot/tmp/shared.1506alice # [ 2.702712] systemd[1]: Mounted /sysroot/tmp/xchg.1507alice # [ 2.706538] systemd[1]: Reload requested from client PID 126 ('systemd-sysroot') (unit initrd-parse-etc.service)...1508bob # [ 2.757813] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1509alice # [ 2.710733] systemd[1]: Reloading...1510bob # [ 2.758483] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1511clare # [ 2.867785] systemd[1]: Reloading finished in 171 ms.1512clare # [ 2.888577] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace...1513clare # [ 2.894415] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace...1514alice # [ 2.881048] systemd[1]: Reloading finished in 170 ms.1515clare # [ 2.902670] systemd[1]: Starting rw-sysroot-nix-store.service...1516clare # [ 2.907359] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1517clare # [ 2.914006] systemd[1]: Finished Mountpoints Configured in the Real Root.1518clare # [ 2.914643] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1519alice # [ 2.900434] systemd-sysroot-fstab-check[126]: Requesting initrd-fs.target/start/replace...1520clare # [ 2.919476] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1521alice # [ 2.901847] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1522clare # [ 2.921179] systemd[1]: Finished rw-sysroot-nix-store.service.1523alice # [ 2.904185] systemd[1]: Finished rw-sysroot-nix-store.service.1524alice # [ 2.907005] systemd-sysroot-fstab-check[126]: Requesting swap.target/start/replace...1525alice # [ 2.911274] systemd[1]: Starting rw-sysroot-nix-store.service...1526alice # [ 2.912235] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1527alice # [ 2.919446] systemd[1]: Finished Mountpoints Configured in the Real Root.1528alice # [ 2.921699] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1529alice # [ 2.937912] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1530alice # [ 2.938816] systemd[1]: Finished rw-sysroot-nix-store.service.1531bob # [ 3.016491] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/pyrvwmigi6qdpz9lbbslj5771pfl7r69-systemd-261.1/lib/udev/mtd_probe": No such file or directory1532bob # [ 3.020402] (udev-worker)[97]: mtd0ro: /nix/store/pyrvwmigi6qdpz9lbbslj5771pfl7r69-systemd-261.1/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1533bob # [ 3.033148] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1534bob # [ 3.034968] systemd[1]: Stopped Virtual Console Setup.1535bob # [ 3.037231] systemd[1]: Stopping Virtual Console Setup...1536bob # [ 3.040116] systemd[1]: Starting Virtual Console Setup...1537bob # [ 3.058565] systemd-vconsole-setup[141]: Configuration of first virtual console was skipped, ignoring remaining ones.1538bob # [ 3.060943] systemd[1]: Finished Virtual Console Setup.1539clare # [ 3.124884] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/pyrvwmigi6qdpz9lbbslj5771pfl7r69-systemd-261.1/lib/udev/mtd_probe": No such file or directory1540clare # [ 3.128382] (udev-worker)[97]: mtd0ro: /nix/store/pyrvwmigi6qdpz9lbbslj5771pfl7r69-systemd-261.1/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1541clare # [ 3.144289] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1542clare # [ 3.145222] systemd[1]: Stopped Virtual Console Setup.1543clare # [ 3.148051] systemd[1]: Stopping Virtual Console Setup...1544clare # [ 3.148677] systemd[1]: Starting Virtual Console Setup...1545alice # [ 3.135922] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/pyrvwmigi6qdpz9lbbslj5771pfl7r69-systemd-261.1/lib/udev/mtd_probe": No such file or directory1546alice # [ 3.139245] (udev-worker)[99]: mtd0ro: /nix/store/pyrvwmigi6qdpz9lbbslj5771pfl7r69-systemd-261.1/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1547clare # [ 3.160498] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1548clare # [ 3.162070] systemd[1]: Stopped Virtual Console Setup.1549clare # [ 3.164171] systemd[1]: Starting Virtual Console Setup...1550clare # [ 3.177670] systemd-vconsole-setup[154]: Configuration of first virtual console was skipped, ignoring remaining ones.1551clare # [ 3.179915] systemd[1]: Finished Virtual Console Setup.1552alice # [ 3.162851] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1553alice # [ 3.163551] systemd[1]: Stopped Virtual Console Setup.1554alice # [ 3.166847] systemd[1]: Stopping Virtual Console Setup...1555alice # [ 3.167380] systemd[1]: Starting Virtual Console Setup...1556alice # [ 3.187094] systemd-vconsole-setup[156]: Configuration of first virtual console was skipped, ignoring remaining ones.1557alice # [ 3.188847] systemd[1]: Finished Virtual Console Setup.1558bob # [ 3.484445] systemd[1]: Mounting /sysroot/nix/.ro-store...1559bob # [ 3.491224] systemd[1]: Mounting /sysroot/nix/.rw-store...1560bob # [ 3.496804] systemd[1]: Mounting /sysroot/run...1561bob # [ 3.508278] systemd[1]: Mounting /sysroot/tmp/shared...1562bob # [ 3.524090] systemd[1]: Mounting /sysroot/tmp/xchg...1563bob # [ 3.527927] systemd[1]: Mounted /sysroot/nix/.rw-store.1564bob # [ 3.529453] systemd[1]: Mounted /sysroot/run.1565bob # [ 3.547852] systemd[1]: Starting rw-sysroot-nix-store.service...1566bob # [ 3.549589] systemd[1]: Mounted /sysroot/nix/.ro-store.1567bob # [ 3.550986] systemd[1]: Mounted /sysroot/tmp/shared.1568bob # [ 3.552675] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1569bob # [ 3.554381] systemd[1]: Finished rw-sysroot-nix-store.service.1570bob # [ 3.557917] systemd[1]: Mounting /sysroot/nix/store...1571bob # [ 3.559438] systemd[1]: Mounted /sysroot/tmp/xchg.1572clare # [ 3.538750] systemd[1]: Mounting /sysroot/nix/store...1573bob # [ 3.583691] systemd[1]: Mounted /sysroot/nix/store.1574bob # [ 3.584605] systemd[1]: Reached target Initrd File Systems.1575alice # [ 3.536318] systemd[1]: Mounting /sysroot/nix/store...1576bob # [ 3.585987] systemd[1]: Starting Find NixOS closure...1577bob # [ 3.591188] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1578clare # [ 3.567302] systemd[1]: Mounted /sysroot/nix/store.1579clare # [ 3.568586] systemd[1]: Reached target Initrd File Systems.1580clare # [ 3.571337] systemd[1]: Starting Find NixOS closure...1581bob # [ 3.608910] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1582clare # [ 3.579933] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1583bob # [ 3.619323] systemd[1]: Finished Find NixOS closure.1584bob # [ 3.620654] systemd[1]: Reached target Initrd Default Target.1585bob # [ 3.622113] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1586clare # [ 3.601247] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1587clare # [ 3.602223] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1588alice # [ 3.588231] systemd[1]: Mounted /sysroot/nix/store.1589alice # [ 3.590436] systemd[1]: Reached target Initrd File Systems.1590clare # [ 3.613337] systemd[1]: Finished Find NixOS closure.1591alice # [ 3.594268] systemd[1]: Starting Find NixOS closure...1592bob # [ 3.642857] systemd[1]: initrd-cleanup.service: Deactivated successfully.1593clare # [ 3.615014] systemd[1]: Reached target Initrd Default Target.1594bob # [ 3.643770] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1595bob # [ 3.646873] systemd[1]: Stopped target Initrd Default Target.1596bob # [ 3.647406] systemd[1]: Stopped target Basic System.1597bob # [ 3.647894] systemd[1]: Stopped target Initrd Root Device.1598bob # [ 3.648817] systemd[1]: Stopped target Path Units.1599clare # [ 3.619945] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1600alice # [ 3.601751] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1601bob # [ 3.650670] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1602bob # [ 3.651321] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1603bob # [ 3.652851] systemd[1]: Stopped target Slice Units.1604bob # [ 3.656119] systemd[1]: Stopped target Socket Units.1605bob # [ 3.656616] systemd[1]: Stopped target System Initialization.1606bob # [ 3.657114] systemd[1]: Stopped target Swaps.1607bob # [ 3.657508] systemd[1]: Stopped target Timer Units.1608bob # [ 3.657937] systemd[1]: dbus.socket: Deactivated successfully.1609bob # [ 3.658459] systemd[1]: Closed D-Bus System Message Bus Socket.1610bob # [ 3.658961] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1611bob # [ 3.659594] systemd[1]: Stopped Find NixOS closure.1612bob # [ 3.663077] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1613bob # [ 3.664118] systemd[1]: Starting rw-sysroot-nix-store.service...1614bob # [ 3.664728] systemd[1]: systemd-sysctl.service: Deactivated successfully.1615bob # [ 3.666716] systemd[1]: Stopped Apply Kernel Variables.1616clare # [ 3.640574] systemd[1]: Stopped target Initrd Default Target.1617clare # [ 3.642219] systemd[1]: Stopped target Basic System.1618bob # [ 3.671632] systemd[1]: systemd-modules-load.service: Deactivated successfully.1619clare # [ 3.643059] systemd[1]: Stopped target Initrd Root Device.1620clare # [ 3.644369] systemd[1]: Stopped target Path Units.1621clare # [ 3.645773] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1622bob # [ 3.675808] systemd[1]: Stopped Load Kernel Modules.1623bob # [ 3.676570] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1624clare # [ 3.648241] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1625bob # [ 3.677310] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1626bob # [ 3.677957] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1627bob # [ 3.678556] systemd[1]: Stopped Create System Files and Directories.1628bob # [ 3.679073] systemd[1]: Stopped target Local File Systems.1629clare # [ 3.650429] systemd[1]: Stopped target Slice Units.1630bob # [ 3.679530] systemd[1]: Stopped target Preparation for Local File Systems.1631clare # [ 3.651268] systemd[1]: Stopped target Socket Units.1632clare # [ 3.652961] systemd[1]: Stopped target System Initialization.1633clare # [ 3.656219] systemd[1]: Stopped target Swaps.1634clare # [ 3.656752] systemd[1]: Stopped target Timer Units.1635clare # [ 3.657275] systemd[1]: dbus.socket: Deactivated successfully.1636clare # [ 3.657865] systemd[1]: Closed D-Bus System Message Bus Socket.1637bob # [ 3.687208] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1638clare # [ 3.658488] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1639bob # [ 3.687916] systemd[1]: Stopped Coldplug All udev Devices.1640clare # [ 3.659237] systemd[1]: Stopped Find NixOS closure.1641bob # [ 3.688671] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1642bob # [ 3.689308] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1643bob # [ 3.689933] systemd[1]: Stopped Virtual Console Setup.1644bob # [ 3.690389] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1645bob # [ 3.691023] systemd[1]: Finished rw-sysroot-nix-store.service.1646bob # [ 3.691526] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1647clare # [ 3.663954] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1648clare # [ 3.665696] systemd[1]: Starting rw-sysroot-nix-store.service...1649alice # [ 3.646397] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1650bob # [ 3.695536] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1651clare # [ 3.667695] systemd[1]: systemd-sysctl.service: Deactivated successfully.1652clare # [ 3.670429] systemd[1]: Stopped Apply Kernel Variables.1653bob # [ 3.699984] systemd[1]: systemd-udevd.service: Deactivated successfully.1654bob # [ 3.700605] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1655alice # [ 3.651665] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1656bob # [ 3.701226] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1657bob # [ 3.701812] systemd[1]: Closed udev Control Socket.1658bob # [ 3.702237] systemd[1]: Starting Cleanup udev Database...1659bob # [ 3.702700] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1660bob # [ 3.703363] systemd[1]: Stopped Create Static Device Nodes in /dev.1661bob # [ 3.703885] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1662clare # [ 3.676127] systemd[1]: systemd-modules-load.service: Deactivated successfully.1663bob # [ 3.705104] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1664bob # [ 3.705710] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1665bob # [ 3.706300] systemd[1]: Stopped Create List of Static Device Nodes.1666clare # [ 3.680370] systemd[1]: Stopped Load Kernel Modules.1667clare # [ 3.680975] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1668clare # [ 3.681714] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1669clare # [ 3.682346] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1670clare # [ 3.682969] systemd[1]: Stopped Create System Files and Directories.1671clare # [ 3.683767] systemd[1]: Stopped target Local File Systems.1672alice # [ 3.666770] systemd[1]: Finished Find NixOS closure.1673alice # [ 3.669163] systemd[1]: Reached target Initrd Default Target.1674alice # [ 3.670526] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1675clare # [ 3.690860] systemd[1]: Stopped target Preparation for Local File Systems.1676clare # [ 3.691524] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1677clare # [ 3.692177] systemd[1]: Stopped Coldplug All udev Devices.1678clare # [ 3.692658] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1679clare # [ 3.693295] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1680clare # [ 3.693923] systemd[1]: Stopped Virtual Console Setup.1681clare # [ 3.694378] systemd[1]: initrd-cleanup.service: Deactivated successfully.1682bob # [ 3.729929] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1683bob # [ 3.730656] systemd[1]: Finished Cleanup udev Database.1684clare # [ 3.703414] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1685bob # [ 3.732897] systemd[1]: Reached target Switch Root.1686clare # [ 3.704251] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1687clare # [ 3.704916] systemd[1]: Finished rw-sysroot-nix-store.service.1688bob # [ 3.735969] systemd[1]: Starting NixOS Activation...1689clare # [ 3.707685] systemd[1]: systemd-udevd.service: Deactivated successfully.1690clare # [ 3.708395] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1691clare # [ 3.709417] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1692clare # [ 3.712572] systemd[1]: Closed udev Control Socket.1693clare # [ 3.713152] systemd[1]: Starting Cleanup udev Database...1694clare # [ 3.713718] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1695clare # [ 3.715053] systemd[1]: Stopped Create Static Device Nodes in /dev.1696clare # [ 3.716224] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1697clare # [ 3.718889] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1698clare # [ 3.719705] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1699clare # [ 3.720421] systemd[1]: Stopped Create List of Static Device Nodes.1700clare # [ 3.721124] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1701clare # [ 3.721757] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1702alice # [ 3.703668] systemd[1]: Stopped target Initrd Default Target.1703alice # [ 3.705673] systemd[1]: Stopped target Basic System.1704alice # [ 3.708371] systemd[1]: Stopped target Initrd Root Device.1705alice # [ 3.709950] systemd[1]: Stopped target Path Units.1706alice # [ 3.712352] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1707alice # [ 3.714589] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1708alice # [ 3.720133] systemd[1]: Stopped target Slice Units.1709alice # [ 3.721127] systemd[1]: Stopped target Socket Units.1710clare # [ 3.740642] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1711alice # [ 3.721773] systemd[1]: Stopped target System Initialization.1712alice # [ 3.722464] systemd[1]: Stopped target Swaps.1713alice # [ 3.723064] systemd[1]: Stopped target Timer Units.1714alice # [ 3.723697] systemd[1]: dbus.socket: Deactivated successfully.1715clare # [ 3.743993] systemd[1]: Finished Cleanup udev Database.1716clare # [ 3.744524] systemd[1]: Reached target Switch Root.1717clare # [ 3.745216] systemd[1]: Starting NixOS Activation...1718alice # [ 3.726086] systemd[1]: Closed D-Bus System Message Bus Socket.1719alice # [ 3.728266] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1720alice # [ 3.732132] systemd[1]: Stopped Find NixOS closure.1721alice # [ 3.733002] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1722alice # [ 3.733691] systemd[1]: Starting rw-sysroot-nix-store.service...1723alice # [ 3.734357] systemd[1]: systemd-sysctl.service: Deactivated successfully.1724alice # [ 3.735716] systemd[1]: Stopped Apply Kernel Variables.1725alice # [ 3.741192] systemd[1]: systemd-modules-load.service: Deactivated successfully.1726alice # [ 3.741940] systemd[1]: Stopped Load Kernel Modules.1727alice # [ 3.742435] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1728alice # [ 3.743181] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1729alice # [ 3.743909] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1730alice # [ 3.751390] systemd[1]: Stopped Create System Files and Directories.1731alice # [ 3.752123] systemd[1]: Stopped target Local File Systems.1732alice # [ 3.752914] systemd[1]: Stopped target Preparation for Local File Systems.1733alice # [ 3.753538] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1734alice # [ 3.754182] systemd[1]: Stopped Coldplug All udev Devices.1735alice # [ 3.754697] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1736alice # [ 3.755367] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1737alice # [ 3.764077] systemd[1]: Stopped Virtual Console Setup.1738alice # [ 3.765478] systemd[1]: initrd-cleanup.service: Deactivated successfully.1739alice # [ 3.771747] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1740alice # [ 3.772719] systemd[1]: systemd-udevd.service: Deactivated successfully.1741alice # [ 3.776371] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1742alice # [ 3.777020] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1743alice # [ 3.777634] systemd[1]: Closed udev Control Socket.1744alice # [ 3.778183] systemd[1]: Starting Cleanup udev Database...1745alice # [ 3.779537] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1746alice # [ 3.780370] systemd[1]: Stopped Create Static Device Nodes in /dev.1747alice # [ 3.781769] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1748alice # [ 3.783045] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1749alice # [ 3.784509] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1750alice # [ 3.785301] systemd[1]: Stopped Create List of Static Device Nodes.1751alice # [ 3.785865] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1752alice # [ 3.786484] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1753alice # [ 3.787011] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1754alice # [ 3.787605] systemd[1]: Finished rw-sysroot-nix-store.service.1755alice # [ 3.809753] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1756alice # [ 3.810473] systemd[1]: Finished Cleanup udev Database.1757alice # [ 3.812938] systemd[1]: Reached target Switch Root.1758alice # [ 3.813434] systemd[1]: Starting NixOS Activation...1759bob # [ 3.922315] initrd-nixos-activation-start[179]: booting system configuration /nix/store/sf5arp0mfbv548i83h2xqs2pa3hzimr3-nixos-system-bob-test1760clare # [ 3.931751] initrd-nixos-activation-start[181]: booting system configuration /nix/store/4qnx8r25v3a5p5minkr499yps30grib6-nixos-system-clare-test1761bob # [ 3.992902] initrd-nixos-activation-start[179]: running activation script...1762clare # [ 3.988200] initrd-nixos-activation-start[181]: running activation script...1763alice # [ 3.982918] initrd-nixos-activation-start[182]: booting system configuration /nix/store/hlkiw87fx747c4krnfxw0h114c74haci-nixos-system-alice-test1764alice # [ 4.044550] initrd-nixos-activation-start[182]: running activation script...1765bob # [ 4.450538] initrd-nixos-activation-start[202]: setting up /etc...1766clare # [ 4.441253] initrd-nixos-activation-start[204]: setting up /etc...1767alice # [ 4.508225] initrd-nixos-activation-start[205]: setting up /etc...1768bob # [ 4.670003] initrd-nixos-activation-start[202]: setting up age secrets...1769clare # [ 4.687472] initrd-nixos-activation-start[204]: setting up age secrets...1770alice # [ 4.727143] initrd-nixos-activation-start[205]: setting up age secrets...1771bob # [ 4.846673] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1772bob # [ 4.848575] systemd[1]: Finished NixOS Activation.1773bob # [ 4.849377] systemd[1]: Starting Switch Root...1774clare # [ 4.835790] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1775clare # [ 4.837766] systemd[1]: Finished NixOS Activation.1776clare # [ 4.838468] systemd[1]: Starting Switch Root...1777bob # [ 4.870087] systemd[1]: Switching root.1778clare # [ 4.853310] systemd[1]: Switching root.1779alice # [ 4.865796] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1780alice # [ 4.867613] systemd[1]: Finished NixOS Activation.1781alice # [ 4.868499] systemd[1]: Starting Switch Root...1782alice # [ 4.889955] systemd[1]: Switching root.1783clare # [ 5.021991] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1784bob # [ 5.051956] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1785alice # [ 5.056983] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1786bob # [ 5.762515] systemd[1]: systemd 261.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1787bob # [ 5.769020] systemd[1]: Detected virtualization qemu.1788bob # [ 5.771039] systemd[1]: Detected architecture arm64.1789bob # [ 5.772238] systemd[1]: Detected first boot.1790bob # [ 5.777585] systemd[1]: Initializing machine ID from random generator.1791clare # [ 5.752321] systemd[1]: systemd 261.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1792clare # [ 5.758861] systemd[1]: Detected virtualization qemu.1793clare # [ 5.761014] systemd[1]: Detected architecture arm64.1794alice # [ 5.735271] systemd[1]: systemd 261.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1795clare # [ 5.762165] systemd[1]: Detected first boot.1796alice # [ 5.742070] systemd[1]: Detected virtualization qemu.1797alice # [ 5.743755] systemd[1]: Detected architecture arm64.1798alice # [ 5.746043] systemd[1]: Detected first boot.1799clare # [ 5.767581] systemd[1]: Initializing machine ID from random generator.1800alice # [ 5.751946] systemd[1]: Initializing machine ID from random generator.1801bob # [ 5.940277] systemd[1]: bpf-restrict-fs: LSM BPF program attached1802clare # [ 6.056652] systemd[1]: bpf-restrict-fs: LSM BPF program attached1803alice # [ 6.040112] systemd[1]: bpf-restrict-fs: LSM BPF program attached1804bob # [ 6.091799] systemd[1]: Applying preset policy.1805clare # [ 6.228397] systemd[1]: Applying preset policy.1806alice # [ 6.209311] systemd[1]: Applying preset policy.1807bob # [ 6.623796] systemd[1]: Populated /etc with preset unit settings.1808alice # [ 6.682542] systemd[1]: Populated /etc with preset unit settings.1809clare # [ 6.738069] systemd[1]: Populated /etc with preset unit settings.1810bob # [ 7.160099] systemd[1]: initrd-switch-root.service: Deactivated successfully.1811bob # [ 7.160936] systemd[1]: Stopped initrd-switch-root.service.1812bob # [ 7.163030] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1813bob # [ 7.166180] systemd[1]: Created slice Slice /system/getty.1814bob # [ 7.167152] systemd[1]: Created slice User and Session Slice.1815bob # [ 7.167791] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1816bob # [ 7.169383] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1817bob # [ 7.170092] systemd[1]: Expecting device /dev/hvc0...1818bob # [ 7.170117] systemd[1]: Expecting device /dev/ttyAMA0...1819bob # [ 7.170144] systemd[1]: Reached target Local Encrypted Volumes.1820bob # [ 7.170174] systemd[1]: Stopped target initrd-fs.target.1821bob # [ 7.170575] systemd[1]: Stopped target initrd-root-fs.target.1822bob # [ 7.170858] systemd[1]: Stopped target initrd-switch-root.target.1823bob # [ 7.171135] systemd[1]: Reached target Virtual Machines and Containers.1824bob # [ 7.171420] systemd[1]: Reached target Path Units.1825bob # [ 7.171675] systemd[1]: Reached target Remote File Systems.1826bob # [ 7.171924] systemd[1]: Reached target Slice Units.1827bob # [ 7.172176] systemd[1]: Reached target Swaps.1828bob # [ 7.179768] systemd[1]: Listening on Query the User Interactively for a Password.1829bob # [ 7.184222] systemd[1]: Listening on Process Core Dump Socket.1830bob # [ 7.187750] systemd[1]: Listening on Credential Encryption/Decryption.1831bob # [ 7.191252] systemd[1]: Listening on Factory Reset Management.1832bob # [ 7.192225] systemd[1]: Listening on Hostname Service Socket.1833bob # [ 7.196784] systemd[1]: Starting Journal Log Access Socket...1834bob # [ 7.198934] systemd[1]: Listening on Journal Audit Socket.1835bob # [ 7.202763] systemd[1]: Listening on Console Output Muting Service Socket.1836bob # [ 7.204395] systemd[1]: Listening on Network Management Metrics Varlink Socket.1837bob # [ 7.206339] systemd[1]: Listening on Network Management Varlink Socket.1838bob # [ 7.207973] systemd[1]: Listening on Network Management Netlink Socket.1839bob # [ 7.210386] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1840bob # [ 7.211920] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1841bob # [ 7.213576] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1842bob # [ 7.223430] systemd[1]: Listening on Disk Repartitioning Service Socket.1843bob # [ 7.223902] systemd[1]: Listening on Resolve Monitor Varlink Socket.1844bob # [ 7.224240] systemd[1]: Listening on Resolve Service Varlink Socket.1845bob # [ 7.224562] systemd[1]: Listening on udev Control Socket.1846bob # [ 7.224881] systemd[1]: Listening on udev Varlink Socket.1847bob # [ 7.230036] systemd[1]: Mounting Huge Pages File System...1848bob # [ 7.233295] systemd[1]: Mounting POSIX Message Queue File System...1849bob # [ 7.239538] systemd[1]: Mounting Kernel Debug File System...1850bob # [ 7.249041] systemd[1]: Mounting Kernel Trace File System...1851bob # [ 7.256453] systemd[1]: Starting Create List of Static Device Nodes...1852bob # [ 7.266593] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1853bob # [ 7.268060] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1854alice # [ 7.227778] systemd[1]: initrd-switch-root.service: Deactivated successfully.1855alice # [ 7.228668] systemd[1]: Stopped initrd-switch-root.service.1856alice # [ 7.230803] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1857bob # [ 7.281200] systemd[1]: Mounting Kernel Configuration File System...1858alice # [ 7.233584] systemd[1]: Created slice Slice /system/getty.1859bob # [ 7.282959] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1860alice # [ 7.235143] systemd[1]: Created slice User and Session Slice.1861alice # [ 7.236321] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1862bob # [ 7.285207] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1863alice # [ 7.237891] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1864alice # [ 7.239131] systemd[1]: Expecting device /dev/hvc0...1865alice # [ 7.240163] systemd[1]: Expecting device /dev/ttyAMA0...1866alice # [ 7.241076] systemd[1]: Reached target Local Encrypted Volumes.1867alice # [ 7.242583] systemd[1]: Stopped target initrd-fs.target.1868alice # [ 7.243742] systemd[1]: Stopped target initrd-root-fs.target.1869alice # [ 7.244729] systemd[1]: Stopped target initrd-switch-root.target.1870alice # [ 7.246310] systemd[1]: Reached target Virtual Machines and Containers.1871alice # [ 7.247856] systemd[1]: Reached target Path Units.1872bob # [ 7.297055] systemd[1]: Starting Load Kernel Module fuse...1873clare # [ 7.270268] systemd[1]: initrd-switch-root.service: Deactivated successfully.1874alice # [ 7.249268] systemd[1]: Reached target Remote File Systems.1875clare # [ 7.271340] systemd[1]: Stopped initrd-switch-root.service.1876alice # [ 7.250176] systemd[1]: Reached target Slice Units.1877alice # [ 7.251099] systemd[1]: Reached target Swaps.1878bob # [ 7.298660] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671879clare # [ 7.272965] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1880clare # [ 7.275817] systemd[1]: Created slice Slice /system/getty.1881alice # [ 7.254824] systemd[1]: Listening on Query the User Interactively for a Password.1882clare # [ 7.277253] systemd[1]: Created slice User and Session Slice.1883clare # [ 7.278621] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1884alice # [ 7.259503] systemd[1]: Listening on Process Core Dump Socket.1885clare # [ 7.280188] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1886clare # [ 7.281577] systemd[1]: Expecting device /dev/hvc0...1887clare # [ 7.282796] systemd[1]: Expecting device /dev/ttyAMA0...1888alice # [ 7.263125] systemd[1]: Listening on Credential Encryption/Decryption.1889clare # [ 7.284020] systemd[1]: Reached target Local Encrypted Volumes.1890clare # [ 7.285281] systemd[1]: Stopped target initrd-fs.target.1891clare # [ 7.286159] systemd[1]: Stopped target initrd-root-fs.target.1892clare # [ 7.287163] systemd[1]: Stopped target initrd-switch-root.target.1893alice # [ 7.266762] systemd[1]: Listening on Factory Reset Management.1894clare # [ 7.288104] systemd[1]: Reached target Virtual Machines and Containers.1895alice # [ 7.267781] systemd[1]: Listening on Hostname Service Socket.1896clare # [ 7.289069] systemd[1]: Reached target Path Units.1897clare # [ 7.289899] systemd[1]: Reached target Remote File Systems.1898clare # [ 7.290789] systemd[1]: Reached target Slice Units.1899clare # [ 7.291956] systemd[1]: Reached target Swaps.1900alice # [ 7.272569] systemd[1]: Starting Journal Log Access Socket...1901alice # [ 7.274267] systemd[1]: Listening on Journal Audit Socket.1902clare # [ 7.295150] systemd[1]: Listening on Query the User Interactively for a Password.1903clare # [ 7.299034] systemd[1]: Listening on Process Core Dump Socket.1904alice # [ 7.278084] systemd[1]: Listening on Console Output Muting Service Socket.1905alice # [ 7.279262] systemd[1]: Listening on Network Management Metrics Varlink Socket.1906clare # [ 7.302026] systemd[1]: Listening on Credential Encryption/Decryption.1907alice # [ 7.280996] systemd[1]: Listening on Network Management Varlink Socket.1908alice # [ 7.282904] systemd[1]: Listening on Network Management Netlink Socket.1909clare # [ 7.305115] systemd[1]: Listening on Factory Reset Management.1910alice # [ 7.284669] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1911clare # [ 7.306099] systemd[1]: Listening on Hostname Service Socket.1912alice # [ 7.286405] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1913alice # [ 7.288326] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1914clare # [ 7.310425] systemd[1]: Starting Journal Log Access Socket...1915clare # [ 7.312418] systemd[1]: Listening on Journal Audit Socket.1916clare # [ 7.316384] systemd[1]: Listening on Console Output Muting Service Socket.1917clare # [ 7.317957] systemd[1]: Listening on Network Management Metrics Varlink Socket.1918clare # [ 7.319627] systemd[1]: Listening on Network Management Varlink Socket.1919alice # [ 7.299407] systemd[1]: Listening on Disk Repartitioning Service Socket.1920alice # [ 7.301404] systemd[1]: Listening on Resolve Monitor Varlink Socket.1921clare # [ 7.321575] systemd[1]: Listening on Network Management Netlink Socket.1922clare # [ 7.322148] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1923clare # [ 7.322522] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1924alice # [ 7.303223] systemd[1]: Listening on Resolve Service Varlink Socket.1925clare # [ 7.322805] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1926alice # [ 7.304783] systemd[1]: Listening on udev Control Socket.1927alice # [ 7.306327] systemd[1]: Listening on udev Varlink Socket.1928alice # [ 7.310297] systemd[1]: Mounting Huge Pages File System...1929clare # [ 7.332963] systemd[1]: Listening on Disk Repartitioning Service Socket.1930clare # [ 7.334081] systemd[1]: Listening on Resolve Monitor Varlink Socket.1931clare # [ 7.335181] systemd[1]: Listening on Resolve Service Varlink Socket.1932alice # [ 7.314423] systemd[1]: Mounting POSIX Message Queue File System...1933clare # [ 7.336212] systemd[1]: Listening on udev Control Socket.1934clare # [ 7.337164] systemd[1]: Listening on udev Varlink Socket.1935bob # [ 7.366262] systemd[1]: Starting Journal Service...1936clare # [ 7.340746] systemd[1]: Mounting Huge Pages File System...1937clare # [ 7.344562] systemd[1]: Mounting POSIX Message Queue File System...1938alice # [ 7.325041] systemd[1]: Mounting Kernel Debug File System...1939clare # [ 7.349077] systemd[1]: Mounting Kernel Debug File System...1940alice # [ 7.334432] systemd[1]: Mounting Kernel Trace File System...1941clare # [ 7.359955] systemd[1]: Mounting Kernel Trace File System...1942clare # [ 7.366333] systemd[1]: Starting Create List of Static Device Nodes...1943alice # [ 7.346293] systemd[1]: Starting Create List of Static Device Nodes...1944bob # [ 7.397028] systemd[1]: Starting Load Kernel Modules...1945alice # [ 7.355933] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1946alice # [ 7.357391] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1947clare # [ 7.380340] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1948clare # [ 7.381423] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1949alice # [ 7.370673] systemd[1]: Mounting Kernel Configuration File System...1950alice # [ 7.371676] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1951alice # [ 7.373811] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1952clare # [ 7.396741] systemd[1]: Mounting Kernel Configuration File System...1953clare # [ 7.398070] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1954clare # [ 7.401026] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1955bob # [ 7.430523] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1956alice # [ 7.384758] systemd[1]: Starting Load Kernel Module fuse...1957alice # [ 7.387702] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671958bob # [ 7.440455] fuse: init (API version 7.45)1959clare # [ 7.426527] systemd[1]: Starting Load Kernel Module fuse...1960clare # [ 7.428121] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671961bob # [ 7.458097] systemd[1]: Starting Remount Root and Kernel File Systems...1962bob # [ 7.461582] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1963bob # [ 7.483459] systemd[1]: Starting Coldplug All udev Devices...1964alice # [ 7.449040] systemd[1]: Starting Journal Service...1965bob # [ 7.502213] systemd[1]: Listening on Journal Log Access Socket.1966clare # [ 7.475323] systemd[1]: Starting Journal Service...1967bob # [ 7.504185] systemd[1]: Mounted Huge Pages File System.1968bob # [ 7.506665] systemd[1]: Mounted POSIX Message Queue File System.1969bob # [ 7.509195] systemd-journald[296]: Collecting audit messages is enabled.1970bob # [ 7.511931] systemd[1]: Mounted Kernel Debug File System.1971bob # [ 7.514927] systemd[1]: Mounted Kernel Trace File System.1972bob # [ 7.518496] systemd[1]: Finished Create List of Static Device Nodes.1973bob # [ 7.521873] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1974bob # [ 7.519123] systemd[1]: Queued start job for default target Multi-User System.1975bob # [ 7.526453] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1976bob # [ 7.527896] systemd[1]: Started Journal Service.1977clare # [ 7.501171] systemd[1]: Starting Load Kernel Modules...1978alice # [ 7.480442] systemd[1]: Starting Load Kernel Modules...1979bob # [ 7.525578] systemd[1]: systemd-journald.service: Deactivated successfully.1980bob # [ 7.530354] systemd-modules-load[297]: Module 'atkbd' is built in1981bob # [ 7.535362] systemd-modules-load[297]: Module 'loop' is built in1982bob # [ 7.539692] systemd[1]: Mounted Kernel Configuration File System.1983clare # [ 7.522643] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1984bob # [ 7.543367] systemd[1]: modprobe@fuse.service: Deactivated successfully.1985bob # [ 7.547709] systemd[1]: Finished Load Kernel Module fuse.1986bob # [ 7.551027] systemd[1]: Finished Load Kernel Modules.1987clare # [ 7.532229] systemd[1]: Starting Remount Root and Kernel File Systems...1988alice # [ 7.513291] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1989bob # [ 7.557057] systemd[1]: Mounting FUSE Control File System...1990clare # [ 7.534425] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1991bob # [ 7.564070] systemd[1]: Starting Firewall...1992bob # [ 7.564632] systemd[1]: Starting Apply Kernel Variables...1993alice # [ 7.523882] fuse: init (API version 7.45)1994alice # [ 7.536440] systemd[1]: Starting Remount Root and Kernel File Systems...1995clare # [ 7.560602] systemd[1]: Starting Coldplug All udev Devices...1996alice # [ 7.539671] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1997clare # [ 7.568588] fuse: init (API version 7.45)1998bob # [ 7.600790] EXT4-fs (vda): re-mounted 69b6f673-0dd1-4751-8d10-fd92a340c62d.1999bob # [ 7.602580] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2000alice # [ 7.571366] systemd[1]: Starting Coldplug All udev Devices...2001clare # [ 7.593225] systemd-journald[290]: Collecting audit messages is enabled.2002clare # [ 7.591725] systemd[1]: Queued start job for default target Multi-User System.2003clare # [ 7.602279] systemd[1]: Started Journal Service.2004clare # [ 7.598310] systemd[1]: systemd-journald.service: Deactivated successfully.2005alice # [ 7.586374] systemd[1]: Listening on Journal Log Access Socket.2006bob # [ 7.630122] systemd-oomd[298]: No swap; memory pressure usage will be degraded2007alice # [ 7.589745] systemd[1]: Mounted Huge Pages File System.2008clare # [ 7.604914] systemd-modules-load[291]: Module 'atkbd' is built in2009clare # [ 7.608523] systemd-modules-load[291]: Module 'loop' is built in2010bob # [ 7.637773] systemd[1]: Finished Remount Root and Kernel File Systems.2011alice # [ 7.596166] systemd[1]: Mounted POSIX Message Queue File System.2012clare # [ 7.613406] systemd[1]: Listening on Journal Log Access Socket.2013alice # [ 7.602675] systemd[1]: Mounted Kernel Debug File System.2014bob # [ 7.646981] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2015clare # [ 7.619589] systemd[1]: Mounted Huge Pages File System.2016clare # [ 7.620489] systemd[1]: Mounted POSIX Message Queue File System.2017alice # [ 7.606888] systemd[1]: Mounted Kernel Trace File System.2018clare # [ 7.624979] systemd[1]: Mounted Kernel Debug File System.2019clare # [ 7.627861] systemd[1]: Mounted Kernel Trace File System.2020clare # [ 7.632432] systemd[1]: Finished Create List of Static Device Nodes.2021clare # [ 7.633069] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.2022clare # [ 7.633734] systemd[1]: Finished Load Kernel Module 9pnet_virtio.2023clare # [ 7.634316] systemd[1]: Mounted Kernel Configuration File System.2024clare # [ 7.634816] systemd[1]: modprobe@fuse.service: Deactivated successfully.2025clare # [ 7.635368] systemd[1]: Finished Load Kernel Module fuse.2026alice # [ 7.617406] systemd[1]: Finished Create List of Static Device Nodes.2027clare # [ 7.635812] systemd[1]: Finished Load Kernel Modules.2028alice # [ 7.618122] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.2029alice # [ 7.618611] systemd[1]: Finished Load Kernel Module 9pnet_virtio.2030alice # [ 7.619074] systemd[1]: Mounted Kernel Configuration File System.2031alice # [ 7.619533] systemd[1]: modprobe@fuse.service: Deactivated successfully.2032alice # [ 7.619986] systemd[1]: Finished Load Kernel Module fuse.2033alice # [ 7.620564] systemd[1]: Finished Load Kernel Modules.2034clare # [ 7.647249] EXT4-fs (vda): re-mounted 910c745b-bf59-4342-b6c9-01ce2fbc03bf.2035clare # [ 7.647595] systemd[1]: Mounting FUSE Control File System...2036alice # [ 7.637398] systemd-journald[291]: Collecting audit messages is enabled.2037bob # [ 7.681241] systemd[1]: Listening on Disk Image Download Service Socket.2038clare # [ 7.654291] systemd[1]: Starting Firewall...2039alice # [ 7.640921] systemd[1]: Mounting FUSE Control File System...2040clare # [ 7.661185] systemd[1]: Starting Apply Kernel Variables...2041bob # [ 7.692213] systemd[1]: Starting Flush Journal to Persistent Storage...2042alice # [ 7.646978] systemd[1]: Queued start job for default target Multi-User System.2043alice # [ 7.653262] EXT4-fs (vda): re-mounted 0f86b075-5202-4980-84fa-7f8536b82631.2044bob # [ 7.697434] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2045alice # [ 7.657438] systemd[1]: Starting Firewall...2046alice # [ 7.653931] systemd[1]: systemd-journald.service: Deactivated successfully.2047alice # [ 7.658211] systemd-modules-load[292]: Module 'atkbd' is built in2048alice # [ 7.663010] systemd-modules-load[292]: Module 'loop' is built in2049bob # [ 7.715135] systemd[1]: Starting Load/Save OS Random Seed...2050bob # [ 7.715768] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2051bob # [ 7.716766] systemd[1]: Mounted FUSE Control File System.2052bob # [ 7.721337] systemd[1]: Finished Apply Kernel Variables.2053clare # [ 7.694749] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2054alice # [ 7.680888] systemd[1]: Starting Apply Kernel Variables...2055alice # [ 7.691662] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...2056clare # [ 7.706588] systemd-oomd[293]: No swap; memory pressure usage will be degraded2057clare # [ 7.709012] systemd[1]: Finished Remount Root and Kernel File Systems.2058alice # [ 7.693441] systemd-oomd[293]: No swap; memory pressure usage will be degraded2059bob # [ 7.746665] systemd[1]: Starting Network Name Resolution...2060alice # [ 7.705839] systemd[1]: Started Journal Service.2061clare # [ 7.727986] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2062clare # [ 7.738281] systemd[1]: Listening on Disk Image Download Service Socket.2063bob # [ 7.771790] systemd-journald[296]: Received client request to flush runtime journal.2064alice # [ 7.733212] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.2065clare # [ 7.758135] systemd[1]: Starting Flush Journal to Persistent Storage...2066clare # [ 7.758779] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2067alice # [ 7.740542] systemd[1]: Finished Remount Root and Kernel File Systems.2068clare # [ 7.767369] systemd[1]: Starting Load/Save OS Random Seed...2069clare # [ 7.771706] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2070alice # [ 7.786849] systemd[1]: Listening on Disk Image Download Service Socket.2071clare # [ 7.813797] systemd[1]: Mounted FUSE Control File System.2072alice # [ 7.798372] systemd[1]: Starting Flush Journal to Persistent Storage...2073alice # [ 7.799571] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2074alice # [ 7.806293] systemd[1]: Starting Load/Save OS Random Seed...2075clare # [ 7.827001] systemd[1]: Finished Apply Kernel Variables.2076clare # [ 7.831738] systemd[1]: Starting Network Name Resolution...2077alice # [ 7.816215] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2078alice # [ 7.820208] systemd[1]: Mounted FUSE Control File System.2079clare # [ 7.865098] systemd-journald[290]: Received client request to flush runtime journal.2080alice # [ 7.844306] systemd[1]: Finished Apply Kernel Variables.2081alice # [ 7.871414] systemd[1]: Starting Network Name Resolution...2082alice # [ 7.907394] systemd-journald[291]: Received client request to flush runtime journal.2083bob # [ 8.024712] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2084alice # [ 7.979915] systemd[1]: Finished Load/Save OS Random Seed.2085alice # [ 7.986093] systemd[1]: Reached target First Boot Complete.2086bob # [ 8.031161] systemd[1]: Starting Create Static Device Nodes in /dev...2087alice # [ 7.992237] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2088alice # [ 7.992966] systemd[1]: Starting Create Static Device Nodes in /dev...2089alice # [ 7.993563] systemd[1]: Finished Flush Journal to Persistent Storage.2090bob # [ 8.044309] systemd-resolved[325]: Positive Trust Anchors:2091bob # [ 8.048493] systemd-resolved[325]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2092bob # [ 8.049384] systemd-resolved[325]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162093bob # [ 8.050175] systemd-resolved[325]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test2094clare # [ 8.025736] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.2095clare # [ 8.031966] systemd[1]: Starting Create Static Device Nodes in /dev...2096clare # [ 8.037741] systemd[1]: Finished Load/Save OS Random Seed.2097clare # [ 8.040640] systemd[1]: Reached target First Boot Complete.2098clare # [ 8.043382] systemd-resolved[319]: Positive Trust Anchors:2099clare # [ 8.048810] systemd-resolved[319]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2100bob # [ 8.078585] systemd[1]: Finished Create Static Device Nodes in /dev.2101bob # [ 8.081675] systemd[1]: Reached target Preparation for Local File Systems.2102clare # [ 8.052664] systemd-resolved[319]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162103bob # [ 8.085153] systemd[1]: Starting Rule-based Manager for Device Events and Files...2104bob # [ 8.088915] systemd[1]: Finished Load/Save OS Random Seed.2105bob # [ 8.090469] systemd[1]: Reached target First Boot Complete.2106clare # [ 8.056692] systemd-resolved[319]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test2107bob # [ 8.092922] systemd[1]: Finished Flush Journal to Persistent Storage.2108bob # [ 8.106994] systemd-udevd[337]: Using default interface naming scheme 'v261'.2109clare # [ 8.082027] systemd[1]: Finished Flush Journal to Persistent Storage.2110clare # [ 8.082671] systemd[1]: Finished Create Static Device Nodes in /dev.2111clare # [ 8.083481] systemd[1]: Reached target Preparation for Local File Systems.2112alice # [ 8.066186] systemd[1]: Finished Create Static Device Nodes in /dev.2113clare # [ 8.085844] systemd[1]: Starting Rule-based Manager for Device Events and Files...2114alice # [ 8.067407] systemd[1]: Reached target Preparation for Local File Systems.2115alice # [ 8.073161] systemd-resolved[321]: Positive Trust Anchors:2116alice # [ 8.074269] systemd-resolved[321]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d2117alice # [ 8.081310] systemd-resolved[321]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b162118alice # [ 8.086129] systemd-resolved[321]: 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 test2119bob # [ 8.156883] systemd[1]: Mounting /run/wrappers...2120alice # [ 8.111252] systemd[1]: Starting Rule-based Manager for Device Events and Files...2121bob # [ 8.205279] systemd-resolved[325]: Using system hostname 'bob'.2122bob # [ 8.211095] systemd[1]: Started Network Name Resolution.2123bob # [ 8.225407] systemd[1]: Mounted /run/wrappers.2124bob # [ 8.226543] systemd[1]: Reached target Local File Systems.2125bob # [ 8.229115] systemd[1]: Listening on Boot Loader Control Service Socket.2126clare # [ 8.201747] systemd-udevd[335]: Using default interface naming scheme 'v261'.2127bob # [ 8.235378] systemd[1]: Starting register-nix-paths.service...2128bob # [ 8.238658] systemd[1]: Starting Create SUID/SGID Wrappers...2129bob # [ 8.240095] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2130bob # [ 8.243913] systemd[1]: Starting Save Transient machine-id to Disk...2131alice # [ 8.209564] systemd-udevd[337]: Using default interface naming scheme 'v261'.2132bob # [ 8.257742] systemd[1]: Starting Create System Files and Directories...2133alice # [ 8.229510] systemd[1]: Mounting /run/wrappers...2134clare # [ 8.272322] systemd[1]: Mounting /run/wrappers...2135clare # [ 8.277309] systemd-resolved[319]: Using system hostname 'clare'.2136clare # [ 8.289353] systemd[1]: Started Network Name Resolution.2137alice # [ 8.297283] systemd[1]: Mounted /run/wrappers.2138alice # [ 8.299556] systemd[1]: Reached target Local File Systems.2139alice # [ 8.308266] systemd[1]: Listening on Boot Loader Control Service Socket.2140alice # [ 8.316061] systemd[1]: Starting register-nix-paths.service...2141clare # [ 8.337424] systemd[1]: Mounted /run/wrappers.2142clare # [ 8.338035] systemd[1]: Reached target Local File Systems.2143clare # [ 8.341338] systemd[1]: Listening on Boot Loader Control Service Socket.2144alice # [ 8.326279] systemd[1]: Starting Create SUID/SGID Wrappers...2145alice # [ 8.326885] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2146clare # [ 8.346580] systemd[1]: Starting register-nix-paths.service...2147alice # [ 8.332479] systemd[1]: Starting Save Transient machine-id to Disk...2148clare # [ 8.362127] systemd[1]: Starting Create SUID/SGID Wrappers...2149clare # [ 8.363402] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2150alice # [ 8.351599] systemd-resolved[321]: Using system hostname 'alice'.2151clare # [ 8.372232] systemd[1]: Starting Save Transient machine-id to Disk...2152bob # [ 8.413581] systemd[1]: Started Rule-based Manager for Device Events and Files.2153clare # [ 8.399144] systemd[1]: Starting Create System Files and Directories...2154alice # [ 8.384660] systemd[1]: Starting Create System Files and Directories...2155alice # [ 8.390286] systemd[1]: Started Network Name Resolution.2156bob # [ 8.457194] systemd[1]: Finished Create System Files and Directories.2157bob # [ 8.471708] systemd[1]: Starting Rebuild Journal Catalog...2158bob # [ 8.475887] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2159bob # [ 8.521344] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2160bob # [ 8.529830] systemd[1]: Finished Save Transient machine-id to Disk.2161alice # [ 8.483879] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2162clare # [ 8.513710] systemd[1]: Started Rule-based Manager for Device Events and Files.2163alice # [ 8.496089] systemd[1]: Finished Save Transient machine-id to Disk.2164clare # [ 8.525748] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.2165clare # [ 8.537276] systemd[1]: Finished Save Transient machine-id to Disk.2166clare # [ 8.574664] systemd[1]: Finished Create System Files and Directories.2167alice # [ 8.557448] systemd[1]: Started Rule-based Manager for Device Events and Files.2168bob # [ 8.610480] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2169alice # [ 8.569278] systemd[1]: Finished Create System Files and Directories.2170clare # [ 8.595996] systemd[1]: Starting Rebuild Journal Catalog...2171clare # [ 8.598840] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2172alice # [ 8.580287] systemd[1]: Starting Rebuild Journal Catalog...2173alice # [ 8.586966] systemd[1]: Starting Record System Boot/Shutdown in UTMP...2174bob # [ 8.652913] systemd[1]: Finished Rebuild Journal Catalog.2175bob # [ 8.663307] systemd[1]: Starting Update is Completed...2176bob # [ 8.716492] systemd[1]: Finished Coldplug All udev Devices.2177clare # [ 8.709201] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2178bob # [ 8.738054] systemd[1]: Finished Update is Completed.2179alice # [ 8.716726] systemd[1]: Finished Record System Boot/Shutdown in UTMP.2180clare # [ 8.741303] systemd[1]: Finished Coldplug All udev Devices.2181clare # [ 8.786254] systemd[1]: Finished Rebuild Journal Catalog.2182clare # [ 8.791860] systemd[1]: Starting Update is Completed...2183alice # [ 8.827708] systemd[1]: Finished Rebuild Journal Catalog.2184clare # [ 8.857202] systemd[1]: Finished Update is Completed.2185alice # [ 8.842171] systemd[1]: Starting Update is Completed...2186bob # [ 8.934604] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2187alice # [ 8.919414] systemd[1]: Finished Update is Completed.2188alice # [ 8.941337] systemd[1]: Finished Coldplug All udev Devices.2189clare # [ 9.030598] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2190alice # [ 9.073024] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2191bob # [ 9.125037] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2192bob # [ 9.127618] systemd[1]: Finished Create SUID/SGID Wrappers.2193bob # [ 9.188246] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2194bob # [ 9.227743] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2195clare # [ 9.253188] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2196clare # [ 9.256364] systemd[1]: Finished Create SUID/SGID Wrappers.2197clare # [ 9.280296] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2198clare # [ 9.328622] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2199alice # [ 9.344845] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2200alice # [ 9.348899] systemd[1]: Finished Create SUID/SGID Wrappers.2201alice # [ 9.349964] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2202alice # [ 9.393857] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2203bob # [ 9.481267] systemd[1]: Finished register-nix-paths.service.2204bob # [ 9.482783] systemd[1]: Reached target System Initialization.2205bob # [ 9.485881] systemd[1]: Started Discard unused filesystem blocks once a week.2206bob # [ 9.489068] systemd[1]: Started Daily Cleanup of Temporary Directories.2207bob # [ 9.492567] systemd[1]: Reached target Timer Units.2208bob # [ 9.493482] systemd[1]: Listening on D-Bus System Message Bus Socket.2209bob # [ 9.496133] systemd[1]: Listening on Nix Daemon Socket.2210bob # [ 9.497883] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2211bob # [ 9.501360] systemd[1]: Reached target Socket Units.2212bob # [ 9.502504] systemd[1]: Reached target Basic System.2213bob # [ 9.505922] systemd[1]: Started backdoor.service.2214bob # [ 9.506398] systemd[1]: Starting Import lastlog data into lastlog2 database...2215bob # [ 9.512176] (udev-worker)[445]: Network interface NamePolicy= disabled on kernel command line.2216bob # [ 9.514081] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2217bob # [ 9.525116] (udev-worker)[441]: Network interface NamePolicy= disabled on kernel command line.2218bob # [ 9.535526] systemd[1]: Starting Post-Boot Actions...2219bob # [ 9.562422] systemd[1]: Started Reset console on configuration changes.2220bob # [ 9.602832] systemd[1]: Starting D-Bus System Message Bus...2221clare # [ 9.586686] systemd[1]: Finished register-nix-paths.service.2222clare # [ 9.588861] systemd[1]: Reached target System Initialization.2223bob # [ 9.615403] systemd[1]: Finished Firewall.2224clare # [ 9.592180] systemd[1]: Started Discard unused filesystem blocks once a week.2225clare # [ 9.593649] systemd[1]: Started Daily Cleanup of Temporary Directories.2226clare # [ 9.596256] systemd[1]: Reached target Timer Units.2227bob # [ 9.624835] systemd[1]: Reached target Preparation for Network.2228clare # [ 9.597571] systemd[1]: Listening on D-Bus System Message Bus Socket.2229bob # [ 9.628537] systemd[1]: Listening on Network Management Resolve Hook Socket.2230clare # [ 9.602283] systemd[1]: Listening on harmonia binary cache socket.2231clare # [ 9.603323] systemd[1]: Listening on Nix Daemon Socket.2232clare # [ 9.604125] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2233clare # [ 9.609069] systemd[1]: Reached target Socket Units.2234clare # [ 9.612268] systemd[1]: Reached target Basic System.2235clare # [ 9.612714] systemd[1]: Started backdoor.service.2236clare # [ 9.613362] (udev-worker)[438]: Network interface NamePolicy= disabled on kernel command line.2237clare # [ 9.614056] systemd[1]: Starting Import lastlog data into lastlog2 database...2238bob # [ 9.644984] systemd[1]: Starting Network Management...2239clare # [ 9.621732] (udev-worker)[430]: Network interface NamePolicy= disabled on kernel command line.2240clare # [ 9.626230] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2241bob # connecting to host...2242bob # [ 9.665120] systemd[1]: Started Name Service Cache Daemon (nsncd).2243bob # [ 9.667581] nsncd[515]: Aug 14 10:05:30.458 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2244bob # [ 9.676866] systemd[1]: Finished Post-Boot Actions.2245clare # [ 9.658343] systemd[1]: Starting Post-Boot Actions...2246clare # [ 9.662511] systemd[1]: Started Reset console on configuration changes.2247bob # [ 9.701108] systemd[1]: Reached target Host and Network Name Lookups.2248bob # [ 9.703429] systemd[1]: Reached target User and Group Name Lookups.2249alice # [ 9.661447] (udev-worker)[387]: Network interface NamePolicy= disabled on kernel command line.2250alice # [ 9.665089] (udev-worker)[380]: Network interface NamePolicy= disabled on kernel command line.2251bob: Guest shell says: b'Spawning backdoor root shell...\n'2252bob # [ 9.723093] systemd[1]: Starting User Login Management...2253clare # [ 9.705286] systemd[1]: Finished Firewall.2254alice # [ 9.690931] systemd[1]: Finished Firewall.2255alice # [ 9.691425] systemd[1]: Reached target Preparation for Network.2256alice # [ 9.691904] systemd[1]: Listening on Network Management Resolve Hook Socket.2257alice # [ 9.695913] systemd[1]: Starting Network Management...2258clare # [ 9.720586] systemd[1]: Reached target Preparation for Network.2259clare # [ 9.725277] systemd[1]: Listening on Network Management Resolve Hook Socket.2260clare # [ 9.738241] systemd[1]: Starting D-Bus System Message Bus...2261clare # connecting to host...2262clare # [ 9.745835] systemd[1]: Starting Network Management...2263bob # [ 9.783700] systemd[1]: Finished Import lastlog data into lastlog2 database.2264clare # [ 9.765306] systemd[1]: Finished Post-Boot Actions.2265bob: connected to guest root shell2266bob: (connecting took 10.06 seconds)2267bob: (finished: waiting for the VM to finish booting, in 10.06 seconds)2268clare # [ 9.809298] nsncd[512]: Aug 14 10:05:30.570 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2269clare # [ 9.812850] systemd[1]: Started Name Service Cache Daemon (nsncd).2270clare # [ 9.816400] systemd[1]: Reached target Host and Network Name Lookups.2271clare # [ 9.820601] systemd[1]: Reached target User and Group Name Lookups.2272bob # [ 9.850381] dbus-broker-launch[527]: Looking up NSS user entry for 'systemd-timesync'...2273clare # [ 9.825678] systemd[1]: Starting User Login Management...2274bob # [ 9.854463] dbus-broker-launch[527]: NSS returned no entry for 'systemd-timesync'2275bob # [ 9.857687] dbus-broker-launch[527]: Invalid user-name in /nix/store/n5gd9j6qjwld9fck2rrn39h60lcgxj0r-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2276alice # [ 9.821829] systemd[1]: Finished register-nix-paths.service.2277alice # [ 9.824283] systemd[1]: Reached target System Initialization.2278alice # [ 9.827201] systemd[1]: Started Discard unused filesystem blocks once a week.2279bob # [ 9.883231] mousedev: PS/2 mouse device common for all mice2280alice # [ 9.830492] systemd[1]: Started Daily Cleanup of Temporary Directories.2281alice # [ 9.833619] systemd[1]: Reached target Timer Units.2282alice # [ 9.836222] systemd[1]: Listening on D-Bus System Message Bus Socket.2283alice # [ 9.840735] systemd[1]: Listening on Nix Daemon Socket.2284alice # [ 9.842013] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.2285clare # [ 9.864751] systemd[1]: Finished Import lastlog data into lastlog2 database.2286alice # [ 9.852241] systemd[1]: Reached target Socket Units.2287alice # [ 9.852728] systemd[1]: Reached target Basic System.2288alice # [ 9.853174] systemd[1]: Started backdoor.service.2289alice # [ 9.853582] systemd[1]: Starting Import lastlog data into lastlog2 database...2290alice # [ 9.856141] systemd[1]: Starting Name Service Cache Daemon (nsncd)...2291bob # [ 9.908110] systemd[1]: Started D-Bus System Message Bus.2292bob # [ 9.911094] systemd-logind[546]: Watching system buttons on /dev/input/event0 (gpio-keys)2293alice # [ 9.871966] mousedev: PS/2 mouse device common for all mice2294bob # [ 9.916290] systemd-logind[546]: New seat seat0.2295alice # [ 9.889696] systemd[1]: Starting Post-Boot Actions...2296clare # [ 9.924313] mousedev: PS/2 mouse device common for all mice2297alice # [ 9.913537] systemd[1]: Started Reset console on configuration changes.2298bob # [ 9.963630] systemd[1]: Started User Login Management.2299bob # [ 9.966066] dbus-broker-launch[527]: Ready2300clare # [ 9.979082] systemd-logind[541]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2301clare # [ 9.980676] systemd-logind[541]: Watching system buttons on /dev/input/event0 (gpio-keys)2302bob # [ 10.014021] systemd-logind[546]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2303bob # [ 10.014817] systemd[1]: Starting linger-users.service...2304clare # [ 9.984811] systemd-logind[541]: New seat seat0.2305clare # [ 9.989994] systemd[1]: Started User Login Management.2306clare # [ 9.993930] systemd[1]: Starting linger-users.service...2307bob # [ 10.034617] cfg80211: Loading compiled-in X.509 certificates for regulatory database2308alice # [ 10.001722] systemd[1]: Starting D-Bus System Message Bus...2309clare # [ 10.023157] dbus-broker-launch[523]: Looking up NSS user entry for 'systemd-timesync'...2310alice # connecting to host...2311clare # [ 10.027738] dbus-broker-launch[523]: NSS returned no entry for 'systemd-timesync'2312clare # [ 10.029029] dbus-broker-launch[523]: Invalid user-name in /nix/store/nziiyl4sggffhcyp07zhky43ihlnxzmf-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2313alice # [ 10.015222] systemd[1]: Finished Post-Boot Actions.[ 10.022516] cfg80211: Loading compiled-in X.509 certificates for regulatory database2314alice # 2315bob # [ 10.083115] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2316bob # [ 10.083626] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2317bob # [ 10.081291] systemd[1]: linger-users.service: Deactivated successfully.2318bob # [ 10.087835] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22319bob # [ 10.088236] cfg80211: failed to load regulatory.db2320bob # [ 10.085498] systemd[1]: Finished linger-users.service.2321clare # [ 10.064702] systemd[1]: linger-users.service: Deactivated successfully.2322clare # [ 10.066816] systemd[1]: Finished linger-users.service.2323clare # [ 10.071439] systemd[1]: Started D-Bus System Message Bus.2324alice # [ 10.070005] systemd[1]: Finished Import lastlog data into lastlog2 database.2325alice # [ 10.078651] systemd[1]: Started Name Service Cache Daemon (nsncd).2326alice # [ 10.082979] nsncd[518]: Aug 14 10:05:30.855 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"2327alice # [ 10.089811] systemd[1]: Reached target Host and Network Name Lookups.2328bob # [ 10.138948] systemd-networkd[529]: lo: Link UP2329bob # [ 10.139439] systemd-networkd[529]: lo: Gained carrier2330alice # [ 10.090387] systemd[1]: Reached target User and Group Name Lookups.2331alice # [ 10.096236] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2332alice # [ 10.096763] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2333clare # [ 10.122446] cfg80211: Loading compiled-in X.509 certificates for regulatory database2334alice # [ 10.101263] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22335alice # [ 10.101660] cfg80211: failed to load regulatory.db2336bob # [ 10.146126] systemd[1]: Started Network Management.2337alice # [ 10.099691] systemd[1]: Starting User Login Management...2338clare # [ 10.120760] dbus-broker-launch[523]: Ready2339bob # [ 10.151035] systemd-networkd[529]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2340bob # [ 10.156501] systemd[1]: Reached target Network.2341bob # [ 10.156995] systemd[1]: Reached target Network is Online.2342bob # [ 10.157445] systemd-networkd[529]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2343bob # [ 10.158665] systemd-networkd[529]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2344bob # [ 10.166084] systemd-networkd[529]: eth0: Link UP2345bob # [ 10.166695] systemd-networkd[529]: eth0: Gained carrier2346bob # [ 10.167208] systemd[1]: Starting ncps binary cache proxy service...2347bob # [ 10.172893] systemd-networkd[529]: eth1: Link UP2348bob # [ 10.173476] systemd-networkd[529]: eth1: Gained carrier2349bob # [ 10.173903] systemd-networkd[529]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2350bob # [ 10.175102] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2351clare # [ 10.160203] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2352clare # [ 10.160723] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2353bob # [ 10.185736] systemd[1]: Starting Permit User Sessions...2354bob # [ 10.186334] systemd-networkd[529]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22355clare # [ 10.165354] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22356clare # [ 10.165728] cfg80211: failed to load regulatory.db2357alice # [ 10.188476] systemd-networkd[512]: lo: Link UP2358alice # [ 10.188992] systemd-networkd[512]: lo: Gained carrier2359clare # [ 10.214416] systemd-networkd[524]: lo: Link UP2360clare # [ 10.215193] systemd-networkd[524]: lo: Gained carrier2361alice # [ 10.200365] systemd[1]: Started Network Management.2362alice # [ 10.201623] systemd[1]: Reached target Network.2363clare # [ 10.221576] systemd-networkd[524]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2364clare # [ 10.224645] systemd-networkd[524]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2365alice # [ 10.210560] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2366bob # [ 10.261118] systemd[1]: Finished Permit User Sessions.2367clare # [ 10.232658] systemd[1]: Started Network Management.2368clare # [ 10.233095] systemd[1]: Reached target Network.2369clare # [ 10.233487] systemd-networkd[524]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2370alice # [ 10.214636] systemd-networkd[512]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2371clare # [ 10.236910] systemd-networkd[524]: eth1: Link UP2372clare # [ 10.237546] systemd-networkd[524]: eth1: Gained carrier2373alice # [ 10.220861] systemd-networkd[512]: eth1: Configuring with /etc/systemd/network/40-eth1.network.2374alice # [ 10.221620] systemd-networkd[512]: eth0: Configuring with /etc/systemd/network/99-ethernet-default-dhcp.network.2375bob # [ 10.270058] systemd[1]: Started Getty on tty1.2376bob # [ 10.270550] systemd[1]: Reached target Login Prompts.2377clare # [ 10.241619] systemd-networkd[524]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2378clare # [ 10.242790] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...2379alice # [ 10.222384] systemd[1]: Starting Permit User Sessions...2380bob # [ 10.276739] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2381clare # [ 10.251474] systemd-networkd[524]: eth0: Link UP2382clare # [ 10.251953] systemd-networkd[524]: eth0: Gained carrier2383alice # [ 10.233202] systemd-networkd[512]: eth1: Link UP2384alice # [ 10.233816] systemd-networkd[512]: eth1: Gained carrier2385clare # [ 10.254488] systemd[1]: Starting Permit User Sessions...2386alice # [ 10.238376] systemd-networkd[512]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network2387clare # [ 10.260531] systemd-networkd[524]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22388alice # [ 10.244458] systemd-networkd[512]: eth0: Link UP2389alice # [ 10.252239] systemd-networkd[512]: eth0: Gained carrier2390alice # [ 10.256817] systemd-networkd[512]: eth0: DHCPv4 address 10.0.2.15/24, gateway 10.0.2.2 acquired from 10.0.2.22391alice # [ 10.272935] dbus-broker-launch[526]: Looking up NSS user entry for 'systemd-timesync'...2392bob # [ 10.322127] ncps-pre-start[573]: /nix/store/jfdayyyhg1p4vdm02yiph4kwrwh1fyqy-ncps-0.9.4/bin/dbmate-ncps up2393alice # [ 10.278673] dbus-broker-launch[526]: NSS returned no entry for 'systemd-timesync'2394alice # [ 10.281962] dbus-broker-launch[526]: Invalid user-name in /nix/store/j0azygl8p4pch9bzm1zrlnrkcrm3h61n-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2395alice # [ 10.289781] systemd-logind[540]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2396alice # [ 10.295124] systemd-logind[540]: Watching system buttons on /dev/input/event0 (gpio-keys)2397clare # [ 10.316800] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2398alice # [ 10.298076] systemd-logind[540]: New seat seat0.2399alice # [ 10.300430] systemd[1]: Started User Login Management.2400clare # [ 10.321084] systemd[1]: Finished Permit User Sessions.2401alice # [ 10.304770] systemd[1]: Starting linger-users.service...2402clare # [ 10.327846] systemd[1]: Started Getty on tty1.2403clare # [ 10.329770] systemd[1]: Reached target Login Prompts.2404clare # [ 10.331365] systemd[1]: Reached target Multi-User System.2405clare # [ 10.340416] systemd[1]: Startup finished in 789ms (kernel) + 4.473s (initrd) + 5.076s (userspace) = 10.339s.2406alice # [ 10.344223] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.2407alice # [ 10.344997] systemd[1]: Started D-Bus System Message Bus.2408alice # [ 10.385287] systemd[1]: Finished Permit User Sessions.2409alice # [ 10.387659] dbus-broker-launch[526]: Ready2410alice # [ 10.390502] systemd[1]: linger-users.service: Deactivated successfully.2411alice # [ 10.391073] systemd[1]: Finished linger-users.service.2412alice # [ 10.399668] systemd[1]: Started Getty on tty1.2413alice # [ 10.401120] systemd[1]: Reached target Login Prompts.2414alice # [ 10.401591] systemd[1]: Reached target Multi-User System.2415alice # [ 10.403973] systemd[1]: Startup finished in 771ms (kernel) + 4.473s (initrd) + 5.157s (userspace) = 10.402s.2416bob # [ 10.677516] ncps-pre-start[587]: Creating: /var/lib/ncps/db/db.sqlite2417clare # [ 10.706514] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input32418bob # [ 10.770901] ncps-pre-start[587]: Applying: 20241210054814_create-narinfos-table.sql2419bob # [ 10.789179] ncps-pre-start[587]: Applied: 20241210054814_create-narinfos-table.sql in 18.2292ms2420bob # [ 10.790775] ncps-pre-start[587]: Applying: 20241210054829_create-nars-table.sql2421bob # [ 10.807551] ncps-pre-start[587]: Applied: 20241210054829_create-nars-table.sql in 18.43556ms2422bob # [ 10.809116] ncps-pre-start[587]: Applying: 20241213014846_add-query-to-nars-table.sql2423bob # [ 10.842134] ncps-pre-start[587]: Applied: 20241213014846_add-query-to-nars-table.sql in 32.9212ms2424bob # [ 10.844072] ncps-pre-start[587]: Applying: 20251230224159_add-cascade-to-nars-fk.sql2425alice # [ 10.815320] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input32426bob # [ 10.880571] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input32427bob # [ 10.876957] ncps-pre-start[587]: Applied: 20251230224159_add-cascade-to-nars-fk.sql in 35.2598ms2428bob # [ 10.877900] ncps-pre-start[587]: Applying: 20260101000000_add-config-table.sql2429bob # [ 10.896270] ncps-pre-start[587]: Applied: 20260101000000_add-config-table.sql in 19.2956ms2430bob # [ 10.897192] ncps-pre-start[587]: Applying: 20260105025735_remove-redundant-indexes.sql2431bob # [ 10.910657] ncps-pre-start[587]: Applied: 20260105025735_remove-redundant-indexes.sql in 14.37524ms2432bob # [ 10.912496] ncps-pre-start[587]: Applying: 20260105030513_support_shared_nars_between_narinfos.sql2433bob # [ 10.931315] ncps-pre-start[587]: Applied: 20260105030513_support_shared_nars_between_narinfos.sql in 20.61596ms2434bob # [ 10.932391] ncps-pre-start[587]: Applying: 20260117195000_add_narinfo_de_normalized.sql2435bob # [ 10.954480] ncps-pre-start[587]: Applied: 20260117195000_add_narinfo_de_normalized.sql in 23.1202ms2436bob # [ 10.955574] ncps-pre-start[587]: Applying: 20260127223000_allow_multiple_nar_representations.sql2437bob # [ 10.975528] ncps-pre-start[587]: Applied: 20260127223000_allow_multiple_nar_representations.sql in 21.07104ms2438bob # [ 10.976704] ncps-pre-start[587]: Applying: 20260131021850_add_chunks.sql2439bob # [ 10.992130] ncps-pre-start[587]: Applied: 20260131021850_add_chunks.sql in 16.55468ms2440bob # [ 10.993078] ncps-pre-start[587]: Applying: 20260205063651_add_total_chunks_to_nar_files.sql2441bob # [ 11.012256] ncps-pre-start[587]: Applied: 20260205063651_add_total_chunks_to_nar_files.sql in 20.0962ms2442bob # [ 11.013373] ncps-pre-start[587]: Applying: 20260217071237_add_chunking_started_at_to_nar_files.sql2443clare # [ 10.986687] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2444bob # [ 11.027435] ncps-pre-start[587]: Applied: 20260217071237_add_chunking_started_at_to_nar_files.sql in 15.17584ms2445bob # [ 11.029727] ncps-pre-start[587]: Applying: 20260301000000_add_verified_at_to_nar_files.sql2446clare # [ 11.010398] systemd-logind[541]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2447clare # [ 11.013867] systemd[1]: Starting Virtual Console Setup...2448bob # [ 11.052498] ncps-pre-start[587]: Applied: 20260301000000_add_verified_at_to_nar_files.sql in 24.55384ms2449alice # [ 11.067010] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2450alice # [ 11.109659] systemd-logind[540]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2451alice # [ 11.117901] systemd[1]: Starting Virtual Console Setup...2452bob # [ 11.179352] systemd[1]: Started ncps binary cache proxy service.2453bob # [ 11.181611] systemd[1]: Reached target Multi-User System.2454bob # [ 11.182206] systemd[1]: Startup finished in 756ms (kernel) + 4.547s (initrd) + 5.878s (userspace) = 11.181s.2455bob # [ 11.361948] systemd-networkd[529]: eth0: Gained IPv6LL2456alice # [ 11.328894] systemd-networkd[512]: eth0: Gained IPv6LL2457bob # [ 11.447292] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2458bob # [ 11.458295] systemd-networkd[529]: eth1: Gained IPv6LL2459bob # [ 11.463186] systemd[1]: Starting Virtual Console Setup...2460clare # [ 11.461873] systemd-vconsole-setup[570]: Configuration of first virtual console was skipped, ignoring remaining ones.2461clare # [ 11.465564] systemd[1]: Finished Virtual Console Setup.2462bob # [ 11.497967] systemd-logind[546]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2463alice # [ 11.567196] systemd-vconsole-setup[568]: Configuration of first virtual console was skipped, ignoring remaining ones.2464alice # [ 11.570012] systemd[1]: Finished Virtual Console Setup.2465bob # [ 11.643408] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","log_level":"info","time":"2026-08-14T10:05:32Z","message":"logger created"}2466bob # [ 11.644972] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","xz-binary-path":"/nix/store/7i4c31hkr3f5859qkwkd1q9avmsa6s74-xz-5.8.3-bin/bin/xz","time":"2026-08-14T10:05:32Z","message":"Using xz binary for xz decompression"}2467bob # [ 11.653456] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","operation":"auto-max-procs","time":"2026-08-14T10:05:32Z","message":"maxprocs: Leaving GOMAXPROCS=1: CPU quota undefined"}2468bob # [ 11.761210] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","time":"2026-08-14T10:05:32Z","message":"using local locks (single-instance mode)"}2469bob # [ 11.768474] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-08-14T10:05:32Z","message":"setting up tracer provider to discard traces"}2470bob # [ 11.772052] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-08-14T10:05:32Z","message":"setting up meter provider to discard metrics"}2471bob # [ 11.776510] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","otel-enabled":false,"otel-grpc-url":"","time":"2026-08-14T10:05:32Z","message":"setting up logger provider to discard logs"}2472bob # [ 11.786855] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","endpoint":"otlp.ncps.dev:443","time":"2026-08-14T10:05:32Z","message":"Reporting anonymous metrics to the project maintainers"}2473bob # [ 11.789858] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"warn","cmd":"serve","error":"open /var/empty/.netrc: no such file or directory","time":"2026-08-14T10:05:32Z","message":"failed to parse netrc file, proceeding without netrc authentication"}2474bob # [ 11.794519] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","path":"/var/lib/ncps","time":"2026-08-14T10:05:32Z","message":"using local storage"}2475clare # [ 11.810385] systemd[1]: Starting harmonia binary cache service...2476clare # [ 11.842894] systemd-networkd[524]: eth0: Gained IPv6LL2477bob # [ 11.901209] systemd-vconsole-setup[620]: Configuration of first virtual console was skipped, ignoring remaining ones.2478bob # [ 11.903697] systemd[1]: Finished Virtual Console Setup.2479clare # [ 11.975091] harmonia-cache[573]: 2026-08-14T10:05:32.735026Z WARN harmonia_cache::tls: /run/credentials/harmonia.service/sign-key-0 has insecure permissions 0o440; recommend 06002480clare # [ 11.985523] harmonia-cache[573]: 2026-08-14T10:05:32.745896Z INFO harmonia_cache: listening on inherited fd 3 (Ok([::]:5000))2481clare # [ 11.988369] harmonia-cache[573]: 2026-08-14T10:05:32.746778Z INFO actix_server::builder: starting 4 workers2482clare # [ 11.991810] harmonia-cache[573]: 2026-08-14T10:05:32.751872Z INFO harmonia_cache::systemd: systemd watchdog enabled, pinging every 7.5s2483clare # [ 11.993812] harmonia-cache[573]: 2026-08-14T10:05:32.751919Z INFO actix_server::server: Actix runtime found; starting in Actix runtime2484clare # [ 11.996248] systemd[1]: Started harmonia binary cache service.2485clare # [ 11.996860] harmonia-cache[573]: 2026-08-14T10:05:32.751937Z INFO actix_server::server: starting service: "actix-web-service-[::]:5000", workers: 4, listening on: [::]:50002486bob # [ 12.036180] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","upstream":"clare.clan","time":"2026-08-14T10:05:32Z","message":"upstream became healthy and is now available for requests"}2487bob # [ 12.042970] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","server_addr":":8502","time":"2026-08-14T10:05:32Z","message":"Server started"}2488alice # [ 12.065189] systemd-networkd[512]: eth1: Gained IPv6LL2489clare # [ 12.192959] systemd-networkd[524]: eth1: Gained IPv6LL2490bob: (finished: waiting for unit ncps, in 12.48 seconds)2491bob: must succeed: systemctl status ncps2492bob: (finished: must succeed: systemctl status ncps, in 0.05 seconds)2493clare: waiting for unit harmonia.socket2494clare: waiting for the VM to finish booting2495clare: Guest shell says: b'Spawning backdoor root shell...\n'2496clare: connected to guest root shell2497clare: (connecting took 0.00 seconds)2498clare: (finished: waiting for the VM to finish booting, in 0.00 seconds)2499clare: (finished: waiting for unit harmonia.socket, in 0.09 seconds)2500alice: waiting for success: curl bob:8502/nix-cache-info2501alice: waiting for the VM to finish booting2502alice: Guest shell says: b'Spawning backdoor root shell...\n'2503alice: connected to guest root shell2504alice: (connecting took 0.00 seconds)2505alice: (finished: waiting for the VM to finish booting, in 0.00 seconds)2506alice # % Total % Received % Xferd Average Speed Time Time Time Current2507alice # Dload Upload Total Spent Left Speed2508bob # [ 12.537870] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:37758","trace_id":"e05f46bddb77e9198424c6d725aa88de","span_id":"9840acbd5c6d71a0","status":200,"elapsed":0.09392,"bytes":50,"time":"2026-08-14T10:05:33Z","message":"handled request"}2509alice # 0 0 0 0 0 0 0 0 0100 50 100 50 0 0 1056 0 0100 50 100 50 0 0 1012 0 0100 50 100 50 0 0 972 0 02510alice: (finished: waiting for success: curl bob:8502/nix-cache-info, in 0.16 seconds)2511bob: waiting for success: curl clare:5000/nix-cache-info2512bob # % Total % Received % Xferd Average Speed Time Time Time Current2513bob # Dload Upload Total Spent Left Speed2514bob # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 4634 0 0100 51 100 51 0 0 3926 0 0100 51 100 51 0 0 3349 0 02515bob: (finished: waiting for success: curl clare:5000/nix-cache-info, in 0.11 seconds)2516clare: waiting for unit harmonia2517clare: (finished: waiting for unit harmonia, in 0.12 seconds)2518clare: must succeed: systemctl status harmonia2519bob # [ 12.796176] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: 2026/08/14 10:05:33 Post "https://otlp.ncps.dev:443/v1/logs": dial tcp: lookup otlp.ncps.dev: no such host2520clare: (finished: must succeed: systemctl status harmonia, in 0.03 seconds)2521clare: must succeed: nix-build --expr '2522 builtins.derivation {2523 name = "hi";2524 builder = "/bin/sh";2525 args = [ "-c" "echo hi > $out" ];2526 system = "aarch64-linux";2527 }2528' 2>&125292530clare # [ 12.967027] systemd-resolved[319]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3.2531clare # [ 12.970040] systemd-resolved[319]: Using degraded feature set TCP instead of UDP for DNS server 10.0.2.3.2532clare # [ 12.971787] systemd-resolved[319]: Using degraded feature set UDP instead of TCP for DNS server 10.0.2.3.2533clare: (finished: must succeed: nix-build --expr '2534 builtins.derivation {2535 name = "hi";2536 builder = "/bin/sh";2537 args = [ "-c" "echo hi > $out" ];2538 system = "aarch64-linux";2539 }2540' 2>&12541, in 4.35 seconds)2542alice: must succeed: nix-build --expr '2543 builtins.derivation {2544 name = "hi";2545 builder = "/bin/sh";2546 args = [ "-c" "echo hi > $out" ];2547 system = "aarch64-linux";2548 }2549' 2>&125502551alice # [ 17.265188] systemd-resolved[321]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 10.0.2.3.2552bob # [ 17.308992] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nix-cache-info","from":"[2001:db8:1::1]:37762","trace_id":"b82446feaa478803706318d2c04ae8ac","span_id":"167b83c893e33603","status":200,"elapsed":0.03296,"bytes":50,"time":"2026-08-14T10:05:38Z","message":"handled request"}2553bob # [ 21.917002] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:37762","trace_id":"e1dc4793391535f232bc2f6ba49d0694","span_id":"b836bfdfad8ba8e9","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_url":"http://clare.clan:5000/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","upstream_url":"http://clare.clan:5000","time":"2026-08-14T10:05:42Z","message":"download the narinfo from upstream"}2554bob # [ 21.959357] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:37762","trace_id":"e1dc4793391535f232bc2f6ba49d0694","span_id":"b836bfdfad8ba8e9","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-08-14T10:05:42Z","message":"downloading the nar from upstream"}2555bob # [ 21.971584] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:37762","trace_id":"e1dc4793391535f232bc2f6ba49d0694","span_id":"b836bfdfad8ba8e9","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_hash":"1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h","nar_compression":"none","nar_query":"hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"http://clare.clan:5000/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","upstream_url":"http://clare.clan:5000","nar_hash":"1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h","nar_compression":"none","nar_query":"hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-08-14T10:05:42Z","message":"download the nar from upstream"}2556bob # [ 22.002443] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:37762","trace_id":"e1dc4793391535f232bc2f6ba49d0694","span_id":"b836bfdfad8ba8e9","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","time":"2026-08-14T10:05:42Z","message":"storing narinfo and nar_file record in the database"}2557bob # [ 22.024085] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:37762","trace_id":"e1dc4793391535f232bc2f6ba49d0694","span_id":"b836bfdfad8ba8e9","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":108.15416,"time":"2026-08-14T10:05:42Z","message":"download of narinfo complete"}2558bob # [ 22.034904] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:37762","trace_id":"e1dc4793391535f232bc2f6ba49d0694","span_id":"b836bfdfad8ba8e9","status":200,"elapsed":122.91692,"bytes":532,"time":"2026-08-14T10:05:42Z","message":"handled request"}2559bob # [ 22.041759] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/dijrshgr0dg50jkvhxs7kzxmvz9zci8p.narinfo","from":"[2001:db8:1::1]:37762","trace_id":"e1dc4793391535f232bc2f6ba49d0694","span_id":"b836bfdfad8ba8e9","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","narinfo_hash":"dijrshgr0dg50jkvhxs7kzxmvz9zci8p","nar_url":"nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","elapsed":75.53184,"time":"2026-08-14T10:05:42Z","message":"download of nar complete"}2560bob # [ 22.049396] xw541s5a4kxjhika7zm6r62vl6921by5-ncps-wrapper[595]: {"level":"info","cmd":"serve","method":"GET","request_uri":"/nar/1xmr8jicvzszfzpz46g37mlpvbzjl2wpwvl2b05psipssyp1sm8h.nar?hash=dijrshgr0dg50jkvhxs7kzxmvz9zci8p","from":"[2001:db8:1::1]:37762","trace_id":"227fca9f6bcc395ed4e521ac0b4b283f","span_id":"46aba2fc921b274e","status":200,"elapsed":8.34508,"bytes":95,"time":"2026-08-14T10:05:42Z","message":"handled request"}2561alice: (finished: must succeed: nix-build --expr '2562 builtins.derivation {2563 name = "hi";2564 builder = "/bin/sh";2565 args = [ "-c" "echo hi > $out" ];2566 system = "aarch64-linux";2567 }2568' 2>&12569, in 4.91 seconds)2570(finished: run the VM test script, in 23.01 seconds)2571test script finished in 23.17s2572cleanup2573kill QemuMachine (pid 48)2574alice # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3inr3nxzqarhb9rh1z7s2s20j8c0395h-python3-3.14.7/bin/python3.14)2575kill QemuMachine (pid 50)2576bob # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3inr3nxzqarhb9rh1z7s2s20j8c0395h-python3-3.14.7/bin/python3.14)2577kill QemuMachine (pid 49)2578clare # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3inr3nxzqarhb9rh1z7s2s20j8c0395h-python3-3.14.7/bin/python3.14)2579(finished: cleanup, in 0.37 seconds)