vm-test-run-wifi
checks.aarch64-linux.wifi
· build #730
· 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.Snjm474KZk', 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: cee1fd67-b1fe-41d1-a75a-d1373824297c20second # 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 done27second # 28second # Virtualisation disk image created.29test # Disk image does not exist, creating the virtualisation disk image...30test: QEMU running (pid 15)31test # Formatting '/build/vm-state-test/tmp.nSJGBlhKOP', fmt=raw size=107374182432test # mke2fs 1.47.4 (6-Mar-2025)33test # Discarding device blocks: 0/262144 done34test # Creating filesystem with 262144 4k blocks and 65536 inodes35test # Filesystem UUID: 9ad9de19-26ba-4595-8d88-35ccef24c70f36test # Superblock backups stored on blocks:37test # 32768, 98304, 163840, 22937638test # 39test # Allocating group tables: 0/8 done40test # Writing inode tables: 0/8 done41test # Creating journal (8192 blocks): done42test # Writing superblocks and filesystem accounting information: 0/8 done43test # 44test # Virtualisation disk image created.45(finished: start all VMs, in 0.49 seconds)46test: waiting for unit NetworkManager.service47test: waiting for the VM to finish booting48second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]49second # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 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 s186392 r8192 d116712 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)80second # [ 0.000000] CPU features: detected: ARM erratum 141804081second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion82second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing83second # [ 0.000000] alternatives: applying boot alternatives84second # [ 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/v4acdij59mf87m5yhq2yi5y7rydsnyrc-nixos-system-second-test/init regInfo=/nix/store/n16ffi2zkshzhk723vzz48r815gjw0ps-closure-info/registration console=ttyAMA0,115200n8 console=tty085second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/n16ffi2zkshzhk723vzz48r815gjw0ps-closure-info/registration", will be passed to user space.86second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes87second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)88second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)89test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]90second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB91second # [ 0.000000] software IO TLB: area num 1.92test # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 202693second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)94test # [ 0.000000] KASLR enabled95second # [ 0.000000] Fallback order for Node 0: 096test # [ 0.000000] random: crng init done97test # [ 0.000000] Machine model: linux,dummy-virt98second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214499test # [ 0.000000] efi: UEFI not found.100second # [ 0.000000] Policy zone: DMA101test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT102second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off103second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1104test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]105second # [ 0.000000] allocated 2097152 bytes of page_ext106test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]107test # [ 0.000000] Zone ranges:108second # [ 0.000000] ftrace: allocating 74815 entries in 294 pages109test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]110second # [ 0.000000] ftrace: allocated 294 pages with 4 groups111test # [ 0.000000] DMA32 empty112second # [ 0.000000] rcu: Hierarchical RCU implementation.113test # [ 0.000000] Normal empty114second # [ 0.000000] rcu: RCU event tracing is enabled.115test # [ 0.000000] Device empty116test # [ 0.000000] Movable zone start for each node117second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.118test # [ 0.000000] Early memory node ranges119second # [ 0.000000] Trampoline variant of Tasks RCU enabled.120test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]121second # [ 0.000000] Rude variant of Tasks RCU enabled.122second # [ 0.000000] Tracing variant of Tasks RCU enabled.123test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]124test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000125second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.126test # [ 0.000000] psci: probing for conduit method from DT.127second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1128test # [ 0.000000] psci: PSCIv1.3 detected in firmware.129test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs130second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.131test # [ 0.000000] psci: Trusted OS migration not required132test # [ 0.000000] psci: SMC Calling Convention v1.1133second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.134test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)135second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.136test # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296137second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0138test # [ 0.000000] Detected PIPT I-cache on CPU0139second # [ 0.000000] GICv3: 256 SPIs implemented140second # [ 0.000000] GICv3: 0 Extended SPIs implemented141test # [ 0.000000] CPU features: detected: GICv3 CPU interface142second # [ 0.000000] Root IRQ handler: gic_handle_irq143test # [ 0.000000] CPU features: detected: Spectre-v4144test # [ 0.000000] CPU features: detected: Spectre-BHB145second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI146second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0147test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR148second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000149test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)150second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]151test # [ 0.000000] CPU features: detected: ARM erratum 1418040152test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion153second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)154test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing155second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)156test # [ 0.000000] alternatives: applying boot alternatives157second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000158second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000159second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.160second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns161test # [ 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/zz9cncmis8x1ppwi2jgvysdlchm7kcyq-nixos-system-test-test/init regInfo=/nix/store/yf5pj4khg581y6bxg1sfcap1lddpiw7j-closure-info/registration console=ttyAMA0,115200n8 console=tty0162second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040163second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).164test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/yf5pj4khg581y6bxg1sfcap1lddpiw7j-closure-info/registration", will be passed to user space.165second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns166test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes167second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns168test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)169second # [ 0.000048] arm-pv: using stolen time PV170test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)171second # [ 0.000486] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)172test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB173second # [ 0.000786] Console: colour dummy device 80x25174test # [ 0.000000] software IO TLB: area num 1.175second # [ 0.000799] printk: legacy console [tty0] enabled176test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)177test # [ 0.000000] Fallback order for Node 0: 0178second # [ 0.001005] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)179second # [ 0.001011] pid_max: default: 32768 minimum: 301180test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144181test # [ 0.000000] Policy zone: DMA182second # [ 0.001076] LSM: initializing lsm=capability,landlock,yama,bpf,ima183second # [ 0.001209] landlock: Up and running.184test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off185second # [ 0.001211] Yama: becoming mindful.186second # [ 0.001706] LSM support for eBPF active187test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1188test # [ 0.000000] allocated 2097152 bytes of page_ext189second # [ 0.001882] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)190test # [ 0.000000] ftrace: allocating 74815 entries in 294 pages191test # [ 0.000000] ftrace: allocated 294 pages with 4 groups192second # [ 0.001902] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)193test # [ 0.000000] rcu: Hierarchical RCU implementation.194second # [ 0.004679] rcu: Hierarchical SRCU implementation.195test # [ 0.000000] rcu: RCU event tracing is enabled.196second # [ 0.004687] rcu: Max phase no-delay instances is 1000.197second # [ 0.005692] fsl-mc MSI: its@8080000 domain created198test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.199second # [ 0.005845] EFI services will not be available.200test # [ 0.000000] Trampoline variant of Tasks RCU enabled.201second # [ 0.006239] smp: Bringing up secondary CPUs ...202test # [ 0.000000] Rude variant of Tasks RCU enabled.203second # [ 0.006269] smp: Brought up 1 node, 1 CPU204test # [ 0.000000] Tracing variant of Tasks RCU enabled.205second # [ 0.006272] SMP: Total of 1 processors activated.206second # [ 0.006274] CPU: All CPU(s) started at EL1207test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.208second # [ 0.006287] CPU features: detected: 32-bit EL0 Support209test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1210second # [ 0.006290] CPU features: detected: Data cache clean to the PoU not required for I/D coherence211test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.212second # [ 0.006293] CPU features: detected: Common not Private translations213second # [ 0.006295] CPU features: detected: CRC32 instructions214test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.215second # [ 0.006297] CPU features: detected: Data cache clean to Point of Persistence216test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.217second # [ 0.006299] CPU features: detected: Enhanced Virtualization Traps218test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0219second # [ 0.006309] CPU features: detected: RCpc load-acquire (LDAPR)220test # [ 0.000000] GICv3: 256 SPIs implemented221second # [ 0.006312] CPU features: detected: LSE atomic instructions222test # [ 0.000000] GICv3: 0 Extended SPIs implemented223test # [ 0.000000] Root IRQ handler: gic_handle_irq224second # [ 0.006314] CPU features: detected: Privileged Access Never225second # [ 0.006316] CPU features: detected: PMUv3226test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI227test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0228second # [ 0.006318] CPU features: detected: RAS Extension Support229test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000230second # [ 0.006322] CPU features: detected: Speculative Store Bypassing Safe (SSBS)231test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]232second # [ 0.006344] alternatives: applying system-wide alternatives233second # [ 0.007924] CPU features: detected: Hardware dirty bit management on CPU0234test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)235test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)236second # [ 0.008162] Memory: 893160K/1048576K available (24320K kernel code, 7090K rwdata, 26356K rodata, 4736K init, 1103K bss, 114092K reserved, 32768K cma-reserved)237test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000238second # [ 0.008648] devtmpfs: initialized239test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000240second # [ 0.010180] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)241test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.242second # [ 0.010205] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).243second # [ 0.010342] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL244test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns245second # [ 0.010346] 0 pages in range for non-PLT usage246test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040247second # [ 0.010347] 508336 pages in range for PLT usage248test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).249second # [ 0.010485] pinctrl core: initialized pinctrl subsystem250second # [ 0.011303] DMI not present or invalid.251test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns252second # [ 0.014973] NET: Registered PF_NETLINK/PF_ROUTE protocol family253test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns254second # [ 0.017580] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations255test # [ 0.000041] arm-pv: using stolen time PV256second # [ 0.017737] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations257test # [ 0.000409] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)258second # [ 0.017909] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations259second # [ 0.017938] audit: initializing netlink subsys (disabled)260test # [ 0.000697] Console: colour dummy device 80x25261test # [ 0.000709] printk: legacy console [tty0] enabled262second # [ 0.018554] thermal_sys: Registered thermal governor 'fair_share'263second # [ 0.018557] thermal_sys: Registered thermal governor 'bang_bang'264test # [ 0.000930] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)265second # [ 0.018560] thermal_sys: Registered thermal governor 'step_wise'266test # [ 0.000935] pid_max: default: 32768 minimum: 301267second # [ 0.018562] thermal_sys: Registered thermal governor 'user_space'268test # [ 0.000998] LSM: initializing lsm=capability,landlock,yama,bpf,ima269test # [ 0.001125] landlock: Up and running.270second # [ 0.018564] thermal_sys: Registered thermal governor 'power_allocator'271test # [ 0.001127] Yama: becoming mindful.272test # [ 0.001532] LSM support for eBPF active273second # [ 0.018601] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1274second # [ 0.018616] cpuidle: using governor ladder275test # [ 0.001710] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)276second # [ 0.018621] cpuidle: using governor menu277test # [ 0.001730] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)278second # [ 0.018745] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.279test # [ 0.004395] rcu: Hierarchical SRCU implementation.280second # [ 0.018768] ASID allocator initialised with 32768 entries281second # [ 0.019470] Serial: AMBA PL011 UART driver282test # [ 0.004403] rcu: Max phase no-delay instances is 1000.283test # [ 0.005385] fsl-mc MSI: its@8080000 domain created284second # [ 0.022767] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1285test # [ 0.005539] EFI services will not be available.286second # [ 0.022924] printk: console [ttyAMA0] enabled287test # [ 0.005648] smp: Bringing up secondary CPUs ...288test # [ 0.005675] smp: Brought up 1 node, 1 CPU289test # [ 0.005677] SMP: Total of 1 processors activated.290test # [ 0.005679] CPU: All CPU(s) started at EL1291test # [ 0.005691] CPU features: detected: 32-bit EL0 Support292test # [ 0.005693] CPU features: detected: Data cache clean to the PoU not required for I/D coherence293test # [ 0.005696] CPU features: detected: Common not Private translations294test # [ 0.005699] CPU features: detected: CRC32 instructions295test # [ 0.005701] CPU features: detected: Data cache clean to Point of Persistence296test # [ 0.005704] CPU features: detected: Enhanced Virtualization Traps297test # [ 0.005713] CPU features: detected: RCpc load-acquire (LDAPR)298test # [ 0.005715] CPU features: detected: LSE atomic instructions299test # [ 0.005717] CPU features: detected: Privileged Access Never300test # [ 0.005719] CPU features: detected: PMUv3301test # [ 0.005721] CPU features: detected: RAS Extension Support302test # [ 0.005725] CPU features: detected: Speculative Store Bypassing Safe (SSBS)303test # [ 0.005746] alternatives: applying system-wide alternatives304test # [ 0.007127] CPU features: detected: Hardware dirty bit management on CPU0305test # [ 0.007325] Memory: 893176K/1048576K available (24320K kernel code, 7090K rwdata, 26356K rodata, 4736K init, 1103K bss, 114092K reserved, 32768K cma-reserved)306second # [ 0.097853] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages307test # [ 0.007774] devtmpfs: initialized308second # [ 0.097877] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page309test # [ 0.009277] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)310second # [ 0.097881] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages311test # [ 0.009301] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).312second # [ 0.097887] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page313test # [ 0.009427] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL314second # [ 0.097890] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages315test # [ 0.009430] 0 pages in range for non-PLT usage316second # [ 0.097893] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page317test # [ 0.009431] 508336 pages in range for PLT usage318test # [ 0.009539] pinctrl core: initialized pinctrl subsystem319second # [ 0.097897] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages320test # [ 0.010298] DMI not present or invalid.321second # [ 0.097900] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page322test # [ 0.013964] NET: Registered PF_NETLINK/PF_ROUTE protocol family323test # [ 0.016486] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations324test # [ 0.016640] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations325test # [ 0.016814] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations326second # [ 0.103312] fbcon: Taking over console327test # [ 0.016840] audit: initializing netlink subsys (disabled)328second # [ 0.103334] ACPI: Interpreter disabled.329test # [ 0.017483] thermal_sys: Registered thermal governor 'fair_share'330second # [ 0.104583] iommu: Default domain type: Translated331test # [ 0.017485] thermal_sys: Registered thermal governor 'bang_bang'332second # [ 0.104594] iommu: DMA domain TLB invalidation policy: strict mode333test # [ 0.017489] thermal_sys: Registered thermal governor 'step_wise'334second # [ 0.105801] SCSI subsystem initialized335test # [ 0.017491] thermal_sys: Registered thermal governor 'user_space'336test # [ 0.017492] thermal_sys: Registered thermal governor 'power_allocator'337second # [ 0.106371] usbcore: registered new interface driver usbfs338test # [ 0.017522] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1339second # [ 0.106412] usbcore: registered new interface driver hub340test # [ 0.017537] cpuidle: using governor ladder341test # [ 0.017542] cpuidle: using governor menu342second # [ 0.106424] usbcore: registered new device driver usb343second # [ 0.106654] pps_core: LinuxPPS API ver. 1 registered344test # [ 0.017675] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.345test # [ 0.017697] ASID allocator initialised with 32768 entries346second # [ 0.106660] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>347test # [ 0.018395] Serial: AMBA PL011 UART driver348second # [ 0.106677] PTP clock support registered349second # [ 0.106712] EDAC MC: Ver: 3.0.0350test # [ 0.021650] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1351test # [ 0.021834] printk: console [ttyAMA0] enabled352test # [ 0.097024] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages353second # [ 0.117858] scmi_core: SCMI protocol bus registered354test # [ 0.097048] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page355test # [ 0.097052] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages356second # [ 0.118661] FPGA manager framework357test # [ 0.097058] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page358second # [ 0.119758] vgaarb: loaded359test # [ 0.097061] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages360second # [ 0.120209] clocksource: Switched to clocksource arch_sys_counter361test # [ 0.097064] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page362test # [ 0.097067] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages363test # [ 0.097070] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page364test # [ 0.102224] fbcon: Taking over console365test # [ 0.102244] ACPI: Interpreter disabled.366test # [ 0.103494] iommu: Default domain type: Translated367test # [ 0.103505] iommu: DMA domain TLB invalidation policy: strict mode368test # [ 0.104738] SCSI subsystem initialized369second # [ 0.125672] VFS: Disk quotas dquot_6.6.0370second # [ 0.125718] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)371test # [ 0.105337] usbcore: registered new interface driver usbfs372test # [ 0.105382] usbcore: registered new interface driver hub373second # [ 0.126894] netfs: FS-Cache loaded374second # [ 0.127030] pnp: PnP ACPI: disabled375test # [ 0.105392] usbcore: registered new device driver usb376test # [ 0.105603] pps_core: LinuxPPS API ver. 1 registered377test # [ 0.105609] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>378test # [ 0.105615] PTP clock support registered379test # [ 0.105645] EDAC MC: Ver: 3.0.0380second # [ 0.129490] NET: Registered PF_INET protocol family381second # [ 0.129692] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)382test # [ 0.112919] scmi_core: SCMI protocol bus registered383test # [ 0.113678] FPGA manager framework384test # [ 0.114718] vgaarb: loaded385test # [ 0.115162] clocksource: Switched to clocksource arch_sys_counter386test # [ 0.123825] VFS: Disk quotas dquot_6.6.0387test # [ 0.123865] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)388test # [ 0.126227] netfs: FS-Cache loaded389test # [ 0.126363] pnp: PnP ACPI: disabled390test # [ 0.128854] NET: Registered PF_INET protocol family391test # [ 0.129044] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)392second # [ 0.154903] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)393second # [ 0.154937] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)394second # [ 0.154950] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)395second # [ 0.154989] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)396second # [ 0.155057] TCP: Hash tables configured (established 8192 bind 8192)397second # [ 0.155160] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)398second # [ 0.155229] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)399second # [ 0.155283] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)400second # [ 0.155406] NET: Registered PF_UNIX/PF_LOCAL protocol family401second # [ 0.155493] NET: Registered PF_XDP protocol family402second # [ 0.155517] PCI: CLS 0 bytes, default 64403second # [ 0.155749] Trying to unpack rootfs image as initramfs...404second # [ 0.170514] kvm [1]: HYP mode not available405test # [ 0.153930] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)406test # [ 0.153963] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)407test # [ 0.153977] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)408test # [ 0.154015] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)409test # [ 0.154083] TCP: Hash tables configured (established 8192 bind 8192)410test # [ 0.154175] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)411test # [ 0.154205] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)412test # [ 0.154264] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)413test # [ 0.154399] NET: Registered PF_UNIX/PF_LOCAL protocol family414test # [ 0.154485] NET: Registered PF_XDP protocol family415test # [ 0.154509] PCI: CLS 0 bytes, default 64416test # [ 0.154720] Trying to unpack rootfs image as initramfs...417test # [ 0.169402] kvm [1]: HYP mode not available418second # [ 0.232636] Initialise system trusted keyrings419second # [ 0.233137] workingset: timestamp_bits=42 max_order=18 bucket_order=0420second # [ 0.234098] squashfs: version 4.0 (2009/01/31) Phillip Lougher421second # [ 0.234591] 9p: Installing v9fs 9p2000 file system support422test # [ 0.233904] Initialise system trusted keyrings423test # [ 0.234428] workingset: timestamp_bits=42 max_order=18 bucket_order=0424test # [ 0.235416] squashfs: version 4.0 (2009/01/31) Phillip Lougher425second # [ 0.257563] Key type asymmetric registered426second # [ 0.257591] Asymmetric key parser 'x509' registered427second # [ 0.257657] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)428second # [ 0.259038] io scheduler mq-deadline registered429second # [ 0.259049] io scheduler kyber registered430test # [ 0.235915] 9p: Installing v9fs 9p2000 file system support431test # [ 0.250724] Key type asymmetric registered432test # [ 0.250746] Asymmetric key parser 'x509' registered433test # [ 0.250796] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)434second # [ 0.268340] pl061_gpio 9030000.pl061: PL061 GPIO chip registered435second # [ 0.268684] ledtrig-cpu: registered to indicate activity on CPUs436second # [ 0.268974] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:437second # [ 0.268996] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000438second # [ 0.269011] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000439second # [ 0.269017] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000440test # [ 0.259231] io scheduler mq-deadline registered441second # [ 0.269061] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits442test # [ 0.259246] io scheduler kyber registered443second # [ 0.269094] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]444second # [ 0.269175] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00445second # [ 0.269193] pci_bus 0000:00: root bus resource [bus 00-ff]446second # [ 0.269198] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]447second # [ 0.269202] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]448second # [ 0.269206] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]449second # [ 0.269336] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint450second # [ 0.269748] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint451second # [ 0.269863] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]452second # [ 0.269874] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]453second # [ 0.269894] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]454second # [ 0.269906] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]455second # [ 0.270183] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint456second # [ 0.270295] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]457second # [ 0.270306] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]458second # [ 0.270326] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]459second # [ 0.270601] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint460second # [ 0.270712] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]461second # [ 0.270723] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]462second # [ 0.270742] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]463second # [ 0.271017] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint464second # [ 0.271139] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]465second # [ 0.271150] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]466second # [ 0.271169] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]467second # [ 0.271449] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint468second # [ 0.271559] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]469second # [ 0.271570] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]470second # [ 0.271590] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]471second # [ 0.271872] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint472test # [ 0.267289] pl061_gpio 9030000.pl061: PL061 GPIO chip registered473second # [ 0.271986] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]474test # [ 0.267648] ledtrig-cpu: registered to indicate activity on CPUs475second # [ 0.271996] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]476test # [ 0.267932] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:477second # [ 0.272016] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]478test # [ 0.267952] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000479second # [ 0.272315] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint480second # [ 0.272431] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]481test # [ 0.267966] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000482second # [ 0.272442] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]483test # [ 0.267972] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000484second # [ 0.272462] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]485second # [ 0.272478] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]486test # [ 0.268010] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits487second # [ 0.272800] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint488test # [ 0.268043] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]489second # [ 0.272918] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]490test # [ 0.268123] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00491test # [ 0.268141] pci_bus 0000:00: root bus resource [bus 00-ff]492second # [ 0.272937] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]493test # [ 0.268146] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]494second # [ 0.273211] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint495test # [ 0.268150] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]496second # [ 0.273331] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]497test # [ 0.268154] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]498second # [ 0.273350] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]499test # [ 0.268246] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint500second # [ 0.273599] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint501second # [ 0.273709] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]502test # [ 0.268664] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint503test # [ 0.268782] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]504second # [ 0.273873] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint505test # [ 0.268793] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]506second # [ 0.274094] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]507test # [ 0.268813] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]508second # [ 0.274108] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]509test # [ 0.268825] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]510second # [ 0.274127] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]511test # [ 0.269106] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint512second # [ 0.274410] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint513test # [ 0.269219] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]514second # [ 0.274523] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]515test # [ 0.269230] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]516second # [ 0.274534] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]517test # [ 0.269250] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]518second # [ 0.274553] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]519second # [ 0.274933] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned520test # [ 0.269527] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint521test # [ 0.269638] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]522second # [ 0.274943] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned523test # [ 0.269648] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]524second # [ 0.274948] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned525test # [ 0.269668] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]526second # [ 0.274979] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned527test # [ 0.269944] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint528second # [ 0.275008] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned529test # [ 0.270056] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]530test # [ 0.270066] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]531second # [ 0.275037] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned532test # [ 0.270085] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]533second # [ 0.275066] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned534test # [ 0.270364] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint535second # [ 0.275094] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned536test # [ 0.270487] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]537second # [ 0.275134] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned538test # [ 0.270498] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]539test # [ 0.270518] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]540second # [ 0.275166] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned541test # [ 0.270798] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint542second # [ 0.275195] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned543test # [ 0.270911] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]544second # [ 0.275224] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned545test # [ 0.270921] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]546second # [ 0.275295] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned547test # [ 0.270941] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]548second # [ 0.275325] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned549test # [ 0.271247] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint550test # [ 0.271362] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]551second # [ 0.275339] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned552test # [ 0.271373] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]553second # [ 0.275354] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned554test # [ 0.271393] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]555second # [ 0.275367] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned556test # [ 0.271408] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]557second # [ 0.275382] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned558second # [ 0.275396] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned559test # [ 0.271723] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint560test # [ 0.271840] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]561second # [ 0.275410] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned562test # [ 0.271859] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]563second # [ 0.275425] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned564second # [ 0.275439] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned565test # [ 0.272131] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint566second # [ 0.275453] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned567test # [ 0.272254] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]568test # [ 0.272274] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]569second # [ 0.275468] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned570second # [ 0.275482] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned571test # [ 0.272535] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint572second # [ 0.275496] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned573test # [ 0.272647] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]574second # [ 0.275510] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned575test # [ 0.272807] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint576second # [ 0.275524] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned577test # [ 0.273009] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]578second # [ 0.275538] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned579test # [ 0.273024] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]580second # [ 0.275558] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned581test # [ 0.273044] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]582second # [ 0.275572] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned583test # [ 0.273330] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint584second # [ 0.275586] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned585test # [ 0.273443] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]586second # [ 0.275600] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned587test # [ 0.273454] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]588second # [ 0.275615] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned589test # [ 0.273475] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]590second # [ 0.275633] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]591test # [ 0.273871] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned592second # [ 0.275639] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]593test # [ 0.273881] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned594second # [ 0.275643] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]595test # [ 0.273886] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned596test # [ 0.273917] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned597test # [ 0.273947] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned598test # [ 0.273977] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned599test # [ 0.274006] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned600test # [ 0.274035] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned601test # [ 0.274065] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned602test # [ 0.274096] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned603test # [ 0.274126] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned604test # [ 0.274155] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned605second # [ 0.332415] pci 0000:00:0a.0: enabling device (0000 -> 0002)606test # [ 0.274227] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned607test # [ 0.274259] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned608test # [ 0.274273] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned609test # [ 0.274287] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned610test # [ 0.274301] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned611test # [ 0.274315] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned612test # [ 0.274330] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned613test # [ 0.274345] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned614test # [ 0.274359] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned615test # [ 0.274373] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned616test # [ 0.274388] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned617test # [ 0.274402] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned618second # [ 0.338939] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)619test # [ 0.274416] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned620test # [ 0.274431] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned621test # [ 0.274454] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned622test # [ 0.274468] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned623test # [ 0.274482] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned624test # [ 0.274500] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned625test # [ 0.274514] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned626test # [ 0.274528] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned627test # [ 0.274542] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned628test # [ 0.274556] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned629test # [ 0.274574] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]630test # [ 0.274580] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]631test # [ 0.274584] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]632second # [ 0.349140] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)633test # [ 0.331341] pci 0000:00:0a.0: enabling device (0000 -> 0002)634test # [ 0.337801] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)635second # [ 0.356397] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)636second # [ 0.358239] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)637second # [ 0.360144] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)638test # [ 0.347716] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)639test # [ 0.350778] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)640second # [ 0.369896] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)641second # [ 0.372006] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)642test # [ 0.360211] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)643second # [ 0.382527] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)644second # [ 0.388370] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)645test # [ 0.367294] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)646second # [ 0.390560] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)647test # [ 0.369139] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)648test # [ 0.371074] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)649second # [ 0.400749] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)650test # [ 0.380896] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)651test # [ 0.382627] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)652second # [ 0.404913] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled653second # [ 0.406433] msm_serial: driver initialized654second # [ 0.406519] SuperH (H)SCI(F) driver initialized655second # [ 0.406553] STM32 USART driver initialized656test # [ 0.393647] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)657test # [ 0.396530] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)658test # [ 0.408123] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled659test # [ 0.409640] msm_serial: driver initialized660test # [ 0.409767] SuperH (H)SCI(F) driver initialized661test # [ 0.409804] STM32 USART driver initialized662second # [ 0.432951] loop: module loaded663second # [ 0.433084] virtio_blk virtio5: 1/0/0 default/read/poll queues664second # [ 0.433733] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)665second # [ 0.440616] megasas: 07.734.00.00-rc1666second # [ 0.441110] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]667test # [ 0.432088] loop: module loaded668test # [ 0.432223] virtio_blk virtio5: 1/0/0 default/read/poll queues669test # [ 0.432776] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)670second # [ 0.452942] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000671second # [ 0.453003] Intel/Sharp Extended Query Table at 0x0031672second # [ 0.454859] Using buffer write method673second # [ 0.454914] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]674second # [ 0.459054] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000675second # [ 0.459078] Intel/Sharp Extended Query Table at 0x0031676second # [ 0.462376] Using buffer write method677second # [ 0.462401] Concatenating MTD devices:678second # [ 0.462404] (0): "0.flash"679second # [ 0.462407] (1): "0.flash"680second # [ 0.462410] into device "0.flash"681test # [ 0.439572] megasas: 07.734.00.00-rc1682test # [ 0.440046] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]683test # [ 0.444063] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000684test # [ 0.444080] Intel/Sharp Extended Query Table at 0x0031685test # [ 0.445875] Using buffer write method686test # [ 0.445928] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]687test # [ 0.455780] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000688test # [ 0.455811] Intel/Sharp Extended Query Table at 0x0031689test # [ 0.457542] Using buffer write method690test # [ 0.457564] Concatenating MTD devices:691test # [ 0.457567] (0): "0.flash"692test # [ 0.457570] (1): "0.flash"693test # [ 0.457572] into device "0.flash"694second # [ 0.668088] Freeing initrd memory: 25484K695second # [ 0.675023] tun: Universal TUN/TAP device driver, 1.6696test # [ 0.658093] Freeing initrd memory: 25484K697second # [ 0.677805] thunder_xcv, ver 1.0698second # [ 0.677836] thunder_bgx, ver 1.0699second # [ 0.677848] nicpf, ver 1.0700second # [ 0.678137] e1000: Intel(R) PRO/1000 Network Driver701second # [ 0.678144] e1000: Copyright (c) 1999-2006 Intel Corporation.702second # [ 0.678158] e1000e: Intel(R) PRO/1000 Network Driver703second # [ 0.678165] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.704second # [ 0.678178] igb: Intel(R) Gigabit Ethernet Network Driver705second # [ 0.678181] igb: Copyright (c) 2007-2014 Intel Corporation.706second # [ 0.678192] igbvf: Intel(R) Gigabit Virtual Function Network Driver707second # [ 0.678195] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.708second # [ 0.678257] sky2: driver version 1.30709second # [ 0.679041] usbcore: registered new interface driver usb-storage710test # [ 0.664999] tun: Universal TUN/TAP device driver, 1.6711second # [ 0.679090] usbcore: registered new interface driver usbserial_generic712second # [ 0.679098] usbserial: USB Serial support registered for generic713second # [ 0.679413] hv_vmbus: registering driver hyperv_keyboard714second # [ 0.680526] ehci-pci 0000:00:0a.0: EHCI Host Controller715second # [ 0.680561] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1716second # [ 0.680769] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000717second # [ 0.688979] rtc-pl031 9010000.pl031: registered as rtc0718second # [ 0.689019] rtc-pl031 9010000.pl031: setting system clock to 2026-09-20T15:12:55 UTC (1789917175)719second # [ 0.689216] i2c_dev: i2c /dev entries driver720test # [ 0.667714] thunder_xcv, ver 1.0721test # [ 0.667741] thunder_bgx, ver 1.0722test # [ 0.667753] nicpf, ver 1.0723test # [ 0.668035] e1000: Intel(R) PRO/1000 Network Driver724test # [ 0.668042] e1000: Copyright (c) 1999-2006 Intel Corporation.725test # [ 0.668058] e1000e: Intel(R) PRO/1000 Network Driver726test # [ 0.668064] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.727test # [ 0.668077] igb: Intel(R) Gigabit Ethernet Network Driver728test # [ 0.668081] igb: Copyright (c) 2007-2014 Intel Corporation.729second # [ 0.692237] sdhci: Secure Digital Host Controller Interface driver730second # [ 0.692249] sdhci: Copyright(c) Pierre Ossman731test # [ 0.668091] igbvf: Intel(R) Gigabit Virtual Function Network Driver732second # [ 0.692386] Synopsys Designware Multimedia Card Interface Driver733test # [ 0.668095] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.734test # [ 0.668156] sky2: driver version 1.30735second # [ 0.692549] sdhci-pltfm: SDHCI platform and OF driver helper736second # [ 0.693385] hid: raw HID events driver (C) Jiri Kosina737test # [ 0.668953] usbcore: registered new interface driver usb-storage738second # [ 0.693528] usbcore: registered new interface driver usbhid739test # [ 0.668999] usbcore: registered new interface driver usbserial_generic740second # [ 0.693533] usbhid: USB HID core driver741test # [ 0.669007] usbserial: USB Serial support registered for generic742second # [ 0.696983] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00743test # [ 0.669282] hv_vmbus: registering driver hyperv_keyboard744test # [ 0.670293] ehci-pci 0000:00:0a.0: EHCI Host Controller745second # [ 0.697823] hub 1-0:1.0: USB hub found746second # [ 0.698159] hub 1-0:1.0: 6 ports detected747test # [ 0.670334] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1748test # [ 0.670571] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000749second # [ 0.698842] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available750test # [ 0.678543] rtc-pl031 9010000.pl031: registered as rtc0751second # [ 0.699656] drop_monitor: Initializing network drop monitor service752test # [ 0.678587] rtc-pl031 9010000.pl031: setting system clock to 2026-09-20T15:12:55 UTC (1789917175)753second # [ 0.699818] NET: Registered PF_INET6 protocol family754test # [ 0.678775] i2c_dev: i2c /dev entries driver755test # [ 0.679222] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00756second # [ 0.702230] Segment Routing with IPv6757test # [ 0.679599] hub 1-0:1.0: USB hub found758second # [ 0.702252] In-situ OAM (IOAM) with IPv6759test # [ 0.679627] hub 1-0:1.0: 6 ports detected760second # [ 0.702295] NET: Registered PF_PACKET protocol family761second # [ 0.703396] 9pnet: Installing 9P2000 support762test # [ 0.683597] sdhci: Secure Digital Host Controller Interface driver763test # [ 0.683611] sdhci: Copyright(c) Pierre Ossman764test # [ 0.683743] Synopsys Designware Multimedia Card Interface Driver765test # [ 0.683907] sdhci-pltfm: SDHCI platform and OF driver helper766test # [ 0.684706] hid: raw HID events driver (C) Jiri Kosina767second # [ 0.705370] Key type dns_resolver registered768test # [ 0.684850] usbcore: registered new interface driver usbhid769test # [ 0.684854] usbhid: USB HID core driver770test # [ 0.688381] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available771test # [ 0.689132] drop_monitor: Initializing network drop monitor service772test # [ 0.689254] NET: Registered PF_INET6 protocol family773test # [ 0.691548] Segment Routing with IPv6774test # [ 0.691569] In-situ OAM (IOAM) with IPv6775test # [ 0.691610] NET: Registered PF_PACKET protocol family776second # [ 0.708609] registered taskstats version 1777second # [ 0.708793] Loading compiled-in X.509 certificates778second # [ 0.714041] Demotion targets for Node 0: null779second # [ 0.714128] Key type .fscrypt registered780second # [ 0.714134] Key type fscrypt-provisioning registered781second # [ 0.714237] ima: No TPM chip found, activating TPM-bypass!782test # [ 0.692762] 9pnet: Installing 9P2000 support783second # [ 0.714250] ima: Allocated hash algorithm: sha1784test # [ 0.694344] Key type dns_resolver registered785second # [ 0.714302] ima: No architecture policies found786second # [ 0.717362] input: gpio-keys as /devices/platform/gpio-keys/input/input0787test # [ 0.697848] registered taskstats version 1788test # [ 0.698028] Loading compiled-in X.509 certificates789test # [ 0.703598] Demotion targets for Node 0: null790test # [ 0.703674] Key type .fscrypt registered791test # [ 0.703679] Key type fscrypt-provisioning registered792test # [ 0.703779] ima: No TPM chip found, activating TPM-bypass!793test # [ 0.703791] ima: Allocated hash algorithm: sha1794test # [ 0.703841] ima: No architecture policies found795test # [ 0.706466] input: gpio-keys as /devices/platform/gpio-keys/input/input0796second # [ 0.727458] clk: Disabling unused clocks797second # [ 0.727477] PM: genpd: Disabling unused power domains798second # [ 0.730665] Freeing unused kernel memory: 4736K799second # [ 0.730756] Run /init as init process800test # [ 0.716531] clk: Disabling unused clocks801test # [ 0.716552] PM: genpd: Disabling unused power domains802test # [ 0.719742] Freeing unused kernel memory: 4736K803test # [ 0.719836] Run /init as init process804second # [ 0.751000] systemd[1]: Successfully made /usr/ read-only.805test # [ 0.741498] systemd[1]: Successfully made /usr/ read-only.806second # [ 0.944260] usb 1-1: new high-speed USB device number 2 using ehci-pci807test # [ 0.927212] usb 1-1: new high-speed USB device number 2 using ehci-pci808second # [ 1.085585] systemd[1]: systemd 260.4 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)809second # [ 1.089422] systemd[1]: Detected virtualization qemu.810second # [ 1.090292] systemd[1]: Detected architecture arm64.811second # [ 1.090902] systemd[1]: Running in initrd.812second # [ 1.092504] systemd[1]: Initializing machine ID from random generator.813second # [ 1.093907] systemd[1]: Hostname set to <second>.814second # [ 1.099250] 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/input1815test # [ 1.076300] systemd[1]: systemd 260.4 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)816test # [ 1.083983] systemd[1]: Detected virtualization qemu.817test # [ 1.085132] systemd[1]: Detected architecture arm64.818test # [ 1.086241] systemd[1]: Running in initrd.819test # [ 1.088687] 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/input1820test # [ 1.092684] systemd[1]: Initializing machine ID from random generator.821test # [ 1.094636] systemd[1]: Hostname set to <test>.822second # [ 1.173394] systemd[1]: Queued start job for default target Initrd Default Target.823second # [ 1.181752] systemd[1]: Created slice Slice /system/modprobe.824second # [ 1.182632] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.825second # [ 1.183408] systemd[1]: Expecting device /dev/disk/by-label/nixos...826second # [ 1.184028] systemd[1]: Reached target Path Units.827second # [ 1.184699] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0828second # [ 1.185977] systemd[1]: Reached target Slice Units.829second # [ 1.186469] systemd[1]: Reached target Swaps.830second # [ 1.186982] systemd[1]: Reached target Timer Units.831second # [ 1.187672] systemd[1]: Listening on D-Bus System Message Bus Socket.832second # [ 1.188516] systemd[1]: Listening on Journal Socket (/dev/log).833second # [ 1.189263] systemd[1]: Listening on Journal Sockets.834second # [ 1.189872] systemd[1]: Listening on udev Control Socket.835second # [ 1.190526] systemd[1]: Listening on udev Kernel Socket.836second # [ 1.191055] systemd[1]: Reached target Socket Units.837second # [ 1.192918] systemd[1]: Starting Create List of Static Device Nodes...838test # [ 1.175528] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0839second # [ 1.199818] systemd[1]: Starting Load Kernel Module 9pnet_virtio...840second # [ 1.200538] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs841second # [ 1.207138] systemd[1]: Mounting Kernel Configuration File System...842second # [ 1.212336] systemd[1]: Starting Journal Service...843test # [ 1.202754] systemd[1]: Queued start job for default target Initrd Default Target.844second # [ 1.228714] systemd[1]: Starting Load Kernel Modules...845second # [ 1.229272] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki846test # [ 1.212860] systemd[1]: Created slice Slice /system/modprobe.847test # [ 1.213698] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.848test # [ 1.214582] systemd[1]: Expecting device /dev/disk/by-label/nixos...849test # [ 1.215285] systemd[1]: Reached target Path Units.850second # [ 1.236348] systemd[1]: Starting Coldplug All udev Devices...851test # [ 1.215867] systemd[1]: Reached target Slice Units.852test # [ 1.216379] systemd[1]: Reached target Swaps.853test # [ 1.216852] systemd[1]: Reached target Timer Units.854test # [ 1.217581] systemd[1]: Listening on D-Bus System Message Bus Socket.855second # [ 1.239032] systemd[1]: Finished Create List of Static Device Nodes.856test # [ 1.218441] systemd[1]: Listening on Journal Socket (/dev/log).857test # [ 1.219267] systemd[1]: Listening on Journal Sockets.858test # [ 1.219930] systemd[1]: Listening on udev Control Socket.859second # [ 1.240706] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.860test # [ 1.220690] systemd[1]: Listening on udev Kernel Socket.861test # [ 1.221274] systemd[1]: Reached target Socket Units.862test # [ 1.223210] systemd[1]: Starting Create List of Static Device Nodes...863test # [ 1.227280] systemd[1]: Starting Load Kernel Module 9pnet_virtio...864second # [ 1.247499] systemd[1]: Finished Load Kernel Module 9pnet_virtio.865second # [ 1.247937] systemd[1]: Mounted Kernel Configuration File System.866test # [ 1.227993] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs867second # [ 1.252957] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...868test # [ 1.235324] systemd[1]: Mounting Kernel Configuration File System...869test # [ 1.243466] systemd[1]: Starting Journal Service...870second # [ 1.279984] systemd-journald[73]: Collecting audit messages is disabled.871test # [ 1.261183] systemd[1]: Starting Load Kernel Modules...872test # [ 1.261252] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki873test # [ 1.267464] systemd[1]: Starting Coldplug All udev Devices...874test # [ 1.268762] systemd[1]: Finished Create List of Static Device Nodes.875test # [ 1.270326] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.876test # [ 1.280003] systemd[1]: Finished Load Kernel Module 9pnet_virtio.877test # [ 1.281041] systemd[1]: Mounted Kernel Configuration File System.878second # [ 1.303613] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.879second # [ 1.304942] usb 1-2: new high-speed USB device number 3 using ehci-pci880second # [ 1.308722] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.881second # [ 1.312629] systemd[1]: Starting Create Static Device Nodes in /dev...882test # [ 1.295298] usb 1-2: new high-speed USB device number 3 using ehci-pci883test # [ 1.303360] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...884second # [ 1.324289] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev885second # [ 1.332085] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0886test # [ 1.316795] systemd-journald[73]: Collecting audit messages is disabled.887second # [ 1.356596] [drm] features: -virgl +edid -resource_blob -host_visible888second # [ 1.356611] [drm] features: -context_init889second # [ 1.357114] [drm] number of scanouts: 1890second # [ 1.357130] [drm] number of cap sets: 0891second # [ 1.359653] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic892test # [ 1.339354] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.893second # [ 1.359671] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0894test # [ 1.351256] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev895test # [ 1.353378] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.896second # [ 1.376734] systemd[1]: Finished Create Static Device Nodes in /dev.897second # [ 1.376889] systemd[1]: Reached target Preparation for Local File Systems.898second # [ 1.376918] systemd[1]: Reached target Local File Systems.899second # [ 1.379702] systemd[1]: Starting Rule-based Manager for Device Events and Files...900test # [ 1.360264] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0901test # [ 1.360503] [drm] features: -virgl +edid -resource_blob -host_visible902test # [ 1.360509] [drm] features: -context_init903test # [ 1.360988] [drm] number of scanouts: 1904test # [ 1.361003] [drm] number of cap sets: 0905test # [ 1.363970] systemd[1]: Starting Create Static Device Nodes in /dev...906second # [ 1.385966] systemd-modules-load[74]: Inserted module 'dm_mod'907second # [ 1.396422] systemd[1]: Started Journal Service.908second # [ 1.389577] systemd-modules-load[74]: Module 'virtio_balloon' is built in909second # [ 1.390348] systemd-modules-load[74]: Module 'virtio_console' is built in910test # [ 1.383634] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic911second # [ 1.408150] Console: switching to colour frame buffer device 160x50912test # [ 1.383652] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0913second # [ 1.409435] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device914second # [ 1.405572] systemd[1]: Starting Create System Files and Directories...915second # [ 1.422662] systemd-modules-load[74]: Inserted module 'virtio_gpu'916second # [ 1.423328] systemd-modules-load[74]: Module 'virtio_rng' is built in917second # [ 1.423981] systemd[1]: Finished Load Kernel Modules.918test # [ 1.409718] Console: switching to colour frame buffer device 160x50919test # [ 1.410123] systemd-modules-load[74]: Inserted module 'dm_mod'920test # [ 1.410908] systemd-modules-load[74]: Module 'virtio_balloon' is built in921test # [ 1.411688] systemd-modules-load[74]: Module 'virtio_console' is built in922test # [ 1.419823] systemd[1]: Started Journal Service.923test # [ 1.420071] systemd[1]: Finished Create Static Device Nodes in /dev.924second # [ 1.441038] systemd[1]: Starting Apply Kernel Variables...925test # [ 1.430576] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device926test # [ 1.424945] systemd[1]: Reached target Preparation for Local File Systems.927test # [ 1.425798] systemd[1]: Reached target Local File Systems.928test # [ 1.428689] systemd[1]: Starting Create System Files and Directories...929test # [ 1.432204] systemd-modules-load[74]: Inserted module 'virtio_gpu'930second # [ 1.452634] systemd[1]: Finished Create System Files and Directories.931test # [ 1.436291] systemd-modules-load[74]: Module 'virtio_rng' is built in932second # [ 1.465097] 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/input2933second # [ 1.466994] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0934test # [ 1.440899] systemd[1]: Starting Rule-based Manager for Device Events and Files...935test # [ 1.451064] 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/input2936test # [ 1.453076] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0937test # [ 1.447911] systemd[1]: Finished Load Kernel Modules.938second # [ 1.468864] systemd-udevd[80]: Using default interface naming scheme 'v260'.939second # [ 1.482756] systemd[1]: Finished Apply Kernel Variables.940test # [ 1.472105] systemd[1]: Starting Apply Kernel Variables...941second # [ 1.500127] systemd[1]: Started Rule-based Manager for Device Events and Files.942test # [ 1.484830] systemd[1]: Finished Create System Files and Directories.943test # [ 1.498416] systemd[1]: Finished Apply Kernel Variables.944test # [ 1.513664] systemd-udevd[86]: Using default interface naming scheme 'v260'.945second # [ 1.555570] systemd[1]: Starting Virtual Console Setup...946test # [ 1.536813] systemd[1]: Started Rule-based Manager for Device Events and Files.947second # [ 1.612409] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.948second # [ 1.614985] systemd[1]: Finished Virtual Console Setup.949test # [ 1.596108] systemd[1]: Starting Virtual Console Setup...950test # [ 1.644373] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.951test # [ 1.646994] systemd[1]: Finished Virtual Console Setup.952second # [ 1.865723] systemd[1]: Finished Coldplug All udev Devices.953second # [ 1.866348] systemd[1]: Reached target System Initialization.954second # [ 1.866883] systemd[1]: Reached target Basic System.955test # [ 1.900583] systemd[1]: Finished Coldplug All udev Devices.956test # [ 1.901177] systemd[1]: Reached target System Initialization.957test # [ 1.904151] systemd[1]: Reached target Basic System.958second # [ 1.974579] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.959second # [ 2.004971] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.960test # [ 1.998132] (udev-worker)[102]: Network interface NamePolicy= disabled on kernel command line.961test # [ 2.016808] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.962second # [ 2.039144] systemd[1]: Found device /dev/disk/by-label/nixos.963second # [ 2.040616] systemd[1]: Reached target Initrd Root Device.964second # [ 2.043003] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...965second # [ 2.084076] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks966test # [ 2.064557] systemd[1]: Found device /dev/disk/by-label/nixos.967test # [ 2.066009] systemd[1]: Reached target Initrd Root Device.968test # [ 2.068313] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...969second # [ 2.104869] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.970test # [ 2.118708] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks971test # [ 2.126789] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.972second # [ 2.232428] systemd[1]: Mounting /sysroot...973second # [ 2.265319] EXT4-fs (vda): mounted filesystem cee1fd67-b1fe-41d1-a75a-d1373824297c r/w with ordered data mode. Quota mode: none.974second # [ 2.261924] systemd[1]: Mounted /sysroot.975second # [ 2.262383] systemd[1]: Reached target Initrd Root File System.976second # [ 2.264083] systemd[1]: Starting Mountpoints Configured in the Real Root...977second # [ 2.283058] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.978test # [ 2.264165] systemd[1]: Mounting /sysroot...979second # [ 2.288400] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...980second # [ 2.291151] systemd[1]: Reloading...981test # [ 2.294229] EXT4-fs (vda): mounted filesystem 9ad9de19-26ba-4595-8d88-35ccef24c70f r/w with ordered data mode. Quota mode: none.982test # [ 2.292856] systemd[1]: Mounted /sysroot.983test # [ 2.293855] systemd[1]: Reached target Initrd Root File System.984test # [ 2.297337] systemd[1]: Starting Mountpoints Configured in the Real Root...985test # [ 2.319361] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.986test # [ 2.324901] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...987test # [ 2.328223] systemd[1]: Reloading...988second # [ 2.469772] systemd[1]: Reloading finished in 179 ms.989second # [ 2.479731] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...990second # [ 2.482280] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...991second # [ 2.495424] systemd[1]: Starting Load Kernel Module 9pnet_virtio...992second # [ 2.496401] systemd[1]: initrd-parse-etc.service: Deactivated successfully.993second # [ 2.498291] systemd[1]: Finished Mountpoints Configured in the Real Root.994second # [ 2.500137] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.995second # [ 2.512421] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.996second # [ 2.513122] systemd[1]: Finished Load Kernel Module 9pnet_virtio.997test # [ 2.511445] systemd[1]: Reloading finished in 185 ms.998test # [ 2.525344] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...999test # [ 2.529026] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...1000test # [ 2.532116] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1001test # [ 2.537762] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1002test # [ 2.538994] systemd[1]: Finished Mountpoints Configured in the Real Root.1003test # [ 2.540507] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1004test # [ 2.556465] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1005test # [ 2.557305] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1006second # [ 2.921746] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/mtd_probe": No such file or directory1007second # [ 2.925090] (udev-worker)[99]: mtd0ro: /nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/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 directory1008second # [ 2.936648] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1009second # [ 2.937667] systemd[1]: Stopped Virtual Console Setup.1010second # [ 2.940156] systemd[1]: Stopping Virtual Console Setup...1011second # [ 2.945853] systemd[1]: Starting Virtual Console Setup...1012test # [ 2.939930] (udev-worker)[92]: mtd0ro: Failed to find and pin callout binary "/nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/mtd_probe": No such file or directory1013test # [ 2.943767] (udev-worker)[92]: mtd0ro: /nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/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 directory1014second # [ 2.968799] systemd-vconsole-setup[141]: Configuration of first virtual console was skipped, ignoring remaining ones.1015second # [ 2.972742] systemd[1]: Finished Virtual Console Setup.1016test # [ 2.972285] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1017test # [ 2.974919] systemd[1]: Stopped Virtual Console Setup.1018test # [ 2.975462] systemd[1]: Stopping Virtual Console Setup...1019test # [ 2.980801] systemd[1]: Starting Virtual Console Setup...1020test # [ 3.007289] systemd-vconsole-setup[142]: Configuration of first virtual console was skipped, ignoring remaining ones.1021test # [ 3.009590] systemd[1]: Finished Virtual Console Setup.1022second # [ 3.234262] systemd[1]: Mounting /sysroot/nix/.ro-store...1023second # [ 3.242213] systemd[1]: Mounting /sysroot/nix/.rw-store...1024second # [ 3.256164] systemd[1]: Mounting /sysroot/run...1025second # [ 3.269655] systemd[1]: Mounting /sysroot/tmp/shared...1026second # [ 3.272133] systemd[1]: Mounting /sysroot/tmp/xchg...1027test # [ 3.264880] systemd[1]: Mounting /sysroot/nix/.ro-store...1028second # [ 3.285825] systemd[1]: Mounted /sysroot/nix/.rw-store.1029second # [ 3.293181] systemd[1]: Starting rw-sysroot-nix-store.service...1030test # [ 3.276318] systemd[1]: Mounting /sysroot/nix/.rw-store...1031test # [ 3.279743] systemd[1]: Mounting /sysroot/run...1032second # [ 3.302642] systemd[1]: Mounted /sysroot/nix/.ro-store.1033second # [ 3.306338] systemd[1]: Mounted /sysroot/run.1034second # [ 3.313991] systemd[1]: Mounted /sysroot/tmp/shared.1035test # [ 3.293835] systemd[1]: Mounting /sysroot/tmp/shared...1036second # [ 3.318810] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1037second # [ 3.321683] systemd[1]: Finished rw-sysroot-nix-store.service.1038second # [ 3.322427] systemd[1]: Mounted /sysroot/tmp/xchg.1039test # [ 3.305364] systemd[1]: Mounting /sysroot/tmp/xchg...1040test # [ 3.341722] systemd[1]: Mounted /sysroot/nix/.rw-store.1041test # [ 3.347994] systemd[1]: Mounted /sysroot/nix/.ro-store.1042test # [ 3.351221] systemd[1]: Mounted /sysroot/run.1043test # [ 3.357402] systemd[1]: Mounted /sysroot/tmp/shared.1044test # [ 3.358884] systemd[1]: Mounted /sysroot/tmp/xchg.1045test # [ 3.364431] systemd[1]: Starting rw-sysroot-nix-store.service...1046test # [ 3.382273] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1047test # [ 3.383848] systemd[1]: Finished rw-sysroot-nix-store.service.1048second # [ 4.231750] systemd[1]: Mounting /sysroot/nix/store...1049second # [ 4.275282] systemd[1]: Mounted /sysroot/nix/store.1050second # [ 4.277386] systemd[1]: Reached target Initrd File Systems.1051second # [ 4.281098] systemd[1]: Starting Find NixOS closure...1052test # [ 4.263306] systemd[1]: Mounting /sysroot/nix/store...1053second # [ 4.292274] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1054test # [ 4.308598] systemd[1]: Mounted /sysroot/nix/store.1055test # [ 4.310785] systemd[1]: Reached target Initrd File Systems.1056test # [ 4.314666] systemd[1]: Starting Find NixOS closure...1057second # [ 4.333105] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1058second # [ 4.337429] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1059test # [ 4.324295] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1060second # [ 4.354157] systemd[1]: Finished Find NixOS closure.1061second # [ 4.356231] systemd[1]: Reached target Initrd Default Target.1062second # [ 4.358358] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1063test # [ 4.363053] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1064second # [ 4.387219] systemd[1]: Stopped target Initrd Default Target.1065second # [ 4.388872] systemd[1]: Stopped target Basic System.1066test # [ 4.368300] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1067second # [ 4.389955] systemd[1]: Stopped target Initrd Root Device.1068second # [ 4.392259] systemd[1]: Stopped target Path Units.1069second # [ 4.393028] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1070second # [ 4.396225] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1071second # [ 4.397731] systemd[1]: Stopped target Slice Units.1072second # [ 4.400124] systemd[1]: Stopped target Socket Units.1073second # [ 4.402033] systemd[1]: Stopped target System Initialization.1074second # [ 4.403025] systemd[1]: Stopped target Swaps.1075test # [ 4.383979] systemd[1]: Finished Find NixOS closure.1076test # [ 4.386582] systemd[1]: Reached target Initrd Default Target.1077second # [ 4.407458] systemd[1]: Stopped target Timer Units.1078second # [ 4.408310] systemd[1]: dbus.socket: Deactivated successfully.1079test # [ 4.388349] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1080second # [ 4.409038] systemd[1]: Closed D-Bus System Message Bus Socket.1081second # [ 4.409829] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1082second # [ 4.410712] systemd[1]: Stopped Find NixOS closure.1083second # [ 4.411425] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1084second # [ 4.416221] systemd[1]: Starting rw-sysroot-nix-store.service...1085second # [ 4.417324] systemd[1]: systemd-sysctl.service: Deactivated successfully.1086second # [ 4.419634] systemd[1]: Stopped Apply Kernel Variables.1087second # [ 4.421074] systemd[1]: systemd-modules-load.service: Deactivated successfully.1088second # [ 4.423300] systemd[1]: Stopped Load Kernel Modules.1089second # [ 4.427690] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1090second # [ 4.430260] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1091second # [ 4.431053] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1092second # [ 4.431994] systemd[1]: Stopped Create System Files and Directories.1093second # [ 4.436761] systemd[1]: Stopped target Local File Systems.1094second # [ 4.438328] systemd[1]: Stopped target Preparation for Local File Systems.1095second # [ 4.438987] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1096second # [ 4.439643] systemd[1]: Stopped Coldplug All udev Devices.1097test # [ 4.424990] systemd[1]: initrd-cleanup.service: Deactivated successfully.1098test # [ 4.428136] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1099second # [ 4.448875] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1100test # [ 4.430331] systemd[1]: Stopped target Initrd Default Target.1101second # [ 4.450391] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1102test # [ 4.432191] systemd[1]: Stopped target Basic System.1103second # [ 4.452398] systemd[1]: Stopped Virtual Console Setup.1104second # [ 4.453716] systemd[1]: initrd-cleanup.service: Deactivated successfully.1105test # [ 4.434740] systemd[1]: Stopped target Initrd Root Device.1106test # [ 4.435645] systemd[1]: Stopped target Path Units.1107second # [ 4.456287] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1108second # [ 4.456927] systemd[1]: systemd-udevd.service: Deactivated successfully.1109test # [ 4.437746] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1110second # [ 4.457727] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1111test # [ 4.438767] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1112test # [ 4.439794] systemd[1]: Stopped target Slice Units.1113second # [ 4.459142] systemd[1]: systemd-udevd.service: Consumed 1.002s CPU time over 3.078s wall clock time, 20.5M memory peak.1114test # [ 4.444160] systemd[1]: Stopped target Socket Units.1115second # [ 4.463864] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1116test # [ 4.444827] systemd[1]: Stopped target System Initialization.1117second # [ 4.464758] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1118test # [ 4.445521] systemd[1]: Stopped target Swaps.1119second # [ 4.465333] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1120test # [ 4.446085] systemd[1]: Stopped target Timer Units.1121second # [ 4.465970] systemd[1]: Finished rw-sysroot-nix-store.service.1122test # [ 4.446683] systemd[1]: dbus.socket: Deactivated successfully.1123second # [ 4.466481] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1124second # [ 4.467303] systemd[1]: Closed udev Control Socket.1125test # [ 4.447403] systemd[1]: Closed D-Bus System Message Bus Socket.1126second # [ 4.467745] systemd[1]: Starting Cleanup udev Database...1127second # [ 4.468906] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1128second # [ 4.469587] systemd[1]: Stopped Create Static Device Nodes in /dev.1129second # [ 4.470125] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1130second # [ 4.470806] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1131second # [ 4.471422] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1132second # [ 4.472091] systemd[1]: Stopped Create List of Static Device Nodes.1133test # [ 4.452184] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1134test # [ 4.453038] systemd[1]: Stopped Find NixOS closure.1135test # [ 4.454663] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1136test # [ 4.457674] systemd[1]: Starting rw-sysroot-nix-store.service...1137test # [ 4.459452] systemd[1]: systemd-sysctl.service: Deactivated successfully.1138test # [ 4.461748] systemd[1]: Stopped Apply Kernel Variables.1139test # [ 4.464451] systemd[1]: systemd-modules-load.service: Deactivated successfully.1140test # [ 4.466023] systemd[1]: Stopped Load Kernel Modules.1141test # [ 4.469843] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1142test # [ 4.472698] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1143second # [ 4.494353] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1144second # [ 4.495186] systemd[1]: Finished Cleanup udev Database.1145second # [ 4.495720] systemd[1]: Reached target Switch Root.1146test # [ 4.478250] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1147second # [ 4.500105] systemd[1]: Starting NixOS Activation...1148test # [ 4.481150] systemd[1]: Stopped Create System Files and Directories.1149test # [ 4.482727] systemd[1]: Stopped target Local File Systems.1150test # [ 4.484164] systemd[1]: Stopped target Preparation for Local File Systems.1151test # [ 4.485922] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1152test # [ 4.486803] systemd[1]: Stopped Coldplug All udev Devices.1153test # [ 4.491528] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1154test # [ 4.492610] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1155test # [ 4.493283] systemd[1]: Stopped Virtual Console Setup.1156test # [ 4.493752] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1157test # [ 4.494386] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1158test # [ 4.494924] systemd[1]: systemd-udevd.service: Deactivated successfully.1159test # [ 4.495514] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1160test # [ 4.497303] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1161test # [ 4.497974] systemd[1]: Closed udev Control Socket.1162test # [ 4.498433] systemd[1]: Starting Cleanup udev Database...1163test # [ 4.498923] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1164test # [ 4.499613] systemd[1]: Stopped Create Static Device Nodes in /dev.1165test # [ 4.500263] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1166test # [ 4.500983] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1167test # [ 4.501609] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1168test # [ 4.502209] systemd[1]: Stopped Create List of Static Device Nodes.1169test # [ 4.502780] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1170test # [ 4.503437] systemd[1]: Finished rw-sysroot-nix-store.service.1171test # [ 4.521707] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1172test # [ 4.522456] systemd[1]: Finished Cleanup udev Database.1173test # [ 4.522925] systemd[1]: Reached target Switch Root.1174test # [ 4.524885] systemd[1]: Starting NixOS Activation...1175second # [ 4.695950] initrd-nixos-activation-start[178]: booting system configuration /nix/store/v4acdij59mf87m5yhq2yi5y7rydsnyrc-nixos-system-second-test1176test # [ 4.701652] initrd-nixos-activation-start[179]: booting system configuration /nix/store/zz9cncmis8x1ppwi2jgvysdlchm7kcyq-nixos-system-test-test1177second # [ 4.767892] initrd-nixos-activation-start[178]: running activation script...1178test # [ 4.755758] initrd-nixos-activation-start[179]: running activation script...1179test # [ 5.171062] initrd-nixos-activation-start[202]: setting up /etc...1180second # [ 5.259695] initrd-nixos-activation-start[201]: setting up /etc...1181test # [ 5.386913] initrd-nixos-activation-start[202]: setting up age secrets...1182second # [ 5.502222] initrd-nixos-activation-start[201]: setting up age secrets...1183test # [ 5.747561] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1184test # [ 5.749666] systemd[1]: Finished NixOS Activation.1185test # [ 5.750501] systemd[1]: Starting Switch Root...1186second # [ 5.782554] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1187test # [ 5.765962] systemd[1]: Switching root.1188second # [ 5.784199] systemd[1]: Finished NixOS Activation.1189second # [ 5.785513] systemd[1]: Starting Switch Root...1190second # [ 5.807138] systemd[1]: Switching root.1191test # [ 5.819915] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1192second # [ 5.866539] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1193test # [ 6.383872] systemd[1]: systemd 260.4 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)1194test # [ 6.388770] systemd[1]: Detected virtualization qemu.1195test # [ 6.390200] systemd[1]: Detected architecture arm64.1196test # [ 6.391799] systemd[1]: Detected first boot.1197test # [ 6.397425] systemd[1]: Initializing machine ID from random generator.1198second # [ 6.427372] systemd[1]: systemd 260.4 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)1199second # [ 6.434489] systemd[1]: Detected virtualization qemu.1200second # [ 6.436774] systemd[1]: Detected architecture arm64.1201second # [ 6.438043] systemd[1]: Detected first boot.1202second # [ 6.444378] systemd[1]: Initializing machine ID from random generator.1203test # [ 6.692278] systemd[1]: bpf-restrict-fs: LSM BPF program attached1204second # [ 6.741494] systemd[1]: bpf-restrict-fs: LSM BPF program attached1205test # [ 6.896352] systemd[1]: Applying preset policy.1206second # [ 6.928799] systemd[1]: Applying preset policy.1207test # [ 7.371892] systemd[1]: Populated /etc with preset unit settings.1208second # [ 7.510421] systemd[1]: Populated /etc with preset unit settings.1209test # [ 7.896430] systemd[1]: initrd-switch-root.service: Deactivated successfully.1210test # [ 7.897301] systemd[1]: Stopped initrd-switch-root.service.1211test # [ 7.899348] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1212test # [ 7.900879] systemd[1]: Created slice Slice /system/getty.1213test # [ 7.902499] systemd[1]: Created slice User and Session Slice.1214test # [ 7.903829] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1215test # [ 7.904608] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1216test # [ 7.905775] systemd[1]: Expecting device /dev/hvc0...1217test # [ 7.906758] systemd[1]: Expecting device /dev/ttyAMA0...1218test # [ 7.908020] systemd[1]: Reached target Local Encrypted Volumes.1219test # [ 7.909003] systemd[1]: Stopped target initrd-fs.target.1220test # [ 7.909973] systemd[1]: Stopped target initrd-root-fs.target.1221test # [ 7.911557] systemd[1]: Stopped target initrd-switch-root.target.1222test # [ 7.913077] systemd[1]: Reached target Virtual Machines and Containers.1223test # [ 7.914639] systemd[1]: Reached target Path Units.1224test # [ 7.915966] systemd[1]: Reached target Remote File Systems.1225test # [ 7.917222] systemd[1]: Reached target Slice Units.1226test # [ 7.918443] systemd[1]: Reached target Swaps.1227test # [ 7.922410] systemd[1]: Listening on Process Core Dump Socket.1228test # [ 7.925824] systemd[1]: Listening on Credential Encryption/Decryption.1229test # [ 7.930163] systemd[1]: Starting Journal Log Access Socket...1230test # [ 7.932131] systemd[1]: Listening on Journal Audit Socket.1231test # [ 7.933574] systemd[1]: Listening on Network Management Metrics Varlink Socket.1232test # [ 7.935130] systemd[1]: Listening on Network Management Netlink Socket.1233test # [ 7.937012] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1234test # [ 7.938486] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1235test # [ 7.939989] systemd[1]: Listening on udev Control Socket.1236test # [ 7.943868] systemd[1]: Mounting Huge Pages File System...1237test # [ 7.947482] systemd[1]: Mounting POSIX Message Queue File System...1238test # [ 7.952584] systemd[1]: Mounting Kernel Debug File System...1239test # [ 7.960669] systemd[1]: Mounting Kernel Trace File System...1240test # [ 7.968167] systemd[1]: Starting Create List of Static Device Nodes...1241test # [ 7.979637] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1242test # [ 7.980685] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1243test # [ 7.991627] systemd[1]: Mounting Kernel Configuration File System...1244test # [ 7.993894] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1245test # [ 7.997152] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1246test # [ 8.008675] systemd[1]: Starting Load Kernel Module fuse...1247test # [ 8.010729] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671248second # [ 8.035571] systemd[1]: initrd-switch-root.service: Deactivated successfully.1249second # [ 8.036587] systemd[1]: Stopped initrd-switch-root.service.1250second # [ 8.038281] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1251second # [ 8.040093] systemd[1]: Created slice Slice /system/getty.1252second # [ 8.041349] systemd[1]: Created slice User and Session Slice.1253second # [ 8.042454] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1254second # [ 8.043228] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1255second # [ 8.044692] systemd[1]: Expecting device /dev/hvc0...1256second # [ 8.045119] systemd[1]: Expecting device /dev/ttyAMA0...1257second # [ 8.046047] systemd[1]: Reached target Local Encrypted Volumes.1258second # [ 8.047192] systemd[1]: Stopped target initrd-fs.target.1259second # [ 8.048155] systemd[1]: Stopped target initrd-root-fs.target.1260second # [ 8.049846] systemd[1]: Stopped target initrd-switch-root.target.1261second # [ 8.051372] systemd[1]: Reached target Virtual Machines and Containers.1262second # [ 8.053077] systemd[1]: Reached target Path Units.1263second # [ 8.053942] systemd[1]: Reached target Remote File Systems.1264second # [ 8.055451] systemd[1]: Reached target Slice Units.1265second # [ 8.056932] systemd[1]: Reached target Swaps.1266second # [ 8.061402] systemd[1]: Listening on Process Core Dump Socket.1267second # [ 8.065085] systemd[1]: Listening on Credential Encryption/Decryption.1268second # [ 8.069836] systemd[1]: Starting Journal Log Access Socket...1269second # [ 8.071431] systemd[1]: Listening on Journal Audit Socket.1270second # [ 8.072600] systemd[1]: Listening on Network Management Metrics Varlink Socket.1271second # [ 8.073810] systemd[1]: Listening on Network Management Netlink Socket.1272second # [ 8.075598] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1273second # [ 8.077537] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1274second # [ 8.078904] systemd[1]: Listening on udev Control Socket.1275second # [ 8.083368] systemd[1]: Mounting Huge Pages File System...1276second # [ 8.087632] systemd[1]: Mounting POSIX Message Queue File System...1277second # [ 8.092362] systemd[1]: Mounting Kernel Debug File System...1278second # [ 8.096469] systemd[1]: Mounting Kernel Trace File System...1279test # [ 8.085650] systemd[1]: Starting Journal Service...1280second # [ 8.108538] systemd[1]: Starting Create List of Static Device Nodes...1281second # [ 8.122959] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1282second # [ 8.124020] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1283second # [ 8.130770] systemd[1]: Mounting Kernel Configuration File System...1284second # [ 8.131960] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1285test # [ 8.113365] systemd[1]: Starting Load Kernel Modules...1286second # [ 8.134551] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1287second # [ 8.149738] systemd[1]: Starting Load Kernel Module fuse...1288second # [ 8.151443] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671289test # [ 8.141111] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1290test # [ 8.150192] systemd[1]: Starting Remount Root and Kernel File Systems...1291test # [ 8.150652] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1292second # [ 8.201336] systemd[1]: Starting Journal Service...1293test # [ 8.183724] systemd[1]: Starting Coldplug All udev Devices...1294second # [ 8.223683] systemd[1]: Starting Load Kernel Modules...1295test # [ 8.203564] fuse: init (API version 7.45)1296test # [ 8.208328] systemd[1]: Listening on Journal Log Access Socket.1297test # [ 8.214916] systemd[1]: Mounted Huge Pages File System.1298test # [ 8.218265] systemd[1]: Mounted POSIX Message Queue File System.1299test # [ 8.220888] systemd[1]: Mounted Kernel Debug File System.1300test # [ 8.223905] systemd-journald[323]: Collecting audit messages is enabled.1301test # [ 8.228483] systemd[1]: Mounted Kernel Trace File System.1302test # [ 8.231587] systemd[1]: Finished Create List of Static Device Nodes.1303test # [ 8.232252] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1304second # [ 8.256570] fuse: init (API version 7.45)1305second # [ 8.257823] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1306test # [ 8.240559] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1307test # [ 8.241177] systemd[1]: Mounted Kernel Configuration File System.1308test # [ 8.241654] systemd[1]: modprobe@fuse.service: Deactivated successfully.1309test # [ 8.242087] systemd[1]: Finished Load Kernel Module fuse.1310test # [ 8.242671] systemd[1]: Finished Load Kernel Modules.1311test # [ 8.240953] systemd[1]: Queued start job for default target Multi-User System.1312test # [ 8.241961] systemd[1]: systemd-journald.service: Deactivated successfully.1313second # [ 8.269934] systemd[1]: Starting Remount Root and Kernel File Systems...1314test # [ 8.246731] systemd-modules-load[324]: Module 'atkbd' is built in1315second # [ 8.275572] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1316test # [ 8.249522] systemd-modules-load[324]: Module 'ctr' is built in1317test # [ 8.257387] systemd-modules-load[324]: Module 'loop' is built in1318test # [ 8.266583] systemd[1]: Mounting FUSE Control File System...1319test # [ 8.275356] systemd[1]: Starting Firewall...1320second # [ 8.296330] systemd[1]: Starting Coldplug All udev Devices...1321second # [ 8.298256] systemd[1]: Listening on Journal Log Access Socket.1322second # [ 8.298689] systemd[1]: Mounted Huge Pages File System.1323second # [ 8.299115] systemd[1]: Mounted POSIX Message Queue File System.1324second # [ 8.299501] systemd[1]: Mounted Kernel Debug File System.1325second # [ 8.299870] systemd[1]: Mounted Kernel Trace File System.1326test # [ 8.293877] systemd[1]: Starting Apply Kernel Variables...1327second # [ 8.318207] systemd[1]: Finished Create List of Static Device Nodes.1328test # [ 8.299578] EXT4-fs (vda): re-mounted 9ad9de19-26ba-4595-8d88-35ccef24c70f.1329second # [ 8.328085] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1330test # [ 8.312004] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1331test # [ 8.313109] systemd[1]: Started Journal Service.1332second # [ 8.333992] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1333second # [ 8.339414] systemd[1]: Mounted Kernel Configuration File System.1334second # [ 8.342979] systemd-journald[301]: Collecting audit messages is enabled.1335second # [ 8.345353] systemd[1]: modprobe@fuse.service: Deactivated successfully.1336second # [ 8.352996] systemd[1]: Finished Load Kernel Module fuse.1337second # [ 8.360166] systemd[1]: Mounting FUSE Control File System...1338test # [ 8.337804] systemd[1]: Finished Remount Root and Kernel File Systems.1339second # [ 8.361738] systemd[1]: Queued start job for default target Multi-User System.1340second # [ 8.374022] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1341test # [ 8.347141] systemd-oomd[326]: No swap; memory pressure usage will be degraded1342second # [ 8.365425] systemd[1]: systemd-journald.service: Deactivated successfully.[ 8.380795] systemd[1]: Started Journal Service.1343second # 1344second # [ 8.377207] systemd-modules-load[302]: Module 'atkbd' is built in1345test # [ 8.360213] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1346second # [ 8.382202] systemd-modules-load[302]: Module 'ctr' is built in1347second # [ 8.387110] systemd-modules-load[302]: Module 'loop' is built in1348second # [ 8.393533] systemd[1]: Finished Load Kernel Modules.1349second # [ 8.416370] EXT4-fs (vda): re-mounted cee1fd67-b1fe-41d1-a75a-d1373824297c.1350second # [ 8.429472] systemd[1]: Finished Remount Root and Kernel File Systems.1351test # [ 8.414670] systemd[1]: Starting Flush Journal to Persistent Storage...1352test # [ 8.423136] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1353second # [ 8.445187] systemd-oomd[303]: No swap; memory pressure usage will be degraded1354test # [ 8.429249] systemd[1]: Starting Load/Save OS Random Seed...1355test # [ 8.432895] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1356second # [ 8.458549] systemd[1]: Starting Firewall...1357second # [ 8.461627] systemd[1]: Starting Flush Journal to Persistent Storage...1358second # [ 8.463829] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1359test # [ 8.453742] systemd[1]: Mounted FUSE Control File System.1360second # [ 8.481373] systemd[1]: Starting Load/Save OS Random Seed...1361second # [ 8.487521] systemd[1]: Starting Apply Kernel Variables...1362second # [ 8.492344] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1363second # [ 8.493096] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1364test # [ 8.486109] systemd[1]: Finished Apply Kernel Variables.1365test # [ 8.493789] systemd[1]: Starting Network Name Resolution...1366second # [ 8.544932] systemd[1]: Mounted FUSE Control File System.1367test # [ 8.539958] systemd-journald[323]: Received client request to flush runtime journal.1368second # [ 8.567317] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1369second # [ 8.570878] systemd[1]: Starting Create Static Device Nodes in /dev...1370second # [ 8.622764] systemd-journald[301]: Received client request to flush runtime journal.1371test # [ 8.624527] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1372test # [ 8.627509] systemd[1]: Starting Create Static Device Nodes in /dev...1373test # [ 8.633670] systemd[1]: Finished Load/Save OS Random Seed.1374test # [ 8.635673] systemd[1]: Reached target First Boot Complete.1375test # [ 8.640655] systemd[1]: Finished Flush Journal to Persistent Storage.1376second # [ 8.684730] systemd[1]: Finished Load/Save OS Random Seed.1377second # [ 8.686765] systemd[1]: Reached target First Boot Complete.1378second # [ 8.690027] systemd[1]: Finished Apply Kernel Variables.1379second # [ 8.694091] systemd[1]: Starting Network Name Resolution...1380second # [ 8.696837] systemd[1]: Finished Flush Journal to Persistent Storage.1381second # [ 8.700566] systemd[1]: Finished Create Static Device Nodes in /dev.1382second # [ 8.708562] systemd[1]: Reached target Preparation for Local File Systems.1383test # [ 8.691894] systemd[1]: Finished Create Static Device Nodes in /dev.1384test # [ 8.692887] systemd[1]: Reached target Preparation for Local File Systems.1385test # [ 8.696304] systemd-resolved[352]: Positive Trust Anchors:1386second # [ 8.717233] systemd[1]: Starting Rule-based Manager for Device Events and Files...1387test # [ 8.700555] systemd-resolved[352]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1388test # [ 8.702091] systemd-resolved[352]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161389test # [ 8.704537] systemd-resolved[352]: 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 test1390test # [ 8.738478] systemd[1]: Starting Rule-based Manager for Device Events and Files...1391test # [ 8.831625] systemd-udevd[367]: Using default interface naming scheme 'v260'.1392second # [ 8.855776] systemd-resolved[335]: Positive Trust Anchors:1393second # [ 8.856719] systemd-resolved[335]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1394second # [ 8.860664] systemd-resolved[335]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161395second # [ 8.863061] systemd-resolved[335]: 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 test1396second # [ 8.890272] systemd-udevd[338]: Using default interface naming scheme 'v260'.1397test # [ 8.905937] systemd[1]: Mounting /run/wrappers...1398test # [ 8.965669] systemd-resolved[352]: Using system hostname 'test'.1399test # [ 8.970804] systemd[1]: Mounted /run/wrappers.1400test # [ 8.976300] systemd[1]: Reached target Local File Systems.1401test # [ 8.980516] systemd[1]: Listening on Boot Loader Control Service Socket.1402test # [ 8.985507] systemd[1]: Starting register-nix-paths.service...1403test # [ 8.988103] systemd[1]: Starting Create SUID/SGID Wrappers...1404test # [ 8.993201] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1405test # [ 9.005487] systemd[1]: Starting Save Transient machine-id to Disk...1406second # [ 9.036186] systemd[1]: Mounting /run/wrappers...1407test # [ 9.021325] systemd[1]: Starting Create System Files and Directories...1408test # [ 9.024127] systemd[1]: Started Network Name Resolution.1409second # [ 9.115228] systemd-resolved[335]: Using system hostname 'second'.1410second # [ 9.121001] systemd[1]: Mounted /run/wrappers.1411second # [ 9.122359] systemd[1]: Reached target Local File Systems.1412second # [ 9.130364] systemd[1]: Listening on Boot Loader Control Service Socket.1413second # [ 9.135268] systemd[1]: Starting register-nix-paths.service...1414second # [ 9.139142] systemd[1]: Starting Create SUID/SGID Wrappers...1415second # [ 9.145469] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1416second # [ 9.150151] systemd[1]: Starting Save Transient machine-id to Disk...1417second # [ 9.162410] systemd[1]: Starting Create System Files and Directories...1418test # [ 9.149872] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1419second # [ 9.179218] systemd[1]: Started Network Name Resolution.1420test # [ 9.160435] systemd[1]: Finished Save Transient machine-id to Disk.1421second # [ 9.213056] systemd[1]: Started Rule-based Manager for Device Events and Files.1422test # [ 9.234425] systemd[1]: Started Rule-based Manager for Device Events and Files.1423test # [ 9.236318] systemd[1]: Finished Create System Files and Directories.1424test # [ 9.246812] systemd[1]: Starting Rebuild Journal Catalog...1425test # [ 9.257503] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1426second # [ 9.323406] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1427second # [ 9.333892] systemd[1]: Finished Save Transient machine-id to Disk.1428test # [ 9.377316] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1429second # [ 9.415633] systemd[1]: Finished Create System Files and Directories.1430second # [ 9.435391] systemd[1]: Starting Rebuild Journal Catalog...1431second # [ 9.438449] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1432test # [ 9.460215] systemd[1]: Finished Rebuild Journal Catalog.1433test # [ 9.477530] systemd[1]: Starting Update is Completed...1434second # [ 9.574741] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1435test # [ 9.572776] systemd[1]: Finished Update is Completed.1436test # [ 9.659480] systemd[1]: Finished Coldplug All udev Devices.1437second # [ 9.683094] systemd[1]: Finished Rebuild Journal Catalog.1438second # [ 9.696346] systemd[1]: Starting Update is Completed...1439second # [ 9.752175] systemd[1]: Finished Coldplug All udev Devices.1440second # [ 9.782309] systemd[1]: Finished Update is Completed.1441test # [ 9.806600] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1442second # [ 9.888272] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1443test # [ 10.082730] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1444second # [ 10.166392] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1445test # [ 10.170093] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1446test # [ 10.172519] systemd[1]: Finished Create SUID/SGID Wrappers.1447test # [ 10.181964] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1448second # [ 10.277463] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1449second # [ 10.296847] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1450second # [ 10.298939] systemd[1]: Finished Create SUID/SGID Wrappers.1451test # [ 10.350939] systemd[1]: Finished register-nix-paths.service.1452test # [ 10.352824] systemd[1]: Reached target System Initialization.1453test # [ 10.356367] systemd[1]: Started Discard unused filesystem blocks once a week.1454test # [ 10.358607] systemd[1]: Started Daily Cleanup of Temporary Directories.1455test # [ 10.362198] systemd[1]: Reached target Timer Units.1456test # [ 10.364168] systemd[1]: Listening on D-Bus System Message Bus Socket.1457test # [ 10.366286] systemd[1]: Listening on Nix Daemon Socket.1458test # [ 10.369812] systemd[1]: Listening on Authorization Manager Agent Helper.1459test # [ 10.373390] systemd[1]: Listening on Hostname Service Socket.1460test # [ 10.374564] systemd[1]: Reached target Socket Units.1461test # [ 10.378536] systemd[1]: Starting D-Bus System Message Bus...1462test # [ 10.383237] systemd[1]: Finished Firewall.1463test # [ 10.384758] systemd[1]: Reached target Preparation for Network.1464test # [ 10.392321] systemd[1]: Starting Network Management...1465test # [ 10.469006] (udev-worker)[426]: Network interface NamePolicy= disabled on kernel command line.1466test # [ 10.499256] (udev-worker)[423]: Network interface NamePolicy= disabled on kernel command line.1467second # [ 10.545552] systemd[1]: Finished register-nix-paths.service.1468second # [ 10.548417] systemd[1]: Reached target System Initialization.1469second # [ 10.551905] systemd[1]: Started Discard unused filesystem blocks once a week.1470second # [ 10.556312] systemd[1]: Started Daily Cleanup of Temporary Directories.1471second # [ 10.557479] systemd[1]: Reached target Timer Units.1472second # [ 10.559876] systemd[1]: Listening on D-Bus System Message Bus Socket.1473second # [ 10.565090] systemd[1]: Listening on Nix Daemon Socket.1474second # [ 10.566818] systemd[1]: Listening on Authorization Manager Agent Helper.1475second # [ 10.570562] systemd[1]: Listening on Hostname Service Socket.1476second # [ 10.573523] systemd[1]: Reached target Socket Units.1477second # [ 10.575451] systemd[1]: Starting D-Bus System Message Bus...1478second # [ 10.587207] (udev-worker)[373]: Network interface NamePolicy= disabled on kernel command line.1479second # [ 10.591306] (udev-worker)[374]: Network interface NamePolicy= disabled on kernel command line.1480second # [ 10.599305] systemd[1]: Finished Firewall.1481second # [ 10.602324] systemd[1]: Reached target Preparation for Network.1482second # [ 10.606808] systemd[1]: Starting Network Management...1483test # [ 10.608166] dbus-broker-launch[555]: Looking up NSS user entry for 'systemd-timesync'...1484test # [ 10.639112] cfg80211: Loading compiled-in X.509 certificates for regulatory database1485test # [ 10.644689] dbus-broker-launch[555]: NSS returned no entry for 'systemd-timesync'1486test # [ 10.645546] dbus-broker-launch[555]: Invalid user-name in /nix/store/78dx439lkpiwvm10lwiaw3q29qirb64w-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1487test # [ 10.686811] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1488test # [ 10.688779] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1489test # [ 10.716765] mousedev: PS/2 mouse device common for all mice1490test # [ 10.711608] dbus-broker-launch[555]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1491test # [ 10.717614] dbus-broker-launch[555]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1492test # [ 10.722456] dbus-broker-launch[555]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/sx40vhhwgyvayv82kxrdfsb24z1wvcrg-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1493test # [ 10.732613] dbus-broker-launch[555]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/0qin0mxlbmkcw8jq0dql0shhcfa41crl-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1494test # [ 10.740146] systemd[1]: Started D-Bus System Message Bus.1495test # [ 10.743894] systemd[1]: Reached target Basic System.1496test # [ 10.748161] systemd[1]: Starting Network Manager Script Dispatcher Service...1497test # [ 10.749656] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1498test # [ 10.754057] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1499second # [ 10.792179] dbus-broker-launch[532]: Looking up NSS user entry for 'systemd-timesync'...1500test # [ 10.778226] systemd-networkd[557]: /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.1501test # [ 10.792222] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1502test # [ 10.796123] systemd-networkd[557]: /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.1503second # [ 10.818827] dbus-broker-launch[532]: NSS returned no entry for 'systemd-timesync'1504test # [ 10.804147] systemd-networkd[557]: lo: Link UP1505test # [ 10.804640] systemd-networkd[557]: lo: Gained carrier1506second # [ 10.825221] dbus-broker-launch[532]: Invalid user-name in /nix/store/78dx439lkpiwvm10lwiaw3q29qirb64w-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1507test # [ 10.814460] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1508test # [ 10.822223] systemd-networkd[557]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1509test # [ 10.823023] systemd-networkd[557]: eth1: Link UP1510test # [ 10.823892] systemd-networkd[557]: eth1: Gained carrier1511test # [ 10.831991] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1512second # [ 10.859656] cfg80211: Loading compiled-in X.509 certificates for regulatory database1513test # [ 10.833497] dbus-broker-launch[555]: Ready1514second # [ 10.865217] mousedev: PS/2 mouse device common for all mice1515test # [ 10.839355] systemd[1]: Starting Network Manager...1516test # [ 10.865859] systemd[1]: Started backdoor.service.1517second # [ 10.902652] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1518second # [ 10.903203] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1519test # [ 10.885929] systemd[1]: Starting Import lastlog data into lastlog2 database...1520second # [ 10.906663] dbus-broker-launch[532]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1521second # [ 10.909460] dbus-broker-launch[532]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1522second # [ 10.914371] dbus-broker-launch[532]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/sx40vhhwgyvayv82kxrdfsb24z1wvcrg-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1523second # [ 10.920835] dbus-broker-launch[532]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/0qin0mxlbmkcw8jq0dql0shhcfa41crl-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1524test # [ 10.913916] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1525second # [ 10.931462] systemd[1]: Started D-Bus System Message Bus.1526second # [ 10.935682] systemd[1]: Reached target Basic System.1527second # [ 10.937958] systemd[1]: Starting Network Manager Script Dispatcher Service...1528second # [ 10.941552] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1529second # [ 10.951256] systemd[1]: Starting Network Manager...1530test # [ 10.931600] gihlbcfxrzdmnrhdz3bb45n3f4b0k7kd-wifi-secrets[568]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1531test # [ 10.947931] systemd[1]: Starting Post-Boot Actions...1532second # [ 10.969734] systemd[1]: Started backdoor.service.1533test # [ 10.961055] 9zw00hrl9ri87shidqszb96sxar6g9fp-wifi-secrets[570]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1534test # [ 10.968400] 4bb5snallvknp7ll9gml95rlfxqybb0y-wifi-secrets[569]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1535second # [ 11.002542] systemd[1]: Starting Import lastlog data into lastlog2 database...1536second # [ 11.006012] systemd-networkd[535]: /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.1537second # [ 11.015814] dbus-broker-launch[532]: Ready1538second # [ 11.019771] systemd-networkd[535]: /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.1539second # [ 11.024748] systemd-networkd[535]: lo: Link UP1540test # [ 11.006904] systemd[1]: Started Reset console on configuration changes.1541test # [ 11.010430] systemd[1]: Started Network Management.1542second # [ 11.030138] systemd-networkd[535]: lo: Gained carrier1543test # [ 11.015952] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1544second # [ 11.036755] systemd-networkd[535]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1545second # [ 11.038017] systemd-networkd[535]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1546second # [ 11.038699] systemd-networkd[535]: eth1: Link UP1547second # [ 11.039151] systemd-networkd[535]: eth1: Gained carrier1548second # [ 11.039615] systemd-networkd[535]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1549second # [ 11.054063] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1550test # [ 11.043174] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1551test # [ 11.047184] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1552test # [ 11.054097] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1553test # [ 11.057770] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1554test # [ 11.066264] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1555second # [ 11.094039] systemd[1]: Starting Post-Boot Actions...1556second # [ 11.112946] systemd[1]: Started Reset console on configuration changes.1557second # [ 11.124165] systemd[1]: Started Network Management.1558second # [ 11.141107] gihlbcfxrzdmnrhdz3bb45n3f4b0k7kd-wifi-secrets[546]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1559test # [ 11.148405] systemd[1]: Finished Post-Boot Actions.1560test # connecting to host...1561second # [ 11.175336] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1562second # [ 11.188075] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1563second # [ 11.208220] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1564test # [ 11.192837] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1565second # connecting to host...1566test: Guest shell says: b'Spawning backdoor root shell...\n'1567test: connected to guest root shell1568test # [ 11.232351] systemd[1]: Started Name Service Cache Daemon (nsncd).1569test: (connecting took 11.54 seconds)1570test # [ 11.236705] nsncd[574]: Sep 20 15:13:06.063 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1571test: (finished: waiting for the VM to finish booting, in 11.54 seconds)1572test # [ 11.243603] systemd[1]: Finished Import lastlog data into lastlog2 database.1573test # [ 11.249235] systemd[1]: Reached target Host and Network Name Lookups.1574test # [ 11.249832] systemd[1]: Reached target User and Group Name Lookups.1575test # [ 11.264357] systemd[1]: Starting User Login Management...1576test # [ 11.267169] systemd[1]: Started Network Manager Script Dispatcher Service.1577second # [ 11.339228] systemd[1]: Started Name Service Cache Daemon (nsncd).1578second # [ 11.343214] nsncd[550]: Sep 20 15:13:06.160 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1579second # [ 11.353525] systemd[1]: Finished Post-Boot Actions.1580second # [ 11.354056] systemd[1]: Reached target Host and Network Name Lookups.1581second # [ 11.354574] systemd[1]: Reached target User and Group Name Lookups.1582second # [ 11.376109] systemd[1]: Starting User Login Management...1583test # [ 11.357401] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1584second # [ 11.397410] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1585second # [ 11.409168] systemd[1]: Finished Import lastlog data into lastlog2 database.1586second # [ 11.420942] systemd[1]: Started Network Manager Script Dispatcher Service.1587test # [ 11.433219] systemd-logind[617]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1588test # [ 11.437051] systemd-logind[617]: Watching system buttons on /dev/input/event0 (gpio-keys)1589test # [ 11.440526] systemd-logind[617]: New seat seat0.1590test # [ 11.442978] systemd[1]: Started User Login Management.1591test # [ 11.454656] systemd[1]: Starting linger-users.service...1592test # [ 11.459390] systemd[1]: Starting Hostname Service...1593second # [ 11.524966] systemd[1]: Starting Hostname Service...1594test # [ 11.532096] systemd[1]: linger-users.service: Deactivated successfully.1595second # [ 11.551757] systemd-logind[580]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1596test # [ 11.533280] systemd[1]: Finished linger-users.service.1597second # [ 11.555248] systemd-logind[580]: Watching system buttons on /dev/input/event0 (gpio-keys)1598second # [ 11.561260] systemd-logind[580]: New seat seat0.1599second # [ 11.562004] systemd[1]: Started User Login Management.1600second # [ 11.568547] systemd[1]: Starting linger-users.service...1601test # [ 11.586851] systemd[1]: Started Hostname Service.1602second # [ 11.654013] systemd[1]: linger-users.service: Deactivated successfully.1603second # [ 11.655233] systemd[1]: Finished linger-users.service.1604second # [ 11.669305] systemd[1]: Started Hostname Service.1605test # [ 11.696755] systemd-networkd[557]: eth0: Link UP1606test # [ 11.697241] systemd-networkd[557]: eth0: Gained carrier1607test # [ 11.715515] systemd[1]: Started Network Manager.1608test # [ 11.717448] systemd[1]: Reached target Network.1609test # [ 11.724078] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1610test # [ 11.730800] systemd[1]: Starting Permit User Sessions...1611second # [ 11.774642] systemd-networkd[535]: eth0: Link UP1612second # [ 11.775141] systemd-networkd[535]: eth0: Gained carrier1613second # [ 11.797052] systemd[1]: Started Network Manager.1614second # [ 11.799428] systemd[1]: Reached target Network.1615second # [ 11.805754] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1616second # [ 11.811037] systemd[1]: Starting Permit User Sessions...1617test # [ 11.885828] systemd[1]: Finished Permit User Sessions.1618test # [ 11.900365] systemd-resolved[352]: eth0: Bus client set default route setting: yes1619test # [ 11.910130] systemd-resolved[352]: eth0: Bus client set DNS server list to: 10.0.2.31620test # [ 11.915015] systemd[1]: Started Getty on tty1.1621test # [ 11.915472] systemd[1]: Reached target Login Prompts.1622second # [ 11.974940] systemd[1]: Finished Permit User Sessions.1623test # [ 11.960101] NetworkManager[571]: <warn> [1789917186.7833] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1624second # [ 11.986276] systemd[1]: Started Getty on tty1.1625second # [ 11.988167] systemd[1]: Reached target Login Prompts.1626test # [ 12.007708] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1627test # [ 12.012119] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1628second # [ 12.046374] systemd-resolved[335]: eth0: Bus client set default route setting: yes1629second # [ 12.049887] systemd-resolved[335]: eth0: Bus client set DNS server list to: 10.0.2.31630second # [ 12.112408] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1631second # [ 12.116201] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1632test # [ 12.155012] systemd[1]: Reached target Multi-User System.1633test # [ 12.159170] systemd[1]: Startup finished in 719ms (kernel) + 5.183s (initrd) + 6.256s (userspace) = 12.158s.1634second # [ 12.257837] systemd-networkd[535]: eth1: Gained IPv6LL1635second # [ 12.274313] systemd[1]: Reached target Multi-User System.1636second # [ 12.274959] systemd[1]: Startup finished in 729ms (kernel) + 5.219s (initrd) + 6.321s (userspace) = 12.270s.1637test: (finished: waiting for unit NetworkManager.service, in 12.79 seconds)1638test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1639test # [ 12.545494] systemd-networkd[557]: eth1: Gained IPv6LL1640test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.05 seconds)1641test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1642second # [ 12.626034] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31643test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.04 seconds)1644test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1645test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.04 seconds)1646(finished: run the VM test script, in 13.41 seconds)1647test # [ 12.687236] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31648test script finished in 13.49s1649cleanup1650kill QemuMachine (pid 14)1651second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/76jq0mngg7m8zv5yjz1qgg0xspzi8x2w-python3-3.13.15/bin/python3.13)1652kill QemuMachine (pid 15)1653test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/76jq0mngg7m8zv5yjz1qgg0xspzi8x2w-python3-3.13.15/bin/python3.13)1654(finished: cleanup, in 0.30 seconds)