vm-test-run-wifi
checks.aarch64-linux.wifi
· build #838
· raw
1build01: building fy2y8yssg7418v6r9haplg2dkgvdcwn6-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.yDWYiTcAVx', 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: dd42245c-1cbe-4e66-86ca-8c292184439a20second # 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: QEMU running (pid 15)31test # Disk image does not exist, creating the virtualisation disk image...32test # Formatting '/build/vm-state-test/tmp.YAQpB2s9ph', fmt=raw size=107374182433test # 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: 7ac41099-d678-49a1-9597-6347f986492f37test # Superblock backups stored on blocks:38test # 32768, 98304, 163840, 22937639test # 40test # Allocating group tables: 0/8 done41(finished: start all VMs, in 0.52 seconds)42test # Writing inode tables: 0/8 done43test: waiting for unit NetworkManager.service44test # Creating journal (8192 blocks): done45test: waiting for the VM to finish booting46test # Writing superblocks and filesystem accounting information: 0/8 done47test # 48test # Virtualisation disk image created.49second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]50second # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 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 alternatives85test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]86second # [ 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/349syfvjs07q78ia0yfmnyngrygackn1-nixos-system-second-test/init regInfo=/nix/store/dp47lcqbwkfr79wx7w5wd7bsmmzlhd11-closure-info/registration console=ttyAMA0,115200n8 console=tty087test # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202688second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/dp47lcqbwkfr79wx7w5wd7bsmmzlhd11-closure-info/registration", will be passed to user space.89test # [ 0.000000] KASLR enabled90test # [ 0.000000] random: crng init done91second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes92test # [ 0.000000] Machine model: linux,dummy-virt93test # [ 0.000000] efi: UEFI not found.94second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)95test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT96second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)97test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]98second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB99test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]100second # [ 0.000000] software IO TLB: area num 1.101test # [ 0.000000] Zone ranges:102second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)103test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]104test # [ 0.000000] DMA32 empty105second # [ 0.000000] Fallback order for Node 0: 0106test # [ 0.000000] Normal empty107test # [ 0.000000] Device empty108second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144109test # [ 0.000000] Movable zone start for each node110second # [ 0.000000] Policy zone: DMA111test # [ 0.000000] Early memory node ranges112second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off113test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]114second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1115test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]116second # [ 0.000000] allocated 2097152 bytes of page_ext117test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000118second # [ 0.000000] ftrace: allocating 74832 entries in 294 pages119test # [ 0.000000] psci: probing for conduit method from DT.120second # [ 0.000000] ftrace: allocated 294 pages with 4 groups121test # [ 0.000000] psci: PSCIv1.3 detected in firmware.122second # [ 0.000000] rcu: Hierarchical RCU implementation.123second # [ 0.000000] rcu: RCU event tracing is enabled.124test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs125test # [ 0.000000] psci: Trusted OS migration not required126second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.127test # [ 0.000000] psci: SMC Calling Convention v1.1128second # [ 0.000000] Trampoline variant of Tasks RCU enabled.129second # [ 0.000000] Rude variant of Tasks RCU enabled.130test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)131second # [ 0.000000] Tracing variant of Tasks RCU enabled.132test # [ 0.000000] percpu: Embedded 76 pages/cpu s186520 r8192 d116584 u311296133test # [ 0.000000] Detected PIPT I-cache on CPU0134second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.135test # [ 0.000000] CPU features: detected: GICv3 CPU interface136second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1137test # [ 0.000000] CPU features: detected: Spectre-v4138test # [ 0.000000] CPU features: detected: Spectre-BHB139second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.140test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR141second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.142test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)143test # [ 0.000000] CPU features: detected: ARM erratum 1418040144second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.145second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0146test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion147second # [ 0.000000] GICv3: 256 SPIs implemented148test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing149second # [ 0.000000] GICv3: 0 Extended SPIs implemented150test # [ 0.000000] alternatives: applying boot alternatives151second # [ 0.000000] Root IRQ handler: gic_handle_irq152second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI153second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0154second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000155second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]156second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)157test # [ 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/xb3s2lxich4ryndlcidlr3b077mq6kda-nixos-system-test-test/init regInfo=/nix/store/gw1a7sjrgqrf87j51ss3gdwp540q7g1w-closure-info/registration console=ttyAMA0,115200n8 console=tty0158second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)159test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/gw1a7sjrgqrf87j51ss3gdwp540q7g1w-closure-info/registration", will be passed to user space.160second # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000161test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes162second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000163second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.164test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)165test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)166second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns167test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB168second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040169test # [ 0.000000] software IO TLB: area num 1.170second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).171test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)172test # [ 0.000000] Fallback order for Node 0: 0173second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns174test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144175test # [ 0.000000] Policy zone: DMA176second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns177second # [ 0.000058] arm-pv: using stolen time PV178test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off179test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1180second # [ 0.000346] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)181test # [ 0.000000] allocated 2097152 bytes of page_ext182second # [ 0.000618] Console: colour dummy device 80x25183test # [ 0.000000] ftrace: allocating 74832 entries in 294 pages184second # [ 0.000629] printk: legacy console [tty0] enabled185test # [ 0.000000] ftrace: allocated 294 pages with 4 groups186test # [ 0.000000] rcu: Hierarchical RCU implementation.187second # [ 0.000839] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)188test # [ 0.000000] rcu: RCU event tracing is enabled.189second # [ 0.000845] pid_max: default: 32768 minimum: 301190test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.191second # [ 0.000916] LSM: initializing lsm=capability,landlock,yama,bpf,ima192test # [ 0.000000] Trampoline variant of Tasks RCU enabled.193second # [ 0.001077] landlock: Up and running.194second # [ 0.001080] Yama: becoming mindful.195test # [ 0.000000] Rude variant of Tasks RCU enabled.196second # [ 0.001424] LSM support for eBPF active197test # [ 0.000000] Tracing variant of Tasks RCU enabled.198second # [ 0.001576] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)199second # [ 0.001594] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)200test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.201second # [ 0.004177] rcu: Hierarchical SRCU implementation.202test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1203second # [ 0.004183] rcu: Max phase no-delay instances is 1000.204second # [ 0.005107] fsl-mc MSI: its@8080000 domain created205test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.206second # [ 0.005290] EFI services will not be available.207second # [ 0.005376] smp: Bringing up secondary CPUs ...208test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.209second # [ 0.005409] smp: Brought up 1 node, 1 CPU210second # [ 0.005411] SMP: Total of 1 processors activated.211test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.212second # [ 0.005413] CPU: All CPU(s) started at EL1213test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0214second # [ 0.005425] CPU features: detected: 32-bit EL0 Support215test # [ 0.000000] GICv3: 256 SPIs implemented216test # [ 0.000000] GICv3: 0 Extended SPIs implemented217second # [ 0.005428] CPU features: detected: Data cache clean to the PoU not required for I/D coherence218test # [ 0.000000] Root IRQ handler: gic_handle_irq219second # [ 0.005431] CPU features: detected: Common not Private translations220test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI221second # [ 0.005434] CPU features: detected: CRC32 instructions222test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0223second # [ 0.005436] CPU features: detected: Data cache clean to Point of Persistence224test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000225second # [ 0.005438] CPU features: detected: Enhanced Virtualization Traps226test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]227second # [ 0.005448] CPU features: detected: RCpc load-acquire (LDAPR)228test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)229second # [ 0.005450] CPU features: detected: LSE atomic instructions230second # [ 0.005452] CPU features: detected: Privileged Access Never231test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)232second # [ 0.005454] CPU features: detected: PMUv3233test # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000234second # [ 0.005456] CPU features: detected: RAS Extension Support235test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000236second # [ 0.005460] CPU features: detected: Speculative Store Bypassing Safe (SSBS)237test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.238second # [ 0.005480] alternatives: applying system-wide alternatives239second # [ 0.006874] CPU features: detected: Hardware dirty bit management on CPU0240test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns241test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040242second # [ 0.007046] Memory: 892924K/1048576K available (24320K kernel code, 7090K rwdata, 26572K rodata, 4736K init, 1103K bss, 114364K reserved, 32768K cma-reserved)243test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).244second # [ 0.007504] devtmpfs: initialized245second # [ 0.008972] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)246test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns247test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns248second # [ 0.008996] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).249test # [ 0.000054] arm-pv: using stolen time PV250second # [ 0.009129] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL251second # [ 0.009132] 0 pages in range for non-PLT usage252test # [ 0.000314] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)253second # [ 0.009133] 508272 pages in range for PLT usage254test # [ 0.000594] Console: colour dummy device 80x25255second # [ 0.009245] pinctrl core: initialized pinctrl subsystem256test # [ 0.000607] printk: legacy console [tty0] enabled257second # [ 0.010052] DMI not present or invalid.258second # [ 0.013220] NET: Registered PF_NETLINK/PF_ROUTE protocol family259test # [ 0.000808] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)260test # [ 0.000813] pid_max: default: 32768 minimum: 301261second # [ 0.015528] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations262test # [ 0.000889] LSM: initializing lsm=capability,landlock,yama,bpf,ima263second # [ 0.015673] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations264test # [ 0.001015] landlock: Up and running.265test # [ 0.001017] Yama: becoming mindful.266second # [ 0.015843] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations267test # [ 0.001312] LSM support for eBPF active268second # [ 0.015869] audit: initializing netlink subsys (disabled)269test # [ 0.001460] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)270second # [ 0.016109] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1271test # [ 0.001481] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)272test # [ 0.003900] rcu: Hierarchical SRCU implementation.273test # [ 0.003907] rcu: Max phase no-delay instances is 1000.274second # [ 0.016547] thermal_sys: Registered thermal governor 'fair_share'275test # [ 0.004906] fsl-mc MSI: its@8080000 domain created276second # [ 0.016549] thermal_sys: Registered thermal governor 'bang_bang'277test # [ 0.005090] EFI services will not be available.278second # [ 0.016552] thermal_sys: Registered thermal governor 'step_wise'279test # [ 0.005172] smp: Bringing up secondary CPUs ...280test # [ 0.005201] smp: Brought up 1 node, 1 CPU281second # [ 0.016555] thermal_sys: Registered thermal governor 'user_space'282test # [ 0.005204] SMP: Total of 1 processors activated.283second # [ 0.016557] thermal_sys: Registered thermal governor 'power_allocator'284test # [ 0.005206] CPU: All CPU(s) started at EL1285second # [ 0.016584] cpuidle: using governor ladder286test # [ 0.005216] CPU features: detected: 32-bit EL0 Support287second # [ 0.016590] cpuidle: using governor menu288second # [ 0.016708] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.289test # [ 0.005220] CPU features: detected: Data cache clean to the PoU not required for I/D coherence290second # [ 0.016729] ASID allocator initialised with 32768 entries291test # [ 0.005222] CPU features: detected: Common not Private translations292second # [ 0.017351] Serial: AMBA PL011 UART driver293test # [ 0.005225] CPU features: detected: CRC32 instructions294second # [ 0.020773] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1295test # [ 0.005226] CPU features: detected: Data cache clean to Point of Persistence296second # [ 0.020938] printk: console [ttyAMA0] enabled297test # [ 0.005229] CPU features: detected: Enhanced Virtualization Traps298test # [ 0.005238] CPU features: detected: RCpc load-acquire (LDAPR)299test # [ 0.005241] CPU features: detected: LSE atomic instructions300test # [ 0.005243] CPU features: detected: Privileged Access Never301test # [ 0.005245] CPU features: detected: PMUv3302test # [ 0.005246] CPU features: detected: RAS Extension Support303test # [ 0.005250] CPU features: detected: Speculative Store Bypassing Safe (SSBS)304test # [ 0.005269] alternatives: applying system-wide alternatives305test # [ 0.006743] CPU features: detected: Hardware dirty bit management on CPU0306test # [ 0.006943] Memory: 892876K/1048576K available (24320K kernel code, 7090K rwdata, 26572K rodata, 4736K init, 1103K bss, 114368K reserved, 32768K cma-reserved)307test # [ 0.007370] devtmpfs: initialized308test # [ 0.008750] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)309test # [ 0.008772] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).310test # [ 0.008891] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL311test # [ 0.008894] 0 pages in range for non-PLT usage312test # [ 0.008895] 508272 pages in range for PLT usage313test # [ 0.008993] pinctrl core: initialized pinctrl subsystem314test # [ 0.009741] DMI not present or invalid.315test # [ 0.012749] NET: Registered PF_NETLINK/PF_ROUTE protocol family316test # [ 0.015026] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations317test # [ 0.015175] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations318test # [ 0.015343] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations319test # [ 0.015365] audit: initializing netlink subsys (disabled)320test # [ 0.015959] thermal_sys: Registered thermal governor 'fair_share'321test # [ 0.015961] thermal_sys: Registered thermal governor 'bang_bang'322second # [ 0.093445] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages323test # [ 0.015964] thermal_sys: Registered thermal governor 'step_wise'324second # [ 0.093459] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page325test # [ 0.015966] thermal_sys: Registered thermal governor 'user_space'326second # [ 0.093462] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages327test # [ 0.015968] thermal_sys: Registered thermal governor 'power_allocator'328second # [ 0.093467] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page329test # [ 0.015999] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1330second # [ 0.093469] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages331test # [ 0.016014] cpuidle: using governor ladder332test # [ 0.016019] cpuidle: using governor menu333second # [ 0.093471] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page334test # [ 0.016151] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.335second # [ 0.093473] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages336test # [ 0.016172] ASID allocator initialised with 32768 entries337second # [ 0.093475] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page338test # [ 0.016796] Serial: AMBA PL011 UART driver339second # [ 0.094441] fbcon: Taking over console340second # [ 0.094451] ACPI: Interpreter disabled.341test # [ 0.020117] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1342second # [ 0.095099] iommu: Default domain type: Translated343test # [ 0.020242] printk: console [ttyAMA0] enabled344second # [ 0.095103] iommu: DMA domain TLB invalidation policy: strict mode345second # [ 0.095385] SCSI subsystem initialized346second # [ 0.095572] usbcore: registered new interface driver usbfs347second # [ 0.095602] usbcore: registered new interface driver hub348second # [ 0.095622] usbcore: registered new device driver usb349second # [ 0.095826] pps_core: LinuxPPS API ver. 1 registered350second # [ 0.095829] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>351second # [ 0.095834] PTP clock support registered352second # [ 0.095860] EDAC MC: Ver: 3.0.0353second # [ 0.105478] scmi_core: SCMI protocol bus registered354second # [ 0.106210] FPGA manager framework355second # [ 0.109426] vgaarb: loaded356second # [ 0.110125] clocksource: Switched to clocksource arch_sys_counter357test # [ 0.092538] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages358test # [ 0.092553] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page359second # [ 0.119324] VFS: Disk quotas dquot_6.6.0360test # [ 0.092555] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages361second # [ 0.119349] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)362test # [ 0.092560] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page363second # [ 0.120734] netfs: FS-Cache loaded364second # [ 0.120841] pnp: PnP ACPI: disabled365test # [ 0.092562] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages366test # [ 0.092564] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page367test # [ 0.092566] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages368test # [ 0.092568] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page369test # [ 0.093513] fbcon: Taking over console370test # [ 0.093525] ACPI: Interpreter disabled.371test # [ 0.094186] iommu: Default domain type: Translated372test # [ 0.094190] iommu: DMA domain TLB invalidation policy: strict mode373second # [ 0.123964] NET: Registered PF_INET protocol family374test # [ 0.094486] SCSI subsystem initialized375second # [ 0.124133] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)376test # [ 0.094705] usbcore: registered new interface driver usbfs377test # [ 0.094735] usbcore: registered new interface driver hub378test # [ 0.094748] usbcore: registered new device driver usb379test # [ 0.094963] pps_core: LinuxPPS API ver. 1 registered380test # [ 0.094965] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>381test # [ 0.094970] PTP clock support registered382test # [ 0.095003] EDAC MC: Ver: 3.0.0383test # [ 0.095164] scmi_core: SCMI protocol bus registered384test # [ 0.095440] FPGA manager framework385test # [ 0.113119] vgaarb: loaded386test # [ 0.113327] clocksource: Switched to clocksource arch_sys_counter387test # [ 0.116773] VFS: Disk quotas dquot_6.6.0388test # [ 0.116799] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)389test # [ 0.119985] netfs: FS-Cache loaded390test # [ 0.120095] pnp: PnP ACPI: disabled391test # [ 0.122514] NET: Registered PF_INET protocol family392test # [ 0.122700] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)393second # [ 0.148262] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)394second # [ 0.148285] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)395second # [ 0.148297] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)396second # [ 0.148331] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)397second # [ 0.148398] TCP: Hash tables configured (established 8192 bind 8192)398second # [ 0.148487] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)399second # [ 0.148527] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)400second # [ 0.148550] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)401second # [ 0.148689] NET: Registered PF_UNIX/PF_LOCAL protocol family402second # [ 0.148724] NET: Registered PF_XDP protocol family403second # [ 0.148745] PCI: CLS 0 bytes, default 64404second # [ 0.148961] Trying to unpack rootfs image as initramfs...405second # [ 0.162148] kvm [1]: HYP mode not available406test # [ 0.147451] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)407test # [ 0.147474] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)408test # [ 0.147486] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)409test # [ 0.147522] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)410test # [ 0.147588] TCP: Hash tables configured (established 8192 bind 8192)411test # [ 0.147674] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)412test # [ 0.147700] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)413test # [ 0.147754] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)414test # [ 0.147884] NET: Registered PF_UNIX/PF_LOCAL protocol family415test # [ 0.147919] NET: Registered PF_XDP protocol family416test # [ 0.147939] PCI: CLS 0 bytes, default 64417test # [ 0.148150] Trying to unpack rootfs image as initramfs...418test # [ 0.161352] kvm [1]: HYP mode not available419second # [ 0.226565] Initialise system trusted keyrings420second # [ 0.226688] workingset: timestamp_bits=42 max_order=18 bucket_order=0421second # [ 0.227081] squashfs: version 4.0 (2009/01/31) Phillip Lougher422second # [ 0.227130] 9p: Installing v9fs 9p2000 file system support423test # [ 0.225725] Initialise system trusted keyrings424test # [ 0.225848] workingset: timestamp_bits=42 max_order=18 bucket_order=0425test # [ 0.226284] squashfs: version 4.0 (2009/01/31) Phillip Lougher426test # [ 0.226321] 9p: Installing v9fs 9p2000 file system support427second # [ 0.251663] Key type asymmetric registered428second # [ 0.251682] Asymmetric key parser 'x509' registered429second # [ 0.251744] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)430second # [ 0.251840] io scheduler mq-deadline registered431second # [ 0.251844] io scheduler kyber registered432second # [ 0.262253] pl061_gpio 9030000.pl061: PL061 GPIO chip registered433test # [ 0.241121] Key type asymmetric registered434test # [ 0.241138] Asymmetric key parser 'x509' registered435second # [ 0.262626] ledtrig-cpu: registered to indicate activity on CPUs436test # [ 0.241195] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)437second # [ 0.262966] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:438second # [ 0.262998] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000439second # [ 0.263012] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000440second # [ 0.263017] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000441second # [ 0.263053] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits442test # [ 0.250641] io scheduler mq-deadline registered443second # [ 0.263079] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]444test # [ 0.250648] io scheduler kyber registered445second # [ 0.263151] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00446second # [ 0.263167] pci_bus 0000:00: root bus resource [bus 00-ff]447second # [ 0.263170] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]448second # [ 0.263173] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]449second # [ 0.263176] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]450second # [ 0.263260] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint451second # [ 0.263682] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint452second # [ 0.263794] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]453second # [ 0.263802] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]454second # [ 0.263821] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]455second # [ 0.263831] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]456second # [ 0.264103] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint457second # [ 0.264210] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]458second # [ 0.264218] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]459second # [ 0.264236] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]460test # [ 0.257456] pl061_gpio 9030000.pl061: PL061 GPIO chip registered461second # [ 0.264503] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint462second # [ 0.264611] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]463test # [ 0.257816] ledtrig-cpu: registered to indicate activity on CPUs464second # [ 0.264620] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]465test # [ 0.258144] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:466second # [ 0.264638] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]467test # [ 0.258161] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000468second # [ 0.264904] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint469second # [ 0.265010] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]470test # [ 0.258174] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000471second # [ 0.265019] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]472test # [ 0.258179] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000473second # [ 0.265038] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]474test # [ 0.258207] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits475second # [ 0.265308] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint476second # [ 0.265414] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]477test # [ 0.258229] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]478second # [ 0.265423] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]479test # [ 0.258300] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00480test # [ 0.258316] pci_bus 0000:00: root bus resource [bus 00-ff]481second # [ 0.265442] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]482test # [ 0.258320] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]483second # [ 0.265702] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint484test # [ 0.258322] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]485second # [ 0.265809] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]486second # [ 0.265817] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]487test # [ 0.258325] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]488second # [ 0.265834] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]489test # [ 0.258401] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint490second # [ 0.266100] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint491test # [ 0.258825] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint492test # [ 0.258937] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]493test # [ 0.258946] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]494test # [ 0.258965] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]495test # [ 0.258975] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]496test # [ 0.259249] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint497test # [ 0.259359] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]498test # [ 0.259367] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]499test # [ 0.259385] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]500second # [ 0.286622] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]501second # [ 0.286636] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]502test # [ 0.259656] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint503test # [ 0.259765] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]504second # [ 0.286655] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]505test # [ 0.259773] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]506second # [ 0.286672] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]507test # [ 0.259792] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]508second # [ 0.287036] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint509test # [ 0.260063] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint510second # [ 0.287151] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]511test # [ 0.260172] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]512second # [ 0.287168] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]513test # [ 0.260180] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]514second # [ 0.287438] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint515test # [ 0.260199] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]516second # [ 0.287551] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]517test # [ 0.260476] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint518second # [ 0.287569] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]519test # [ 0.260584] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]520second # [ 0.287813] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint521test # [ 0.260591] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]522second # [ 0.287921] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]523test # [ 0.260610] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]524second # [ 0.288080] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint525test # [ 0.260893] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint526second # [ 0.288231] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]527test # [ 0.261002] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]528second # [ 0.288240] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]529test # [ 0.261011] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]530second # [ 0.288327] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]531test # [ 0.261029] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]532second # [ 0.288606] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint533second # [ 0.288716] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]534second # [ 0.288725] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]535second # [ 0.288744] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]536second # [ 0.289144] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned537second # [ 0.289150] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned538second # [ 0.289153] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned539second # [ 0.289185] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned540second # [ 0.289214] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned541second # [ 0.289243] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned542second # [ 0.289273] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned543second # [ 0.289301] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned544second # [ 0.289330] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned545second # [ 0.289360] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned546second # [ 0.289389] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned547second # [ 0.289417] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned548second # [ 0.289477] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned549second # [ 0.289508] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned550second # [ 0.289521] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned551second # [ 0.289534] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned552second # [ 0.289546] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned553second # [ 0.289558] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned554second # [ 0.289571] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned555second # [ 0.289584] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned556second # [ 0.289597] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned557second # [ 0.289609] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned558test # [ 0.289394] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint559test # [ 0.289520] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]560second # [ 0.289621] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned561test # [ 0.289529] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]562second # [ 0.289634] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned563test # [ 0.289548] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]564second # [ 0.289647] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned565test # [ 0.289562] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]566second # [ 0.289660] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned567second # [ 0.289672] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned568test # [ 0.289895] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint569second # [ 0.289685] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned570test # [ 0.290021] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]571second # [ 0.289697] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned572test # [ 0.290039] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]573second # [ 0.289714] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned574test # [ 0.290312] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint575second # [ 0.289726] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned576test # [ 0.290424] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]577second # [ 0.289738] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned578test # [ 0.290442] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]579second # [ 0.289751] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned580second # [ 0.289763] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned581test # [ 0.290687] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint582second # [ 0.289780] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]583test # [ 0.290794] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]584second # [ 0.289784] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]585test # [ 0.290967] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint586second # [ 0.289787] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]587test # [ 0.291186] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]588test # [ 0.291196] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]589test # [ 0.291214] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]590test # [ 0.291508] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint591test # [ 0.291618] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]592test # [ 0.291627] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]593test # [ 0.291646] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]594test # [ 0.292039] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned595test # [ 0.292046] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned596test # [ 0.292050] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned597test # [ 0.292081] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned598test # [ 0.292111] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned599test # [ 0.292140] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned600test # [ 0.292168] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned601test # [ 0.292198] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned602test # [ 0.292227] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned603test # [ 0.292256] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned604test # [ 0.292285] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned605test # [ 0.292314] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned606test # [ 0.292389] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned607test # [ 0.292419] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned608second # [ 0.326538] pci 0000:00:0a.0: enabling device (0000 -> 0002)609test # [ 0.292431] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned610test # [ 0.292443] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned611test # [ 0.292456] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned612test # [ 0.292468] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned613test # [ 0.292480] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned614test # [ 0.292493] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned615test # [ 0.292506] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned616test # [ 0.292518] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned617test # [ 0.292530] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned618test # [ 0.292542] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned619test # [ 0.292555] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned620test # [ 0.292567] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned621test # [ 0.292579] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned622test # [ 0.292591] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned623test # [ 0.292604] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned624test # [ 0.292620] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned625test # [ 0.292632] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned626second # [ 0.336947] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)627test # [ 0.292644] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned628test # [ 0.292656] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned629test # [ 0.292668] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned630test # [ 0.292685] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]631test # [ 0.292689] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]632test # [ 0.292691] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]633test # [ 0.293381] pci 0000:00:0a.0: enabling device (0000 -> 0002)634second # [ 0.342650] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)635second # [ 0.344393] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)636second # [ 0.350261] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)637second # [ 0.352031] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)638second # [ 0.353682] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)639test # [ 0.334110] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)640test # [ 0.336048] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)641test # [ 0.342756] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)642test # [ 0.345206] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)643second # [ 0.363624] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)644second # [ 0.365729] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)645test # [ 0.354355] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)646test # [ 0.356038] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)647second # [ 0.376350] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)648second # [ 0.378952] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)649second # [ 0.380970] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)650test # [ 0.366479] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)651test # [ 0.368458] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)652second # [ 0.392584] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled653second # [ 0.393543] msm_serial: driver initialized654second # [ 0.393632] SuperH (H)SCI(F) driver initialized655second # [ 0.393671] STM32 USART driver initialized656test # [ 0.378098] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)657test # [ 0.380431] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)658test # [ 0.382485] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)659test # [ 0.394082] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled660test # [ 0.395070] msm_serial: driver initialized661test # [ 0.395152] SuperH (H)SCI(F) driver initialized662test # [ 0.395192] STM32 USART driver initialized663second # [ 0.418321] loop: module loaded664second # [ 0.418444] virtio_blk virtio5: 1/0/0 default/read/poll queues665second # [ 0.419015] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)666second # [ 0.422532] megasas: 07.734.00.00-rc1667second # [ 0.423039] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]668second # [ 0.435151] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000669second # [ 0.435179] Intel/Sharp Extended Query Table at 0x0031670second # [ 0.436845] Using buffer write method671second # [ 0.436877] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]672test # [ 0.419275] loop: module loaded673test # [ 0.419403] virtio_blk virtio5: 1/0/0 default/read/poll queues674test # [ 0.420036] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)675test # [ 0.421748] megasas: 07.734.00.00-rc1676test # [ 0.422245] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]677second # [ 0.446658] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000678second # [ 0.446670] Intel/Sharp Extended Query Table at 0x0031679second # [ 0.448211] Using buffer write method680second # [ 0.448225] Concatenating MTD devices:681second # [ 0.448226] (0): "0.flash"682second # [ 0.448228] (1): "0.flash"683second # [ 0.448230] into device "0.flash"684test # [ 0.434347] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000685test # [ 0.434397] Intel/Sharp Extended Query Table at 0x0031686test # [ 0.436119] Using buffer write method687test # [ 0.436165] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]688test # [ 0.445890] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000689test # [ 0.445902] Intel/Sharp Extended Query Table at 0x0031690test # [ 0.447401] Using buffer write method691test # [ 0.447413] Concatenating MTD devices:692test # [ 0.447415] (0): "0.flash"693test # [ 0.447417] (1): "0.flash"694test # [ 0.447418] into device "0.flash"695second # [ 0.641177] Freeing initrd memory: 25500K696second # [ 0.648094] tun: Universal TUN/TAP device driver, 1.6697second # [ 0.650740] thunder_xcv, ver 1.0698test # [ 0.637286] Freeing initrd memory: 25504K699second # [ 0.650755] thunder_bgx, ver 1.0700second # [ 0.650763] nicpf, ver 1.0701second # [ 0.651093] e1000: Intel(R) PRO/1000 Network Driver702second # [ 0.651098] e1000: Copyright (c) 1999-2006 Intel Corporation.703second # [ 0.651110] e1000e: Intel(R) PRO/1000 Network Driver704second # [ 0.651116] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.705second # [ 0.651127] igb: Intel(R) Gigabit Ethernet Network Driver706second # [ 0.651129] igb: Copyright (c) 2007-2014 Intel Corporation.707second # [ 0.651139] igbvf: Intel(R) Gigabit Virtual Function Network Driver708second # [ 0.651141] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.709second # [ 0.651208] sky2: driver version 1.30710second # [ 0.652067] usbcore: registered new interface driver usb-storage711second # [ 0.652107] usbcore: registered new interface driver usbserial_generic712second # [ 0.652124] usbserial: USB Serial support registered for generic713second # [ 0.652425] hv_vmbus: registering driver hyperv_keyboard714second # [ 0.653459] ehci-pci 0000:00:0a.0: EHCI Host Controller715second # [ 0.653485] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1716second # [ 0.653719] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000717second # [ 0.653895] rtc-pl031 9010000.pl031: registered as rtc0718test # [ 0.644050] tun: Universal TUN/TAP device driver, 1.6719second # [ 0.653922] rtc-pl031 9010000.pl031: setting system clock to 2026-10-03T15:03:04 UTC (1791039784)720second # [ 0.654093] i2c_dev: i2c /dev entries driver721second # [ 0.663170] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00722second # [ 0.663537] hub 1-0:1.0: USB hub found723test # [ 0.646725] thunder_xcv, ver 1.0724second # [ 0.663564] hub 1-0:1.0: 6 ports detected725test # [ 0.646740] thunder_bgx, ver 1.0726test # [ 0.646749] nicpf, ver 1.0727second # [ 0.665481] sdhci: Secure Digital Host Controller Interface driver728test # [ 0.647045] e1000: Intel(R) PRO/1000 Network Driver729second # [ 0.665486] sdhci: Copyright(c) Pierre Ossman730test # [ 0.647048] e1000: Copyright (c) 1999-2006 Intel Corporation.731second # [ 0.665635] Synopsys Designware Multimedia Card Interface Driver732test # [ 0.647060] e1000e: Intel(R) PRO/1000 Network Driver733second # [ 0.665814] sdhci-pltfm: SDHCI platform and OF driver helper734test # [ 0.647065] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.735test # [ 0.647076] igb: Intel(R) Gigabit Ethernet Network Driver736test # [ 0.647078] igb: Copyright (c) 2007-2014 Intel Corporation.737test # [ 0.647088] igbvf: Intel(R) Gigabit Virtual Function Network Driver738second # [ 0.669492] hid: raw HID events driver (C) Jiri Kosina739test # [ 0.647090] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.740second # [ 0.669636] usbcore: registered new interface driver usbhid741test # [ 0.647155] sky2: driver version 1.30742second # [ 0.669638] usbhid: USB HID core driver743test # [ 0.648028] usbcore: registered new interface driver usb-storage744test # [ 0.648137] usbcore: registered new interface driver usbserial_generic745test # [ 0.648155] usbserial: USB Serial support registered for generic746test # [ 0.648456] hv_vmbus: registering driver hyperv_keyboard747test # [ 0.649485] ehci-pci 0000:00:0a.0: EHCI Host Controller748test # [ 0.649509] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1749test # [ 0.649746] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000750test # [ 0.657406] rtc-pl031 9010000.pl031: registered as rtc0751test # [ 0.657444] rtc-pl031 9010000.pl031: setting system clock to 2026-10-03T15:03:04 UTC (1791039784)752test # [ 0.657638] i2c_dev: i2c /dev entries driver753test # [ 0.659216] sdhci: Secure Digital Host Controller Interface driver754second # [ 0.711281] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available755second # [ 0.712159] drop_monitor: Initializing network drop monitor service756test # [ 0.659220] sdhci: Copyright(c) Pierre Ossman757second # [ 0.712280] NET: Registered PF_INET6 protocol family758test # [ 0.659372] Synopsys Designware Multimedia Card Interface Driver759second # [ 0.712917] Segment Routing with IPv6760test # [ 0.659551] sdhci-pltfm: SDHCI platform and OF driver helper761second # [ 0.712935] In-situ OAM (IOAM) with IPv6762test # [ 0.660414] hid: raw HID events driver (C) Jiri Kosina763second # [ 0.712980] NET: Registered PF_PACKET protocol family764second # [ 0.713084] 9pnet: Installing 9P2000 support765test # [ 0.660548] usbcore: registered new interface driver usbhid766test # [ 0.660551] usbhid: USB HID core driver767test # [ 0.694265] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00768test # [ 0.694838] hub 1-0:1.0: USB hub found769test # [ 0.694884] hub 1-0:1.0: 6 ports detected770second # [ 0.717448] Key type dns_resolver registered771second # [ 0.720668] registered taskstats version 1772second # [ 0.720811] Loading compiled-in X.509 certificates773test # [ 0.701552] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available774test # [ 0.702424] drop_monitor: Initializing network drop monitor service775test # [ 0.702547] NET: Registered PF_INET6 protocol family776test # [ 0.703190] Segment Routing with IPv6777test # [ 0.703208] In-situ OAM (IOAM) with IPv6778test # [ 0.703253] NET: Registered PF_PACKET protocol family779test # [ 0.703346] 9pnet: Installing 9P2000 support780test # [ 0.704923] Key type dns_resolver registered781second # [ 0.726071] Demotion targets for Node 0: null782second # [ 0.726792] Key type .fscrypt registered783test # [ 0.710990] registered taskstats version 1784second # [ 0.726797] Key type fscrypt-provisioning registered785test # [ 0.711152] Loading compiled-in X.509 certificates786second # [ 0.726903] ima: No TPM chip found, activating TPM-bypass!787second # [ 0.726913] ima: Allocated hash algorithm: sha1788second # [ 0.726965] ima: No architecture policies found789second # [ 0.727519] input: gpio-keys as /devices/platform/gpio-keys/input/input0790test # [ 0.716393] Demotion targets for Node 0: null791test # [ 0.716469] Key type .fscrypt registered792test # [ 0.716471] Key type fscrypt-provisioning registered793test # [ 0.716572] ima: No TPM chip found, activating TPM-bypass!794test # [ 0.716581] ima: Allocated hash algorithm: sha1795test # [ 0.716629] ima: No architecture policies found796test # [ 0.717126] input: gpio-keys as /devices/platform/gpio-keys/input/input0797second # [ 0.739362] clk: Disabling unused clocks798second # [ 0.739372] PM: genpd: Disabling unused power domains799second # [ 0.741771] Freeing unused kernel memory: 4736K800second # [ 0.741854] Run /init as init process801test # [ 0.729492] clk: Disabling unused clocks802test # [ 0.729504] PM: genpd: Disabling unused power domains803test # [ 0.731911] Freeing unused kernel memory: 4736K804test # [ 0.732005] Run /init as init process805second # [ 0.764931] systemd[1]: Successfully made /usr/ read-only.806test # [ 0.756201] systemd[1]: Successfully made /usr/ read-only.807second # [ 0.934185] usb 1-1: new high-speed USB device number 2 using ehci-pci808test # [ 0.949377] usb 1-1: new high-speed USB device number 2 using ehci-pci809second # [ 1.086315] 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.099104] systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)811second # [ 1.099134] systemd[1]: Detected virtualization qemu.812second # [ 1.099170] systemd[1]: Detected architecture arm64.813second # [ 1.099180] systemd[1]: Running in initrd.814second # [ 1.100509] systemd[1]: Initializing machine ID from random generator.815second # [ 1.100803] systemd[1]: Hostname set to <second>.816test # [ 1.090498] systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)817test # [ 1.090528] systemd[1]: Detected virtualization qemu.818test # [ 1.090568] systemd[1]: Detected architecture arm64.819test # [ 1.090577] systemd[1]: Running in initrd.820test # [ 1.091572] systemd[1]: Initializing machine ID from random generator.821test # [ 1.091844] systemd[1]: Hostname set to <test>.822test # [ 1.112133] 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.175543] 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.213973] systemd[1]: Queued start job for default target Initrd Default Target.825test # [ 1.197506] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0826second # [ 1.223545] systemd[1]: Created slice Slice /system/modprobe.827test # [ 1.208804] systemd[1]: Queued start job for default target Initrd Default Target.828second # [ 1.223707] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.829second # [ 1.223732] systemd[1]: Expecting device /dev/disk/by-label/nixos...830second # [ 1.223750] systemd[1]: Reached target Path Units.831second # [ 1.223762] systemd[1]: Reached target Slice Units.832second # [ 1.223776] systemd[1]: Reached target Swaps.833second # [ 1.223791] systemd[1]: Reached target Timer Units.834second # [ 1.223945] systemd[1]: Listening on D-Bus System Message Bus Socket.835second # [ 1.224066] systemd[1]: Listening on Journal Socket (/dev/log).836second # [ 1.224181] systemd[1]: Listening on Journal Sockets.837second # [ 1.224255] systemd[1]: Listening on udev Control Socket.838second # [ 1.224337] systemd[1]: Listening on udev Kernel Socket.839second # [ 1.224357] systemd[1]: Reached target Socket Units.840second # [ 1.225426] systemd[1]: Starting Create List of Static Device Nodes...841second # [ 1.237843] systemd[1]: Starting Load Kernel Module 9pnet_virtio...842test # [ 1.218844] systemd[1]: Created slice Slice /system/modprobe.843test # [ 1.219009] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.844second # [ 1.237889] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs845test # [ 1.219034] systemd[1]: Expecting device /dev/disk/by-label/nixos...846test # [ 1.219055] systemd[1]: Reached target Path Units.847test # [ 1.219069] systemd[1]: Reached target Slice Units.848test # [ 1.219081] systemd[1]: Reached target Swaps.849test # [ 1.219095] systemd[1]: Reached target Timer Units.850test # [ 1.219253] systemd[1]: Listening on D-Bus System Message Bus Socket.851test # [ 1.219385] systemd[1]: Listening on Journal Socket (/dev/log).852test # [ 1.219505] systemd[1]: Listening on Journal Sockets.853test # [ 1.219599] systemd[1]: Listening on udev Control Socket.854test # [ 1.219685] systemd[1]: Listening on udev Kernel Socket.855second # [ 1.243517] systemd[1]: Mounting Kernel Configuration File System...856test # [ 1.219706] systemd[1]: Reached target Socket Units.857test # [ 1.220816] systemd[1]: Starting Create List of Static Device Nodes...858test # [ 1.230992] systemd[1]: Starting Load Kernel Module 9pnet_virtio...859test # [ 1.231042] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs860second # [ 1.252283] systemd[1]: Starting Journal Service...861test # [ 1.239574] systemd[1]: Mounting Kernel Configuration File System...862second # [ 1.255213] systemd[1]: Starting Load Kernel Modules...863second # [ 1.255254] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki864test # [ 1.246628] systemd[1]: Starting Journal Service...865second # [ 1.275124] systemd[1]: Starting Coldplug All udev Devices...866test # [ 1.258044] systemd[1]: Starting Load Kernel Modules...867test # [ 1.258091] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki868second # [ 1.284590] systemd[1]: Finished Create List of Static Device Nodes.869second # [ 1.285537] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.870second # [ 1.294220] usb 1-2: new high-speed USB device number 3 using ehci-pci871second # [ 1.299273] systemd[1]: Finished Load Kernel Module 9pnet_virtio.872test # [ 1.283563] systemd[1]: Starting Coldplug All udev Devices...873second # [ 1.299570] systemd[1]: Mounted Kernel Configuration File System.874test # [ 1.284567] systemd[1]: Finished Create List of Static Device Nodes.875second # [ 1.303397] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...876second # [ 1.307622] systemd-journald[73]: Collecting audit messages is disabled.877test # [ 1.290650] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.878test # [ 1.291399] systemd[1]: Finished Load Kernel Module 9pnet_virtio.879test # [ 1.291623] systemd[1]: Mounted Kernel Configuration File System.880test # [ 1.292585] systemd-journald[73]: Collecting audit messages is disabled.881test # [ 1.309556] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...882second # [ 1.328183] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.883test # [ 1.317394] usb 1-2: new high-speed USB device number 3 using ehci-pci884second # [ 1.342218] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev885test # [ 1.327552] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.886second # [ 1.350593] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.887second # [ 1.351592] systemd[1]: Starting Create Static Device Nodes in /dev...888test # [ 1.343770] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.889test # [ 1.344774] systemd[1]: Starting Create Static Device Nodes in /dev...890second # [ 1.362874] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0891second # [ 1.363097] [drm] features: -virgl +edid -resource_blob -host_visible892second # [ 1.363099] [drm] features: -context_init893second # [ 1.363551] [drm] number of scanouts: 1894second # [ 1.363560] [drm] number of cap sets: 0895second # [ 1.364918] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic896second # [ 1.364924] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0897test # [ 1.353399] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev898test # [ 1.369912] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0899test # [ 1.370154] [drm] features: -virgl +edid -resource_blob -host_visible900test # [ 1.370156] [drm] features: -context_init901test # [ 1.370616] [drm] number of scanouts: 1902test # [ 1.370626] [drm] number of cap sets: 0903second # [ 1.387967] Console: switching to colour frame buffer device 160x50904test # [ 1.375849] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic905test # [ 1.375856] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0906second # [ 1.394635] systemd[1]: Finished Create Static Device Nodes in /dev.907second # [ 1.394818] systemd[1]: Reached target Preparation for Local File Systems.908second # [ 1.394837] systemd[1]: Reached target Local File Systems.909second # [ 1.395891] systemd[1]: Starting Rule-based Manager for Device Events and Files...910second # [ 1.406386] systemd[1]: Started Journal Service.911test # [ 1.389780] systemd[1]: Finished Create Static Device Nodes in /dev.912test # [ 1.390072] systemd[1]: Reached target Preparation for Local File Systems.913test # [ 1.390093] systemd[1]: Reached target Local File Systems.914test # [ 1.390415] systemd-modules-load[74]: Inserted module 'dm_mod'915second # [ 1.415232] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device916test # [ 1.396678] Console: switching to colour frame buffer device 160x50917second # [ 1.404742] systemd-modules-load[74]: Inserted module 'dm_mod'918test # [ 1.398172] systemd-modules-load[74]: Module 'virtio_balloon' is built in919second # [ 1.414838] systemd-modules-load[74]: Module 'virtio_balloon' is built in920test # [ 1.398897] systemd-modules-load[74]: Module 'virtio_console' is built in921second # [ 1.415714] systemd-modules-load[74]: Module 'virtio_console' is built in922second # [ 1.417862] systemd[1]: Starting Create System Files and Directories...923test # [ 1.402437] systemd[1]: Starting Rule-based Manager for Device Events and Files...924test # [ 1.402596] systemd[1]: Started Journal Service.925second # [ 1.422474] systemd-modules-load[74]: Inserted module 'virtio_gpu'926second # [ 1.423113] systemd-modules-load[74]: Module 'virtio_rng' is built in927test # [ 1.413949] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device928second # [ 1.431505] systemd[1]: Finished Load Kernel Modules.929second # [ 1.442832] systemd[1]: Starting Apply Kernel Variables...930second # [ 1.451959] 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/input2931test # [ 1.409680] systemd[1]: Starting Create System Files and Directories...932second # [ 1.452129] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0933test # [ 1.433820] systemd-modules-load[74]: Inserted module 'virtio_gpu'934test # [ 1.434612] systemd-modules-load[74]: Module 'virtio_rng' is built in935test # [ 1.435178] systemd[1]: Finished Load Kernel Modules.936test # [ 1.438310] systemd[1]: Starting Apply Kernel Variables...937second # [ 1.462348] systemd[1]: Finished Create System Files and Directories.938test # [ 1.461735] systemd[1]: Finished Apply Kernel Variables.939second # [ 1.483005] systemd-udevd[81]: Using default interface naming scheme 'v260'.940second # [ 1.484537] systemd[1]: Finished Apply Kernel Variables.941test # [ 1.468751] systemd[1]: Finished Create System Files and Directories.942test # [ 1.477271] 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/input2943test # [ 1.479204] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0944second # [ 1.504340] systemd[1]: Started Rule-based Manager for Device Events and Files.945test # [ 1.493203] systemd-udevd[80]: Using default interface naming scheme 'v260'.946test # [ 1.513949] systemd[1]: Started Rule-based Manager for Device Events and Files.947second # [ 1.551813] systemd[1]: Starting Virtual Console Setup...948test # [ 1.568650] systemd[1]: Starting Virtual Console Setup...949second # [ 1.601267] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.950second # [ 1.604140] systemd[1]: Finished Virtual Console Setup.951test # [ 1.620358] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.952test # [ 1.622603] systemd[1]: Finished Virtual Console Setup.953second # [ 1.873233] systemd[1]: Finished Coldplug All udev Devices.954second # [ 1.873785] systemd[1]: Reached target System Initialization.955second # [ 1.874252] systemd[1]: Reached target Basic System.956test # [ 1.876792] systemd[1]: Finished Coldplug All udev Devices.957test # [ 1.877368] systemd[1]: Reached target System Initialization.958test # [ 1.877852] systemd[1]: Reached target Basic System.959second # [ 1.967355] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.960second # [ 1.996525] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.961test # [ 1.986716] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.962test # [ 2.005196] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.963second # [ 2.044894] systemd[1]: Found device /dev/disk/by-label/nixos.964second # [ 2.046786] systemd[1]: Reached target Initrd Root Device.965second # [ 2.048076] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...966test # [ 2.037266] systemd[1]: Found device /dev/disk/by-label/nixos.967test # [ 2.042164] systemd[1]: Reached target Initrd Root Device.968test # [ 2.044734] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...969second # [ 2.091772] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks970test # [ 2.083594] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks971second # [ 2.111901] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.972test # [ 2.098819] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.973second # [ 2.272196] systemd[1]: Mounting /sysroot...974test # [ 2.280934] systemd[1]: Mounting /sysroot...975second # [ 2.303717] systemd[1]: Mounted /sysroot.976second # [ 2.306407] EXT4-fs (vda): mounted filesystem dd42245c-1cbe-4e66-86ca-8c292184439a r/w with ordered data mode. Quota mode: none.977second # [ 2.306137] systemd[1]: Reached target Initrd Root File System.978second # [ 2.309705] systemd[1]: Starting Mountpoints Configured in the Real Root...979test # [ 2.315182] EXT4-fs (vda): mounted filesystem 7ac41099-d678-49a1-9597-6347f986492f r/w with ordered data mode. Quota mode: none.980test # [ 2.314320] systemd[1]: Mounted /sysroot.981second # [ 2.331270] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.982test # [ 2.315758] systemd[1]: Reached target Initrd Root File System.983test # [ 2.319320] systemd[1]: Starting Mountpoints Configured in the Real Root...984second # [ 2.338057] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...985second # [ 2.339967] systemd[1]: Reloading...986test # [ 2.339219] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.987test # [ 2.343889] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...988test # [ 2.347044] systemd[1]: Reloading...989second # [ 2.521910] systemd[1]: Reloading finished in 183 ms.990second # [ 2.532518] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...991second # [ 2.535698] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...992test # [ 2.521175] systemd[1]: Reloading finished in 174 ms.993second # [ 2.538008] systemd[1]: Starting Load Kernel Module 9pnet_virtio...994second # [ 2.543185] systemd[1]: initrd-parse-etc.service: Deactivated successfully.995second # [ 2.545832] systemd[1]: Finished Mountpoints Configured in the Real Root.996second # [ 2.547163] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.997test # [ 2.534909] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...998test # [ 2.538229] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...999test # [ 2.543428] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1000second # [ 2.563868] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1001test # [ 2.548696] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1002second # [ 2.565145] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1003test # [ 2.549938] systemd[1]: Finished Mountpoints Configured in the Real Root.1004test # [ 2.552066] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1005test # [ 2.563823] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1006test # [ 2.564989] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1007second # [ 2.857999] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/mtd_probe": No such file or directory1008second # [ 2.868513] (udev-worker)[97]: mtd0ro: /nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1009second # [ 2.878749] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1010second # [ 2.880333] systemd[1]: Stopped Virtual Console Setup.1011second # [ 2.881996] systemd[1]: Stopping Virtual Console Setup...1012second # [ 2.887877] systemd[1]: Starting Virtual Console Setup...1013second # [ 2.910491] systemd-vconsole-setup[143]: Configuration of first virtual console was skipped, ignoring remaining ones.1014second # [ 2.912655] systemd[1]: Finished Virtual Console Setup.1015test # [ 2.930847] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/mtd_probe": No such file or directory1016test # [ 2.938652] (udev-worker)[97]: mtd0ro: /nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1017test # [ 2.951129] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1018test # [ 2.951812] systemd[1]: Stopped Virtual Console Setup.1019test # [ 2.953088] systemd[1]: Stopping Virtual Console Setup...1020test # [ 2.956118] systemd[1]: Starting Virtual Console Setup...1021test # [ 2.965389] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1022test # [ 2.966270] systemd[1]: Stopped Virtual Console Setup.1023test # [ 2.969773] systemd[1]: Starting Virtual Console Setup...1024test # [ 2.985939] systemd-vconsole-setup[144]: Configuration of first virtual console was skipped, ignoring remaining ones.1025test # [ 2.988309] systemd[1]: Finished Virtual Console Setup.1026second # [ 3.274287] systemd[1]: Mounting /sysroot/nix/.ro-store...1027second # [ 3.281532] systemd[1]: Mounting /sysroot/nix/.rw-store...1028second # [ 3.292277] systemd[1]: Mounting /sysroot/run...1029second # [ 3.295456] systemd[1]: Mounting /sysroot/tmp/shared...1030test # [ 3.282862] systemd[1]: Mounting /sysroot/nix/.ro-store...1031test # [ 3.292295] systemd[1]: Mounting /sysroot/nix/.rw-store...1032second # [ 3.310931] systemd[1]: Mounting /sysroot/tmp/xchg...1033test # [ 3.300351] systemd[1]: Mounting /sysroot/run...1034test # [ 3.312262] systemd[1]: Mounting /sysroot/tmp/shared...1035test # [ 3.323099] systemd[1]: Mounting /sysroot/tmp/xchg...1036second # [ 3.349207] systemd[1]: Mounted /sysroot/nix/.ro-store.1037second # [ 3.357357] systemd[1]: Mounted /sysroot/nix/.rw-store.1038second # [ 3.358813] systemd[1]: Mounted /sysroot/run.1039second # [ 3.365151] systemd[1]: Mounted /sysroot/tmp/shared.1040second # [ 3.366796] systemd[1]: Mounted /sysroot/tmp/xchg.1041second # [ 3.370780] systemd[1]: Starting rw-sysroot-nix-store.service...1042test # [ 3.358975] systemd[1]: Mounted /sysroot/nix/.rw-store.1043test # [ 3.366756] systemd[1]: Mounted /sysroot/nix/.ro-store.1044test # [ 3.368905] systemd[1]: Mounted /sysroot/run.1045test # [ 3.370910] systemd[1]: Mounted /sysroot/tmp/shared.1046test # [ 3.372300] systemd[1]: Mounted /sysroot/tmp/xchg.1047second # [ 3.391117] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1048test # [ 3.376344] systemd[1]: Starting rw-sysroot-nix-store.service...1049second # [ 3.392844] systemd[1]: Finished rw-sysroot-nix-store.service.1050test # [ 3.395220] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1051test # [ 3.396786] systemd[1]: Finished rw-sysroot-nix-store.service.1052second # [ 4.272550] systemd[1]: Mounting /sysroot/nix/store...1053test # [ 4.281372] systemd[1]: Mounting /sysroot/nix/store...1054second # [ 4.318486] systemd[1]: Mounted /sysroot/nix/store.1055second # [ 4.319741] systemd[1]: Reached target Initrd File Systems.1056second # [ 4.322848] systemd[1]: Starting Find NixOS closure...1057second # [ 4.332280] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1058test # [ 4.325040] systemd[1]: Mounted /sysroot/nix/store.1059test # [ 4.327933] systemd[1]: Reached target Initrd File Systems.1060test # [ 4.331111] systemd[1]: Starting Find NixOS closure...1061test # [ 4.340352] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1062second # [ 4.375928] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1063second # [ 4.377861] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1064second # [ 4.393509] systemd[1]: Finished Find NixOS closure.1065second # [ 4.396124] systemd[1]: Reached target Initrd Default Target.1066test # [ 4.379469] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1067second # [ 4.400262] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1068test # [ 4.386103] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1069test # [ 4.399919] systemd[1]: Finished Find NixOS closure.1070test # [ 4.402591] systemd[1]: Reached target Initrd Default Target.1071test # [ 4.404251] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1072second # [ 4.430584] systemd[1]: Stopped target Initrd Default Target.1073second # [ 4.432293] systemd[1]: Stopped target Basic System.1074second # [ 4.436397] systemd[1]: Stopped target Initrd Root Device.1075second # [ 4.437452] systemd[1]: Stopped target Path Units.1076second # [ 4.438199] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1077second # [ 4.440611] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1078second # [ 4.443423] systemd[1]: Stopped target Slice Units.1079second # [ 4.448180] systemd[1]: Stopped target Socket Units.1080second # [ 4.449010] systemd[1]: Stopped target System Initialization.1081second # [ 4.449732] systemd[1]: Stopped target Swaps.1082second # [ 4.450302] systemd[1]: Stopped target Timer Units.1083second # [ 4.450917] systemd[1]: dbus.socket: Deactivated successfully.1084second # [ 4.451682] systemd[1]: Closed D-Bus System Message Bus Socket.1085test # [ 4.439109] systemd[1]: initrd-cleanup.service: Deactivated successfully.1086second # [ 4.456199] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1087second # [ 4.457251] systemd[1]: Stopped Find NixOS closure.1088test # [ 4.440561] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1089second # [ 4.457852] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1090second # [ 4.459184] systemd[1]: Starting rw-sysroot-nix-store.service...1091test # [ 4.444206] systemd[1]: Stopped target Initrd Default Target.1092second # [ 4.460794] systemd[1]: systemd-sysctl.service: Deactivated successfully.1093test # [ 4.445216] systemd[1]: Stopped target Basic System.1094test # [ 4.448223] systemd[1]: Stopped target Initrd Root Device.1095second # [ 4.465073] systemd[1]: Stopped Apply Kernel Variables.1096test # [ 4.449479] systemd[1]: Stopped target Path Units.1097second # [ 4.467059] systemd[1]: systemd-modules-load.service: Deactivated successfully.1098test # [ 4.451241] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1099second # [ 4.470012] systemd[1]: Stopped Load Kernel Modules.1100second # [ 4.471793] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1101test # [ 4.456201] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1102test # [ 4.457289] systemd[1]: Stopped target Slice Units.1103test # [ 4.457987] systemd[1]: Stopped target Socket Units.1104test # [ 4.458722] systemd[1]: Stopped target System Initialization.1105test # [ 4.459521] systemd[1]: Stopped target Swaps.1106second # [ 4.476351] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1107second # [ 4.477536] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1108test # [ 4.464284] systemd[1]: Stopped target Timer Units.1109test # [ 4.465251] systemd[1]: dbus.socket: Deactivated successfully.1110test # [ 4.466038] systemd[1]: Closed D-Bus System Message Bus Socket.1111test # [ 4.466821] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1112test # [ 4.467802] systemd[1]: Stopped Find NixOS closure.1113test # [ 4.470036] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1114second # [ 4.487255] systemd[1]: Stopped Create System Files and Directories.1115second # [ 4.487949] systemd[1]: Stopped target Local File Systems.1116test # [ 4.473952] systemd[1]: Starting rw-sysroot-nix-store.service...1117second # [ 4.491344] systemd[1]: Stopped target Preparation for Local File Systems.1118test # [ 4.475754] systemd[1]: systemd-sysctl.service: Deactivated successfully.1119second # [ 4.494442] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1120second # [ 4.495101] systemd[1]: Stopped Coldplug All udev Devices.1121test # [ 4.478893] systemd[1]: Stopped Apply Kernel Variables.1122second # [ 4.495599] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1123test # [ 4.481448] systemd[1]: systemd-modules-load.service: Deactivated successfully.1124second # [ 4.498263] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1125second # [ 4.498906] systemd[1]: Stopped Virtual Console Setup.1126second # [ 4.499345] systemd[1]: systemd-udevd.service: Deactivated successfully.1127test # [ 4.484166] systemd[1]: Stopped Load Kernel Modules.1128second # [ 4.500493] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1129second # [ 4.502425] systemd[1]: initrd-cleanup.service: Deactivated successfully.1130second # [ 4.503573] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1131second # [ 4.507785] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1132second # [ 4.508518] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1133test # [ 4.492286] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1134second # [ 4.509038] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1135second # [ 4.509614] systemd[1]: Finished rw-sysroot-nix-store.service.1136second # [ 4.510084] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1137second # [ 4.510648] systemd[1]: Closed udev Control Socket.1138test # [ 4.494292] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1139second # [ 4.511056] systemd[1]: Starting Cleanup udev Database...1140second # [ 4.511492] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1141test # [ 4.495235] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1142second # [ 4.512665] systemd[1]: Stopped Create Static Device Nodes in /dev.1143second # [ 4.513235] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1144second # [ 4.513881] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1145test # [ 4.497537] systemd[1]: Stopped Create System Files and Directories.1146second # [ 4.514465] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1147test # [ 4.498568] systemd[1]: Stopped target Local File Systems.1148second # [ 4.515022] systemd[1]: Stopped Create List of Static Device Nodes.1149test # [ 4.499146] systemd[1]: Stopped target Preparation for Local File Systems.1150test # [ 4.499817] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1151test # [ 4.507593] systemd[1]: Stopped Coldplug All udev Devices.1152test # [ 4.508573] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1153test # [ 4.509362] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1154test # [ 4.510121] systemd[1]: Stopped Virtual Console Setup.1155test # [ 4.510672] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1156test # [ 4.511407] systemd[1]: Finished rw-sysroot-nix-store.service.1157test # [ 4.512778] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1158test # [ 4.513626] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1159test # [ 4.514281] systemd[1]: systemd-udevd.service: Deactivated successfully.1160test # [ 4.514978] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1161test # [ 4.515717] systemd[1]: systemd-udevd.service: Consumed 1.010s CPU time over 3.105s wall clock time, 22.7M memory peak.1162test # [ 4.516903] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1163test # [ 4.517666] systemd[1]: Closed udev Control Socket.1164test # [ 4.518205] systemd[1]: Starting Cleanup udev Database...1165test # [ 4.518785] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1166second # [ 4.535234] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1167test # [ 4.519572] systemd[1]: Stopped Create Static Device Nodes in /dev.1168second # [ 4.537610] systemd[1]: Finished Cleanup udev Database.1169second # [ 4.538093] systemd[1]: Reached target Switch Root.1170second # [ 4.538808] systemd[1]: Starting NixOS Activation...1171test # [ 4.524173] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1172test # [ 4.525169] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1173test # [ 4.528064] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1174test # [ 4.529019] systemd[1]: Stopped Create List of Static Device Nodes.1175test # [ 4.542666] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1176test # [ 4.543390] systemd[1]: Finished Cleanup udev Database.1177test # [ 4.548983] systemd[1]: Reached target Switch Root.1178test # [ 4.549472] systemd[1]: Starting NixOS Activation...1179second # [ 4.737766] initrd-nixos-activation-start[180]: booting system configuration /nix/store/349syfvjs07q78ia0yfmnyngrygackn1-nixos-system-second-test1180test # [ 4.740380] initrd-nixos-activation-start[181]: booting system configuration /nix/store/xb3s2lxich4ryndlcidlr3b077mq6kda-nixos-system-test-test1181second # [ 4.808974] initrd-nixos-activation-start[180]: running activation script...1182test # [ 4.810313] initrd-nixos-activation-start[181]: running activation script...1183second # [ 5.299880] initrd-nixos-activation-start[203]: setting up /etc...1184test # [ 5.288778] initrd-nixos-activation-start[204]: setting up /etc...1185second # [ 5.577818] initrd-nixos-activation-start[203]: setting up age secrets...1186test # [ 5.563278] initrd-nixos-activation-start[204]: setting up age secrets...1187second # [ 5.802655] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1188second # [ 5.804133] systemd[1]: Finished NixOS Activation.1189second # [ 5.805099] systemd[1]: Starting Switch Root...1190second # [ 5.824113] systemd[1]: Switching root.1191second # [ 5.883504] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1192test # [ 5.875406] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1193test # [ 5.877188] systemd[1]: Finished NixOS Activation.1194test # [ 5.880145] systemd[1]: Starting Switch Root...1195test # [ 5.891978] systemd[1]: Switching root.1196test # [ 5.946822] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1197second # [ 6.450626] systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1198second # [ 6.450715] systemd[1]: Detected virtualization qemu.1199second # [ 6.450761] systemd[1]: Detected architecture arm64.1200second # [ 6.450874] systemd[1]: Detected first boot.1201second # [ 6.466664] systemd[1]: Initializing machine ID from random generator.1202test # [ 6.476094] systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1203test # [ 6.476185] systemd[1]: Detected virtualization qemu.1204test # [ 6.476232] systemd[1]: Detected architecture arm64.1205test # [ 6.476342] systemd[1]: Detected first boot.1206test # [ 6.491757] systemd[1]: Initializing machine ID from random generator.1207second # [ 6.754806] systemd[1]: bpf-restrict-fs: LSM BPF program attached1208test # [ 6.782073] systemd[1]: bpf-restrict-fs: LSM BPF program attached1209second # [ 6.944705] systemd[1]: Applying preset policy.1210test # [ 6.982791] systemd[1]: Applying preset policy.1211second # [ 7.477362] systemd[1]: Populated /etc with preset unit settings.1212test # [ 7.501314] systemd[1]: Populated /etc with preset unit settings.1213second # [ 7.983431] systemd[1]: initrd-switch-root.service: Deactivated successfully.1214second # [ 7.983712] systemd[1]: Stopped initrd-switch-root.service.1215second # [ 7.984679] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1216second # [ 7.985319] systemd[1]: Created slice Slice /system/getty.1217second # [ 7.985772] systemd[1]: Created slice User and Session Slice.1218second # [ 7.989823] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1219second # [ 7.991671] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1220second # [ 7.992171] systemd[1]: Expecting device /dev/hvc0...1221second # [ 7.992696] systemd[1]: Expecting device /dev/ttyAMA0...1222second # [ 7.993240] systemd[1]: Reached target Local Encrypted Volumes.1223second # [ 7.993783] systemd[1]: Stopped target initrd-fs.target.1224second # [ 7.997094] systemd[1]: Stopped target initrd-root-fs.target.1225second # [ 7.997565] systemd[1]: Stopped target initrd-switch-root.target.1226second # [ 7.998099] systemd[1]: Reached target Virtual Machines and Containers.1227second # [ 8.000616] systemd[1]: Reached target Path Units.1228second # [ 8.001096] systemd[1]: Reached target Remote File Systems.1229second # [ 8.001596] systemd[1]: Reached target Slice Units.1230second # [ 8.002097] systemd[1]: Reached target Swaps.1231second # [ 8.007928] systemd[1]: Listening on Process Core Dump Socket.1232second # [ 8.011594] systemd[1]: Listening on Credential Encryption/Decryption.1233second # [ 8.016217] systemd[1]: Starting Journal Log Access Socket...1234second # [ 8.017221] systemd[1]: Listening on Journal Audit Socket.1235second # [ 8.017815] systemd[1]: Listening on Network Management Metrics Varlink Socket.1236second # [ 8.020553] systemd[1]: Listening on Network Management Netlink Socket.1237second # [ 8.021219] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1238second # [ 8.021707] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1239second # [ 8.025311] systemd[1]: Listening on udev Control Socket.1240second # [ 8.028664] systemd[1]: Mounting Huge Pages File System...1241second # [ 8.032156] systemd[1]: Mounting POSIX Message Queue File System...1242second # [ 8.035414] systemd[1]: Mounting Kernel Debug File System...1243second # [ 8.040361] systemd[1]: Mounting Kernel Trace File System...1244second # [ 8.048374] systemd[1]: Starting Create List of Static Device Nodes...1245test # [ 8.031028] systemd[1]: initrd-switch-root.service: Deactivated successfully.1246test # [ 8.031305] systemd[1]: Stopped initrd-switch-root.service.1247test # [ 8.032341] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1248test # [ 8.032950] systemd[1]: Created slice Slice /system/getty.1249test # [ 8.036468] systemd[1]: Created slice User and Session Slice.1250test # [ 8.036634] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1251test # [ 8.037190] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1252test # [ 8.039784] systemd[1]: Expecting device /dev/hvc0...1253second # [ 8.057249] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1254test # [ 8.040255] systemd[1]: Expecting device /dev/ttyAMA0...1255test # [ 8.040820] systemd[1]: Reached target Local Encrypted Volumes.1256second # [ 8.057825] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1257test # [ 8.043094] systemd[1]: Stopped target initrd-fs.target.1258test # [ 8.043571] systemd[1]: Stopped target initrd-root-fs.target.1259test # [ 8.044096] systemd[1]: Stopped target initrd-switch-root.target.1260test # [ 8.044624] systemd[1]: Reached target Virtual Machines and Containers.1261test # [ 8.045150] systemd[1]: Reached target Path Units.1262test # [ 8.048389] systemd[1]: Reached target Remote File Systems.1263test # [ 8.048855] systemd[1]: Reached target Slice Units.1264test # [ 8.050727] systemd[1]: Reached target Swaps.1265test # [ 8.055223] systemd[1]: Listening on Process Core Dump Socket.1266second # [ 8.070849] systemd[1]: Mounting Kernel Configuration File System...1267second # [ 8.071471] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1268second # [ 8.072063] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1269test # [ 8.058966] systemd[1]: Listening on Credential Encryption/Decryption.1270test # [ 8.063687] systemd[1]: Starting Journal Log Access Socket...1271test # [ 8.064718] systemd[1]: Listening on Journal Audit Socket.1272test # [ 8.066517] systemd[1]: Listening on Network Management Metrics Varlink Socket.1273test # [ 8.067034] systemd[1]: Listening on Network Management Netlink Socket.1274test # [ 8.067497] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1275test # [ 8.067772] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1276test # [ 8.068125] systemd[1]: Listening on udev Control Socket.1277second # [ 8.086997] systemd[1]: Starting Load Kernel Module fuse...1278second # [ 8.087953] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671279test # [ 8.075274] systemd[1]: Mounting Huge Pages File System...1280test # [ 8.078684] systemd[1]: Mounting POSIX Message Queue File System...1281test # [ 8.082551] systemd[1]: Mounting Kernel Debug File System...1282test # [ 8.090110] systemd[1]: Mounting Kernel Trace File System...1283test # [ 8.100505] systemd[1]: Starting Create List of Static Device Nodes...1284second # [ 8.125297] systemd[1]: Starting Journal Service...1285test # [ 8.110471] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1286test # [ 8.110932] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1287second # [ 8.137468] systemd[1]: Starting Load Kernel Modules...1288test # [ 8.117399] systemd[1]: Mounting Kernel Configuration File System...1289test # [ 8.118199] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1290test # [ 8.118913] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1291test # [ 8.127889] systemd[1]: Starting Load Kernel Module fuse...1292test # [ 8.129108] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671293second # [ 8.157714] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1294test # [ 8.146878] systemd[1]: Starting Journal Service...1295second # [ 8.174214] systemd[1]: Starting Remount Root and Kernel File Systems...1296second # [ 8.178774] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1297second # [ 8.200260] systemd[1]: Starting Coldplug All udev Devices...1298test # [ 8.186081] systemd[1]: Starting Load Kernel Modules...1299second # [ 8.218634] systemd[1]: Listening on Journal Log Access Socket.1300second # [ 8.226752] systemd[1]: Mounted Huge Pages File System.1301second # [ 8.227278] systemd[1]: Mounted POSIX Message Queue File System.1302second # [ 8.227652] systemd[1]: Mounted Kernel Debug File System.1303second # [ 8.227995] systemd[1]: Mounted Kernel Trace File System.1304second # [ 8.240513] systemd[1]: Finished Create List of Static Device Nodes.1305test # [ 8.222312] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1306second # [ 8.245681] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1307second # [ 8.250710] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1308second # [ 8.258101] systemd[1]: Mounted Kernel Configuration File System.1309test # [ 8.246481] fuse: init (API version 7.45)1310test # [ 8.248559] systemd[1]: Starting Remount Root and Kernel File Systems...1311test # [ 8.250336] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1312second # [ 8.271165] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1313second # [ 8.273633] systemd[1]: Finished Load Kernel Modules.1314second # [ 8.282425] fuse: init (API version 7.45)1315second # [ 8.291269] systemd[1]: Starting Firewall...1316test # [ 8.277477] systemd[1]: Starting Coldplug All udev Devices...1317second # [ 8.298754] systemd-journald[303]: Collecting audit messages is enabled.1318second # [ 8.306704] systemd[1]: Queued start job for default target Multi-User System.1319second # [ 8.309715] systemd[1]: systemd-journald.service: Deactivated successfully.1320second # [ 8.314279] EXT4-fs (vda): re-mounted dd42245c-1cbe-4e66-86ca-8c292184439a.1321test # [ 8.302636] systemd[1]: Listening on Journal Log Access Socket.1322second # [ 8.319475] systemd[1]: Starting Apply Kernel Variables...1323test # [ 8.304569] systemd[1]: Mounted Huge Pages File System.1324second # [ 8.318397] systemd-modules-load[304]: Module 'atkbd' is built in1325test # [ 8.307444] systemd[1]: Mounted POSIX Message Queue File System.1326second # [ 8.324286] systemd-modules-load[304]: Module 'ctr' is built in1327second # [ 8.326026] systemd-modules-load[304]: Module 'loop' is built in1328second # [ 8.331487] systemd[1]: Started Journal Service.1329test # [ 8.311808] systemd[1]: Mounted Kernel Debug File System.1330test # [ 8.320335] systemd[1]: Mounted Kernel Trace File System.1331test # [ 8.321220] systemd[1]: Finished Create List of Static Device Nodes.1332second # [ 8.333824] systemd[1]: modprobe@fuse.service: Deactivated successfully.1333test # [ 8.323571] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1334second # [ 8.337474] systemd[1]: Finished Load Kernel Module fuse.1335test # [ 8.324535] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1336test # [ 8.325249] systemd[1]: Mounted Kernel Configuration File System.1337test # [ 8.328703] systemd[1]: modprobe@fuse.service: Deactivated successfully.1338second # [ 8.345476] systemd[1]: Finished Remount Root and Kernel File Systems.1339test # [ 8.334310] systemd[1]: Finished Load Kernel Module fuse.1340test # [ 8.344561] systemd[1]: Mounting FUSE Control File System...1341second # [ 8.356491] systemd-oomd[305]: No swap; memory pressure usage will be degraded1342test # [ 8.354214] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1343test # [ 8.364462] systemd[1]: Finished Load Kernel Modules.1344second # [ 8.376591] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1345test # [ 8.382901] systemd[1]: Queued start job for default target Multi-User System.1346test # [ 8.381456] systemd-journald[325]: Collecting audit messages is enabled.1347test # [ 8.389606] systemd[1]: Starting Firewall...1348test # [ 8.389579] systemd[1]: systemd-journald.service: Deactivated successfully.1349second # [ 8.406175] systemd[1]: Mounting FUSE Control File System...1350second # [ 8.410743] systemd[1]: Starting Flush Journal to Persistent Storage...1351second # [ 8.412913] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1352test # [ 8.397933] systemd-modules-load[326]: Module 'atkbd' is built in1353test # [ 8.398814] systemd-modules-load[326]: Module 'ctr' is built in1354test # [ 8.399404] systemd-modules-load[326]: Module 'loop' is built in1355test # [ 8.402169] EXT4-fs (vda): re-mounted 7ac41099-d678-49a1-9597-6347f986492f.1356test # [ 8.402976] systemd-oomd[327]: No swap; memory pressure usage will be degraded1357second # [ 8.419649] systemd[1]: Starting Load/Save OS Random Seed...1358test # [ 8.407175] systemd[1]: Starting Apply Kernel Variables...1359second # [ 8.428394] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1360test # [ 8.421883] systemd[1]: Started Journal Service.1361test # [ 8.425053] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1362test # [ 8.435715] systemd[1]: Finished Remount Root and Kernel File Systems.1363second # [ 8.486162] systemd[1]: Finished Apply Kernel Variables.1364second # [ 8.500737] systemd[1]: Starting Network Name Resolution...1365test # [ 8.486025] systemd[1]: Starting Flush Journal to Persistent Storage...1366test # [ 8.486853] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1367second # [ 8.508634] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1368test # [ 8.498770] systemd[1]: Starting Load/Save OS Random Seed...1369test # [ 8.499719] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1370test # [ 8.501175] systemd[1]: Mounted FUSE Control File System.1371second # [ 8.518328] systemd[1]: Starting Create Static Device Nodes in /dev...1372second # [ 8.536942] systemd-journald[303]: Received client request to flush runtime journal.1373test # [ 8.542106] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1374test # [ 8.555236] systemd[1]: Starting Create Static Device Nodes in /dev...1375test # [ 8.561280] systemd[1]: Finished Apply Kernel Variables.1376test # [ 8.584370] systemd[1]: Starting Network Name Resolution...1377test # [ 8.592458] systemd-journald[325]: Received client request to flush runtime journal.1378second # [ 8.641111] systemd[1]: Mounted FUSE Control File System.1379second # [ 8.648486] systemd[1]: Finished Load/Save OS Random Seed.1380second # [ 8.652831] systemd[1]: Reached target First Boot Complete.1381second # [ 8.655335] systemd[1]: Finished Flush Journal to Persistent Storage.1382test # [ 8.661349] systemd[1]: Finished Load/Save OS Random Seed.1383second # [ 8.680110] systemd[1]: Finished Create Static Device Nodes in /dev.1384second # [ 8.680798] systemd[1]: Reached target Preparation for Local File Systems.1385second # [ 8.682724] systemd[1]: Starting Rule-based Manager for Device Events and Files...1386test # [ 8.668128] systemd[1]: Reached target First Boot Complete.1387test # [ 8.673132] systemd[1]: Finished Flush Journal to Persistent Storage.1388second # [ 8.734045] systemd-resolved[331]: Positive Trust Anchors:1389test # [ 8.719426] systemd[1]: Finished Create Static Device Nodes in /dev.1390test # [ 8.720484] systemd[1]: Reached target Preparation for Local File Systems.1391second # [ 8.735509] systemd-resolved[331]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1392second # [ 8.738683] systemd-resolved[331]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161393test # [ 8.724866] systemd[1]: Starting Rule-based Manager for Device Events and Files...1394second # [ 8.742123] 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 test1395test # [ 8.758958] systemd-resolved[357]: Positive Trust Anchors:1396test # [ 8.764356] systemd-resolved[357]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1397test # [ 8.765719] systemd-resolved[357]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161398test # [ 8.766966] systemd-resolved[357]: 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 test1399second # [ 8.808418] systemd-udevd[346]: Using default interface naming scheme 'v260'.1400test # [ 8.867349] systemd-udevd[365]: Using default interface naming scheme 'v260'.1401second # [ 8.957728] systemd-resolved[331]: Using system hostname 'second'.1402second # [ 8.966235] systemd[1]: Started Network Name Resolution.1403second # [ 8.982282] systemd[1]: Mounting /run/wrappers...1404test # [ 9.008309] systemd-resolved[357]: Using system hostname 'test'.1405second # [ 9.033040] systemd[1]: Mounted /run/wrappers.1406second # [ 9.033648] systemd[1]: Reached target Local File Systems.1407test # [ 9.018881] systemd[1]: Started Network Name Resolution.1408second # [ 9.038063] systemd[1]: Listening on Boot Loader Control Service Socket.1409second # [ 9.041791] systemd[1]: Starting register-nix-paths.service...1410test # [ 9.029225] systemd[1]: Mounting /run/wrappers...1411second # [ 9.046253] systemd[1]: Starting Create SUID/SGID Wrappers...1412second # [ 9.052488] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1413second # [ 9.055989] systemd[1]: Starting Save Transient machine-id to Disk...1414second # [ 9.062397] systemd[1]: Starting Create System Files and Directories...1415test # [ 9.090316] systemd[1]: Mounted /run/wrappers.1416test # [ 9.091069] systemd[1]: Reached target Local File Systems.1417test # [ 9.094801] systemd[1]: Listening on Boot Loader Control Service Socket.1418test # [ 9.107128] systemd[1]: Starting register-nix-paths.service...1419second # [ 9.123887] systemd[1]: Started Rule-based Manager for Device Events and Files.1420test # [ 9.110630] systemd[1]: Starting Create SUID/SGID Wrappers...1421test # [ 9.112199] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1422test # [ 9.116517] systemd[1]: Starting Save Transient machine-id to Disk...1423test # [ 9.140644] systemd[1]: Starting Create System Files and Directories...1424test # [ 9.183189] systemd[1]: Started Rule-based Manager for Device Events and Files.1425second # [ 9.209936] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1426second # [ 9.213805] systemd[1]: Finished Save Transient machine-id to Disk.1427second # [ 9.292348] systemd[1]: Finished Create System Files and Directories.1428test # [ 9.284141] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1429test # [ 9.288552] systemd[1]: Finished Save Transient machine-id to Disk.1430second # [ 9.316331] systemd[1]: Starting Rebuild Journal Catalog...1431second # [ 9.319752] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1432test # [ 9.352108] systemd[1]: Finished Create System Files and Directories.1433test # [ 9.365733] systemd[1]: Starting Rebuild Journal Catalog...1434test # [ 9.376754] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1435second # [ 9.460160] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1436test # [ 9.489693] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1437second # [ 9.532389] systemd[1]: Finished Rebuild Journal Catalog.1438second # [ 9.536675] systemd[1]: Finished Coldplug All udev Devices.1439second # [ 9.543287] systemd[1]: Starting Update is Completed...1440test # [ 9.569889] systemd[1]: Finished Rebuild Journal Catalog.1441test # [ 9.585376] systemd[1]: Starting Update is Completed...1442second # [ 9.604590] systemd[1]: Finished Update is Completed.1443test # [ 9.621332] systemd[1]: Finished Coldplug All udev Devices.1444test # [ 9.664049] systemd[1]: Finished Update is Completed.1445second # [ 9.708194] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1446test # [ 9.769879] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1447second # [ 9.969477] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1448second # [ 10.015800] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1449test # [ 10.037344] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1450test # [ 10.112481] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1451second # [ 10.131074] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1452second # [ 10.131871] systemd[1]: Finished Create SUID/SGID Wrappers.1453test # [ 10.207485] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1454test # [ 10.209119] systemd[1]: Finished Create SUID/SGID Wrappers.1455second # [ 10.292109] systemd[1]: Finished Firewall.1456second # [ 10.292687] systemd[1]: Reached target Preparation for Network.1457second # [ 10.296450] systemd[1]: Starting Network Management...1458second # [ 10.348308] systemd[1]: Finished register-nix-paths.service.1459second # [ 10.350707] systemd[1]: Reached target System Initialization.1460second # [ 10.352745] systemd[1]: Started Discard unused filesystem blocks once a week.1461second # [ 10.358150] systemd[1]: Started Daily Cleanup of Temporary Directories.1462second # [ 10.358720] systemd[1]: Reached target Timer Units.1463second # [ 10.359147] systemd[1]: Listening on D-Bus System Message Bus Socket.1464second # [ 10.359660] systemd[1]: Listening on Nix Daemon Socket.1465second # [ 10.368077] systemd[1]: Listening on Authorization Manager Agent Helper.1466second # [ 10.368684] systemd[1]: Listening on Hostname Service Socket.1467second # [ 10.369192] systemd[1]: Reached target Socket Units.1468second # [ 10.373557] systemd[1]: Starting D-Bus System Message Bus...1469second # [ 10.376973] (udev-worker)[385]: Network interface NamePolicy= disabled on kernel command line.1470second # [ 10.385379] (udev-worker)[381]: Network interface NamePolicy= disabled on kernel command line.1471test # [ 10.388255] systemd[1]: Finished Firewall.1472test # [ 10.388782] systemd[1]: Reached target Preparation for Network.1473test # [ 10.392991] systemd[1]: Starting Network Management...1474test # [ 10.427624] systemd[1]: Finished register-nix-paths.service.1475test # [ 10.431013] systemd[1]: Reached target System Initialization.1476test # [ 10.434610] systemd[1]: Started Discard unused filesystem blocks once a week.1477test # [ 10.437068] systemd[1]: Started Daily Cleanup of Temporary Directories.1478test # [ 10.439787] systemd[1]: Reached target Timer Units.1479test # [ 10.443360] systemd[1]: Listening on D-Bus System Message Bus Socket.1480test # [ 10.449331] systemd[1]: Listening on Nix Daemon Socket.1481test # [ 10.449802] systemd[1]: Listening on Authorization Manager Agent Helper.1482test # [ 10.450341] systemd[1]: Listening on Hostname Service Socket.1483test # [ 10.450821] systemd[1]: Reached target Socket Units.1484test # [ 10.455281] systemd[1]: Starting D-Bus System Message Bus...1485test # [ 10.472906] (udev-worker)[401]: Network interface NamePolicy= disabled on kernel command line.1486test # [ 10.475214] (udev-worker)[407]: Network interface NamePolicy= disabled on kernel command line.1487second # [ 10.532883] cfg80211: Loading compiled-in X.509 certificates for regulatory database1488second # [ 10.570489] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1489second # [ 10.571007] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1490second # [ 10.576668] dbus-broker-launch[538]: Looking up NSS user entry for 'systemd-timesync'...1491second # [ 10.593270] mousedev: PS/2 mouse device common for all mice1492second # [ 10.596379] dbus-broker-launch[538]: NSS returned no entry for 'systemd-timesync'1493second # [ 10.597657] dbus-broker-launch[538]: Invalid user-name in /nix/store/pdfjlavvs9rx4znydk7cl6pjkvc2q5sx-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1494second # [ 10.631055] 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.1495second # [ 10.636580] 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.1496second # [ 10.640650] systemd-networkd[535]: lo: Link UP1497second # [ 10.643290] systemd-networkd[535]: lo: Gained carrier1498test # [ 10.632051] cfg80211: Loading compiled-in X.509 certificates for regulatory database1499second # [ 10.648271] systemd[1]: Started Network Management.1500second # [ 10.652165] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1501second # [ 10.656365] systemd-networkd[535]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1502second # [ 10.661838] systemd-networkd[535]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1503second # [ 10.667092] systemd-networkd[535]: eth1: Link UP1504second # [ 10.669698] systemd-networkd[535]: eth1: Gained carrier1505second # [ 10.672109] systemd-networkd[535]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1506test # [ 10.657171] dbus-broker-launch[560]: Looking up NSS user entry for 'systemd-timesync'...1507second # [ 10.675642] dbus-broker-launch[538]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1508second # [ 10.681240] dbus-broker-launch[538]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1509test # [ 10.670709] mousedev: PS/2 mouse device common for all mice1510test # [ 10.672264] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1511test # [ 10.672779] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1512second # [ 10.685883] dbus-broker-launch[538]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/sx40vhhwgyvayv82kxrdfsb24z1wvcrg-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1513second # [ 10.693533] dbus-broker-launch[538]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/0qin0mxlbmkcw8jq0dql0shhcfa41crl-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1514test # [ 10.679384] dbus-broker-launch[560]: NSS returned no entry for 'systemd-timesync'1515test # [ 10.680405] dbus-broker-launch[560]: Invalid user-name in /nix/store/pdfjlavvs9rx4znydk7cl6pjkvc2q5sx-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1516second # [ 10.701203] systemd[1]: Started D-Bus System Message Bus.1517second # [ 10.703432] systemd[1]: Reached target Basic System.1518second # [ 10.706886] systemd[1]: Starting Network Manager Script Dispatcher Service...1519second # [ 10.712561] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1520second # [ 10.720153] systemd[1]: Starting Network Manager...1521second # [ 10.746039] systemd[1]: Started backdoor.service.1522test # [ 10.732767] 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.1523test # [ 10.737684] 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.1524test # [ 10.745707] systemd-networkd[557]: lo: Link UP1525test # [ 10.746136] systemd-networkd[557]: lo: Gained carrier1526test # [ 10.749784] systemd[1]: Started Network Management.1527second # [ 10.769689] dbus-broker-launch[538]: Ready1528test # [ 10.752854] dbus-broker-launch[560]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1529second # [ 10.781475] systemd[1]: Starting Import lastlog data into lastlog2 database...1530test # [ 10.763084] dbus-broker-launch[560]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1531test # [ 10.774609] dbus-broker-launch[560]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/sx40vhhwgyvayv82kxrdfsb24z1wvcrg-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1532test # [ 10.784157] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1533test # [ 10.786973] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1534test # [ 10.792397] systemd-networkd[557]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1535second # [ 10.812117] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1536test # [ 10.796932] dbus-broker-launch[560]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/0qin0mxlbmkcw8jq0dql0shhcfa41crl-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1537test # [ 10.804139] systemd-networkd[557]: eth1: Link UP1538test # [ 10.807162] systemd[1]: Started D-Bus System Message Bus.1539test # [ 10.813886] systemd[1]: Reached target Basic System.1540test # [ 10.814367] systemd-networkd[557]: eth1: Gained carrier1541test # [ 10.814812] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1542test # [ 10.821113] systemd[1]: Starting Network Manager Script Dispatcher Service...1543test # [ 10.825073] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1544second # [ 10.845020] systemd[1]: Starting Post-Boot Actions...1545test # [ 10.831616] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1546second # [ 10.854964] systemd[1]: Started Reset console on configuration changes.1547second # [ 10.863141] gihlbcfxrzdmnrhdz3bb45n3f4b0k7kd-wifi-secrets[550]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1548second # [ 10.877134] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1549test # [ 10.861266] dbus-broker-launch[560]: Ready1550test # [ 10.864341] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1551test # [ 10.873844] systemd[1]: Starting Network Manager...1552test # [ 10.889247] systemd[1]: Started backdoor.service.1553second # [ 10.905113] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1554second # [ 10.913842] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1555second # connecting to host...1556test # [ 10.928390] systemd[1]: Starting Import lastlog data into lastlog2 database...1557test # [ 10.959589] gihlbcfxrzdmnrhdz3bb45n3f4b0k7kd-wifi-secrets[571]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1558test # [ 10.967842] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1559test # [ 10.974374] 4bb5snallvknp7ll9gml95rlfxqybb0y-wifi-secrets[573]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1560second # [ 11.013893] systemd[1]: Started Name Service Cache Daemon (nsncd).1561test # [ 11.001176] systemd[1]: Starting Post-Boot Actions...1562second # [ 11.020624] nsncd[554]: Oct 03 15:03:14.861 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1563second # [ 11.028218] systemd[1]: Reached target Host and Network Name Lookups.1564second # [ 11.033390] systemd[1]: Reached target User and Group Name Lookups.1565second # [ 11.037994] systemd[1]: Starting User Login Management...1566second # [ 11.040845] systemd[1]: Finished Post-Boot Actions.1567test # [ 11.038729] 9zw00hrl9ri87shidqszb96sxar6g9fp-wifi-secrets[574]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1568test # [ 11.046080] systemd[1]: Started Reset console on configuration changes.1569second # [ 11.067849] systemd[1]: Finished Import lastlog data into lastlog2 database.1570test # [ 11.056999] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1571test # [ 11.065353] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1572test # [ 11.085798] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1573second # [ 11.114615] systemd[1]: Started Network Manager Script Dispatcher Service.1574test # [ 11.100439] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1575test # [ 11.106285] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1576test # [ 11.113921] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1577test # connecting to host...1578test # [ 11.122847] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1579test # [ 11.142429] systemd[1]: Finished Post-Boot Actions.1580test: Guest shell says: b'Spawning backdoor root shell...\n'1581test: connected to guest root shell1582test: (connecting took 11.46 seconds)1583test: (finished: waiting for the VM to finish booting, in 11.46 seconds)1584second # [ 11.196967] systemd-logind[581]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1585second # [ 11.197953] systemd-logind[581]: Watching system buttons on /dev/input/event0 (gpio-keys)1586test # [ 11.211006] systemd[1]: Started Name Service Cache Daemon (nsncd).1587second # [ 11.198542] systemd-logind[581]: New seat seat0.1588test # [ 11.214930] systemd[1]: Reached target Host and Network Name Lookups.1589second # [ 11.204238] systemd[1]: Starting Hostname Service...1590test # [ 11.218236] systemd[1]: Reached target User and Group Name Lookups.1591second # [ 11.204687] systemd[1]: Started User Login Management.1592test # [ 11.219220] nsncd[579]: Oct 03 15:03:15.065 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1593second # [ 11.211568] systemd[1]: Starting linger-users.service...1594test # [ 11.234765] systemd[1]: Starting User Login Management...1595test # [ 11.259627] systemd[1]: Started Network Manager Script Dispatcher Service.1596test # [ 11.270966] systemd[1]: Finished Import lastlog data into lastlog2 database.1597second # [ 11.288327] systemd[1]: linger-users.service: Deactivated successfully.1598second # [ 11.293218] systemd[1]: Finished linger-users.service.1599second # [ 11.336088] systemd[1]: Started Hostname Service.1600test # [ 11.394135] systemd-logind[620]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1601test # [ 11.395148] systemd-logind[620]: Watching system buttons on /dev/input/event0 (gpio-keys)1602test # [ 11.395765] systemd-logind[620]: New seat seat0.1603test # [ 11.400345] systemd[1]: Starting Hostname Service...1604test # [ 11.402740] systemd[1]: Started User Login Management.1605test # [ 11.408879] systemd[1]: Starting linger-users.service...1606second # [ 11.428964] systemd-networkd[535]: eth0: Link UP1607second # [ 11.429428] systemd-networkd[535]: eth0: Gained carrier1608second # [ 11.449435] systemd[1]: Started Network Manager.1609second # [ 11.451469] systemd[1]: Reached target Network.1610second # [ 11.455301] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1611second # [ 11.459341] systemd[1]: Starting Permit User Sessions...1612test # [ 11.480353] systemd[1]: linger-users.service: Deactivated successfully.1613test # [ 11.481786] systemd[1]: Finished linger-users.service.1614test # [ 11.529912] systemd[1]: Started Hostname Service.1615second # [ 11.621727] systemd[1]: Finished Permit User Sessions.1616second # [ 11.631945] systemd[1]: Started Getty on tty1.1617second # [ 11.636858] systemd[1]: Reached target Login Prompts.1618test # [ 11.621085] systemd-networkd[557]: eth0: Link UP1619test # [ 11.621563] systemd-networkd[557]: eth0: Gained carrier1620second # [ 11.651046] systemd-resolved[331]: eth0: Bus client set default route setting: yes1621second # [ 11.656259] systemd-resolved[331]: eth0: Bus client set DNS server list to: 10.0.2.31622test # [ 11.641712] systemd[1]: Started Network Manager.1623test # [ 11.644176] systemd[1]: Reached target Network.1624test # [ 11.649131] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1625test # [ 11.656671] systemd[1]: Starting Permit User Sessions...1626second # [ 11.763578] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1627second # [ 11.766015] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1628test # [ 11.808330] systemd[1]: Finished Permit User Sessions.1629test # [ 11.825517] systemd[1]: Started Getty on tty1.1630test # [ 11.826009] systemd[1]: Reached target Login Prompts.1631test # [ 11.870166] NetworkManager[575]: <warn> [1791039795.7113] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1632test # [ 11.873832] NetworkManager[575]: <warn> [1791039795.7176] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1633test # [ 11.888381] systemd-resolved[357]: eth0: Bus client set default route setting: yes1634test # [ 11.892111] systemd-resolved[357]: eth0: Bus client set DNS server list to: 10.0.2.31635second # [ 11.913606] systemd[1]: Reached target Multi-User System.1636second # [ 11.915090] systemd[1]: Startup finished in 743ms (kernel) + 5.239s (initrd) + 5.931s (userspace) = 11.914s.1637test # [ 11.938969] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1638test # [ 11.940593] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1639test # [ 11.969204] systemd-networkd[557]: eth1: Gained IPv6LL1640second # [ 12.098135] systemd-networkd[535]: eth1: Gained IPv6LL1641test # [ 12.082256] systemd[1]: Reached target Multi-User System.1642test # [ 12.084614] systemd[1]: Startup finished in 734ms (kernel) + 5.285s (initrd) + 6.063s (userspace) = 12.083s.1643second # [ 12.243127] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31644test: (finished: waiting for unit NetworkManager.service, in 12.70 seconds)1645test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1646second # [ 12.501639] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1647test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds)1648cleanup1649kill QemuMachine (pid 14)1650second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/76jq0mngg7m8zv5yjz1qgg0xspzi8x2w-python3-3.13.15/bin/python3.13)1651test # [ 12.537410] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31652kill QemuMachine (pid 15)1653test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/76jq0mngg7m8zv5yjz1qgg0xspzi8x2w-python3-3.13.15/bin/python3.13)1654(finished: cleanup, in 0.26 seconds)1655Traceback (most recent call last):1656 File "/nix/store/bx2f2mvw7byg1lw3sjx9ya00n556icn7-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in <module>1657 sys.exit(main())1658 ~~~~^^1659 File "/nix/store/bx2f2mvw7byg1lw3sjx9ya00n556icn7-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/__init__.py", line 183, in main1660 driver.run_tests()1661 ~~~~~~~~~~~~~~~~^^1662 File "/nix/store/bx2f2mvw7byg1lw3sjx9ya00n556icn7-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/driver.py", line 434, in run_tests1663 self.test_script()1664 ~~~~~~~~~~~~~~~~^^1665 File "/nix/store/bx2f2mvw7byg1lw3sjx9ya00n556icn7-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/driver.py", line 392, in test_script1666 exec(self.tests, symbols, None)1667 ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^1668 File "<string>", line 4, in <module>1669AssertionError: Expected mock password in connection file:1670[connection]1671autoconnect=true1672id=1673type=wifi16741675[wifi]1676mode=infrastructure1677ssid=16781679[wifi-security]1680key-mgmt=wpa-psk1681psk=1682