vm-test-run-wifi
checks.aarch64-linux.wifi
· build #214
· raw
1build01: building kza8jv93byqwf9gxhvalx565cayz1x2a-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)16second # Formatting '/build/vm-state-second/tmp.d9EqeFQYyM', fmt=raw size=107374182417second # mke2fs 1.47.4 (6-Mar-2025)18second # Discarding device blocks: 0/262144 done19second # Creating filesystem with 262144 4k blocks and 65536 inodes20second # Filesystem UUID: 4bb1824f-be15-43fc-948c-bdbeaa11207621second # Superblock backups stored on blocks:22second # 32768, 98304, 163840, 22937623second # 24second # Allocating group tables: 0/8 done25second # Writing inode tables: 0/8 done26second # Creating journal (8192 blocks): done27second # Writing superblocks and filesystem accounting information: 0/8 done28second # 29second # Virtualisation disk image created.30second # Starting virtiofs daemons...31second # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32second # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33second # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34second # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35second # [2026-10-03T00:04:47Z INFO virtiofsd] Waiting for vhost-user socket connection...36second # [2026-10-03T00:04:47Z INFO virtiofsd] Waiting for vhost-user socket connection...37second # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)38second # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether39second # [2026-10-03T00:04:47Z INFO virtiofsd] Waiting for vhost-user socket connection...40second # [2026-10-03T00:04:47Z INFO virtiofsd] Client connected, servicing requests41second # [2026-10-03T00:04:47Z INFO virtiofsd] Client connected, servicing requests42second # [2026-10-03T00:04:47Z INFO virtiofsd] Client connected, servicing requests43test # Disk image does not exist, creating the virtualisation disk image...44test # Formatting '/build/vm-state-test/tmp.cFFf4qzln8', fmt=raw size=107374182445test # mke2fs 1.47.4 (6-Mar-2025)46test # Discarding device blocks: 0/262144 done47test # Creating filesystem with 262144 4k blocks and 65536 inodes48test # Filesystem UUID: 80dda120-7136-485e-9bf4-6225ad27eebb49test # Superblock backups stored on blocks:50test # 32768, 98304, 163840, 22937651test # 52test # Allocating group tables: 0/8 done53test # Writing inode tables: 0/8 done54test: QEMU running (pid 48)55test # Creating journal (8192 blocks): done56test # Writing superblocks and filesystem accounting information: 0/8 done57test # 58test # Virtualisation disk image created.59test # Starting virtiofs daemons...60test # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)61test # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether62test # [2026-10-03T00:04:47Z INFO virtiofsd] Waiting for vhost-user socket connection...63test # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)64test # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether65test # [2026-10-03T00:04:47Z INFO virtiofsd] Waiting for vhost-user socket connection...66test # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)67test # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68test # [2026-10-03T00:04:47Z INFO virtiofsd] Waiting for vhost-user socket connection...69test # [2026-10-03T00:04:47Z INFO virtiofsd] Client connected, servicing requests70test # [2026-10-03T00:04:47Z INFO virtiofsd] Client connected, servicing requests71test # [2026-10-03T00:04:47Z INFO virtiofsd] Client connected, servicing requests72(finished: start all VMs, in 0.42 seconds)73test: waiting for unit NetworkManager.service74test: waiting for the VM to finish booting75second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]76second # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202677second # [ 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 DT82test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]83second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]84second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]85test # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202686second # [ 0.000000] Zone ranges:87test # [ 0.000000] KASLR enabled88test # [ 0.000000] random: crng init done89second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]90second # [ 0.000000] DMA32 empty91test # [ 0.000000] Machine model: linux,dummy-virt92second # [ 0.000000] Normal empty93test # [ 0.000000] efi: UEFI not found.94second # [ 0.000000] Device empty95second # [ 0.000000] Movable zone start for each node96test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT97second # [ 0.000000] Early memory node ranges98second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]99test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]100test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]101second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]102test # [ 0.000000] Zone ranges:103second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000104test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]105test # [ 0.000000] DMA32 empty106second # [ 0.000000] psci: probing for conduit method from DT.107test # [ 0.000000] Normal empty108second # [ 0.000000] psci: PSCIv1.3 detected in firmware.109test # [ 0.000000] Device empty110test # [ 0.000000] Movable zone start for each node111second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs112test # [ 0.000000] Early memory node ranges113second # [ 0.000000] psci: Trusted OS migration not required114second # [ 0.000000] psci: SMC Calling Convention v1.1115test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]116test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]117second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)118test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000119second # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296120second # [ 0.000000] Detected PIPT I-cache on CPU0121test # [ 0.000000] psci: probing for conduit method from DT.122test # [ 0.000000] psci: PSCIv1.3 detected in firmware.123second # [ 0.000000] CPU features: detected: GICv3 CPU interface124second # [ 0.000000] CPU features: detected: Spectre-v4125test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs126second # [ 0.000000] CPU features: detected: Spectre-BHB127test # [ 0.000000] psci: Trusted OS migration not required128test # [ 0.000000] psci: SMC Calling Convention v1.1129second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR130second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)131test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)132second # [ 0.000000] CPU features: detected: ARM erratum 1418040133test # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296134second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion135test # [ 0.000000] Detected PIPT I-cache on CPU0136test # [ 0.000000] CPU features: detected: GICv3 CPU interface137second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing138test # [ 0.000000] CPU features: detected: Spectre-v4139second # [ 0.000000] alternatives: applying boot alternatives140test # [ 0.000000] CPU features: detected: Spectre-BHB141test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR142test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)143test # [ 0.000000] CPU features: detected: ARM erratum 1418040144test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion145second # [ 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/zc805yy98n4wgcl6blvgfrfzi3hamvv9-nixos-system-second-test/init regInfo=/nix/.ro-store/lri5knw7xgd7k2yzc6pm86vdxkmk46kn-closure-info/registration console=ttyAMA0,115200n8 console=tty0146test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing147test # [ 0.000000] alternatives: applying boot alternatives148second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/lri5knw7xgd7k2yzc6pm86vdxkmk46kn-closure-info/registration", will be passed to user space.149second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes150second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)151second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)152test # [ 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/nhzs5nq18r2wcww7qxwy0v9wknbxljdj-nixos-system-test-test/init regInfo=/nix/.ro-store/n0ri1yrhjalps8j5yrqkyj3x987ly7s4-closure-info/registration console=ttyAMA0,115200n8 console=tty0153second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB154second # [ 0.000000] software IO TLB: area num 1.155test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/n0ri1yrhjalps8j5yrqkyj3x987ly7s4-closure-info/registration", will be passed to user space.156second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)157second # [ 0.000000] Fallback order for Node 0: 0158test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes159second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144160test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)161second # [ 0.000000] Policy zone: DMA162second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off163test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)164second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1165test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB166second # [ 0.000000] allocated 2097152 bytes of page_ext167test # [ 0.000000] software IO TLB: area num 1.168second # [ 0.000000] ftrace: allocating 75026 entries in 294 pages169test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)170second # [ 0.000000] ftrace: allocated 294 pages with 4 groups171test # [ 0.000000] Fallback order for Node 0: 0172second # [ 0.000000] rcu: Hierarchical RCU implementation.173second # [ 0.000000] rcu: RCU event tracing is enabled.174test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144175test # [ 0.000000] Policy zone: DMA176second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.177test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off178second # [ 0.000000] Trampoline variant of Tasks RCU enabled.179test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1180second # [ 0.000000] Rude variant of Tasks RCU enabled.181test # [ 0.000000] allocated 2097152 bytes of page_ext182second # [ 0.000000] Tracing variant of Tasks RCU enabled.183test # [ 0.000000] ftrace: allocating 75026 entries in 294 pages184second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.185test # [ 0.000000] ftrace: allocated 294 pages with 4 groups186second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1187test # [ 0.000000] rcu: Hierarchical RCU implementation.188test # [ 0.000000] rcu: RCU event tracing is enabled.189second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.190test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.191second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.192test # [ 0.000000] Trampoline variant of Tasks RCU enabled.193test # [ 0.000000] Rude variant of Tasks RCU enabled.194second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.195test # [ 0.000000] Tracing variant of Tasks RCU enabled.196second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0197test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.198second # [ 0.000000] GICv3: 256 SPIs implemented199second # [ 0.000000] GICv3: 0 Extended SPIs implemented200test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1201second # [ 0.000000] Root IRQ handler: gic_handle_irq202second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI203test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.204second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0205test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.206second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000207second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]208test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.209second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)210test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0211test # [ 0.000000] GICv3: 256 SPIs implemented212test # [ 0.000000] GICv3: 0 Extended SPIs implemented213second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)214test # [ 0.000000] Root IRQ handler: gic_handle_irq215second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000216test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI217test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0218second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000219test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000220second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.221test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]222second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns223test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)224second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040225second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).226test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)227test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000228second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns229test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000230second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns231test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.232second # [ 0.000040] arm-pv: using stolen time PV233test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns234second # [ 0.000308] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)235second # [ 0.000570] Console: colour dummy device 80x25236test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040237second # [ 0.000581] printk: legacy console [tty0] enabled238test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).239second # [ 0.000796] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)240test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns241second # [ 0.000802] pid_max: default: 32768 minimum: 301242second # [ 0.000877] LSM: initializing lsm=capability,landlock,yama,bpf,ima243test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns244second # [ 0.001000] landlock: Up and running.245test # [ 0.000039] arm-pv: using stolen time PV246second # [ 0.001002] Yama: becoming mindful.247second # [ 0.001345] LSM support for eBPF active248test # [ 0.000381] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)249second # [ 0.001488] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)250test # [ 0.000641] Console: colour dummy device 80x25251test # [ 0.000652] printk: legacy console [tty0] enabled252second # [ 0.001508] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)253second # [ 0.004218] rcu: Hierarchical SRCU implementation.254test # [ 0.000865] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)255second # [ 0.004224] rcu: Max phase no-delay instances is 1000.256test # [ 0.000870] pid_max: default: 32768 minimum: 301257second # [ 0.005175] fsl-mc MSI: its@8080000 domain created258second # [ 0.005366] EFI services will not be available.259test # [ 0.000944] LSM: initializing lsm=capability,landlock,yama,bpf,ima260test # [ 0.001112] landlock: Up and running.261second # [ 0.005459] smp: Bringing up secondary CPUs ...262test # [ 0.001115] Yama: becoming mindful.263second # [ 0.005503] smp: Brought up 1 node, 1 CPU264test # [ 0.001431] LSM support for eBPF active265second # [ 0.005508] SMP: Total of 1 processors activated.266second # [ 0.005510] CPU: All CPU(s) started at EL1267test # [ 0.001621] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)268second # [ 0.005520] CPU features: detected: 32-bit EL0 Support269test # [ 0.001640] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)270second # [ 0.005523] CPU features: detected: Data cache clean to the PoU not required for I/D coherence271test # [ 0.004151] rcu: Hierarchical SRCU implementation.272test # [ 0.004156] rcu: Max phase no-delay instances is 1000.273second # [ 0.005526] CPU features: detected: Common not Private translations274test # [ 0.005094] fsl-mc MSI: its@8080000 domain created275second # [ 0.005528] CPU features: detected: CRC32 instructions276test # [ 0.005273] EFI services will not be available.277second # [ 0.005530] CPU features: detected: Data cache clean to Point of Persistence278test # [ 0.005369] smp: Bringing up secondary CPUs ...279test # [ 0.005409] smp: Brought up 1 node, 1 CPU280second # [ 0.005532] CPU features: detected: Enhanced Virtualization Traps281test # [ 0.005414] SMP: Total of 1 processors activated.282second # [ 0.005541] CPU features: detected: RCpc load-acquire (LDAPR)283test # [ 0.005416] CPU: All CPU(s) started at EL1284second # [ 0.005544] CPU features: detected: LSE atomic instructions285test # [ 0.005426] CPU features: detected: 32-bit EL0 Support286second # [ 0.005546] CPU features: detected: Privileged Access Never287second # [ 0.005547] CPU features: detected: PMUv3288test # [ 0.005428] CPU features: detected: Data cache clean to the PoU not required for I/D coherence289second # [ 0.005549] CPU features: detected: RAS Extension Support290test # [ 0.005431] CPU features: detected: Common not Private translations291second # [ 0.005553] CPU features: detected: Speculative Store Bypassing Safe (SSBS)292test # [ 0.005433] CPU features: detected: CRC32 instructions293second # [ 0.005573] alternatives: applying system-wide alternatives294test # [ 0.005435] CPU features: detected: Data cache clean to Point of Persistence295second # [ 0.006952] CPU features: detected: Hardware dirty bit management on CPU0296test # [ 0.005437] CPU features: detected: Enhanced Virtualization Traps297test # [ 0.005447] CPU features: detected: RCpc load-acquire (LDAPR)298test # [ 0.005449] CPU features: detected: LSE atomic instructions299second # [ 0.007174] Memory: 891196K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116068K reserved, 32768K cma-reserved)300second # [ 0.007643] devtmpfs: initialized301test # [ 0.005451] CPU features: detected: Privileged Access Never302test # [ 0.005452] CPU features: detected: PMUv3303second # [ 0.009050] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)304test # [ 0.005454] CPU features: detected: RAS Extension Support305second # [ 0.009073] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).306test # [ 0.005458] CPU features: detected: Speculative Store Bypassing Safe (SSBS)307test # [ 0.005477] alternatives: applying system-wide alternatives308second # [ 0.009205] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL309second # [ 0.009208] 0 pages in range for non-PLT usage310test # [ 0.006933] CPU features: detected: Hardware dirty bit management on CPU0311second # [ 0.009208] 508208 pages in range for PLT usage312second # [ 0.009321] pinctrl core: initialized pinctrl subsystem313second # [ 0.010077] DMI not present or invalid.314test # [ 0.007130] Memory: 891232K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116040K reserved, 32768K cma-reserved)315test # [ 0.007610] devtmpfs: initialized316second # [ 0.013484] NET: Registered PF_NETLINK/PF_ROUTE protocol family317second # [ 0.016119] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations318test # [ 0.009077] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)319second # [ 0.016249] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations320test # [ 0.009103] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).321test # [ 0.009228] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL322second # [ 0.016396] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations323test # [ 0.009231] 0 pages in range for non-PLT usage324test # [ 0.009232] 508208 pages in range for PLT usage325second # [ 0.016411] audit: initializing netlink subsys (disabled)326test # [ 0.009349] pinctrl core: initialized pinctrl subsystem327test # [ 0.010112] DMI not present or invalid.328second # [ 0.016969] thermal_sys: Registered thermal governor 'fair_share'329test # [ 0.013481] NET: Registered PF_NETLINK/PF_ROUTE protocol family330second # [ 0.016971] thermal_sys: Registered thermal governor 'bang_bang'331test # [ 0.016076] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations332second # [ 0.016974] thermal_sys: Registered thermal governor 'step_wise'333second # [ 0.016976] thermal_sys: Registered thermal governor 'user_space'334test # [ 0.016227] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations335second # [ 0.016978] thermal_sys: Registered thermal governor 'power_allocator'336test # [ 0.016373] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations337second # [ 0.017021] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1338test # [ 0.016389] audit: initializing netlink subsys (disabled)339second # [ 0.017030] cpuidle: using governor ladder340test # [ 0.016941] thermal_sys: Registered thermal governor 'fair_share'341second # [ 0.017035] cpuidle: using governor menu342test # [ 0.016943] thermal_sys: Registered thermal governor 'bang_bang'343second # [ 0.017156] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.344test # [ 0.016946] thermal_sys: Registered thermal governor 'step_wise'345second # [ 0.017176] ASID allocator initialised with 32768 entries346test # [ 0.016948] thermal_sys: Registered thermal governor 'user_space'347second # [ 0.017801] Serial: AMBA PL011 UART driver348test # [ 0.016950] thermal_sys: Registered thermal governor 'power_allocator'349second # [ 0.021459] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1350second # [ 0.021620] printk: console [ttyAMA0] enabled351test # [ 0.016982] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1352test # [ 0.016991] cpuidle: using governor ladder353test # [ 0.016996] cpuidle: using governor menu354test # [ 0.017104] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.355test # [ 0.017123] ASID allocator initialised with 32768 entries356test # [ 0.017753] Serial: AMBA PL011 UART driver357test # [ 0.021103] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1358test # [ 0.021268] printk: console [ttyAMA0] enabled359second # [ 0.093955] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages360second # [ 0.093968] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page361second # [ 0.093971] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages362second # [ 0.093976] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page363second # [ 0.093978] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages364second # [ 0.093980] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page365second # [ 0.093982] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages366second # [ 0.093984] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page367second # [ 0.095016] fbcon: Taking over console368second # [ 0.095033] ACPI: Interpreter disabled.369test # [ 0.094761] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages370test # [ 0.094776] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page371second # [ 0.095681] iommu: Default domain type: Translated372second # [ 0.095685] iommu: DMA domain TLB invalidation policy: strict mode373test # [ 0.094778] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages374second # [ 0.097903] SCSI subsystem initialized375test # [ 0.094782] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page376test # [ 0.094785] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages377test # [ 0.094786] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page378test # [ 0.094788] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages379test # [ 0.094790] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page380test # [ 0.095814] fbcon: Taking over console381second # [ 0.103909] usbcore: registered new interface driver usbfs382test # [ 0.095830] ACPI: Interpreter disabled.383test # [ 0.096492] iommu: Default domain type: Translated384second # [ 0.103941] usbcore: registered new interface driver hub385second # [ 0.103958] usbcore: registered new device driver usb386test # [ 0.096496] iommu: DMA domain TLB invalidation policy: strict mode387test # [ 0.096785] SCSI subsystem initialized388second # [ 0.104176] pps_core: LinuxPPS API ver. 1 registered389test # [ 0.098591] usbcore: registered new interface driver usbfs390second # [ 0.104179] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>391test # [ 0.098621] usbcore: registered new interface driver hub392second # [ 0.104183] PTP clock support registered393test # [ 0.098637] usbcore: registered new device driver usb394second # [ 0.104217] EDAC MC: Ver: 3.0.0395test # [ 0.098848] pps_core: LinuxPPS API ver. 1 registered396second # [ 0.104381] scmi_core: SCMI protocol bus registered397second # [ 0.104667] FPGA manager framework398test # [ 0.098851] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>399test # [ 0.098856] PTP clock support registered400second # [ 0.109473] vgaarb: loaded401test # [ 0.098888] EDAC MC: Ver: 3.0.0402second # [ 0.109710] clocksource: Switched to clocksource arch_sys_counter403test # [ 0.107459] scmi_core: SCMI protocol bus registered404test # [ 0.107778] FPGA manager framework405test # [ 0.111136] vgaarb: loaded406test # [ 0.112201] clocksource: Switched to clocksource arch_sys_counter407second # [ 0.121965] VFS: Disk quotas dquot_6.6.0408second # [ 0.121990] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)409second # [ 0.123398] netfs: FS-Cache loaded410second # [ 0.123509] pnp: PnP ACPI: disabled411second # [ 0.125307] NET: Registered PF_INET protocol family412test # [ 0.122277] VFS: Disk quotas dquot_6.6.0413test # [ 0.122302] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)414second # [ 0.125477] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)415test # [ 0.123715] netfs: FS-Cache loaded416test # [ 0.123841] pnp: PnP ACPI: disabled417test # [ 0.127071] NET: Registered PF_INET protocol family418test # [ 0.127249] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)419second # [ 0.151184] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)420second # [ 0.151202] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)421second # [ 0.151213] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)422test # [ 0.151550] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)423second # [ 0.151247] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)424test # [ 0.151567] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)425second # [ 0.151313] TCP: Hash tables configured (established 8192 bind 8192)426test # [ 0.151577] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)427second # [ 0.151399] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)428test # [ 0.151613] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)429second # [ 0.151428] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)430test # [ 0.151679] TCP: Hash tables configured (established 8192 bind 8192)431second # [ 0.151450] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)432test # [ 0.151786] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)433second # [ 0.151568] NET: Registered PF_UNIX/PF_LOCAL protocol family434second # [ 0.151597] NET: Registered PF_XDP protocol family435test # [ 0.151817] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)436second # [ 0.151615] PCI: CLS 0 bytes, default 64437test # [ 0.151868] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)438second # [ 0.151906] Trying to unpack rootfs image as initramfs...439second # [ 0.153884] kvm [1]: HYP mode not available440test # [ 0.151982] NET: Registered PF_UNIX/PF_LOCAL protocol family441test # [ 0.152008] NET: Registered PF_XDP protocol family442test # [ 0.152027] PCI: CLS 0 bytes, default 64443test # [ 0.152283] Trying to unpack rootfs image as initramfs...444test # [ 0.166237] kvm [1]: HYP mode not available445second # [ 0.226047] Initialise system trusted keyrings446second # [ 0.226173] workingset: timestamp_bits=42 max_order=18 bucket_order=0447second # [ 0.226493] squashfs: version 4.0 (2009/01/31) Phillip Lougher448second # [ 0.226526] 9p: Installing v9fs 9p2000 file system support449test # [ 0.228622] Initialise system trusted keyrings450test # [ 0.228762] workingset: timestamp_bits=42 max_order=18 bucket_order=0451test # [ 0.229060] squashfs: version 4.0 (2009/01/31) Phillip Lougher452test # [ 0.229093] 9p: Installing v9fs 9p2000 file system support453second # [ 0.245321] Key type asymmetric registered454second # [ 0.245335] Asymmetric key parser 'x509' registered455second # [ 0.245387] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)456test # [ 0.249699] Key type asymmetric registered457test # [ 0.249713] Asymmetric key parser 'x509' registered458test # [ 0.249760] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)459test # [ 0.249854] io scheduler mq-deadline registered460test # [ 0.249858] io scheduler kyber registered461second # [ 0.253773] io scheduler mq-deadline registered462second # [ 0.253779] io scheduler kyber registered463second # [ 0.256140] pl061_gpio 9030000.pl061: PL061 GPIO chip registered464second # [ 0.256456] ledtrig-cpu: registered to indicate activity on CPUs465second # [ 0.256746] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:466second # [ 0.256763] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000467second # [ 0.256775] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000468second # [ 0.256780] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000469second # [ 0.256825] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits470second # [ 0.256845] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]471second # [ 0.256920] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00472second # [ 0.256936] pci_bus 0000:00: root bus resource [bus 00-ff]473second # [ 0.256940] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]474second # [ 0.256942] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]475second # [ 0.256944] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]476second # [ 0.257016] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint477second # [ 0.257469] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint478second # [ 0.257597] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]479test # [ 0.260306] pl061_gpio 9030000.pl061: PL061 GPIO chip registered480second # [ 0.257606] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]481test # [ 0.260635] ledtrig-cpu: registered to indicate activity on CPUs482second # [ 0.257625] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]483test # [ 0.260927] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:484second # [ 0.257634] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]485test # [ 0.260950] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000486test # [ 0.260963] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000487test # [ 0.260967] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000488test # [ 0.260995] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits489test # [ 0.261016] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]490test # [ 0.261083] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00491test # [ 0.261098] pci_bus 0000:00: root bus resource [bus 00-ff]492test # [ 0.261102] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]493test # [ 0.261104] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]494test # [ 0.261107] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]495test # [ 0.261178] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint496test # [ 0.261630] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint497test # [ 0.261757] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]498test # [ 0.261767] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]499test # [ 0.261786] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]500test # [ 0.261796] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]501test # [ 0.262112] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint502second # [ 0.274006] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint503test # [ 0.262236] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]504second # [ 0.274134] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]505test # [ 0.262245] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]506second # [ 0.274144] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]507second # [ 0.274162] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]508test # [ 0.262263] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]509second # [ 0.274481] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint510test # [ 0.262573] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint511second # [ 0.274606] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]512test # [ 0.262698] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]513second # [ 0.274615] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]514test # [ 0.262707] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]515test # [ 0.262725] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]516second # [ 0.274633] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]517test # [ 0.263035] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint518second # [ 0.275040] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint519test # [ 0.263160] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]520second # [ 0.275167] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]521test # [ 0.263169] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]522second # [ 0.275176] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]523second # [ 0.275195] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]524test # [ 0.263187] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]525second # [ 0.275203] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]526test # [ 0.263196] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]527second # [ 0.275516] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint528test # [ 0.263507] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint529second # [ 0.275643] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]530test # [ 0.263635] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]531second # [ 0.275661] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]532test # [ 0.263653] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]533test # [ 0.263987] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint534second # [ 0.275998] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint535test # [ 0.264114] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]536second # [ 0.276125] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]537test # [ 0.264133] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]538second # [ 0.276144] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]539second # [ 0.276412] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint540second # [ 0.276534] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]541second # [ 0.276715] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint542second # [ 0.276842] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]543second # [ 0.276860] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]544second # [ 0.277174] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint545second # [ 0.277303] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]546test # [ 0.284656] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint547second # [ 0.277321] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]548test # [ 0.284778] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]549second # [ 0.277628] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint550test # [ 0.284986] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint551test # [ 0.285111] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]552test # [ 0.285129] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]553second # [ 0.292576] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]554test # [ 0.285437] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint555second # [ 0.292596] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]556test # [ 0.285564] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]557second # [ 0.292931] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint558test # [ 0.285582] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]559second # [ 0.293090] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]560test # [ 0.285888] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint561second # [ 0.293100] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]562test # [ 0.286012] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]563second # [ 0.293118] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]564test # [ 0.286030] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]565second # [ 0.293479] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint566test # [ 0.286357] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint567second # [ 0.293605] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]568test # [ 0.286520] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]569second # [ 0.293614] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]570test # [ 0.286530] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]571second # [ 0.293632] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]572test # [ 0.286548] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]573test # [ 0.286964] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint574test # [ 0.287086] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]575test # [ 0.287095] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]576test # [ 0.287113] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]577test # [ 0.287540] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned578test # [ 0.287547] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned579test # [ 0.287550] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned580test # [ 0.287581] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned581test # [ 0.287611] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned582test # [ 0.287640] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned583test # [ 0.287672] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned584test # [ 0.287702] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned585test # [ 0.287731] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned586test # [ 0.287774] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned587test # [ 0.287804] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned588test # [ 0.287833] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned589test # [ 0.287915] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned590test # [ 0.287951] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned591test # [ 0.287963] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned592second # [ 0.306172] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned593test # [ 0.287975] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned594second # [ 0.306180] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned595test # [ 0.287987] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned596test # [ 0.287999] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned597second # [ 0.306183] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned598test # [ 0.288011] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned599second # [ 0.306216] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned600test # [ 0.288024] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned601second # [ 0.306246] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned602test # [ 0.288036] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned603second # [ 0.306277] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned604test # [ 0.288048] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned605second # [ 0.306308] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned606test # [ 0.288060] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned607test # [ 0.288072] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned608second # [ 0.306338] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned609test # [ 0.288084] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned610second # [ 0.306368] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned611test # [ 0.288095] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned612second # [ 0.306398] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned613test # [ 0.288107] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned614test # [ 0.288118] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned615second # [ 0.306429] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned616test # [ 0.288130] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned617second # [ 0.306459] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned618test # [ 0.288142] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned619second # [ 0.306516] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned620test # [ 0.288153] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned621test # [ 0.288169] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]622second # [ 0.306545] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned623test # [ 0.288173] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]624second # [ 0.306677] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned625test # [ 0.288181] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]626second # [ 0.306690] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned627second # [ 0.306703] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned628second # [ 0.306715] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned629second # [ 0.306727] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned630second # [ 0.306748] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned631second # [ 0.306762] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned632second # [ 0.306774] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned633second # [ 0.306785] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned634second # [ 0.306798] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned635second # [ 0.306810] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned636second # [ 0.306822] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned637second # [ 0.306833] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned638second # [ 0.306845] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned639second # [ 0.306857] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned640second # [ 0.306869] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned641second # [ 0.306881] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned642second # [ 0.306897] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]643second # [ 0.306901] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]644second # [ 0.306909] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]645second # [ 0.307446] pci 0000:00:07.0: enabling device (0000 -> 0002)646test # [ 0.324826] pci 0000:00:07.0: enabling device (0000 -> 0002)647second # [ 0.333005] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)648test # [ 0.331696] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)649second # [ 0.342604] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)650second # [ 0.344304] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)651test # [ 0.341337] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)652test # [ 0.343093] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)653second # [ 0.353850] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)654second # [ 0.357014] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)655test # [ 0.352683] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)656test # [ 0.355858] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)657second # [ 0.360096] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)658second # [ 0.361595] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)659test # [ 0.358564] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)660test # [ 0.360098] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)661second # [ 0.370944] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)662second # [ 0.372605] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)663test # [ 0.369423] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)664test # [ 0.371070] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)665second # [ 0.375408] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)666second # [ 0.377258] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)667test # [ 0.373822] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)668test # [ 0.375679] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)669second # [ 0.388419] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled670second # [ 0.389350] msm_serial: driver initialized671second # [ 0.389420] SuperH (H)SCI(F) driver initialized672second # [ 0.389461] STM32 USART driver initialized673test # [ 0.386867] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled674test # [ 0.387807] msm_serial: driver initialized675test # [ 0.387888] SuperH (H)SCI(F) driver initialized676test # [ 0.387928] STM32 USART driver initialized677second # [ 0.414852] loop: module loaded678second # [ 0.414985] virtio_blk virtio2: 1/0/0 default/read/poll queues679second # [ 0.415652] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)680test # [ 0.412653] loop: module loaded681test # [ 0.412774] virtio_blk virtio2: 1/0/0 default/read/poll queues682test # [ 0.413370] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)683second # [ 0.419427] megasas: 07.734.00.00-rc1684test # [ 0.417872] megasas: 07.734.00.00-rc1685second # [ 0.419890] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]686test # [ 0.418295] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]687test # [ 0.422354] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000688test # [ 0.422376] Intel/Sharp Extended Query Table at 0x0031689test # [ 0.424019] Using buffer write method690test # [ 0.424067] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]691second # [ 0.430609] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000692second # [ 0.430632] Intel/Sharp Extended Query Table at 0x0031693second # [ 0.432404] Using buffer write method694second # [ 0.432469] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]695second # [ 0.436869] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000696second # [ 0.436881] Intel/Sharp Extended Query Table at 0x0031697test # [ 0.434250] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000698test # [ 0.434289] Intel/Sharp Extended Query Table at 0x0031699test # [ 0.436003] Using buffer write method700test # [ 0.436015] Concatenating MTD devices:701test # [ 0.436017] (0): "0.flash"702test # [ 0.436019] (1): "0.flash"703test # [ 0.436020] into device "0.flash"704second # [ 0.447900] Using buffer write method705second # [ 0.447912] Concatenating MTD devices:706second # [ 0.447914] (0): "0.flash"707second # [ 0.447916] (1): "0.flash"708second # [ 0.447918] into device "0.flash"709second # [ 0.650901] Freeing initrd memory: 26948K710test # [ 0.650422] Freeing initrd memory: 26920K711second # [ 0.658267] tun: Universal TUN/TAP device driver, 1.6712second # [ 0.660518] thunder_xcv, ver 1.0713second # [ 0.660533] thunder_bgx, ver 1.0714second # [ 0.660541] nicpf, ver 1.0715second # [ 0.660865] e1000: Intel(R) PRO/1000 Network Driver716test # [ 0.657760] tun: Universal TUN/TAP device driver, 1.6717second # [ 0.660869] e1000: Copyright (c) 1999-2006 Intel Corporation.718test # [ 0.659963] thunder_xcv, ver 1.0719second # [ 0.660883] e1000e: Intel(R) PRO/1000 Network Driver720test # [ 0.659980] thunder_bgx, ver 1.0721test # [ 0.659989] nicpf, ver 1.0722second # [ 0.660887] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.723second # [ 0.660898] igb: Intel(R) Gigabit Ethernet Network Driver724second # [ 0.660900] igb: Copyright (c) 2007-2014 Intel Corporation.725second # [ 0.660909] igbvf: Intel(R) Gigabit Virtual Function Network Driver726second # [ 0.660914] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.727second # [ 0.660976] sky2: driver version 1.30728test # [ 0.661476] e1000: Intel(R) PRO/1000 Network Driver729test # [ 0.661482] e1000: Copyright (c) 1999-2006 Intel Corporation.730test # [ 0.661497] e1000e: Intel(R) PRO/1000 Network Driver731test # [ 0.661502] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.732test # [ 0.661513] igb: Intel(R) Gigabit Ethernet Network Driver733test # [ 0.661515] igb: Copyright (c) 2007-2014 Intel Corporation.734test # [ 0.661526] igbvf: Intel(R) Gigabit Virtual Function Network Driver735second # [ 0.667251] ehci-pci 0000:00:07.0: EHCI Host Controller736test # [ 0.661530] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.737second # [ 0.667284] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1738test # [ 0.661598] sky2: driver version 1.30739second # [ 0.667494] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000740test # [ 0.662409] usbcore: registered new interface driver usb-storage741second # [ 0.667683] usbcore: registered new interface driver usb-storage742test # [ 0.662450] usbcore: registered new interface driver usbserial_generic743second # [ 0.667794] usbcore: registered new interface driver usbserial_generic744test # [ 0.662468] usbserial: USB Serial support registered for generic745second # [ 0.667816] usbserial: USB Serial support registered for generic746test # [ 0.662758] hv_vmbus: registering driver hyperv_keyboard747second # [ 0.668123] hv_vmbus: registering driver hyperv_keyboard748test # [ 0.663721] ehci-pci 0000:00:07.0: EHCI Host Controller749second # [ 0.668630] rtc-pl031 9010000.pl031: registered as rtc0750test # [ 0.663765] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1751second # [ 0.668656] rtc-pl031 9010000.pl031: setting system clock to 2026-10-03T00:04:48 UTC (1790985888)752test # [ 0.664064] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000753second # [ 0.668835] i2c_dev: i2c /dev entries driver754second # [ 0.675223] sdhci: Secure Digital Host Controller Interface driver755test # [ 0.671230] rtc-pl031 9010000.pl031: registered as rtc0756second # [ 0.675228] sdhci: Copyright(c) Pierre Ossman757second # [ 0.675380] Synopsys Designware Multimedia Card Interface Driver758second # [ 0.675559] sdhci-pltfm: SDHCI platform and OF driver helper759test # [ 0.671261] rtc-pl031 9010000.pl031: setting system clock to 2026-10-03T00:04:48 UTC (1790985888)760second # [ 0.676415] hid: raw HID events driver (C) Jiri Kosina761test # [ 0.671463] i2c_dev: i2c /dev entries driver762second # [ 0.676560] usbcore: registered new interface driver usbhid763second # [ 0.676563] usbhid: USB HID core driver764test # [ 0.673032] sdhci: Secure Digital Host Controller Interface driver765test # [ 0.673037] sdhci: Copyright(c) Pierre Ossman766test # [ 0.673185] Synopsys Designware Multimedia Card Interface Driver767test # [ 0.673358] sdhci-pltfm: SDHCI platform and OF driver helper768test # [ 0.674185] hid: raw HID events driver (C) Jiri Kosina769test # [ 0.674318] usbcore: registered new interface driver usbhid770test # [ 0.674320] usbhid: USB HID core driver771test # [ 0.715877] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00772second # [ 0.721768] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00773second # [ 0.722121] hub 1-0:1.0: USB hub found774second # [ 0.722144] hub 1-0:1.0: 6 ports detected775second # [ 0.722428] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available776second # [ 0.723283] drop_monitor: Initializing network drop monitor service777test # [ 0.722287] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available778second # [ 0.723388] NET: Registered PF_INET6 protocol family779second # [ 0.724008] Segment Routing with IPv6780test # [ 0.723085] drop_monitor: Initializing network drop monitor service781second # [ 0.724025] In-situ OAM (IOAM) with IPv6782test # [ 0.723193] NET: Registered PF_INET6 protocol family783second # [ 0.724069] NET: Registered PF_PACKET protocol family784test # [ 0.723864] hub 1-0:1.0: USB hub found785second # [ 0.724125] 9pnet: Installing 9P2000 support786test # [ 0.723889] hub 1-0:1.0: 6 ports detected787test # [ 0.724396] Segment Routing with IPv6788second # [ 0.724156] Key type dns_resolver registered789test # [ 0.724413] In-situ OAM (IOAM) with IPv6790test # [ 0.724479] NET: Registered PF_PACKET protocol family791test # [ 0.727626] 9pnet: Installing 9P2000 support792test # [ 0.727663] Key type dns_resolver registered793second # [ 0.731196] registered taskstats version 1794second # [ 0.731349] Loading compiled-in X.509 certificates795test # [ 0.731091] registered taskstats version 1796test # [ 0.731238] Loading compiled-in X.509 certificates797second # [ 0.736608] Demotion targets for Node 0: null798second # [ 0.736673] Key type .fscrypt registered799second # [ 0.736679] Key type fscrypt-provisioning registered800second # [ 0.736779] ima: No TPM chip found, activating TPM-bypass!801second # [ 0.736789] ima: Allocated hash algorithm: sha1802second # [ 0.736843] ima: No architecture policies found803second # [ 0.737280] input: gpio-keys as /devices/platform/gpio-keys/input/input0804test # [ 0.736732] Demotion targets for Node 0: null805test # [ 0.736807] Key type .fscrypt registered806test # [ 0.736813] Key type fscrypt-provisioning registered807test # [ 0.736911] ima: No TPM chip found, activating TPM-bypass!808test # [ 0.736921] ima: Allocated hash algorithm: sha1809test # [ 0.736976] ima: No architecture policies found810test # [ 0.737419] input: gpio-keys as /devices/platform/gpio-keys/input/input0811second # [ 0.749540] clk: Disabling unused clocks812second # [ 0.749548] PM: genpd: Disabling unused power domains813second # [ 0.752483] Freeing unused kernel memory: 4736K814second # [ 0.752556] Run /init as init process815test # [ 0.749522] clk: Disabling unused clocks816test # [ 0.749530] PM: genpd: Disabling unused power domains817test # [ 0.751763] Freeing unused kernel memory: 4736K818test # [ 0.751842] Run /init as init process819second # [ 0.772285] systemd[1]: Successfully made /usr/ read-only.820test # [ 0.774658] systemd[1]: Successfully made /usr/ read-only.821second # [ 0.969773] usb 1-1: new high-speed USB device number 2 using ehci-pci822test # [ 0.972255] usb 1-1: new high-speed USB device number 2 using ehci-pci823second # [ 1.106386] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)824second # [ 1.106416] systemd[1]: Detected virtualization qemu.825second # [ 1.106455] systemd[1]: Detected architecture arm64.826second # [ 1.106464] systemd[1]: Running in initrd.827second # [ 1.107291] systemd[1]: Initializing machine ID from random generator.828test # [ 1.108785] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)829second # [ 1.107562] systemd[1]: Hostname set to <second>.830test # [ 1.108815] systemd[1]: Detected virtualization qemu.831test # [ 1.108854] systemd[1]: Detected architecture arm64.832test # [ 1.108863] systemd[1]: Running in initrd.833test # [ 1.109633] systemd[1]: Initializing machine ID from random generator.834test # [ 1.109897] systemd[1]: Hostname set to <test>.835second # [ 1.127477] 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/input1836test # [ 1.138985] 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/input1837second # [ 1.217860] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0838test # [ 1.224355] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0839second # [ 1.241192] systemd[1]: bpf-restrict-fs: LSM BPF program attached840test # [ 1.247885] systemd[1]: bpf-restrict-fs: LSM BPF program attached841second # [ 1.290041] systemd[1]: Queued start job for default target Initrd Default Target.842second # [ 1.299190] systemd[1]: Created slice Slice /system/modprobe.843second # [ 1.299317] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.844second # [ 1.299347] systemd[1]: Expecting device /dev/disk/by-label/nixos...845second # [ 1.299367] systemd[1]: Reached target Path Units.846second # [ 1.299378] systemd[1]: Reached target Slice Units.847second # [ 1.299388] systemd[1]: Reached target Swaps.848second # [ 1.299398] systemd[1]: Reached target Timer Units.849second # [ 1.299520] systemd[1]: Listening on D-Bus System Message Bus Socket.850second # [ 1.299623] systemd[1]: Listening on Journal Socket (/dev/log).851second # [ 1.299704] systemd[1]: Listening on Journal Sockets.852test # [ 1.300731] systemd[1]: Queued start job for default target Initrd Default Target.853second # [ 1.299764] systemd[1]: Listening on udev Control Socket.854second # [ 1.299835] systemd[1]: Listening on udev Kernel Socket.855second # [ 1.299853] systemd[1]: Reached target Socket Units.856second # [ 1.300775] systemd[1]: Starting Create List of Static Device Nodes...857second # [ 1.300822] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs858second # [ 1.312406] systemd[1]: Mounting Kernel Configuration File System...859test # [ 1.309506] systemd[1]: Created slice Slice /system/modprobe.860test # [ 1.309637] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.861test # [ 1.309665] systemd[1]: Expecting device /dev/disk/by-label/nixos...862test # [ 1.309684] systemd[1]: Reached target Path Units.863test # [ 1.309695] systemd[1]: Reached target Slice Units.864test # [ 1.309705] systemd[1]: Reached target Swaps.865test # [ 1.309715] systemd[1]: Reached target Timer Units.866test # [ 1.309837] systemd[1]: Listening on D-Bus System Message Bus Socket.867test # [ 1.309938] systemd[1]: Listening on Journal Socket (/dev/log).868test # [ 1.310041] systemd[1]: Listening on Journal Sockets.869test # [ 1.310104] systemd[1]: Listening on udev Control Socket.870test # [ 1.310142] systemd[1]: Listening on udev Kernel Socket.871test # [ 1.310155] systemd[1]: Reached target Socket Units.872test # [ 1.311104] systemd[1]: Starting Create List of Static Device Nodes...873test # [ 1.311151] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs874second # [ 1.321805] systemd[1]: Starting Journal Service...875test # [ 1.323229] systemd[1]: Mounting Kernel Configuration File System...876test # [ 1.328438] systemd[1]: Starting Journal Service...877second # [ 1.337726] usb 1-2: new high-speed USB device number 3 using ehci-pci878second # [ 1.338013] systemd[1]: Starting Load Kernel Modules...879second # [ 1.338072] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os880test # [ 1.339003] systemd[1]: Starting Load Kernel Modules...881test # [ 1.339063] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os882second # [ 1.348226] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.883test # [ 1.348843] systemd[1]: Starting Coldplug All udev Devices...884second # [ 1.355459] systemd[1]: Starting Coldplug All udev Devices...885test # [ 1.356282] usb 1-2: new high-speed USB device number 3 using ehci-pci886second # [ 1.365517] systemd-journald[71]: Collecting audit messages is disabled.887test # [ 1.360909] systemd[1]: Finished Create List of Static Device Nodes.888test # [ 1.361731] systemd[1]: Mounted Kernel Configuration File System.889second # [ 1.366560] systemd[1]: Finished Create List of Static Device Nodes.890second # [ 1.367357] systemd[1]: Mounted Kernel Configuration File System.891second # [ 1.367583] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev892test # [ 1.374880] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.893test # [ 1.375621] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...894second # [ 1.374982] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0895second # [ 1.375189] [drm] features: -virgl +edid -resource_blob -host_visible896second # [ 1.375190] [drm] features: -context_init897second # [ 1.375761] [drm] number of scanouts: 1898second # [ 1.375769] [drm] number of cap sets: 0899second # [ 1.384286] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...900test # [ 1.382916] systemd-journald[71]: Collecting audit messages is disabled.901test # [ 1.384855] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev902second # [ 1.397999] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic903second # [ 1.398008] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0904test # [ 1.397199] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.905test # [ 1.401181] systemd[1]: Starting Create Static Device Nodes in /dev...906second # [ 1.415005] Console: switching to colour frame buffer device 160x50907test # [ 1.418617] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0908test # [ 1.418821] [drm] features: -virgl +edid -resource_blob -host_visible909test # [ 1.418823] [drm] features: -context_init910test # [ 1.419262] [drm] number of scanouts: 1911test # [ 1.419271] [drm] number of cap sets: 0912test # [ 1.425868] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic913test # [ 1.425876] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0914second # [ 1.430107] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.915second # [ 1.431096] systemd[1]: Starting Create Static Device Nodes in /dev...916test # [ 1.432604] systemd[1]: Finished Create Static Device Nodes in /dev.917test # [ 1.432728] systemd[1]: Reached target Preparation for Local File Systems.918test # [ 1.432747] systemd[1]: Reached target Local File Systems.919test # [ 1.433785] systemd[1]: Starting Rule-based Manager for Device Events and Files...920second # [ 1.442317] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device921test # [ 1.450084] Console: switching to colour frame buffer device 160x50922second # [ 1.454711] systemd[1]: Finished Load Kernel Modules.923second # [ 1.455672] systemd[1]: Starting Apply Kernel Variables...924second # [ 1.450158] systemd-modules-load[72]: Inserted module 'dm_mod'925second # [ 1.451049] systemd-modules-load[72]: Module 'virtio_balloon' is built in926second # [ 1.451895] systemd-modules-load[72]: Module 'virtio_console' is built in927second # [ 1.462488] systemd[1]: Started Journal Service.928second # [ 1.456692] systemd-modules-load[72]: Inserted module 'virtio_gpu'929second # [ 1.457394] systemd-modules-load[72]: Module 'virtio_rng' is built in930test # [ 1.464757] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device931test # [ 1.468803] systemd-modules-load[72]: Inserted module 'dm_mod'932test # [ 1.470683] systemd-modules-load[72]: Module 'virtio_balloon' is built in933second # [ 1.472650] systemd[1]: Finished Create Static Device Nodes in /dev.934test # [ 1.471443] systemd-modules-load[72]: Module 'virtio_console' is built in935test # [ 1.477794] systemd[1]: Started Journal Service.936second # [ 1.475651] systemd[1]: Reached target Preparation for Local File Systems.937test # [ 1.476283] systemd-modules-load[72]: Inserted module 'virtio_gpu'938test # [ 1.476952] systemd-modules-load[72]: Module 'virtio_rng' is built in939second # [ 1.478453] systemd[1]: Reached target Local File Systems.940test # [ 1.477621] systemd[1]: Finished Load Kernel Modules.941test # [ 1.478272] systemd[1]: Starting Apply Kernel Variables...942second # [ 1.491735] 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/input2943second # [ 1.491903] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0944second # [ 1.486193] systemd[1]: Starting Create System Files and Directories...945second # [ 1.496253] systemd[1]: Starting Rule-based Manager for Device Events and Files...946test # [ 1.496113] systemd[1]: Starting Create System Files and Directories...947second # [ 1.508465] systemd[1]: Finished Apply Kernel Variables.948test # [ 1.512656] 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/input2949test # [ 1.517566] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0950test # [ 1.516337] systemd-udevd[79]: Using default interface naming scheme 'v261'.951second # [ 1.520588] systemd[1]: Finished Create System Files and Directories.952test # [ 1.528643] systemd[1]: Finished Apply Kernel Variables.953second # [ 1.537603] systemd-udevd[87]: Using default interface naming scheme 'v261'.954test # [ 1.536665] systemd[1]: Finished Create System Files and Directories.955test # [ 1.544958] systemd[1]: Started Rule-based Manager for Device Events and Files.956second # [ 1.556906] systemd[1]: Started Rule-based Manager for Device Events and Files.957second # [ 1.600152] systemd[1]: Starting Virtual Console Setup...958test # [ 1.602403] systemd[1]: Starting Virtual Console Setup...959test # [ 1.640330] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.960test # [ 1.642482] systemd[1]: Finished Virtual Console Setup.961second # [ 1.644395] systemd-vconsole-setup[102]: Configuration of first virtual console was skipped, ignoring remaining ones.962second # [ 1.646690] systemd[1]: Finished Virtual Console Setup.963second # [ 1.857161] systemd[1]: Finished Coldplug All udev Devices.964second # [ 1.857775] systemd[1]: Reached target System Initialization.965second # [ 1.858308] systemd[1]: Reached target Basic System.966test # [ 1.868509] systemd[1]: Finished Coldplug All udev Devices.967test # [ 1.869095] systemd[1]: Reached target System Initialization.968test # [ 1.869618] systemd[1]: Reached target Basic System.969second # [ 1.958709] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.970test # [ 1.964263] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.971test # [ 1.981966] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.972second # [ 1.984303] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.973test # [ 2.036408] systemd[1]: Found device /dev/disk/by-label/nixos.974second # [ 2.044092] systemd[1]: Found device /dev/disk/by-label/nixos.975test # [ 2.044363] systemd[1]: Reached target Initrd Root Device.976second # [ 2.046963] systemd[1]: Reached target Initrd Root Device.977second # [ 2.048154] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...978test # [ 2.048181] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...979second # [ 2.080736] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks980second # [ 2.094777] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.981test # [ 2.093571] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks982test # [ 2.100189] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.983test # [ 2.342612] systemd[1]: Mounting /sysroot...984second # [ 2.347410] systemd[1]: Mounting /sysroot...985test # [ 2.375541] EXT4-fs (vda): mounted filesystem 80dda120-7136-485e-9bf4-6225ad27eebb r/w with ordered data mode. Quota mode: none.986test # [ 2.371010] systemd[1]: Mounted /sysroot.987second # [ 2.381213] EXT4-fs (vda): mounted filesystem 4bb1824f-be15-43fc-948c-bdbeaa112076 r/w with ordered data mode. Quota mode: none.988test # [ 2.372469] systemd[1]: Reached target Initrd Root File System.989second # [ 2.374286] systemd[1]: Mounted /sysroot.990second # [ 2.375164] systemd[1]: Reached target Initrd Root File System.991test # [ 2.377253] systemd[1]: Mounting /sysroot/nix/.ro-store...992second # [ 2.379487] systemd[1]: Mounting /sysroot/nix/.ro-store...993test # [ 2.390533] systemd[1]: Mounting /sysroot/nix/.rw-store...994second # [ 2.392524] systemd[1]: Mounting /sysroot/nix/.rw-store...995second # [ 2.400069] systemd[1]: Mounting /sysroot/run...996test # [ 2.402070] systemd[1]: Mounting /sysroot/run...997second # [ 2.413517] systemd[1]: Mounting /sysroot/tmp/shared...998test # [ 2.412803] systemd[1]: Mounting /sysroot/tmp/shared...999test # [ 2.415218] systemd[1]: Mounting /sysroot/tmp/xchg...1000second # [ 2.426379] systemd[1]: Mounting /sysroot/tmp/xchg...1001test # [ 2.457995] fuse: init (API version 7.45)1002test # [ 2.452488] systemd[1]: Starting Mountpoints Configured in the Real Root...1003second # [ 2.457362] systemd[1]: Starting Mountpoints Configured in the Real Root...1004second # [ 2.464250] fuse: init (API version 7.45)1005test # [ 2.459849] systemd[1]: Mounted /sysroot/nix/.rw-store.[ 2.466596] virtiofs virtio6: discovered new tag: nix-store1006test # [ 2.467086] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1007test # 1008second # [ 2.464629] systemd[1]: Mounted /sysroot/nix/.rw-store.1009second # [ 2.477609] virtiofs virtio6: discovered new tag: nix-store1010second # [ 2.479980] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload.1011test # [ 2.481696] virtiofs virtio7: discovered new tag: shared1012test # [ 2.482201] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1013second # [ 2.491067] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1014test # [ 2.491299] virtiofs virtio8: discovered new tag: xchg1015second # [ 2.486455] systemd[1]: Mounted /sysroot/run.1016test # [ 2.491792] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1017test # [ 2.488558] systemd-sysroot-fstab-check[123]: /sysroot should be mounted in the initrd, will request daemon-reload.1018test # [ 2.494147] systemd[1]: Starting rw-sysroot-nix-store.service...1019second # [ 2.507195] virtiofs virtio7: discovered new tag: shared1020second # [ 2.507699] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1021second # [ 2.509493] virtiofs virtio8: discovered new tag: xchg1022second # [ 2.504100] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)...1023second # [ 2.505104] systemd[1]: Reloading...1024second # [ 2.516944] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1025test # [ 2.499677] systemd[1]: Mounted /sysroot/run.1026test # [ 2.511872] systemd[1]: Mounted /sysroot/nix/.ro-store.1027test # [ 2.513502] systemd[1]: Mounted /sysroot/tmp/shared.1028test # [ 2.514976] systemd[1]: Mounted /sysroot/tmp/xchg.1029test # [ 2.521914] systemd[1]: Reload requested from client PID 123 ('systemd-sysroot') (unit initrd-parse-etc.service)...1030test # [ 2.525465] systemd[1]: Reloading...1031second # [ 2.679757] systemd[1]: Reloading finished in 176 ms.1032test # [ 2.694422] systemd[1]: Reloading finished in 170 ms.1033second # [ 2.708575] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace...1034second # [ 2.710801] systemd[1]: Mounted /sysroot/nix/.ro-store.1035second # [ 2.712553] systemd[1]: Mounted /sysroot/tmp/shared.1036second # [ 2.714593] systemd[1]: Mounted /sysroot/tmp/xchg.1037second # [ 2.718667] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace...1038second # [ 2.720866] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1039second # [ 2.724127] systemd[1]: Finished Mountpoints Configured in the Real Root.1040test # [ 2.722749] systemd-sysroot-fstab-check[123]: Requesting initrd-fs.target/start/replace...1041second # [ 2.726067] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1042test # [ 2.725002] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1043test # [ 2.728141] systemd[1]: Finished rw-sysroot-nix-store.service.1044test # [ 2.730159] systemd-sysroot-fstab-check[123]: Requesting swap.target/start/replace...1045second # [ 2.734389] systemd[1]: Starting rw-sysroot-nix-store.service...1046test # [ 2.738758] systemd[1]: Starting rw-sysroot-nix-store.service...1047test # [ 2.743453] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1048second # [ 2.746164] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1049second # [ 2.747037] systemd[1]: Finished rw-sysroot-nix-store.service.1050test # [ 2.749301] systemd[1]: Finished Mountpoints Configured in the Real Root.1051test # [ 2.749923] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1052test # [ 2.752945] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1053test # [ 2.753923] systemd[1]: Finished rw-sysroot-nix-store.service.1054second # [ 2.869143] (udev-worker)[92]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1055second # [ 2.873666] (udev-worker)[92]: 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 directory1056second # [ 2.892631] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1057second # [ 2.894618] systemd[1]: Stopped Virtual Console Setup.1058second # [ 2.895661] systemd[1]: Stopping Virtual Console Setup...1059second # [ 2.897936] systemd[1]: Starting Virtual Console Setup...1060second # [ 2.908442] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1061second # [ 2.909960] systemd[1]: Stopped Virtual Console Setup.1062second # [ 2.910673] systemd[1]: Starting Virtual Console Setup...1063test # [ 2.909285] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1064test # [ 2.913263] (udev-worker)[94]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1065second # [ 2.924271] systemd-vconsole-setup[159]: Configuration of first virtual console was skipped, ignoring remaining ones.1066second # [ 2.926070] systemd[1]: Finished Virtual Console Setup.1067test # [ 2.932418] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1068test # [ 2.934345] systemd[1]: Stopped Virtual Console Setup.1069test # [ 2.936112] systemd[1]: Stopping Virtual Console Setup...1070test # [ 2.937445] systemd[1]: Starting Virtual Console Setup...1071test # [ 2.952494] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1072test # [ 2.953435] systemd[1]: Stopped Virtual Console Setup.1073test # [ 2.954781] systemd[1]: Starting Virtual Console Setup...1074test # [ 2.968503] systemd-vconsole-setup[156]: Configuration of first virtual console was skipped, ignoring remaining ones.1075test # [ 2.970406] systemd[1]: Finished Virtual Console Setup.1076test # [ 3.344608] systemd[1]: Mounting /sysroot/nix/store...1077second # [ 3.349858] systemd[1]: Mounting /sysroot/nix/store...1078test # [ 3.394979] systemd[1]: Mounted /sysroot/nix/store.1079second # [ 3.396660] systemd[1]: Mounted /sysroot/nix/store.1080second # [ 3.398984] systemd[1]: Reached target Initrd File Systems.1081test # [ 3.398225] systemd[1]: Reached target Initrd File Systems.1082test # [ 3.401287] systemd[1]: Starting Find NixOS closure...1083second # [ 3.402753] systemd[1]: Starting Find NixOS closure...1084second # [ 3.409751] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1085test # [ 3.408314] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1086second # [ 3.444132] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1087second # [ 3.446700] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1088test # [ 3.448775] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1089test # [ 3.451792] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1090second # [ 3.459522] systemd[1]: Finished Find NixOS closure.1091second # [ 3.462160] systemd[1]: Reached target Initrd Default Target.1092second # [ 3.464468] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1093test # [ 3.463996] systemd[1]: Finished Find NixOS closure.1094test # [ 3.465459] systemd[1]: Reached target Initrd Default Target.1095test # [ 3.467849] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1096second # [ 3.496959] systemd[1]: initrd-cleanup.service: Deactivated successfully.1097second # [ 3.498514] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1098test # [ 3.499164] systemd[1]: Stopped target Initrd Default Target.1099test # [ 3.500858] systemd[1]: Stopped target Basic System.1100test # [ 3.502189] systemd[1]: Stopped target Initrd Root Device.1101second # [ 3.503703] systemd[1]: Stopped target Initrd Default Target.1102second # [ 3.504731] systemd[1]: Stopped target Basic System.1103second # [ 3.505583] systemd[1]: Stopped target Initrd Root Device.1104test # [ 3.504422] systemd[1]: Stopped target Path Units.1105second # [ 3.506347] systemd[1]: Stopped target Path Units.1106second # [ 3.507019] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1107test # [ 3.508311] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1108second # [ 3.510685] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1109test # [ 3.509446] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1110test # [ 3.510970] systemd[1]: Stopped target Slice Units.1111second # [ 3.516765] systemd[1]: Stopped target Slice Units.1112test # [ 3.516114] systemd[1]: Stopped target Socket Units.1113test # [ 3.516820] systemd[1]: Stopped target System Initialization.1114second # [ 3.518543] systemd[1]: Stopped target Socket Units.1115test # [ 3.517591] systemd[1]: Stopped target Swaps.1116second # [ 3.519200] systemd[1]: Stopped target System Initialization.1117test # [ 3.518208] systemd[1]: Stopped target Timer Units.1118second # [ 3.519897] systemd[1]: Stopped target Swaps.1119test # [ 3.518891] systemd[1]: dbus.socket: Deactivated successfully.1120test # [ 3.520560] systemd[1]: Closed D-Bus System Message Bus Socket.1121second # [ 3.523575] systemd[1]: Stopped target Timer Units.1122test # [ 3.522341] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1123test # [ 3.524554] systemd[1]: Stopped Find NixOS closure.1124test # [ 3.526410] systemd[1]: Starting rw-sysroot-nix-store.service...1125second # [ 3.528336] systemd[1]: dbus.socket: Deactivated successfully.1126second # [ 3.529074] systemd[1]: Closed D-Bus System Message Bus Socket.1127test # [ 3.528834] systemd[1]: systemd-sysctl.service: Deactivated successfully.1128second # [ 3.530978] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1129second # [ 3.533683] systemd[1]: Stopped Find NixOS closure.1130second # [ 3.534479] systemd[1]: Starting rw-sysroot-nix-store.service...1131test # [ 3.533448] systemd[1]: Stopped Apply Kernel Variables.1132test # [ 3.534126] systemd[1]: systemd-modules-load.service: Deactivated successfully.1133second # [ 3.535816] systemd[1]: systemd-sysctl.service: Deactivated successfully.1134test # [ 3.536976] systemd[1]: Stopped Load Kernel Modules.1135second # [ 3.540096] systemd[1]: Stopped Apply Kernel Variables.1136test # [ 3.539320] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1137test # [ 3.542088] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1138second # [ 3.544220] systemd[1]: systemd-modules-load.service: Deactivated successfully.1139test # [ 3.543263] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1140second # [ 3.544957] systemd[1]: Stopped Load Kernel Modules.1141second # [ 3.545565] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1142second # [ 3.546304] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1143second # [ 3.547040] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1144second # [ 3.547736] systemd[1]: Stopped Create System Files and Directories.1145second # [ 3.548874] systemd[1]: Stopped target Local File Systems.1146second # [ 3.549940] systemd[1]: Stopped target Preparation for Local File Systems.1147second # [ 3.550676] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1148second # [ 3.551287] systemd[1]: Stopped Coldplug All udev Devices.1149test # [ 3.551422] systemd[1]: Stopped Create System Files and Directories.1150second # [ 3.551769] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1151second # [ 3.552505] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1152second # [ 3.553434] systemd[1]: Stopped Virtual Console Setup.1153second # [ 3.553893] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1154second # [ 3.554503] systemd[1]: Finished rw-sysroot-nix-store.service.1155test # [ 3.553247] systemd[1]: Stopped target Local File Systems.1156second # [ 3.555015] systemd[1]: systemd-udevd.service: Deactivated successfully.1157test # [ 3.553902] systemd[1]: Stopped target Preparation for Local File Systems.1158second # [ 3.555596] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1159test # [ 3.556216] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1160test # [ 3.556916] systemd[1]: Stopped Coldplug All udev Devices.1161second # [ 3.559004] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1162second # [ 3.559651] systemd[1]: Closed udev Control Socket.1163test # [ 3.560270] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1164test # [ 3.561017] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1165test # [ 3.561683] systemd[1]: Stopped Virtual Console Setup.1166second # [ 3.564237] systemd[1]: Starting Cleanup udev Database...1167test # [ 3.562464] systemd[1]: initrd-cleanup.service: Deactivated successfully.1168second # [ 3.564765] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1169second # [ 3.565444] systemd[1]: Stopped Create Static Device Nodes in /dev.1170second # [ 3.566012] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1171second # [ 3.566718] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1172second # [ 3.567355] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1173second # [ 3.567970] systemd[1]: Stopped Create List of Static Device Nodes.1174test # [ 3.563051] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1175test # [ 3.563713] systemd[1]: systemd-udevd.service: Deactivated successfully.1176test # [ 3.564431] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1177test # [ 3.565028] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1178test # [ 3.565619] systemd[1]: Closed udev Control Socket.1179test # [ 3.566052] systemd[1]: Starting Cleanup udev Database...1180test # [ 3.566515] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1181test # [ 3.567146] systemd[1]: Stopped Create Static Device Nodes in /dev.1182test # [ 3.567863] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1183test # [ 3.576231] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1184test # [ 3.576887] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1185second # [ 3.578882] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1186test # [ 3.577672] systemd[1]: Stopped Create List of Static Device Nodes.1187second # [ 3.579600] systemd[1]: Finished Cleanup udev Database.1188test # [ 3.578251] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1189test # [ 3.578863] systemd[1]: Finished rw-sysroot-nix-store.service.1190second # [ 3.580706] systemd[1]: Reached target Switch Root.1191second # [ 3.584692] systemd[1]: Starting NixOS Activation...1192test # [ 3.588188] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1193test # [ 3.588895] systemd[1]: Finished Cleanup udev Database.1194test # [ 3.589356] systemd[1]: Reached target Switch Root.1195test # [ 3.592705] systemd[1]: Starting NixOS Activation...1196second # [ 3.647206] initrd-nixos-activation-start[182]: booting system configuration /nix/store/zc805yy98n4wgcl6blvgfrfzi3hamvv9-nixos-system-second-test1197test # [ 3.665296] initrd-nixos-activation-start[180]: booting system configuration /nix/store/nhzs5nq18r2wcww7qxwy0v9wknbxljdj-nixos-system-test-test1198second # [ 3.671245] initrd-nixos-activation-start[182]: running activation script...1199test # [ 3.692759] initrd-nixos-activation-start[180]: running activation script...1200second # [ 3.889611] initrd-nixos-activation-start[205]: setting up /etc...1201test # [ 3.900653] initrd-nixos-activation-start[203]: setting up /etc...1202second # [ 3.982674] initrd-nixos-activation-start[205]: setting up age secrets...1203test # [ 3.994964] initrd-nixos-activation-start[203]: setting up age secrets...1204second # [ 4.068428] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1205second # [ 4.070202] systemd[1]: Finished NixOS Activation.1206second # [ 4.070932] systemd[1]: Starting Switch Root...1207second # [ 4.088165] systemd[1]: Switching root.1208test # [ 4.112692] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1209test # [ 4.114424] systemd[1]: Finished NixOS Activation.1210test # [ 4.115141] systemd[1]: Starting Switch Root...1211test # [ 4.131905] systemd[1]: Switching root.1212second # [ 4.270059] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1213test # [ 4.300512] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1214second # [ 4.784022] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1215second # [ 4.784118] systemd[1]: Detected virtualization qemu.1216second # [ 4.784178] systemd[1]: Detected architecture arm64.1217second # [ 4.784317] systemd[1]: Detected first boot.1218second # [ 4.797292] systemd[1]: Initializing machine ID from random generator.1219test # [ 4.820469] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1220test # [ 4.820565] systemd[1]: Detected virtualization qemu.1221test # [ 4.820624] systemd[1]: Detected architecture arm64.1222test # [ 4.820751] systemd[1]: Detected first boot.1223test # [ 4.823533] systemd[1]: Initializing machine ID from random generator.1224second # [ 4.926141] systemd[1]: bpf-restrict-fs: LSM BPF program attached1225test # [ 4.966105] systemd[1]: bpf-restrict-fs: LSM BPF program attached1226second # [ 5.009586] systemd[1]: Applying preset policy.1227test # [ 5.049888] systemd[1]: Applying preset policy.1228second # [ 5.197090] systemd[1]: Populated /etc with preset unit settings.1229test # [ 5.241229] systemd[1]: Populated /etc with preset unit settings.1230second # [ 5.420569] systemd[1]: initrd-switch-root.service: Deactivated successfully.1231second # [ 5.421222] systemd[1]: Stopped initrd-switch-root.service.1232second # [ 5.424628] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1233second # [ 5.426950] systemd[1]: Created slice Slice /system/getty.1234second # [ 5.428009] systemd[1]: Created slice User and Session Slice.1235second # [ 5.428609] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1236second # [ 5.429228] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1237second # [ 5.432532] systemd[1]: Expecting device /dev/hvc0...1238second # [ 5.433013] systemd[1]: Expecting device /dev/ttyAMA0...1239second # [ 5.433553] systemd[1]: Reached target Local Encrypted Volumes.1240second # [ 5.435967] systemd[1]: Stopped target initrd-fs.target.1241second # [ 5.436445] systemd[1]: Stopped target initrd-root-fs.target.1242second # [ 5.436979] systemd[1]: Stopped target initrd-switch-root.target.1243second # [ 5.437537] systemd[1]: Reached target Virtual Machines and Containers.1244second # [ 5.440535] systemd[1]: Reached target Path Units.1245second # [ 5.441034] systemd[1]: Reached target Remote File Systems.1246second # [ 5.441581] systemd[1]: Reached target Slice Units.1247second # [ 5.443877] systemd[1]: Reached target Swaps.1248second # [ 5.446179] systemd[1]: Listening on Query the User Interactively for a Password.1249second # [ 5.448225] systemd[1]: Listening on Process Core Dump Socket.1250second # [ 5.450859] systemd[1]: Listening on Credential Encryption/Decryption.1251second # [ 5.452607] systemd[1]: Listening on Factory Reset Management.1252second # [ 5.453160] systemd[1]: Listening on Hostname Service Socket.1253second # [ 5.457373] systemd[1]: Starting Journal Log Access Socket...1254second # [ 5.459060] systemd[1]: Listening on Journal Audit Socket.1255second # [ 5.461327] systemd[1]: Listening on Console Output Muting Service Socket.1256second # [ 5.463528] systemd[1]: Listening on Network Management Metrics Varlink Socket.1257second # [ 5.464229] systemd[1]: Listening on Network Management Varlink Socket.1258second # [ 5.464895] systemd[1]: Listening on Network Management Netlink Socket.1259second # [ 5.465603] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1260second # [ 5.469078] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1261second # [ 5.471346] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1262second # [ 5.475427] systemd[1]: Listening on Disk Repartitioning Service Socket.1263second # [ 5.475963] systemd[1]: Listening on Resolve Monitor Varlink Socket.1264second # [ 5.476508] systemd[1]: Listening on Resolve Service Varlink Socket.1265test # [ 5.475343] systemd[1]: initrd-switch-root.service: Deactivated successfully.1266second # [ 5.477059] systemd[1]: Listening on udev Control Socket.1267test # [ 5.475617] systemd[1]: Stopped initrd-switch-root.service.1268second # [ 5.477614] systemd[1]: Listening on udev Varlink Socket.1269test # [ 5.479145] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1270second # [ 5.482424] systemd[1]: Mounting Huge Pages File System...1271second # [ 5.484560] systemd[1]: Mounting POSIX Message Queue File System...1272test # [ 5.481337] systemd[1]: Created slice Slice /system/getty.1273test # [ 5.482269] systemd[1]: Created slice User and Session Slice.1274test # [ 5.482856] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1275test # [ 5.483439] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1276test # [ 5.483997] systemd[1]: Expecting device /dev/hvc0...1277test # [ 5.487576] systemd[1]: Expecting device /dev/ttyAMA0...1278test # [ 5.488073] systemd[1]: Reached target Local Encrypted Volumes.1279test # [ 5.490048] systemd[1]: Stopped target initrd-fs.target.1280test # [ 5.490514] systemd[1]: Stopped target initrd-root-fs.target.1281test # [ 5.491059] systemd[1]: Stopped target initrd-switch-root.target.1282test # [ 5.491606] systemd[1]: Reached target Virtual Machines and Containers.1283second # [ 5.494277] systemd[1]: Mounting Kernel Debug File System...1284test # [ 5.492171] systemd[1]: Reached target Path Units.1285test # [ 5.495428] systemd[1]: Reached target Remote File Systems.1286test # [ 5.495912] systemd[1]: Reached target Slice Units.1287second # [ 5.500640] systemd[1]: Mounting Kernel Trace File System...1288test # [ 5.497852] systemd[1]: Reached target Swaps.1289test # [ 5.499932] systemd[1]: Listening on Query the User Interactively for a Password.1290test # [ 5.503062] systemd[1]: Listening on Process Core Dump Socket.1291test # [ 5.505202] systemd[1]: Listening on Credential Encryption/Decryption.1292test # [ 5.507056] systemd[1]: Listening on Factory Reset Management.1293test # [ 5.507592] systemd[1]: Listening on Hostname Service Socket.1294second # [ 5.511149] systemd[1]: Starting Create List of Static Device Nodes...1295second # [ 5.512958] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1296test # [ 5.512089] systemd[1]: Starting Journal Log Access Socket...1297test # [ 5.513775] systemd[1]: Listening on Journal Audit Socket.1298test # [ 5.516046] systemd[1]: Listening on Console Output Muting Service Socket.1299test # [ 5.518220] systemd[1]: Listening on Network Management Metrics Varlink Socket.1300test # [ 5.518908] systemd[1]: Listening on Network Management Varlink Socket.1301test # [ 5.519566] systemd[1]: Listening on Network Management Netlink Socket.1302test # [ 5.522334] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1303test # [ 5.523656] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1304test # [ 5.524085] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1305second # [ 5.527106] systemd[1]: Mounting Kernel Configuration File System...1306second # [ 5.527462] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1307second # [ 5.527719] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1308second # [ 5.527964] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1309test # [ 5.530217] systemd[1]: Listening on Disk Repartitioning Service Socket.1310test # [ 5.530721] systemd[1]: Listening on Resolve Monitor Varlink Socket.1311test # [ 5.531256] systemd[1]: Listening on Resolve Service Varlink Socket.1312test # [ 5.531798] systemd[1]: Listening on udev Control Socket.1313test # [ 5.534802] systemd[1]: Listening on udev Varlink Socket.1314test # [ 5.537391] systemd[1]: Mounting Huge Pages File System...1315test # [ 5.539699] systemd[1]: Mounting POSIX Message Queue File System...1316test # [ 5.546216] systemd[1]: Mounting Kernel Debug File System...1317second # [ 5.553976] systemd[1]: Mounting FUSE Control File System...1318second # [ 5.554361] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671319test # [ 5.556713] systemd[1]: Mounting Kernel Trace File System...1320test # [ 5.563928] systemd[1]: Starting Create List of Static Device Nodes...1321test # [ 5.567351] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1322second # [ 5.574933] systemd[1]: Starting Journal Service...1323test # [ 5.580097] systemd[1]: Mounting Kernel Configuration File System...1324second # [ 5.586782] systemd[1]: Starting Load Kernel Modules...1325test # [ 5.585841] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1326test # [ 5.586199] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1327test # [ 5.586460] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1328second # [ 5.595718] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1329test # [ 5.608354] systemd[1]: Mounting FUSE Control File System...1330test # [ 5.608851] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671331second # [ 5.615133] systemd[1]: Starting Remount Root and Kernel File Systems...1332second # [ 5.615515] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1333second # [ 5.622488] systemd[1]: Starting Coldplug All udev Devices...1334second # [ 5.624388] systemd[1]: Listening on Journal Log Access Socket.1335second # [ 5.624774] systemd[1]: Mounted Huge Pages File System.1336second # [ 5.625132] systemd[1]: Mounted POSIX Message Queue File System.1337second # [ 5.625467] systemd[1]: Mounted Kernel Debug File System.1338test # [ 5.630135] systemd[1]: Starting Journal Service...1339second # [ 5.641893] systemd[1]: Mounted Kernel Trace File System.1340second # [ 5.644957] systemd[1]: Finished Create List of Static Device Nodes.1341second # [ 5.645521] systemd[1]: Mounted Kernel Configuration File System.1342test # [ 5.640955] systemd[1]: Starting Load Kernel Modules...1343second # [ 5.649904] systemd[1]: Mounted FUSE Control File System.1344second # [ 5.655225] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1345test # [ 5.652515] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1346test # [ 5.659919] systemd[1]: Starting Remount Root and Kernel File Systems...1347test # [ 5.660950] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1348second # [ 5.676569] systemd-journald[309]: Collecting audit messages is enabled.1349test # [ 5.675882] systemd[1]: Starting Coldplug All udev Devices...1350test # [ 5.678951] systemd[1]: Listening on Journal Log Access Socket.1351test # [ 5.679421] systemd[1]: Mounted Huge Pages File System.1352test # [ 5.679872] systemd[1]: Mounted POSIX Message Queue File System.1353second # [ 5.683130] systemd[1]: Queued start job for default target Multi-User System.1354second # [ 5.690757] systemd[1]: Finished Load Kernel Modules.1355test # [ 5.688657] systemd[1]: Mounted Kernel Debug File System.1356test # [ 5.689127] systemd[1]: Mounted Kernel Trace File System.1357test # [ 5.699011] systemd[1]: Finished Create List of Static Device Nodes.1358second # [ 5.700038] systemd[1]: Starting Firewall...1359second # [ 5.708054] systemd[1]: Starting Apply Kernel Variables...1360second # [ 5.708510] systemd[1]: Started Journal Service.1361test # [ 5.708401] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1362test # [ 5.723204] systemd-journald[328]: Collecting audit messages is enabled.1363second # [ 5.730314] EXT4-fs (vda): re-mounted 4bb1824f-be15-43fc-948c-bdbeaa112076.1364test # [ 5.728663] systemd[1]: Finished Load Kernel Modules.1365test # [ 5.735588] systemd[1]: Starting Firewall...1366test # [ 5.731295] systemd[1]: Queued start job for default target Multi-User System.1367test # [ 5.741308] EXT4-fs (vda): re-mounted 80dda120-7136-485e-9bf4-6225ad27eebb.1368test # [ 5.746014] systemd[1]: Starting Apply Kernel Variables...1369test # [ 5.746933] systemd[1]: Started Journal Service.1370test # [ 5.741606] systemd[1]: systemd-journald.service: Deactivated successfully.1371test # [ 5.742397] systemd-modules-load[329]: Module 'atkbd' is built in1372test # [ 5.743063] systemd-modules-load[329]: Module 'ctr' is built in1373test # [ 5.743687] systemd-modules-load[329]: Module 'loop' is built in1374test # [ 5.751320] systemd[1]: Mounted FUSE Control File System.1375test # [ 5.773261] systemd[1]: Finished Remount Root and Kernel File Systems.1376test # [ 5.778236] systemd[1]: Mounted Kernel Configuration File System.1377test # [ 5.789216] systemd[1]: Listening on Disk Image Download Service Socket.1378test # [ 5.790797] systemd-oomd[330]: No swap; memory pressure usage will be degraded1379test # [ 5.803511] systemd[1]: Starting Flush Journal to Persistent Storage...1380test # [ 5.804249] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1381test # [ 5.807877] systemd[1]: Starting Load/Save OS Random Seed...1382test # [ 5.811400] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1383test # [ 5.816384] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1384test # [ 5.869019] systemd-journald[328]: Received client request to flush runtime journal.1385test # [ 5.908824] systemd[1]: Finished Apply Kernel Variables.1386test # [ 5.909393] systemd[1]: Starting Network Name Resolution...1387test # [ 5.909895] systemd[1]: Finished Load/Save OS Random Seed.1388test # [ 5.910382] systemd[1]: Reached target First Boot Complete.1389test # [ 5.924629] systemd[1]: Finished Flush Journal to Persistent Storage.1390test # [ 5.941528] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1391test # [ 5.943181] systemd[1]: Starting Create Static Device Nodes in /dev...1392test # [ 6.064286] systemd-resolved[353]: Positive Trust Anchors:1393test # [ 6.064870] systemd-resolved[353]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1394test # [ 6.065715] systemd-resolved[353]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161395test # [ 6.066583] systemd-resolved[353]: 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 test1396test # [ 6.238527] systemd[1]: Finished Create Static Device Nodes in /dev.1397test # [ 6.239193] systemd[1]: Reached target Preparation for Local File Systems.1398test # [ 6.241168] systemd[1]: Starting Rule-based Manager for Device Events and Files...1399test # [ 6.242528] systemd[1]: Finished Coldplug All udev Devices.1400test # [ 6.244060] systemd-resolved[353]: Using system hostname 'test'.1401test # [ 6.247159] systemd[1]: Started Network Name Resolution.1402test # [ 6.297607] systemd-udevd[369]: Using default interface naming scheme 'v261'.1403second # [ 5.685270] systemd[1]: systemd-journald.service: Deactivated successfully.1404second # [ 6.318549] systemd-journald[309]: Received client request to flush runtime journal.1405second # [ 6.344918] systemd-modules-load[310]: Module 'atkbd' is built in1406second # [ 6.345661] systemd-modules-load[310]: Module 'ctr' is built in1407second # [ 6.346361] systemd-modules-load[310]: Module 'loop' is built in1408second # [ 6.347216] systemd-oomd[311]: No swap; memory pressure usage will be degraded1409second # [ 6.354258] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1410second # [ 6.360206] systemd[1]: Finished Remount Root and Kernel File Systems.1411second # [ 6.360928] systemd[1]: Listening on Disk Image Download Service Socket.1412second # [ 6.361653] systemd[1]: Starting Flush Journal to Persistent Storage...1413second # [ 6.362351] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1414second # [ 6.363446] systemd[1]: Starting Load/Save OS Random Seed...1415test # [ 6.364913] systemd[1]: Started Rule-based Manager for Device Events and Files.1416second # [ 6.368362] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1417second # [ 6.369258] systemd[1]: Finished Load/Save OS Random Seed.1418second # [ 6.369856] systemd[1]: Reached target First Boot Complete.1419second # [ 6.370480] systemd[1]: Finished Apply Kernel Variables.1420second # [ 6.371072] systemd[1]: Starting Network Name Resolution...1421second # [ 6.371686] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1422second # [ 6.380248] systemd[1]: Starting Create Static Device Nodes in /dev...1423second # [ 6.381139] systemd-resolved[325]: Positive Trust Anchors:1424second # [ 6.381658] systemd-resolved[325]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1425second # [ 6.382529] systemd-resolved[325]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161426second # [ 6.383412] systemd-resolved[325]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1427second # [ 6.410595] systemd[1]: Finished Create Static Device Nodes in /dev.1428second # [ 6.412780] systemd[1]: Reached target Preparation for Local File Systems.1429second # [ 6.413428] systemd[1]: Starting Rule-based Manager for Device Events and Files...1430second # [ 6.414078] systemd[1]: Finished Coldplug All udev Devices.1431second # [ 6.414578] systemd-resolved[325]: Using system hostname 'second'.1432second # [ 6.415117] systemd[1]: Started Network Name Resolution.1433second # [ 6.415790] systemd-udevd[333]: Using default interface naming scheme 'v261'.1434second # [ 6.418438] systemd[1]: Started Rule-based Manager for Device Events and Files.1435second # [ 6.419067] systemd[1]: Finished Flush Journal to Persistent Storage.1436second # [ 6.419633] systemd[1]: Mounting /run/wrappers...1437second # [ 6.450435] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1438second # [ 6.460616] systemd[1]: Mounted /run/wrappers.1439second # [ 6.461684] systemd[1]: Reached target Local File Systems.1440second # [ 6.466074] systemd[1]: Listening on Boot Loader Control Service Socket.1441second # [ 6.469474] systemd[1]: Starting register-nix-paths.service...1442test # [ 6.470770] systemd[1]: Mounting /run/wrappers...1443second # [ 6.472408] systemd[1]: Starting Create SUID/SGID Wrappers...1444second # [ 6.473015] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1445second # [ 6.477980] systemd[1]: Starting Save Transient machine-id to Disk...1446test # [ 6.481334] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1447second # [ 6.495003] systemd[1]: Starting Create System Files and Directories...1448second # [ 6.499774] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1449test # [ 6.505579] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1450test # [ 6.520473] systemd[1]: Mounted /run/wrappers.1451test # [ 6.520944] systemd[1]: Reached target Local File Systems.1452test # [ 6.524660] systemd[1]: Listening on Boot Loader Control Service Socket.1453test # [ 6.529156] systemd[1]: Starting register-nix-paths.service...1454test # [ 6.531934] systemd[1]: Starting Create SUID/SGID Wrappers...1455test # [ 6.532959] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1456test # [ 6.536654] systemd[1]: Starting Save Transient machine-id to Disk...1457test # [ 6.550299] systemd[1]: Starting Create System Files and Directories...1458second # [ 6.601222] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1459second # [ 6.607164] systemd[1]: Finished Save Transient machine-id to Disk.1460test # [ 6.637593] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1461test # [ 6.643809] systemd[1]: Finished Save Transient machine-id to Disk.1462second # [ 6.651434] systemd[1]: Finished Create System Files and Directories.1463second # [ 6.659894] systemd[1]: Starting Rebuild Journal Catalog...1464second # [ 6.662974] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1465test # [ 6.692292] systemd[1]: Finished Create System Files and Directories.1466test # [ 6.700357] systemd[1]: Starting Rebuild Journal Catalog...1467test # [ 6.706009] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1468second # [ 6.735045] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1469test # [ 6.745504] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1470test # [ 6.770793] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1471second # [ 6.785390] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1472second # [ 6.823094] systemd[1]: Finished Rebuild Journal Catalog.1473test # [ 6.821529] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1474second # [ 6.829965] systemd[1]: Starting Update is Completed...1475second # [ 6.848369] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1476test # [ 6.873174] systemd[1]: Finished Rebuild Journal Catalog.1477test # [ 6.880092] systemd[1]: Starting Update is Completed...1478second # [ 6.888120] systemd[1]: Finished Update is Completed.1479test # [ 6.936568] systemd[1]: Finished Update is Completed.1480test # [ 7.069931] (udev-worker)[400]: Network interface NamePolicy= disabled on kernel command line.1481second # [ 7.081535] (udev-worker)[379]: Network interface NamePolicy= disabled on kernel command line.1482second # [ 7.089384] (udev-worker)[381]: Network interface NamePolicy= disabled on kernel command line.1483test # [ 7.087782] (udev-worker)[402]: Network interface NamePolicy= disabled on kernel command line.1484second # [ 7.296355] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1485second # [ 7.299800] systemd[1]: Finished Create SUID/SGID Wrappers.1486second # [ 7.331143] systemd[1]: Finished register-nix-paths.service.1487second # [ 7.333476] systemd[1]: Reached target System Initialization.1488second # [ 7.336427] systemd[1]: Started Discard unused filesystem blocks once a week.1489second # [ 7.337116] systemd[1]: Started Daily Cleanup of Temporary Directories.1490second # [ 7.337710] systemd[1]: Reached target Timer Units.1491second # [ 7.338179] systemd[1]: Listening on D-Bus System Message Bus Socket.1492second # [ 7.338762] systemd[1]: Listening on Nix Daemon Socket.1493test # [ 7.346272] mousedev: PS/2 mouse device common for all mice1494second # [ 7.349503] systemd[1]: Listening on Authorization Manager Agent Helper.1495second # [ 7.350130] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1496second # [ 7.350888] systemd[1]: Reached target Socket Units.1497test # [ 7.349553] systemd[1]: Finished register-nix-paths.service.1498second # [ 7.354624] systemd[1]: Starting D-Bus System Message Bus...1499test # [ 7.365003] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1500second # [ 7.374193] mousedev: PS/2 mouse device common for all mice1501test # [ 7.367095] systemd[1]: Finished Create SUID/SGID Wrappers.1502test # [ 7.367646] systemd[1]: Reached target System Initialization.1503test # [ 7.370224] systemd[1]: Started Discard unused filesystem blocks once a week.1504test # [ 7.370842] systemd[1]: Started Daily Cleanup of Temporary Directories.1505test # [ 7.371419] systemd[1]: Reached target Timer Units.1506test # [ 7.371879] systemd[1]: Listening on D-Bus System Message Bus Socket.1507test # [ 7.377277] systemd[1]: Listening on Nix Daemon Socket.1508test # [ 7.382062] systemd[1]: Listening on Authorization Manager Agent Helper.1509test # [ 7.382813] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1510test # [ 7.383564] systemd[1]: Reached target Socket Units.1511test # [ 7.388955] systemd[1]: Starting D-Bus System Message Bus...1512second # [ 7.483830] dbus-broker-launch[504]: Looking up NSS user entry for 'systemd-timesync'...1513second # [ 7.492774] dbus-broker-launch[504]: NSS returned no entry for 'systemd-timesync'1514second # [ 7.493453] dbus-broker-launch[504]: Invalid user-name in /nix/store/15l45ll9fvv42h2g8acw1xxsr83ypjcq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1515test # [ 7.496057] dbus-broker-launch[525]: Looking up NSS user entry for 'systemd-timesync'...1516test # [ 7.504451] dbus-broker-launch[525]: NSS returned no entry for 'systemd-timesync'1517test # [ 7.505107] dbus-broker-launch[525]: Invalid user-name in /nix/store/15l45ll9fvv42h2g8acw1xxsr83ypjcq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1518second # [ 7.526381] dbus-broker-launch[504]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1519second # [ 7.529701] dbus-broker-launch[504]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1520second # [ 7.534392] dbus-broker-launch[504]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/zaklcm28mfdjnfq1076w3nzwsyjwkw1p-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1521second # [ 7.541517] dbus-broker-launch[504]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/km79636izr3pfdnzj6kd4kb57b0wl7gm-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1522second # [ 7.543193] systemd[1]: Started D-Bus System Message Bus.1523second # [ 7.543691] systemd[1]: Reached target Basic System.1524test # [ 7.538175] dbus-broker-launch[525]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1525test # [ 7.542812] dbus-broker-launch[525]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1526second # [ 7.551864] systemd[1]: Starting Network Manager Script Dispatcher Service...1527test # [ 7.547166] dbus-broker-launch[525]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/zaklcm28mfdjnfq1076w3nzwsyjwkw1p-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1528test # [ 7.552331] dbus-broker-launch[525]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/km79636izr3pfdnzj6kd4kb57b0wl7gm-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1529second # [ 7.556602] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1530second # [ 7.557259] systemd[1]: Started backdoor.service.1531test # [ 7.559116] systemd[1]: Started D-Bus System Message Bus.1532test # [ 7.559722] systemd[1]: Reached target Basic System.1533test # [ 7.563722] systemd[1]: Starting Network Manager Script Dispatcher Service...1534test # [ 7.571375] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1535second # [ 7.573143] systemd[1]: Starting Import lastlog data into lastlog2 database...1536test # [ 7.592386] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1537second # [ 7.601519] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1538second # [ 7.604817] dbus-broker-launch[504]: Ready1539test # [ 7.609069] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1540test # [ 7.614610] dbus-broker-launch[525]: Ready1541test # [ 7.628677] systemd[1]: Started backdoor.service.1542second # [ 7.630546] systemd[1]: Starting Post-Boot Actions...1543second # [ 7.634772] systemd[1]: Started Reset console on configuration changes.1544test # [ 7.635757] systemd[1]: Starting Import lastlog data into lastlog2 database...1545test # [ 7.655099] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1546test # [ 7.679595] systemd[1]: Starting Post-Boot Actions...1547second # [ 7.711291] jfm1sfzvhs9cnbhrfml5p82i6z6c2sxr-wifi-secrets[522]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1548test # [ 7.730330] jfm1sfzvhs9cnbhrfml5p82i6z6c2sxr-wifi-secrets[542]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1549test # [ 7.740697] systemd[1]: Started Reset console on configuration changes.1550second # [ 7.759583] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1551second # connecting to host...1552test # [ 7.755194] adg9kzka51ddw5qgki73hk89404rrphj-wifi-secrets[544]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1553second # [ 7.768273] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1554test # [ 7.776290] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1555second # [ 7.781601] systemd[1]: Finished Post-Boot Actions.1556test # [ 7.783355] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1557second # [ 7.804691] systemd[1]: Started Name Service Cache Daemon (nsncd).1558second # [ 7.811017] systemd[1]: Reached target Host and Network Name Lookups.1559second # [ 7.811807] nsncd[526]: Oct 03 00:04:55.651 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1560test # [ 7.811906] pqf2w5skr70322kcnwqj9n6c53wanj8f-wifi-secrets[546]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1561test # [ 7.819268] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1562second # [ 7.823244] systemd[1]: Reached target User and Group Name Lookups.1563test # [ 7.823182] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1564second # [ 7.825289] systemd[1]: Starting User Login Management...1565test # [ 7.823841] systemd[1]: Finished Firewall.1566test # connecting to host...1567second # [ 7.849899] systemd[1]: Finished Firewall.1568test # [ 7.849260] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1569test # [ 7.853755] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1570second # [ 7.858231] systemd[1]: Reached target Preparation for Network.1571second # [ 7.858789] systemd[1]: Listening on Network Management Resolve Hook Socket.1572second # [ 7.874406] systemd[1]: Starting Network Manager...1573test # [ 7.877951] systemd[1]: Reached target Preparation for Network.1574test # [ 7.880680] nsncd[551]: Oct 03 00:04:55.714 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1575test # [ 7.891292] systemd[1]: Listening on Network Management Resolve Hook Socket.1576test # [ 7.895285] systemd[1]: Starting Network Manager...1577second # [ 7.901387] systemd[1]: Starting Network Management...1578test: Guest shell says: b'Spawning backdoor root shell...\n'1579test # [ 7.902559] systemd[1]: Starting Network Management...1580test # [ 7.903043] systemd[1]: Started Name Service Cache Daemon (nsncd).1581second # [ 7.904703] systemd[1]: Finished Import lastlog data into lastlog2 database.1582test # [ 7.914673] systemd[1]: Finished Post-Boot Actions.1583second # [ 7.924067] systemd[1]: Started Network Manager Script Dispatcher Service.1584test # [ 7.915122] systemd[1]: Reached target Host and Network Name Lookups.1585test # [ 7.915652] systemd[1]: Reached target User and Group Name Lookups.1586test # [ 7.933182] systemd[1]: Starting User Login Management...1587test # [ 7.942958] systemd[1]: Started Network Manager Script Dispatcher Service.1588test # [ 7.954190] systemd[1]: Finished Import lastlog data into lastlog2 database.1589test: connected to guest root shell1590test: (connecting took 8.21 seconds)1591test: (finished: waiting for the VM to finish booting, in 8.21 seconds)1592second # [ 8.019041] systemd-logind[558]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1593second # [ 8.020358] systemd-logind[558]: Watching system buttons on /dev/input/event0 (gpio-keys)1594second # [ 8.028973] systemd-logind[558]: New seat seat0.1595second # [ 8.029436] systemd[1]: Started User Login Management.1596second # [ 8.033116] systemd[1]: Starting linger-users.service...1597second # [ 8.093777] systemd[1]: linger-users.service: Deactivated successfully.1598second # [ 8.094422] systemd[1]: Finished linger-users.service.1599test # [ 8.114392] systemd-logind[599]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1600test # [ 8.117423] systemd-logind[599]: Watching system buttons on /dev/input/event0 (gpio-keys)1601test # [ 8.133567] systemd-logind[599]: New seat seat0.1602test # [ 8.134019] systemd[1]: Started User Login Management.1603test # [ 8.138861] systemd[1]: Starting linger-users.service...1604second # [ 8.173268] cfg80211: Loading compiled-in X.509 certificates for regulatory database1605test # [ 8.214985] cfg80211: Loading compiled-in X.509 certificates for regulatory database1606second # [ 8.236152] systemd[1]: Starting Hostname Service...1607second # [ 8.248199] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1608second # [ 8.248715] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1609test # [ 8.251512] systemd[1]: linger-users.service: Deactivated successfully.1610test # [ 8.252594] systemd[1]: Finished linger-users.service.1611test # [ 8.288524] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1612test # [ 8.289007] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1613second # [ 8.300780] systemd-networkd[576]: /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.1614second # [ 8.308318] systemd-networkd[576]: /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.1615second # [ 8.310003] systemd-networkd[576]: lo: Link UP1616second # [ 8.310428] systemd-networkd[576]: lo: Gained carrier1617test # [ 8.317090] systemd[1]: Starting Hostname Service...1618second # [ 8.321507] systemd[1]: Started Network Management.1619second # [ 8.324677] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1620second # [ 8.325363] systemd-networkd[576]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1621second # [ 8.326662] systemd-networkd[576]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1622second # [ 8.340071] systemd-networkd[576]: eth1: Link UP1623second # [ 8.340561] systemd-networkd[576]: eth1: Gained carrier1624second # [ 8.341040] systemd-networkd[576]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1625test # [ 8.344839] systemd-networkd[598]: /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.1626test # [ 8.350960] systemd-networkd[598]: /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.1627test # [ 8.357269] systemd-networkd[598]: lo: Link UP1628test # [ 8.357701] systemd-networkd[598]: lo: Gained carrier1629test # [ 8.358153] systemd[1]: Started Network Management.1630test # [ 8.366171] systemd-networkd[598]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1631test # [ 8.367712] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1632test # [ 8.378994] systemd-networkd[598]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1633second # [ 8.382791] systemd[1]: Started Hostname Service.1634test # [ 8.379765] systemd-networkd[598]: eth1: Link UP1635test # [ 8.384167] systemd-networkd[598]: eth1: Gained carrier1636test # [ 8.384636] systemd-networkd[598]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1637second # [ 8.405629] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1638second # [ 8.459024] systemd-networkd[576]: eth0: Link UP1639second # [ 8.459524] systemd-networkd[576]: eth0: Gained carrier1640test # [ 8.462221] systemd[1]: Started Hostname Service.1641test # [ 8.474857] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1642second # [ 8.477841] systemd[1]: Started Network Manager.1643second # [ 8.480183] systemd[1]: Reached target Network.1644second # [ 8.486052] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1645second # [ 8.490559] systemd[1]: Starting Permit User Sessions...1646test # [ 8.530294] systemd-networkd[598]: eth0: Link UP1647test # [ 8.532085] systemd-networkd[598]: eth0: Gained carrier1648test # [ 8.549624] systemd[1]: Started Network Manager.1649test # [ 8.551962] systemd[1]: Reached target Network.1650test # [ 8.556170] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1651test # [ 8.573416] systemd[1]: Starting Permit User Sessions...1652second # [ 8.620213] systemd[1]: Finished Permit User Sessions.1653second # [ 8.629540] systemd[1]: Started Getty on tty1.1654second # [ 8.637572] systemd[1]: Reached target Login Prompts.1655test # [ 8.678586] systemd[1]: Finished Permit User Sessions.1656second # [ 8.693465] systemd-resolved[325]: eth0: Bus client set default route setting: yes1657second # [ 8.697642] systemd-resolved[325]: eth0: Bus client set DNS server list to: 10.0.2.31658test # [ 8.698795] systemd[1]: Started Getty on tty1.1659test # [ 8.699273] systemd[1]: Reached target Login Prompts.1660test # [ 8.743516] NetworkManager[597]: <warn> [1790985896.5771] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1661test # [ 8.749834] NetworkManager[597]: <warn> [1790985896.5797] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1662second # [ 8.756827] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1663second # [ 8.761404] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1664test # [ 8.763507] systemd-resolved[353]: eth0: Bus client set default route setting: yes1665test # [ 8.767186] systemd-resolved[353]: eth0: Bus client set DNS server list to: 10.0.2.31666second # [ 8.769373] systemd[1]: Reached target Multi-User System.1667second # [ 8.772452] systemd[1]: Startup finished in 748ms (kernel) + 3.578s (initrd) + 4.444s (userspace) = 8.771s.1668test # [ 8.827518] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1669test # [ 8.833101] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1670second # [ 8.896024] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31671test # [ 8.929419] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31672test # [ 9.178923] systemd[1]: Reached target Multi-User System.1673test # [ 9.180153] systemd[1]: Startup finished in 750ms (kernel) + 3.611s (initrd) + 4.817s (userspace) = 9.179s.1674second # [ 9.182211] systemd[1]: Starting Virtual Console Setup...1675second # [ 9.212089] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1676second # [ 9.222372] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1677test: (finished: waiting for unit NetworkManager.service, in 9.47 seconds)1678second # [ 9.224064] systemd[1]: Stopped Virtual Console Setup.1679test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1680second # [ 9.230982] systemd[1]: Starting Virtual Console Setup...1681test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.03 seconds)1682cleanup1683kill QemuMachine (pid 47)1684second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)1685second # [2026-10-03T00:04:56Z INFO virtiofsd] Client disconnected, shutting down1686second # [2026-10-03T00:04:56Z INFO virtiofsd] Client disconnected, shutting down1687second # [2026-10-03T00:04:56Z INFO virtiofsd] Client disconnected, shutting down1688test # [ 9.288216] systemd[1]: Starting Virtual Console Setup...1689test # [ 9.296603] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1690test # [ 9.298548] systemd[1]: Stopped Virtual Console Setup.1691test # [ 9.313295] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1692test # [ 9.317526] systemd[1]: Starting Virtual Console Setup...1693test # [ 9.402411] systemd-logind[599]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1694kill QemuMachine (pid 48)1695test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)1696test # [2026-10-03T00:04:57Z INFO virtiofsd] Client disconnected, shutting down1697test # [2026-10-03T00:04:57Z INFO virtiofsd] Client disconnected, shutting down1698test # [2026-10-03T00:04:57Z INFO virtiofsd] Client disconnected, shutting down1699(finished: cleanup, in 0.31 seconds)1700Traceback (most recent call last):1701 File "/nix/store/v7jjlkxf97a1d9c41xc7m8xw03k84200-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in <module>1702 sys.exit(main())1703 ~~~~^^1704 File "/nix/store/v7jjlkxf97a1d9c41xc7m8xw03k84200-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/__init__.py", line 194, in main1705 driver.run_tests()1706 ~~~~~~~~~~~~~~~~^^1707 File "/nix/store/v7jjlkxf97a1d9c41xc7m8xw03k84200-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 437, in run_tests1708 self.test_script()1709 ~~~~~~~~~~~~~~~~^^1710 File "/nix/store/v7jjlkxf97a1d9c41xc7m8xw03k84200-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 395, in test_script1711 exec(self.tests, symbols, None)1712 ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^1713 File "<string>", line 4, in <module>1714AssertionError: Expected mock password in connection file:1715[connection]1716autoconnect=true1717id=1718type=wifi17191720[wifi]1721mode=infrastructure1722ssid=17231724[wifi-security]1725key-mgmt=wpa-psk1726psk=1727