vm-test-run-wifi
checks.aarch64-linux.wifi
· build #1008
· raw
1build01: building q58rgd3fv58nasfdgq2p7z6khirs1c4c-vm-test-run-wifi.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600 seconds6run the VM test script7additionally exposed symbols:8 second, test,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12second: starting vm13test: starting vm14second # Disk image does not exist, creating the virtualisation disk image...15second # Formatting '/build/vm-state-second/tmp.TMYsDRJ0lL', 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: 5b110db3-920f-4167-b169-aa3728dadabb20second # 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.29second: QEMU running (pid 14)30test # Disk image does not exist, creating the virtualisation disk image...31test # Formatting '/build/vm-state-test/tmp.704sDnfSns', fmt=raw size=107374182432test: QEMU running (pid 15)33test # mke2fs 1.47.4 (6-Mar-2025)34test # Discarding device blocks: 0/262144 done35test # Creating filesystem with 262144 4k blocks and 65536 inodes36test # Filesystem UUID: 444db581-12ab-419f-8e7c-7420bcdc11f737test # Superblock backups stored on blocks:38test # 32768, 98304, 163840, 22937639test # 40test # Allocating group tables: 0/8 done41test # Writing inode tables: 0/8 done42test # Creating journal (8192 blocks): done43test # Writing superblocks and filesystem accounting information: 0/8 done44test # 45test # Virtualisation disk image created.46(finished: start all VMs, in 0.48 seconds)47test: waiting for unit NetworkManager.service48test: waiting for the VM to finish booting49second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]50second # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sun Oct 11 13:26:12 UTC 202651second # [ 0.000000] KASLR enabled52second # [ 0.000000] random: crng init done53second # [ 0.000000] Machine model: linux,dummy-virt54second # [ 0.000000] efi: UEFI not found.55second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT56second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]57second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]58second # [ 0.000000] Zone ranges:59second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]60second # [ 0.000000] DMA32 empty61second # [ 0.000000] Normal empty62second # [ 0.000000] Device empty63second # [ 0.000000] Movable zone start for each node64second # [ 0.000000] Early memory node ranges65second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]66second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]67second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000068second # [ 0.000000] psci: probing for conduit method from DT.69second # [ 0.000000] psci: PSCIv1.3 detected in firmware.70second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs71second # [ 0.000000] psci: Trusted OS migration not required72second # [ 0.000000] psci: SMC Calling Convention v1.173second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)74second # [ 0.000000] percpu: Embedded 76 pages/cpu s186520 r8192 d116584 u31129675second # [ 0.000000] Detected PIPT I-cache on CPU076second # [ 0.000000] CPU features: detected: GICv3 CPU interface77second # [ 0.000000] CPU features: detected: Spectre-v478second # [ 0.000000] CPU features: detected: Spectre-BHB79second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR80second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)81second # [ 0.000000] CPU features: detected: ARM erratum 141804082second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion83second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing84second # [ 0.000000] alternatives: applying boot alternatives85second # [ 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/z6dfhlsgl1gb2ncijyzyi7zwqg7nfsas-nixos-system-second-test/init regInfo=/nix/store/yjaak5qli0j3mjs14x5l3d0bjhphnflj-closure-info/registration console=ttyAMA0,115200n8 console=tty086second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/yjaak5qli0j3mjs14x5l3d0bjhphnflj-closure-info/registration", will be passed to user space.87second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes88second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)89second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)90second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB91second # [ 0.000000] software IO TLB: area num 1.92second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)93second # [ 0.000000] Fallback order for Node 0: 094second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214495second # [ 0.000000] Policy zone: DMA96second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off97second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=198second # [ 0.000000] allocated 2097152 bytes of page_ext99second # [ 0.000000] ftrace: allocating 74856 entries in 294 pages100second # [ 0.000000] ftrace: allocated 294 pages with 4 groups101second # [ 0.000000] rcu: Hierarchical RCU implementation.102second # [ 0.000000] rcu: RCU event tracing is enabled.103second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.104second # [ 0.000000] Trampoline variant of Tasks RCU enabled.105second # [ 0.000000] Rude variant of Tasks RCU enabled.106second # [ 0.000000] Tracing variant of Tasks RCU enabled.107second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.108second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1109second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.110second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.111second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.112second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0113second # [ 0.000000] GICv3: 256 SPIs implemented114second # [ 0.000000] GICv3: 0 Extended SPIs implemented115second # [ 0.000000] Root IRQ handler: gic_handle_irq116second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI117second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0118second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000119second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]120second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)121second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)122second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000123second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000124second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.125test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]126second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns127second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040128test # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sun Oct 11 13:26:12 UTC 2026129second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).130test # [ 0.000000] KASLR enabled131second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns132second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns133second # [ 0.000074] arm-pv: using stolen time PV134second # [ 0.000482] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)135second # [ 0.000852] Console: colour dummy device 80x25136second # [ 0.000865] printk: legacy console [tty0] enabled137second # [ 0.001097] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)138test # [ 0.000000] random: crng init done139test # [ 0.000000] Machine model: linux,dummy-virt140test # [ 0.000000] efi: UEFI not found.141test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT142test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]143test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]144test # [ 0.000000] Zone ranges:145test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]146test # [ 0.000000] DMA32 empty147test # [ 0.000000] Normal empty148test # [ 0.000000] Device empty149test # [ 0.000000] Movable zone start for each node150second # [ 0.001102] pid_max: default: 32768 minimum: 301151test # [ 0.000000] Early memory node ranges152second # [ 0.001177] LSM: initializing lsm=capability,landlock,yama,bpf,ima153second # [ 0.001317] landlock: Up and running.154second # [ 0.001319] Yama: becoming mindful.155second # [ 0.001767] LSM support for eBPF active156second # [ 0.001969] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)157second # [ 0.001988] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)158second # [ 0.004798] rcu: Hierarchical SRCU implementation.159second # [ 0.004806] rcu: Max phase no-delay instances is 1000.160test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]161test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]162test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000163test # [ 0.000000] psci: probing for conduit method from DT.164test # [ 0.000000] psci: PSCIv1.3 detected in firmware.165test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs166test # [ 0.000000] psci: Trusted OS migration not required167test # [ 0.000000] psci: SMC Calling Convention v1.1168second # [ 0.005840] fsl-mc MSI: its@8080000 domain created169test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)170test # [ 0.000000] percpu: Embedded 76 pages/cpu s186520 r8192 d116584 u311296171test # [ 0.000000] Detected PIPT I-cache on CPU0172test # [ 0.000000] CPU features: detected: GICv3 CPU interface173test # [ 0.000000] CPU features: detected: Spectre-v4174second # [ 0.006050] EFI services will not be available.175second # [ 0.006156] smp: Bringing up secondary CPUs ...176second # [ 0.006186] smp: Brought up 1 node, 1 CPU177second # [ 0.006189] SMP: Total of 1 processors activated.178second # [ 0.006191] CPU: All CPU(s) started at EL1179second # [ 0.006204] CPU features: detected: 32-bit EL0 Support180second # [ 0.006207] CPU features: detected: Data cache clean to the PoU not required for I/D coherence181test # [ 0.000000] CPU features: detected: Spectre-BHB182test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR183second # [ 0.006210] CPU features: detected: Common not Private translations184test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)185second # [ 0.006212] CPU features: detected: CRC32 instructions186test # [ 0.000000] CPU features: detected: ARM erratum 1418040187second # [ 0.006214] CPU features: detected: Data cache clean to Point of Persistence188test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion189second # [ 0.006216] CPU features: detected: Enhanced Virtualization Traps190test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing191second # [ 0.006227] CPU features: detected: RCpc load-acquire (LDAPR)192test # [ 0.000000] alternatives: applying boot alternatives193second # [ 0.006229] CPU features: detected: LSE atomic instructions194second # [ 0.006231] CPU features: detected: Privileged Access Never195second # [ 0.006233] CPU features: detected: PMUv3196second # [ 0.006235] CPU features: detected: RAS Extension Support197second # [ 0.006240] CPU features: detected: Speculative Store Bypassing Safe (SSBS)198test # [ 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/dsibvd7a0gzhgcxd4vwarm1bkklv69j7-nixos-system-test-test/init regInfo=/nix/store/8mgkffgir0wclplxs9n4z4sx25a9k6l8-closure-info/registration console=ttyAMA0,115200n8 console=tty0199second # [ 0.006265] alternatives: applying system-wide alternatives200second # [ 0.007728] CPU features: detected: Hardware dirty bit management on CPU0201test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/8mgkffgir0wclplxs9n4z4sx25a9k6l8-closure-info/registration", will be passed to user space.202test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes203second # [ 0.007941] Memory: 892924K/1048576K available (24384K kernel code, 7090K rwdata, 26576K rodata, 4736K init, 1103K bss, 114332K reserved, 32768K cma-reserved)204second # [ 0.008464] devtmpfs: initialized205test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)206second # [ 0.010024] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)207test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)208second # [ 0.010055] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).209test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB210second # [ 0.010214] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL211test # [ 0.000000] software IO TLB: area num 1.212second # [ 0.010217] 0 pages in range for non-PLT usage213test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)214second # [ 0.010218] 508256 pages in range for PLT usage215test # [ 0.000000] Fallback order for Node 0: 0216second # [ 0.010354] pinctrl core: initialized pinctrl subsystem217second # [ 0.011253] DMI not present or invalid.218test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144219test # [ 0.000000] Policy zone: DMA220second # [ 0.014928] NET: Registered PF_NETLINK/PF_ROUTE protocol family221test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off222second # [ 0.017374] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations223test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1224second # [ 0.017547] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations225test # [ 0.000000] allocated 2097152 bytes of page_ext226test # [ 0.000000] ftrace: allocating 74856 entries in 294 pages227second # [ 0.017722] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations228test # [ 0.000000] ftrace: allocated 294 pages with 4 groups229second # [ 0.017743] audit: initializing netlink subsys (disabled)230test # [ 0.000000] rcu: Hierarchical RCU implementation.231second # [ 0.018562] thermal_sys: Registered thermal governor 'fair_share'232test # [ 0.000000] rcu: RCU event tracing is enabled.233second # [ 0.018565] thermal_sys: Registered thermal governor 'bang_bang'234test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.235second # [ 0.018568] thermal_sys: Registered thermal governor 'step_wise'236test # [ 0.000000] Trampoline variant of Tasks RCU enabled.237second # [ 0.018570] thermal_sys: Registered thermal governor 'user_space'238test # [ 0.000000] Rude variant of Tasks RCU enabled.239test # [ 0.000000] Tracing variant of Tasks RCU enabled.240second # [ 0.018572] thermal_sys: Registered thermal governor 'power_allocator'241test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.242second # [ 0.018635] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1243second # [ 0.018653] cpuidle: using governor ladder244test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1245second # [ 0.018658] cpuidle: using governor menu246second # [ 0.018797] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.247test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.248second # [ 0.018823] ASID allocator initialised with 32768 entries249second # [ 0.019482] Serial: AMBA PL011 UART driver250test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.251second # [ 0.023091] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1252test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.253second # [ 0.023287] printk: console [ttyAMA0] enabled254test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0255test # [ 0.000000] GICv3: 256 SPIs implemented256test # [ 0.000000] GICv3: 0 Extended SPIs implemented257test # [ 0.000000] Root IRQ handler: gic_handle_irq258test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI259test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0260test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000261test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]262test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)263test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)264test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000265test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000266test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.267test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns268test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040269test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).270test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns271test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns272test # [ 0.000060] arm-pv: using stolen time PV273test # [ 0.000407] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)274test # [ 0.000736] Console: colour dummy device 80x25275test # [ 0.000747] printk: legacy console [tty0] enabled276test # [ 0.000973] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)277test # [ 0.000978] pid_max: default: 32768 minimum: 301278test # [ 0.001052] LSM: initializing lsm=capability,landlock,yama,bpf,ima279second # [ 0.098571] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages280test # [ 0.001179] landlock: Up and running.281test # [ 0.001181] Yama: becoming mindful.282second # [ 0.098587] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page283test # [ 0.001646] LSM support for eBPF active284second # [ 0.098589] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages285test # [ 0.001814] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)286second # [ 0.098594] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page287test # [ 0.001837] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)288second # [ 0.098596] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages289test # [ 0.004607] rcu: Hierarchical SRCU implementation.290second # [ 0.098598] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page291test # [ 0.004614] rcu: Max phase no-delay instances is 1000.292second # [ 0.098600] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages293test # [ 0.005616] fsl-mc MSI: its@8080000 domain created294test # [ 0.005843] EFI services will not be available.295second # [ 0.098602] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page296second # [ 0.099619] fbcon: Taking over console297test # [ 0.005931] smp: Bringing up secondary CPUs ...298second # [ 0.099637] ACPI: Interpreter disabled.299test # [ 0.005958] smp: Brought up 1 node, 1 CPU300test # [ 0.005961] SMP: Total of 1 processors activated.301second # [ 0.100337] iommu: Default domain type: Translated302test # [ 0.005963] CPU: All CPU(s) started at EL1303second # [ 0.100341] iommu: DMA domain TLB invalidation policy: strict mode304test # [ 0.005975] CPU features: detected: 32-bit EL0 Support305second # [ 0.100662] SCSI subsystem initialized306second # [ 0.100888] usbcore: registered new interface driver usbfs307test # [ 0.005978] CPU features: detected: Data cache clean to the PoU not required for I/D coherence308second # [ 0.100917] usbcore: registered new interface driver hub309test # [ 0.005981] CPU features: detected: Common not Private translations310second # [ 0.100925] usbcore: registered new device driver usb311test # [ 0.005983] CPU features: detected: CRC32 instructions312second # [ 0.101128] pps_core: LinuxPPS API ver. 1 registered313test # [ 0.005985] CPU features: detected: Data cache clean to Point of Persistence314second # [ 0.101131] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>315test # [ 0.005988] CPU features: detected: Enhanced Virtualization Traps316second # [ 0.101136] PTP clock support registered317second # [ 0.101162] EDAC MC: Ver: 3.0.0318test # [ 0.005997] CPU features: detected: RCpc load-acquire (LDAPR)319test # [ 0.006000] CPU features: detected: LSE atomic instructions320test # [ 0.006002] CPU features: detected: Privileged Access Never321test # [ 0.006004] CPU features: detected: PMUv3322test # [ 0.006005] CPU features: detected: RAS Extension Support323test # [ 0.006009] CPU features: detected: Speculative Store Bypassing Safe (SSBS)324test # [ 0.006030] alternatives: applying system-wide alternatives325test # [ 0.007523] CPU features: detected: Hardware dirty bit management on CPU0326test # [ 0.007777] Memory: 892928K/1048576K available (24384K kernel code, 7090K rwdata, 26576K rodata, 4736K init, 1103K bss, 114332K reserved, 32768K cma-reserved)327second # [ 0.110762] scmi_core: SCMI protocol bus registered328test # [ 0.008276] devtmpfs: initialized329second # [ 0.111161] FPGA manager framework330second # [ 0.114669] vgaarb: loaded331test # [ 0.009814] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)332test # [ 0.009842] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).333test # [ 0.009988] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL334test # [ 0.009991] 0 pages in range for non-PLT usage335test # [ 0.009992] 508256 pages in range for PLT usage336test # [ 0.010107] pinctrl core: initialized pinctrl subsystem337test # [ 0.010953] DMI not present or invalid.338test # [ 0.014455] NET: Registered PF_NETLINK/PF_ROUTE protocol family339second # [ 0.115806] clocksource: Switched to clocksource arch_sys_counter340test # [ 0.016925] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations341test # [ 0.017082] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations342test # [ 0.017255] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations343test # [ 0.017279] audit: initializing netlink subsys (disabled)344test # [ 0.018068] thermal_sys: Registered thermal governor 'fair_share'345test # [ 0.018071] thermal_sys: Registered thermal governor 'bang_bang'346test # [ 0.018074] thermal_sys: Registered thermal governor 'step_wise'347test # [ 0.018076] thermal_sys: Registered thermal governor 'user_space'348test # [ 0.018078] thermal_sys: Registered thermal governor 'power_allocator'349test # [ 0.018122] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1350test # [ 0.018138] cpuidle: using governor ladder351test # [ 0.018143] cpuidle: using governor menu352test # [ 0.018291] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.353test # [ 0.018313] ASID allocator initialised with 32768 entries354test # [ 0.019047] Serial: AMBA PL011 UART driver355test # [ 0.022715] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1356test # [ 0.022910] printk: console [ttyAMA0] enabled357second # [ 0.124907] VFS: Disk quotas dquot_6.6.0358second # [ 0.124935] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)359second # [ 0.125237] netfs: FS-Cache loaded360second # [ 0.125364] pnp: PnP ACPI: disabled361second # [ 0.129980] NET: Registered PF_INET protocol family362second # [ 0.130158] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)363test # [ 0.098203] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages364test # [ 0.098218] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page365test # [ 0.098221] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages366test # [ 0.098225] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page367test # [ 0.098227] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages368test # [ 0.098229] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page369test # [ 0.098232] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages370test # [ 0.098234] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page371test # [ 0.099273] fbcon: Taking over console372test # [ 0.099290] ACPI: Interpreter disabled.373test # [ 0.099964] iommu: Default domain type: Translated374test # [ 0.099968] iommu: DMA domain TLB invalidation policy: strict mode375test # [ 0.100290] SCSI subsystem initialized376test # [ 0.100580] usbcore: registered new interface driver usbfs377test # [ 0.100611] usbcore: registered new interface driver hub378test # [ 0.100625] usbcore: registered new device driver usb379test # [ 0.100845] pps_core: LinuxPPS API ver. 1 registered380test # [ 0.100848] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>381test # [ 0.100865] PTP clock support registered382test # [ 0.100898] EDAC MC: Ver: 3.0.0383test # [ 0.110337] scmi_core: SCMI protocol bus registered384test # [ 0.114483] FPGA manager framework385test # [ 0.115989] vgaarb: loaded386test # [ 0.116261] clocksource: Switched to clocksource arch_sys_counter387second # [ 0.154683] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)388second # [ 0.154709] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)389test # [ 0.124742] VFS: Disk quotas dquot_6.6.0390second # [ 0.154721] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)391test # [ 0.124770] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)392second # [ 0.154760] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)393test # [ 0.126354] netfs: FS-Cache loaded394second # [ 0.154826] TCP: Hash tables configured (established 8192 bind 8192)395second # [ 0.154961] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)396second # [ 0.154989] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)397second # [ 0.155023] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)398second # [ 0.155136] NET: Registered PF_UNIX/PF_LOCAL protocol family399second # [ 0.155167] NET: Registered PF_XDP protocol family400second # [ 0.155186] PCI: CLS 0 bytes, default 64401test # [ 0.126493] pnp: PnP ACPI: disabled402second # [ 0.155423] Trying to unpack rootfs image as initramfs...403test # [ 0.133075] NET: Registered PF_INET protocol family404test # [ 0.133284] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)405second # [ 0.169802] kvm [1]: HYP mode not available406test # [ 0.162311] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)407test # [ 0.162336] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)408test # [ 0.162350] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)409test # [ 0.162391] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)410test # [ 0.162458] TCP: Hash tables configured (established 8192 bind 8192)411test # [ 0.162584] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)412test # [ 0.162641] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)413test # [ 0.162699] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)414test # [ 0.162802] NET: Registered PF_UNIX/PF_LOCAL protocol family415test # [ 0.162873] NET: Registered PF_XDP protocol family416test # [ 0.162888] PCI: CLS 0 bytes, default 64417test # [ 0.163074] Trying to unpack rootfs image as initramfs...418test # [ 0.176301] kvm [1]: HYP mode not available419second # [ 0.236783] Initialise system trusted keyrings420second # [ 0.236915] workingset: timestamp_bits=42 max_order=18 bucket_order=0421second # [ 0.237352] squashfs: version 4.0 (2009/01/31) Phillip Lougher422second # [ 0.237395] 9p: Installing v9fs 9p2000 file system support423second # [ 0.260411] Key type asymmetric registered424second # [ 0.260436] Asymmetric key parser 'x509' registered425second # [ 0.260499] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)426second # [ 0.260607] io scheduler mq-deadline registered427second # [ 0.260612] io scheduler kyber registered428second # [ 0.271985] pl061_gpio 9030000.pl061: PL061 GPIO chip registered429second # [ 0.272372] ledtrig-cpu: registered to indicate activity on CPUs430second # [ 0.272737] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:431second # [ 0.272758] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000432second # [ 0.272771] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000433second # [ 0.272775] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000434second # [ 0.272801] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits435second # [ 0.272827] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]436second # [ 0.272906] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00437second # [ 0.272928] pci_bus 0000:00: root bus resource [bus 00-ff]438second # [ 0.272932] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]439second # [ 0.272935] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]440second # [ 0.272937] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]441second # [ 0.273037] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint442test # [ 0.244720] Initialise system trusted keyrings443test # [ 0.244850] workingset: timestamp_bits=42 max_order=18 bucket_order=0444second # [ 0.273510] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint445test # [ 0.245315] squashfs: version 4.0 (2009/01/31) Phillip Lougher446second # [ 0.273621] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]447test # [ 0.245352] 9p: Installing v9fs 9p2000 file system support448second # [ 0.273630] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]449second # [ 0.273648] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]450second # [ 0.273658] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]451second # [ 0.273948] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint452second # [ 0.274058] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]453second # [ 0.274067] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]454second # [ 0.274086] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]455second # [ 0.274364] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint456second # [ 0.274469] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]457second # [ 0.274478] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]458second # [ 0.274496] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]459second # [ 0.274764] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint460second # [ 0.274870] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]461second # [ 0.274879] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]462second # [ 0.274897] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]463second # [ 0.275171] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint464second # [ 0.275277] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]465second # [ 0.275286] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]466second # [ 0.275304] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]467second # [ 0.275575] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint468second # [ 0.275681] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]469second # [ 0.275689] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]470second # [ 0.275707] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]471second # [ 0.280388] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint472second # [ 0.280502] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]473second # [ 0.280511] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]474second # [ 0.280528] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]475second # [ 0.280541] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]476second # [ 0.280829] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint477second # [ 0.280939] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]478second # [ 0.280956] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]479second # [ 0.281224] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint480second # [ 0.281332] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]481second # [ 0.281348] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]482second # [ 0.281616] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint483second # [ 0.281724] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]484second # [ 0.281891] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint485test # [ 0.270077] Key type asymmetric registered486second # [ 0.282085] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]487test # [ 0.270100] Asymmetric key parser 'x509' registered488second # [ 0.282095] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]489test # [ 0.270177] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)490second # [ 0.282113] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]491test # [ 0.270280] io scheduler mq-deadline registered492test # [ 0.270284] io scheduler kyber registered493second # [ 0.282400] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint494second # [ 0.282507] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]495second # [ 0.282516] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]496second # [ 0.282533] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]497second # [ 0.282954] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned498second # [ 0.282961] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned499second # [ 0.282964] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned500second # [ 0.282996] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned501second # [ 0.283026] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned502second # [ 0.283056] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned503second # [ 0.283086] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned504second # [ 0.283115] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned505second # [ 0.283145] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned506second # [ 0.283176] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned507second # [ 0.283206] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned508second # [ 0.283235] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned509second # [ 0.283296] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned510second # [ 0.283325] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned511test # [ 0.280915] pl061_gpio 9030000.pl061: PL061 GPIO chip registered512second # [ 0.283338] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned513test # [ 0.281264] ledtrig-cpu: registered to indicate activity on CPUs514second # [ 0.283351] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned515test # [ 0.281588] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:516second # [ 0.283363] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned517test # [ 0.281605] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000518second # [ 0.283376] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned519second # [ 0.283389] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned520test # [ 0.281619] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000521second # [ 0.283401] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned522test # [ 0.281623] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000523second # [ 0.283413] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned524test # [ 0.281648] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits525second # [ 0.283425] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned526second # [ 0.283438] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned527second # [ 0.283451] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned528second # [ 0.283464] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned529second # [ 0.283478] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned530second # [ 0.283492] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned531second # [ 0.283506] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned532second # [ 0.283519] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned533second # [ 0.283538] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned534second # [ 0.283551] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned535second # [ 0.283564] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned536second # [ 0.283576] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned537second # [ 0.283589] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned538second # [ 0.283606] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]539second # [ 0.283611] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]540second # [ 0.283613] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]541second # [ 0.284305] pci 0000:00:0a.0: enabling device (0000 -> 0002)542test # [ 0.281670] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]543test # [ 0.281741] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00544test # [ 0.281757] pci_bus 0000:00: root bus resource [bus 00-ff]545test # [ 0.281760] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]546test # [ 0.281763] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]547test # [ 0.281765] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]548test # [ 0.281852] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint549test # [ 0.282273] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint550test # [ 0.282390] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]551test # [ 0.282398] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]552test # [ 0.282417] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]553test # [ 0.282426] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]554test # [ 0.282705] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint555test # [ 0.282821] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]556test # [ 0.282830] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]557test # [ 0.282848] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]558test # [ 0.283136] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint559test # [ 0.283245] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]560test # [ 0.283254] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]561test # [ 0.283272] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]562test # [ 0.283547] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint563test # [ 0.283656] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]564test # [ 0.283665] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]565test # [ 0.283683] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]566test # [ 0.283965] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint567test # [ 0.284074] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]568test # [ 0.284083] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]569test # [ 0.284101] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]570second # [ 0.345215] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)571second # [ 0.347232] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)572test # [ 0.311812] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint573test # [ 0.311931] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]574test # [ 0.311940] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]575test # [ 0.311959] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]576test # [ 0.312302] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint577test # [ 0.312412] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]578test # [ 0.312420] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]579test # [ 0.312437] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]580test # [ 0.312451] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]581test # [ 0.312747] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint582test # [ 0.312858] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]583second # [ 0.357422] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)584test # [ 0.312875] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]585second # [ 0.359270] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)586test # [ 0.313161] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint587test # [ 0.313270] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]588test # [ 0.313286] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]589test # [ 0.313534] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint590test # [ 0.313638] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]591test # [ 0.313794] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint592test # [ 0.313979] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]593test # [ 0.313989] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]594test # [ 0.314007] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]595test # [ 0.314288] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint596test # [ 0.314395] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]597test # [ 0.314404] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]598test # [ 0.314422] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]599test # [ 0.314838] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned600test # [ 0.314845] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned601test # [ 0.314848] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned602test # [ 0.314881] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned603test # [ 0.314910] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned604test # [ 0.314938] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned605test # [ 0.314965] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned606second # [ 0.369467] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)607test # [ 0.314992] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned608second # [ 0.371297] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)609test # [ 0.315020] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned610test # [ 0.315048] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned611test # [ 0.315075] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned612test # [ 0.315102] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned613test # [ 0.315156] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned614test # [ 0.315185] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned615test # [ 0.315196] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned616test # [ 0.315209] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned617test # [ 0.315221] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned618test # [ 0.315233] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned619test # [ 0.315245] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned620test # [ 0.315258] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned621test # [ 0.315270] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned622test # [ 0.315283] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned623test # [ 0.315296] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned624test # [ 0.315309] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned625test # [ 0.315322] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned626test # [ 0.315362] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned627test # [ 0.315375] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned628test # [ 0.315387] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned629second # [ 0.382265] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)630test # [ 0.315399] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned631test # [ 0.315416] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned632test # [ 0.315429] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned633test # [ 0.315441] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned634test # [ 0.315453] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned635test # [ 0.315465] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned636test # [ 0.315483] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]637second # [ 0.384730] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)638test # [ 0.315488] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]639second # [ 0.386492] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)640test # [ 0.315490] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]641test # [ 0.316153] pci 0000:00:0a.0: enabling device (0000 -> 0002)642second # [ 0.396487] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)643second # [ 0.398794] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)644test # [ 0.366422] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)645test # [ 0.368980] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)646test # [ 0.370848] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)647second # [ 0.408599] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled648second # [ 0.409722] msm_serial: driver initialized649second # [ 0.409793] SuperH (H)SCI(F) driver initialized650second # [ 0.409832] STM32 USART driver initialized651test # [ 0.380915] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)652test # [ 0.382733] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)653test # [ 0.385673] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)654test # [ 0.387526] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)655test # [ 0.394466] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)656test # [ 0.396227] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)657second # [ 0.434301] loop: module loaded658second # [ 0.434439] virtio_blk virtio5: 1/0/0 default/read/poll queues659second # [ 0.435116] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)660second # [ 0.440358] megasas: 07.734.00.00-rc1661second # [ 0.440906] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]662second # [ 0.443002] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000663test # [ 0.406264] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)664second # [ 0.443016] Intel/Sharp Extended Query Table at 0x0031665test # [ 0.409175] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)666second # [ 0.453367] Using buffer write method667second # [ 0.453440] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]668second # [ 0.455289] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000669second # [ 0.455306] Intel/Sharp Extended Query Table at 0x0031670test # [ 0.421249] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled671test # [ 0.422265] msm_serial: driver initialized672test # [ 0.422358] SuperH (H)SCI(F) driver initialized673test # [ 0.422399] STM32 USART driver initialized674second # [ 0.459467] Using buffer write method675second # [ 0.459486] Concatenating MTD devices:676second # [ 0.459488] (0): "0.flash"677second # [ 0.459490] (1): "0.flash"678second # [ 0.459491] into device "0.flash"679test # [ 0.446890] loop: module loaded680test # [ 0.447036] virtio_blk virtio5: 1/0/0 default/read/poll queues681test # [ 0.447768] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)682test # [ 0.452812] megasas: 07.734.00.00-rc1683test # [ 0.453387] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]684test # [ 0.455416] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000685test # [ 0.455471] Intel/Sharp Extended Query Table at 0x0031686test # [ 0.465969] Using buffer write method687test # [ 0.466039] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]688test # [ 0.467851] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000689test # [ 0.467868] Intel/Sharp Extended Query Table at 0x0031690test # [ 0.471945] Using buffer write method691test # [ 0.471961] Concatenating MTD devices:692test # [ 0.471963] (0): "0.flash"693test # [ 0.471965] (1): "0.flash"694test # [ 0.471966] into device "0.flash"695second # [ 0.689748] Freeing initrd memory: 25404K696second # [ 0.700427] tun: Universal TUN/TAP device driver, 1.6697second # [ 0.703027] thunder_xcv, ver 1.0698second # [ 0.703047] thunder_bgx, ver 1.0699second # [ 0.703056] nicpf, ver 1.0700second # [ 0.703416] e1000: Intel(R) PRO/1000 Network Driver701second # [ 0.703420] e1000: Copyright (c) 1999-2006 Intel Corporation.702second # [ 0.703434] e1000e: Intel(R) PRO/1000 Network Driver703second # [ 0.703439] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.704second # [ 0.703451] igb: Intel(R) Gigabit Ethernet Network Driver705second # [ 0.703453] igb: Copyright (c) 2007-2014 Intel Corporation.706second # [ 0.703462] igbvf: Intel(R) Gigabit Virtual Function Network Driver707second # [ 0.703465] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.708second # [ 0.703531] sky2: driver version 1.30709second # [ 0.709439] usbcore: registered new interface driver usb-storage710second # [ 0.709547] usbcore: registered new interface driver usbserial_generic711second # [ 0.709554] usbserial: USB Serial support registered for generic712second # [ 0.709912] hv_vmbus: registering driver hyperv_keyboard713second # [ 0.711044] ehci-pci 0000:00:0a.0: EHCI Host Controller714second # [ 0.711081] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1715second # [ 0.711347] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000716second # [ 0.711531] rtc-pl031 9010000.pl031: registered as rtc0717second # [ 0.711567] rtc-pl031 9010000.pl031: setting system clock to 2026-10-16T10:07:19 UTC (1792145239)718second # [ 0.711744] i2c_dev: i2c /dev entries driver719second # [ 0.718366] sdhci: Secure Digital Host Controller Interface driver720second # [ 0.718373] sdhci: Copyright(c) Pierre Ossman721second # [ 0.718525] Synopsys Designware Multimedia Card Interface Driver722second # [ 0.718705] sdhci-pltfm: SDHCI platform and OF driver helper723second # [ 0.719712] hid: raw HID events driver (C) Jiri Kosina724second # [ 0.719874] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00725second # [ 0.720360] hub 1-0:1.0: USB hub found726second # [ 0.720434] hub 1-0:1.0: 6 ports detected727second # [ 0.724100] usbcore: registered new interface driver usbhid728second # [ 0.724106] usbhid: USB HID core driver729second # [ 0.724940] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available730second # [ 0.725800] drop_monitor: Initializing network drop monitor service731second # [ 0.725957] NET: Registered PF_INET6 protocol family732second # [ 0.726805] Segment Routing with IPv6733second # [ 0.726830] In-situ OAM (IOAM) with IPv6734second # [ 0.726896] NET: Registered PF_PACKET protocol family735second # [ 0.727001] 9pnet: Installing 9P2000 support736second # [ 0.732550] Key type dns_resolver registered737second # [ 0.735566] registered taskstats version 1738test # [ 0.696673] Freeing initrd memory: 25404K739second # [ 0.736503] Loading compiled-in X.509 certificates740second # [ 0.742021] Demotion targets for Node 0: null741second # [ 0.742099] Key type .fscrypt registered742second # [ 0.742102] Key type fscrypt-provisioning registered743second # [ 0.742208] ima: No TPM chip found, activating TPM-bypass!744test # [ 0.705168] tun: Universal TUN/TAP device driver, 1.6745second # [ 0.742219] ima: Allocated hash algorithm: sha1746test # [ 0.707384] thunder_xcv, ver 1.0747second # [ 0.742283] ima: No architecture policies found748test # [ 0.707429] thunder_bgx, ver 1.0749test # [ 0.707439] nicpf, ver 1.0750second # [ 0.742832] input: gpio-keys as /devices/platform/gpio-keys/input/input0751test # [ 0.707753] e1000: Intel(R) PRO/1000 Network Driver752test # [ 0.707757] e1000: Copyright (c) 1999-2006 Intel Corporation.753test # [ 0.707769] e1000e: Intel(R) PRO/1000 Network Driver754test # [ 0.707773] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.755test # [ 0.707786] igb: Intel(R) Gigabit Ethernet Network Driver756test # [ 0.707788] igb: Copyright (c) 2007-2014 Intel Corporation.757test # [ 0.707797] igbvf: Intel(R) Gigabit Virtual Function Network Driver758test # [ 0.707799] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.759test # [ 0.707863] sky2: driver version 1.30760test # [ 0.714112] usbcore: registered new interface driver usb-storage761test # [ 0.714169] usbcore: registered new interface driver usbserial_generic762test # [ 0.714175] usbserial: USB Serial support registered for generic763test # [ 0.714493] hv_vmbus: registering driver hyperv_keyboard764test # [ 0.715611] ehci-pci 0000:00:0a.0: EHCI Host Controller765test # [ 0.715649] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1766test # [ 0.715885] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000767test # [ 0.716059] rtc-pl031 9010000.pl031: registered as rtc0768test # [ 0.716091] rtc-pl031 9010000.pl031: setting system clock to 2026-10-16T10:07:19 UTC (1792145239)769second # [ 0.756082] clk: Disabling unused clocks770second # [ 0.756095] PM: genpd: Disabling unused power domains771second # [ 0.758642] Freeing unused kernel memory: 4736K772second # [ 0.758786] Run /init as init process773test # [ 0.721026] i2c_dev: i2c /dev entries driver774test # [ 0.722842] sdhci: Secure Digital Host Controller Interface driver775test # [ 0.722847] sdhci: Copyright(c) Pierre Ossman776test # [ 0.723007] Synopsys Designware Multimedia Card Interface Driver777test # [ 0.723193] sdhci-pltfm: SDHCI platform and OF driver helper778test # [ 0.724173] hid: raw HID events driver (C) Jiri Kosina779test # [ 0.724330] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00780test # [ 0.724799] hub 1-0:1.0: USB hub found781test # [ 0.724836] hub 1-0:1.0: 6 ports detected782test # [ 0.729067] usbcore: registered new interface driver usbhid783test # [ 0.729072] usbhid: USB HID core driver784test # [ 0.729881] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available785test # [ 0.730719] drop_monitor: Initializing network drop monitor service786test # [ 0.730872] NET: Registered PF_INET6 protocol family787test # [ 0.731727] Segment Routing with IPv6788test # [ 0.731742] In-situ OAM (IOAM) with IPv6789test # [ 0.731822] NET: Registered PF_PACKET protocol family790test # [ 0.731930] 9pnet: Installing 9P2000 support791test # [ 0.737611] Key type dns_resolver registered792test # [ 0.740961] registered taskstats version 1793test # [ 0.741125] Loading compiled-in X.509 certificates794test # [ 0.746621] Demotion targets for Node 0: null795test # [ 0.746695] Key type .fscrypt registered796test # [ 0.746698] Key type fscrypt-provisioning registered797test # [ 0.746805] ima: No TPM chip found, activating TPM-bypass!798test # [ 0.746816] ima: Allocated hash algorithm: sha1799test # [ 0.746878] ima: No architecture policies found800test # [ 0.747421] input: gpio-keys as /devices/platform/gpio-keys/input/input0801second # [ 0.786339] systemd[1]: Successfully made /usr/ read-only.802test # [ 0.760532] clk: Disabling unused clocks803test # [ 0.760545] PM: genpd: Disabling unused power domains804test # [ 0.763025] Freeing unused kernel memory: 4736K805test # [ 0.763129] Run /init as init process806test # [ 0.782837] systemd[1]: Successfully made /usr/ read-only.807second # [ 0.967836] usb 1-1: new high-speed USB device number 2 using ehci-pci808test # [ 0.972310] usb 1-1: new high-speed USB device number 2 using ehci-pci809second # [ 1.115297] 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/input1810second # [ 1.120377] systemd[1]: systemd 260.5 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)811second # [ 1.120395] systemd[1]: Detected virtualization qemu.812second # [ 1.120424] systemd[1]: Detected architecture arm64.813second # [ 1.120429] systemd[1]: Running in initrd.814second # [ 1.121358] systemd[1]: Initializing machine ID from random generator.815second # [ 1.121619] systemd[1]: Hostname set to <second>.816test # [ 1.116936] systemd[1]: systemd 260.5 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)817test # [ 1.116952] systemd[1]: Detected virtualization qemu.818test # [ 1.116979] systemd[1]: Detected architecture arm64.819test # [ 1.116983] systemd[1]: Running in initrd.820test # [ 1.117810] systemd[1]: Initializing machine ID from random generator.821test # [ 1.118347] systemd[1]: Hostname set to <test>.822test # [ 1.126813] 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/input1823second # [ 1.200035] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0824second # [ 1.206585] systemd[1]: Queued start job for default target Initrd Default Target.825second # [ 1.209994] systemd[1]: Created slice Slice /system/modprobe.826second # [ 1.210161] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.827second # [ 1.210184] systemd[1]: Expecting device /dev/disk/by-label/nixos...828second # [ 1.210205] systemd[1]: Reached target Path Units.829second # [ 1.210217] systemd[1]: Reached target Slice Units.830second # [ 1.210230] systemd[1]: Reached target Swaps.831second # [ 1.210243] systemd[1]: Reached target Timer Units.832second # [ 1.210387] systemd[1]: Listening on D-Bus System Message Bus Socket.833second # [ 1.210510] systemd[1]: Listening on Journal Socket (/dev/log).834second # [ 1.210652] systemd[1]: Listening on Journal Sockets.835second # [ 1.210722] systemd[1]: Listening on udev Control Socket.836second # [ 1.210767] systemd[1]: Listening on udev Kernel Socket.837second # [ 1.210781] systemd[1]: Reached target Socket Units.838second # [ 1.218116] systemd[1]: Starting Create List of Static Device Nodes...839second # [ 1.223953] systemd[1]: Starting Load Kernel Module 9pnet_virtio...840second # [ 1.224018] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs841test # [ 1.189384] systemd[1]: Queued start job for default target Initrd Default Target.842second # [ 1.232033] systemd[1]: Mounting Kernel Configuration File System...843test # [ 1.197953] systemd[1]: Created slice Slice /system/modprobe.844test # [ 1.198131] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.845test # [ 1.198155] systemd[1]: Expecting device /dev/disk/by-label/nixos...846test # [ 1.198178] systemd[1]: Reached target Path Units.847test # [ 1.198191] systemd[1]: Reached target Slice Units.848test # [ 1.198203] systemd[1]: Reached target Swaps.849test # [ 1.198215] systemd[1]: Reached target Timer Units.850second # [ 1.239570] systemd[1]: Starting Journal Service...851test # [ 1.198347] systemd[1]: Listening on D-Bus System Message Bus Socket.852test # [ 1.198441] systemd[1]: Listening on Journal Socket (/dev/log).853test # [ 1.198599] systemd[1]: Listening on Journal Sockets.854test # [ 1.198673] systemd[1]: Listening on udev Control Socket.855test # [ 1.198722] systemd[1]: Listening on udev Kernel Socket.856test # [ 1.198735] systemd[1]: Reached target Socket Units.857test # [ 1.199886] systemd[1]: Starting Create List of Static Device Nodes...858test # [ 1.208477] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0859test # [ 1.208979] systemd[1]: Starting Load Kernel Module 9pnet_virtio...860test # [ 1.209026] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs861test # [ 1.218057] systemd[1]: Mounting Kernel Configuration File System...862test # [ 1.228125] systemd[1]: Starting Journal Service...863second # [ 1.264165] systemd[1]: Starting Load Kernel Modules...864second # [ 1.264226] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki865second # [ 1.275991] systemd[1]: Starting Coldplug All udev Devices...866second # [ 1.277074] systemd[1]: Finished Create List of Static Device Nodes.867second # [ 1.277988] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.868second # [ 1.278149] systemd[1]: Finished Load Kernel Module 9pnet_virtio.869second # [ 1.278372] systemd[1]: Mounted Kernel Configuration File System.870test # [ 1.244597] systemd[1]: Starting Load Kernel Modules...871test # [ 1.244645] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki872second # [ 1.285853] systemd-journald[73]: Collecting audit messages is disabled.873test # [ 1.257860] systemd[1]: Starting Coldplug All udev Devices...874second # [ 1.296590] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...875test # [ 1.260917] systemd[1]: Finished Create List of Static Device Nodes.876test # [ 1.261816] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.877test # [ 1.261975] systemd[1]: Finished Load Kernel Module 9pnet_virtio.878test # [ 1.262159] systemd[1]: Mounted Kernel Configuration File System.879test # [ 1.268214] systemd-journald[73]: Collecting audit messages is disabled.880second # [ 1.319918] usb 1-2: new high-speed USB device number 3 using ehci-pci881test # [ 1.285999] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...882second # [ 1.328350] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.883second # [ 1.332636] systemd[1]: Starting Create Static Device Nodes in /dev...884test # [ 1.299547] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.885second # [ 1.349098] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.886test # [ 1.323178] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev887second # [ 1.360248] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev888test # [ 1.329052] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0889second # [ 1.361331] systemd-modules-load[74]: Inserted module 'dm_mod'890test # [ 1.329287] [drm] features: -virgl +edid -resource_blob -host_visible891test # [ 1.329289] [drm] features: -context_init892second # [ 1.362276] systemd-modules-load[74]: Module 'virtio_balloon' is built in893test # [ 1.329755] [drm] number of scanouts: 1894test # [ 1.329764] [drm] number of cap sets: 0895second # [ 1.362996] systemd-modules-load[74]: Module 'virtio_console' is built in896test # [ 1.330519] usb 1-2: new high-speed USB device number 3 using ehci-pci897test # [ 1.334851] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.898second # [ 1.372216] systemd[1]: Started Journal Service.899second # [ 1.381779] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0900second # [ 1.382016] [drm] features: -virgl +edid -resource_blob -host_visible901second # [ 1.382018] [drm] features: -context_init902second # [ 1.382484] [drm] number of scanouts: 1903test # [ 1.340656] systemd[1]: Starting Create Static Device Nodes in /dev...904second # [ 1.382493] [drm] number of cap sets: 0905second # [ 1.377966] systemd[1]: Finished Create Static Device Nodes in /dev.906test # [ 1.349110] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic907second # [ 1.381335] systemd[1]: Reached target Preparation for Local File Systems.908test # [ 1.349119] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0909second # [ 1.382193] systemd[1]: Reached target Local File Systems.910second # [ 1.391755] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic911second # [ 1.391765] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0912test # [ 1.349265] systemd-modules-load[75]: Inserted module 'dm_mod'913test # [ 1.350452] systemd-modules-load[75]: Module 'virtio_balloon' is built in914test # [ 1.356954] systemd[1]: Started Journal Service.915second # [ 1.392238] systemd[1]: Starting Create System Files and Directories...916test # [ 1.360349] systemd-modules-load[75]: Module 'virtio_console' is built in917test # [ 1.374069] Console: switching to colour frame buffer device 160x50918test # [ 1.374488] systemd[1]: Finished Create Static Device Nodes in /dev.919test # [ 1.375192] systemd[1]: Reached target Preparation for Local File Systems.920second # [ 1.411773] systemd[1]: Starting Rule-based Manager for Device Events and Files...[ 1.416279] Console: switching to colour frame buffer device 160x50921test # [ 1.375788] systemd[1]: Reached target Local File Systems.922second # [ 1.419234] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device923second # 924second # [ 1.417688] systemd-modules-load[74]: Inserted module 'virtio_gpu'925second # [ 1.418444] systemd-modules-load[74]: Module 'virtio_rng' is built in926test # [ 1.381482] systemd[1]: Starting Create System Files and Directories...927test # [ 1.390830] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device928test # [ 1.392164] systemd-modules-load[75]: Inserted module 'virtio_gpu'929test # [ 1.392839] systemd-modules-load[75]: Module 'virtio_rng' is built in930test # [ 1.393833] systemd[1]: Starting Rule-based Manager for Device Events and Files...931second # [ 1.431191] systemd[1]: Finished Load Kernel Modules.932test # [ 1.396177] systemd[1]: Finished Load Kernel Modules.933second # [ 1.440258] systemd[1]: Starting Apply Kernel Variables...934test # [ 1.412946] systemd[1]: Starting Apply Kernel Variables...935second # [ 1.454116] systemd[1]: Finished Create System Files and Directories.936second # [ 1.471137] 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/input2937second # [ 1.471323] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0938second # [ 1.481303] systemd[1]: Finished Apply Kernel Variables.939test # [ 1.447665] systemd[1]: Finished Apply Kernel Variables.940test # [ 1.448873] systemd[1]: Finished Create System Files and Directories.941second # [ 1.491645] systemd-udevd[87]: Using default interface naming scheme 'v260'.942second # [ 1.509262] systemd[1]: Started Rule-based Manager for Device Events and Files.943test # [ 1.479680] 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/input2944test # [ 1.479863] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0945test # [ 1.476120] systemd-udevd[86]: Using default interface naming scheme 'v260'.946test # [ 1.498621] systemd[1]: Started Rule-based Manager for Device Events and Files.947second # [ 1.571214] systemd[1]: Starting Virtual Console Setup...948second # [ 1.628399] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.949second # [ 1.630956] systemd[1]: Finished Virtual Console Setup.950test # [ 1.613303] systemd[1]: Starting Virtual Console Setup...951test # [ 1.658317] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.952test # [ 1.661014] systemd[1]: Finished Virtual Console Setup.953second # [ 1.901592] systemd[1]: Finished Coldplug All udev Devices.954second # [ 1.902196] systemd[1]: Reached target System Initialization.955second # [ 1.902698] systemd[1]: Reached target Basic System.956test # [ 1.893005] systemd[1]: Finished Coldplug All udev Devices.957test # [ 1.893602] systemd[1]: Reached target System Initialization.958test # [ 1.894093] systemd[1]: Reached target Basic System.959second # [ 2.034060] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.960second # [ 2.037983] (udev-worker)[101]: Network interface NamePolicy= disabled on kernel command line.961test # [ 2.015578] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.962test # [ 2.025570] (udev-worker)[101]: Network interface NamePolicy= disabled on kernel command line.963second # [ 2.084448] systemd[1]: Found device /dev/disk/by-label/nixos.964second # [ 2.085717] systemd[1]: Reached target Initrd Root Device.965second # [ 2.088607] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...966test # [ 2.069840] systemd[1]: Found device /dev/disk/by-label/nixos.967test # [ 2.071439] systemd[1]: Reached target Initrd Root Device.968test # [ 2.074287] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...969second # [ 2.138049] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks970second # [ 2.145461] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.971test # [ 2.119847] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks972test # [ 2.125432] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.973second # [ 2.272500] systemd[1]: Mounting /sysroot...974test # [ 2.259995] systemd[1]: Mounting /sysroot...975second # [ 2.307760] EXT4-fs (vda): mounted filesystem 5b110db3-920f-4167-b169-aa3728dadabb r/w with ordered data mode. Quota mode: none.976second # [ 2.304554] systemd[1]: Mounted /sysroot.977second # [ 2.305310] systemd[1]: Reached target Initrd Root File System.978second # [ 2.312076] systemd[1]: Starting Mountpoints Configured in the Real Root...979test # [ 2.286325] EXT4-fs (vda): mounted filesystem 444db581-12ab-419f-8e7c-7420bcdc11f7 r/w with ordered data mode. Quota mode: none.980test # [ 2.282937] systemd[1]: Mounted /sysroot.981test # [ 2.284722] systemd[1]: Reached target Initrd Root File System.982test # [ 2.288181] systemd[1]: Starting Mountpoints Configured in the Real Root...983second # [ 2.331259] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.984second # [ 2.337541] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...985second # [ 2.338593] systemd[1]: Reloading...986test # [ 2.307900] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.987test # [ 2.314295] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...988test # [ 2.316159] systemd[1]: Reloading...989second # [ 2.534170] systemd[1]: Reloading finished in 194 ms.990test # [ 2.506920] systemd[1]: Reloading finished in 190 ms.991second # [ 2.544754] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...992second # [ 2.546606] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...993second # [ 2.550103] systemd[1]: Starting Load Kernel Module 9pnet_virtio...994second # [ 2.551659] systemd[1]: initrd-parse-etc.service: Deactivated successfully.995test # [ 2.520275] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...996test # [ 2.522596] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...997second # [ 2.560194] systemd[1]: Finished Mountpoints Configured in the Real Root.998second # [ 2.561592] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.999test # [ 2.526717] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1000test # [ 2.528595] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1001test # [ 2.534403] systemd[1]: Finished Mountpoints Configured in the Real Root.1002test # [ 2.535151] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1003second # [ 2.576184] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1004second # [ 2.576893] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1005test # [ 2.551745] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1006test # [ 2.553229] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1007test # [ 2.946399] (udev-worker)[92]: mtd0ro: Failed to find and pin callout binary "/nix/store/jnsrd8c9vfdwv114xnkryg2ng2nsrcn0-systemd-260.5/lib/udev/mtd_probe": No such file or directory1008test # [ 2.952245] (udev-worker)[92]: mtd0ro: /nix/store/jnsrd8c9vfdwv114xnkryg2ng2nsrcn0-systemd-260.5/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 directory1009second # [ 2.990707] (udev-worker)[98]: mtd0ro: Failed to find and pin callout binary "/nix/store/jnsrd8c9vfdwv114xnkryg2ng2nsrcn0-systemd-260.5/lib/udev/mtd_probe": No such file or directory1010second # [ 2.996243] (udev-worker)[98]: mtd0ro: /nix/store/jnsrd8c9vfdwv114xnkryg2ng2nsrcn0-systemd-260.5/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 directory1011second # [ 3.007553] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1012test # [ 2.970858] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1013second # [ 3.008735] systemd[1]: Stopped Virtual Console Setup.1014test # [ 2.972141] systemd[1]: Stopped Virtual Console Setup.1015test # [ 2.973826] systemd[1]: Stopping Virtual Console Setup...1016second # [ 3.011639] systemd[1]: Stopping Virtual Console Setup...1017test # [ 2.976122] systemd[1]: Starting Virtual Console Setup...1018second # [ 3.013399] systemd[1]: Starting Virtual Console Setup...1019second # [ 3.041922] systemd-vconsole-setup[142]: Configuration of first virtual console was skipped, ignoring remaining ones.1020second # [ 3.042924] systemd[1]: Finished Virtual Console Setup.1021test # [ 3.008111] systemd-vconsole-setup[142]: Configuration of first virtual console was skipped, ignoring remaining ones.1022test # [ 3.010669] systemd[1]: Finished Virtual Console Setup.1023second # [ 3.271431] systemd[1]: Mounting /sysroot/nix/.ro-store...1024second # [ 3.280066] systemd[1]: Mounting /sysroot/nix/.rw-store...1025second # [ 3.281623] systemd[1]: Mounting /sysroot/run...1026second # [ 3.288614] systemd[1]: Mounting /sysroot/tmp/shared...1027test # [ 3.254374] systemd[1]: Mounting /sysroot/nix/.ro-store...1028test # [ 3.262088] systemd[1]: Mounting /sysroot/nix/.rw-store...1029second # [ 3.308719] systemd[1]: Mounting /sysroot/tmp/xchg...1030test # [ 3.276366] systemd[1]: Mounting /sysroot/run...1031test # [ 3.284983] systemd[1]: Mounting /sysroot/tmp/shared...1032second # [ 3.329685] systemd[1]: Mounted /sysroot/nix/.rw-store.1033second # [ 3.333706] systemd[1]: Mounted /sysroot/run.1034second # [ 3.338709] systemd[1]: Mounted /sysroot/nix/.ro-store.1035second # [ 3.340156] systemd[1]: Mounted /sysroot/tmp/shared.1036test # [ 3.306452] systemd[1]: Mounting /sysroot/tmp/xchg...1037second # [ 3.348374] systemd[1]: Starting rw-sysroot-nix-store.service...1038second # [ 3.355389] systemd[1]: Mounted /sysroot/tmp/xchg.1039second # [ 3.370500] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1040second # [ 3.371603] systemd[1]: Finished rw-sysroot-nix-store.service.1041test # [ 3.333461] systemd[1]: Mounted /sysroot/nix/.ro-store.1042test # [ 3.338082] systemd[1]: Mounted /sysroot/nix/.rw-store.1043test # [ 3.349004] systemd[1]: Starting rw-sysroot-nix-store.service...1044test # [ 3.352860] systemd[1]: Mounted /sysroot/run.1045test # [ 3.357305] systemd[1]: Mounted /sysroot/tmp/shared.1046test # [ 3.361101] systemd[1]: Mounted /sysroot/tmp/xchg.1047test # [ 3.374101] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1048test # [ 3.375175] systemd[1]: Finished rw-sysroot-nix-store.service.1049second # [ 4.270290] systemd[1]: Mounting /sysroot/nix/store...1050test # [ 4.251717] systemd[1]: Mounting /sysroot/nix/store...1051second # [ 4.296864] systemd[1]: Mounted /sysroot/nix/store.1052second # [ 4.298826] systemd[1]: Reached target Initrd File Systems.1053second # [ 4.300079] systemd[1]: Starting Find NixOS closure...1054second # [ 4.308337] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1055test # [ 4.280313] systemd[1]: Mounted /sysroot/nix/store.1056test # [ 4.281395] systemd[1]: Reached target Initrd File Systems.1057test # [ 4.283204] systemd[1]: Starting Find NixOS closure...1058test # [ 4.291944] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1059second # [ 4.337535] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1060second # [ 4.342806] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1061test # [ 4.312697] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1062test # [ 4.315363] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1063second # [ 4.354668] systemd[1]: Finished Find NixOS closure.1064second # [ 4.355929] systemd[1]: Reached target Initrd Default Target.1065second # [ 4.358230] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1066test # [ 4.327058] systemd[1]: Finished Find NixOS closure.1067test # [ 4.328136] systemd[1]: Reached target Initrd Default Target.1068test # [ 4.329676] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1069second # [ 4.387105] systemd[1]: Stopped target Initrd Default Target.1070second # [ 4.388475] systemd[1]: Stopped target Basic System.1071test # [ 4.352178] systemd[1]: Stopped target Initrd Default Target.1072test # [ 4.353259] systemd[1]: Stopped target Basic System.1073second # [ 4.392132] systemd[1]: Stopped target Initrd Root Device.1074second # [ 4.393086] systemd[1]: Stopped target Path Units.1075test # [ 4.356134] systemd[1]: Stopped target Initrd Root Device.1076second # [ 4.393725] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1077test # [ 4.356804] systemd[1]: Stopped target Path Units.1078second # [ 4.394632] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1079test # [ 4.357591] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1080second # [ 4.395393] systemd[1]: Stopped target Slice Units.1081test # [ 4.358238] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1082second # [ 4.395895] systemd[1]: Stopped target Socket Units.1083test # [ 4.359027] systemd[1]: Stopped target Slice Units.1084test # [ 4.359676] systemd[1]: Stopped target Socket Units.1085second # [ 4.398331] systemd[1]: Stopped target System Initialization.1086test # [ 4.362534] systemd[1]: Stopped target System Initialization.1087second # [ 4.400176] systemd[1]: Stopped target Swaps.1088second # [ 4.400706] systemd[1]: Stopped target Timer Units.1089test # [ 4.364191] systemd[1]: Stopped target Swaps.1090test # [ 4.364710] systemd[1]: Stopped target Timer Units.1091second # [ 4.404126] systemd[1]: dbus.socket: Deactivated successfully.1092second # [ 4.404778] systemd[1]: Closed D-Bus System Message Bus Socket.1093test # [ 4.368154] systemd[1]: dbus.socket: Deactivated successfully.1094second # [ 4.405374] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1095test # [ 4.368785] systemd[1]: Closed D-Bus System Message Bus Socket.1096second # [ 4.406154] systemd[1]: Stopped Find NixOS closure.1097test # [ 4.369320] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1098test # [ 4.369932] systemd[1]: Stopped Find NixOS closure.1099second # [ 4.407209] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1100test # [ 4.370347] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1101test # [ 4.371216] systemd[1]: Starting rw-sysroot-nix-store.service...1102test # [ 4.372500] systemd[1]: systemd-sysctl.service: Deactivated successfully.1103second # [ 4.410150] systemd[1]: Starting rw-sysroot-nix-store.service...1104second # [ 4.411071] systemd[1]: systemd-sysctl.service: Deactivated successfully.1105test # [ 4.375818] systemd[1]: Stopped Apply Kernel Variables.1106test # [ 4.376480] systemd[1]: systemd-modules-load.service: Deactivated successfully.1107test # [ 4.377277] systemd[1]: Stopped Load Kernel Modules.1108test # [ 4.378050] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1109second # [ 4.416380] systemd[1]: Stopped Apply Kernel Variables.1110test # [ 4.379306] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1111second # [ 4.416917] systemd[1]: systemd-modules-load.service: Deactivated successfully.1112second # [ 4.417524] systemd[1]: Stopped Load Kernel Modules.1113test # [ 4.380786] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1114second # [ 4.418040] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1115second # [ 4.418726] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1116second # [ 4.419394] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1117test # [ 4.383195] systemd[1]: Stopped Create System Files and Directories.1118test # [ 4.383779] systemd[1]: Stopped target Local File Systems.1119test # [ 4.384824] systemd[1]: Stopped target Preparation for Local File Systems.1120test # [ 4.386957] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1121test # [ 4.389032] systemd[1]: Stopped Coldplug All udev Devices.1122second # [ 4.427632] systemd[1]: Stopped Create System Files and Directories.1123second # [ 4.429456] systemd[1]: Stopped target Local File Systems.1124second # [ 4.430084] systemd[1]: Stopped target Preparation for Local File Systems.1125test # [ 4.393139] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1126test # [ 4.394497] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1127test # [ 4.395526] systemd[1]: Stopped Virtual Console Setup.1128second # [ 4.432591] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1129second # [ 4.433320] systemd[1]: Stopped Coldplug All udev Devices.1130second # [ 4.434428] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1131second # [ 4.435747] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1132second # [ 4.437617] systemd[1]: Stopped Virtual Console Setup.1133second # [ 4.438100] systemd[1]: systemd-udevd.service: Deactivated successfully.1134test # [ 4.408981] systemd[1]: initrd-cleanup.service: Deactivated successfully.1135second # [ 4.446436] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1136second # [ 4.447109] systemd[1]: systemd-udevd.service: Consumed 1.030s CPU time over 3.025s wall clock time, 21.5M memory peak.1137second # [ 4.447941] systemd[1]: initrd-cleanup.service: Deactivated successfully.1138test # [ 4.412414] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1139test # [ 4.413099] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1140test # [ 4.413702] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1141second # [ 4.451028] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1142second # [ 4.452171] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1143test # [ 4.415342] systemd[1]: systemd-udevd.service: Deactivated successfully.1144second # [ 4.453450] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1145test # [ 4.416889] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1146second # [ 4.455612] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1147test # [ 4.419096] systemd[1]: systemd-udevd.service: Consumed 1.023s CPU time over 3.022s wall clock time, 22.8M memory peak.1148second # [ 4.456838] systemd[1]: Finished rw-sysroot-nix-store.service.1149test # [ 4.420183] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1150second # [ 4.458194] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1151second # [ 4.459642] systemd[1]: Closed udev Control Socket.1152second # [ 4.461012] systemd[1]: Starting Cleanup udev Database...1153test # [ 4.424408] systemd[1]: Finished rw-sysroot-nix-store.service.1154test # [ 4.425558] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1155test # [ 4.426141] systemd[1]: Closed udev Control Socket.1156second # [ 4.464165] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1157test # [ 4.427237] systemd[1]: Starting Cleanup udev Database...1158second # [ 4.464837] systemd[1]: Stopped Create Static Device Nodes in /dev.1159test # [ 4.427758] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1160second # [ 4.465337] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1161second # [ 4.465994] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1162test # [ 4.429768] systemd[1]: Stopped Create Static Device Nodes in /dev.1163test # [ 4.430302] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1164second # [ 4.467758] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1165test # [ 4.430939] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1166test # [ 4.431501] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1167second # [ 4.468619] systemd[1]: Stopped Create List of Static Device Nodes.1168test # [ 4.432819] systemd[1]: Stopped Create List of Static Device Nodes.1169second # [ 4.494747] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1170test # [ 4.457616] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1171second # [ 4.497016] systemd[1]: Finished Cleanup udev Database.1172test # [ 4.459662] systemd[1]: Finished Cleanup udev Database.1173test # [ 4.461315] systemd[1]: Reached target Switch Root.1174second # [ 4.500408] systemd[1]: Reached target Switch Root.1175second # [ 4.501647] systemd[1]: Starting NixOS Activation...1176test # [ 4.465142] systemd[1]: Starting NixOS Activation...1177second # [ 4.665813] initrd-nixos-activation-start[179]: booting system configuration /nix/store/z6dfhlsgl1gb2ncijyzyi7zwqg7nfsas-nixos-system-second-test1178test # [ 4.637780] initrd-nixos-activation-start[179]: booting system configuration /nix/store/dsibvd7a0gzhgcxd4vwarm1bkklv69j7-nixos-system-test-test1179second # [ 4.722166] initrd-nixos-activation-start[179]: running activation script...1180test # [ 4.695146] initrd-nixos-activation-start[179]: running activation script...1181second # [ 5.071469] initrd-nixos-activation-start[202]: setting up /etc...1182test # [ 5.051463] initrd-nixos-activation-start[202]: setting up /etc...1183test # [ 5.245556] initrd-nixos-activation-start[202]: setting up age secrets...1184second # [ 5.290328] initrd-nixos-activation-start[202]: setting up age secrets...1185second # [ 5.485016] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1186second # [ 5.486886] systemd[1]: Finished NixOS Activation.1187second # [ 5.487745] systemd[1]: Starting Switch Root...1188second # [ 5.505598] systemd[1]: Switching root.1189test # [ 5.485654] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1190test # [ 5.487444] systemd[1]: Finished NixOS Activation.1191test # [ 5.488284] systemd[1]: Starting Switch Root...1192test # [ 5.505117] systemd[1]: Switching root.1193second # [ 5.560656] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1194test # [ 5.560448] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1195second # [ 6.074458] systemd[1]: systemd 260.5 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)1196second # [ 6.074507] systemd[1]: Detected virtualization qemu.1197second # [ 6.074544] systemd[1]: Detected architecture arm64.1198second # [ 6.074631] systemd[1]: Detected first boot.1199second # [ 6.088423] systemd[1]: Initializing machine ID from random generator.1200test # [ 6.084489] systemd[1]: systemd 260.5 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)1201test # [ 6.084588] systemd[1]: Detected virtualization qemu.1202test # [ 6.084674] systemd[1]: Detected architecture arm64.1203test # [ 6.084802] systemd[1]: Detected first boot.1204test # [ 6.099371] systemd[1]: Initializing machine ID from random generator.1205second # [ 6.357736] systemd[1]: bpf-restrict-fs: LSM BPF program attached1206test # [ 6.374495] systemd[1]: bpf-restrict-fs: LSM BPF program attached1207second # [ 6.511024] systemd[1]: Applying preset policy.1208test # [ 6.565952] systemd[1]: Applying preset policy.1209second # [ 6.870853] systemd[1]: Populated /etc with preset unit settings.1210test # [ 6.912119] systemd[1]: Populated /etc with preset unit settings.1211second # [ 7.253698] systemd[1]: initrd-switch-root.service: Deactivated successfully.1212second # [ 7.254024] systemd[1]: Stopped initrd-switch-root.service.1213second # [ 7.255162] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1214second # [ 7.258677] systemd[1]: Created slice Slice /system/getty.1215second # [ 7.259376] systemd[1]: Created slice User and Session Slice.1216second # [ 7.259579] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1217second # [ 7.261965] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1218second # [ 7.262077] systemd[1]: Expecting device /dev/hvc0...1219second # [ 7.262514] systemd[1]: Expecting device /dev/ttyAMA0...1220second # [ 7.262975] systemd[1]: Reached target Local Encrypted Volumes.1221second # [ 7.263583] systemd[1]: Stopped target initrd-fs.target.1222second # [ 7.266775] systemd[1]: Stopped target initrd-root-fs.target.1223second # [ 7.267264] systemd[1]: Stopped target initrd-switch-root.target.1224second # [ 7.269503] systemd[1]: Reached target Virtual Machines and Containers.1225second # [ 7.270032] systemd[1]: Reached target Path Units.1226second # [ 7.270836] systemd[1]: Reached target Remote File Systems.1227second # [ 7.271561] systemd[1]: Reached target Slice Units.1228second # [ 7.274440] systemd[1]: Reached target Swaps.1229second # [ 7.278839] systemd[1]: Listening on Process Core Dump Socket.1230second # [ 7.282346] systemd[1]: Listening on Credential Encryption/Decryption.1231second # [ 7.286759] systemd[1]: Starting Journal Log Access Socket...1232second # [ 7.288721] systemd[1]: Listening on Journal Audit Socket.1233second # [ 7.289425] systemd[1]: Listening on Network Management Metrics Varlink Socket.1234second # [ 7.290054] systemd[1]: Listening on Network Management Netlink Socket.1235second # [ 7.290747] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1236second # [ 7.291338] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1237second # [ 7.295799] systemd[1]: Listening on udev Control Socket.1238second # [ 7.298555] systemd[1]: Mounting Huge Pages File System...1239second # [ 7.302811] systemd[1]: Mounting POSIX Message Queue File System...1240second # [ 7.309305] systemd[1]: Mounting Kernel Debug File System...1241second # [ 7.316600] systemd[1]: Mounting Kernel Trace File System...1242second # [ 7.329848] systemd[1]: Starting Create List of Static Device Nodes...1243second # [ 7.341561] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1244second # [ 7.342190] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1245second # [ 7.353366] systemd[1]: Mounting Kernel Configuration File System...1246test # [ 7.318509] systemd[1]: initrd-switch-root.service: Deactivated successfully.1247test # [ 7.318811] systemd[1]: Stopped initrd-switch-root.service.1248test # [ 7.319851] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1249second # [ 7.356642] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1250second # [ 7.357350] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1251test # [ 7.323220] systemd[1]: Created slice Slice /system/getty.1252test # [ 7.323832] systemd[1]: Created slice User and Session Slice.1253test # [ 7.324026] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1254test # [ 7.326466] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1255test # [ 7.326569] systemd[1]: Expecting device /dev/hvc0...1256test # [ 7.326961] systemd[1]: Expecting device /dev/ttyAMA0...1257test # [ 7.327357] systemd[1]: Reached target Local Encrypted Volumes.1258test # [ 7.327906] systemd[1]: Stopped target initrd-fs.target.1259test # [ 7.331240] systemd[1]: Stopped target initrd-root-fs.target.1260test # [ 7.331714] systemd[1]: Stopped target initrd-switch-root.target.1261test # [ 7.332222] systemd[1]: Reached target Virtual Machines and Containers.1262test # [ 7.334640] systemd[1]: Reached target Path Units.1263test # [ 7.335117] systemd[1]: Reached target Remote File Systems.1264test # [ 7.335599] systemd[1]: Reached target Slice Units.1265test # [ 7.336084] systemd[1]: Reached target Swaps.1266test # [ 7.340905] systemd[1]: Listening on Process Core Dump Socket.1267test # [ 7.343500] systemd[1]: Listening on Credential Encryption/Decryption.1268test # [ 7.348888] systemd[1]: Starting Journal Log Access Socket...1269test # [ 7.349693] systemd[1]: Listening on Journal Audit Socket.1270test # [ 7.350339] systemd[1]: Listening on Network Management Metrics Varlink Socket.1271test # [ 7.350943] systemd[1]: Listening on Network Management Netlink Socket.1272test # [ 7.351633] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1273test # [ 7.352115] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1274test # [ 7.356666] systemd[1]: Listening on udev Control Socket.1275test # [ 7.363923] systemd[1]: Mounting Huge Pages File System...1276test # [ 7.367427] systemd[1]: Mounting POSIX Message Queue File System...1277test # [ 7.373268] systemd[1]: Mounting Kernel Debug File System...1278test # [ 7.379911] systemd[1]: Mounting Kernel Trace File System...1279second # [ 7.424446] systemd[1]: Starting Load Kernel Module fuse...1280test # [ 7.388802] systemd[1]: Starting Create List of Static Device Nodes...1281second # [ 7.431478] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671282test # [ 7.396823] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1283test # [ 7.397712] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1284test # [ 7.405049] systemd[1]: Mounting Kernel Configuration File System...1285test # [ 7.405856] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1286test # [ 7.406583] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1287second # [ 7.456061] systemd[1]: Starting Journal Service...1288test # [ 7.427801] systemd[1]: Starting Load Kernel Module fuse...1289second # [ 7.466440] systemd[1]: Starting Load Kernel Modules...1290test # [ 7.429449] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671291second # [ 7.487116] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1292second # [ 7.495485] systemd[1]: Starting Remount Root and Kernel File Systems...1293second # [ 7.500530] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1294test # [ 7.482478] systemd[1]: Starting Journal Service...1295second # [ 7.538260] systemd[1]: Starting Coldplug All udev Devices...1296test # [ 7.510896] systemd[1]: Starting Load Kernel Modules...1297second # [ 7.548633] fuse: init (API version 7.45)1298second # [ 7.564327] systemd[1]: Listening on Journal Log Access Socket.1299second # [ 7.565037] systemd-journald[302]: Collecting audit messages is enabled.1300test # [ 7.532011] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1301second # [ 7.569875] systemd[1]: Mounted Huge Pages File System.1302second # [ 7.573022] systemd[1]: Mounted POSIX Message Queue File System.1303second # [ 7.571188] systemd[1]: Queued start job for default target Multi-User System.1304second # [ 7.577915] systemd[1]: Mounted Kernel Debug File System.1305second # [ 7.573735] systemd[1]: systemd-journald.service: Deactivated successfully.1306second # [ 7.582929] systemd[1]: Started Journal Service.1307second # [ 7.578968] systemd-modules-load[303]: Module 'atkbd' is built in1308second # [ 7.586303] systemd-modules-load[303]: Module 'ctr' is built in1309test # [ 7.558920] systemd[1]: Starting Remount Root and Kernel File Systems...1310second # [ 7.590772] systemd-modules-load[303]: Module 'loop' is built in1311test # [ 7.562008] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1312second # [ 7.596528] systemd[1]: Mounted Kernel Trace File System.1313second # [ 7.604989] systemd[1]: Finished Create List of Static Device Nodes.1314second # [ 7.605692] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1315second # [ 7.606449] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1316second # [ 7.607099] systemd[1]: Mounted Kernel Configuration File System.1317second # [ 7.607719] systemd[1]: modprobe@fuse.service: Deactivated successfully.1318second # [ 7.617079] systemd[1]: Finished Load Kernel Module fuse.1319second # [ 7.621191] systemd[1]: Finished Load Kernel Modules.1320test # [ 7.592204] fuse: init (API version 7.45)1321test # [ 7.595561] systemd[1]: Starting Coldplug All udev Devices...1322second # [ 7.633657] systemd[1]: Mounting FUSE Control File System...1323second # [ 7.636206] systemd[1]: Starting Firewall...1324second # [ 7.644682] EXT4-fs (vda): re-mounted 5b110db3-920f-4167-b169-aa3728dadabb.1325test # [ 7.617182] systemd[1]: Listening on Journal Log Access Socket.1326test # [ 7.622583] systemd[1]: Mounted Huge Pages File System.1327second # [ 7.658501] systemd[1]: Starting Apply Kernel Variables...1328test # [ 7.628753] systemd[1]: Mounted POSIX Message Queue File System.1329test # [ 7.631201] systemd-journald[323]: Collecting audit messages is enabled.1330test # [ 7.634536] systemd[1]: Mounted Kernel Debug File System.1331test # [ 7.639126] systemd[1]: Mounted Kernel Trace File System.1332second # [ 7.677269] systemd-oomd[304]: No swap; memory pressure usage will be degraded1333test # [ 7.646040] systemd[1]: Finished Create List of Static Device Nodes.1334test # [ 7.655136] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1335second # [ 7.687454] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1336second # [ 7.688282] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1337test # [ 7.657874] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1338test # [ 7.658487] systemd[1]: Mounted Kernel Configuration File System.1339test # [ 7.658941] systemd[1]: modprobe@fuse.service: Deactivated successfully.1340second # [ 7.694075] systemd[1]: Finished Remount Root and Kernel File Systems.1341test # [ 7.656854] systemd[1]: Queued start job for default target Multi-User System.1342test # [ 7.658624] systemd[1]: systemd-journald.service: Deactivated successfully.1343test # [ 7.667674] systemd[1]: Finished Load Kernel Module fuse.1344test # [ 7.669665] systemd[1]: Started Journal Service.1345test # [ 7.666568] systemd-modules-load[324]: Module 'atkbd' is built in1346test # [ 7.669941] systemd-modules-load[324]: Module 'ctr' is built in1347test # [ 7.674397] systemd-modules-load[324]: Module 'loop' is built in1348test # [ 7.680480] systemd[1]: Finished Load Kernel Modules.1349test # [ 7.686341] systemd[1]: Mounting FUSE Control File System...1350second # [ 7.723701] systemd[1]: Starting Flush Journal to Persistent Storage...1351test # [ 7.688795] systemd[1]: Starting Firewall...1352second # [ 7.733198] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1353test # [ 7.700399] EXT4-fs (vda): re-mounted 444db581-12ab-419f-8e7c-7420bcdc11f7.1354second # [ 7.746363] systemd[1]: Starting Load/Save OS Random Seed...1355second # [ 7.748988] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1356test # [ 7.721308] systemd[1]: Starting Apply Kernel Variables...1357test # [ 7.725667] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1358test # [ 7.734170] systemd[1]: Finished Remount Root and Kernel File Systems.1359test # [ 7.752735] systemd-oomd[325]: No swap; memory pressure usage will be degraded1360second # [ 7.794236] systemd[1]: Finished Apply Kernel Variables.1361test # [ 7.765618] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1362second # [ 7.812374] systemd[1]: Mounted FUSE Control File System.1363second # [ 7.820532] systemd[1]: Starting Network Name Resolution...1364test # [ 7.792962] systemd[1]: Starting Flush Journal to Persistent Storage...1365test # [ 7.794981] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1366test # [ 7.804344] systemd[1]: Starting Load/Save OS Random Seed...1367test # [ 7.804873] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1368second # [ 7.855442] systemd-journald[302]: Received client request to flush runtime journal.1369test # [ 7.885078] systemd[1]: Mounted FUSE Control File System.1370test # [ 7.898444] systemd[1]: Finished Apply Kernel Variables.1371second # [ 7.937400] systemd[1]: Finished Load/Save OS Random Seed.1372test # [ 7.900645] systemd[1]: Starting Network Name Resolution...1373second # [ 7.944407] systemd[1]: Reached target First Boot Complete.1374second # [ 7.945130] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1375second # [ 7.950000] systemd[1]: Starting Create Static Device Nodes in /dev...1376second # [ 7.952318] systemd[1]: Finished Flush Journal to Persistent Storage.1377test # [ 7.933876] systemd-journald[323]: Received client request to flush runtime journal.1378second # [ 7.999613] systemd-resolved[331]: Positive Trust Anchors:1379second # [ 8.001434] systemd-resolved[331]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1380second # [ 8.003015] systemd-resolved[331]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161381second # [ 8.008459] systemd-resolved[331]: 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 test1382second # [ 8.062008] systemd[1]: Finished Create Static Device Nodes in /dev.1383second # [ 8.062893] systemd[1]: Reached target Preparation for Local File Systems.1384second # [ 8.066241] systemd[1]: Starting Rule-based Manager for Device Events and Files...1385test # [ 8.032403] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1386test # [ 8.034927] systemd[1]: Starting Create Static Device Nodes in /dev...1387test # [ 8.036106] systemd[1]: Finished Load/Save OS Random Seed.1388test # [ 8.037137] systemd[1]: Reached target First Boot Complete.1389test # [ 8.039502] systemd[1]: Finished Flush Journal to Persistent Storage.1390test # [ 8.099723] systemd[1]: Finished Create Static Device Nodes in /dev.1391test # [ 8.102244] systemd[1]: Reached target Preparation for Local File Systems.1392test # [ 8.106763] systemd[1]: Starting Rule-based Manager for Device Events and Files...1393test # [ 8.118950] systemd-resolved[353]: Positive Trust Anchors:1394test # [ 8.119635] systemd-resolved[353]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1395test # [ 8.123174] systemd-resolved[353]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161396test # [ 8.128372] systemd-resolved[353]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1397second # [ 8.196811] systemd-udevd[350]: Using default interface naming scheme 'v260'.1398second # [ 8.236304] systemd-resolved[331]: Using system hostname 'second'.1399second # [ 8.245193] systemd[1]: Started Network Name Resolution.1400second # [ 8.252528] systemd[1]: Mounting /run/wrappers...1401test # [ 8.247776] systemd-udevd[369]: Using default interface naming scheme 'v260'.1402second # [ 8.321441] systemd[1]: Mounted /run/wrappers.1403second # [ 8.322188] systemd[1]: Reached target Local File Systems.1404second # [ 8.326474] systemd[1]: Listening on Boot Loader Control Service Socket.1405second # [ 8.330380] systemd[1]: Starting register-nix-paths.service...1406second # [ 8.333045] systemd[1]: Starting Create SUID/SGID Wrappers...1407second # [ 8.333924] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1408second # [ 8.340530] systemd[1]: Starting Save Transient machine-id to Disk...1409second # [ 8.344195] systemd[1]: Starting Create System Files and Directories...1410test # [ 8.318941] systemd[1]: Mounting /run/wrappers...1411test # [ 8.379219] systemd-resolved[353]: Using system hostname 'test'.1412test # [ 8.391488] systemd[1]: Started Network Name Resolution.1413test # [ 8.393963] systemd[1]: Mounted /run/wrappers.1414test # [ 8.394644] systemd[1]: Reached target Local File Systems.1415test # [ 8.398902] systemd[1]: Listening on Boot Loader Control Service Socket.1416test # [ 8.400828] systemd[1]: Starting register-nix-paths.service...1417test # [ 8.409221] systemd[1]: Starting Create SUID/SGID Wrappers...1418test # [ 8.409840] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1419test # [ 8.412184] systemd[1]: Starting Save Transient machine-id to Disk...1420test # [ 8.419094] systemd[1]: Starting Create System Files and Directories...1421second # [ 8.514321] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1422second # [ 8.526008] systemd[1]: Finished Save Transient machine-id to Disk.1423second # [ 8.578639] systemd[1]: Finished Create System Files and Directories.1424second # [ 8.585753] systemd[1]: Starting Rebuild Journal Catalog...1425second # [ 8.590497] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1426test # [ 8.572452] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1427test # [ 8.589260] systemd[1]: Finished Save Transient machine-id to Disk.1428second # [ 8.623448] systemd[1]: Started Rule-based Manager for Device Events and Files.1429test # [ 8.653554] systemd[1]: Finished Create System Files and Directories.1430test # [ 8.667047] systemd[1]: Starting Rebuild Journal Catalog...1431test # [ 8.680554] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1432second # [ 8.726107] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1433test # [ 8.687924] systemd[1]: Started Rule-based Manager for Device Events and Files.1434second # [ 8.825572] systemd[1]: Finished Rebuild Journal Catalog.1435second # [ 8.838850] systemd[1]: Starting Update is Completed...1436test # [ 8.845388] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1437test # [ 8.900207] systemd[1]: Finished Rebuild Journal Catalog.1438second # [ 8.947357] systemd[1]: Finished Update is Completed.1439second # [ 8.951004] systemd[1]: Finished Coldplug All udev Devices.1440test # [ 8.916303] systemd[1]: Starting Update is Completed...1441test # [ 9.011272] systemd[1]: Finished Update is Completed.1442test # [ 9.064946] systemd[1]: Finished Coldplug All udev Devices.1443second # [ 9.209114] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1444test # [ 9.306340] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1445second # [ 9.509483] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1446second # [ 9.516532] systemd[1]: Finished Create SUID/SGID Wrappers.1447second # [ 9.587897] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1448second # [ 9.639979] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1449second # [ 9.707826] systemd[1]: Finished register-nix-paths.service.1450second # [ 9.711471] systemd[1]: Reached target System Initialization.1451second # [ 9.714490] systemd[1]: Started Discard unused filesystem blocks once a week.1452test # [ 9.678398] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1453second # [ 9.717169] systemd[1]: Started Daily Cleanup of Temporary Directories.1454test # [ 9.680986] systemd[1]: Finished Create SUID/SGID Wrappers.1455second # [ 9.719675] systemd[1]: Reached target Timer Units.1456second # [ 9.723436] systemd[1]: Listening on D-Bus System Message Bus Socket.1457second # [ 9.726355] systemd[1]: Listening on Nix Daemon Socket.1458second # [ 9.731264] systemd[1]: Listening on Authorization Manager Agent Helper.1459second # [ 9.736795] systemd[1]: Listening on Hostname Service Socket.1460second # [ 9.738591] systemd[1]: Reached target Socket Units.1461test # [ 9.701922] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1462second # [ 9.741317] systemd[1]: Starting D-Bus System Message Bus...1463second # [ 9.747823] systemd[1]: Finished Firewall.1464second # [ 9.756452] systemd[1]: Reached target Preparation for Network.1465second # [ 9.764880] systemd[1]: Starting Network Management...1466test # [ 9.757238] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1467test # [ 9.841000] systemd[1]: Finished Firewall.1468test # [ 9.843954] systemd[1]: Finished register-nix-paths.service.1469test # [ 9.844648] systemd[1]: Reached target Preparation for Network.1470test # [ 9.846605] systemd[1]: Reached target System Initialization.1471test # [ 9.848501] systemd[1]: Started Discard unused filesystem blocks once a week.1472test # [ 9.850352] systemd[1]: Started Daily Cleanup of Temporary Directories.1473test # [ 9.853216] systemd[1]: Reached target Timer Units.1474test # [ 9.854528] systemd[1]: Listening on D-Bus System Message Bus Socket.1475test # [ 9.855736] systemd[1]: Listening on Nix Daemon Socket.1476test # [ 9.860661] systemd[1]: Listening on Authorization Manager Agent Helper.1477test # [ 9.861296] systemd[1]: Listening on Hostname Service Socket.1478test # [ 9.863197] systemd[1]: Reached target Socket Units.1479test # [ 9.868556] systemd[1]: Starting D-Bus System Message Bus...1480test # [ 9.871961] systemd[1]: Starting Network Management...1481second # [ 9.943162] (udev-worker)[458]: Network interface NamePolicy= disabled on kernel command line.1482second # [ 9.968101] dbus-broker-launch[533]: Looking up NSS user entry for 'systemd-timesync'...1483second # [ 10.007862] (udev-worker)[459]: Network interface NamePolicy= disabled on kernel command line.1484second # [ 10.011683] dbus-broker-launch[533]: NSS returned no entry for 'systemd-timesync'1485second # [ 10.012735] dbus-broker-launch[533]: Invalid user-name in /nix/store/2a5gkk8cdn812rpli3mknfqjqv0rbz0c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1486second # [ 10.041906] cfg80211: Loading compiled-in X.509 certificates for regulatory database1487second # [ 10.078073] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1488second # [ 10.078598] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1489second # [ 10.080549] dbus-broker-launch[533]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/6lmph1rn0dzdzcdhcaxbrzr5l4rmgzq0-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1490second # [ 10.084536] dbus-broker-launch[533]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/6lmph1rn0dzdzcdhcaxbrzr5l4rmgzq0-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1491second # [ 10.093718] dbus-broker-launch[533]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/29ymypflk6jb9d2n079ikwbba15isr3i-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1492second # [ 10.100695] dbus-broker-launch[533]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/qk0n05qwbhr7xcs41d4f41ndi9cy28y8-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1493test # [ 10.072120] (udev-worker)[477]: Network interface NamePolicy= disabled on kernel command line.1494second # [ 10.110583] systemd[1]: Started D-Bus System Message Bus.1495second # [ 10.113861] systemd[1]: Reached target Basic System.1496second # [ 10.116660] systemd[1]: Starting Network Manager Script Dispatcher Service...1497test # [ 10.083623] (udev-worker)[479]: Network interface NamePolicy= disabled on kernel command line.1498second # [ 10.121511] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1499test # [ 10.085871] dbus-broker-launch[556]: Looking up NSS user entry for 'systemd-timesync'...1500second # [ 10.124684] systemd[1]: Starting Network Manager...1501second # [ 10.153204] systemd[1]: Started backdoor.service.1502test # [ 10.121921] dbus-broker-launch[556]: NSS returned no entry for 'systemd-timesync'1503test # [ 10.124183] dbus-broker-launch[556]: Invalid user-name in /nix/store/2a5gkk8cdn812rpli3mknfqjqv0rbz0c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1504test # [ 10.137434] cfg80211: Loading compiled-in X.509 certificates for regulatory database1505second # [ 10.178852] 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.1506second # [ 10.188735] systemd[1]: Starting Import lastlog data into lastlog2 database...1507second # [ 10.194025] 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.1508test # [ 10.167887] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1509test # [ 10.169844] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1510second # [ 10.203242] systemd-networkd[535]: lo: Link UP1511second # [ 10.209264] systemd-networkd[535]: lo: Gained carrier1512test # [ 10.171348] dbus-broker-launch[556]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/6lmph1rn0dzdzcdhcaxbrzr5l4rmgzq0-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1513second # [ 10.214953] dbus-broker-launch[533]: Ready1514test # [ 10.175802] dbus-broker-launch[556]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/6lmph1rn0dzdzcdhcaxbrzr5l4rmgzq0-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1515second # [ 10.218126] systemd-networkd[535]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1516test # [ 10.185268] dbus-broker-launch[556]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/29ymypflk6jb9d2n079ikwbba15isr3i-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1517second # [ 10.223814] systemd-networkd[535]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1518second # [ 10.231334] systemd-networkd[535]: eth1: Link UP1519second # [ 10.233287] systemd-networkd[535]: eth1: Gained carrier1520test # [ 10.196734] dbus-broker-launch[556]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/qk0n05qwbhr7xcs41d4f41ndi9cy28y8-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1521second # [ 10.238098] systemd-networkd[535]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1522test # [ 10.204531] systemd[1]: Started D-Bus System Message Bus.1523second # [ 10.242688] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1524test # [ 10.208422] systemd[1]: Reached target Basic System.1525test # [ 10.209276] systemd[1]: Starting Network Manager Script Dispatcher Service...1526test # [ 10.215178] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1527second # [ 10.254093] systemd[1]: Starting Post-Boot Actions...1528test # [ 10.221818] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1529test # [ 10.224121] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1530second # [ 10.284520] mousedev: PS/2 mouse device common for all mice1531second # [ 10.294609] systemd[1]: Started Reset console on configuration changes.1532test # [ 10.259933] 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.1533test # [ 10.265624] 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.1534second # [ 10.304816] systemd[1]: Started Network Management.1535test # [ 10.276281] systemd-networkd[557]: lo: Link UP1536test # [ 10.276769] systemd-networkd[557]: lo: Gained carrier1537test # [ 10.283205] dbus-broker-launch[556]: Ready1538test # [ 10.285086] systemd[1]: Starting Network Manager...1539second # [ 10.342498] n25cm952iq0m8cl7wjkynss0g05assxn-wifi-secrets[546]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1540test # [ 10.306503] systemd[1]: Started backdoor.service.1541test # [ 10.318367] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1542test # [ 10.332552] systemd-networkd[557]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1543test # [ 10.333324] systemd-networkd[557]: eth1: Link UP1544test # [ 10.333730] systemd-networkd[557]: eth1: Gained carrier1545test # [ 10.334180] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1546second # [ 10.394378] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1547test # [ 10.361732] systemd[1]: Starting Import lastlog data into lastlog2 database...1548second # [ 10.400946] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1549test # [ 10.392840] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1550second # connecting to host...1551test # [ 10.422558] 55lza9x2jamf2vld6h2l9yh9vghqrzg4-wifi-secrets[569]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1552second # [ 10.463047] systemd[1]: Finished Post-Boot Actions.1553test # [ 10.430317] n25cm952iq0m8cl7wjkynss0g05assxn-wifi-secrets[568]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1554test # [ 10.445496] systemd[1]: Starting Post-Boot Actions...1555test # [ 10.451978] 64w2igmnj1gk4d8c0r8vvnfligqhqa7w-wifi-secrets[570]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1556second # [ 10.487628] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1557test # [ 10.466324] systemd[1]: Started Reset console on configuration changes.1558test # [ 10.471065] systemd[1]: Started Network Management.1559second # [ 10.514225] nsncd[550]: Oct 16 10:07:29.309 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1560second # [ 10.525008] systemd[1]: Started Name Service Cache Daemon (nsncd).1561test # [ 10.499343] mousedev: PS/2 mouse device common for all mice1562second # [ 10.530300] systemd[1]: Finished Import lastlog data into lastlog2 database.1563test # [ 10.493084] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1564test # [ 10.501352] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1565second # [ 10.539585] systemd[1]: Reached target Host and Network Name Lookups.1566second # [ 10.548380] systemd[1]: Reached target User and Group Name Lookups.1567test # [ 10.511561] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1568second # [ 10.553219] systemd[1]: Starting User Login Management...1569test # [ 10.515660] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1570test # [ 10.524687] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1571test # [ 10.530787] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1572second # [ 10.580653] systemd[1]: Started Network Manager Script Dispatcher Service.1573test # connecting to host...1574second # [ 10.621610] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1575test: Guest shell says: b'Spawning backdoor root shell...\n'1576test: connected to guest root shell1577test: (connecting took 10.95 seconds)1578test: (finished: waiting for the VM to finish booting, in 10.95 seconds)1579second # [ 10.690596] systemd[1]: Starting Hostname Service...1580test # [ 10.630477] systemd[1]: Finished Post-Boot Actions.1581test # [ 10.649545] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1582test # [ 10.659843] nsncd[574]: Oct 16 10:07:29.448 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1583test # [ 10.671110] systemd[1]: Started Name Service Cache Daemon (nsncd).1584test # [ 10.671926] systemd[1]: Reached target Host and Network Name Lookups.1585test # [ 10.674985] systemd[1]: Reached target User and Group Name Lookups.1586second # [ 10.721767] systemd-logind[582]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1587second # [ 10.725614] systemd-logind[582]: Watching system buttons on /dev/input/event0 (gpio-keys)1588second # [ 10.729430] systemd-logind[582]: New seat seat0.1589second # [ 10.729936] systemd[1]: Started User Login Management.1590second # [ 10.735137] systemd[1]: Starting linger-users.service...1591test # [ 10.713273] systemd[1]: Starting User Login Management...1592test # [ 10.718536] systemd[1]: Finished Import lastlog data into lastlog2 database.1593test # [ 10.735893] systemd[1]: Started Network Manager Script Dispatcher Service.1594second # [ 10.818341] systemd[1]: linger-users.service: Deactivated successfully.1595second # [ 10.820083] systemd[1]: Finished linger-users.service.1596second # [ 10.840386] systemd[1]: Started Hostname Service.1597test # [ 10.855119] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1598test # [ 10.874383] systemd[1]: Starting Hostname Service...1599test # [ 10.896806] systemd-logind[617]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1600test # [ 10.897654] systemd-logind[617]: Watching system buttons on /dev/input/event0 (gpio-keys)1601test # [ 10.902907] systemd-logind[617]: New seat seat0.1602test # [ 10.905300] systemd[1]: Started User Login Management.1603second # [ 10.948105] systemd-networkd[535]: eth0: Link UP1604second # [ 10.948601] systemd-networkd[535]: eth0: Gained carrier1605test # [ 10.911511] systemd[1]: Starting linger-users.service...1606second # [ 10.970335] systemd[1]: Started Network Manager.1607second # [ 10.972199] systemd[1]: Reached target Network.1608second # [ 10.977811] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1609second # [ 10.983336] systemd[1]: Starting Permit User Sessions...1610test # [ 10.986903] systemd[1]: linger-users.service: Deactivated successfully.1611test # [ 10.987531] systemd[1]: Finished linger-users.service.1612test # [ 11.010981] systemd[1]: Started Hostname Service.1613test # [ 11.107084] systemd-networkd[557]: eth0: Link UP1614test # [ 11.107578] systemd-networkd[557]: eth0: Gained carrier1615second # [ 11.148249] systemd[1]: Finished Permit User Sessions.1616second # [ 11.155183] systemd[1]: Started Getty on tty1.1617second # [ 11.162009] systemd[1]: Reached target Login Prompts.1618test # [ 11.127787] systemd[1]: Started Network Manager.1619test # [ 11.129520] systemd[1]: Reached target Network.1620test # [ 11.137676] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1621second # [ 11.167340] systemd-resolved[331]: eth0: Bus client set default route setting: yes1622second # [ 11.177153] systemd-resolved[331]: eth0: Bus client set DNS server list to: 10.0.2.31623test # [ 11.142410] systemd[1]: Starting Permit User Sessions...1624second # [ 11.285630] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1625second # [ 11.288324] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1626test # [ 11.292516] systemd[1]: Finished Permit User Sessions.1627test # [ 11.311101] systemd[1]: Started Getty on tty1.1628test # [ 11.311807] systemd[1]: Reached target Login Prompts.1629test # [ 11.337404] NetworkManager[571]: <warn> [1792145250.1284] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1630test # [ 11.340169] NetworkManager[571]: <warn> [1792145250.1315] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1631test # [ 11.354042] systemd-resolved[353]: eth0: Bus client set default route setting: yes1632test # [ 11.356666] systemd-resolved[353]: eth0: Bus client set DNS server list to: 10.0.2.31633second # [ 11.437404] systemd[1]: Reached target Multi-User System.1634second # [ 11.441656] systemd[1]: Startup finished in 761ms (kernel) + 4.858s (initrd) + 5.820s (userspace) = 11.440s.1635test # [ 11.413526] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1636test # [ 11.415146] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1637second # [ 11.457073] systemd-networkd[535]: eth1: Gained IPv6LL1638test # [ 11.560212] systemd[1]: Reached target Multi-User System.1639test # [ 11.561836] systemd[1]: Startup finished in 762ms (kernel) + 4.865s (initrd) + 5.933s (userspace) = 11.561s.1640test # [ 11.808884] systemd-networkd[557]: eth1: Gained IPv6LL1641second # [ 11.937799] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31642test: (finished: waiting for unit NetworkManager.service, in 12.24 seconds)1643test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1644test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds)1645test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1646test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.04 seconds)1647test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1648second # [ 12.065957] systemd-networkd[535]: eth0: Gained IPv6LL1649test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.04 seconds)1650(finished: run the VM test script, in 12.84 seconds)1651test script finished in 12.91s1652cleanup1653kill QemuMachine (pid 14)1654second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/q1khrhbn506imjxx7kiqcbihycawa6kl-python3-3.13.15/bin/python3.13)1655test # [ 12.204507] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31656kill QemuMachine (pid 15)1657test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/q1khrhbn506imjxx7kiqcbihycawa6kl-python3-3.13.15/bin/python3.13)1658(finished: cleanup, in 0.28 seconds)