vm-test-run-wifi
checks.aarch64-linux.wifi
· build #295
· raw
1build01: building gwqqqgmrjyb2nrg9ikdkp13crwqxsqhy-vm-test-run-wifi.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 second, test,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12second: starting vm13test: starting vm14second # Disk image does not exist, creating the virtualisation disk image...15second: QEMU running (pid 47)16test # Disk image does not exist, creating the virtualisation disk image...17test: QEMU running (pid 48)18test # Formatting '/build/vm-state-test/tmp.h84rXZGRLq', fmt=raw size=107374182419second # Formatting '/build/vm-state-second/tmp.OE9yswNXwo', fmt=raw size=107374182420test # mke2fs 1.47.4 (6-Mar-2025)21second # mke2fs 1.47.4 (6-Mar-2025)22(finished: start all VMs, in 0.81 seconds)23second # Discarding device blocks: 0/262144 done24test: waiting for unit NetworkManager.service25second # Creating filesystem with 262144 4k blocks and 65536 inodes26test: waiting for the VM to finish booting27second # Filesystem UUID: 8401d8ce-4aae-4d29-817d-75787602346e28test # Discarding device blocks: 0/262144 done29second # Superblock backups stored on blocks:30second # 32768, 98304, 163840, 22937631second # 32second # Allocating group tables: 0/8 done33second # Writing inode tables: 0/8 done34second # Creating journal (8192 blocks): done35second # Writing superblocks and filesystem accounting information: 0/8 done36second # 37second # Virtualisation disk image created.38test # Creating filesystem with 262144 4k blocks and 65536 inodes39second # Starting virtiofs daemons...40test # Filesystem UUID: 981b3276-3780-48c0-b37a-0b53d842044841second # [2026-10-22T15:01:59Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)42test # Superblock backups stored on blocks:43second # [2026-10-22T15:01:59Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether44test # 32768, 98304, 163840, 22937645second # [2026-10-22T15:01:59Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)46test # 47second # [2026-10-22T15:01:59Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether48test # Allocating group tables: 0/8 done49second # [2026-10-22T15:01:59Z INFO virtiofsd] Waiting for vhost-user socket connection...50test # Writing inode tables: 0/8 done51second # [2026-10-22T15:01:59Z INFO virtiofsd] Waiting for vhost-user socket connection...52test # Creating journal (8192 blocks): done53second # [2026-10-22T15:01:59Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)54test # Writing superblocks and filesystem accounting information: 0/8 done55second # [2026-10-22T15:01:59Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether56test # 57second # [2026-10-22T15:01:59Z INFO virtiofsd] Waiting for vhost-user socket connection...58test # Virtualisation disk image created.59second # [2026-10-22T15:01:59Z INFO virtiofsd] Client connected, servicing requests60test # Starting virtiofs daemons...61second # [2026-10-22T15:01:59Z INFO virtiofsd] Client connected, servicing requests62test # [2026-10-22T15:01:59Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)63second # [2026-10-22T15:01:59Z INFO virtiofsd] Client connected, servicing requests64test # [2026-10-22T15:01:59Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether65test # [2026-10-22T15:01:59Z INFO virtiofsd] Waiting for vhost-user socket connection...66test # [2026-10-22T15:01:59Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)67test # [2026-10-22T15:01:59Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68test # [2026-10-22T15:01:59Z INFO virtiofsd] Waiting for vhost-user socket connection...69test # [2026-10-22T15:01:59Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)70test # [2026-10-22T15:01:59Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether71test # [2026-10-22T15:01:59Z INFO virtiofsd] Waiting for vhost-user socket connection...72test # [2026-10-22T15:01:59Z INFO virtiofsd] Client connected, servicing requests73test # [2026-10-22T15:01:59Z INFO virtiofsd] Client connected, servicing requests74test # [2026-10-22T15:01:59Z INFO virtiofsd] Client connected, servicing requests75second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]76second # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @179205830077second # [ 0.000000] KASLR enabled78second # [ 0.000000] random: crng init done79second # [ 0.000000] Machine model: linux,dummy-virt80second # [ 0.000000] efi: UEFI not found.81second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT82second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]83second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]84second # [ 0.000000] Zone ranges:85second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]86second # [ 0.000000] DMA32 empty87second # [ 0.000000] Normal empty88second # [ 0.000000] Device empty89second # [ 0.000000] Movable zone start for each node90second # [ 0.000000] Early memory node ranges91second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]92second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]93second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000094second # [ 0.000000] psci: probing for conduit method from DT.95second # [ 0.000000] psci: PSCIv1.3 detected in firmware.96second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs97second # [ 0.000000] psci: Trusted OS migration not required98second # [ 0.000000] psci: SMC Calling Convention v1.199second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)100second # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u311296101second # [ 0.000000] Detected PIPT I-cache on CPU0102second # [ 0.000000] CPU features: detected: GICv3 CPU interface103second # [ 0.000000] CPU features: detected: Spectre-v4104second # [ 0.000000] CPU features: detected: Spectre-BHB105second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR106second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)107second # [ 0.000000] CPU features: detected: ARM erratum 1418040108second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion109second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing110second # [ 0.000000] alternatives: applying boot alternatives111test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]112second # [ 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/y5jasjl3fkyjjl1kjdgsj4s9354nqi6w-nixos-system-second-test/init regInfo=/nix/.ro-store/4nydfb7mxxbl8bzi13klhyq74bgs1cjs-closure-info/registration console=ttyAMA0,115200n8 console=tty0113test # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1792058300114test # [ 0.000000] KASLR enabled115test # [ 0.000000] random: crng init done116test # [ 0.000000] Machine model: linux,dummy-virt117test # [ 0.000000] efi: UEFI not found.118second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/4nydfb7mxxbl8bzi13klhyq74bgs1cjs-closure-info/registration", will be passed to user space.119test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT120second # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes121test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]122second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)123test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]124second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)125test # [ 0.000000] Zone ranges:126second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB127test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]128test # [ 0.000000] DMA32 empty129second # [ 0.000000] software IO TLB: area num 1.130test # [ 0.000000] Normal empty131test # [ 0.000000] Device empty132second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)133test # [ 0.000000] Movable zone start for each node134second # [ 0.000000] Fallback order for Node 0: 0135test # [ 0.000000] Early memory node ranges136second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144137test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]138second # [ 0.000000] Policy zone: DMA139test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]140second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off141test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000142second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1143test # [ 0.000000] psci: probing for conduit method from DT.144second # [ 0.000000] allocated 2097152 bytes of page_ext145test # [ 0.000000] psci: PSCIv1.3 detected in firmware.146second # [ 0.000000] ftrace: allocating 75301 entries in 296 pages147test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs148second # [ 0.000000] ftrace: allocated 296 pages with 3 groups149test # [ 0.000000] psci: Trusted OS migration not required150second # [ 0.000000] rcu: Hierarchical RCU implementation.151test # [ 0.000000] psci: SMC Calling Convention v1.1152second # [ 0.000000] rcu: RCU event tracing is enabled.153test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)154second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.155second # [ 0.000000] Trampoline variant of Tasks RCU enabled.156test # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u311296157second # [ 0.000000] Rude variant of Tasks RCU enabled.158test # [ 0.000000] Detected PIPT I-cache on CPU0159second # [ 0.000000] Tracing variant of Tasks RCU enabled.160test # [ 0.000000] CPU features: detected: GICv3 CPU interface161test # [ 0.000000] CPU features: detected: Spectre-v4162second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.163test # [ 0.000000] CPU features: detected: Spectre-BHB164second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1165test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR166second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.167test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)168test # [ 0.000000] CPU features: detected: ARM erratum 1418040169second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.170test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion171second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.172test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing173test # [ 0.000000] alternatives: applying boot alternatives174second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0175second # [ 0.000000] GICv3: 256 SPIs implemented176second # [ 0.000000] GICv3: 0 Extended SPIs implemented177second # [ 0.000000] Root IRQ handler: gic_handle_irq178second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI179second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0180second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000181test # [ 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/pkmrsr198f79v866bcannckgyyj06krl-nixos-system-test-test/init regInfo=/nix/.ro-store/2dg5cmz00r2dx01w8ryp0m7lhcabzf5w-closure-info/registration console=ttyAMA0,115200n8 console=tty0182second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]183second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450f0000 (indirect, esz 8, psz 64K, shr 1)184test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/2dg5cmz00r2dx01w8ryp0m7lhcabzf5w-closure-info/registration", will be passed to user space.185second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1)186test # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes187second # [ 0.000000] GICv3: using LPI property table @0x0000000045110000188test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)189second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000190test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)191second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.192test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB193test # [ 0.000000] software IO TLB: area num 1.194second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns195test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)196second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040197test # [ 0.000000] Fallback order for Node 0: 0198second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).199test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144200test # [ 0.000000] Policy zone: DMA201second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns202test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off203test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1204second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns205test # [ 0.000000] allocated 2097152 bytes of page_ext206second # [ 0.000059] arm-pv: using stolen time PV207test # [ 0.000000] ftrace: allocating 75301 entries in 296 pages208test # [ 0.000000] ftrace: allocated 296 pages with 3 groups209second # [ 0.000309] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)210second # [ 0.000581] Console: colour dummy device 80x25211test # [ 0.000000] rcu: Hierarchical RCU implementation.212second # [ 0.000592] printk: legacy console [tty0] enabled213test # [ 0.000000] rcu: RCU event tracing is enabled.214test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.215second # [ 0.000805] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)216test # [ 0.000000] Trampoline variant of Tasks RCU enabled.217second # [ 0.000810] pid_max: default: 32768 minimum: 301218test # [ 0.000000] Rude variant of Tasks RCU enabled.219second # [ 0.000919] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima220test # [ 0.000000] Tracing variant of Tasks RCU enabled.221second # [ 0.001021] landlock: Up and running.222second # [ 0.001023] Yama: becoming mindful.223test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.224second # [ 0.001342] LSM support for eBPF active225test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1226second # [ 0.001510] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)227test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.228second # [ 0.001529] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)229second # [ 0.004095] rcu: Hierarchical SRCU implementation.230test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.231second # [ 0.004101] rcu: Max phase no-delay instances is 1000.232second # [ 0.005016] fsl-mc MSI: its@8080000 domain created233test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.234second # [ 0.005210] EFI services will not be available.235test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0236second # [ 0.005298] smp: Bringing up secondary CPUs ...237test # [ 0.000000] GICv3: 256 SPIs implemented238second # [ 0.005329] smp: Brought up 1 node, 1 CPU239test # [ 0.000000] GICv3: 0 Extended SPIs implemented240second # [ 0.005331] SMP: Total of 1 processors activated.241test # [ 0.000000] Root IRQ handler: gic_handle_irq242second # [ 0.005333] CPU: All CPU(s) started at EL1243test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI244second # [ 0.005345] CPU features: detected: 32-bit EL0 Support245test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0246second # [ 0.005349] CPU features: detected: Data cache clean to the PoU not required for I/D coherence247test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000248test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]249second # [ 0.005352] CPU features: detected: Common not Private translations250second # [ 0.005355] CPU features: detected: CRC32 instructions251test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450f0000 (indirect, esz 8, psz 64K, shr 1)252second # [ 0.005367] CPU features: detected: Data cache clean to Point of Persistence253second # [ 0.005371] CPU features: detected: Enhanced Virtualization Traps254test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1)255second # [ 0.005381] CPU features: detected: RCpc load-acquire (LDAPR)256test # [ 0.000000] GICv3: using LPI property table @0x0000000045110000257second # [ 0.005384] CPU features: detected: LSE atomic instructions258test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000259second # [ 0.005386] CPU features: detected: Privileged Access Never260second # [ 0.005388] CPU features: detected: PMUv3261test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.262second # [ 0.005389] CPU features: detected: RAS Extension Support263test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns264test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040265second # [ 0.005393] CPU features: detected: Speculative Store Bypassing Safe (SSBS)266test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).267second # [ 0.005414] alternatives: applying system-wide alternatives268second # [ 0.006810] CPU features: detected: Hardware dirty bit management on CPU0269test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns270test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns271second # [ 0.007011] Memory: 885608K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 121656K reserved, 32768K cma-reserved)272test # [ 0.000055] arm-pv: using stolen time PV273second # [ 0.007462] devtmpfs: initialized274second # [ 0.009006] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)275test # [ 0.000335] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)276test # [ 0.000595] Console: colour dummy device 80x25277second # [ 0.009029] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).278test # [ 0.000606] printk: legacy console [tty0] enabled279second # [ 0.009153] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL280test # [ 0.000829] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)281second # [ 0.009157] 0 pages in range for non-PLT usage282test # [ 0.000834] pid_max: default: 32768 minimum: 301283second # [ 0.009159] 506368 pages in range for PLT usage284second # [ 0.009272] pinctrl core: initialized pinctrl subsystem285test # [ 0.000905] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima286second # [ 0.010001] DMI not present or invalid.287test # [ 0.001014] landlock: Up and running.288test # [ 0.001017] Yama: becoming mindful.289second # [ 0.013135] NET: Registered PF_NETLINK/PF_ROUTE protocol family290test # [ 0.001347] LSM support for eBPF active291second # [ 0.015478] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations292second # [ 0.015605] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations293test # [ 0.001485] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)294second # [ 0.015750] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations295test # [ 0.001505] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)296second # [ 0.015765] audit: initializing netlink subsys (disabled)297test # [ 0.003922] rcu: Hierarchical SRCU implementation.298test # [ 0.003927] rcu: Max phase no-delay instances is 1000.299second # [ 0.016142] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1300test # [ 0.004918] fsl-mc MSI: its@8080000 domain created301second # [ 0.016392] thermal_sys: Registered thermal governor 'fair_share'302test # [ 0.005098] EFI services will not be available.303second # [ 0.016394] thermal_sys: Registered thermal governor 'bang_bang'304test # [ 0.005178] smp: Bringing up secondary CPUs ...305test # [ 0.005209] smp: Brought up 1 node, 1 CPU306second # [ 0.016396] thermal_sys: Registered thermal governor 'step_wise'307test # [ 0.005211] SMP: Total of 1 processors activated.308second # [ 0.016398] thermal_sys: Registered thermal governor 'user_space'309test # [ 0.005213] CPU: All CPU(s) started at EL1310second # [ 0.016400] thermal_sys: Registered thermal governor 'power_allocator'311test # [ 0.005226] CPU features: detected: 32-bit EL0 Support312second # [ 0.016434] cpuidle: using governor ladder313second # [ 0.016440] cpuidle: using governor menu314test # [ 0.005230] CPU features: detected: Data cache clean to the PoU not required for I/D coherence315second # [ 0.016555] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.316test # [ 0.005233] CPU features: detected: Common not Private translations317second # [ 0.016575] ASID allocator initialised with 32768 entries318test # [ 0.005235] CPU features: detected: CRC32 instructions319second # [ 0.017231] Serial: AMBA PL011 UART driver320test # [ 0.005237] CPU features: detected: Data cache clean to Point of Persistence321second # [ 0.020684] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1322test # [ 0.005240] CPU features: detected: Enhanced Virtualization Traps323second # [ 0.020848] printk: console [ttyAMA0] enabled324test # [ 0.005249] CPU features: detected: RCpc load-acquire (LDAPR)325test # [ 0.005251] CPU features: detected: LSE atomic instructions326test # [ 0.005253] CPU features: detected: Privileged Access Never327test # [ 0.005256] CPU features: detected: PMUv3328test # [ 0.005257] CPU features: detected: RAS Extension Support329test # [ 0.005261] CPU features: detected: Speculative Store Bypassing Safe (SSBS)330test # [ 0.005280] alternatives: applying system-wide alternatives331test # [ 0.006750] CPU features: detected: Hardware dirty bit management on CPU0332test # [ 0.006934] Memory: 885612K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 121652K reserved, 32768K cma-reserved)333test # [ 0.007442] devtmpfs: initialized334test # [ 0.008862] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)335test # [ 0.008884] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).336test # [ 0.009006] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL337test # [ 0.009011] 0 pages in range for non-PLT usage338test # [ 0.009012] 506368 pages in range for PLT usage339test # [ 0.009120] pinctrl core: initialized pinctrl subsystem340test # [ 0.009847] DMI not present or invalid.341test # [ 0.013081] NET: Registered PF_NETLINK/PF_ROUTE protocol family342test # [ 0.015419] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations343test # [ 0.015549] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations344test # [ 0.015695] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations345test # [ 0.015710] audit: initializing netlink subsys (disabled)346test # [ 0.016110] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1347test # [ 0.016296] thermal_sys: Registered thermal governor 'fair_share'348second # [ 0.093409] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages349test # [ 0.016298] thermal_sys: Registered thermal governor 'bang_bang'350second # [ 0.093416] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page351test # [ 0.016300] thermal_sys: Registered thermal governor 'step_wise'352second # [ 0.093418] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages353test # [ 0.016302] thermal_sys: Registered thermal governor 'user_space'354second # [ 0.093420] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page355test # [ 0.016304] thermal_sys: Registered thermal governor 'power_allocator'356test # [ 0.016330] cpuidle: using governor ladder357second # [ 0.093422] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages358test # [ 0.016336] cpuidle: using governor menu359second # [ 0.093424] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page360test # [ 0.016443] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.361second # [ 0.093426] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages362test # [ 0.016463] ASID allocator initialised with 32768 entries363second # [ 0.093428] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page364test # [ 0.017099] Serial: AMBA PL011 UART driver365second # [ 0.094346] fbcon: Taking over console366second # [ 0.094355] ACPI: Interpreter disabled.367test # [ 0.020428] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1368test # [ 0.020594] printk: console [ttyAMA0] enabled369second # [ 0.095012] iommu: Default domain type: Translated370second # [ 0.095016] iommu: DMA domain TLB invalidation policy: strict mode371second # [ 0.095284] SCSI subsystem initialized372second # [ 0.095502] usbcore: registered new interface driver usbfs373second # [ 0.095536] usbcore: registered new interface driver hub374second # [ 0.095543] usbcore: registered new device driver usb375second # [ 0.095755] pps_core: LinuxPPS API ver. 1 registered376second # [ 0.095758] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>377second # [ 0.095762] PTP clock support registered378second # [ 0.095789] EDAC MC: Ver: 3.0.0379second # [ 0.105404] scmi_core: SCMI protocol bus registered380second # [ 0.106100] FPGA manager framework381second # [ 0.109324] vgaarb: loaded382test # [ 0.093317] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages383test # [ 0.093324] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page384second # [ 0.110010] clocksource: Switched to clocksource arch_sys_counter385test # [ 0.093327] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages386test # [ 0.093328] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page387test # [ 0.093330] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages388test # [ 0.093332] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page389test # [ 0.093334] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages390test # [ 0.093336] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page391test # [ 0.094308] fbcon: Taking over console392test # [ 0.094317] ACPI: Interpreter disabled.393test # [ 0.094944] iommu: Default domain type: Translated394test # [ 0.094948] iommu: DMA domain TLB invalidation policy: strict mode395test # [ 0.095216] SCSI subsystem initialized396test # [ 0.095424] usbcore: registered new interface driver usbfs397test # [ 0.095461] usbcore: registered new interface driver hub398test # [ 0.095468] usbcore: registered new device driver usb399test # [ 0.095676] pps_core: LinuxPPS API ver. 1 registered400test # [ 0.095679] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>401test # [ 0.095683] PTP clock support registered402test # [ 0.095708] EDAC MC: Ver: 3.0.0403test # [ 0.105359] scmi_core: SCMI protocol bus registered404second # [ 0.119766] VFS: Disk quotas dquot_6.6.0405second # [ 0.119794] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)406second # [ 0.121298] netfs: FS-Cache loaded407second # [ 0.121417] pnp: PnP ACPI: disabled408test # [ 0.106072] FPGA manager framework409test # [ 0.109294] vgaarb: loaded410second # [ 0.124535] NET: Registered PF_INET protocol family411second # [ 0.124717] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)412test # [ 0.109984] clocksource: Switched to clocksource arch_sys_counter413test # [ 0.119329] VFS: Disk quotas dquot_6.6.0414test # [ 0.119353] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)415test # [ 0.120816] netfs: FS-Cache loaded416test # [ 0.120914] pnp: PnP ACPI: disabled417test # [ 0.124042] NET: Registered PF_INET protocol family418test # [ 0.124217] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)419second # [ 0.149401] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)420second # [ 0.149419] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)421second # [ 0.149430] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)422second # [ 0.149464] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)423second # [ 0.149531] TCP: Hash tables configured (established 8192 bind 8192)424second # [ 0.149620] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)425second # [ 0.149675] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)426second # [ 0.149729] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)427second # [ 0.149841] NET: Registered PF_UNIX/PF_LOCAL protocol family428second # [ 0.149884] NET: Registered PF_XDP protocol family429second # [ 0.149902] PCI: CLS 0 bytes, default 64430second # [ 0.150105] Trying to unpack rootfs image as initramfs...431test # [ 0.148388] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)432test # [ 0.148407] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)433test # [ 0.148417] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)434test # [ 0.148453] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)435test # [ 0.148519] TCP: Hash tables configured (established 8192 bind 8192)436test # [ 0.148645] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)437test # [ 0.148669] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)438test # [ 0.148690] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)439test # [ 0.148793] NET: Registered PF_UNIX/PF_LOCAL protocol family440test # [ 0.148830] NET: Registered PF_XDP protocol family441test # [ 0.148849] PCI: CLS 0 bytes, default 64442test # [ 0.149042] Trying to unpack rootfs image as initramfs...443second # [ 0.163875] kvm [1]: HYP mode not available444test # [ 0.162007] kvm [1]: HYP mode not available445second # [ 0.226365] Initialise system trusted keyrings446second # [ 0.226422] Key type blacklist registered447second # [ 0.226510] workingset: timestamp_bits=42 max_order=18 bucket_order=0448second # [ 0.226854] squashfs: version 4.0 (2009/01/31) Phillip Lougher449second # [ 0.226898] 9p: Installing v9fs 9p2000 file system support450second # [ 0.226989] integrity: Platform Keyring initialized451second # [ 0.227002] integrity: Machine keyring initialized452second # [ 0.245743] Key type asymmetric registered453second # [ 0.245757] Asymmetric key parser 'x509' registered454second # [ 0.245810] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)455test # [ 0.222347] Initialise system trusted keyrings456test # [ 0.222398] Key type blacklist registered457test # [ 0.222479] workingset: timestamp_bits=42 max_order=18 bucket_order=0458test # [ 0.222812] squashfs: version 4.0 (2009/01/31) Phillip Lougher459test # [ 0.222845] 9p: Installing v9fs 9p2000 file system support460test # [ 0.222933] integrity: Platform Keyring initialized461test # [ 0.222939] integrity: Machine keyring initialized462second # [ 0.254050] io scheduler mq-deadline registered463second # [ 0.254056] io scheduler kyber registered464test # [ 0.244420] Key type asymmetric registered465test # [ 0.244432] Asymmetric key parser 'x509' registered466second # [ 0.258767] pl061_gpio 9030000.pl061: PL061 GPIO chip registered467test # [ 0.244486] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)468second # [ 0.259098] ledtrig-cpu: registered to indicate activity on CPUs469second # [ 0.259408] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:470second # [ 0.259425] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000471second # [ 0.259437] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000472second # [ 0.259442] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000473second # [ 0.259466] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits474second # [ 0.259486] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]475second # [ 0.259550] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00476second # [ 0.259566] pci_bus 0000:00: root bus resource [bus 00-ff]477second # [ 0.259569] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]478second # [ 0.259572] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]479second # [ 0.259574] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]480second # [ 0.259659] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint481second # [ 0.260081] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint482second # [ 0.260202] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]483second # [ 0.260211] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]484second # [ 0.260228] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]485second # [ 0.260237] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]486second # [ 0.260522] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint487second # [ 0.260638] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]488second # [ 0.260647] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]489test # [ 0.254034] io scheduler mq-deadline registered490test # [ 0.254041] io scheduler kyber registered491second # [ 0.260664] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]492test # [ 0.256308] pl061_gpio 9030000.pl061: PL061 GPIO chip registered493second # [ 0.260945] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint494test # [ 0.256623] ledtrig-cpu: registered to indicate activity on CPUs495second # [ 0.261059] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]496test # [ 0.256932] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:497second # [ 0.261067] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]498second # [ 0.261085] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]499test # [ 0.256948] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000500second # [ 0.261382] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint501test # [ 0.256961] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000502second # [ 0.261497] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]503second # [ 0.261505] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]504test # [ 0.256966] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000505second # [ 0.261523] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]506test # [ 0.256992] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits507second # [ 0.261531] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]508test # [ 0.257011] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]509second # [ 0.261821] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint510test # [ 0.257075] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00511second # [ 0.261940] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]512test # [ 0.257091] pci_bus 0000:00: root bus resource [bus 00-ff]513second # [ 0.261958] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]514test # [ 0.257094] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]515test # [ 0.257097] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]516test # [ 0.257099] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]517test # [ 0.257184] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint518test # [ 0.257606] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint519test # [ 0.257742] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]520test # [ 0.257752] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]521test # [ 0.257770] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]522test # [ 0.257778] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]523test # [ 0.269025] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint524test # [ 0.269142] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]525test # [ 0.269151] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]526test # [ 0.269168] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]527test # [ 0.269452] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint528test # [ 0.269567] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]529test # [ 0.269575] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]530test # [ 0.269593] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]531test # [ 0.269895] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint532second # [ 0.286313] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint533second # [ 0.286437] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]534second # [ 0.286454] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]535second # [ 0.286744] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint536second # [ 0.286859] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]537second # [ 0.287037] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint538second # [ 0.287156] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]539second # [ 0.287172] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]540second # [ 0.287456] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint541second # [ 0.287574] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]542second # [ 0.287591] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]543second # [ 0.287876] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint544second # [ 0.287995] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]545second # [ 0.288012] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]546second # [ 0.288301] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint547second # [ 0.288510] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]548second # [ 0.288520] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]549second # [ 0.288537] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]550second # [ 0.288832] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint551second # [ 0.288949] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]552second # [ 0.288958] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]553second # [ 0.288975] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]554second # [ 0.289391] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned555test # [ 0.282063] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]556second # [ 0.289398] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned557test # [ 0.282074] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]558second # [ 0.289401] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned559test # [ 0.282092] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]560test # [ 0.282101] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]561second # [ 0.289435] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned562test # [ 0.282412] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint563second # [ 0.289464] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned564test # [ 0.282534] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]565second # [ 0.289495] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned566test # [ 0.282551] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]567second # [ 0.289524] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned568test # [ 0.282856] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint569second # [ 0.289552] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned570test # [ 0.282976] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]571second # [ 0.289580] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned572test # [ 0.282993] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]573second # [ 0.289608] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned574test # [ 0.283248] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint575test # [ 0.283362] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]576second # [ 0.289636] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned577test # [ 0.283532] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint578second # [ 0.289664] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned579test # [ 0.283653] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]580second # [ 0.289718] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned581test # [ 0.283670] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]582second # [ 0.289772] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned583test # [ 0.283960] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint584second # [ 0.289784] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned585test # [ 0.284080] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]586second # [ 0.289796] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned587test # [ 0.284097] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]588second # [ 0.289808] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned589test # [ 0.284389] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint590second # [ 0.289820] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned591test # [ 0.284509] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]592second # [ 0.289836] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned593test # [ 0.284526] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]594second # [ 0.289848] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned595second # [ 0.289860] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned596test # [ 0.284821] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint597test # [ 0.285023] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]598second # [ 0.289871] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned599test # [ 0.285032] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]600second # [ 0.289883] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned601test # [ 0.285050] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]602second # [ 0.289895] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned603test # [ 0.285350] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint604second # [ 0.289907] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned605test # [ 0.285469] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]606second # [ 0.289918] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned607test # [ 0.285478] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]608second # [ 0.289930] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned609test # [ 0.285495] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]610second # [ 0.289941] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned611second # [ 0.289953] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned612test # [ 0.285925] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned613second # [ 0.289965] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned614test # [ 0.285932] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned615second # [ 0.289976] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned616test # [ 0.285935] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned617second # [ 0.289992] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]618second # [ 0.289996] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]619test # [ 0.285968] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned620second # [ 0.289998] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]621test # [ 0.303106] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned622second # [ 0.319694] pci 0000:00:07.0: enabling device (0000 -> 0002)623test # [ 0.303146] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned624test # [ 0.303176] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned625test # [ 0.303204] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned626test # [ 0.303234] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned627test # [ 0.303263] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned628test # [ 0.303291] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned629test # [ 0.303319] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned630test # [ 0.303375] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned631test # [ 0.303404] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned632test # [ 0.303416] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned633test # [ 0.303454] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned634test # [ 0.303466] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned635test # [ 0.303478] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned636test # [ 0.303494] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned637test # [ 0.303506] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned638test # [ 0.303518] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned639test # [ 0.303530] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned640test # [ 0.303542] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned641test # [ 0.303554] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned642test # [ 0.303566] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned643test # [ 0.303578] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned644test # [ 0.303589] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned645test # [ 0.303601] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned646test # [ 0.303612] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned647test # [ 0.303624] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned648test # [ 0.303636] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned649test # [ 0.303654] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]650test # [ 0.303658] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]651test # [ 0.303661] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]652test # [ 0.304165] pci 0000:00:07.0: enabling device (0000 -> 0002)653second # [ 0.335239] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)654second # [ 0.336751] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)655second # [ 0.346152] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)656second # [ 0.348452] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)657test # [ 0.334458] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)658second # [ 0.351082] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)659test # [ 0.335939] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)660second # [ 0.352759] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)661test # [ 0.337298] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)662second # [ 0.362097] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)663test # [ 0.346893] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)664second # [ 0.363670] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)665test # [ 0.348737] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)666second # [ 0.365224] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)667test # [ 0.351506] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)668second # [ 0.368272] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)669test # [ 0.352866] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)670second # [ 0.378462] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)671test # [ 0.362097] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)672second # [ 0.381880] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled673test # [ 0.363704] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)674test # [ 0.365277] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)675test # [ 0.368877] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)676second # [ 0.383804] msm_serial: driver initialized677second # [ 0.383896] SuperH (H)SCI(F) driver initialized678second # [ 0.383936] STM32 USART driver initialized679test # [ 0.380600] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled680test # [ 0.381531] msm_serial: driver initialized681test # [ 0.381619] SuperH (H)SCI(F) driver initialized682test # [ 0.381658] STM32 USART driver initialized683second # [ 0.408250] loop: module loaded684second # [ 0.408375] virtio_blk virtio2: 1/0/0 default/read/poll queues685second # [ 0.408958] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)686second # [ 0.411836] megasas: 07.734.00.00-rc1687second # [ 0.412271] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]688test # [ 0.401337] loop: module loaded689test # [ 0.401459] virtio_blk virtio2: 1/0/0 default/read/poll queues690second # [ 0.422563] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000691second # [ 0.422584] Intel/Sharp Extended Query Table at 0x0031692second # [ 0.424190] Using buffer write method693second # [ 0.424219] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]694second # [ 0.429057] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000695second # [ 0.429069] Intel/Sharp Extended Query Table at 0x0031696second # [ 0.431573] Using buffer write method697second # [ 0.431583] Concatenating MTD devices:698second # [ 0.431585] (0): "0.flash"699second # [ 0.431587] (1): "0.flash"700second # [ 0.431588] into device "0.flash"701test # [ 0.410121] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)702test # [ 0.414343] megasas: 07.734.00.00-rc1703test # [ 0.414793] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]704test # [ 0.419433] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000705test # [ 0.419454] Intel/Sharp Extended Query Table at 0x0031706test # [ 0.421159] Using buffer write method707test # [ 0.421188] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]708test # [ 0.426584] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000709test # [ 0.426596] Intel/Sharp Extended Query Table at 0x0031710test # [ 0.428264] Using buffer write method711test # [ 0.428275] Concatenating MTD devices:712test # [ 0.428277] (0): "0.flash"713test # [ 0.428279] (1): "0.flash"714test # [ 0.428280] into device "0.flash"715second # [ 0.623094] Freeing initrd memory: 25176K716second # [ 0.629761] tun: Universal TUN/TAP device driver, 1.6717second # [ 0.632382] thunder_xcv, ver 1.0718second # [ 0.632396] thunder_bgx, ver 1.0719second # [ 0.632405] nicpf, ver 1.0720second # [ 0.632728] e1000: Intel(R) PRO/1000 Network Driver721second # [ 0.632732] e1000: Copyright (c) 1999-2006 Intel Corporation.722second # [ 0.632747] e1000e: Intel(R) PRO/1000 Network Driver723second # [ 0.632751] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.724second # [ 0.632763] igb: Intel(R) Gigabit Ethernet Network Driver725second # [ 0.632765] igb: Copyright (c) 2007-2014 Intel Corporation.726second # [ 0.632774] igbvf: Intel(R) Gigabit Virtual Function Network Driver727second # [ 0.632777] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.728second # [ 0.632842] sky2: driver version 1.30729second # [ 0.633670] usbcore: registered new interface driver usb-storage730second # [ 0.633751] usbcore: registered new interface driver usbserial_generic731second # [ 0.633757] usbserial: USB Serial support registered for generic732second # [ 0.634644] ehci-pci 0000:00:07.0: EHCI Host Controller733second # [ 0.634676] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1734test # [ 0.622655] Freeing initrd memory: 25172K735second # [ 0.634927] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000736test # [ 0.629320] tun: Universal TUN/TAP device driver, 1.6737second # [ 0.642365] hv_vmbus: registering driver hyperv_keyboard738second # [ 0.642915] rtc-pl031 9010000.pl031: registered as rtc0739second # [ 0.642939] rtc-pl031 9010000.pl031: setting system clock to 2026-10-22T15:02:00 UTC (1792681320)740second # [ 0.643095] i2c_dev: i2c /dev entries driver741second # [ 0.644658] sdhci: Secure Digital Host Controller Interface driver742second # [ 0.644663] sdhci: Copyright(c) Pierre Ossman743second # [ 0.644814] Synopsys Designware Multimedia Card Interface Driver744second # [ 0.644992] sdhci-pltfm: SDHCI platform and OF driver helper745second # [ 0.645880] hid: raw HID events driver (C) Jiri Kosina746test # [ 0.631938] thunder_xcv, ver 1.0747test # [ 0.631953] thunder_bgx, ver 1.0748test # [ 0.631962] nicpf, ver 1.0749second # [ 0.649872] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00750test # [ 0.632288] e1000: Intel(R) PRO/1000 Network Driver751test # [ 0.632292] e1000: Copyright (c) 1999-2006 Intel Corporation.752test # [ 0.632307] e1000e: Intel(R) PRO/1000 Network Driver753test # [ 0.632312] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.754test # [ 0.632324] igb: Intel(R) Gigabit Ethernet Network Driver755test # [ 0.632326] igb: Copyright (c) 2007-2014 Intel Corporation.756test # [ 0.632336] igbvf: Intel(R) Gigabit Virtual Function Network Driver757test # [ 0.632338] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.758test # [ 0.632405] sky2: driver version 1.30759test # [ 0.633210] usbcore: registered new interface driver usb-storage760test # [ 0.633317] usbcore: registered new interface driver usbserial_generic761test # [ 0.633323] usbserial: USB Serial support registered for generic762test # [ 0.633643] hv_vmbus: registering driver hyperv_keyboard763test # [ 0.640823] ehci-pci 0000:00:07.0: EHCI Host Controller764test # [ 0.640855] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1765test # [ 0.641099] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000766test # [ 0.641384] rtc-pl031 9010000.pl031: registered as rtc0767test # [ 0.641410] rtc-pl031 9010000.pl031: setting system clock to 2026-10-22T15:02:00 UTC (1792681320)768test # [ 0.641576] i2c_dev: i2c /dev entries driver769second # [ 0.650691] hub 1-0:1.0: USB hub found770test # [ 0.646114] sdhci: Secure Digital Host Controller Interface driver771second # [ 0.650709] hub 1-0:1.0: 6 ports detected772test # [ 0.646119] sdhci: Copyright(c) Pierre Ossman773second # [ 0.650990] usbcore: registered new interface driver usbhid774test # [ 0.646266] Synopsys Designware Multimedia Card Interface Driver775second # [ 0.650994] usbhid: USB HID core driver776test # [ 0.646442] sdhci-pltfm: SDHCI platform and OF driver helper777test # [ 0.647310] hid: raw HID events driver (C) Jiri Kosina778test # [ 0.647444] usbcore: registered new interface driver usbhid779test # [ 0.647449] usbhid: USB HID core driver780test # [ 0.673842] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00781test # [ 0.677474] hub 1-0:1.0: USB hub found782test # [ 0.677493] hub 1-0:1.0: 6 ports detected783second # [ 0.691064] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available784test # [ 0.677797] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available785second # [ 0.691863] drop_monitor: Initializing network drop monitor service786second # [ 0.692030] NET: Registered PF_INET6 protocol family787second # [ 0.692646] Segment Routing with IPv6788second # [ 0.692665] In-situ OAM (IOAM) with IPv6789second # [ 0.692711] NET: Registered PF_PACKET protocol family790second # [ 0.692770] 9pnet: Installing 9P2000 support791second # [ 0.692804] Key type dns_resolver registered792test # [ 0.679945] drop_monitor: Initializing network drop monitor service793test # [ 0.680058] NET: Registered PF_INET6 protocol family794test # [ 0.680677] Segment Routing with IPv6795test # [ 0.680704] In-situ OAM (IOAM) with IPv6796test # [ 0.680753] NET: Registered PF_PACKET protocol family797test # [ 0.680814] 9pnet: Installing 9P2000 support798test # [ 0.680848] Key type dns_resolver registered799second # [ 0.698753] registered taskstats version 1800second # [ 0.698926] Loading compiled-in X.509 certificates801test # [ 0.686135] registered taskstats version 1802second # [ 0.704311] Demotion targets for Node 0: null803test # [ 0.686285] Loading compiled-in X.509 certificates804second # [ 0.704383] Key type .fscrypt registered805second # [ 0.704386] Key type fscrypt-provisioning registered806second # [ 0.704472] ima: No TPM chip found, activating TPM-bypass!807second # [ 0.704486] ima: Allocated hash algorithm: sha1808second # [ 0.704525] ima: No architecture policies found809second # [ 0.704966] input: gpio-keys as /devices/platform/gpio-keys/input/input0810test # [ 0.691601] Demotion targets for Node 0: null811test # [ 0.691669] Key type .fscrypt registered812test # [ 0.691671] Key type fscrypt-provisioning registered813test # [ 0.691757] ima: No TPM chip found, activating TPM-bypass!814test # [ 0.691768] ima: Allocated hash algorithm: sha1815test # [ 0.691807] ima: No architecture policies found816test # [ 0.692257] input: gpio-keys as /devices/platform/gpio-keys/input/input0817second # [ 0.717145] clk: Disabling unused clocks818second # [ 0.717153] PM: genpd: Disabling unused power domains819test # [ 0.704559] clk: Disabling unused clocks820test # [ 0.704567] PM: genpd: Disabling unused power domains821second # [ 0.721340] Freeing unused kernel memory: 7808K822second # [ 0.721426] Run /init as init process823test # [ 0.708747] Freeing unused kernel memory: 7808K824test # [ 0.708827] Run /init as init process825second # [ 0.740388] systemd[1]: Successfully made /usr/ read-only.826test # [ 0.728367] systemd[1]: Successfully made /usr/ read-only.827second # [ 0.910098] usb 1-1: new high-speed USB device number 2 using ehci-pci828test # [ 0.926066] usb 1-1: new high-speed USB device number 2 using ehci-pci829second # [ 1.062424] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1830second # [ 1.074434] systemd[1]: systemd 262 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)831second # [ 1.074463] systemd[1]: Detected virtualization qemu.832second # [ 1.074524] systemd[1]: Detected architecture arm64.833second # [ 1.074533] systemd[1]: Running in initrd.834test # [ 1.062443] systemd[1]: systemd 262 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)835test # [ 1.062472] systemd[1]: Detected virtualization qemu.836second # [ 1.075380] systemd[1]: Initializing machine ID from random generator.837test # [ 1.062537] systemd[1]: Detected architecture arm64.838second # [ 1.075679] systemd[1]: Hostname set to <second>.839test # [ 1.062548] systemd[1]: Running in initrd.840test # [ 1.063456] systemd[1]: Initializing machine ID from random generator.841test # [ 1.063751] systemd[1]: Hostname set to <test>.842test # [ 1.083257] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1843second # [ 1.150231] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0844test # [ 1.174135] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0845second # [ 1.206123] systemd[1]: bpf-restrict-fs: LSM BPF program attached846test # [ 1.198044] systemd[1]: bpf-restrict-fs: LSM BPF program attached847second # [ 1.250324] systemd[1]: Queued start job for default target Initrd Default Target.848test # [ 1.241810] systemd[1]: Queued start job for default target Initrd Default Target.849second # [ 1.255954] systemd[1]: Created slice Slice /system/modprobe.850second # [ 1.256091] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.851second # [ 1.256112] systemd[1]: Expecting device /dev/disk/by-label/nixos...852second # [ 1.256135] systemd[1]: Reached target Path Units.853second # [ 1.256148] systemd[1]: Reached target Slice Units.854second # [ 1.256159] systemd[1]: Reached target Swaps.855second # [ 1.256171] systemd[1]: Reached target Timer Units.856second # [ 1.256303] systemd[1]: Listening on D-Bus System Message Bus Socket.857second # [ 1.256407] systemd[1]: Listening on Journal Socket (/dev/log).858second # [ 1.256486] systemd[1]: Listening on Journal Sockets.859second # [ 1.256531] systemd[1]: Listening on udev Kernel Socket.860second # [ 1.256643] systemd[1]: Listening on udev Varlink Socket.861second # [ 1.256662] systemd[1]: Reached target Socket Units.862second # [ 1.257650] systemd[1]: Starting Create List of Static Device Nodes...863second # [ 1.257710] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs864test # [ 1.251262] systemd[1]: Created slice Slice /system/modprobe.865test # [ 1.251399] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.866second # [ 1.266610] systemd[1]: Mounting Kernel Configuration File System...867test # [ 1.251419] systemd[1]: Expecting device /dev/disk/by-label/nixos...868second # [ 1.270045] usb 1-2: new high-speed USB device number 3 using ehci-pci869test # [ 1.251441] systemd[1]: Reached target Path Units.870test # [ 1.251452] systemd[1]: Reached target Slice Units.871test # [ 1.251464] systemd[1]: Reached target Swaps.872test # [ 1.251476] systemd[1]: Reached target Timer Units.873test # [ 1.251606] systemd[1]: Listening on D-Bus System Message Bus Socket.874test # [ 1.251709] systemd[1]: Listening on Journal Socket (/dev/log).875test # [ 1.251785] systemd[1]: Listening on Journal Sockets.876test # [ 1.251833] systemd[1]: Listening on udev Kernel Socket.877test # [ 1.251941] systemd[1]: Listening on udev Varlink Socket.878test # [ 1.251960] systemd[1]: Reached target Socket Units.879test # [ 1.252875] systemd[1]: Starting Create List of Static Device Nodes...880test # [ 1.252930] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs881test # [ 1.262126] systemd[1]: Mounting Kernel Configuration File System...882second # [ 1.278268] systemd[1]: Starting Journal Service...883test # [ 1.274490] systemd[1]: Starting Journal Service...884second # [ 1.294832] systemd[1]: Starting Load Kernel Modules...885second # [ 1.294906] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os886test # [ 1.294082] usb 1-2: new high-speed USB device number 3 using ehci-pci887test # [ 1.294333] systemd[1]: Starting Load Kernel Modules...888second # [ 1.310124] systemd[1]: Starting Coldplug All udev Devices...889test # [ 1.294408] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os890second # [ 1.319033] systemd[1]: Finished Create List of Static Device Nodes.891second # [ 1.319700] systemd[1]: Mounted Kernel Configuration File System.892second # [ 1.326278] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...893second # [ 1.329930] systemd-journald[71]: Collecting audit messages is disabled.894test # [ 1.318095] systemd[1]: Starting Coldplug All udev Devices...895test # [ 1.320551] systemd-journald[71]: Collecting audit messages is disabled.896test # [ 1.323501] systemd[1]: Finished Create List of Static Device Nodes.897test # [ 1.326753] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...898test # [ 1.326891] systemd[1]: Mounted Kernel Configuration File System.899test # [ 1.332090] dm_mod: module verification failed: signature and/or required key missing - tainting kernel900second # [ 1.362777] dm_mod: module verification failed: signature and/or required key missing - tainting kernel901test # [ 1.342202] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.902test # [ 1.342355] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev903second # [ 1.364725] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.904second # [ 1.368032] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.905second # [ 1.368995] systemd[1]: Starting Create Static Device Nodes in /dev...906test # [ 1.358604] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0907test # [ 1.358808] [drm] features: -virgl +edid -resource_blob -host_visible908test # [ 1.358810] [drm] features: -context_init909test # [ 1.359235] [drm] number of scanouts: 1910test # [ 1.359244] [drm] number of cap sets: 0911test # [ 1.370376] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.912second # [ 1.382079] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev913test # [ 1.374776] systemd[1]: Starting Create Static Device Nodes in /dev...914second # [ 1.395895] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0915test # [ 1.377712] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic916second # [ 1.396098] [drm] features: -virgl +edid -resource_blob -host_visible917test # [ 1.377720] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0918second # [ 1.396100] [drm] features: -context_init919second # [ 1.396620] [drm] number of scanouts: 1920second # [ 1.396629] [drm] number of cap sets: 0921second # [ 1.402850] systemd-modules-load[73]: Inserted module 'dm_mod'922second # [ 1.403569] systemd-modules-load[73]: Module 'virtio_balloon' is built in[ 1.407251] systemd[1]: Started Journal Service.923second # [ 1.411699] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic924second # [ 1.411707] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0925second # 926second # [ 1.409788] systemd-modules-load[73]: Module 'virtio_console' is built in927test # [ 1.398331] Console: switching to colour frame buffer device 160x50928second # [ 1.413300] systemd[1]: Finished Create Static Device Nodes in /dev.929second # [ 1.420448] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2930second # [ 1.420541] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0931test # [ 1.408460] systemd[1]: Started Journal Service.932second # [ 1.420277] systemd[1]: Reached target Preparation for Local File Systems.933second # [ 1.420974] systemd[1]: Reached target Local File Systems.934test # [ 1.405898] systemd-modules-load[73]: Inserted module 'dm_mod'935second # [ 1.421795] systemd[1]: Starting Create System Files and Directories...936test # [ 1.406783] systemd-modules-load[73]: Module 'virtio_balloon' is built in937test # [ 1.421241] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device938test # [ 1.417395] systemd-modules-load[73]: Module 'virtio_console' is built in939test # [ 1.418229] systemd[1]: Finished Create Static Device Nodes in /dev.940test # [ 1.418895] systemd[1]: Reached target Preparation for Local File Systems.941test # [ 1.419584] systemd[1]: Reached target Local File Systems.942test # [ 1.421708] systemd[1]: Starting Create System Files and Directories...943second # [ 1.438223] Console: switching to colour frame buffer device 160x50944second # [ 1.437947] systemd[1]: Starting Rule-based Manager for Device Events and Files...945test # [ 1.428143] systemd-modules-load[73]: Inserted module 'virtio_gpu'946test # [ 1.428728] systemd-modules-load[73]: Module 'virtio_rng' is built in947test # [ 1.432204] systemd[1]: Starting Rule-based Manager for Device Events and Files...948test # [ 1.433055] systemd[1]: Finished Load Kernel Modules.949second # [ 1.456660] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device950test # [ 1.449127] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2951test # [ 1.449289] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0952second # [ 1.464498] systemd-modules-load[73]: Inserted module 'virtio_gpu'953second # [ 1.465131] systemd-modules-load[73]: Module 'virtio_rng' is built in954second # [ 1.467207] systemd[1]: Finished Load Kernel Modules.955test # [ 1.454745] systemd[1]: Starting Apply Kernel Variables...956second # [ 1.476534] systemd[1]: Starting Apply Kernel Variables...957second # [ 1.480081] systemd[1]: Finished Create System Files and Directories.958test # [ 1.471843] systemd[1]: Finished Create System Files and Directories.959test # [ 1.472972] systemd[1]: Finished Apply Kernel Variables.960second # [ 1.500705] systemd[1]: Finished Apply Kernel Variables.961second # [ 1.502132] systemd-udevd[80]: Using default interface naming scheme 'v261'.962test # [ 1.503166] systemd-udevd[80]: Using default interface naming scheme 'v261'.963second # [ 1.524333] systemd[1]: Started Rule-based Manager for Device Events and Files.964test # [ 1.524318] systemd[1]: Started Rule-based Manager for Device Events and Files.965test # [ 1.572071] systemd[1]: Starting Virtual Console Setup...966second # [ 1.588079] systemd[1]: Starting Virtual Console Setup...967second # [ 1.620367] systemd-vconsole-setup[102]: Configuration of first virtual console was skipped, ignoring remaining ones.968second # [ 1.622592] systemd[1]: Finished Virtual Console Setup.969test # [ 1.617401] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.970test # [ 1.618442] systemd[1]: Finished Virtual Console Setup.971test # [ 1.819955] systemd[1]: Finished Coldplug All udev Devices.972test # [ 1.820561] systemd[1]: Reached target System Initialization.973test # [ 1.821093] systemd[1]: Reached target Basic System.974second # [ 1.836652] systemd[1]: Finished Coldplug All udev Devices.975second # [ 1.837318] systemd[1]: Reached target System Initialization.976second # [ 1.837841] systemd[1]: Reached target Basic System.977second # [ 1.922325] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.978test # [ 1.919755] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.979test # [ 1.927274] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.980second # [ 1.944481] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.981second # [ 1.994196] systemd[1]: Found device /dev/disk/by-label/nixos.982second # [ 1.997063] systemd[1]: Reached target Initrd Root Device.983second # [ 1.999994] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...984test # [ 1.991465] systemd[1]: Found device /dev/disk/by-label/nixos.985test # [ 1.994488] systemd[1]: Reached target Initrd Root Device.986test # [ 1.996878] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...987second # [ 2.031473] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks988test # [ 2.031241] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks989second # [ 2.063121] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.990test # [ 2.047988] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.991second # [ 2.313483] systemd[1]: Mounting /sysroot...992test # [ 2.314879] systemd[1]: Mounting /sysroot...993second # [ 2.342981] systemd[1]: Mounted /sysroot.994second # [ 2.346126] EXT4-fs (vda): mounted filesystem 8401d8ce-4aae-4d29-817d-75787602346e r/w with ordered data mode. Quota mode: none.995second # [ 2.345838] systemd[1]: Reached target Initrd Root File System.996second # [ 2.349983] systemd[1]: Mounting /sysroot/nix/.ro-store...997test # [ 2.345973] EXT4-fs (vda): mounted filesystem 981b3276-3780-48c0-b37a-0b53d8420448 r/w with ordered data mode. Quota mode: none.998test # [ 2.345071] systemd[1]: Mounted /sysroot.999test # [ 2.346492] systemd[1]: Reached target Initrd Root File System.1000second # [ 2.362811] systemd[1]: Mounting /sysroot/nix/.rw-store...1001second # [ 2.366487] systemd[1]: Mounting /sysroot/run...1002test # [ 2.350859] systemd[1]: Mounting /sysroot/nix/.ro-store...1003test # [ 2.362322] systemd[1]: Mounting /sysroot/nix/.rw-store...1004test # [ 2.366801] systemd[1]: Mounting /sysroot/run...1005second # [ 2.388876] systemd[1]: Mounting /sysroot/tmp/shared...1006test # [ 2.378174] systemd[1]: Mounting /sysroot/tmp/shared...1007second # [ 2.398710] systemd[1]: Mounting /sysroot/tmp/xchg...1008second # [ 2.406003] systemd[1]: Starting Mountpoints Configured in the Real Root...1009test # [ 2.395109] systemd[1]: Mounting /sysroot/tmp/xchg...1010test # [ 2.406638] systemd[1]: Starting Mountpoints Configured in the Real Root...1011second # [ 2.426817] systemd[1]: Mounted /sysroot/nix/.rw-store.1012second # [ 2.443823] fuse: init (API version 7.45)1013second # [ 2.453839] virtiofs virtio6: discovered new tag: nix-store1014test # [ 2.434072] systemd[1]: Mounted /sysroot/nix/.rw-store.1015test # [ 2.435614] systemd[1]: Mounted /sysroot/run.1016second # [ 2.458423] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1017second # [ 2.458869] systemd[1]: Starting rw-sysroot-nix-store.service...1018second # [ 2.461035] systemd[1]: Mounted /sysroot/run.1019test # [ 2.450199] fuse: init (API version 7.45)1020second # [ 2.475499] virtiofs virtio7: discovered new tag: shared1021second # [ 2.475960] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1022test # [ 2.456994] systemd-sysroot-fstab-check[123]: /sysroot should be mounted in the initrd, will request daemon-reload.1023second # [ 2.483268] virtiofs virtio8: discovered new tag: xchg1024test # [ 2.466195] virtiofs virtio6: discovered new tag: nix-store1025second # [ 2.483768] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1026test # [ 2.466668] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1027second # [ 2.481905] systemd-sysroot-fstab-check[123]: /sysroot should be mounted in the initrd, will request daemon-reload.1028test # [ 2.468104] systemd[1]: Starting rw-sysroot-nix-store.service...1029test # [ 2.472453] systemd[1]: Reload requested from client PID 123 ('systemd-sysroot') (unit initrd-parse-etc.service)...1030test # [ 2.475522] systemd[1]: Reloading...1031second # [ 2.491975] systemd[1]: Mounted /sysroot/nix/.ro-store.1032second # [ 2.493279] systemd[1]: Mounted /sysroot/tmp/shared.1033second # [ 2.496057] systemd[1]: Mounted /sysroot/tmp/xchg.1034test # [ 2.483290] virtiofs virtio7: discovered new tag: shared1035test # [ 2.483756] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1036test # [ 2.485494] virtiofs virtio8: discovered new tag: xchg1037test # [ 2.485973] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1038second # [ 2.497681] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1039second # [ 2.499520] systemd[1]: Finished rw-sysroot-nix-store.service.1040second # [ 2.503084] systemd[1]: Reload requested from client PID 123 ('systemd-sysroot') (unit initrd-parse-etc.service)...1041second # [ 2.507275] systemd[1]: Reloading...1042second # [ 2.641524] systemd[1]: Reloading finished in 136 ms.1043test # [ 2.642808] systemd[1]: Reloading finished in 169 ms.1044second # [ 2.668932] systemd-sysroot-fstab-check[123]: Requesting initrd-fs.target/start/replace...1045second # [ 2.670431] systemd-sysroot-fstab-check[123]: Requesting swap.target/start/replace...1046second # [ 2.673421] systemd[1]: Starting rw-sysroot-nix-store.service...1047second # [ 2.675136] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1048second # [ 2.682368] systemd[1]: Finished Mountpoints Configured in the Real Root.1049second # [ 2.683107] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1050test # [ 2.673646] systemd-sysroot-fstab-check[123]: Requesting initrd-fs.target/start/replace...1051test # [ 2.677589] systemd[1]: Mounted /sysroot/nix/.ro-store.1052second # [ 2.694903] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1053test # [ 2.680340] systemd[1]: Mounted /sysroot/tmp/shared.1054second # [ 2.695892] systemd[1]: Finished rw-sysroot-nix-store.service.1055test # [ 2.680860] systemd[1]: Mounted /sysroot/tmp/xchg.1056test # [ 2.681665] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1057test # [ 2.683965] systemd[1]: Finished rw-sysroot-nix-store.service.1058test # [ 2.688235] systemd-sysroot-fstab-check[123]: Requesting swap.target/start/replace...1059test # [ 2.692095] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1060test # [ 2.695009] systemd[1]: Finished Mountpoints Configured in the Real Root.1061test # [ 2.696444] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1062test # [ 2.701720] systemd[1]: Starting rw-sysroot-nix-store.service...1063test # [ 2.717715] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1064test # [ 2.718584] systemd[1]: Finished rw-sysroot-nix-store.service.1065second # [ 2.833407] (udev-worker)[98]: mtd0ro: Failed to find and pin callout binary "/nix/store/115gmjp006v2rh6wbyxqxlhng9bqa6q4-systemd-262/lib/udev/mtd_probe": No such file or directory1066second # [ 2.834865] (udev-worker)[98]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1067second # [ 2.851553] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1068second # [ 2.853173] systemd[1]: Stopped Virtual Console Setup.1069second # [ 2.853753] systemd[1]: Stopping Virtual Console Setup...1070test # [ 2.838939] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/115gmjp006v2rh6wbyxqxlhng9bqa6q4-systemd-262/lib/udev/mtd_probe": No such file or directory1071second # [ 2.855268] systemd[1]: Starting Virtual Console Setup...1072test # [ 2.840728] (udev-worker)[99]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1073test # [ 2.856646] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1074test # [ 2.857875] systemd[1]: Stopped Virtual Console Setup.1075test # [ 2.858632] systemd[1]: Stopping Virtual Console Setup...1076test # [ 2.861562] systemd[1]: Starting Virtual Console Setup...1077second # [ 2.882664] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones.1078second # [ 2.884814] systemd[1]: Finished Virtual Console Setup.1079test # [ 2.886643] systemd-vconsole-setup[159]: Configuration of first virtual console was skipped, ignoring remaining ones.1080test # [ 2.888911] systemd[1]: Finished Virtual Console Setup.1081second # [ 3.315924] systemd[1]: Mounting /sysroot/nix/store...1082test # [ 3.316301] systemd[1]: Mounting /sysroot/nix/store...1083second # [ 3.364751] systemd[1]: Mounted /sysroot/nix/store.1084second # [ 3.368206] systemd[1]: Reached target Initrd File Systems.1085second # [ 3.370630] systemd[1]: Starting Find NixOS closure...1086second # [ 3.377745] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1087test # [ 3.365025] systemd[1]: Mounted /sysroot/nix/store.1088test # [ 3.368179] systemd[1]: Reached target Initrd File Systems.1089test # [ 3.371081] systemd[1]: Starting Find NixOS closure...1090test # [ 3.380618] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1091second # [ 3.422910] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1092second # [ 3.428829] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1093test # [ 3.423146] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1094second # [ 3.440976] systemd[1]: Finished Find NixOS closure.1095test # [ 3.425734] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1096second # [ 3.442969] systemd[1]: Reached target Initrd Default Target.1097second # [ 3.448182] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1098test # [ 3.435243] systemd[1]: Finished Find NixOS closure.1099test # [ 3.438241] systemd[1]: Reached target Initrd Default Target.1100test # [ 3.444797] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1101second # [ 3.474889] systemd[1]: Stopped target Initrd Default Target.1102second # [ 3.476572] systemd[1]: Stopped target Basic System.1103second # [ 3.477806] systemd[1]: Stopped target Initrd Root Device.1104second # [ 3.478960] systemd[1]: Stopped target Path Units.1105second # [ 3.483223] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1106second # [ 3.484561] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1107second # [ 3.485966] systemd[1]: Stopped target Slice Units.1108second # [ 3.487623] systemd[1]: Stopped target Socket Units.1109test # [ 3.472607] systemd[1]: Stopped target Initrd Default Target.1110test # [ 3.473551] systemd[1]: Stopped target Basic System.1111second # [ 3.489551] systemd[1]: Stopped target System Initialization.1112test # [ 3.474342] systemd[1]: Stopped target Initrd Root Device.1113second # [ 3.491433] systemd[1]: Stopped target Swaps.1114test # [ 3.476042] systemd[1]: Stopped target Path Units.1115second # [ 3.493300] systemd[1]: Stopped target Timer Units.1116test # [ 3.478287] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1117test # [ 3.480094] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1118test # [ 3.484387] systemd[1]: Stopped target Slice Units.1119test # [ 3.485076] systemd[1]: Stopped target Socket Units.1120test # [ 3.485726] systemd[1]: Stopped target System Initialization.1121test # [ 3.486820] systemd[1]: Stopped target Swaps.1122test # [ 3.488375] systemd[1]: Stopped target Timer Units.1123second # [ 3.496689] systemd[1]: dbus.socket: Deactivated successfully.1124second # [ 3.498184] systemd[1]: Closed D-Bus System Message Bus Socket.1125second # [ 3.504500] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1126second # [ 3.506330] systemd[1]: Stopped Find NixOS closure.1127test # [ 3.489968] systemd[1]: dbus.socket: Deactivated successfully.1128second # [ 3.506931] systemd[1]: Starting rw-sysroot-nix-store.service...1129second # [ 3.509881] systemd[1]: systemd-sysctl.service: Deactivated successfully.1130second # [ 3.510631] systemd[1]: Stopped Apply Kernel Variables.1131test # [ 3.491876] systemd[1]: Closed D-Bus System Message Bus Socket.1132second # [ 3.511201] systemd[1]: systemd-modules-load.service: Deactivated successfully.1133test # [ 3.497658] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1134test # [ 3.498683] systemd[1]: Stopped Find NixOS closure.1135test # [ 3.499269] systemd[1]: Starting rw-sysroot-nix-store.service...1136second # [ 3.513987] systemd[1]: Stopped Load Kernel Modules.1137second # [ 3.515134] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1138second # [ 3.519714] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1139second # [ 3.520723] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1140second # [ 3.522048] systemd[1]: Stopped Create System Files and Directories.1141test # [ 3.504550] systemd[1]: systemd-sysctl.service: Deactivated successfully.1142test # [ 3.505401] systemd[1]: Stopped Apply Kernel Variables.1143test # [ 3.505940] systemd[1]: systemd-modules-load.service: Deactivated successfully.1144second # [ 3.523284] systemd[1]: Stopped target Local File Systems.1145test # [ 3.506625] systemd[1]: Stopped Load Kernel Modules.1146test # [ 3.507145] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1147second # [ 3.524534] systemd[1]: Stopped target Preparation for Local File Systems.1148second # [ 3.525876] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1149second # [ 3.526669] systemd[1]: Stopped Coldplug All udev Devices.1150second # [ 3.527287] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1151test # [ 3.513408] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1152test # [ 3.514833] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1153test # [ 3.515576] systemd[1]: Stopped Create System Files and Directories.1154second # [ 3.533619] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1155second # [ 3.536186] systemd[1]: Stopped Virtual Console Setup.1156second # [ 3.537694] systemd[1]: initrd-cleanup.service: Deactivated successfully.1157test # [ 3.522792] systemd[1]: Stopped target Local File Systems.1158test # [ 3.523392] systemd[1]: Stopped target Preparation for Local File Systems.1159second # [ 3.540336] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1160second # [ 3.541106] systemd[1]: systemd-udevd.service: Deactivated successfully.1161second # [ 3.542384] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1162test # [ 3.528302] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1163test # [ 3.529019] systemd[1]: Stopped Coldplug All udev Devices.1164second # [ 3.544409] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.1165test # [ 3.529564] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1166test # [ 3.530261] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1167second # [ 3.546210] systemd[1]: Closed udev Varlink Socket.1168test # [ 3.530953] systemd[1]: Stopped Virtual Console Setup.1169second # [ 3.546870] systemd[1]: Starting Cleanup udev Database...1170test # [ 3.531450] systemd[1]: systemd-udevd.service: Deactivated successfully.1171second # [ 3.547504] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1172second # [ 3.548441] systemd[1]: Stopped Create Static Device Nodes in /dev.1173second # [ 3.549136] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1174second # [ 3.550014] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1175test # [ 3.534514] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1176second # [ 3.550790] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1177test # [ 3.535340] systemd[1]: initrd-cleanup.service: Deactivated successfully.1178second # [ 3.551566] systemd[1]: Stopped Create List of Static Device Nodes.1179test # [ 3.536150] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1180test # [ 3.536756] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.1181test # [ 3.537406] systemd[1]: Closed udev Varlink Socket.1182test # [ 3.537874] systemd[1]: Starting Cleanup udev Database...1183test # [ 3.538380] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1184test # [ 3.539069] systemd[1]: Stopped Create Static Device Nodes in /dev.1185test # [ 3.539645] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1186second # [ 3.555836] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1187test # [ 3.544411] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1188second # [ 3.560173] systemd[1]: Finished rw-sysroot-nix-store.service.1189test # [ 3.548224] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1190test # [ 3.549062] systemd[1]: Stopped Create List of Static Device Nodes.1191test # [ 3.549636] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1192test # [ 3.550242] systemd[1]: Finished rw-sysroot-nix-store.service.1193second # [ 3.574515] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1194second # [ 3.575292] systemd[1]: Finished Cleanup udev Database.1195second # [ 3.575948] systemd[1]: Reached target Switch Root.1196test # [ 3.560872] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1197test # [ 3.561582] systemd[1]: Finished Cleanup udev Database.1198test # [ 3.564750] systemd[1]: Reached target Switch Root.1199second # [ 3.580459] systemd[1]: Starting NixOS Activation...1200test # [ 3.565247] systemd[1]: Starting NixOS Activation...1201second # [ 3.653099] initrd-nixos-activation-start[179]: booting system configuration /nix/store/y5jasjl3fkyjjl1kjdgsj4s9354nqi6w-nixos-system-second-test1202test # [ 3.637560] initrd-nixos-activation-start[182]: booting system configuration /nix/store/pkmrsr198f79v866bcannckgyyj06krl-nixos-system-test-test1203second # [ 3.679189] initrd-nixos-activation-start[179]: running activation script...1204test # [ 3.663981] initrd-nixos-activation-start[182]: running activation script...1205second # [ 3.883144] initrd-nixos-activation-start[202]: setting up /etc...1206test # [ 3.894029] initrd-nixos-activation-start[205]: setting up /etc...1207second # [ 3.977078] initrd-nixos-activation-start[202]: setting up age secrets...1208test # [ 3.989184] initrd-nixos-activation-start[205]: setting up age secrets...1209second # [ 4.060912] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1210second # [ 4.062644] systemd[1]: Finished NixOS Activation.1211second # [ 4.063373] systemd[1]: Starting Switch Root...1212second # [ 4.076225] systemd[1]: Switching root.1213test # [ 4.105770] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1214test # [ 4.107483] systemd[1]: Finished NixOS Activation.1215test # [ 4.108268] systemd[1]: Starting Switch Root...1216test # [ 4.124173] systemd[1]: Switching root.1217second # [ 4.250469] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1218test # [ 4.290326] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1219second # [ 4.776165] systemd[1]: systemd 262 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)1220second # [ 4.776271] systemd[1]: Detected virtualization qemu.1221second # [ 4.776343] systemd[1]: Detected architecture arm64.1222second # [ 4.776460] systemd[1]: Detected first boot.1223second # [ 4.788681] systemd[1]: Initializing machine ID from random generator.1224test # [ 4.805445] systemd[1]: systemd 262 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)1225test # [ 4.805575] systemd[1]: Detected virtualization qemu.1226test # [ 4.805737] systemd[1]: Detected architecture arm64.1227test # [ 4.805864] systemd[1]: Detected first boot.1228test # [ 4.818198] systemd[1]: Initializing machine ID from random generator.1229second # [ 4.923802] systemd[1]: bpf-restrict-fs: LSM BPF program attached1230test # [ 4.950416] systemd[1]: bpf-restrict-fs: LSM BPF program attached1231second # [ 5.013054] systemd[1]: Applying preset policy.1232test # [ 5.037792] systemd[1]: Applying preset policy.1233second # [ 5.161175] systemd[1]: Populated /etc with preset unit settings.1234test # [ 5.224072] systemd[1]: Populated /etc with preset unit settings.1235second # [ 5.352772] systemd[1]: initrd-switch-root.service: Deactivated successfully.1236second # [ 5.353038] systemd[1]: Stopped initrd-switch-root.service.1237second # [ 5.356273] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1238second # [ 5.357079] systemd[1]: Created slice Slice /system/getty.1239second # [ 5.357634] systemd[1]: Created slice User and Session Slice.1240second # [ 5.360499] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1241second # [ 5.361066] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1242second # [ 5.361615] systemd[1]: Expecting device /dev/hvc0...1243second # [ 5.364319] systemd[1]: Expecting device /dev/ttyAMA0...1244second # [ 5.364793] systemd[1]: Reached target Local Encrypted Volumes.1245second # [ 5.365370] systemd[1]: Stopped target initrd-fs.target.1246second # [ 5.365901] systemd[1]: Stopped target initrd-root-fs.target.1247second # [ 5.368730] systemd[1]: Stopped target initrd-switch-root.target.1248second # [ 5.369194] systemd[1]: Reached target Virtual Machines and Containers.1249second # [ 5.369742] systemd[1]: Reached target Path Units.1250second # [ 5.372190] systemd[1]: Reached target Remote File Systems.1251second # [ 5.372633] systemd[1]: Reached target Slice Units.1252second # [ 5.373160] systemd[1]: Reached target Swaps.1253second # [ 5.376067] systemd[1]: Listening on Query the User Interactively for a Password.1254second # [ 5.377933] systemd[1]: Listening on Process Core Dump Socket.1255second # [ 5.380375] systemd[1]: Listening on Credential Encryption/Decryption.1256second # [ 5.381726] systemd[1]: Listening on Factory Reset Management.1257second # [ 5.383233] systemd[1]: Listening on Hostname Service Socket.1258second # [ 5.385374] systemd[1]: Starting Journal Log Access Socket...1259second # [ 5.387775] systemd[1]: Listening on Journal Audit Socket.1260second # [ 5.389713] systemd[1]: Listening on Console Output Muting Service Socket.1261second # [ 5.391339] systemd[1]: Listening on Network Management Metrics Varlink Socket.1262second # [ 5.392048] systemd[1]: Listening on Network Management Varlink Socket.1263second # [ 5.392681] systemd[1]: Listening on Network Management Netlink Socket.1264second # [ 5.393421] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1265second # [ 5.393941] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1266second # [ 5.398079] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1267second # [ 5.401912] systemd[1]: Listening on Disk Repartitioning Service Socket.1268second # [ 5.403705] systemd[1]: Listening on Resolve Monitor Varlink Socket.1269second # [ 5.404225] systemd[1]: Listening on Resolve Service Varlink Socket.1270second # [ 5.404759] systemd[1]: Listening on udev Varlink Socket.1271second # [ 5.407978] systemd[1]: Mounting Huge Pages File System...1272second # [ 5.410580] systemd[1]: Mounting POSIX Message Queue File System...1273second # [ 5.424561] systemd[1]: Mounting Kernel Debug File System...1274second # [ 5.431232] systemd[1]: Mounting Kernel Trace File System...1275second # [ 5.435325] systemd[1]: Starting Create List of Static Device Nodes...1276second # [ 5.438623] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1277second # [ 5.448107] systemd[1]: Mounting Kernel Configuration File System...1278test # [ 5.432643] systemd[1]: initrd-switch-root.service: Deactivated successfully.1279test # [ 5.432929] systemd[1]: Stopped initrd-switch-root.service.1280test # [ 5.436093] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1281second # [ 5.450594] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1282test # [ 5.437237] systemd[1]: Created slice Slice /system/getty.1283second # [ 5.455056] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1284test # [ 5.439623] systemd[1]: Created slice User and Session Slice.1285test # [ 5.440196] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1286second # [ 5.455731] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1287test # [ 5.440773] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1288test # [ 5.441317] systemd[1]: Expecting device /dev/hvc0...1289test # [ 5.441893] systemd[1]: Expecting device /dev/ttyAMA0...1290test # [ 5.445375] systemd[1]: Reached target Local Encrypted Volumes.1291test # [ 5.445862] systemd[1]: Stopped target initrd-fs.target.1292test # [ 5.447854] systemd[1]: Stopped target initrd-root-fs.target.1293test # [ 5.448315] systemd[1]: Stopped target initrd-switch-root.target.1294test # [ 5.448875] systemd[1]: Reached target Virtual Machines and Containers.1295test # [ 5.449555] systemd[1]: Reached target Path Units.1296test # [ 5.452442] systemd[1]: Reached target Remote File Systems.1297test # [ 5.452892] systemd[1]: Reached target Slice Units.1298test # [ 5.453434] systemd[1]: Reached target Swaps.1299test # [ 5.456693] systemd[1]: Listening on Query the User Interactively for a Password.1300test # [ 5.459255] systemd[1]: Listening on Process Core Dump Socket.1301test # [ 5.460635] systemd[1]: Listening on Credential Encryption/Decryption.1302second # [ 5.476234] systemd[1]: Mounting FUSE Control File System...1303second # [ 5.476632] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671304test # [ 5.463006] systemd[1]: Listening on Factory Reset Management.1305test # [ 5.463465] systemd[1]: Listening on Hostname Service Socket.1306test # [ 5.465689] systemd[1]: Starting Journal Log Access Socket...1307test # [ 5.468845] systemd[1]: Listening on Journal Audit Socket.1308test # [ 5.471247] systemd[1]: Listening on Console Output Muting Service Socket.1309test # [ 5.471917] systemd[1]: Listening on Network Management Metrics Varlink Socket.1310test # [ 5.472618] systemd[1]: Listening on Network Management Varlink Socket.1311test # [ 5.473241] systemd[1]: Listening on Network Management Netlink Socket.1312test # [ 5.473921] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1313test # [ 5.477927] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1314test # [ 5.480205] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1315test # [ 5.484718] systemd[1]: Listening on Disk Repartitioning Service Socket.1316second # [ 5.501845] systemd[1]: Starting Journal Service...1317test # [ 5.485238] systemd[1]: Listening on Resolve Monitor Varlink Socket.1318test # [ 5.485816] systemd[1]: Listening on Resolve Service Varlink Socket.1319test # [ 5.487880] systemd[1]: Listening on udev Varlink Socket.1320test # [ 5.489843] systemd[1]: Mounting Huge Pages File System...1321test # [ 5.494536] systemd[1]: Mounting POSIX Message Queue File System...1322second # [ 5.512088] systemd[1]: Starting Load Kernel Modules...1323test # [ 5.502631] systemd[1]: Mounting Kernel Debug File System...1324second # [ 5.530285] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1325test # [ 5.515848] systemd[1]: Mounting Kernel Trace File System...1326test # [ 5.526635] systemd[1]: Starting Create List of Static Device Nodes...1327second # [ 5.545696] systemd[1]: Starting Remount Root and Kernel File Systems...1328test # [ 5.527057] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1329second # [ 5.547005] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1330test # [ 5.538585] systemd[1]: Mounting Kernel Configuration File System...1331test # [ 5.539008] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1332second # [ 5.557098] systemd-journald[307]: Collecting audit messages is enabled.1333test # [ 5.543901] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1334second # [ 5.558119] systemd[1]: Starting Coldplug All udev Devices...1335second # [ 5.559856] systemd[1]: Listening on Journal Log Access Socket.1336second # [ 5.560219] systemd[1]: Mounted Huge Pages File System.1337second # [ 5.560627] systemd[1]: Mounted POSIX Message Queue File System.1338second # [ 5.560978] systemd[1]: Mounted Kernel Debug File System.1339second # [ 5.561334] systemd[1]: Mounted Kernel Trace File System.1340test # [ 5.547582] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1341second # [ 5.562028] systemd[1]: Queued start job for default target Multi-User System.1342second # [ 5.562898] systemd[1]: systemd-journald.service: Deactivated successfully.1343test # [ 5.561929] systemd[1]: Mounting FUSE Control File System...1344second # [ 5.585843] systemd[1]: Finished Create List of Static Device Nodes.1345test # [ 5.567190] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671346second # [ 5.587101] systemd[1]: Started Journal Service.1347second # [ 5.586987] systemd[1]: Mounted Kernel Configuration File System.1348test # [ 5.587185] systemd[1]: Starting Journal Service...1349second # [ 5.604251] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1350test # [ 5.598342] systemd[1]: Starting Load Kernel Modules...1351test # [ 5.608371] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1352second # [ 5.621635] systemd-modules-load[308]: Module 'atkbd' is built in1353second # [ 5.622241] systemd-modules-load[308]: Module 'ctr' is built in1354second # [ 5.629405] systemd-modules-load[308]: Module 'loop' is built in1355second # [ 5.630016] systemd[1]: Mounted FUSE Control File System.1356test # [ 5.618156] systemd[1]: Starting Remount Root and Kernel File Systems...1357test # [ 5.618514] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1358second # [ 5.633373] systemd[1]: Finished Load Kernel Modules.1359test # [ 5.629274] systemd[1]: Starting Coldplug All udev Devices...1360second # [ 5.643341] systemd[1]: Starting Firewall...1361test # [ 5.633254] systemd[1]: Listening on Journal Log Access Socket.1362test # [ 5.633745] systemd[1]: Mounted Huge Pages File System.1363second # [ 5.646256] EXT4-fs (vda): re-mounted 8401d8ce-4aae-4d29-817d-75787602346e.1364second # [ 5.649403] systemd[1]: Starting Apply Kernel Variables...1365test # [ 5.647976] systemd-journald[331]: Collecting audit messages is enabled.1366test # [ 5.654991] systemd[1]: Mounted POSIX Message Queue File System.1367test # [ 5.655443] systemd[1]: Mounted Kernel Debug File System.1368test # [ 5.655851] systemd[1]: Mounted Kernel Trace File System.1369test # [ 5.651920] systemd[1]: Queued start job for default target Multi-User System.1370test # [ 5.657208] systemd[1]: systemd-journald.service: Deactivated successfully.1371second # [ 5.676871] systemd[1]: Finished Remount Root and Kernel File Systems.1372second # [ 5.678256] systemd[1]: Listening on Disk Image Download Service Socket.1373test # [ 5.666322] systemd[1]: Finished Create List of Static Device Nodes.1374test # [ 5.666962] systemd[1]: Started Journal Service.1375test # [ 5.666334] systemd[1]: Mounted Kernel Configuration File System.1376second # [ 5.682782] systemd[1]: Starting Flush Journal to Persistent Storage...1377second # [ 5.683404] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1378test # [ 5.676145] systemd-modules-load[332]: Module 'atkbd' is built in1379test # [ 5.676726] systemd-modules-load[332]: Module 'ctr' is built in1380second # [ 5.692432] systemd[1]: Starting Load/Save OS Random Seed...1381second # [ 5.692947] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1382test # [ 5.679902] systemd-modules-load[332]: Module 'loop' is built in1383test # [ 5.685344] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1384second # [ 5.701454] systemd-oomd[310]: No swap; memory pressure usage will be degraded1385test # [ 5.693900] systemd[1]: Finished Load Kernel Modules.1386second # [ 5.714570] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1387test # [ 5.712699] systemd[1]: Starting Firewall...1388test # [ 5.717075] systemd[1]: Starting Apply Kernel Variables...1389test # [ 5.717625] systemd[1]: Mounted FUSE Control File System.1390test # [ 5.730093] EXT4-fs (vda): re-mounted 981b3276-3780-48c0-b37a-0b53d8420448.1391second # [ 5.754748] systemd-journald[307]: Received client request to flush runtime journal.1392test # [ 5.741748] systemd[1]: Finished Remount Root and Kernel File Systems.1393test # [ 5.742436] systemd[1]: Listening on Disk Image Download Service Socket.1394test # [ 5.756404] systemd[1]: Starting Flush Journal to Persistent Storage...1395test # [ 5.757029] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1396test # [ 5.772611] systemd[1]: Starting Load/Save OS Random Seed...1397test # [ 5.773241] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1398second # [ 5.792831] systemd[1]: Finished Load/Save OS Random Seed.1399second # [ 5.793426] systemd[1]: Reached target First Boot Complete.1400second # [ 5.800672] systemd[1]: Finished Flush Journal to Persistent Storage.1401test # [ 5.785969] systemd-oomd[334]: No swap; memory pressure usage will be degraded1402test # [ 5.792252] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1403second # [ 5.809733] systemd[1]: Finished Apply Kernel Variables.1404second # [ 5.812444] systemd[1]: Starting Network Name Resolution...1405test # [ 5.830165] systemd-journald[331]: Received client request to flush runtime journal.1406second # [ 5.849405] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1407second # [ 5.850914] systemd[1]: Starting Create Static Device Nodes in /dev...1408test # [ 5.865954] systemd[1]: Finished Load/Save OS Random Seed.1409test # [ 5.866555] systemd[1]: Reached target First Boot Complete.1410test # [ 5.873030] systemd[1]: Finished Flush Journal to Persistent Storage.1411test # [ 5.903899] systemd[1]: Finished Apply Kernel Variables.1412test # [ 5.912577] systemd[1]: Starting Network Name Resolution...1413test # [ 5.947478] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1414test # [ 5.948938] systemd[1]: Starting Create Static Device Nodes in /dev...1415second # [ 5.993574] systemd-resolved[336]: Positive Trust Anchors:1416second # [ 5.994160] systemd-resolved[336]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1417second # [ 5.995048] systemd-resolved[336]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161418second # [ 5.995945] systemd-resolved[336]: 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 test1419second # [ 6.028582] systemd-resolved[336]: Using system hostname 'second'.1420second # [ 6.029189] systemd[1]: Started Network Name Resolution.1421second # [ 6.088508] systemd[1]: Finished Coldplug All udev Devices.1422test # [ 6.077728] systemd-resolved[360]: Positive Trust Anchors:1423test # [ 6.078308] systemd-resolved[360]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1424test # [ 6.079204] systemd-resolved[360]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161425second # [ 6.097375] systemd[1]: Finished Create Static Device Nodes in /dev.1426second # [ 6.098482] systemd[1]: Reached target Preparation for Local File Systems.1427second # [ 6.100872] systemd[1]: Starting Rule-based Manager for Device Events and Files...1428test # [ 6.084800] systemd-resolved[360]: 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 test1429test # [ 6.116579] systemd-resolved[360]: Using system hostname 'test'.1430test # [ 6.117174] systemd[1]: Started Network Name Resolution.1431second # [ 6.151177] systemd-udevd[348]: Using default interface naming scheme 'v261'.1432test # [ 6.162938] systemd[1]: Finished Coldplug All udev Devices.1433test # [ 6.172455] systemd[1]: Finished Create Static Device Nodes in /dev.1434test # [ 6.174147] systemd[1]: Reached target Preparation for Local File Systems.1435test # [ 6.175983] systemd[1]: Starting Rule-based Manager for Device Events and Files...1436second # [ 6.221651] systemd[1]: Started Rule-based Manager for Device Events and Files.1437test # [ 6.221560] systemd-udevd[372]: Using default interface naming scheme 'v261'.1438test # [ 6.286609] systemd[1]: Started Rule-based Manager for Device Events and Files.1439second # [ 6.336310] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1440second # [ 6.350979] systemd[1]: Mounting /run/wrappers...1441second # [ 6.358190] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1442second # [ 6.396171] systemd[1]: Mounted /run/wrappers.1443second # [ 6.397226] systemd[1]: Reached target Local File Systems.1444second # [ 6.401030] systemd[1]: Listening on Boot Loader Control Service Socket.1445second # [ 6.403557] systemd[1]: Starting register-nix-paths.service...1446second # [ 6.408066] systemd[1]: Starting Create SUID/SGID Wrappers...1447test # [ 6.392395] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1448second # [ 6.408618] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1449second # [ 6.412103] systemd[1]: Starting Save Transient machine-id to Disk...1450test # [ 6.412825] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1451test # [ 6.430414] systemd[1]: Mounting /run/wrappers...1452second # [ 6.447786] systemd[1]: Starting Create System Files and Directories...1453test # [ 6.476376] systemd[1]: Mounted /run/wrappers.1454test # [ 6.476877] systemd[1]: Reached target Local File Systems.1455test # [ 6.480499] systemd[1]: Listening on Boot Loader Control Service Socket.1456test # [ 6.484495] systemd[1]: Starting register-nix-paths.service...1457test # [ 6.489477] systemd[1]: Starting Create SUID/SGID Wrappers...1458test # [ 6.490056] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1459test # [ 6.500331] systemd[1]: Starting Save Transient machine-id to Disk...1460test # [ 6.507297] systemd[1]: Starting Create System Files and Directories...1461second # [ 6.578326] systemd[1]: Finished Create System Files and Directories.1462second # [ 6.583287] systemd[1]: Starting Rebuild Journal Catalog...1463second # [ 6.588288] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1464second # [ 6.628123] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1465test # [ 6.612542] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1466second # [ 6.651156] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1467test # [ 6.655806] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1468test # [ 6.666930] systemd[1]: Finished Create System Files and Directories.1469test # [ 6.671992] systemd[1]: Starting Rebuild Journal Catalog...1470test # [ 6.675837] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1471second # [ 6.732732] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1472second # [ 6.775360] systemd[1]: Finished Rebuild Journal Catalog.1473second # [ 6.783723] systemd[1]: Starting Update is Completed...1474test # [ 6.802623] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1475second # [ 6.857335] systemd[1]: Finished Update is Completed.1476test # [ 6.842293] systemd[1]: Finished Rebuild Journal Catalog.1477test # [ 6.849146] systemd[1]: Starting Update is Completed...1478test # [ 6.916065] systemd[1]: Finished Update is Completed.1479test # [ 6.974324] (udev-worker)[400]: Network interface NamePolicy= disabled on kernel command line.1480second # [ 6.991110] (udev-worker)[376]: Network interface NamePolicy= disabled on kernel command line.1481second # [ 6.994526] (udev-worker)[373]: Network interface NamePolicy= disabled on kernel command line.1482test # [ 6.981436] (udev-worker)[404]: Network interface NamePolicy= disabled on kernel command line.1483second # [ 7.192768] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1484second # [ 7.194977] systemd[1]: Finished Create SUID/SGID Wrappers.1485second # [ 7.215188] mousedev: PS/2 mouse device common for all mice1486test # [ 7.299434] mousedev: PS/2 mouse device common for all mice1487test # [ 7.349464] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1488test # [ 7.350286] systemd[1]: Finished Create SUID/SGID Wrappers.1489second # [ 7.369418] systemd[1]: Finished register-nix-paths.service.1490second # [ 7.371134] systemd[1]: Reached target System Initialization.1491second # [ 7.373523] systemd[1]: Started Discard unused filesystem blocks once a week.1492second # [ 7.374201] systemd[1]: Started Daily Cleanup of Temporary Directories.1493second # [ 7.374798] systemd[1]: Reached target Timer Units.1494second # [ 7.375274] systemd[1]: Listening on D-Bus System Message Bus Socket.1495second # [ 7.375848] systemd[1]: Listening on Nix Daemon Socket.1496second # [ 7.387163] systemd[1]: Listening on Authorization Manager Agent Helper.1497second # [ 7.387820] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1498second # [ 7.390756] systemd[1]: Reached target Socket Units.1499second # [ 7.392850] systemd[1]: Starting D-Bus System Message Bus...1500test # [ 7.416090] systemd[1]: Finished register-nix-paths.service.1501test # [ 7.416735] systemd[1]: Reached target System Initialization.1502test # [ 7.419982] systemd[1]: Started Discard unused filesystem blocks once a week.1503test # [ 7.420853] systemd[1]: Started Daily Cleanup of Temporary Directories.1504test # [ 7.421478] systemd[1]: Reached target Timer Units.1505test # [ 7.421945] systemd[1]: Listening on D-Bus System Message Bus Socket.1506test # [ 7.422532] systemd[1]: Listening on Nix Daemon Socket.1507test # [ 7.433211] systemd[1]: Listening on Authorization Manager Agent Helper.1508test # [ 7.433884] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1509test # [ 7.434638] systemd[1]: Reached target Socket Units.1510test # [ 7.438031] systemd[1]: Starting D-Bus System Message Bus...1511second # [ 7.499329] systemd[1]: Finished Firewall.1512second # [ 7.499846] systemd[1]: Reached target Preparation for Network.1513second # [ 7.501616] systemd[1]: Listening on Network Management Resolve Hook Socket.1514second # [ 7.503894] dbus-broker-launch[515]: Looking up NSS user entry for 'systemd-timesync'...1515second # [ 7.506494] systemd[1]: Starting Network Management...1516second # [ 7.509008] dbus-broker-launch[515]: NSS returned no entry for 'systemd-timesync'1517second # [ 7.509677] dbus-broker-launch[515]: Invalid user-name in /nix/store/pck2w3smbwrh948q75yfark74z6v1l71-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1518test # [ 7.538637] dbus-broker-launch[537]: Looking up NSS user entry for 'systemd-timesync'...1519test # [ 7.542745] dbus-broker-launch[537]: NSS returned no entry for 'systemd-timesync'1520test # [ 7.543470] dbus-broker-launch[537]: Invalid user-name in /nix/store/pck2w3smbwrh948q75yfark74z6v1l71-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1521second # [ 7.579020] dbus-broker-launch[515]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/mn4lmv01d4rl5y8l37k6hjlpdh2xpdfg-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1522second # [ 7.583099] dbus-broker-launch[515]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/mn4lmv01d4rl5y8l37k6hjlpdh2xpdfg-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1523test # [ 7.572094] systemd[1]: Finished Firewall.1524test # [ 7.572615] systemd[1]: Reached target Preparation for Network.1525test # [ 7.573156] systemd[1]: Listening on Network Management Resolve Hook Socket.1526second # [ 7.588122] dbus-broker-launch[515]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/35a9madwirlwb9wnblmwzbdv54cw49pj-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1527test # [ 7.577912] systemd[1]: Starting Network Management...1528second # [ 7.592596] dbus-broker-launch[515]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/xsp02xw40qnj0xybs6i40055kkc9mxyl-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1529second # [ 7.600730] systemd[1]: Started D-Bus System Message Bus.1530second # [ 7.601294] systemd[1]: Reached target Basic System.1531second # [ 7.601779] systemd[1]: Starting Network Manager Script Dispatcher Service...1532test # [ 7.590938] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/mn4lmv01d4rl5y8l37k6hjlpdh2xpdfg-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1533second # [ 7.608157] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1534second # [ 7.611374] systemd[1]: Starting Network Manager...1535test # [ 7.595111] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/mn4lmv01d4rl5y8l37k6hjlpdh2xpdfg-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1536test # [ 7.602023] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/35a9madwirlwb9wnblmwzbdv54cw49pj-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1537test # [ 7.607155] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/xsp02xw40qnj0xybs6i40055kkc9mxyl-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1538test # [ 7.614510] systemd[1]: Started D-Bus System Message Bus.1539test # [ 7.615025] systemd[1]: Reached target Basic System.1540second # [ 7.632151] systemd[1]: Started backdoor.service.1541test # [ 7.618874] systemd[1]: Starting Network Manager Script Dispatcher Service...1542second # [ 7.637077] systemd[1]: Starting Import lastlog data into lastlog2 database...1543test # [ 7.622181] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1544test # [ 7.626321] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1545second # [ 7.660052] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1546second # [ 7.667137] dbus-broker-launch[515]: Ready1547test # [ 7.664240] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1548test # [ 7.675779] dbus-broker-launch[537]: Ready1549second # [ 7.698237] systemd[1]: Starting Post-Boot Actions...1550test # [ 7.685952] systemd[1]: Starting Network Manager...1551second # [ 7.735095] systemd[1]: Started Reset console on configuration changes.1552test # [ 7.720899] systemd[1]: Started backdoor.service.1553test # [ 7.733527] systemd[1]: Starting Import lastlog data into lastlog2 database...1554test # [ 7.768233] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1555test # [ 7.791224] systemd[1]: Starting Post-Boot Actions...1556test # [ 7.820766] systemd[1]: Started Reset console on configuration changes.1557second # [ 7.846441] wxrshyqifd3a301c2q4frbrsgvl9225i-wifi-secrets[532]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1558test # [ 7.837313] i7s7ishricp8ddyw1z8fynkr3bhavn7n-wifi-secrets[557]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1559second # [ 7.857168] systemd[1]: Finished Post-Boot Actions.1560test # [ 7.853283] wxrshyqifd3a301c2q4frbrsgvl9225i-wifi-secrets[556]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1561second # [ 7.884510] cfg80211: Loading compiled-in X.509 certificates for regulatory database1562second # [ 7.890985] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1563second # [ 7.903194] systemd[1]: Started Name Service Cache Daemon (nsncd).1564test # [ 7.888663] zirzjmjcpcp2h9bmxad7ld6caiwshd23-wifi-secrets[558]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1565second # [ 7.908177] systemd[1]: Finished Save Transient machine-id to Disk.1566second # [ 7.917962] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1567second # connecting to host...1568second # [ 7.922618] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1569test # [ 7.906008] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1570second # [ 7.921723] systemd[1]: Reached target Host and Network Name Lookups.1571second # [ 7.926258] systemd[1]: Reached target User and Group Name Lookups.1572test # [ 7.920366] systemd[1]: Finished Save Transient machine-id to Disk.1573test # [ 7.921067] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1574test # [ 7.921848] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1575test # [ 7.922460] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1576test # [ 7.923245] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1577second # [ 7.941655] systemd[1]: Starting User Login Management...1578second # [ 7.950361] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1579test # [ 7.935782] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1580second # [ 7.956451] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1581test # [ 7.945020] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1582second # [ 7.963935] nsncd[537]: Oct 22 15:02:07.824 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1583second # [ 7.973527] systemd[1]: Finished Import lastlog data into lastlog2 database.1584test # connecting to host...1585test # [ 7.989406] systemd[1]: Finished Post-Boot Actions.1586second # [ 8.021086] systemd-networkd[528]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1587test # [ 8.020370] systemd[1]: Started Name Service Cache Daemon (nsncd).1588test # [ 8.021242] nsncd[562]: Oct 22 15:02:07.881 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1589test # [ 8.022473] systemd[1]: Reached target Host and Network Name Lookups.1590test # [ 8.023038] systemd[1]: Reached target User and Group Name Lookups.1591second # [ 8.038973] systemd-networkd[528]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1592second # [ 8.050260] systemd-networkd[528]: lo: Link UP1593second # [ 8.050736] systemd-networkd[528]: lo: Gained carrier1594test # [ 8.043740] systemd[1]: Starting User Login Management...1595second # [ 8.051195] systemd-networkd[528]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1596second # [ 8.067295] systemd-networkd[528]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1597test # [ 8.060177] cfg80211: Loading compiled-in X.509 certificates for regulatory database1598second # [ 8.076148] systemd[1]: Started Network Management.1599second # [ 8.076634] systemd-networkd[528]: eth1: Link UP1600second # [ 8.077049] systemd-networkd[528]: eth1: Gained carrier1601second # [ 8.077518] systemd-networkd[528]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1602second # [ 8.078845] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1603test # [ 8.082454] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1604test # [ 8.082971] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1605test: Guest shell says: b'Spawning backdoor root shell...\n'1606second # [ 8.109593] systemd[1]: Started Network Manager Script Dispatcher Service.1607test # [ 8.110671] systemd[1]: Finished Import lastlog data into lastlog2 database.1608test: connected to guest root shell1609test: (connecting took 8.39 seconds)1610test: (finished: waiting for the VM to finish booting, in 8.39 seconds)1611second # [ 8.169844] systemd-logind[567]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1612second # [ 8.179930] systemd-logind[567]: Watching system buttons on /dev/input/event0 (gpio-keys)1613test # [ 8.168930] systemd[1]: Started Network Manager Script Dispatcher Service.1614second # [ 8.185116] systemd-logind[567]: New seat seat0.1615second # [ 8.185567] systemd[1]: Started User Login Management.1616second # [ 8.188068] systemd[1]: Starting linger-users.service...1617second # [ 8.190848] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1618test # [ 8.175700] systemd-networkd[552]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1619test # [ 8.188342] systemd-networkd[552]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1620test # [ 8.190044] systemd-networkd[552]: lo: Link UP1621test # [ 8.190463] systemd-networkd[552]: lo: Gained carrier1622second # [ 8.215463] systemd[1]: Starting Hostname Service...1623test # [ 8.204506] systemd[1]: Started Network Management.1624test # [ 8.211601] systemd-networkd[552]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1625test # [ 8.221500] systemd-networkd[552]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1626test # [ 8.222276] systemd-networkd[552]: eth1: Link UP1627test # [ 8.222696] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1628test # [ 8.223322] systemd-networkd[552]: eth1: Gained carrier1629test # [ 8.223794] systemd-networkd[552]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1630second # [ 8.260728] systemd[1]: linger-users.service: Deactivated successfully.1631second # [ 8.264313] systemd[1]: Finished linger-users.service.1632test # [ 8.264209] systemd[1]: Starting Hostname Service...1633test # [ 8.281041] systemd-logind[600]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1634test # [ 8.286342] systemd-logind[600]: Watching system buttons on /dev/input/event0 (gpio-keys)1635test # [ 8.292560] systemd-logind[600]: New seat seat0.1636test # [ 8.295980] systemd[1]: Started User Login Management.1637second # [ 8.330035] systemd[1]: Started Hostname Service.1638test # [ 8.322252] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1639test # [ 8.334579] systemd[1]: Starting linger-users.service...1640second # [ 8.395406] systemd-networkd[528]: eth0: Link UP1641second # [ 8.395908] systemd-networkd[528]: eth0: Gained carrier1642second # [ 8.413842] systemd[1]: Started Network Manager.1643second # [ 8.415918] systemd[1]: Reached target Network.1644second # [ 8.420962] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1645test # [ 8.406446] systemd[1]: Started Hostname Service.1646second # [ 8.429279] systemd[1]: Starting Permit User Sessions...1647test # [ 8.419183] systemd[1]: linger-users.service: Deactivated successfully.1648test # [ 8.422376] systemd[1]: Finished linger-users.service.1649test # [ 8.464954] systemd-networkd[552]: eth0: Link UP1650test # [ 8.465513] systemd-networkd[552]: eth0: Gained carrier1651test # [ 8.483408] systemd[1]: Started Network Manager.1652test # [ 8.485701] systemd[1]: Reached target Network.1653test # [ 8.490616] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1654test # [ 8.497759] systemd[1]: Starting Permit User Sessions...1655second # [ 8.550546] systemd[1]: Finished Permit User Sessions.1656second # [ 8.561951] systemd[1]: Started Getty on tty1.1657second # [ 8.567976] systemd[1]: Reached target Login Prompts.1658test # [ 8.617369] systemd[1]: Finished Permit User Sessions.1659second # [ 8.634315] systemd-resolved[336]: eth0: Bus client set default route setting: yes1660second # [ 8.638589] systemd-resolved[336]: eth0: Bus client set DNS server list to: 10.0.2.31661test # [ 8.639430] systemd[1]: Started Getty on tty1.1662test # [ 8.639976] systemd[1]: Reached target Login Prompts.1663second # [ 8.692618] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1664second # [ 8.697194] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1665test # [ 8.683378] NetworkManager[559]: <warn> [1792681328.5469] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1666test # [ 8.688822] NetworkManager[559]: <warn> [1792681328.5504] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1667test # [ 8.709464] systemd-resolved[360]: eth0: Bus client set default route setting: yes1668test # [ 8.712080] systemd-resolved[360]: eth0: Bus client set DNS server list to: 10.0.2.31669test # [ 8.766530] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1670test # [ 8.771600] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1671second # [ 8.814955] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31672test # [ 8.922731] systemd[1]: Reached target Multi-User System.1673test # [ 8.925066] systemd[1]: Startup finished in 709ms (kernel) + 3.636s (initrd) + 4.579s (userspace) = 8.924s.1674test # [ 8.943328] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31675second # [ 9.034518] systemd[1]: Reached target Multi-User System.1676second # [ 9.037592] systemd[1]: Startup finished in 721ms (kernel) + 3.585s (initrd) + 4.730s (userspace) = 9.036s.1677second # [ 9.126920] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1678second # [ 9.132304] systemd[1]: Starting Virtual Console Setup...1679second # [ 9.149235] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1680second # [ 9.149922] systemd[1]: Stopped Virtual Console Setup.1681second # [ 9.155659] systemd[1]: Starting Virtual Console Setup...1682test # [ 9.222560] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1683test # [ 9.229881] systemd[1]: Starting Virtual Console Setup...1684test # [ 9.241771] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1685second # [ 9.257364] systemd-logind[567]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1686test # [ 9.243490] systemd[1]: Stopped Virtual Console Setup.1687test # [ 9.252742] systemd[1]: Starting Virtual Console Setup...1688second # [ 9.307333] systemd-vconsole-setup[628]: Configuration of first virtual console was skipped, ignoring remaining ones.1689second # [ 9.310207] systemd[1]: Finished Virtual Console Setup.1690test # [ 9.358504] systemd-logind[600]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1691test: (finished: waiting for unit NetworkManager.service, in 9.63 seconds)1692test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1693test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.02 seconds)1694test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1695test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.02 seconds)1696test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1697test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.02 seconds)1698(finished: run the VM test script, in 10.48 seconds)1699test # [ 9.456825] systemd-vconsole-setup[673]: Configuration of first virtual console was skipped, ignoring remaining ones.1700test # [ 9.459987] systemd[1]: Finished Virtual Console Setup.1701test script finished in 10.52s1702cleanup1703kill QemuMachine (pid 47)1704second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)1705second # [2026-10-22T15:02:09Z INFO virtiofsd] Client disconnected, shutting down1706second # [2026-10-22T15:02:09Z INFO virtiofsd] Client disconnected, shutting down1707second # [2026-10-22T15:02:09Z INFO virtiofsd] Client disconnected, shutting down1708test # [ 9.540079] systemd-networkd[552]: eth1: Gained IPv6LL1709kill QemuMachine (pid 48)1710test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)1711test # [2026-10-22T15:02:09Z INFO virtiofsd] Client disconnected, shutting down1712test # [2026-10-22T15:02:09Z INFO virtiofsd] Client disconnected, shutting down1713test # [2026-10-22T15:02:09Z INFO virtiofsd] Client disconnected, shutting down1714(finished: cleanup, in 0.28 seconds)