vm-test-run-wifi
checks.aarch64-linux.wifi
· build #628
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600 seconds5run the VM test script6additionally exposed symbols:7 second, test,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10start all VMs11second: starting vm12test: starting vm13test # Disk image does not exist, creating the virtualisation disk image...14test: QEMU running (pid 14)15test # Formatting '/build/vm-state-test/tmp.EyGI9xbrp7', fmt=raw size=107374182416second: QEMU running (pid 15)17test # mke2fs 1.47.4 (6-Mar-2025)18second # Disk image does not exist, creating the virtualisation disk image...19test # Discarding device blocks: 0/262144 done20second # Formatting '/build/vm-state-second/tmp.PiYs01Ra1Y', fmt=raw size=107374182421test # Creating filesystem with 262144 4k blocks and 65536 inodes22(finished: start all VMs, in 0.46 seconds)23test # Filesystem UUID: f4576233-d662-45db-918b-ffd034aeac8e24test: waiting for unit NetworkManager.service25test # Superblock backups stored on blocks:26second # mke2fs 1.47.4 (6-Mar-2025)27test # 32768, 98304, 163840, 22937628second # Discarding device blocks: 0/262144 done29test # 30second # Creating filesystem with 262144 4k blocks and 65536 inodes31test # Allocating group tables: 0/8 done32second # Filesystem UUID: 166f86c6-9a5c-46a9-aa50-25cbb6abaccc33test # Writing inode tables: 0/8 done34second # Superblock backups stored on blocks:35test # Creating journal (8192 blocks): done36second # 32768, 98304, 163840, 22937637test # Writing superblocks and filesystem accounting information: 0/8 done38second # 39test # 40second # Allocating group tables: 0/8 done41test # Virtualisation disk image created.42second # Writing inode tables: 0/8 done43test: waiting for the VM to finish booting44second # Creating journal (8192 blocks): done45second # Writing superblocks and filesystem accounting information: 0/8 done46second # 47second # Virtualisation disk image created.48test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]49test # [ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 7 15:23:00 UTC 202650test # [ 0.000000] KASLR enabled51test # [ 0.000000] random: crng init done52test # [ 0.000000] Machine model: linux,dummy-virt53test # [ 0.000000] efi: UEFI not found.54test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT55test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]56test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]57test # [ 0.000000] Zone ranges:58test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]59test # [ 0.000000] DMA32 empty60test # [ 0.000000] Normal empty61test # [ 0.000000] Device empty62test # [ 0.000000] Movable zone start for each node63test # [ 0.000000] Early memory node ranges64test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]65test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]66test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000067test # [ 0.000000] psci: probing for conduit method from DT.68test # [ 0.000000] psci: PSCIv1.3 detected in firmware.69test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs70test # [ 0.000000] psci: Trusted OS migration not required71test # [ 0.000000] psci: SMC Calling Convention v1.172test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)73test # [ 0.000000] percpu: Embedded 76 pages/cpu s186328 r8192 d116776 u31129674test # [ 0.000000] Detected PIPT I-cache on CPU075test # [ 0.000000] CPU features: detected: GICv3 CPU interface76test # [ 0.000000] CPU features: detected: Spectre-v477test # [ 0.000000] CPU features: detected: Spectre-BHB78test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR79test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)80test # [ 0.000000] CPU features: detected: ARM erratum 141804081test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion82test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing83test # [ 0.000000] alternatives: applying boot alternatives84test # [ 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/a7vf68qa1b2ch1i0nrvr7iib4hnf2b6z-nixos-system-test-test/init regInfo=/nix/store/1bpv2q8x289449905f9rgs1j2c5rkk5j-closure-info/registration console=ttyAMA0,115200n8 console=tty085test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/1bpv2q8x289449905f9rgs1j2c5rkk5j-closure-info/registration", will be passed to user space.86test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes87test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)88test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)89test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB90test # [ 0.000000] software IO TLB: area num 1.91test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)92test # [ 0.000000] Fallback order for Node 0: 093test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214494test # [ 0.000000] Policy zone: DMA95second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]96test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off97test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=198second # [ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 7 15:23:00 UTC 202699test # [ 0.000000] allocated 2097152 bytes of page_ext100second # [ 0.000000] KASLR enabled101second # [ 0.000000] random: crng init done102test # [ 0.000000] ftrace: allocating 74755 entries in 294 pages103second # [ 0.000000] Machine model: linux,dummy-virt104test # [ 0.000000] ftrace: allocated 294 pages with 4 groups105second # [ 0.000000] efi: UEFI not found.106second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT107test # [ 0.000000] rcu: Hierarchical RCU implementation.108test # [ 0.000000] rcu: RCU event tracing is enabled.109second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]110test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.111test # [ 0.000000] Trampoline variant of Tasks RCU enabled.112second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]113test # [ 0.000000] Rude variant of Tasks RCU enabled.114test # [ 0.000000] Tracing variant of Tasks RCU enabled.115test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.116test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1117second # [ 0.000000] Zone ranges:118second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]119second # [ 0.000000] DMA32 empty120second # [ 0.000000] Normal empty121second # [ 0.000000] Device empty122second # [ 0.000000] Movable zone start for each node123second # [ 0.000000] Early memory node ranges124test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.125second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]126second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]127test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.128second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000129second # [ 0.000000] psci: probing for conduit method from DT.130test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.131second # [ 0.000000] psci: PSCIv1.3 detected in firmware.132second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs133test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0134second # [ 0.000000] psci: Trusted OS migration not required135test # [ 0.000000] GICv3: 256 SPIs implemented136second # [ 0.000000] psci: SMC Calling Convention v1.1137test # [ 0.000000] GICv3: 0 Extended SPIs implemented138test # [ 0.000000] Root IRQ handler: gic_handle_irq139test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI140second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)141second # [ 0.000000] percpu: Embedded 76 pages/cpu s186328 r8192 d116776 u311296142test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0143test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000144second # [ 0.000000] Detected PIPT I-cache on CPU0145second # [ 0.000000] CPU features: detected: GICv3 CPU interface146test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]147second # [ 0.000000] CPU features: detected: Spectre-v4148second # [ 0.000000] CPU features: detected: Spectre-BHB149test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)150second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR151test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)152second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)153test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000154second # [ 0.000000] CPU features: detected: ARM erratum 1418040155test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000156second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion157test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.158second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing159second # [ 0.000000] alternatives: applying boot alternatives160test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns161test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040162test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).163test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns164second # [ 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/lkf23qhiidbwar4yqhywvx6n160njd44-nixos-system-second-test/init regInfo=/nix/store/r5ns4z1v3bdxar83fb8iklk4diad7qks-closure-info/registration console=ttyAMA0,115200n8 console=tty0165test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns166test # [ 0.000074] arm-pv: using stolen time PV167second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/r5ns4z1v3bdxar83fb8iklk4diad7qks-closure-info/registration", will be passed to user space.168test # [ 0.000460] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)169second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes170test # [ 0.000783] Console: colour dummy device 80x25171test # [ 0.000801] printk: legacy console [tty0] enabled172second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)173second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)174test # [ 0.001056] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)175test # [ 0.001061] pid_max: default: 32768 minimum: 301176second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB177second # [ 0.000000] software IO TLB: area num 1.178test # [ 0.001130] LSM: initializing lsm=capability,landlock,yama,bpf,ima179test # [ 0.001309] landlock: Up and running.180second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)181test # [ 0.001311] Yama: becoming mindful.182test # [ 0.001728] LSM support for eBPF active183second # [ 0.000000] Fallback order for Node 0: 0184second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144185test # [ 0.001892] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)186second # [ 0.000000] Policy zone: DMA187test # [ 0.001911] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)188second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off189test # [ 0.004133] cacheinfo: Unable to detect cache hierarchy for CPU 0190second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1191test # [ 0.004909] rcu: Hierarchical SRCU implementation.192second # [ 0.000000] allocated 2097152 bytes of page_ext193test # [ 0.004915] rcu: Max phase no-delay instances is 1000.194second # [ 0.000000] ftrace: allocating 74755 entries in 294 pages195test # [ 0.005890] fsl-mc MSI: its@8080000 domain created196second # [ 0.000000] ftrace: allocated 294 pages with 4 groups197test # [ 0.006057] EFI services will not be available.198second # [ 0.000000] rcu: Hierarchical RCU implementation.199test # [ 0.006189] smp: Bringing up secondary CPUs ...200second # [ 0.000000] rcu: RCU event tracing is enabled.201test # [ 0.006222] smp: Brought up 1 node, 1 CPU202test # [ 0.006225] SMP: Total of 1 processors activated.203second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.204test # [ 0.006226] CPU: All CPU(s) started at EL1205second # [ 0.000000] Trampoline variant of Tasks RCU enabled.206test # [ 0.006239] CPU features: detected: 32-bit EL0 Support207second # [ 0.000000] Rude variant of Tasks RCU enabled.208second # [ 0.000000] Tracing variant of Tasks RCU enabled.209test # [ 0.006242] CPU features: detected: Data cache clean to the PoU not required for I/D coherence210test # [ 0.006245] CPU features: detected: Common not Private translations211second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.212test # [ 0.006247] CPU features: detected: CRC32 instructions213second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1214test # [ 0.006248] CPU features: detected: Data cache clean to Point of Persistence215second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.216test # [ 0.006251] CPU features: detected: Enhanced Virtualization Traps217test # [ 0.006261] CPU features: detected: RCpc load-acquire (LDAPR)218second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.219test # [ 0.006263] CPU features: detected: LSE atomic instructions220second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.221test # [ 0.006265] CPU features: detected: Privileged Access Never222test # [ 0.006267] CPU features: detected: PMUv3223second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0224second # [ 0.000000] GICv3: 256 SPIs implemented225test # [ 0.006268] CPU features: detected: RAS Extension Support226second # [ 0.000000] GICv3: 0 Extended SPIs implemented227test # [ 0.006273] CPU features: detected: Speculative Store Bypassing Safe (SSBS)228second # [ 0.000000] Root IRQ handler: gic_handle_irq229test # [ 0.006295] alternatives: applying system-wide alternatives230second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI231second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0232test # [ 0.007921] CPU features: detected: Hardware dirty bit management on CPU0233second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000234second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]235test # [ 0.008240] Memory: 893856K/1048576K available (24320K kernel code, 7086K rwdata, 26340K rodata, 4736K init, 1103K bss, 113404K reserved, 32768K cma-reserved)236test # [ 0.008761] devtmpfs: initialized237second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)238test # [ 0.010357] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)239second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)240test # [ 0.010383] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).241second # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000242test # [ 0.010525] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL243second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000244test # [ 0.010528] 0 pages in range for non-PLT usage245test # [ 0.010529] 508336 pages in range for PLT usage246second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.247test # [ 0.010674] pinctrl core: initialized pinctrl subsystem248test # [ 0.011490] DMI not present or invalid.249second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns250test # [ 0.015725] NET: Registered PF_NETLINK/PF_ROUTE protocol family251second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040252test # [ 0.018509] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations253second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).254test # [ 0.018678] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations255second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns256test # [ 0.018849] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations257second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns258test # [ 0.018876] audit: initializing netlink subsys (disabled)259second # [ 0.000065] arm-pv: using stolen time PV260test # [ 0.019573] thermal_sys: Registered thermal governor 'fair_share'261second # [ 0.000450] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)262test # [ 0.019575] thermal_sys: Registered thermal governor 'bang_bang'263second # [ 0.000735] Console: colour dummy device 80x25264test # [ 0.019578] thermal_sys: Registered thermal governor 'step_wise'265second # [ 0.000753] printk: legacy console [tty0] enabled266test # [ 0.019581] thermal_sys: Registered thermal governor 'user_space'267second # [ 0.000970] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)268test # [ 0.019583] thermal_sys: Registered thermal governor 'power_allocator'269test # [ 0.019614] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1270test # [ 0.019629] cpuidle: using governor ladder271second # [ 0.000976] pid_max: default: 32768 minimum: 301272test # [ 0.019634] cpuidle: using governor menu273second # [ 0.001044] LSM: initializing lsm=capability,landlock,yama,bpf,ima274test # [ 0.019745] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.275second # [ 0.001204] landlock: Up and running.276second # [ 0.001206] Yama: becoming mindful.277test # [ 0.019768] ASID allocator initialised with 32768 entries278second # [ 0.001781] LSM support for eBPF active279test # [ 0.020500] Serial: AMBA PL011 UART driver280test # [ 0.023789] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1281test # [ 0.023949] printk: console [ttyAMA0] enabled282second # [ 0.001944] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)283second # [ 0.001963] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)284second # [ 0.003978] cacheinfo: Unable to detect cache hierarchy for CPU 0285second # [ 0.004920] rcu: Hierarchical SRCU implementation.286second # [ 0.004925] rcu: Max phase no-delay instances is 1000.287second # [ 0.005909] fsl-mc MSI: its@8080000 domain created288second # [ 0.006093] EFI services will not be available.289second # [ 0.006188] smp: Bringing up secondary CPUs ...290second # [ 0.006219] smp: Brought up 1 node, 1 CPU291second # [ 0.006222] SMP: Total of 1 processors activated.292second # [ 0.006224] CPU: All CPU(s) started at EL1293second # [ 0.006236] CPU features: detected: 32-bit EL0 Support294second # [ 0.006239] CPU features: detected: Data cache clean to the PoU not required for I/D coherence295second # [ 0.006242] CPU features: detected: Common not Private translations296second # [ 0.006244] CPU features: detected: CRC32 instructions297second # [ 0.006246] CPU features: detected: Data cache clean to Point of Persistence298second # [ 0.006249] CPU features: detected: Enhanced Virtualization Traps299second # [ 0.006260] CPU features: detected: RCpc load-acquire (LDAPR)300second # [ 0.006262] CPU features: detected: LSE atomic instructions301second # [ 0.006264] CPU features: detected: Privileged Access Never302second # [ 0.006266] CPU features: detected: PMUv3303test # [ 0.101395] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages304second # [ 0.006268] CPU features: detected: RAS Extension Support305test # [ 0.101426] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page306second # [ 0.006273] CPU features: detected: Speculative Store Bypassing Safe (SSBS)307second # [ 0.006297] alternatives: applying system-wide alternatives308test # [ 0.101434] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages309test # [ 0.101437] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page310second # [ 0.007802] CPU features: detected: Hardware dirty bit management on CPU0311test # [ 0.101440] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages312second # [ 0.008022] Memory: 893896K/1048576K available (24320K kernel code, 7086K rwdata, 26340K rodata, 4736K init, 1103K bss, 113416K reserved, 32768K cma-reserved)313second # [ 0.008548] devtmpfs: initialized314second # [ 0.010214] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)315second # [ 0.010238] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).316second # [ 0.010373] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL317second # [ 0.010376] 0 pages in range for non-PLT usage318second # [ 0.010377] 508336 pages in range for PLT usage319second # [ 0.010524] pinctrl core: initialized pinctrl subsystem320second # [ 0.011441] DMI not present or invalid.321second # [ 0.015851] NET: Registered PF_NETLINK/PF_ROUTE protocol family322test # [ 0.101444] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page323test # [ 0.101447] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages324test # [ 0.101450] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page325test # [ 0.107011] fbcon: Taking over console326test # [ 0.107037] ACPI: Interpreter disabled.327second # [ 0.018528] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations328test # [ 0.108389] iommu: Default domain type: Translated329second # [ 0.018688] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations330test # [ 0.108402] iommu: DMA domain TLB invalidation policy: strict mode331second # [ 0.018861] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations332second # [ 0.018899] audit: initializing netlink subsys (disabled)333test # [ 0.109846] SCSI subsystem initialized334second # [ 0.019600] thermal_sys: Registered thermal governor 'fair_share'335test # [ 0.110476] usbcore: registered new interface driver usbfs336test # [ 0.110515] usbcore: registered new interface driver hub337second # [ 0.019602] thermal_sys: Registered thermal governor 'bang_bang'338test # [ 0.110525] usbcore: registered new device driver usb339second # [ 0.019606] thermal_sys: Registered thermal governor 'step_wise'340second # [ 0.019607] thermal_sys: Registered thermal governor 'user_space'341test # [ 0.110792] pps_core: LinuxPPS API ver. 1 registered342second # [ 0.019609] thermal_sys: Registered thermal governor 'power_allocator'343test # [ 0.110798] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>344test # [ 0.110804] PTP clock support registered345test # [ 0.110834] EDAC MC: Ver: 3.0.0346second # [ 0.019649] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1347second # [ 0.019666] cpuidle: using governor ladder348second # [ 0.019671] cpuidle: using governor menu349second # [ 0.019791] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.350test # [ 0.114202] scmi_core: SCMI protocol bus registered351second # [ 0.019816] ASID allocator initialised with 32768 entries352second # [ 0.020571] Serial: AMBA PL011 UART driver353second # [ 0.024108] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1354second # [ 0.024291] printk: console [ttyAMA0] enabled355test # [ 0.121932] FPGA manager framework356test # [ 0.123087] vgaarb: loaded357test # [ 0.123587] clocksource: Switched to clocksource arch_sys_counter358second # [ 0.102415] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages359second # [ 0.102442] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page360second # [ 0.102450] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages361second # [ 0.102453] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page362second # [ 0.102457] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages363second # [ 0.102460] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page364second # [ 0.102463] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages365second # [ 0.102467] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page366second # [ 0.108132] fbcon: Taking over console367test # [ 0.129511] VFS: Disk quotas dquot_6.6.0368second # [ 0.108157] ACPI: Interpreter disabled.369second # [ 0.109576] iommu: Default domain type: Translated370test # [ 0.129561] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)371second # [ 0.109590] iommu: DMA domain TLB invalidation policy: strict mode372second # [ 0.110912] SCSI subsystem initialized373second # [ 0.111569] usbcore: registered new interface driver usbfs374second # [ 0.111616] usbcore: registered new interface driver hub375second # [ 0.111627] usbcore: registered new device driver usb376test # [ 0.132095] netfs: FS-Cache loaded377second # [ 0.111912] pps_core: LinuxPPS API ver. 1 registered378second # [ 0.111918] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>379test # [ 0.132247] pnp: PnP ACPI: disabled380test # [ 0.134846] NET: Registered PF_INET protocol family381test # [ 0.135071] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)382second # [ 0.111925] PTP clock support registered383second # [ 0.111975] EDAC MC: Ver: 3.0.0384second # [ 0.115161] scmi_core: SCMI protocol bus registered385second # [ 0.122907] FPGA manager framework386second # [ 0.124169] vgaarb: loaded387second # [ 0.124669] clocksource: Switched to clocksource arch_sys_counter388second # [ 0.130871] VFS: Disk quotas dquot_6.6.0389second # [ 0.130927] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)390second # [ 0.133538] netfs: FS-Cache loaded391second # [ 0.133695] pnp: PnP ACPI: disabled392second # [ 0.136429] NET: Registered PF_INET protocol family393second # [ 0.137182] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)394test # [ 0.161130] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)395test # [ 0.161164] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)396test # [ 0.161179] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)397test # [ 0.161227] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)398test # [ 0.161295] TCP: Hash tables configured (established 8192 bind 8192)399test # [ 0.161456] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)400test # [ 0.161522] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)401test # [ 0.161578] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)402test # [ 0.161745] NET: Registered PF_UNIX/PF_LOCAL protocol family403test # [ 0.161822] NET: Registered PF_XDP protocol family404test # [ 0.161849] PCI: CLS 0 bytes, default 64405test # [ 0.162094] Trying to unpack rootfs image as initramfs...406test # [ 0.174127] kvm [1]: HYP mode not available407second # [ 0.162598] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)408second # [ 0.162634] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)409second # [ 0.162651] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)410second # [ 0.162692] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)411second # [ 0.162761] TCP: Hash tables configured (established 8192 bind 8192)412second # [ 0.162957] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)413second # [ 0.162991] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)414second # [ 0.163017] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)415second # [ 0.163165] NET: Registered PF_UNIX/PF_LOCAL protocol family416second # [ 0.163215] NET: Registered PF_XDP protocol family417second # [ 0.163247] PCI: CLS 0 bytes, default 64418second # [ 0.163528] Trying to unpack rootfs image as initramfs...419second # [ 0.175124] kvm [1]: HYP mode not available420test # [ 0.248042] Initialise system trusted keyrings421test # [ 0.248617] workingset: timestamp_bits=42 max_order=18 bucket_order=0422test # [ 0.249763] squashfs: version 4.0 (2009/01/31) Phillip Lougher423test # [ 0.250314] 9p: Installing v9fs 9p2000 file system support424test # [ 0.269798] Key type asymmetric registered425test # [ 0.269832] Asymmetric key parser 'x509' registered426test # [ 0.269906] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)427second # [ 0.249123] Initialise system trusted keyrings428second # [ 0.249662] workingset: timestamp_bits=42 max_order=18 bucket_order=0429second # [ 0.250678] squashfs: version 4.0 (2009/01/31) Phillip Lougher430second # [ 0.251223] 9p: Installing v9fs 9p2000 file system support431test # [ 0.275732] io scheduler mq-deadline registered432test # [ 0.275756] io scheduler kyber registered433test # [ 0.278985] pl061_gpio 9030000.pl061: PL061 GPIO chip registered434test # [ 0.283634] ledtrig-cpu: registered to indicate activity on CPUs435test # [ 0.284088] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:436test # [ 0.284113] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000437test # [ 0.284129] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000438test # [ 0.284135] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000439test # [ 0.284174] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits440test # [ 0.284202] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]441test # [ 0.284297] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00442second # [ 0.274369] Key type asymmetric registered443test # [ 0.284322] pci_bus 0000:00: root bus resource [bus 00-ff]444second # [ 0.274404] Asymmetric key parser 'x509' registered445test # [ 0.284327] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]446second # [ 0.274477] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)447test # [ 0.284331] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]448second # [ 0.275936] io scheduler mq-deadline registered449test # [ 0.284335] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]450second # [ 0.275952] io scheduler kyber registered451test # [ 0.284427] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint452test # [ 0.284930] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint453test # [ 0.285055] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]454test # [ 0.285066] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]455test # [ 0.285088] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]456test # [ 0.285100] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]457test # [ 0.285407] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint458test # [ 0.285523] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]459test # [ 0.285535] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]460test # [ 0.285555] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]461test # [ 0.285845] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint462test # [ 0.285961] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]463test # [ 0.285972] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]464test # [ 0.285993] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]465test # [ 0.286283] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint466test # [ 0.286400] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]467test # [ 0.286413] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]468test # [ 0.286433] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]469test # [ 0.286728] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint470test # [ 0.286845] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]471test # [ 0.286857] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]472test # [ 0.286878] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]473test # [ 0.287166] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint474test # [ 0.287285] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]475test # [ 0.287297] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]476test # [ 0.287319] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]477second # [ 0.284898] pl061_gpio 9030000.pl061: PL061 GPIO chip registered478second # [ 0.285285] ledtrig-cpu: registered to indicate activity on CPUs479second # [ 0.285626] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:480test # [ 0.287682] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint481test # [ 0.287804] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]482test # [ 0.287815] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]483test # [ 0.287840] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]484test # [ 0.287852] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]485test # [ 0.288148] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint486test # [ 0.288267] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]487test # [ 0.288286] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]488test # [ 0.288575] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint489second # [ 0.285647] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000490second # [ 0.285663] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000491second # [ 0.285669] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000492second # [ 0.285698] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits493second # [ 0.285730] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]494second # [ 0.285810] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00495second # [ 0.285829] pci_bus 0000:00: root bus resource [bus 00-ff]496test # [ 0.288711] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]497test # [ 0.288732] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]498test # [ 0.289015] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint499test # [ 0.289134] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]500test # [ 0.289322] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint501test # [ 0.289539] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]502test # [ 0.289554] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]503test # [ 0.289577] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]504test # [ 0.289885] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint505test # [ 0.290005] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]506test # [ 0.290017] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]507second # [ 0.285834] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]508test # [ 0.290037] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]509test # [ 0.290486] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned510test # [ 0.290499] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned511test # [ 0.290504] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned512second # [ 0.285838] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]513second # [ 0.285842] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]514test # [ 0.290541] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned515second # [ 0.285934] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint516second # [ 0.286388] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint517second # [ 0.286514] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]518second # [ 0.286525] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]519second # [ 0.286547] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]520second # [ 0.286559] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]521second # [ 0.286860] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint522second # [ 0.286994] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]523second # [ 0.287006] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]524second # [ 0.287028] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]525second # [ 0.287336] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint526test # [ 0.290574] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned527test # [ 0.290606] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned528test # [ 0.290638] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned529test # [ 0.290670] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned530second # [ 0.287456] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]531second # [ 0.287466] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]532second # [ 0.287487] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]533second # [ 0.287781] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint534second # [ 0.287903] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]535test # [ 0.290705] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned536test # [ 0.290739] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned537test # [ 0.290771] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned538test # [ 0.290802] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned539test # [ 0.290885] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned540test # [ 0.290918] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned541second # [ 0.287914] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]542second # [ 0.287934] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]543second # [ 0.288235] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint544second # [ 0.288355] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]545test # [ 0.290932] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned546second # [ 0.288366] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]547test # [ 0.290949] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned548second # [ 0.288388] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]549test # [ 0.290965] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned550test # [ 0.290982] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned551test # [ 0.290998] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned552test # [ 0.291014] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned553test # [ 0.291031] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned554test # [ 0.291047] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned555second # [ 0.288709] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint556second # [ 0.288829] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]557second # [ 0.288840] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]558second # [ 0.288861] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]559second # [ 0.289161] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint560second # [ 0.289281] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]561test # [ 0.291064] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned562test # [ 0.291081] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned563second # [ 0.289292] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]564second # [ 0.289318] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]565second # [ 0.289330] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]566test # [ 0.291098] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned567test # [ 0.291115] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned568test # [ 0.291132] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned569test # [ 0.291150] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned570test # [ 0.291166] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned571second # [ 0.289691] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint572second # [ 0.289829] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]573second # [ 0.289849] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]574second # [ 0.290172] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint575second # [ 0.290296] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]576test # [ 0.291189] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned577test # [ 0.291204] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned578test # [ 0.291220] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned579test # [ 0.291237] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned580test # [ 0.291253] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned581test # [ 0.291276] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]582test # [ 0.291282] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]583test # [ 0.291286] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]584second # [ 0.290316] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]585second # [ 0.290585] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint586second # [ 0.290703] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]587second # [ 0.290898] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint588second # [ 0.291114] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]589second # [ 0.291127] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]590second # [ 0.291148] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]591second # [ 0.291470] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint592second # [ 0.291590] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]593second # [ 0.291602] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]594second # [ 0.291623] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]595second # [ 0.292075] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned596second # [ 0.292087] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned597second # [ 0.292092] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned598second # [ 0.292128] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned599second # [ 0.292162] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned600second # [ 0.292194] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned601second # [ 0.292225] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned602second # [ 0.292258] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned603second # [ 0.292291] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned604second # [ 0.292325] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned605second # [ 0.292358] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned606test # [ 0.352128] pci 0000:00:0a.0: enabling device (0000 -> 0002)607second # [ 0.292389] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned608second # [ 0.292486] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned609second # [ 0.292543] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned610second # [ 0.292558] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned611second # [ 0.292573] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned612second # [ 0.292589] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned613test # [ 0.359088] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)614second # [ 0.292605] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned615second # [ 0.292621] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned616second # [ 0.292637] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned617second # [ 0.292654] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned618second # [ 0.340744] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned619second # [ 0.340784] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned620second # [ 0.340802] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned621second # [ 0.340820] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned622second # [ 0.340838] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned623second # [ 0.340856] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned624second # [ 0.340873] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned625second # [ 0.340889] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned626second # [ 0.340910] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned627second # [ 0.340928] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned628second # [ 0.340945] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned629second # [ 0.340962] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned630second # [ 0.340979] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned631second # [ 0.341007] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]632test # [ 0.368989] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)633second # [ 0.341013] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]634second # [ 0.341017] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]635second # [ 0.341758] pci 0000:00:0a.0: enabling device (0000 -> 0002)636test # [ 0.376140] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)637test # [ 0.378385] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)638second # [ 0.362079] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)639second # [ 0.364315] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)640test # [ 0.388117] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)641test # [ 0.390438] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)642second # [ 0.374503] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)643second # [ 0.376517] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)644test # [ 0.396650] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)645test # [ 0.403810] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)646test # [ 0.405744] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)647second # [ 0.386705] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)648second # [ 0.392824] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)649second # [ 0.395261] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)650test # [ 0.415793] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)651test # [ 0.418718] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)652second # [ 0.405958] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)653second # [ 0.407889] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)654test # [ 0.431641] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled655second # [ 0.411265] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)656test # [ 0.433326] msm_serial: driver initialized657test # [ 0.433408] SuperH (H)SCI(F) driver initialized658test # [ 0.433444] STM32 USART driver initialized659second # [ 0.421946] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)660second # [ 0.433534] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled661test # [ 0.461582] loop: module loaded662second # [ 0.435279] msm_serial: driver initialized663test # [ 0.461767] virtio_blk virtio5: 1/0/0 default/read/poll queues664second # [ 0.435381] SuperH (H)SCI(F) driver initialized665second # [ 0.435422] STM32 USART driver initialized666test # [ 0.462423] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)667test # [ 0.468077] megasas: 07.734.00.00-rc1668test # [ 0.468596] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]669test # [ 0.470814] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000670test # [ 0.470843] Intel/Sharp Extended Query Table at 0x0031671second # [ 0.455318] loop: module loaded672second # [ 0.455492] virtio_blk virtio5: 1/0/0 default/read/poll queues673second # [ 0.456106] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)674test # [ 0.480759] Using buffer write method675test # [ 0.480844] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]676test # [ 0.482554] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000677test # [ 0.482584] Intel/Sharp Extended Query Table at 0x0031678test # [ 0.486763] Using buffer write method679test # [ 0.486808] Concatenating MTD devices:680test # [ 0.486813] (0): "0.flash"681test # [ 0.486817] (1): "0.flash"682test # [ 0.486819] into device "0.flash"683second # [ 0.465214] megasas: 07.734.00.00-rc1684second # [ 0.465754] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]685second # [ 0.468412] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000686second # [ 0.468448] Intel/Sharp Extended Query Table at 0x0031687second # [ 0.470301] Using buffer write method688second # [ 0.470386] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]689second # [ 0.472351] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000690second # [ 0.472381] Intel/Sharp Extended Query Table at 0x0031691second # [ 0.489988] Using buffer write method692second # [ 0.490041] Concatenating MTD devices:693second # [ 0.490045] (0): "0.flash"694second # [ 0.490048] (1): "0.flash"695second # [ 0.490051] into device "0.flash"696test # [ 0.713654] Freeing initrd memory: 24796K697test # [ 0.721082] tun: Universal TUN/TAP device driver, 1.6698test # [ 0.724151] thunder_xcv, ver 1.0699test # [ 0.724187] thunder_bgx, ver 1.0700test # [ 0.724198] nicpf, ver 1.0701test # [ 0.724503] e1000: Intel(R) PRO/1000 Network Driver702test # [ 0.724511] e1000: Copyright (c) 1999-2006 Intel Corporation.703test # [ 0.724527] e1000e: Intel(R) PRO/1000 Network Driver704test # [ 0.724535] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.705test # [ 0.724548] igb: Intel(R) Gigabit Ethernet Network Driver706test # [ 0.724551] igb: Copyright (c) 2007-2014 Intel Corporation.707test # [ 0.724562] igbvf: Intel(R) Gigabit Virtual Function Network Driver708test # [ 0.724566] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.709test # [ 0.724662] sky2: driver version 1.30710test # [ 0.725521] usbcore: registered new interface driver usb-storage711test # [ 0.725578] usbcore: registered new interface driver usbserial_generic712test # [ 0.725586] usbserial: USB Serial support registered for generic713test # [ 0.725875] hv_vmbus: registering driver hyperv_keyboard714test # [ 0.727043] ehci-pci 0000:00:0a.0: EHCI Host Controller715test # [ 0.727081] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1716test # [ 0.727310] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000717test # [ 0.735580] rtc-pl031 9010000.pl031: registered as rtc0718test # [ 0.735658] rtc-pl031 9010000.pl031: setting system clock to 2026-09-10T20:08:42 UTC (1789070922)719test # [ 0.735912] i2c_dev: i2c /dev entries driver720test # [ 0.737909] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00721second # [ 0.713525] Freeing initrd memory: 24808K722test # [ 0.738858] hub 1-0:1.0: USB hub found723test # [ 0.739221] hub 1-0:1.0: 6 ports detected724test # [ 0.740877] sdhci: Secure Digital Host Controller Interface driver725second # [ 0.721292] tun: Universal TUN/TAP device driver, 1.6726test # [ 0.740895] sdhci: Copyright(c) Pierre Ossman727test # [ 0.741039] Synopsys Designware Multimedia Card Interface Driver728test # [ 0.741208] sdhci-pltfm: SDHCI platform and OF driver helper729test # [ 0.742185] hid: raw HID events driver (C) Jiri Kosina730test # [ 0.742338] usbcore: registered new interface driver usbhid731test # [ 0.742343] usbhid: USB HID core driver732second # [ 0.724276] thunder_xcv, ver 1.0733second # [ 0.724311] thunder_bgx, ver 1.0734second # [ 0.724323] nicpf, ver 1.0735test # [ 0.746096] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available736test # [ 0.746925] drop_monitor: Initializing network drop monitor service737test # [ 0.747112] NET: Registered PF_INET6 protocol family738test # [ 0.749649] Segment Routing with IPv6739test # [ 0.749679] In-situ OAM (IOAM) with IPv6740test # [ 0.749737] NET: Registered PF_PACKET protocol family741second # [ 0.725490] e1000: Intel(R) PRO/1000 Network Driver742second # [ 0.725502] e1000: Copyright (c) 1999-2006 Intel Corporation.743test # [ 0.750873] 9pnet: Installing 9P2000 support744second # [ 0.725529] e1000e: Intel(R) PRO/1000 Network Driver745second # [ 0.725540] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.746second # [ 0.725555] igb: Intel(R) Gigabit Ethernet Network Driver747second # [ 0.725559] igb: Copyright (c) 2007-2014 Intel Corporation.748second # [ 0.725570] igbvf: Intel(R) Gigabit Virtual Function Network Driver749second # [ 0.725574] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.750second # [ 0.725664] sky2: driver version 1.30751second # [ 0.726569] usbcore: registered new interface driver usb-storage752second # [ 0.726677] usbcore: registered new interface driver usbserial_generic753second # [ 0.726687] usbserial: USB Serial support registered for generic754test # [ 0.753066] Key type dns_resolver registered755second # [ 0.727022] hv_vmbus: registering driver hyperv_keyboard756second # [ 0.728143] ehci-pci 0000:00:0a.0: EHCI Host Controller757second # [ 0.728185] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1758second # [ 0.728461] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000759test # [ 0.756376] registered taskstats version 1760second # [ 0.736098] rtc-pl031 9010000.pl031: registered as rtc0761test # [ 0.756560] Loading compiled-in X.509 certificates762second # [ 0.736148] rtc-pl031 9010000.pl031: setting system clock to 2026-09-10T20:08:42 UTC (1789070922)763second # [ 0.736396] i2c_dev: i2c /dev entries driver764second # [ 0.736732] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00765second # [ 0.737303] hub 1-0:1.0: USB hub found766second # [ 0.737392] hub 1-0:1.0: 6 ports detected767test # [ 0.762025] Demotion targets for Node 0: null768test # [ 0.762114] Key type .fscrypt registered769test # [ 0.762120] Key type fscrypt-provisioning registered770test # [ 0.762252] ima: No TPM chip found, activating TPM-bypass!771test # [ 0.762266] ima: Allocated hash algorithm: sha1772test # [ 0.762332] ima: No architecture policies found773second # [ 0.741625] sdhci: Secure Digital Host Controller Interface driver774test # [ 0.765690] input: gpio-keys as /devices/platform/gpio-keys/input/input0775second # [ 0.741649] sdhci: Copyright(c) Pierre Ossman776second # [ 0.741798] Synopsys Designware Multimedia Card Interface Driver777second # [ 0.741978] sdhci-pltfm: SDHCI platform and OF driver helper778second # [ 0.742998] hid: raw HID events driver (C) Jiri Kosina779second # [ 0.743150] usbcore: registered new interface driver usbhid780second # [ 0.743155] usbhid: USB HID core driver781second # [ 0.747039] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available782second # [ 0.748043] drop_monitor: Initializing network drop monitor service783second # [ 0.748228] NET: Registered PF_INET6 protocol family784second # [ 0.750910] Segment Routing with IPv6785second # [ 0.750942] In-situ OAM (IOAM) with IPv6786second # [ 0.751001] NET: Registered PF_PACKET protocol family787second # [ 0.752165] 9pnet: Installing 9P2000 support788test # [ 0.776668] clk: Disabling unused clocks789second # [ 0.754400] Key type dns_resolver registered790test # [ 0.776692] PM: genpd: Disabling unused power domains791test # [ 0.780213] Freeing unused kernel memory: 4736K792test # [ 0.780377] Run /init as init process793second # [ 0.757823] registered taskstats version 1794second # [ 0.758016] Loading compiled-in X.509 certificates795second # [ 0.763796] Demotion targets for Node 0: null796second # [ 0.763893] Key type .fscrypt registered797second # [ 0.763900] Key type fscrypt-provisioning registered798second # [ 0.764022] ima: No TPM chip found, activating TPM-bypass!799second # [ 0.764036] ima: Allocated hash algorithm: sha1800second # [ 0.764112] ima: No architecture policies found801second # [ 0.767465] input: gpio-keys as /devices/platform/gpio-keys/input/input0802second # [ 0.778267] clk: Disabling unused clocks803second # [ 0.778300] PM: genpd: Disabling unused power domains804test # [ 0.802461] systemd[1]: Successfully made /usr/ read-only.805second # [ 0.781810] Freeing unused kernel memory: 4736K806second # [ 0.781911] Run /init as init process807second # [ 0.803323] systemd[1]: Successfully made /usr/ read-only.808test # [ 0.987696] usb 1-1: new high-speed USB device number 2 using ehci-pci809second # [ 0.984723] usb 1-1: new high-speed USB device number 2 using ehci-pci810test # [ 1.135306] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1811test # [ 1.137072] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)812test # [ 1.137095] systemd[1]: Detected virtualization qemu.813test # [ 1.137123] systemd[1]: Detected architecture arm64.814test # [ 1.137133] systemd[1]: Running in initrd.815test # [ 1.137765] systemd[1]: Initializing machine ID from random generator.816test # [ 1.137993] systemd[1]: Hostname set to <test>.817second # [ 1.132326] 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/input1818second # [ 1.137955] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)819second # [ 1.141940] systemd[1]: Detected virtualization qemu.820second # [ 1.142639] systemd[1]: Detected architecture arm64.821second # [ 1.143430] systemd[1]: Running in initrd.822second # [ 1.144880] systemd[1]: Initializing machine ID from random generator.823second # [ 1.146073] systemd[1]: Hostname set to <second>.824test # [ 1.215058] systemd[1]: Queued start job for default target Initrd Default Target.825test # [ 1.220015] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0826test # [ 1.225251] systemd[1]: Created slice Slice /system/modprobe.827test # [ 1.226235] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.828test # [ 1.226932] systemd[1]: Expecting device /dev/disk/by-label/nixos...829test # [ 1.227498] systemd[1]: Reached target Path Units.830test # [ 1.228030] systemd[1]: Reached target Slice Units.831test # [ 1.228526] systemd[1]: Reached target Swaps.832test # [ 1.228986] systemd[1]: Reached target Timer Units.833test # [ 1.229977] systemd[1]: Listening on D-Bus System Message Bus Socket.834test # [ 1.230819] systemd[1]: Listening on Journal Socket (/dev/log).835test # [ 1.232101] systemd[1]: Listening on Journal Sockets.836test # [ 1.232801] systemd[1]: Listening on udev Control Socket.837test # [ 1.233819] systemd[1]: Listening on udev Kernel Socket.838test # [ 1.234363] systemd[1]: Reached target Socket Units.839test # [ 1.236072] systemd[1]: Starting Create List of Static Device Nodes...840second # [ 1.216895] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0841test # [ 1.243087] systemd[1]: Starting Load Kernel Module 9pnet_virtio...842test # [ 1.243873] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs843test # [ 1.247716] systemd[1]: Mounting Kernel Configuration File System...844second # [ 1.229946] systemd[1]: Queued start job for default target Initrd Default Target.845test # [ 1.255699] systemd[1]: Starting Journal Service...846second # [ 1.238288] systemd[1]: Created slice Slice /system/modprobe.847second # [ 1.239125] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.848second # [ 1.239954] systemd[1]: Expecting device /dev/disk/by-label/nixos...849second # [ 1.240592] systemd[1]: Reached target Path Units.850second # [ 1.241161] systemd[1]: Reached target Slice Units.851second # [ 1.241664] systemd[1]: Reached target Swaps.852second # [ 1.242106] systemd[1]: Reached target Timer Units.853second # [ 1.242793] systemd[1]: Listening on D-Bus System Message Bus Socket.854second # [ 1.243639] systemd[1]: Listening on Journal Socket (/dev/log).855second # [ 1.244389] systemd[1]: Listening on Journal Sockets.856second # [ 1.245110] systemd[1]: Listening on udev Control Socket.857second # [ 1.245795] systemd[1]: Listening on udev Kernel Socket.858second # [ 1.246344] systemd[1]: Reached target Socket Units.859second # [ 1.248207] systemd[1]: Starting Create List of Static Device Nodes...860test # [ 1.272796] systemd[1]: Starting Load Kernel Modules...861test # [ 1.273917] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki862second # [ 1.255815] systemd[1]: Starting Load Kernel Module 9pnet_virtio...863second # [ 1.256508] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs864second # [ 1.258750] systemd[1]: Mounting Kernel Configuration File System...865test # [ 1.284923] systemd[1]: Starting Coldplug All udev Devices...866test # [ 1.291797] systemd[1]: Finished Create List of Static Device Nodes.867second # [ 1.270794] systemd[1]: Starting Journal Service...868test # [ 1.293565] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.869test # [ 1.299220] systemd-journald[73]: Collecting audit messages is disabled.870test # [ 1.299499] systemd[1]: Finished Load Kernel Module 9pnet_virtio.871test # [ 1.301483] systemd[1]: Mounted Kernel Configuration File System.872second # [ 1.286537] systemd[1]: Starting Load Kernel Modules...873second # [ 1.286607] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki874test # [ 1.312567] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...875second # [ 1.306621] systemd[1]: Starting Coldplug All udev Devices...876second # [ 1.312461] systemd-journald[73]: Collecting audit messages is disabled.877second # [ 1.314424] systemd[1]: Finished Create List of Static Device Nodes.878second # [ 1.316156] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.879second # [ 1.321013] systemd[1]: Finished Load Kernel Module 9pnet_virtio.880second # [ 1.321276] systemd[1]: Mounted Kernel Configuration File System.881test # [ 1.342170] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.882second # [ 1.327604] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...883test # [ 1.351637] usb 1-2: new high-speed USB device number 3 using ehci-pci884second # [ 1.336772] usb 1-2: new high-speed USB device number 3 using ehci-pci885test # [ 1.361768] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev886test # [ 1.368213] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.887test # [ 1.376001] systemd[1]: Starting Create Static Device Nodes in /dev...888test # [ 1.382513] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0889test # [ 1.382750] [drm] features: -virgl +edid -resource_blob -host_visible890test # [ 1.382756] [drm] features: -context_init891test # [ 1.383237] [drm] number of scanouts: 1892test # [ 1.383253] [drm] number of cap sets: 0893second # [ 1.372065] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.894test # [ 1.392442] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic895test # [ 1.392461] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0896second # [ 1.373945] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.897test # [ 1.404030] Console: switching to colour frame buffer device 160x50898second # [ 1.381723] systemd[1]: Starting Create Static Device Nodes in /dev...899test # [ 1.406617] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device900test # [ 1.404567] systemd-modules-load[74]: Inserted module 'dm_mod'901test # [ 1.413266] systemd[1]: Started Journal Service.902second # [ 1.392791] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev903test # [ 1.407925] systemd-modules-load[74]: Module 'virtio_balloon' is built in904test # [ 1.412630] systemd-modules-load[74]: Module 'virtio_console' is built in905second # [ 1.400990] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0906second # [ 1.401233] [drm] features: -virgl +edid -resource_blob -host_visible907second # [ 1.401239] [drm] features: -context_init908second # [ 1.401774] [drm] number of scanouts: 1909second # [ 1.401791] [drm] number of cap sets: 0910test # [ 1.422655] systemd[1]: Finished Create Static Device Nodes in /dev.911test # [ 1.423333] systemd[1]: Reached target Preparation for Local File Systems.912test # [ 1.423910] systemd[1]: Reached target Local File Systems.913test # [ 1.428285] systemd-modules-load[74]: Inserted module 'virtio_gpu'914test # [ 1.428862] systemd-modules-load[74]: Module 'virtio_rng' is built in915test # [ 1.429442] systemd[1]: Starting Create System Files and Directories...916second # [ 1.408475] systemd-modules-load[74]: Inserted module 'dm_mod'917second # [ 1.417958] systemd[1]: Started Journal Service.918second # [ 1.410106] systemd-modules-load[74]: Module 'virtio_balloon' is built in919second # [ 1.419703] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic920second # [ 1.419722] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0921test # [ 1.440639] systemd[1]: Starting Rule-based Manager for Device Events and Files...922test # [ 1.441886] systemd[1]: Finished Load Kernel Modules.923second # [ 1.424416] systemd-modules-load[74]: Module 'virtio_console' is built in924second # [ 1.427313] systemd[1]: Finished Create Static Device Nodes in /dev.925test # [ 1.456487] systemd[1]: Starting Apply Kernel Variables...926second # [ 1.441235] Console: switching to colour frame buffer device 160x50927second # [ 1.439756] systemd[1]: Reached target Preparation for Local File Systems.928second # [ 1.441419] systemd[1]: Reached target Local File Systems.929second # [ 1.444082] systemd[1]: Starting Create System Files and Directories...930second # [ 1.461170] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device931test # [ 1.488641] systemd[1]: Finished Create System Files and Directories.932test # [ 1.492131] systemd[1]: Finished Apply Kernel Variables.933second # [ 1.464741] systemd-modules-load[74]: Inserted module 'virtio_gpu'934second # [ 1.478576] systemd-modules-load[74]: Module 'virtio_rng' is built in935second # [ 1.479172] systemd[1]: Starting Rule-based Manager for Device Events and Files...936test # [ 1.509236] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2937second # [ 1.479885] systemd[1]: Finished Load Kernel Modules.938test # [ 1.511042] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0939second # [ 1.486118] systemd[1]: Starting Apply Kernel Variables...940second # [ 1.495645] 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/input2941second # [ 1.500145] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0942test # [ 1.517873] systemd-udevd[86]: Using default interface naming scheme 'v260'.943second # [ 1.516468] systemd[1]: Finished Create System Files and Directories.944second # [ 1.517786] systemd[1]: Finished Apply Kernel Variables.945test # [ 1.542335] systemd[1]: Started Rule-based Manager for Device Events and Files.946second # [ 1.539903] systemd-udevd[87]: Using default interface naming scheme 'v260'.947second # [ 1.565663] systemd[1]: Started Rule-based Manager for Device Events and Files.948test # [ 1.600226] systemd[1]: Starting Virtual Console Setup...949second # [ 1.625845] systemd[1]: Starting Virtual Console Setup...950test # [ 1.653822] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.951test # [ 1.654882] systemd[1]: Finished Virtual Console Setup.952second # [ 1.684466] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.953second # [ 1.687202] systemd[1]: Finished Virtual Console Setup.954test # [ 1.907973] systemd[1]: Finished Coldplug All udev Devices.955test # [ 1.908622] systemd[1]: Reached target System Initialization.956test # [ 1.909123] systemd[1]: Reached target Basic System.957second # [ 1.948685] systemd[1]: Finished Coldplug All udev Devices.958second # [ 1.949912] systemd[1]: Reached target System Initialization.959second # [ 1.952131] systemd[1]: Reached target Basic System.960test # [ 2.008415] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.961test # [ 2.034242] (udev-worker)[101]: Network interface NamePolicy= disabled on kernel command line.962second # [ 2.066642] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.963test # [ 2.091760] systemd[1]: Found device /dev/disk/by-label/nixos.964test # [ 2.094309] systemd[1]: Reached target Initrd Root Device.965test # [ 2.097132] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...966second # [ 2.087252] (udev-worker)[103]: Network interface NamePolicy= disabled on kernel command line.967test # [ 2.149114] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks968second # [ 2.131479] systemd[1]: Found device /dev/disk/by-label/nixos.969second # [ 2.134375] systemd[1]: Reached target Initrd Root Device.970test # [ 2.158477] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.971second # [ 2.137376] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...972second # [ 2.185206] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks973second # [ 2.197308] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.974test # [ 2.280231] systemd[1]: Mounting /sysroot...975test # [ 2.326055] EXT4-fs (vda): mounted filesystem f4576233-d662-45db-918b-ffd034aeac8e r/w with ordered data mode. Quota mode: none.976test # [ 2.322347] systemd[1]: Mounted /sysroot.977test # [ 2.324334] systemd[1]: Reached target Initrd Root File System.978second # [ 2.302556] systemd[1]: Mounting /sysroot...979test # [ 2.328373] systemd[1]: Starting Mountpoints Configured in the Real Root...980test # [ 2.355058] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.981test # [ 2.359443] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...982second # [ 2.341523] EXT4-fs (vda): mounted filesystem 166f86c6-9a5c-46a9-aa50-25cbb6abaccc r/w with ordered data mode. Quota mode: none.983second # [ 2.338928] systemd[1]: Mounted /sysroot.984test # [ 2.362450] systemd[1]: Reloading...985second # [ 2.340467] systemd[1]: Reached target Initrd Root File System.986second # [ 2.346295] systemd[1]: Mounting /sysroot/nix/.ro-store...987second # [ 2.362676] systemd[1]: Mounting /sysroot/nix/.rw-store...988second # [ 2.365932] systemd[1]: Mounting /sysroot/run...989second # [ 2.380521] systemd[1]: Mounting /sysroot/tmp/shared...990second # [ 2.403339] systemd[1]: Mounting /sysroot/tmp/xchg...991second # [ 2.416259] systemd[1]: Starting Mountpoints Configured in the Real Root...992second # [ 2.439556] systemd[1]: Mounted /sysroot/nix/.rw-store.993second # [ 2.456999] systemd[1]: Starting rw-sysroot-nix-store.service...994second # [ 2.459400] systemd[1]: Mounted /sysroot/nix/.ro-store.995second # [ 2.474838] systemd[1]: Mounted /sysroot/run.996second # [ 2.480399] systemd-sysroot-fstab-check[126]: /sysroot should be mounted in the initrd, will request daemon-reload.997second # [ 2.484948] systemd[1]: Mounted /sysroot/tmp/shared.998second # [ 2.498007] systemd[1]: Mounted /sysroot/tmp/xchg.999second # [ 2.501228] systemd[1]: Reload requested from client PID 126 ('systemd-sysroot') (unit initrd-parse-etc.service)...1000second # [ 2.504732] systemd[1]: Reloading...1001test # [ 2.570564] systemd[1]: Reloading finished in 208 ms.1002test # [ 2.583931] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...1003test # [ 2.587054] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...1004test # [ 2.592198] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1005test # [ 2.593215] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1006test # [ 2.598258] systemd[1]: Finished Mountpoints Configured in the Real Root.1007test # [ 2.600592] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1008test # [ 2.620653] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1009test # [ 2.621770] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1010second # [ 2.710860] systemd[1]: Reloading finished in 206 ms.1011second # [ 2.721214] systemd-sysroot-fstab-check[126]: Requesting initrd-fs.target/start/replace...1012second # [ 2.724168] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1013second # [ 2.725588] systemd[1]: Finished rw-sysroot-nix-store.service.1014second # [ 2.728296] systemd-sysroot-fstab-check[126]: Requesting swap.target/start/replace...1015second # [ 2.739434] systemd[1]: Starting rw-sysroot-nix-store.service...1016second # [ 2.745418] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1017second # [ 2.747580] systemd[1]: Finished Mountpoints Configured in the Real Root.1018second # [ 2.750094] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1019second # [ 2.756689] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1020second # [ 2.757648] systemd[1]: Finished rw-sysroot-nix-store.service.1021test # [ 2.965437] (udev-worker)[95]: mtd0ro: Failed to find and pin callout binary "/nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/mtd_probe": No such file or directory1022test # [ 2.969794] (udev-worker)[95]: mtd0ro: /nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1023test # [ 2.984400] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1024test # [ 2.986910] systemd[1]: Stopped Virtual Console Setup.1025test # [ 2.988110] systemd[1]: Stopping Virtual Console Setup...1026test # [ 2.990321] systemd[1]: Starting Virtual Console Setup...1027test # [ 3.015684] systemd-vconsole-setup[141]: Configuration of first virtual console was skipped, ignoring remaining ones.1028test # [ 3.018642] systemd[1]: Finished Virtual Console Setup.1029second # [ 3.168585] (udev-worker)[98]: mtd0ro: Failed to find and pin callout binary "/nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/mtd_probe": No such file or directory1030second # [ 3.175542] (udev-worker)[98]: mtd0ro: /nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1031second # [ 3.184928] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1032second # [ 3.186828] systemd[1]: Stopped Virtual Console Setup.1033second # [ 3.188753] systemd[1]: Stopping Virtual Console Setup...1034second # [ 3.192221] systemd[1]: Starting Virtual Console Setup...1035second # [ 3.220753] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones.1036second # [ 3.223564] systemd[1]: Finished Virtual Console Setup.1037test # [ 3.283708] systemd[1]: Mounting /sysroot/nix/.ro-store...1038test # [ 3.292371] systemd[1]: Mounting /sysroot/nix/.rw-store...1039test # [ 3.307794] systemd[1]: Mounting /sysroot/run...1040test # [ 3.313382] systemd[1]: Mounting /sysroot/tmp/shared...1041second # [ 3.301264] systemd[1]: Mounting /sysroot/nix/store...1042test # [ 3.347231] systemd[1]: Mounting /sysroot/tmp/xchg...1043second # [ 3.328431] systemd[1]: Mounted /sysroot/nix/store.1044second # [ 3.329631] systemd[1]: Reached target Initrd File Systems.1045second # [ 3.331591] systemd[1]: Starting Find NixOS closure...1046second # [ 3.340505] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1047test # [ 3.371467] systemd[1]: Mounted /sysroot/nix/.ro-store.1048test # [ 3.373491] systemd[1]: Mounted /sysroot/nix/.rw-store.1049test # [ 3.376169] systemd[1]: Mounted /sysroot/run.1050test # [ 3.387100] systemd[1]: Starting rw-sysroot-nix-store.service...1051second # [ 3.364269] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1052second # [ 3.365785] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1053test # [ 3.389772] systemd[1]: Mounted /sysroot/tmp/shared.1054test # [ 3.397715] systemd[1]: Mounted /sysroot/tmp/xchg.1055second # [ 3.379965] systemd[1]: Finished Find NixOS closure.1056second # [ 3.382630] systemd[1]: Reached target Initrd Default Target.1057second # [ 3.388527] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1058test # [ 3.411890] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1059test # [ 3.413197] systemd[1]: Finished rw-sysroot-nix-store.service.1060test # [ 3.415660] systemd[1]: Mounting /sysroot/nix/store...1061second # [ 3.407731] systemd[1]: Stopped target Initrd Default Target.1062second # [ 3.409003] systemd[1]: Stopped target Basic System.1063second # [ 3.412149] systemd[1]: Stopped target Initrd Root Device.1064second # [ 3.412940] systemd[1]: Stopped target Path Units.1065second # [ 3.413425] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1066second # [ 3.414084] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1067second # [ 3.414828] systemd[1]: Stopped target Slice Units.1068second # [ 3.415269] systemd[1]: Stopped target Socket Units.1069second # [ 3.415749] systemd[1]: Stopped target System Initialization.1070second # [ 3.420306] systemd[1]: Stopped target Swaps.1071second # [ 3.420825] systemd[1]: Stopped target Timer Units.1072second # [ 3.421294] systemd[1]: dbus.socket: Deactivated successfully.1073second # [ 3.421822] systemd[1]: Closed D-Bus System Message Bus Socket.1074second # [ 3.422352] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1075second # [ 3.423016] systemd[1]: Stopped Find NixOS closure.1076second # [ 3.423470] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1077test # [ 3.448922] systemd[1]: Mounted /sysroot/nix/store.1078test # [ 3.450370] systemd[1]: Reached target Initrd File Systems.1079second # [ 3.428496] systemd[1]: Starting rw-sysroot-nix-store.service...1080second # [ 3.429172] systemd[1]: systemd-sysctl.service: Deactivated successfully.1081test # [ 3.452622] systemd[1]: Starting Find NixOS closure...1082second # [ 3.430030] systemd[1]: Stopped Apply Kernel Variables.1083second # [ 3.430991] systemd[1]: systemd-modules-load.service: Deactivated successfully.1084second # [ 3.434726] systemd[1]: Stopped Load Kernel Modules.1085second # [ 3.435553] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1086second # [ 3.437042] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1087test # [ 3.460299] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1088second # [ 3.447354] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1089second # [ 3.449254] systemd[1]: Stopped Create System Files and Directories.1090second # [ 3.449947] systemd[1]: Stopped target Local File Systems.1091second # [ 3.450497] systemd[1]: Stopped target Preparation for Local File Systems.1092second # [ 3.451099] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1093second # [ 3.451746] systemd[1]: Stopped Coldplug All udev Devices.1094second # [ 3.457150] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1095second # [ 3.457841] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1096second # [ 3.458503] systemd[1]: Stopped Virtual Console Setup.1097second # [ 3.458973] systemd[1]: initrd-cleanup.service: Deactivated successfully.1098second # [ 3.459542] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1099second # [ 3.460185] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1100test # [ 3.485170] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1101second # [ 3.469627] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1102second # [ 3.471698] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1103second # [ 3.473495] systemd[1]: Finished rw-sysroot-nix-store.service.1104second # [ 3.474037] systemd[1]: systemd-udevd.service: Deactivated successfully.1105second # [ 3.476440] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1106test # [ 3.500152] systemd[1]: Finished Find NixOS closure.1107second # [ 3.477118] systemd[1]: systemd-udevd.service: Consumed 1.068s CPU time over 2.008s wall clock time, 21.5M memory peak.1108test # [ 3.500814] systemd[1]: Reached target Initrd Default Target.1109test # [ 3.502684] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1110second # [ 3.479792] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1111second # [ 3.480674] systemd[1]: Closed udev Control Socket.1112second # [ 3.485147] systemd[1]: Starting Cleanup udev Database...1113second # [ 3.485731] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1114second # [ 3.487590] systemd[1]: Stopped Create Static Device Nodes in /dev.1115second # [ 3.488790] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1116second # [ 3.489568] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1117second # [ 3.490199] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1118second # [ 3.490788] systemd[1]: Stopped Create List of Static Device Nodes.1119test # [ 3.529863] systemd[1]: Stopped target Initrd Default Target.1120test # [ 3.530975] systemd[1]: Stopped target Basic System.1121test # [ 3.531832] systemd[1]: Stopped target Initrd Root Device.1122test # [ 3.532825] systemd[1]: Stopped target Path Units.1123test # [ 3.536144] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1124test # [ 3.537142] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1125test # [ 3.537995] systemd[1]: Stopped target Slice Units.1126test # [ 3.538812] systemd[1]: Stopped target Socket Units.1127test # [ 3.540131] systemd[1]: Stopped target System Initialization.1128second # [ 3.514219] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1129second # [ 3.516958] systemd[1]: Finished Cleanup udev Database.1130second # [ 3.517498] systemd[1]: Reached target Switch Root.1131second # [ 3.518733] systemd[1]: Starting NixOS Activation...1132test # [ 3.544222] systemd[1]: Stopped target Swaps.1133test # [ 3.544752] systemd[1]: Stopped target Timer Units.1134test # [ 3.545275] systemd[1]: dbus.socket: Deactivated successfully.1135test # [ 3.546233] systemd[1]: Closed D-Bus System Message Bus Socket.1136test # [ 3.546972] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1137test # [ 3.547626] systemd[1]: Stopped Find NixOS closure.1138test # [ 3.549641] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1139test # [ 3.552139] systemd[1]: Starting rw-sysroot-nix-store.service...1140test # [ 3.552731] systemd[1]: systemd-sysctl.service: Deactivated successfully.1141test # [ 3.553788] systemd[1]: Stopped Apply Kernel Variables.1142test # [ 3.554945] systemd[1]: systemd-modules-load.service: Deactivated successfully.1143test # [ 3.556541] systemd[1]: Stopped Load Kernel Modules.1144test # [ 3.560188] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1145test # [ 3.564901] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1146test # [ 3.565889] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1147test # [ 3.568114] systemd[1]: Stopped Create System Files and Directories.1148test # [ 3.572633] systemd[1]: Stopped target Local File Systems.1149test # [ 3.573450] systemd[1]: Stopped target Preparation for Local File Systems.1150test # [ 3.574240] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1151test # [ 3.574881] systemd[1]: Stopped Coldplug All udev Devices.1152test # [ 3.575345] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1153test # [ 3.579184] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1154test # [ 3.579870] systemd[1]: Stopped Virtual Console Setup.1155test # [ 3.581345] systemd[1]: systemd-udevd.service: Deactivated successfully.1156test # [ 3.589995] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1157test # [ 3.590682] systemd[1]: initrd-cleanup.service: Deactivated successfully.1158test # [ 3.591247] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1159test # [ 3.594963] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1160test # [ 3.596661] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1161test # [ 3.597828] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1162test # [ 3.600142] systemd[1]: Finished rw-sysroot-nix-store.service.1163test # [ 3.601483] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1164test # [ 3.602951] systemd[1]: Closed udev Control Socket.1165test # [ 3.603722] systemd[1]: Starting Cleanup udev Database...1166test # [ 3.608340] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1167test # [ 3.609004] systemd[1]: Stopped Create Static Device Nodes in /dev.1168test # [ 3.609560] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1169test # [ 3.610221] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1170test # [ 3.610801] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1171test # [ 3.611382] systemd[1]: Stopped Create List of Static Device Nodes.1172test # [ 3.638457] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1173test # [ 3.639218] systemd[1]: Finished Cleanup udev Database.1174test # [ 3.639698] systemd[1]: Reached target Switch Root.1175test # [ 3.641923] systemd[1]: Starting NixOS Activation...1176second # [ 3.709327] initrd-nixos-activation-start[180]: booting system configuration /nix/store/lkf23qhiidbwar4yqhywvx6n160njd44-nixos-system-second-test1177second # [ 3.790601] initrd-nixos-activation-start[180]: running activation script...1178test # [ 3.860298] initrd-nixos-activation-start[178]: booting system configuration /nix/store/a7vf68qa1b2ch1i0nrvr7iib4hnf2b6z-nixos-system-test-test1179test # [ 3.935618] initrd-nixos-activation-start[178]: running activation script...1180second # [ 4.251192] initrd-nixos-activation-start[203]: setting up /etc...1181test # [ 4.413682] initrd-nixos-activation-start[201]: setting up /etc...1182second # [ 4.473794] initrd-nixos-activation-start[203]: setting up age secrets...1183test # [ 4.617194] initrd-nixos-activation-start[201]: setting up age secrets...1184second # [ 4.677996] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1185second # [ 4.679887] systemd[1]: Finished NixOS Activation.1186second # [ 4.684215] systemd[1]: Starting Switch Root...1187second # [ 4.697566] systemd[1]: Switching root.1188second # [ 4.753620] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1189test # [ 4.898327] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1190test # [ 4.900130] systemd[1]: Finished NixOS Activation.1191test # [ 4.900985] systemd[1]: Starting Switch Root...1192test # [ 4.917382] systemd[1]: Switching root.1193test # [ 4.972741] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1194second # [ 5.298222] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1195second # [ 5.305954] systemd[1]: Detected virtualization qemu.1196second # [ 5.307934] systemd[1]: Detected architecture arm64.1197second # [ 5.310606] systemd[1]: Detected first boot.1198second # [ 5.318113] systemd[1]: Initializing machine ID from random generator.1199test # [ 5.580690] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1200test # [ 5.587936] systemd[1]: Detected virtualization qemu.1201test # [ 5.589854] systemd[1]: Detected architecture arm64.1202test # [ 5.592322] systemd[1]: Detected first boot.1203test # [ 5.598556] systemd[1]: Initializing machine ID from random generator.1204second # [ 5.618436] systemd[1]: bpf-restrict-fs: LSM BPF program attached1205second # [ 5.816330] systemd[1]: Applying preset policy.1206test # [ 5.897183] systemd[1]: bpf-restrict-fs: LSM BPF program attached1207test # [ 6.097098] systemd[1]: Applying preset policy.1208second # [ 6.285193] systemd[1]: Populated /etc with preset unit settings.1209test # [ 6.619403] systemd[1]: Populated /etc with preset unit settings.1210second # [ 6.782739] systemd[1]: initrd-switch-root.service: Deactivated successfully.1211second # [ 6.783800] systemd[1]: Stopped initrd-switch-root.service.1212second # [ 6.785946] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1213second # [ 6.787947] systemd[1]: Created slice Slice /system/getty.1214second # [ 6.789817] systemd[1]: Created slice User and Session Slice.1215second # [ 6.791051] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1216second # [ 6.792468] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1217second # [ 6.794091] systemd[1]: Expecting device /dev/hvc0...1218second # [ 6.795133] systemd[1]: Expecting device /dev/ttyAMA0...1219second # [ 6.796195] systemd[1]: Reached target Local Encrypted Volumes.1220second # [ 6.797349] systemd[1]: Stopped target initrd-fs.target.1221second # [ 6.798354] systemd[1]: Stopped target initrd-root-fs.target.1222second # [ 6.799434] systemd[1]: Stopped target initrd-switch-root.target.1223second # [ 6.800510] systemd[1]: Reached target Virtual Machines and Containers.1224second # [ 6.801711] systemd[1]: Reached target Path Units.1225second # [ 6.803115] systemd[1]: Reached target Remote File Systems.1226second # [ 6.804568] systemd[1]: Reached target Slice Units.1227second # [ 6.806011] systemd[1]: Reached target Swaps.1228second # [ 6.811042] systemd[1]: Listening on Process Core Dump Socket.1229second # [ 6.815289] systemd[1]: Listening on Credential Encryption/Decryption.1230second # [ 6.820642] systemd[1]: Starting Journal Log Access Socket...1231second # [ 6.822580] systemd[1]: Listening on Journal Audit Socket.1232second # [ 6.824345] systemd[1]: Listening on Network Management Metrics Varlink Socket.1233second # [ 6.826245] systemd[1]: Listening on Network Management Netlink Socket.1234second # [ 6.827544] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1235second # [ 6.828851] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1236second # [ 6.830773] systemd[1]: Listening on udev Control Socket.1237second # [ 6.835763] systemd[1]: Mounting Huge Pages File System...1238second # [ 6.839660] systemd[1]: Mounting POSIX Message Queue File System...1239second # [ 6.843528] systemd[1]: Mounting Kernel Debug File System...1240second # [ 6.851031] systemd[1]: Mounting Kernel Trace File System...1241second # [ 6.862899] systemd[1]: Starting Create List of Static Device Nodes...1242second # [ 6.874163] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1243second # [ 6.876660] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1244second # [ 6.890605] systemd[1]: Mounting Kernel Configuration File System...1245second # [ 6.891797] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1246second # [ 6.894313] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1247second # [ 6.906501] systemd[1]: Starting Load Kernel Module fuse...1248second # [ 6.911189] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671249second # [ 6.958168] systemd[1]: Starting Journal Service...1250second # [ 7.002641] systemd[1]: Starting Load Kernel Modules...1251second # [ 7.027673] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1252second # [ 7.052388] systemd[1]: Starting Remount Root and Kernel File Systems...1253second # [ 7.058432] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1254second # [ 7.076889] systemd[1]: Starting Coldplug All udev Devices...1255second # [ 7.094170] fuse: init (API version 7.45)1256second # [ 7.107260] systemd[1]: Listening on Journal Log Access Socket.1257second # [ 7.112274] systemd[1]: Mounted Huge Pages File System.1258second # [ 7.118248] systemd[1]: Mounted POSIX Message Queue File System.1259second # [ 7.125126] systemd-journald[302]: Collecting audit messages is enabled.1260second # [ 7.127701] systemd[1]: Mounted Kernel Debug File System.1261second # [ 7.126866] systemd[1]: Queued start job for default target Multi-User System.1262second # [ 7.137137] systemd[1]: Started Journal Service.1263second # [ 7.135033] systemd[1]: systemd-journald.service: Deactivated successfully.1264second # [ 7.142778] systemd-modules-load[303]: Module 'atkbd' is built in1265second # [ 7.145224] systemd-modules-load[303]: Module 'ctr' is built in1266second # [ 7.151914] systemd-modules-load[303]: Module 'loop' is built in1267second # [ 7.157015] systemd[1]: Mounted Kernel Trace File System.1268test # [ 7.192129] systemd[1]: initrd-switch-root.service: Deactivated successfully.1269test # [ 7.193110] systemd[1]: Stopped initrd-switch-root.service.1270second # [ 7.164250] systemd[1]: Finished Create List of Static Device Nodes.1271test # [ 7.195385] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1272second # [ 7.166891] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1273test # [ 7.197969] systemd[1]: Created slice Slice /system/getty.1274test # [ 7.199115] systemd[1]: Created slice User and Session Slice.1275test # [ 7.199989] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1276test # [ 7.201467] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1277second # [ 7.172452] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1278test # [ 7.202782] systemd[1]: Expecting device /dev/hvc0...1279test # [ 7.203883] systemd[1]: Expecting device /dev/ttyAMA0...1280test # [ 7.205030] systemd[1]: Reached target Local Encrypted Volumes.1281test # [ 7.206076] systemd[1]: Stopped target initrd-fs.target.1282test # [ 7.207170] systemd[1]: Stopped target initrd-root-fs.target.1283test # [ 7.208868] systemd[1]: Stopped target initrd-switch-root.target.1284second # [ 7.180561] systemd[1]: Mounted Kernel Configuration File System.1285test # [ 7.209883] systemd[1]: Reached target Virtual Machines and Containers.1286second # [ 7.181339] systemd[1]: modprobe@fuse.service: Deactivated successfully.1287test # [ 7.211739] systemd[1]: Reached target Path Units.1288test # [ 7.213287] systemd[1]: Reached target Remote File Systems.1289second # [ 7.183964] systemd[1]: Finished Load Kernel Module fuse.1290test # [ 7.214786] systemd[1]: Reached target Slice Units.1291second # [ 7.185463] systemd[1]: Finished Load Kernel Modules.1292test # [ 7.216389] systemd[1]: Reached target Swaps.1293second # [ 7.190902] systemd[1]: Mounting FUSE Control File System...1294test # [ 7.221718] systemd[1]: Listening on Process Core Dump Socket.1295test # [ 7.226170] systemd[1]: Listening on Credential Encryption/Decryption.1296second # [ 7.205028] EXT4-fs (vda): re-mounted 166f86c6-9a5c-46a9-aa50-25cbb6abaccc.1297second # [ 7.199126] systemd[1]: Starting Firewall...1298second # [ 7.199597] systemd[1]: Starting Apply Kernel Variables...1299test # [ 7.232072] systemd[1]: Starting Journal Log Access Socket...1300test # [ 7.234222] systemd[1]: Listening on Journal Audit Socket.1301test # [ 7.236128] systemd[1]: Listening on Network Management Metrics Varlink Socket.1302test # [ 7.237997] systemd[1]: Listening on Network Management Netlink Socket.1303test # [ 7.239892] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1304second # [ 7.210438] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1305test # [ 7.241785] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1306test # [ 7.243907] systemd[1]: Listening on udev Control Socket.1307test # [ 7.249494] systemd[1]: Mounting Huge Pages File System...1308test # [ 7.254114] systemd[1]: Mounting POSIX Message Queue File System...1309test # [ 7.261204] systemd[1]: Mounting Kernel Debug File System...1310second # [ 7.237138] systemd-oomd[304]: No swap; memory pressure usage will be degraded1311test # [ 7.268919] systemd[1]: Mounting Kernel Trace File System...1312test # [ 7.276775] systemd[1]: Starting Create List of Static Device Nodes...1313second # [ 7.250394] systemd[1]: Finished Remount Root and Kernel File Systems.1314second # [ 7.253022] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1315test # [ 7.294022] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1316test # [ 7.296269] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1317test # [ 7.304272] systemd[1]: Mounting Kernel Configuration File System...1318test # [ 7.307007] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1319test # [ 7.311115] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1320test # [ 7.324007] systemd[1]: Starting Load Kernel Module fuse...1321test # [ 7.325959] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671322second # [ 7.313762] systemd[1]: Starting Flush Journal to Persistent Storage...1323second # [ 7.320190] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1324second # [ 7.331589] systemd[1]: Starting Load/Save OS Random Seed...1325second # [ 7.333641] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1326test # [ 7.374589] systemd[1]: Starting Journal Service...1327second # [ 7.356561] systemd[1]: Mounted FUSE Control File System.1328test # [ 7.395135] systemd[1]: Starting Load Kernel Modules...1329second # [ 7.389242] systemd[1]: Finished Apply Kernel Variables.1330second # [ 7.400328] systemd[1]: Starting Network Name Resolution...1331test # [ 7.437153] fuse: init (API version 7.45)1332test # [ 7.439184] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1333test # [ 7.462218] systemd[1]: Starting Remount Root and Kernel File Systems...1334test # [ 7.463819] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1335test # [ 7.472893] systemd[1]: Starting Coldplug All udev Devices...1336second # [ 7.458193] systemd-journald[302]: Received client request to flush runtime journal.1337test # [ 7.489228] systemd[1]: Listening on Journal Log Access Socket.1338test # [ 7.515018] systemd[1]: Mounted Huge Pages File System.1339test # [ 7.517620] systemd[1]: Mounted POSIX Message Queue File System.1340test # [ 7.522571] systemd[1]: Mounted Kernel Debug File System.1341test # [ 7.530251] systemd[1]: Mounted Kernel Trace File System.1342test # [ 7.537798] systemd[1]: Finished Create List of Static Device Nodes.1343test # [ 7.541729] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1344test # [ 7.543385] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1345test # [ 7.551321] systemd[1]: Mounted Kernel Configuration File System.1346test # [ 7.555007] systemd[1]: modprobe@fuse.service: Deactivated successfully.1347second # [ 7.528126] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1348second # [ 7.532214] systemd[1]: Starting Create Static Device Nodes in /dev...1349test # [ 7.561333] systemd[1]: Finished Load Kernel Module fuse.1350test # [ 7.562150] systemd[1]: Finished Load Kernel Modules.1351second # [ 7.537664] systemd[1]: Finished Load/Save OS Random Seed.1352test # [ 7.567967] systemd[1]: Mounting FUSE Control File System...1353test # [ 7.572221] systemd-journald[321]: Collecting audit messages is enabled.1354test # [ 7.579264] systemd[1]: Starting Firewall...1355second # [ 7.543839] systemd[1]: Reached target First Boot Complete.1356second # [ 7.553352] systemd[1]: Finished Flush Journal to Persistent Storage.1357test # [ 7.580742] systemd[1]: Queued start job for default target Multi-User System.1358test # [ 7.584092] systemd[1]: systemd-journald.service: Deactivated successfully.1359test # [ 7.588805] systemd-modules-load[322]: Module 'atkbd' is built in1360test # [ 7.591870] systemd-modules-load[322]: Module 'ctr' is built in1361test # [ 7.597933] systemd-modules-load[322]: Module 'loop' is built in1362test # [ 7.619908] systemd[1]: Starting Apply Kernel Variables...1363test # [ 7.615908] systemd-oomd[323]: No swap; memory pressure usage will be degraded[ 7.625905] EXT4-fs (vda): re-mounted f4576233-d662-45db-918b-ffd034aeac8e.1364test # 1365test # [ 7.632899] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1366test # [ 7.640643] systemd[1]: Started Journal Service.1367second # [ 7.618613] systemd[1]: Finished Create Static Device Nodes in /dev.1368test # [ 7.645578] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1369test # [ 7.647841] systemd[1]: Finished Remount Root and Kernel File Systems.1370second # [ 7.625070] systemd[1]: Reached target Preparation for Local File Systems.1371second # [ 7.628803] systemd[1]: Starting Rule-based Manager for Device Events and Files...1372second # [ 7.637471] systemd-resolved[331]: Positive Trust Anchors:1373second # [ 7.639159] systemd-resolved[331]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1374second # [ 7.644651] systemd-resolved[331]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161375second # [ 7.648378] 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 test1376test # [ 7.726685] systemd[1]: Starting Flush Journal to Persistent Storage...1377test # [ 7.727852] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1378test # [ 7.739184] systemd[1]: Starting Load/Save OS Random Seed...1379test # [ 7.743094] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1380test # [ 7.771364] systemd[1]: Mounted FUSE Control File System.1381second # [ 7.765156] systemd-udevd[345]: Using default interface naming scheme 'v260'.1382test # [ 7.794691] systemd[1]: Finished Apply Kernel Variables.1383second # [ 7.784643] systemd[1]: Mounting /run/wrappers...1384test # [ 7.817090] systemd[1]: Starting Network Name Resolution...1385test # [ 7.846232] systemd-journald[321]: Received client request to flush runtime journal.1386second # [ 7.863432] systemd[1]: Mounted /run/wrappers.1387second # [ 7.864811] systemd[1]: Reached target Local File Systems.1388second # [ 7.872630] systemd[1]: Listening on Boot Loader Control Service Socket.1389second # [ 7.886761] systemd[1]: Starting register-nix-paths.service...1390second # [ 7.890831] systemd[1]: Starting Create SUID/SGID Wrappers...1391second # [ 7.891624] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1392test # [ 7.920365] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1393test # [ 7.926015] systemd[1]: Finished Load/Save OS Random Seed.1394second # [ 7.903504] systemd-resolved[331]: Using system hostname 'second'.1395test # [ 7.930309] systemd[1]: Reached target First Boot Complete.1396test # [ 7.934897] systemd[1]: Starting Create Static Device Nodes in /dev...1397test # [ 7.938491] systemd[1]: Finished Flush Journal to Persistent Storage.1398second # [ 7.930620] systemd[1]: Starting Save Transient machine-id to Disk...1399second # [ 7.951907] systemd[1]: Starting Create System Files and Directories...1400second # [ 7.956134] systemd[1]: Started Network Name Resolution.1401test # [ 8.009731] systemd[1]: Finished Create Static Device Nodes in /dev.1402test # [ 8.010655] systemd[1]: Reached target Preparation for Local File Systems.1403test # [ 8.018816] systemd[1]: Starting Rule-based Manager for Device Events and Files...1404test # [ 8.030117] systemd-resolved[351]: Positive Trust Anchors:1405test # [ 8.032486] systemd-resolved[351]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1406test # [ 8.034341] systemd-resolved[351]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161407test # [ 8.040408] systemd-resolved[351]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1408second # [ 8.089386] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1409second # [ 8.095146] systemd[1]: Finished Save Transient machine-id to Disk.1410test # [ 8.156337] systemd-udevd[364]: Using default interface naming scheme 'v260'.1411second # [ 8.150156] systemd[1]: Finished Create System Files and Directories.1412second # [ 8.161405] systemd[1]: Starting Rebuild Journal Catalog...1413test # [ 8.191993] systemd[1]: Mounting /run/wrappers...1414second # [ 8.186758] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1415second # [ 8.197589] systemd[1]: Started Rule-based Manager for Device Events and Files.1416test # [ 8.269917] systemd[1]: Mounted /run/wrappers.1417test # [ 8.271540] systemd[1]: Reached target Local File Systems.1418test # [ 8.281147] systemd[1]: Listening on Boot Loader Control Service Socket.1419test # [ 8.287306] systemd[1]: Starting register-nix-paths.service...1420test # [ 8.290576] systemd[1]: Starting Create SUID/SGID Wrappers...1421test # [ 8.293925] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1422test # [ 8.303211] systemd-resolved[351]: Using system hostname 'test'.1423test # [ 8.314501] systemd[1]: Starting Save Transient machine-id to Disk...1424test # [ 8.350296] systemd[1]: Starting Create System Files and Directories...1425test # [ 8.353352] systemd[1]: Started Network Name Resolution.1426second # [ 8.337576] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1427second # [ 8.400267] systemd[1]: Finished Rebuild Journal Catalog.1428second # [ 8.415690] systemd[1]: Starting Update is Completed...1429test # [ 8.470996] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1430test # [ 8.482051] systemd[1]: Finished Save Transient machine-id to Disk.1431second # [ 8.499094] systemd[1]: Finished Update is Completed.1432test # [ 8.576239] systemd[1]: Finished Create System Files and Directories.1433test # [ 8.592857] systemd[1]: Starting Rebuild Journal Catalog...1434test # [ 8.604486] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1435test # [ 8.609501] systemd[1]: Started Rule-based Manager for Device Events and Files.1436second # [ 8.655907] systemd[1]: Finished Coldplug All udev Devices.1437test # [ 8.771853] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1438second # [ 8.809992] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1439test # [ 8.857232] systemd[1]: Finished Rebuild Journal Catalog.1440test # [ 8.874280] systemd[1]: Starting Update is Completed...1441test # [ 8.971588] systemd[1]: Finished Update is Completed.1442test # [ 8.986834] systemd[1]: Finished Coldplug All udev Devices.1443second # [ 9.157396] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1444test # [ 9.186063] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1445second # [ 9.166480] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1446second # [ 9.170117] systemd[1]: Finished Create SUID/SGID Wrappers.1447second # [ 9.205244] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1448second # [ 9.375052] systemd[1]: Finished register-nix-paths.service.1449second # [ 9.376687] systemd[1]: Reached target System Initialization.1450second # [ 9.380291] systemd[1]: Started Discard unused filesystem blocks once a week.1451second # [ 9.382013] systemd[1]: Started Daily Cleanup of Temporary Directories.1452second # [ 9.385690] systemd[1]: Reached target Timer Units.1453second # [ 9.386821] systemd[1]: Listening on D-Bus System Message Bus Socket.1454second # [ 9.389220] systemd[1]: Listening on Nix Daemon Socket.1455second # [ 9.394169] systemd[1]: Listening on Authorization Manager Agent Helper.1456second # [ 9.397923] systemd[1]: Listening on Hostname Service Socket.1457second # [ 9.398491] systemd[1]: Reached target Socket Units.1458second # [ 9.404205] systemd[1]: Starting D-Bus System Message Bus...1459test # [ 9.493400] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1460second # [ 9.479195] systemd[1]: Finished Firewall.1461second # [ 9.480467] systemd[1]: Reached target Preparation for Network.1462second # [ 9.489454] systemd[1]: Starting Network Management...1463test # [ 9.552409] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1464test # [ 9.553965] systemd[1]: Finished Create SUID/SGID Wrappers.1465second # [ 9.552416] (udev-worker)[395]: Network interface NamePolicy= disabled on kernel command line.1466second # [ 9.566564] (udev-worker)[408]: Network interface NamePolicy= disabled on kernel command line.1467test # [ 9.609330] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1468second # [ 9.610745] dbus-broker-launch[529]: Looking up NSS user entry for 'systemd-timesync'...1469second # [ 9.632812] dbus-broker-launch[529]: NSS returned no entry for 'systemd-timesync'1470second # [ 9.633693] dbus-broker-launch[529]: Invalid user-name in /nix/store/ic595frpbmssfh2fplsqwnwp7a99rqks-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1471test # [ 9.716671] systemd[1]: Finished register-nix-paths.service.1472test # [ 9.717372] systemd[1]: Reached target System Initialization.1473second # [ 9.693711] dbus-broker-launch[529]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1474test # [ 9.718699] systemd[1]: Started Discard unused filesystem blocks once a week.1475test # [ 9.723579] systemd[1]: Started Daily Cleanup of Temporary Directories.1476second # [ 9.700194] dbus-broker-launch[529]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1477test # [ 9.726217] systemd[1]: Reached target Timer Units.1478test # [ 9.728981] systemd[1]: Listening on D-Bus System Message Bus Socket.1479test # [ 9.730696] systemd[1]: Listening on Nix Daemon Socket.1480second # [ 9.708269] dbus-broker-launch[529]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/wrvznzg05dkx9ia8dwkg7f4i04nhv3v6-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1481test # [ 9.735758] systemd[1]: Listening on Authorization Manager Agent Helper.1482test # [ 9.738245] systemd[1]: Listening on Hostname Service Socket.1483test # [ 9.739564] systemd[1]: Reached target Socket Units.1484test # [ 9.743114] systemd[1]: Starting D-Bus System Message Bus...1485second # [ 9.724504] dbus-broker-launch[529]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/wns3agiik86mwy41ad6z21sjzqgla78w-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1486second # [ 9.726164] systemd[1]: Started D-Bus System Message Bus.1487second # [ 9.748470] cfg80211: Loading compiled-in X.509 certificates for regulatory database1488test # [ 9.802634] systemd[1]: Finished Firewall.1489test # [ 9.803507] systemd[1]: Reached target Preparation for Network.1490second # [ 9.783743] dbus-broker-launch[529]: Ready1491test # [ 9.809801] systemd[1]: Starting Network Management...1492second # [ 9.789673] systemd[1]: Reached target Basic System.1493second # [ 9.796914] systemd[1]: Starting Network Manager Script Dispatcher Service...1494second # [ 9.807154] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1495second # [ 9.807698] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1496second # [ 9.809796] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1497second # [ 9.821935] systemd[1]: Starting Network Manager...1498second # [ 9.838616] systemd[1]: Started backdoor.service.1499second # [ 9.865640] systemd[1]: Starting Import lastlog data into lastlog2 database...1500second # [ 9.884942] systemd-networkd[536]: /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.1501second # [ 9.896284] systemd-networkd[536]: /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.1502test # [ 9.922663] (udev-worker)[415]: Network interface NamePolicy= disabled on kernel command line.1503second # [ 9.903993] systemd-networkd[536]: lo: Link UP1504test # [ 9.927777] (udev-worker)[422]: Network interface NamePolicy= disabled on kernel command line.1505test # [ 9.933042] dbus-broker-launch[549]: Looking up NSS user entry for 'systemd-timesync'...1506second # [ 9.911395] systemd-networkd[536]: lo: Gained carrier1507second # [ 9.915603] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1508second # [ 9.922592] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1509test # [ 9.946135] dbus-broker-launch[549]: NSS returned no entry for 'systemd-timesync'1510second # [ 9.923916] systemd-networkd[536]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1511test # [ 9.948561] dbus-broker-launch[549]: Invalid user-name in /nix/store/ic595frpbmssfh2fplsqwnwp7a99rqks-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1512second # [ 9.930305] systemd-networkd[536]: eth1: Link UP1513second # [ 9.932690] systemd-networkd[536]: eth1: Gained carrier1514second # [ 9.933312] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1515second # [ 9.949567] mousedev: PS/2 mouse device common for all mice1516second # [ 9.967075] systemd[1]: Starting Post-Boot Actions...1517test # [ 10.012095] dbus-broker-launch[549]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1518second # [ 10.002298] izfdm2vcilqki06lkkfdqg1q1s7gnwp8-wifi-secrets[547]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1519test # [ 10.019054] dbus-broker-launch[549]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1520test # [ 10.032430] dbus-broker-launch[549]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/wrvznzg05dkx9ia8dwkg7f4i04nhv3v6-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1521test # [ 10.034297] dbus-broker-launch[549]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/wns3agiik86mwy41ad6z21sjzqgla78w-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1522test # [ 10.035914] systemd[1]: Started D-Bus System Message Bus.1523second # [ 10.018774] systemd[1]: Started Reset console on configuration changes.1524second # [ 10.038137] systemd[1]: Started Network Management.1525test # [ 10.068742] cfg80211: Loading compiled-in X.509 certificates for regulatory database1526second # [ 10.056178] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1527second # [ 10.074458] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1528test # [ 10.103616] dbus-broker-launch[549]: Ready1529test # [ 10.109774] systemd[1]: Reached target Basic System.1530test # [ 10.114472] systemd[1]: Starting Network Manager Script Dispatcher Service...1531test # [ 10.123322] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1532test # [ 10.125190] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1533test # [ 10.121506] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1534test # [ 10.124740] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1535second # [ 10.118494] systemd[1]: Finished Post-Boot Actions.1536test # [ 10.156523] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1537second # connecting to host...1538test # [ 10.173370] systemd[1]: Starting Network Manager...1539second # [ 10.180280] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1540test # [ 10.214873] systemd[1]: Started backdoor.service.1541test # [ 10.221899] systemd-networkd[555]: /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.1542test # [ 10.232652] systemd-networkd[555]: /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.1543second # [ 10.222118] nsncd[551]: Sep 10 20:08:51.992 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1544test # [ 10.243724] systemd-networkd[555]: lo: Link UP1545second # [ 10.223384] systemd[1]: Started Name Service Cache Daemon (nsncd).1546test # [ 10.248180] systemd-networkd[555]: lo: Gained carrier1547second # [ 10.231069] systemd[1]: Reached target Host and Network Name Lookups.1548test # [ 10.256400] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1549second # [ 10.234606] systemd[1]: Reached target User and Group Name Lookups.1550test # [ 10.267893] systemd-networkd[555]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1551second # [ 10.250779] systemd[1]: Starting User Login Management...1552second # [ 10.252307] systemd[1]: Finished Import lastlog data into lastlog2 database.1553second # [ 10.255434] systemd[1]: Started Network Manager Script Dispatcher Service.1554test # [ 10.278691] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1555test # [ 10.294838] systemd-networkd[555]: eth1: Link UP1556test # [ 10.303052] ispsphlnyb2js715g0mg6bn3157dmskk-wifi-secrets[567]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1557test # [ 10.314339] izfdm2vcilqki06lkkfdqg1q1s7gnwp8-wifi-secrets[564]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1558test # [ 10.325482] systemd-networkd[555]: eth1: Gained carrier1559test # [ 10.331887] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network[ 10.338806] mousedev: PS/2 mouse device common for all mice1560test # 1561second # [ 10.321232] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1562test # [ 10.346720] systemd[1]: Starting Import lastlog data into lastlog2 database...1563test # [ 10.353254] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1564test # [ 10.361870] systemd[1]: Starting Post-Boot Actions...1565test # [ 10.368381] vz5k34ilhns09vh81dvgw89nvkf1plwv-wifi-secrets[568]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1566second # [ 10.372547] systemd[1]: Starting Hostname Service...1567test # [ 10.415199] systemd[1]: Started Reset console on configuration changes.1568second # [ 10.416107] systemd-logind[585]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1569second # [ 10.419046] systemd-logind[585]: Watching system buttons on /dev/input/event0 (gpio-keys)1570second # [ 10.421805] systemd-logind[585]: New seat seat0.1571test # [ 10.446841] systemd[1]: Started Network Management.1572test # connecting to host...1573second # [ 10.427323] systemd[1]: Started User Login Management.1574second # [ 10.433433] systemd[1]: Starting linger-users.service...1575test # [ 10.477127] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1576test # [ 10.483449] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1577test # [ 10.491746] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1578test # [ 10.501562] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1579test # [ 10.508433] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1580test # [ 10.518556] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1581test: Guest shell says: b'Spawning backdoor root shell...\n'1582test: connected to guest root shell1583test: (connecting took 11.11 seconds)1584test: (finished: waiting for the VM to finish booting, in 11.11 seconds)1585test # [ 10.552097] systemd[1]: Finished Post-Boot Actions.1586second # [ 10.515419] systemd[1]: linger-users.service: Deactivated successfully.1587second # [ 10.516115] systemd[1]: Finished linger-users.service.1588second # [ 10.519528] systemd[1]: Started Hostname Service.1589test # [ 10.596638] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1590test # [ 10.600880] systemd[1]: Started Network Manager Script Dispatcher Service.1591test # [ 10.635513] systemd[1]: Finished Import lastlog data into lastlog2 database.1592test # [ 10.639677] systemd[1]: Started Name Service Cache Daemon (nsncd).1593test # [ 10.645349] nsncd[579]: Sep 10 20:08:52.408 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1594test # [ 10.646616] systemd[1]: Reached target Host and Network Name Lookups.1595test # [ 10.647164] systemd[1]: Reached target User and Group Name Lookups.1596second # [ 10.633268] systemd-networkd[536]: eth0: Link UP1597second # [ 10.633772] systemd-networkd[536]: eth0: Gained carrier1598test # [ 10.661185] systemd[1]: Starting User Login Management...1599second # [ 10.654033] systemd[1]: Started Network Manager.1600second # [ 10.657674] systemd[1]: Reached target Network.1601second # [ 10.663040] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1602second # [ 10.669916] systemd[1]: Starting Permit User Sessions...1603test # [ 10.763390] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1604test # [ 10.786726] systemd[1]: Starting Hostname Service...1605test # [ 10.824998] systemd-logind[622]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1606test # [ 10.826353] systemd-logind[622]: Watching system buttons on /dev/input/event0 (gpio-keys)1607test # [ 10.833012] systemd-logind[622]: New seat seat0.1608test # [ 10.833538] systemd[1]: Started User Login Management.1609test # [ 10.842855] systemd[1]: Starting linger-users.service...1610second # [ 10.839091] systemd[1]: Finished Permit User Sessions.1611second # [ 10.851967] systemd[1]: Started Getty on tty1.1612second # [ 10.854842] systemd[1]: Reached target Login Prompts.1613second # [ 10.887496] systemd-resolved[331]: eth0: Bus client set default route setting: yes1614test # [ 10.914305] systemd[1]: linger-users.service: Deactivated successfully.1615test # [ 10.916460] systemd[1]: Finished linger-users.service.1616second # [ 10.891860] systemd-resolved[331]: eth0: Bus client set DNS server list to: 10.0.2.31617test # [ 10.927557] systemd[1]: Started Hostname Service.1618second # [ 10.959623] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1619second # [ 10.964152] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1620test # [ 11.030934] systemd-networkd[555]: eth0: Link UP1621test # [ 11.031434] systemd-networkd[555]: eth0: Gained carrier1622test # [ 11.054044] systemd[1]: Started Network Manager.1623test # [ 11.055408] systemd[1]: Reached target Network.1624test # [ 11.064054] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1625test # [ 11.070091] systemd[1]: Starting Permit User Sessions...1626second # [ 11.095399] systemd[1]: Reached target Multi-User System.1627second # [ 11.099443] systemd[1]: Startup finished in 780ms (kernel) + 4.045s (initrd) + 6.272s (userspace) = 11.098s.1628test # [ 11.220145] systemd[1]: Finished Permit User Sessions.1629test # [ 11.238744] systemd[1]: Started Getty on tty1.1630test # [ 11.239236] systemd[1]: Reached target Login Prompts.1631test # [ 11.265862] systemd-resolved[351]: eth0: Bus client set default route setting: yes1632test # [ 11.271650] systemd-resolved[351]: eth0: Bus client set DNS server list to: 10.0.2.31633test # [ 11.280517] NetworkManager[569]: <warn> [1789070933.0500] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1634test # [ 11.283762] NetworkManager[569]: <warn> [1789070933.0513] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1635test # [ 11.345450] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1636test # [ 11.347129] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1637test # [ 11.355586] systemd[1]: Reached target Multi-User System.1638test # [ 11.369431] systemd[1]: Startup finished in 780ms (kernel) + 4.290s (initrd) + 6.298s (userspace) = 11.368s.1639test # [ 11.457442] systemd-networkd[555]: eth1: Gained IPv6LL1640second # [ 11.635353] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31641test: (finished: waiting for unit NetworkManager.service, in 12.39 seconds)1642test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1643test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.05 seconds)1644cleanup1645kill QemuMachine (pid 15)1646second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/akbq19lqha5w5h9hcchn309znqm94pq0-python3-3.13.15/bin/python3.13)1647kill QemuMachine (pid 14)1648test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/akbq19lqha5w5h9hcchn309znqm94pq0-python3-3.13.15/bin/python3.13)1649(finished: cleanup, in 0.26 seconds)1650Traceback (most recent call last):1651 File "/nix/store/g9529d3bpfr38l0hs56ri15djwx9bi06-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in <module>1652 sys.exit(main())1653 ~~~~^^1654 File "/nix/store/g9529d3bpfr38l0hs56ri15djwx9bi06-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/__init__.py", line 183, in main1655 driver.run_tests()1656 ~~~~~~~~~~~~~~~~^^1657 File "/nix/store/g9529d3bpfr38l0hs56ri15djwx9bi06-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/driver.py", line 434, in run_tests1658 self.test_script()1659 ~~~~~~~~~~~~~~~~^^1660 File "/nix/store/g9529d3bpfr38l0hs56ri15djwx9bi06-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/driver.py", line 392, in test_script1661 exec(self.tests, symbols, None)1662 ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^1663 File "<string>", line 4, in <module>1664AssertionError: Expected mock password in connection file:1665[connection]1666autoconnect=true1667id=1668type=wifi16691670[wifi]1671mode=infrastructure1672ssid=16731674[wifi-security]1675key-mgmt=wpa-psk1676psk=1677