vm-test-run-wifi
checks.aarch64-linux.wifi
· build #616
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600 seconds5run the VM test script6additionally exposed symbols:7 second, test,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10start all VMs11second: starting vm12test: starting vm13second # Disk image does not exist, creating the virtualisation disk image...14second: QEMU running (pid 14)15second # Formatting '/build/vm-state-second/tmp.m1HgLZNjGV', fmt=raw size=107374182416second # mke2fs 1.47.4 (6-Mar-2025)17second # Discarding device blocks: 0/262144 done18second # Creating filesystem with 262144 4k blocks and 65536 inodes19second # Filesystem UUID: 43e3c57a-1be9-4327-9039-19acfe768f6520second # Superblock backups stored on blocks:21second # 32768, 98304, 163840, 22937622second # 23second # Allocating group tables: 0/8 done24second # Writing inode tables: 0/8 done25second # Creating journal (8192 blocks): done26second # Writing superblocks and filesystem accounting information: 0/8 done27test # Disk image does not exist, creating the virtualisation disk image...28test: QEMU running (pid 15)29test # Formatting '/build/vm-state-test/tmp.cHCfxWWWNZ', fmt=raw size=107374182430second # 31test # mke2fs 1.47.4 (6-Mar-2025)32second # Virtualisation disk image created.33test # Discarding device blocks: 0/262144 done34(finished: start all VMs, in 0.50 seconds)35test # Creating filesystem with 262144 4k blocks and 65536 inodes36test: waiting for unit NetworkManager.service37test # Filesystem UUID: 355a78f4-3f73-4bde-9dd9-4469b868fc3438test: waiting for the VM to finish booting39test # Superblock backups stored on blocks:40test # 32768, 98304, 163840, 22937641test # 42test # Allocating group tables: 0/8 done43test # Writing inode tables: 0/8 done44test # Creating journal (8192 blocks): done45test # Writing superblocks and filesystem accounting information: 0/8 done46test # 47test # Virtualisation disk image created.48second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]49second # [ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 7 15:23:00 UTC 202650second # [ 0.000000] KASLR enabled51second # [ 0.000000] random: crng init done52second # [ 0.000000] Machine model: linux,dummy-virt53second # [ 0.000000] efi: UEFI not found.54second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT55second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]56second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]57second # [ 0.000000] Zone ranges:58second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]59second # [ 0.000000] DMA32 empty60second # [ 0.000000] Normal empty61second # [ 0.000000] Device empty62second # [ 0.000000] Movable zone start for each node63second # [ 0.000000] Early memory node ranges64second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]65second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]66second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000067second # [ 0.000000] psci: probing for conduit method from DT.68second # [ 0.000000] psci: PSCIv1.3 detected in firmware.69second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs70second # [ 0.000000] psci: Trusted OS migration not required71second # [ 0.000000] psci: SMC Calling Convention v1.172second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)73second # [ 0.000000] percpu: Embedded 76 pages/cpu s186328 r8192 d116776 u31129674second # [ 0.000000] Detected PIPT I-cache on CPU075second # [ 0.000000] CPU features: detected: GICv3 CPU interface76second # [ 0.000000] CPU features: detected: Spectre-v477second # [ 0.000000] CPU features: detected: Spectre-BHB78second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR79second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)80test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]81test # [ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 7 15:23:00 UTC 202682test # [ 0.000000] KASLR enabled83test # [ 0.000000] random: crng init done84second # [ 0.000000] CPU features: detected: ARM erratum 141804085second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion86second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing87second # [ 0.000000] alternatives: applying boot alternatives88test # [ 0.000000] Machine model: linux,dummy-virt89test # [ 0.000000] efi: UEFI not found.90test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT91second # [ 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/vbfr23sxzxnjwxlqvyj53yhd04zsa5ns-nixos-system-second-test/init regInfo=/nix/store/ah6v0c9xgxg2mv9j7vk03k6qy41cj0vf-closure-info/registration console=ttyAMA0,115200n8 console=tty092test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]93test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]94test # [ 0.000000] Zone ranges:95test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]96second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/ah6v0c9xgxg2mv9j7vk03k6qy41cj0vf-closure-info/registration", will be passed to user space.97second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes98second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)99test # [ 0.000000] DMA32 empty100test # [ 0.000000] Normal empty101test # [ 0.000000] Device empty102test # [ 0.000000] Movable zone start for each node103test # [ 0.000000] Early memory node ranges104test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]105second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)106test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]107second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB108test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000109second # [ 0.000000] software IO TLB: area num 1.110test # [ 0.000000] psci: probing for conduit method from DT.111second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)112second # [ 0.000000] Fallback order for Node 0: 0113test # [ 0.000000] psci: PSCIv1.3 detected in firmware.114test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs115test # [ 0.000000] psci: Trusted OS migration not required116second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144117test # [ 0.000000] psci: SMC Calling Convention v1.1118second # [ 0.000000] Policy zone: DMA119second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off120second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1121test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)122test # [ 0.000000] percpu: Embedded 76 pages/cpu s186328 r8192 d116776 u311296123second # [ 0.000000] allocated 2097152 bytes of page_ext124second # [ 0.000000] ftrace: allocating 74755 entries in 294 pages125test # [ 0.000000] Detected PIPT I-cache on CPU0126test # [ 0.000000] CPU features: detected: GICv3 CPU interface127second # [ 0.000000] ftrace: allocated 294 pages with 4 groups128second # [ 0.000000] rcu: Hierarchical RCU implementation.129test # [ 0.000000] CPU features: detected: Spectre-v4130test # [ 0.000000] CPU features: detected: Spectre-BHB131second # [ 0.000000] rcu: RCU event tracing is enabled.132test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR133test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)134second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.135test # [ 0.000000] CPU features: detected: ARM erratum 1418040136second # [ 0.000000] Trampoline variant of Tasks RCU enabled.137second # [ 0.000000] Rude variant of Tasks RCU enabled.138second # [ 0.000000] Tracing variant of Tasks RCU enabled.139test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion140test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing141test # [ 0.000000] alternatives: applying boot alternatives142second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.143second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1144second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.145second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.146second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.147second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0148second # [ 0.000000] GICv3: 256 SPIs implemented149test # [ 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/a8zqafllrxvslrfi9km4zjssyjb4s67x-nixos-system-test-test/init regInfo=/nix/store/12ml7p5kx7al1qx727aii58fgvby7bbv-closure-info/registration console=ttyAMA0,115200n8 console=tty0150test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/12ml7p5kx7al1qx727aii58fgvby7bbv-closure-info/registration", will be passed to user space.151test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes152second # [ 0.000000] GICv3: 0 Extended SPIs implemented153second # [ 0.000000] Root IRQ handler: gic_handle_irq154second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI155second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0156second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000157second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]158test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)159test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)160test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB161test # [ 0.000000] software IO TLB: area num 1.162test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)163test # [ 0.000000] Fallback order for Node 0: 0164second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)165test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144166test # [ 0.000000] Policy zone: DMA167second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)168test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off169second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000170second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000171second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.172test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1173test # [ 0.000000] allocated 2097152 bytes of page_ext174test # [ 0.000000] ftrace: allocating 74755 entries in 294 pages175test # [ 0.000000] ftrace: allocated 294 pages with 4 groups176test # [ 0.000000] rcu: Hierarchical RCU implementation.177second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns178test # [ 0.000000] rcu: RCU event tracing is enabled.179second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040180second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).181second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns182test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.183test # [ 0.000000] Trampoline variant of Tasks RCU enabled.184test # [ 0.000000] Rude variant of Tasks RCU enabled.185test # [ 0.000000] Tracing variant of Tasks RCU enabled.186test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.187second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns188test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1189second # [ 0.000067] arm-pv: using stolen time PV190second # [ 0.000469] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)191second # [ 0.000793] Console: colour dummy device 80x25192test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.193test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.194second # [ 0.000812] printk: legacy console [tty0] enabled195test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.196test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0197test # [ 0.000000] GICv3: 256 SPIs implemented198second # [ 0.001037] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)199test # [ 0.000000] GICv3: 0 Extended SPIs implemented200second # [ 0.001042] pid_max: default: 32768 minimum: 301201test # [ 0.000000] Root IRQ handler: gic_handle_irq202second # [ 0.001113] LSM: initializing lsm=capability,landlock,yama,bpf,ima203second # [ 0.001302] landlock: Up and running.204second # [ 0.001304] Yama: becoming mindful.205test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI206test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0207second # [ 0.001791] LSM support for eBPF active208test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000209second # [ 0.001983] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)210test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]211second # [ 0.002004] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)212second # [ 0.003964] cacheinfo: Unable to detect cache hierarchy for CPU 0213second # [ 0.004914] rcu: Hierarchical SRCU implementation.214test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)215test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)216test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000217second # [ 0.004920] rcu: Max phase no-delay instances is 1000.218test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000219second # [ 0.005925] fsl-mc MSI: its@8080000 domain created220second # [ 0.006089] EFI services will not be available.221test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.222second # [ 0.006191] smp: Bringing up secondary CPUs ...223second # [ 0.006221] smp: Brought up 1 node, 1 CPU224second # [ 0.006224] SMP: Total of 1 processors activated.225test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns226test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040227second # [ 0.006226] CPU: All CPU(s) started at EL1228test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).229second # [ 0.006237] CPU features: detected: 32-bit EL0 Support230second # [ 0.006240] CPU features: detected: Data cache clean to the PoU not required for I/D coherence231test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns232test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns233second # [ 0.006243] CPU features: detected: Common not Private translations234test # [ 0.000067] arm-pv: using stolen time PV235second # [ 0.006246] CPU features: detected: CRC32 instructions236second # [ 0.006247] CPU features: detected: Data cache clean to Point of Persistence237test # [ 0.000459] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)238test # [ 0.000778] Console: colour dummy device 80x25239second # [ 0.006250] CPU features: detected: Enhanced Virtualization Traps240second # [ 0.006261] CPU features: detected: RCpc load-acquire (LDAPR)241test # [ 0.000800] printk: legacy console [tty0] enabled242second # [ 0.006263] CPU features: detected: LSE atomic instructions243test # [ 0.001020] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)244second # [ 0.006265] CPU features: detected: Privileged Access Never245test # [ 0.001026] pid_max: default: 32768 minimum: 301246second # [ 0.006267] CPU features: detected: PMUv3247second # [ 0.006269] CPU features: detected: RAS Extension Support248test # [ 0.001105] LSM: initializing lsm=capability,landlock,yama,bpf,ima249test # [ 0.001249] landlock: Up and running.250test # [ 0.001251] Yama: becoming mindful.251test # [ 0.001702] LSM support for eBPF active252second # [ 0.006272] CPU features: detected: Speculative Store Bypassing Safe (SSBS)253second # [ 0.006295] alternatives: applying system-wide alternatives254test # [ 0.001882] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)255second # [ 0.007889] CPU features: detected: Hardware dirty bit management on CPU0256test # [ 0.001901] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)257test # [ 0.003957] cacheinfo: Unable to detect cache hierarchy for CPU 0258second # [ 0.008084] Memory: 893852K/1048576K available (24320K kernel code, 7086K rwdata, 26340K rodata, 4736K init, 1103K bss, 113416K reserved, 32768K cma-reserved)259test # [ 0.004848] rcu: Hierarchical SRCU implementation.260test # [ 0.004853] rcu: Max phase no-delay instances is 1000.261second # [ 0.008598] devtmpfs: initialized262test # [ 0.005826] fsl-mc MSI: its@8080000 domain created263test # [ 0.005984] EFI services will not be available.264second # [ 0.010147] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)265test # [ 0.006070] smp: Bringing up secondary CPUs ...266second # [ 0.010171] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).267test # [ 0.006096] smp: Brought up 1 node, 1 CPU268second # [ 0.010313] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL269test # [ 0.006099] SMP: Total of 1 processors activated.270test # [ 0.006101] CPU: All CPU(s) started at EL1271second # [ 0.010316] 0 pages in range for non-PLT usage272second # [ 0.010317] 508336 pages in range for PLT usage273second # [ 0.010455] pinctrl core: initialized pinctrl subsystem274second # [ 0.011239] DMI not present or invalid.275second # [ 0.015031] NET: Registered PF_NETLINK/PF_ROUTE protocol family276test # [ 0.006113] CPU features: detected: 32-bit EL0 Support277test # [ 0.006115] CPU features: detected: Data cache clean to the PoU not required for I/D coherence278test # [ 0.006118] CPU features: detected: Common not Private translations279test # [ 0.006120] CPU features: detected: CRC32 instructions280second # [ 0.017887] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations281second # [ 0.018049] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations282test # [ 0.006122] CPU features: detected: Data cache clean to Point of Persistence283test # [ 0.006125] CPU features: detected: Enhanced Virtualization Traps284second # [ 0.018223] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations285test # [ 0.006135] CPU features: detected: RCpc load-acquire (LDAPR)286test # [ 0.006137] CPU features: detected: LSE atomic instructions287second # [ 0.018246] audit: initializing netlink subsys (disabled)288second # [ 0.018923] thermal_sys: Registered thermal governor 'fair_share'289second # [ 0.018925] thermal_sys: Registered thermal governor 'bang_bang'290second # [ 0.018928] thermal_sys: Registered thermal governor 'step_wise'291second # [ 0.018930] thermal_sys: Registered thermal governor 'user_space'292test # [ 0.006139] CPU features: detected: Privileged Access Never293test # [ 0.006141] CPU features: detected: PMUv3294test # [ 0.006143] CPU features: detected: RAS Extension Support295test # [ 0.006147] CPU features: detected: Speculative Store Bypassing Safe (SSBS)296test # [ 0.006169] alternatives: applying system-wide alternatives297test # [ 0.007518] CPU features: detected: Hardware dirty bit management on CPU0298second # [ 0.018932] thermal_sys: Registered thermal governor 'power_allocator'299second # [ 0.018962] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1300second # [ 0.018975] cpuidle: using governor ladder301test # [ 0.007694] Memory: 893848K/1048576K available (24320K kernel code, 7086K rwdata, 26340K rodata, 4736K init, 1103K bss, 113404K reserved, 32768K cma-reserved)302test # [ 0.008175] devtmpfs: initialized303second # [ 0.018980] cpuidle: using governor menu304test # [ 0.009717] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)305second # [ 0.019095] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.306second # [ 0.019119] ASID allocator initialised with 32768 entries307second # [ 0.019828] Serial: AMBA PL011 UART driver308second # [ 0.022984] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1309test # [ 0.009746] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).310test # [ 0.009878] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL311second # [ 0.023165] printk: console [ttyAMA0] enabled312test # [ 0.009881] 0 pages in range for non-PLT usage313test # [ 0.009882] 508336 pages in range for PLT usage314test # [ 0.010003] pinctrl core: initialized pinctrl subsystem315test # [ 0.010775] DMI not present or invalid.316test # [ 0.014187] NET: Registered PF_NETLINK/PF_ROUTE protocol family317test # [ 0.016933] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations318test # [ 0.017116] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations319test # [ 0.017290] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations320test # [ 0.017312] audit: initializing netlink subsys (disabled)321test # [ 0.017905] thermal_sys: Registered thermal governor 'fair_share'322test # [ 0.017907] thermal_sys: Registered thermal governor 'bang_bang'323test # [ 0.017910] thermal_sys: Registered thermal governor 'step_wise'324test # [ 0.017912] thermal_sys: Registered thermal governor 'user_space'325test # [ 0.017915] thermal_sys: Registered thermal governor 'power_allocator'326test # [ 0.017951] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1327test # [ 0.017966] cpuidle: using governor ladder328test # [ 0.017971] cpuidle: using governor menu329test # [ 0.018097] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.330test # [ 0.018118] ASID allocator initialised with 32768 entries331test # [ 0.018795] Serial: AMBA PL011 UART driver332test # [ 0.022022] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1333second # [ 0.102087] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages334second # [ 0.102113] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page335test # [ 0.022163] printk: console [ttyAMA0] enabled336second # [ 0.102120] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages337second # [ 0.102124] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page338second # [ 0.102127] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages339second # [ 0.102130] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page340second # [ 0.102134] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages341second # [ 0.102137] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page342second # [ 0.107566] fbcon: Taking over console343second # [ 0.107588] ACPI: Interpreter disabled.344second # [ 0.108870] iommu: Default domain type: Translated345second # [ 0.108890] iommu: DMA domain TLB invalidation policy: strict mode346second # [ 0.110148] SCSI subsystem initialized347test # [ 0.095856] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages348test # [ 0.095880] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page349test # [ 0.095887] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages350test # [ 0.095890] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page351test # [ 0.095894] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages352test # [ 0.095897] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page353test # [ 0.095900] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages354test # [ 0.095903] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page355test # [ 0.100858] fbcon: Taking over console356second # [ 0.118335] usbcore: registered new interface driver usbfs357test # [ 0.100878] ACPI: Interpreter disabled.358test # [ 0.102160] iommu: Default domain type: Translated359test # [ 0.102172] iommu: DMA domain TLB invalidation policy: strict mode360test # [ 0.103309] SCSI subsystem initialized361second # [ 0.118374] usbcore: registered new interface driver hub362test # [ 0.103849] usbcore: registered new interface driver usbfs363second # [ 0.118393] usbcore: registered new device driver usb364second # [ 0.118626] pps_core: LinuxPPS API ver. 1 registered365second # [ 0.118634] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>366second # [ 0.118640] PTP clock support registered367second # [ 0.118670] EDAC MC: Ver: 3.0.0368second # [ 0.121931] scmi_core: SCMI protocol bus registered369second # [ 0.122752] FPGA manager framework370second # [ 0.123820] vgaarb: loaded371second # [ 0.124299] clocksource: Switched to clocksource arch_sys_counter372test # [ 0.111922] usbcore: registered new interface driver hub373test # [ 0.111953] usbcore: registered new device driver usb374test # [ 0.112215] pps_core: LinuxPPS API ver. 1 registered375test # [ 0.112223] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>376test # [ 0.112229] PTP clock support registered377test # [ 0.112260] EDAC MC: Ver: 3.0.0378test # [ 0.114896] scmi_core: SCMI protocol bus registered379second # [ 0.130027] VFS: Disk quotas dquot_6.6.0380second # [ 0.130070] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)381test # [ 0.115819] FPGA manager framework382second # [ 0.131306] netfs: FS-Cache loaded383second # [ 0.131459] pnp: PnP ACPI: disabled384test # [ 0.116848] vgaarb: loaded385test # [ 0.117427] clocksource: Switched to clocksource arch_sys_counter386second # [ 0.133962] NET: Registered PF_INET protocol family387second # [ 0.134189] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)388test # [ 0.122608] VFS: Disk quotas dquot_6.6.0389test # [ 0.122648] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)390test # [ 0.125162] netfs: FS-Cache loaded391test # [ 0.125294] pnp: PnP ACPI: disabled392test # [ 0.127936] NET: Registered PF_INET protocol family393test # [ 0.128152] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)394second # [ 0.160471] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)395second # [ 0.160507] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)396second # [ 0.160523] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)397second # [ 0.160561] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)398second # [ 0.160629] TCP: Hash tables configured (established 8192 bind 8192)399second # [ 0.160740] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)400second # [ 0.160815] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)401second # [ 0.160871] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)402second # [ 0.161003] NET: Registered PF_UNIX/PF_LOCAL protocol family403second # [ 0.161036] NET: Registered PF_XDP protocol family404second # [ 0.161059] PCI: CLS 0 bytes, default 64405second # [ 0.161301] Trying to unpack rootfs image as initramfs...406test # [ 0.153623] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)407test # [ 0.153658] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)408test # [ 0.153672] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)409test # [ 0.153710] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)410test # [ 0.153778] TCP: Hash tables configured (established 8192 bind 8192)411test # [ 0.153896] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)412test # [ 0.153926] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)413test # [ 0.153951] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)414test # [ 0.154077] NET: Registered PF_UNIX/PF_LOCAL protocol family415second # [ 0.174603] kvm [1]: HYP mode not available416test # [ 0.154114] NET: Registered PF_XDP protocol family417test # [ 0.154135] PCI: CLS 0 bytes, default 64418test # [ 0.154325] Trying to unpack rootfs image as initramfs...419test # [ 0.167399] kvm [1]: HYP mode not available420second # [ 0.241500] Initialise system trusted keyrings421second # [ 0.242050] workingset: timestamp_bits=42 max_order=18 bucket_order=0422second # [ 0.243100] squashfs: version 4.0 (2009/01/31) Phillip Lougher423second # [ 0.243635] 9p: Installing v9fs 9p2000 file system support424test # [ 0.235200] Initialise system trusted keyrings425test # [ 0.235709] workingset: timestamp_bits=42 max_order=18 bucket_order=0426test # [ 0.236742] squashfs: version 4.0 (2009/01/31) Phillip Lougher427test # [ 0.237283] 9p: Installing v9fs 9p2000 file system support428second # [ 0.262955] Key type asymmetric registered429second # [ 0.262988] Asymmetric key parser 'x509' registered430second # [ 0.263054] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)431second # [ 0.268360] io scheduler mq-deadline registered432second # [ 0.268380] io scheduler kyber registered433test # [ 0.256657] Key type asymmetric registered434test # [ 0.256685] Asymmetric key parser 'x509' registered435test # [ 0.256748] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)436test # [ 0.265509] io scheduler mq-deadline registered437test # [ 0.265531] io scheduler kyber registered438test # [ 0.268597] pl061_gpio 9030000.pl061: PL061 GPIO chip registered439second # [ 0.276464] pl061_gpio 9030000.pl061: PL061 GPIO chip registered440second # [ 0.276824] ledtrig-cpu: registered to indicate activity on CPUs441second # [ 0.277139] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:442second # [ 0.277163] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000443second # [ 0.277178] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000444second # [ 0.277185] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000445test # [ 0.269422] ledtrig-cpu: registered to indicate activity on CPUs446second # [ 0.277211] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits447test # [ 0.269806] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:448second # [ 0.277237] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]449test # [ 0.269831] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000450second # [ 0.277315] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00451test # [ 0.269847] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000452second # [ 0.277333] pci_bus 0000:00: root bus resource [bus 00-ff]453second # [ 0.277338] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]454test # [ 0.269853] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000455second # [ 0.277342] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]456test # [ 0.269889] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits457second # [ 0.277346] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]458test # [ 0.269912] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]459second # [ 0.277428] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint460test # [ 0.269987] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00461second # [ 0.277872] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint462test # [ 0.270006] pci_bus 0000:00: root bus resource [bus 00-ff]463second # [ 0.277992] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]464second # [ 0.278003] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]465test # [ 0.270011] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]466test # [ 0.270015] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]467test # [ 0.270018] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]468second # [ 0.278025] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]469second # [ 0.278037] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]470test # [ 0.270093] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint471second # [ 0.278343] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint472test # [ 0.270539] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint473test # [ 0.270658] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]474second # [ 0.278460] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]475second # [ 0.278471] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]476test # [ 0.270668] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]477test # [ 0.270688] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]478second # [ 0.278492] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]479test # [ 0.270699] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]480second # [ 0.278778] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint481second # [ 0.278896] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]482test # [ 0.270990] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint483second # [ 0.278907] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]484test # [ 0.271104] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]485test # [ 0.271115] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]486second # [ 0.278929] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]487test # [ 0.271135] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]488second # [ 0.279216] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint489second # [ 0.279331] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]490test # [ 0.271416] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint491second # [ 0.279343] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]492test # [ 0.271530] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]493second # [ 0.279363] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]494test # [ 0.271541] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]495second # [ 0.279668] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint496test # [ 0.271561] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]497second # [ 0.279783] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]498test # [ 0.271847] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint499second # [ 0.279794] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]500test # [ 0.271965] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]501test # [ 0.271975] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]502second # [ 0.279815] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]503test # [ 0.271995] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]504second # [ 0.280099] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint505second # [ 0.280218] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]506test # [ 0.272288] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint507second # [ 0.280230] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]508test # [ 0.272402] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]509second # [ 0.280252] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]510test # [ 0.272413] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]511test # [ 0.272432] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]512test # [ 0.272719] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint513test # [ 0.272835] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]514second # [ 0.280657] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint515second # [ 0.280802] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]516second # [ 0.280821] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]517test # [ 0.272845] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]518second # [ 0.280846] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]519test # [ 0.272866] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]520second # [ 0.280859] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]521test # [ 0.273171] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint522second # [ 0.281143] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint523test # [ 0.273290] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]524second # [ 0.281261] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]525test # [ 0.273300] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]526second # [ 0.281281] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]527test # [ 0.273324] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]528test # [ 0.273335] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]529second # [ 0.281577] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint530second # [ 0.281696] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]531second # [ 0.281717] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]532second # [ 0.281992] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint533second # [ 0.282106] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]534second # [ 0.282270] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint535second # [ 0.282467] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]536second # [ 0.282481] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]537second # [ 0.282501] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]538second # [ 0.282799] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint539second # [ 0.282915] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]540second # [ 0.282926] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]541second # [ 0.282946] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]542second # [ 0.283356] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned543second # [ 0.283369] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned544second # [ 0.283384] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned545second # [ 0.283422] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned546second # [ 0.283455] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned547test # [ 0.301754] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint548second # [ 0.283488] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned549test # [ 0.301896] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]550test # [ 0.301917] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]551second # [ 0.283519] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned552test # [ 0.302223] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint553second # [ 0.283552] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned554test # [ 0.302340] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]555second # [ 0.283587] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned556test # [ 0.302360] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]557second # [ 0.283621] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned558test # [ 0.302625] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint559second # [ 0.283653] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned560test # [ 0.302740] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]561second # [ 0.283684] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned562test # [ 0.302912] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint563test # [ 0.303124] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]564second # [ 0.283751] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned565test # [ 0.303138] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]566second # [ 0.283785] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned567test # [ 0.303158] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]568second # [ 0.283800] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned569test # [ 0.303462] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint570second # [ 0.283816] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned571test # [ 0.303579] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]572second # [ 0.283832] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned573test # [ 0.303590] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]574second # [ 0.283848] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned575test # [ 0.303610] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]576second # [ 0.283865] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned577test # [ 0.304042] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned578second # [ 0.283882] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned579test # [ 0.304054] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned580second # [ 0.283898] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned581test # [ 0.304059] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned582second # [ 0.283913] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned583test # [ 0.304095] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned584second # [ 0.283929] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned585test # [ 0.304126] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned586second # [ 0.283949] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned587test # [ 0.304157] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned588second # [ 0.283964] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned589second # [ 0.283980] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned590test # [ 0.304188] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned591second # [ 0.283996] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned592test # [ 0.304220] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned593second # [ 0.284011] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned594test # [ 0.304253] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned595second # [ 0.284027] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned596test # [ 0.304286] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned597second # [ 0.284050] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned598second # [ 0.284065] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned599test # [ 0.304318] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned600test # [ 0.304348] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned601test # [ 0.304416] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned602test # [ 0.304448] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned603second # [ 0.284080] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned604second # [ 0.284095] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned605second # [ 0.284110] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned606second # [ 0.284131] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]607test # [ 0.304462] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned608second # [ 0.284137] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]609test # [ 0.304477] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned610second # [ 0.284141] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]611test # [ 0.304493] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned612test # [ 0.304509] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned613test # [ 0.304524] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned614test # [ 0.304539] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned615test # [ 0.304555] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned616test # [ 0.304571] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned617test # [ 0.304586] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned618test # [ 0.304602] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned619test # [ 0.304619] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned620test # [ 0.304635] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned621test # [ 0.304652] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned622test # [ 0.304668] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned623test # [ 0.304684] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned624test # [ 0.304705] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned625test # [ 0.304721] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned626test # [ 0.304737] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned627second # [ 0.344989] pci 0000:00:0a.0: enabling device (0000 -> 0002)628test # [ 0.304752] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned629test # [ 0.304767] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned630test # [ 0.304788] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]631test # [ 0.304794] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]632test # [ 0.304798] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]633second # [ 0.351716] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)634test # [ 0.341609] pci 0000:00:0a.0: enabling device (0000 -> 0002)635test # [ 0.348572] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)636second # [ 0.361635] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)637second # [ 0.368525] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)638second # [ 0.370713] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)639test # [ 0.358722] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)640second # [ 0.376810] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)641second # [ 0.379596] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)642test # [ 0.365647] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)643test # [ 0.367662] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)644second # [ 0.389642] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)645test # [ 0.377946] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)646test # [ 0.380054] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)647second # [ 0.396897] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)648test # [ 0.382190] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)649second # [ 0.398779] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)650test # [ 0.384545] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)651test # [ 0.394646] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)652second # [ 0.408503] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)653test # [ 0.396695] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)654second # [ 0.410876] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)655second # [ 0.423129] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled656test # [ 0.407345] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)657second # [ 0.424864] msm_serial: driver initialized658second # [ 0.424967] SuperH (H)SCI(F) driver initialized659second # [ 0.425004] STM32 USART driver initialized660test # [ 0.417699] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled661test # [ 0.419456] msm_serial: driver initialized662test # [ 0.419538] SuperH (H)SCI(F) driver initialized663test # [ 0.419576] STM32 USART driver initialized664second # [ 0.450680] loop: module loaded665second # [ 0.450840] virtio_blk virtio5: 1/0/0 default/read/poll queues666second # [ 0.451512] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)667test # [ 0.440129] loop: module loaded668test # [ 0.440283] virtio_blk virtio5: 1/0/0 default/read/poll queues669test # [ 0.440918] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)670second # [ 0.456768] megasas: 07.734.00.00-rc1671second # [ 0.457311] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]672second # [ 0.482584] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000673second # [ 0.482622] Intel/Sharp Extended Query Table at 0x0031674second # [ 0.486217] Using buffer write method675second # [ 0.486295] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]676test # [ 0.469970] megasas: 07.734.00.00-rc1677test # [ 0.470503] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]678test # [ 0.472906] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000679test # [ 0.472933] Intel/Sharp Extended Query Table at 0x0031680test # [ 0.476650] Using buffer write method681test # [ 0.476806] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]682second # [ 0.492351] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000683second # [ 0.492383] Intel/Sharp Extended Query Table at 0x0031684second # [ 0.494464] Using buffer write method685second # [ 0.494506] Concatenating MTD devices:686second # [ 0.494510] (0): "0.flash"687second # [ 0.494514] (1): "0.flash"688second # [ 0.494517] into device "0.flash"689test # [ 0.481975] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000690test # [ 0.482006] Intel/Sharp Extended Query Table at 0x0031691test # [ 0.483820] Using buffer write method692test # [ 0.483861] Concatenating MTD devices:693test # [ 0.483865] (0): "0.flash"694test # [ 0.483868] (1): "0.flash"695test # [ 0.483871] into device "0.flash"696test # [ 0.695383] Freeing initrd memory: 24796K697test # [ 0.702506] tun: Universal TUN/TAP device driver, 1.6698test # [ 0.705356] thunder_xcv, ver 1.0699test # [ 0.705395] thunder_bgx, ver 1.0700test # [ 0.705407] nicpf, ver 1.0701second # [ 0.721408] Freeing initrd memory: 24808K702test # [ 0.706572] e1000: Intel(R) PRO/1000 Network Driver703test # [ 0.706588] e1000: Copyright (c) 1999-2006 Intel Corporation.704test # [ 0.706608] e1000e: Intel(R) PRO/1000 Network Driver705test # [ 0.706616] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.706test # [ 0.706629] igb: Intel(R) Gigabit Ethernet Network Driver707test # [ 0.706633] igb: Copyright (c) 2007-2014 Intel Corporation.708test # [ 0.706644] igbvf: Intel(R) Gigabit Virtual Function Network Driver709test # [ 0.706648] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.710test # [ 0.706713] sky2: driver version 1.30711test # [ 0.707548] usbcore: registered new interface driver usb-storage712test # [ 0.707645] usbcore: registered new interface driver usbserial_generic713test # [ 0.707653] usbserial: USB Serial support registered for generic714second # [ 0.728728] tun: Universal TUN/TAP device driver, 1.6715test # [ 0.707945] hv_vmbus: registering driver hyperv_keyboard716test # [ 0.709030] ehci-pci 0000:00:0a.0: EHCI Host Controller717test # [ 0.709071] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1718test # [ 0.709310] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000719second # [ 0.731540] thunder_xcv, ver 1.0720second # [ 0.731576] thunder_bgx, ver 1.0721test # [ 0.716871] rtc-pl031 9010000.pl031: registered as rtc0722second # [ 0.731588] nicpf, ver 1.0723second # [ 0.731912] e1000: Intel(R) PRO/1000 Network Driver724test # [ 0.716921] rtc-pl031 9010000.pl031: setting system clock to 2026-09-09T10:08:21 UTC (1788948501)725second # [ 0.731921] e1000: Copyright (c) 1999-2006 Intel Corporation.726test # [ 0.717176] i2c_dev: i2c /dev entries driver727second # [ 0.731935] e1000e: Intel(R) PRO/1000 Network Driver728test # [ 0.717483] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00729second # [ 0.731941] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.730test # [ 0.717906] hub 1-0:1.0: USB hub found731test # [ 0.717941] hub 1-0:1.0: 6 ports detected732second # [ 0.731954] igb: Intel(R) Gigabit Ethernet Network Driver733second # [ 0.731958] igb: Copyright (c) 2007-2014 Intel Corporation.734second # [ 0.731969] igbvf: Intel(R) Gigabit Virtual Function Network Driver735second # [ 0.731973] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.736second # [ 0.732037] sky2: driver version 1.30737test # [ 0.721951] sdhci: Secure Digital Host Controller Interface driver738test # [ 0.721966] sdhci: Copyright(c) Pierre Ossman739test # [ 0.722105] Synopsys Designware Multimedia Card Interface Driver740second # [ 0.737785] usbcore: registered new interface driver usb-storage741test # [ 0.722274] sdhci-pltfm: SDHCI platform and OF driver helper742test # [ 0.723235] hid: raw HID events driver (C) Jiri Kosina743second # [ 0.737888] usbcore: registered new interface driver usbserial_generic744test # [ 0.723381] usbcore: registered new interface driver usbhid745second # [ 0.737897] usbserial: USB Serial support registered for generic746test # [ 0.723385] usbhid: USB HID core driver747second # [ 0.738181] hv_vmbus: registering driver hyperv_keyboard748second # [ 0.739327] ehci-pci 0000:00:0a.0: EHCI Host Controller749second # [ 0.739365] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1750second # [ 0.739566] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000751test # [ 0.727060] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available752test # [ 0.727869] drop_monitor: Initializing network drop monitor service753test # [ 0.727999] NET: Registered PF_INET6 protocol family754second # [ 0.743244] rtc-pl031 9010000.pl031: registered as rtc0755second # [ 0.743292] rtc-pl031 9010000.pl031: setting system clock to 2026-09-09T10:08:21 UTC (1788948501)756test # [ 0.730481] Segment Routing with IPv6757second # [ 0.743549] i2c_dev: i2c /dev entries driver758test # [ 0.730509] In-situ OAM (IOAM) with IPv6759test # [ 0.730565] NET: Registered PF_PACKET protocol family760test # [ 0.731681] 9pnet: Installing 9P2000 support761test # [ 0.733402] Key type dns_resolver registered762second # [ 0.746463] sdhci: Secure Digital Host Controller Interface driver763second # [ 0.746483] sdhci: Copyright(c) Pierre Ossman764second # [ 0.746617] Synopsys Designware Multimedia Card Interface Driver765second # [ 0.746778] sdhci-pltfm: SDHCI platform and OF driver helper766second # [ 0.747792] hid: raw HID events driver (C) Jiri Kosina767second # [ 0.747945] usbcore: registered new interface driver usbhid768second # [ 0.747950] usbhid: USB HID core driver769second # [ 0.751387] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00770test # [ 0.736935] registered taskstats version 1771test # [ 0.737176] Loading compiled-in X.509 certificates772second # [ 0.752280] hub 1-0:1.0: USB hub found773second # [ 0.752674] hub 1-0:1.0: 6 ports detected774second # [ 0.753637] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available775second # [ 0.754425] drop_monitor: Initializing network drop monitor service776second # [ 0.754551] NET: Registered PF_INET6 protocol family777second # [ 0.756937] Segment Routing with IPv6778second # [ 0.756967] In-situ OAM (IOAM) with IPv6779test # [ 0.742704] Demotion targets for Node 0: null780second # [ 0.757025] NET: Registered PF_PACKET protocol family781test # [ 0.742788] Key type .fscrypt registered782test # [ 0.742795] Key type fscrypt-provisioning registered783test # [ 0.742913] ima: No TPM chip found, activating TPM-bypass!784test # [ 0.742927] ima: Allocated hash algorithm: sha1785test # [ 0.742981] ima: No architecture policies found786second # [ 0.758170] 9pnet: Installing 9P2000 support787second # [ 0.760190] Key type dns_resolver registered788test # [ 0.745857] input: gpio-keys as /devices/platform/gpio-keys/input/input0789second # [ 0.763889] registered taskstats version 1790second # [ 0.764068] Loading compiled-in X.509 certificates791second # [ 0.769531] Demotion targets for Node 0: null792second # [ 0.769620] Key type .fscrypt registered793second # [ 0.769625] Key type fscrypt-provisioning registered794second # [ 0.769738] ima: No TPM chip found, activating TPM-bypass!795second # [ 0.769752] ima: Allocated hash algorithm: sha1796test # [ 0.756743] clk: Disabling unused clocks797second # [ 0.769806] ima: No architecture policies found798test # [ 0.756770] PM: genpd: Disabling unused power domains799second # [ 0.772690] input: gpio-keys as /devices/platform/gpio-keys/input/input0800test # [ 0.760132] Freeing unused kernel memory: 4736K801test # [ 0.760235] Run /init as init process802second # [ 0.783831] clk: Disabling unused clocks803second # [ 0.783857] PM: genpd: Disabling unused power domains804second # [ 0.787244] Freeing unused kernel memory: 4736K805second # [ 0.787346] Run /init as init process806test # [ 0.780202] systemd[1]: Successfully made /usr/ read-only.807second # [ 0.810997] systemd[1]: Successfully made /usr/ read-only.808test # [ 0.965465] usb 1-1: new high-speed USB device number 2 using ehci-pci809second # [ 1.000360] usb 1-1: new high-speed USB device number 2 using ehci-pci810test # [ 1.112875] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1811test # [ 1.115121] systemd[1]: systemd 260.2 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)812test # [ 1.115152] systemd[1]: Detected virtualization qemu.813test # [ 1.115183] systemd[1]: Detected architecture arm64.814test # [ 1.115193] systemd[1]: Running in initrd.815test # [ 1.115859] systemd[1]: Initializing machine ID from random generator.816test # [ 1.116087] systemd[1]: Hostname set to <test>.817second # [ 1.145856] systemd[1]: systemd 260.2 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)818second # [ 1.153937] systemd[1]: Detected virtualization qemu.819second # [ 1.155105] systemd[1]: Detected architecture arm64.820second # [ 1.156776] systemd[1]: Running in initrd.821second # [ 1.159404] systemd[1]: Initializing machine ID from random generator.822second # [ 1.161719] systemd[1]: Hostname set to <second>.823second # [ 1.163958] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1824test # [ 1.197941] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0825test # [ 1.201383] systemd[1]: Queued start job for default target Initrd Default Target.826test # [ 1.210880] systemd[1]: Created slice Slice /system/modprobe.827second # [ 1.225368] systemd[1]: Queued start job for default target Initrd Default Target.828test # [ 1.211614] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.829test # [ 1.212328] systemd[1]: Expecting device /dev/disk/by-label/nixos...830test # [ 1.212888] systemd[1]: Reached target Path Units.831test # [ 1.213345] systemd[1]: Reached target Slice Units.832test # [ 1.213853] systemd[1]: Reached target Swaps.833test # [ 1.214258] systemd[1]: Reached target Timer Units.834test # [ 1.214872] systemd[1]: Listening on D-Bus System Message Bus Socket.835test # [ 1.215610] systemd[1]: Listening on Journal Socket (/dev/log).836test # [ 1.216285] systemd[1]: Listening on Journal Sockets.837test # [ 1.216864] systemd[1]: Listening on udev Control Socket.838test # [ 1.217773] systemd[1]: Listening on udev Kernel Socket.839test # [ 1.218373] systemd[1]: Reached target Socket Units.840second # [ 1.234038] systemd[1]: Created slice Slice /system/modprobe.841test # [ 1.220082] systemd[1]: Starting Create List of Static Device Nodes...842second # [ 1.234973] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.843second # [ 1.235886] systemd[1]: Expecting device /dev/disk/by-label/nixos...844second # [ 1.236660] systemd[1]: Reached target Path Units.845second # [ 1.236717] systemd[1]: Reached target Slice Units.846second # [ 1.236741] systemd[1]: Reached target Swaps.847second # [ 1.236759] systemd[1]: Reached target Timer Units.848test # [ 1.225912] systemd[1]: Starting Load Kernel Module 9pnet_virtio...849second # [ 1.236961] systemd[1]: Listening on D-Bus System Message Bus Socket.850second # [ 1.237136] systemd[1]: Listening on Journal Socket (/dev/log).851test # [ 1.226561] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs852second # [ 1.237292] systemd[1]: Listening on Journal Sockets.853second # [ 1.237398] systemd[1]: Listening on udev Control Socket.854second # [ 1.237493] systemd[1]: Listening on udev Kernel Socket.855second # [ 1.237518] systemd[1]: Reached target Socket Units.856second # [ 1.243536] systemd[1]: Starting Create List of Static Device Nodes...857second # [ 1.244507] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0858test # [ 1.232754] systemd[1]: Mounting Kernel Configuration File System...859second # [ 1.249380] systemd[1]: Starting Load Kernel Module 9pnet_virtio...860second # [ 1.250029] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs861test # [ 1.241581] systemd[1]: Starting Journal Service...862second # [ 1.259854] systemd[1]: Mounting Kernel Configuration File System...863test # [ 1.258295] systemd[1]: Starting Load Kernel Modules...864test # [ 1.258899] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki865second # [ 1.274205] systemd[1]: Starting Journal Service...866second # [ 1.287012] systemd[1]: Starting Load Kernel Modules...867second # [ 1.287077] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki868test # [ 1.274408] systemd[1]: Starting Coldplug All udev Devices...869test # [ 1.277573] systemd[1]: Finished Create List of Static Device Nodes.870second # [ 1.292474] systemd[1]: Starting Coldplug All udev Devices...871test # [ 1.279830] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.872test # [ 1.281751] systemd[1]: Finished Load Kernel Module 9pnet_virtio.873second # [ 1.296474] systemd[1]: Finished Create List of Static Device Nodes.874second # [ 1.297842] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.875test # [ 1.282524] systemd[1]: Mounted Kernel Configuration File System.876test # [ 1.283942] systemd-journald[73]: Collecting audit messages is disabled.877second # [ 1.300784] systemd[1]: Finished Load Kernel Module 9pnet_virtio.878second # [ 1.309077] systemd[1]: Mounted Kernel Configuration File System.879second # [ 1.316605] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...880test # [ 1.305649] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...881test # [ 1.317794] usb 1-2: new high-speed USB device number 3 using ehci-pci882second # [ 1.338192] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.883second # [ 1.345904] systemd-journald[73]: Collecting audit messages is disabled.884test # [ 1.332172] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.885second # [ 1.356420] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev886test # [ 1.341619] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.887second # [ 1.358413] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.888test # [ 1.343585] systemd[1]: Starting Create Static Device Nodes in /dev...889second # [ 1.364347] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0890second # [ 1.364567] [drm] features: -virgl +edid -resource_blob -host_visible891second # [ 1.364572] [drm] features: -context_init892second # [ 1.365069] [drm] number of scanouts: 1893second # [ 1.365086] [drm] number of cap sets: 0894second # [ 1.367384] usb 1-2: new high-speed USB device number 3 using ehci-pci895test # [ 1.358055] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev896second # [ 1.372822] systemd[1]: Starting Create Static Device Nodes in /dev...897test # [ 1.372602] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0898test # [ 1.372853] [drm] features: -virgl +edid -resource_blob -host_visible899test # [ 1.372859] [drm] features: -context_init900test # [ 1.373376] [drm] number of scanouts: 1901test # [ 1.373395] [drm] number of cap sets: 0902second # [ 1.388586] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic903test # [ 1.374664] systemd-modules-load[75]: Inserted module 'dm_mod'904test # [ 1.380888] systemd[1]: Started Journal Service.905second # [ 1.388606] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0906test # [ 1.382426] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic907test # [ 1.382444] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0908test # [ 1.383504] systemd-modules-load[75]: Module 'virtio_balloon' is built in909test # [ 1.388730] systemd-modules-load[75]: Module 'virtio_console' is built in910second # [ 1.411755] Console: switching to colour frame buffer device 160x50911test # [ 1.394115] systemd[1]: Finished Create Static Device Nodes in /dev.912test # [ 1.397189] systemd[1]: Reached target Preparation for Local File Systems.913second # [ 1.420880] systemd[1]: Finished Create Static Device Nodes in /dev.914second # [ 1.421087] systemd[1]: Reached target Preparation for Local File Systems.915second # [ 1.421114] systemd[1]: Reached target Local File Systems.916test # [ 1.405931] Console: switching to colour frame buffer device 160x50917second # [ 1.424573] systemd[1]: Starting Rule-based Manager for Device Events and Files...918test # [ 1.406463] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device919test # [ 1.407006] systemd[1]: Reached target Local File Systems.920test # [ 1.408162] systemd[1]: Starting Create System Files and Directories...921second # [ 1.428420] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device922test # [ 1.409357] systemd-modules-load[75]: Inserted module 'virtio_gpu'923test # [ 1.409977] systemd-modules-load[75]: Module 'virtio_rng' is built in924second # [ 1.435642] systemd[1]: Started Journal Service.925second # [ 1.433866] systemd-modules-load[74]: Inserted module 'dm_mod'926second # [ 1.435729] systemd-modules-load[74]: Module 'virtio_balloon' is built in927test # [ 1.429931] systemd[1]: Starting Rule-based Manager for Device Events and Files...928test # [ 1.430660] systemd[1]: Finished Load Kernel Modules.929second # [ 1.444100] systemd-modules-load[74]: Module 'virtio_console' is built in930second # [ 1.444799] systemd-modules-load[74]: Inserted module 'virtio_gpu'931second # [ 1.445416] systemd-modules-load[74]: Module 'virtio_rng' is built in932second # [ 1.446077] systemd[1]: Finished Load Kernel Modules.933second # [ 1.446625] systemd[1]: Starting Apply Kernel Variables...934second # [ 1.458199] systemd[1]: Starting Create System Files and Directories...935test # [ 1.448124] systemd[1]: Starting Apply Kernel Variables...936test # [ 1.462887] systemd[1]: Finished Create System Files and Directories.937test # [ 1.473130] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2938test # [ 1.475458] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0939second # [ 1.490160] systemd[1]: Finished Apply Kernel Variables.940test # [ 1.481059] systemd[1]: Finished Apply Kernel Variables.941second # [ 1.500533] systemd-udevd[81]: Using default interface naming scheme 'v260'.942second # [ 1.509485] systemd[1]: Finished Create System Files and Directories.943test # [ 1.499485] systemd-udevd[87]: Using default interface naming scheme 'v260'.944second # [ 1.520491] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2945second # [ 1.522384] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0946second # [ 1.532815] systemd[1]: Started Rule-based Manager for Device Events and Files.947test # [ 1.524185] systemd[1]: Started Rule-based Manager for Device Events and Files.948test # [ 1.592135] systemd[1]: Starting Virtual Console Setup...949second # [ 1.628183] systemd[1]: Starting Virtual Console Setup...950test # [ 1.641837] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.951test # [ 1.643047] systemd[1]: Finished Virtual Console Setup.952second # [ 1.680483] systemd-vconsole-setup[105]: Configuration of first virtual console was skipped, ignoring remaining ones.953second # [ 1.683165] systemd[1]: Finished Virtual Console Setup.954test # [ 1.896997] systemd[1]: Finished Coldplug All udev Devices.955test # [ 1.897748] systemd[1]: Reached target System Initialization.956test # [ 1.898276] systemd[1]: Reached target Basic System.957second # [ 1.932916] systemd[1]: Finished Coldplug All udev Devices.958second # [ 1.933532] systemd[1]: Reached target System Initialization.959second # [ 1.934011] systemd[1]: Reached target Basic System.960test # [ 2.003930] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.961second # [ 2.035015] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.962test # [ 2.029665] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.963second # [ 2.066570] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.964test # [ 2.083588] systemd[1]: Found device /dev/disk/by-label/nixos.965test # [ 2.084478] systemd[1]: Reached target Initrd Root Device.966test # [ 2.087023] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...967second # [ 2.109036] systemd[1]: Found device /dev/disk/by-label/nixos.968second # [ 2.112221] systemd[1]: Reached target Initrd Root Device.969second # [ 2.113783] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...970test # [ 2.135632] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks971test # [ 2.139387] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.972second # [ 2.161329] systemd-fsck[114]: nixos: clean, 12/65536 files, 13019/262144 blocks973second # [ 2.165713] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.974test # [ 2.276154] systemd[1]: Mounting /sysroot...975second # [ 2.287848] systemd[1]: Mounting /sysroot...976second # [ 2.322487] EXT4-fs (vda): mounted filesystem 43e3c57a-1be9-4327-9039-19acfe768f65 r/w with ordered data mode. Quota mode: none.977test # [ 2.308746] EXT4-fs (vda): mounted filesystem 355a78f4-3f73-4bde-9dd9-4469b868fc34 r/w with ordered data mode. Quota mode: none.978second # [ 2.319290] systemd[1]: Mounted /sysroot.979test # [ 2.308153] systemd[1]: Mounted /sysroot.980second # [ 2.320803] systemd[1]: Reached target Initrd Root File System.981test # [ 2.309740] systemd[1]: Reached target Initrd Root File System.982test # [ 2.312204] systemd[1]: Starting Mountpoints Configured in the Real Root...983second # [ 2.324809] systemd[1]: Starting Mountpoints Configured in the Real Root...984second # [ 2.346690] systemd-sysroot-fstab-check[122]: /sysroot should be mounted in the initrd, will request daemon-reload.985test # [ 2.335343] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.986test # [ 2.341044] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...987second # [ 2.352559] systemd[1]: Reload requested from client PID 122 ('systemd-sysroot') (unit initrd-parse-etc.service)...988second # [ 2.354732] systemd[1]: Reloading...989test # [ 2.343757] systemd[1]: Reloading...990second # [ 2.535286] systemd[1]: Reloading finished in 182 ms.991test # [ 2.531346] systemd[1]: Reloading finished in 188 ms.992second # [ 2.548131] systemd-sysroot-fstab-check[122]: Requesting initrd-fs.target/start/replace...993second # [ 2.551184] systemd-sysroot-fstab-check[122]: Requesting swap.target/start/replace...994test # [ 2.540555] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...995second # [ 2.553511] systemd[1]: Starting Load Kernel Module 9pnet_virtio...996test # [ 2.543084] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...997test # [ 2.544979] systemd[1]: Starting Load Kernel Module 9pnet_virtio...998second # [ 2.559860] systemd[1]: initrd-parse-etc.service: Deactivated successfully.999second # [ 2.561284] systemd[1]: Finished Mountpoints Configured in the Real Root.1000second # [ 2.562776] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1001test # [ 2.551695] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1002test # [ 2.553921] systemd[1]: Finished Mountpoints Configured in the Real Root.1003test # [ 2.555469] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1004second # [ 2.580731] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1005second # [ 2.581736] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1006test # [ 2.573179] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1007test # [ 2.573906] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1008second # [ 2.959870] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/mtd_probe": No such file or directory1009second # [ 2.964285] (udev-worker)[97]: mtd0ro: /nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1010second # [ 2.979749] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1011second # [ 2.981156] systemd[1]: Stopped Virtual Console Setup.1012second # [ 2.983987] systemd[1]: Stopping Virtual Console Setup...1013second # [ 2.987839] systemd[1]: Starting Virtual Console Setup...1014test # [ 2.975497] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/mtd_probe": No such file or directory1015test # [ 2.979358] (udev-worker)[94]: mtd0ro: /nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1016second # [ 3.000782] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1017second # [ 3.002275] systemd[1]: Stopped Virtual Console Setup.1018second # [ 3.003027] systemd[1]: Starting Virtual Console Setup...1019test # [ 2.992609] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1020test # [ 2.994337] systemd[1]: Stopped Virtual Console Setup.1021test # [ 2.996397] systemd[1]: Stopping Virtual Console Setup...1022test # [ 3.000188] systemd[1]: Starting Virtual Console Setup...1023second # [ 3.018817] systemd-vconsole-setup[145]: Configuration of first virtual console was skipped, ignoring remaining ones.1024second # [ 3.021463] systemd[1]: Finished Virtual Console Setup.1025test # [ 3.027686] systemd-vconsole-setup[142]: Configuration of first virtual console was skipped, ignoring remaining ones.1026test # [ 3.030466] systemd[1]: Finished Virtual Console Setup.1027test # [ 3.272693] systemd[1]: Mounting /sysroot/nix/.ro-store...1028second # [ 3.288396] systemd[1]: Mounting /sysroot/nix/.ro-store...1029test # [ 3.280159] systemd[1]: Mounting /sysroot/nix/.rw-store...1030second # [ 3.296094] systemd[1]: Mounting /sysroot/nix/.rw-store...1031second # [ 3.297069] systemd[1]: Mounting /sysroot/run...1032test # [ 3.285027] systemd[1]: Mounting /sysroot/run...1033test # [ 3.288173] systemd[1]: Mounting /sysroot/tmp/shared...1034second # [ 3.304805] systemd[1]: Mounting /sysroot/tmp/shared...1035second # [ 3.315407] systemd[1]: Mounting /sysroot/tmp/xchg...1036second # [ 3.316873] systemd[1]: Mounted /sysroot/nix/.rw-store.1037test # [ 3.307606] systemd[1]: Mounting /sysroot/tmp/xchg...1038test # [ 3.312836] systemd[1]: Mounted /sysroot/nix/.rw-store.1039test # [ 3.314053] systemd[1]: Mounted /sysroot/run.1040second # [ 3.331538] systemd[1]: Starting rw-sysroot-nix-store.service...1041test # [ 3.322093] systemd[1]: Starting rw-sysroot-nix-store.service...1042test # [ 3.322732] systemd[1]: Mounted /sysroot/nix/.ro-store.1043second # [ 3.336788] systemd[1]: Mounted /sysroot/run.1044test # [ 3.325502] systemd[1]: Mounted /sysroot/tmp/shared.1045second # [ 3.346176] systemd[1]: Mounted /sysroot/nix/.ro-store.1046second # [ 3.346744] systemd[1]: Mounted /sysroot/tmp/shared.1047test # [ 3.338824] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1048second # [ 3.351763] systemd[1]: Mounted /sysroot/tmp/xchg.1049test # [ 3.340719] systemd[1]: Finished rw-sysroot-nix-store.service.1050second # [ 3.352928] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1051second # [ 3.355395] systemd[1]: Finished rw-sysroot-nix-store.service.1052test # [ 3.343728] systemd[1]: Mounted /sysroot/tmp/xchg.1053second # [ 3.359758] systemd[1]: Mounting /sysroot/nix/store...1054test # [ 3.348372] systemd[1]: Mounting /sysroot/nix/store...1055test # [ 3.371700] systemd[1]: Mounted /sysroot/nix/store.1056test # [ 3.373306] systemd[1]: Reached target Initrd File Systems.1057test # [ 3.374121] systemd[1]: Starting Find NixOS closure...1058second # [ 3.383005] systemd[1]: Mounted /sysroot/nix/store.1059second # [ 3.383735] systemd[1]: Reached target Initrd File Systems.1060second # [ 3.385128] systemd[1]: Starting Find NixOS closure...1061second # [ 3.391367] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1062test # [ 3.379576] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1063second # [ 3.410650] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1064test # [ 3.404286] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1065second # [ 3.422872] systemd[1]: Finished Find NixOS closure.1066second # [ 3.423450] systemd[1]: Reached target Initrd Default Target.1067second # [ 3.424172] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1068test # [ 3.414638] systemd[1]: Finished Find NixOS closure.1069test # [ 3.415786] systemd[1]: Reached target Initrd Default Target.1070test # [ 3.417792] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1071test # [ 3.434993] systemd[1]: Stopped target Initrd Default Target.1072test # [ 3.436170] systemd[1]: Stopped target Basic System.1073second # [ 3.447458] systemd[1]: Stopped target Initrd Default Target.1074second # [ 3.448559] systemd[1]: Stopped target Basic System.1075second # [ 3.449314] systemd[1]: Stopped target Initrd Root Device.1076test # [ 3.439551] systemd[1]: Stopped target Initrd Root Device.1077second # [ 3.452230] systemd[1]: Stopped target Path Units.1078second # [ 3.452766] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1079second # [ 3.453451] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1080second # [ 3.454266] systemd[1]: Stopped target Slice Units.1081test # [ 3.440852] systemd[1]: Stopped target Path Units.1082test # [ 3.441320] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1083test # [ 3.442273] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1084second # [ 3.455384] systemd[1]: Stopped target Socket Units.1085test # [ 3.444116] systemd[1]: Stopped target Slice Units.1086test # [ 3.444603] systemd[1]: Stopped target Socket Units.1087second # [ 3.456273] systemd[1]: Stopped target System Initialization.1088test # [ 3.445652] systemd[1]: Stopped target System Initialization.1089test # [ 3.448164] systemd[1]: Stopped target Swaps.1090second # [ 3.460160] systemd[1]: Stopped target Swaps.1091test # [ 3.448639] systemd[1]: Stopped target Timer Units.1092second # [ 3.460637] systemd[1]: Stopped target Timer Units.1093second # [ 3.461120] systemd[1]: dbus.socket: Deactivated successfully.1094test # [ 3.449142] systemd[1]: dbus.socket: Deactivated successfully.1095test # [ 3.449903] systemd[1]: Closed D-Bus System Message Bus Socket.1096second # [ 3.461641] systemd[1]: Closed D-Bus System Message Bus Socket.1097second # [ 3.462149] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1098second # [ 3.462750] systemd[1]: Stopped Find NixOS closure.1099test # [ 3.451154] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1100second # [ 3.464157] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1101test # [ 3.452760] systemd[1]: Stopped Find NixOS closure.1102second # [ 3.465195] systemd[1]: Starting rw-sysroot-nix-store.service...1103second # [ 3.466335] systemd[1]: systemd-sysctl.service: Deactivated successfully.1104test # [ 3.455722] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1105test # [ 3.456456] systemd[1]: Starting rw-sysroot-nix-store.service...1106test # [ 3.457011] systemd[1]: systemd-sysctl.service: Deactivated successfully.1107second # [ 3.468759] systemd[1]: Stopped Apply Kernel Variables.1108test # [ 3.457620] systemd[1]: Stopped Apply Kernel Variables.1109test # [ 3.458652] systemd[1]: systemd-modules-load.service: Deactivated successfully.1110second # [ 3.469571] systemd[1]: systemd-modules-load.service: Deactivated successfully.1111test # [ 3.461122] systemd[1]: Stopped Load Kernel Modules.1112second # [ 3.472959] systemd[1]: Stopped Load Kernel Modules.1113second # [ 3.473463] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1114test # [ 3.464151] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1115second # [ 3.476427] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1116second # [ 3.478133] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1117test # [ 3.466469] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1118test # [ 3.468175] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1119test # [ 3.469568] systemd[1]: Stopped Create System Files and Directories.1120second # [ 3.482759] systemd[1]: Stopped Create System Files and Directories.1121test # [ 3.473681] systemd[1]: Stopped target Local File Systems.1122test # [ 3.474235] systemd[1]: Stopped target Preparation for Local File Systems.1123second # [ 3.483435] systemd[1]: Stopped target Local File Systems.1124second # [ 3.484382] systemd[1]: Stopped target Preparation for Local File Systems.1125second # [ 3.485413] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1126test # [ 3.475361] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1127second # [ 3.489411] systemd[1]: Stopped Coldplug All udev Devices.1128second # [ 3.489924] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1129test # [ 3.478692] systemd[1]: Stopped Coldplug All udev Devices.1130second # [ 3.490585] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1131test # [ 3.479369] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1132second # [ 3.491727] systemd[1]: Stopped Virtual Console Setup.1133test # [ 3.480870] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1134second # [ 3.492740] systemd[1]: systemd-udevd.service: Deactivated successfully.1135test # [ 3.482468] systemd[1]: Stopped Virtual Console Setup.1136test # [ 3.483045] systemd[1]: systemd-udevd.service: Deactivated successfully.1137test # [ 3.488207] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1138second # [ 3.500342] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1139second # [ 3.501135] systemd[1]: initrd-cleanup.service: Deactivated successfully.1140second # [ 3.501749] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1141test # [ 3.491896] systemd[1]: systemd-udevd.service: Consumed 1.018s CPU time over 2.053s wall clock time, 21.4M memory peak.1142second # [ 3.505404] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1143test # [ 3.494528] systemd[1]: initrd-cleanup.service: Deactivated successfully.1144test # [ 3.495238] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1145test # [ 3.495887] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1146second # [ 3.507942] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1147second # [ 3.510903] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1148test # [ 3.499657] systemd[1]: Finished rw-sysroot-nix-store.service.1149test # [ 3.500671] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1150second # [ 3.513345] systemd[1]: Finished rw-sysroot-nix-store.service.1151second # [ 3.513907] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1152test # [ 3.502619] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1153second # [ 3.514723] systemd[1]: Closed udev Control Socket.1154test # [ 3.503380] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1155test # [ 3.504710] systemd[1]: Closed udev Control Socket.1156second # [ 3.516413] systemd[1]: Starting Cleanup udev Database...1157second # [ 3.517388] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1158test # [ 3.508144] systemd[1]: Starting Cleanup udev Database...1159second # [ 3.520132] systemd[1]: Stopped Create Static Device Nodes in /dev.1160test # [ 3.508677] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1161test # [ 3.509338] systemd[1]: Stopped Create Static Device Nodes in /dev.1162second # [ 3.520711] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1163second # [ 3.521415] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1164test # [ 3.509879] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1165test # [ 3.510556] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1166second # [ 3.522725] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1167second # [ 3.523789] systemd[1]: Stopped Create List of Static Device Nodes.1168test # [ 3.511285] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1169test # [ 3.511899] systemd[1]: Stopped Create List of Static Device Nodes.1170test # [ 3.537132] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1171second # [ 3.549596] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1172second # [ 3.550311] systemd[1]: Finished Cleanup udev Database.1173test # [ 3.539058] systemd[1]: Finished Cleanup udev Database.1174second # [ 3.550778] systemd[1]: Reached target Switch Root.1175test # [ 3.539613] systemd[1]: Reached target Switch Root.1176second # [ 3.552904] systemd[1]: Starting NixOS Activation...1177test # [ 3.541407] systemd[1]: Starting NixOS Activation...1178second # [ 3.715542] initrd-nixos-activation-start[182]: booting system configuration /nix/store/vbfr23sxzxnjwxlqvyj53yhd04zsa5ns-nixos-system-second-test1179test # [ 3.708658] initrd-nixos-activation-start[179]: booting system configuration /nix/store/a8zqafllrxvslrfi9km4zjssyjb4s67x-nixos-system-test-test1180second # [ 3.765815] initrd-nixos-activation-start[182]: running activation script...1181test # [ 3.774416] initrd-nixos-activation-start[179]: running activation script...1182second # [ 4.129333] initrd-nixos-activation-start[205]: setting up /etc...1183test # [ 4.132254] initrd-nixos-activation-start[202]: setting up /etc...1184second # [ 4.306107] initrd-nixos-activation-start[205]: setting up age secrets...1185test # [ 4.315778] initrd-nixos-activation-start[202]: setting up age secrets...1186second # [ 4.470222] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1187second # [ 4.472156] systemd[1]: Finished NixOS Activation.1188second # [ 4.472970] systemd[1]: Starting Switch Root...1189second # [ 4.488812] systemd[1]: Switching root.1190second # [ 4.544766] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1191test # [ 4.569201] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1192test # [ 4.570994] systemd[1]: Finished NixOS Activation.1193test # [ 4.575289] systemd[1]: Starting Switch Root...1194test # [ 4.589594] systemd[1]: Switching root.1195test # [ 4.641936] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1196second # [ 5.138437] systemd[1]: systemd 260.2 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)1197second # [ 5.141552] systemd[1]: Detected virtualization qemu.1198second # [ 5.142624] systemd[1]: Detected architecture arm64.1199second # [ 5.143678] systemd[1]: Detected first boot.1200second # [ 5.148198] systemd[1]: Initializing machine ID from random generator.1201test # [ 5.167448] systemd[1]: systemd 260.2 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)1202test # [ 5.170714] systemd[1]: Detected virtualization qemu.1203test # [ 5.171672] systemd[1]: Detected architecture arm64.1204test # [ 5.173039] systemd[1]: Detected first boot.1205test # [ 5.176501] systemd[1]: Initializing machine ID from random generator.1206second # [ 5.380621] systemd[1]: bpf-restrict-fs: LSM BPF program attached1207test # [ 5.412235] systemd[1]: bpf-restrict-fs: LSM BPF program attached1208second # [ 5.539776] systemd[1]: Applying preset policy.1209test # [ 5.572051] systemd[1]: Applying preset policy.1210second # [ 5.959319] systemd[1]: Populated /etc with preset unit settings.1211test # [ 5.976481] systemd[1]: Populated /etc with preset unit settings.1212second # [ 6.423102] systemd[1]: initrd-switch-root.service: Deactivated successfully.1213second # [ 6.424632] systemd[1]: Stopped initrd-switch-root.service.1214second # [ 6.426743] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1215second # [ 6.428792] systemd[1]: Created slice Slice /system/getty.1216second # [ 6.429859] systemd[1]: Created slice User and Session Slice.1217second # [ 6.430904] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1218second # [ 6.432129] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1219second # [ 6.433741] systemd[1]: Expecting device /dev/hvc0...1220second # [ 6.435021] systemd[1]: Expecting device /dev/ttyAMA0...1221second # [ 6.436561] systemd[1]: Reached target Local Encrypted Volumes.1222second # [ 6.437940] systemd[1]: Stopped target initrd-fs.target.1223second # [ 6.438912] systemd[1]: Stopped target initrd-root-fs.target.1224second # [ 6.439879] systemd[1]: Stopped target initrd-switch-root.target.1225second # [ 6.441041] systemd[1]: Reached target Virtual Machines and Containers.1226second # [ 6.442099] systemd[1]: Reached target Path Units.1227second # [ 6.442978] systemd[1]: Reached target Remote File Systems.1228second # [ 6.443914] systemd[1]: Reached target Slice Units.1229second # [ 6.444813] systemd[1]: Reached target Swaps.1230test # [ 6.433234] systemd[1]: initrd-switch-root.service: Deactivated successfully.1231test # [ 6.434586] systemd[1]: Stopped initrd-switch-root.service.1232second # [ 6.450026] systemd[1]: Listening on Process Core Dump Socket.1233test # [ 6.436480] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1234second # [ 6.453195] systemd[1]: Listening on Credential Encryption/Decryption.1235test # [ 6.439277] systemd[1]: Created slice Slice /system/getty.1236test # [ 6.441064] systemd[1]: Created slice User and Session Slice.1237test # [ 6.442519] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1238second # [ 6.457597] systemd[1]: Starting Journal Log Access Socket...1239test # [ 6.443875] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1240second # [ 6.459446] systemd[1]: Listening on Journal Audit Socket.1241test # [ 6.445154] systemd[1]: Expecting device /dev/hvc0...1242test # [ 6.446303] systemd[1]: Expecting device /dev/ttyAMA0...1243second # [ 6.460704] systemd[1]: Listening on Network Management Metrics Varlink Socket.1244test # [ 6.447325] systemd[1]: Reached target Local Encrypted Volumes.1245test # [ 6.448364] systemd[1]: Stopped target initrd-fs.target.1246second # [ 6.462335] systemd[1]: Listening on Network Management Netlink Socket.1247test # [ 6.449573] systemd[1]: Stopped target initrd-root-fs.target.1248second # [ 6.464131] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1249test # [ 6.450630] systemd[1]: Stopped target initrd-switch-root.target.1250test # [ 6.451734] systemd[1]: Reached target Virtual Machines and Containers.1251second # [ 6.466002] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1252test # [ 6.453268] systemd[1]: Reached target Path Units.1253test # [ 6.454654] systemd[1]: Reached target Remote File Systems.1254test # [ 6.455653] systemd[1]: Reached target Slice Units.1255second # [ 6.467950] systemd[1]: Listening on udev Control Socket.1256test # [ 6.456623] systemd[1]: Reached target Swaps.1257second # [ 6.472519] systemd[1]: Mounting Huge Pages File System...1258test # [ 6.461029] systemd[1]: Listening on Process Core Dump Socket.1259second # [ 6.476427] systemd[1]: Mounting POSIX Message Queue File System...1260test # [ 6.464580] systemd[1]: Listening on Credential Encryption/Decryption.1261second # [ 6.482345] systemd[1]: Mounting Kernel Debug File System...1262test # [ 6.470003] systemd[1]: Starting Journal Log Access Socket...1263test # [ 6.471478] systemd[1]: Listening on Journal Audit Socket.1264test # [ 6.473160] systemd[1]: Listening on Network Management Metrics Varlink Socket.1265test # [ 6.474986] systemd[1]: Listening on Network Management Netlink Socket.1266second # [ 6.490330] systemd[1]: Mounting Kernel Trace File System...1267test # [ 6.477094] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1268test # [ 6.478879] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1269test # [ 6.481545] systemd[1]: Listening on udev Control Socket.1270test # [ 6.485398] systemd[1]: Mounting Huge Pages File System...1271second # [ 6.500855] systemd[1]: Starting Create List of Static Device Nodes...1272test # [ 6.489595] systemd[1]: Mounting POSIX Message Queue File System...1273second # [ 6.508546] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1274test # [ 6.495173] systemd[1]: Mounting Kernel Debug File System...1275second # [ 6.510852] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1276test # [ 6.504394] systemd[1]: Mounting Kernel Trace File System...1277second # [ 6.523565] systemd[1]: Mounting Kernel Configuration File System...1278second # [ 6.525663] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1279second # [ 6.529613] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1280test # [ 6.517647] systemd[1]: Starting Create List of Static Device Nodes...1281test # [ 6.530116] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1282test # [ 6.531498] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1283second # [ 6.547551] systemd[1]: Starting Load Kernel Module fuse...1284second # [ 6.549447] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671285test # [ 6.544349] systemd[1]: Mounting Kernel Configuration File System...1286test # [ 6.545645] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1287test # [ 6.547929] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1288test # [ 6.564084] systemd[1]: Starting Load Kernel Module fuse...1289test # [ 6.565954] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671290second # [ 6.603224] systemd[1]: Starting Journal Service...1291test # [ 6.609738] systemd[1]: Starting Journal Service...1292second # [ 6.624738] systemd[1]: Starting Load Kernel Modules...1293second # [ 6.649824] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1294test # [ 6.638980] systemd[1]: Starting Load Kernel Modules...1295test # [ 6.656602] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1296second # [ 6.676843] systemd[1]: Starting Remount Root and Kernel File Systems...1297second # [ 6.681200] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1298test # [ 6.679743] systemd[1]: Starting Remount Root and Kernel File Systems...1299test # [ 6.683509] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1300second # [ 6.713223] systemd[1]: Starting Coldplug All udev Devices...1301second # [ 6.715341] systemd[1]: Listening on Journal Log Access Socket.1302second # [ 6.715878] systemd[1]: Mounted Huge Pages File System.1303second # [ 6.726590] fuse: init (API version 7.45)1304second # [ 6.729024] systemd[1]: Mounted POSIX Message Queue File System.1305test # [ 6.718020] systemd[1]: Starting Coldplug All udev Devices...1306second # [ 6.733836] systemd[1]: Mounted Kernel Debug File System.1307second # [ 6.737222] systemd[1]: Mounted Kernel Trace File System.1308test # [ 6.731704] fuse: init (API version 7.45)1309test # [ 6.735963] systemd-journald[323]: Collecting audit messages is enabled.1310test # [ 6.743995] systemd[1]: Queued start job for default target Multi-User System.1311second # [ 6.763336] systemd[1]: Finished Create List of Static Device Nodes.1312test # [ 6.746592] systemd[1]: systemd-journald.service: Deactivated successfully.1313second # [ 6.767905] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1314test # [ 6.756644] systemd[1]: Started Journal Service.1315test # [ 6.754370] systemd-modules-load[324]: Module 'atkbd' is built in1316second # [ 6.773439] systemd-journald[305]: Collecting audit messages is enabled.1317test # [ 6.757442] systemd-modules-load[324]: Module 'ctr' is built in1318second # [ 6.780778] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1319test # [ 6.762654] systemd-modules-load[324]: Module 'loop' is built in1320test # [ 6.763980] systemd[1]: Listening on Journal Log Access Socket.1321second # [ 6.783080] systemd[1]: Mounted Kernel Configuration File System.1322second # [ 6.789508] EXT4-fs (vda): re-mounted 43e3c57a-1be9-4327-9039-19acfe768f65.1323second # [ 6.791688] systemd[1]: modprobe@fuse.service: Deactivated successfully.1324test # [ 6.773031] systemd[1]: Mounted Huge Pages File System.1325test # [ 6.773678] systemd[1]: Mounted POSIX Message Queue File System.1326test # [ 6.774323] systemd[1]: Mounted Kernel Debug File System.1327test # [ 6.774936] systemd[1]: Mounted Kernel Trace File System.1328test # [ 6.775551] systemd[1]: Finished Create List of Static Device Nodes.1329second # [ 6.788670] systemd[1]: Queued start job for default target Multi-User System.1330second # [ 6.789491] systemd[1]: systemd-journald.service: Deactivated successfully.1331second # [ 6.798954] systemd[1]: Finished Load Kernel Module fuse.1332second # [ 6.800065] systemd[1]: Started Journal Service.1333test # [ 6.782460] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1334second # [ 6.794494] systemd-modules-load[306]: Module 'atkbd' is built in1335second # [ 6.798343] systemd-modules-load[306]: Module 'ctr' is built in1336test # [ 6.788709] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1337second # [ 6.800964] systemd-modules-load[306]: Module 'loop' is built in1338second # [ 6.805410] systemd[1]: Finished Load Kernel Modules.1339test # [ 6.793860] systemd[1]: Mounted Kernel Configuration File System.1340test # [ 6.795328] systemd[1]: modprobe@fuse.service: Deactivated successfully.1341second # [ 6.807825] systemd[1]: Finished Remount Root and Kernel File Systems.1342test # [ 6.801452] systemd[1]: Finished Load Kernel Module fuse.1343test # [ 6.804141] systemd[1]: Finished Load Kernel Modules.1344test # [ 6.807455] systemd[1]: Mounting FUSE Control File System...1345test # [ 6.811743] systemd[1]: Starting Firewall...1346second # [ 6.826468] systemd[1]: Mounting FUSE Control File System...1347second # [ 6.830268] systemd[1]: Starting Firewall...1348test # [ 6.821709] systemd[1]: Starting Apply Kernel Variables...1349second # [ 6.833307] systemd[1]: Starting Flush Journal to Persistent Storage...1350second # [ 6.836254] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1351test # [ 6.841611] EXT4-fs (vda): re-mounted 355a78f4-3f73-4bde-9dd9-4469b868fc34.1352test # [ 6.844445] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1353second # [ 6.856830] systemd[1]: Starting Load/Save OS Random Seed...1354second # [ 6.858438] systemd-oomd[307]: No swap; memory pressure usage will be degraded1355test # [ 6.860653] systemd[1]: Finished Remount Root and Kernel File Systems.1356second # [ 6.877939] systemd[1]: Starting Apply Kernel Variables...1357test # [ 6.877045] systemd-oomd[326]: No swap; memory pressure usage will be degraded1358second # [ 6.895232] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1359second # [ 6.897739] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1360second # [ 6.902407] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1361test # [ 6.894268] systemd[1]: Starting Flush Journal to Persistent Storage...1362test # [ 6.894983] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1363test # [ 6.911385] systemd[1]: Starting Load/Save OS Random Seed...1364test # [ 6.913814] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1365test # [ 6.921564] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1366second # [ 6.957114] systemd-journald[305]: Received client request to flush runtime journal.1367test # [ 7.011166] systemd[1]: Finished Apply Kernel Variables.1368test # [ 7.038132] systemd[1]: Starting Network Name Resolution...1369second # [ 7.051359] systemd[1]: Mounted FUSE Control File System.1370test # [ 7.041133] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1371second # [ 7.055477] systemd[1]: Finished Load/Save OS Random Seed.1372test # [ 7.049034] systemd-journald[323]: Received client request to flush runtime journal.1373second # [ 7.067322] systemd[1]: Reached target First Boot Complete.1374second # [ 7.073350] systemd[1]: Finished Flush Journal to Persistent Storage.1375second # [ 7.076096] systemd[1]: Finished Apply Kernel Variables.1376second # [ 7.087246] systemd[1]: Starting Network Name Resolution...1377second # [ 7.098474] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1378second # [ 7.123478] systemd[1]: Starting Create Static Device Nodes in /dev...1379test # [ 7.126044] systemd[1]: Mounted FUSE Control File System.1380test # [ 7.133525] systemd[1]: Finished Load/Save OS Random Seed.1381test # [ 7.138522] systemd[1]: Reached target First Boot Complete.1382test # [ 7.148183] systemd[1]: Starting Create Static Device Nodes in /dev...1383test # [ 7.150133] systemd[1]: Finished Flush Journal to Persistent Storage.1384second # [ 7.237444] systemd[1]: Finished Create Static Device Nodes in /dev.1385second # [ 7.238439] systemd[1]: Reached target Preparation for Local File Systems.1386test # [ 7.231550] systemd[1]: Finished Create Static Device Nodes in /dev.1387test # [ 7.233247] systemd[1]: Reached target Preparation for Local File Systems.1388second # [ 7.246362] systemd[1]: Starting Rule-based Manager for Device Events and Files...1389test # [ 7.237652] systemd[1]: Starting Rule-based Manager for Device Events and Files...1390test # [ 7.261676] systemd-resolved[351]: Positive Trust Anchors:1391test # [ 7.262917] systemd-resolved[351]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1392test # [ 7.268171] systemd-resolved[351]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161393second # [ 7.281524] systemd-resolved[339]: Positive Trust Anchors:1394second # [ 7.284956] systemd-resolved[339]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1395second # [ 7.292118] systemd-resolved[339]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161396test # [ 7.269433] systemd-resolved[351]: 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 test1397second # [ 7.294969] systemd-resolved[339]: 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 test1398second # [ 7.381161] systemd-udevd[351]: Using default interface naming scheme 'v260'.1399test # [ 7.385812] systemd-udevd[364]: Using default interface naming scheme 'v260'.1400second # [ 7.424629] systemd[1]: Mounting /run/wrappers...1401test # [ 7.440194] systemd[1]: Mounting /run/wrappers...1402second # [ 7.506345] systemd[1]: Mounted /run/wrappers.1403second # [ 7.510064] systemd[1]: Reached target Local File Systems.1404second # [ 7.514676] systemd[1]: Listening on Boot Loader Control Service Socket.1405test # [ 7.512291] systemd[1]: Mounted /run/wrappers.1406second # [ 7.519999] systemd[1]: Starting register-nix-paths.service...1407test # [ 7.513016] systemd[1]: Reached target Local File Systems.1408test # [ 7.517431] systemd[1]: Listening on Boot Loader Control Service Socket.1409second # [ 7.532145] systemd[1]: Starting Create SUID/SGID Wrappers...1410second # [ 7.532767] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1411second # [ 7.539660] systemd[1]: Starting Save Transient machine-id to Disk...1412test # [ 7.522794] systemd[1]: Starting register-nix-paths.service...1413test # [ 7.532600] systemd[1]: Starting Create SUID/SGID Wrappers...1414test # [ 7.533475] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1415test # [ 7.542791] systemd-resolved[351]: Using system hostname 'test'.1416test # [ 7.553553] systemd[1]: Starting Save Transient machine-id to Disk...1417second # [ 7.572185] systemd-resolved[339]: Using system hostname 'second'.1418second # [ 7.577665] systemd[1]: Starting Create System Files and Directories...1419second # [ 7.581588] systemd[1]: Started Network Name Resolution.1420test # [ 7.570897] systemd[1]: Starting Create System Files and Directories...1421test # [ 7.576236] systemd[1]: Started Network Name Resolution.1422second # [ 7.720310] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1423second # [ 7.731868] systemd[1]: Finished Save Transient machine-id to Disk.1424test # [ 7.727569] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1425test # [ 7.739968] systemd[1]: Finished Save Transient machine-id to Disk.1426second # [ 7.805916] systemd[1]: Finished Create System Files and Directories.1427test # [ 7.802790] systemd[1]: Finished Create System Files and Directories.1428test # [ 7.813612] systemd[1]: Starting Rebuild Journal Catalog...1429second # [ 7.828182] systemd[1]: Starting Rebuild Journal Catalog...1430test # [ 7.820292] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1431second # [ 7.832554] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1432second # [ 7.834647] systemd[1]: Started Rule-based Manager for Device Events and Files.1433test # [ 7.858371] systemd[1]: Started Rule-based Manager for Device Events and Files.1434second # [ 7.984837] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1435test # [ 7.980780] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1436second # [ 8.062331] systemd[1]: Finished Rebuild Journal Catalog.1437test # [ 8.064264] systemd[1]: Finished Rebuild Journal Catalog.1438second # [ 8.078244] systemd[1]: Starting Update is Completed...1439test # [ 8.070686] systemd[1]: Starting Update is Completed...1440second # [ 8.173481] systemd[1]: Finished Update is Completed.1441test # [ 8.164873] systemd[1]: Finished Update is Completed.1442second # [ 8.192703] systemd[1]: Finished Coldplug All udev Devices.1443test # [ 8.256187] systemd[1]: Finished Coldplug All udev Devices.1444second # [ 8.438724] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1445test # [ 8.508798] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1446second # [ 8.644122] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1447second # [ 8.645614] systemd[1]: Finished Create SUID/SGID Wrappers.1448second # [ 8.719541] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1449test # [ 8.733177] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1450test # [ 8.737048] systemd[1]: Finished Create SUID/SGID Wrappers.1451second # [ 8.759575] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1452second # [ 8.799475] systemd[1]: Finished Firewall.1453second # [ 8.800097] systemd[1]: Reached target Preparation for Network.1454second # [ 8.808584] systemd[1]: Starting Network Management...1455test # [ 8.834641] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1456test # [ 8.872312] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1457test # [ 8.916744] systemd[1]: Finished Firewall.1458test # [ 8.920165] systemd[1]: Reached target Preparation for Network.1459test # [ 8.925228] systemd[1]: Starting Network Management...1460second # [ 8.963994] systemd[1]: Finished register-nix-paths.service.1461second # [ 8.964680] systemd[1]: Reached target System Initialization.1462second # [ 8.967966] systemd[1]: Started Discard unused filesystem blocks once a week.1463test # [ 8.958010] systemd[1]: Finished register-nix-paths.service.1464second # [ 8.970654] systemd[1]: Started Daily Cleanup of Temporary Directories.1465test # [ 8.960263] systemd[1]: Reached target System Initialization.1466test # [ 8.961636] systemd[1]: Started Discard unused filesystem blocks once a week.1467second # [ 8.974204] systemd[1]: Reached target Timer Units.1468second # [ 8.975403] systemd[1]: Listening on D-Bus System Message Bus Socket.1469test # [ 8.965668] systemd[1]: Started Daily Cleanup of Temporary Directories.1470second # [ 8.977831] systemd[1]: Listening on Nix Daemon Socket.1471test # [ 8.967950] systemd[1]: Reached target Timer Units.1472test # [ 8.972769] systemd[1]: Listening on D-Bus System Message Bus Socket.1473second # [ 8.983656] systemd[1]: Listening on Authorization Manager Agent Helper.1474second # [ 8.984390] systemd[1]: Listening on Hostname Service Socket.1475second # [ 8.984888] systemd[1]: Reached target Socket Units.1476test # [ 8.973418] systemd[1]: Listening on Nix Daemon Socket.1477test # [ 8.973905] systemd[1]: Listening on Authorization Manager Agent Helper.1478test # [ 8.974521] systemd[1]: Listening on Hostname Service Socket.1479test # [ 8.975060] systemd[1]: Reached target Socket Units.1480second # [ 8.990984] systemd[1]: Starting D-Bus System Message Bus...1481test # [ 8.981168] systemd[1]: Starting D-Bus System Message Bus...1482second # [ 9.053206] cfg80211: Loading compiled-in X.509 certificates for regulatory database1483second # [ 9.085864] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1484second # [ 9.086385] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1485second # [ 9.131786] systemd-networkd[537]: /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.1486second # [ 9.139519] systemd-networkd[537]: /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.1487second # [ 9.149355] systemd-networkd[537]: lo: Link UP1488second # [ 9.151130] systemd-networkd[537]: lo: Gained carrier1489second # [ 9.153145] systemd[1]: Started Network Management.1490second # [ 9.155175] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1491second # [ 9.160576] (udev-worker)[467]: Network interface NamePolicy= disabled on kernel command line.1492second # [ 9.161628] (udev-worker)[458]: Network interface NamePolicy= disabled on kernel command line.1493second # [ 9.170203] dbus-broker-launch[541]: Looking up NSS user entry for 'systemd-timesync'...1494test # [ 9.159609] dbus-broker-launch[557]: Looking up NSS user entry for 'systemd-timesync'...1495test # [ 9.178447] cfg80211: Loading compiled-in X.509 certificates for regulatory database1496second # [ 9.186901] dbus-broker-launch[541]: NSS returned no entry for 'systemd-timesync'1497second # [ 9.188445] dbus-broker-launch[541]: Invalid user-name in /nix/store/y8qn40n64x0bra899qbig7cpnspn0kai-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1498test # [ 9.184207] dbus-broker-launch[557]: NSS returned no entry for 'systemd-timesync'1499test # [ 9.185259] dbus-broker-launch[557]: Invalid user-name in /nix/store/y8qn40n64x0bra899qbig7cpnspn0kai-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1500test # [ 9.210762] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1501test # [ 9.211304] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1502second # [ 9.219362] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1503second # [ 9.231889] dbus-broker-launch[541]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1504second # [ 9.236816] dbus-broker-launch[541]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1505test # [ 9.228922] dbus-broker-launch[557]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1506test # [ 9.231211] dbus-broker-launch[557]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1507second # [ 9.244928] dbus-broker-launch[541]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/wrvznzg05dkx9ia8dwkg7f4i04nhv3v6-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1508second # [ 9.246690] dbus-broker-launch[541]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/wns3agiik86mwy41ad6z21sjzqgla78w-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1509second # [ 9.254143] systemd[1]: Started D-Bus System Message Bus.1510test # [ 9.238833] dbus-broker-launch[557]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/wrvznzg05dkx9ia8dwkg7f4i04nhv3v6-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1511test # [ 9.246861] dbus-broker-launch[557]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/wns3agiik86mwy41ad6z21sjzqgla78w-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1512test # [ 9.252684] systemd[1]: Started D-Bus System Message Bus.1513test # [ 9.268896] systemd-networkd[554]: /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.1514test # [ 9.279108] systemd-networkd[554]: /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.1515second # [ 9.293343] dbus-broker-launch[541]: Ready1516test # [ 9.283092] systemd-networkd[554]: lo: Link UP1517second # [ 9.296226] systemd[1]: Reached target Basic System.1518test # [ 9.288208] systemd-networkd[554]: lo: Gained carrier1519test # [ 9.288923] (udev-worker)[434]: Network interface NamePolicy= disabled on kernel command line.1520second # [ 9.301277] systemd[1]: Starting Network Manager Script Dispatcher Service...1521second # [ 9.305726] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1522test # [ 9.297279] (udev-worker)[423]: Network interface NamePolicy= disabled on kernel command line.1523second # [ 9.309327] systemd[1]: Starting Network Manager...1524test # [ 9.301231] dbus-broker-launch[557]: Ready1525test # [ 9.304103] systemd[1]: Started Network Management.1526test # [ 9.306722] systemd[1]: Reached target Basic System.1527test # [ 9.310979] systemd[1]: Starting Network Manager Script Dispatcher Service...1528second # [ 9.325514] systemd[1]: Started backdoor.service.1529test # [ 9.318398] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1530test # [ 9.330413] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1531second # [ 9.358439] systemd-networkd[537]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1532test # [ 9.352697] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1533second # [ 9.365351] systemd-networkd[537]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1534second # [ 9.371100] systemd[1]: Starting Import lastlog data into lastlog2 database...1535second # [ 9.380994] systemd-networkd[537]: eth1: Link UP1536second # [ 9.383492] systemd-networkd[537]: eth1: Gained carrier1537second # [ 9.386902] systemd-networkd[537]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1538test # [ 9.384871] systemd[1]: Starting Network Manager...1539second # [ 9.398408] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1540test # [ 9.391123] systemd[1]: Started backdoor.service.1541second # [ 9.439364] systemd[1]: Starting Post-Boot Actions...1542second # [ 9.445635] izfdm2vcilqki06lkkfdqg1q1s7gnwp8-wifi-secrets[553]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1543test # [ 9.447858] systemd[1]: Starting Import lastlog data into lastlog2 database...1544second # [ 9.467324] systemd[1]: Started Reset console on configuration changes.1545test # [ 9.469222] izfdm2vcilqki06lkkfdqg1q1s7gnwp8-wifi-secrets[568]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1546test # [ 9.477714] ispsphlnyb2js715g0mg6bn3157dmskk-wifi-secrets[569]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1547test # [ 9.485482] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1548second # [ 9.501899] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1549second # [ 9.526561] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1550second # [ 9.540757] mousedev: PS/2 mouse device common for all mice1551test # [ 9.538995] systemd[1]: Starting Post-Boot Actions...1552test # [ 9.544314] vz5k34ilhns09vh81dvgw89nvkf1plwv-wifi-secrets[570]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1553second # connecting to host...1554test # [ 9.575666] systemd[1]: Started Reset console on configuration changes.1555second # [ 9.622115] systemd[1]: Finished Post-Boot Actions.1556test # [ 9.629594] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1557test # connecting to host...1558test # [ 9.635414] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1559second # [ 9.647661] systemd[1]: Started Name Service Cache Daemon (nsncd).1560second # [ 9.652507] nsncd[557]: Sep 09 10:08:30.411 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1561second # [ 9.653824] systemd[1]: Reached target Host and Network Name Lookups.1562second # [ 9.654514] systemd[1]: Reached target User and Group Name Lookups.1563second # [ 9.665469] systemd[1]: Starting User Login Management...1564test # [ 9.668700] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1565test # [ 9.673809] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1566test: Guest shell says: b'Spawning backdoor root shell...\n'1567test: connected to guest root shell1568second # [ 9.709418] systemd[1]: Finished Import lastlog data into lastlog2 database.1569second # [ 9.711584] systemd[1]: Started Network Manager Script Dispatcher Service.1570test: (connecting took 10.04 seconds)1571test # [ 9.702405] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1572test: (finished: waiting for the VM to finish booting, in 10.04 seconds)1573test # [ 9.705271] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1574test # [ 9.705986] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1575test # [ 9.720424] systemd-networkd[554]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1576test # [ 9.736742] systemd-networkd[554]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1577test # [ 9.737567] systemd-networkd[554]: eth1: Link UP1578test # [ 9.738090] systemd-networkd[554]: eth1: Gained carrier1579test # [ 9.738571] systemd-networkd[554]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1580test # [ 9.739897] systemd[1]: Finished Post-Boot Actions.1581test # [ 9.768486] systemd[1]: Started Name Service Cache Daemon (nsncd).1582test # [ 9.769364] nsncd[576]: Sep 09 10:08:30.537 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1583second # [ 9.791780] systemd[1]: Starting Hostname Service...1584second # [ 9.823623] systemd-logind[586]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1585second # [ 9.828659] systemd-logind[586]: Watching system buttons on /dev/input/event0 (gpio-keys)1586second # [ 9.829348] systemd-logind[586]: New seat seat0.1587second # [ 9.829953] systemd[1]: Started User Login Management.1588test # [ 9.821686] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1589test # [ 9.825904] systemd[1]: Started Network Manager Script Dispatcher Service.1590second # [ 9.838033] systemd[1]: Starting linger-users.service...1591test # [ 9.843683] mousedev: PS/2 mouse device common for all mice1592test # [ 9.845149] systemd[1]: Reached target Host and Network Name Lookups.1593test # [ 9.851033] systemd[1]: Reached target User and Group Name Lookups.1594test # [ 9.861230] systemd[1]: Starting User Login Management...1595test # [ 9.872197] systemd[1]: Finished Import lastlog data into lastlog2 database.1596second # [ 9.914648] systemd[1]: linger-users.service: Deactivated successfully.1597second # [ 9.916142] systemd[1]: Finished linger-users.service.1598second # [ 9.927215] systemd[1]: Started Hostname Service.1599test # [ 9.947549] systemd[1]: Starting Hostname Service...1600test # [ 10.024349] systemd-logind[625]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1601second # [ 10.037905] systemd-networkd[537]: eth0: Link UP1602test # [ 10.026360] systemd-logind[625]: Watching system buttons on /dev/input/event0 (gpio-keys)1603second # [ 10.038411] systemd-networkd[537]: eth0: Gained carrier1604test # [ 10.027067] systemd-logind[625]: New seat seat0.1605test # [ 10.036242] systemd[1]: Started User Login Management.1606test # [ 10.038900] systemd[1]: Starting linger-users.service...1607second # [ 10.060291] systemd[1]: Started Network Manager.1608second # [ 10.062688] systemd[1]: Reached target Network.1609second # [ 10.069969] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1610second # [ 10.079336] systemd[1]: Starting Permit User Sessions...1611test # [ 10.095586] systemd[1]: Started Hostname Service.1612test # [ 10.107237] systemd[1]: linger-users.service: Deactivated successfully.1613test # [ 10.111620] systemd[1]: Finished linger-users.service.1614test # [ 10.190636] systemd-networkd[554]: eth0: Link UP1615test # [ 10.191162] systemd-networkd[554]: eth0: Gained carrier1616second # [ 10.213673] systemd[1]: Finished Permit User Sessions.1617test # [ 10.212600] systemd[1]: Started Network Manager.1618test # [ 10.214563] systemd[1]: Reached target Network.1619second # [ 10.231197] systemd[1]: Started Getty on tty1.1620second # [ 10.234271] systemd[1]: Reached target Login Prompts.1621test # [ 10.223216] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1622test # [ 10.229806] systemd[1]: Starting Permit User Sessions...1623second # [ 10.303334] systemd-resolved[339]: eth0: Bus client set default route setting: yes1624second # [ 10.307100] systemd-resolved[339]: eth0: Bus client set DNS server list to: 10.0.2.31625second # [ 10.337948] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1626second # [ 10.338714] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1627test # [ 10.367680] systemd[1]: Finished Permit User Sessions.1628test # [ 10.385949] systemd[1]: Started Getty on tty1.1629test # [ 10.389768] systemd[1]: Reached target Login Prompts.1630test # [ 10.394724] systemd-resolved[351]: eth0: Bus client set default route setting: yes1631test # [ 10.399858] systemd-resolved[351]: eth0: Bus client set DNS server list to: 10.0.2.31632second # [ 10.435367] systemd-networkd[537]: eth1: Gained IPv6LL1633second # [ 10.451897] systemd[1]: Reached target Multi-User System.1634second # [ 10.454580] systemd[1]: Startup finished in 786ms (kernel) + 3.873s (initrd) + 5.792s (userspace) = 10.452s.1635test # [ 10.460303] NetworkManager[571]: <warn> [1788948511.2475] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1636test # [ 10.462957] NetworkManager[571]: <warn> [1788948511.2509] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1637test # [ 10.488563] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1638test # [ 10.492299] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1639test # [ 10.592661] systemd[1]: Reached target Multi-User System.1640test # [ 10.595071] systemd[1]: Startup finished in 761ms (kernel) + 3.956s (initrd) + 5.876s (userspace) = 10.594s.1641second # [ 10.954341] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31642test: (finished: waiting for unit NetworkManager.service, in 11.32 seconds)1643test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1644test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds)1645cleanup1646kill QemuMachine (pid 14)1647second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/akbq19lqha5w5h9hcchn309znqm94pq0-python3-3.13.15/bin/python3.13)1648kill QemuMachine (pid 15)1649test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/akbq19lqha5w5h9hcchn309znqm94pq0-python3-3.13.15/bin/python3.13)1650(finished: cleanup, in 0.23 seconds)1651Traceback (most recent call last):1652 File "/nix/store/g9529d3bpfr38l0hs56ri15djwx9bi06-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in <module>1653 sys.exit(main())1654 ~~~~^^1655 File "/nix/store/g9529d3bpfr38l0hs56ri15djwx9bi06-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/__init__.py", line 183, in main1656 driver.run_tests()1657 ~~~~~~~~~~~~~~~~^^1658 File "/nix/store/g9529d3bpfr38l0hs56ri15djwx9bi06-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/driver.py", line 434, in run_tests1659 self.test_script()1660 ~~~~~~~~~~~~~~~~^^1661 File "/nix/store/g9529d3bpfr38l0hs56ri15djwx9bi06-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/driver.py", line 392, in test_script1662 exec(self.tests, symbols, None)1663 ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^1664 File "<string>", line 4, in <module>1665AssertionError: Expected mock password in connection file:1666[connection]1667autoconnect=true1668id=1669type=wifi16701671[wifi]1672mode=infrastructure1673ssid=16741675[wifi-security]1676key-mgmt=wpa-psk1677psk=1678