vm-test-run-wifi
checks.aarch64-linux.wifi
· build #508
· 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 15)15test # Formatting '/build/vm-state-test/tmp.nSv0tIDxJL', fmt=raw size=107374182416second: QEMU running (pid 14)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.1WUCwsWGKS', fmt=raw size=107374182421(finished: start all VMs, in 0.47 seconds)22second # mke2fs 1.47.4 (6-Mar-2025)23test: waiting for unit NetworkManager.service24second # Discarding device blocks: 0/262144 done25test: waiting for the VM to finish booting26second # Creating filesystem with 262144 4k blocks and 65536 inodes27test # Creating filesystem with 262144 4k blocks and 65536 inodes28second # Filesystem UUID: 7b0b8769-944d-45f8-b51e-a6399017773129test # Filesystem UUID: 5b37b49a-3366-48d9-b441-f34c184c312d30second # Superblock backups stored on blocks:31second # 32768, 98304, 163840, 22937632test # Superblock backups stored on blocks:33second # 34test # 32768, 98304, 163840, 22937635second # Allocating group tables: 0/8 done36test # 37second # Writing inode tables: 0/8 done38test # Allocating group tables: 0/8 done39second # Creating journal (8192 blocks): done40second # Writing superblocks and filesystem accounting information: 0/8 done41test # Writing inode tables: 0/8 done42second # 43test # Creating journal (8192 blocks): done44second # Virtualisation disk image created.45test # Writing superblocks and filesystem accounting information: 0/8 done46test # 47test # Virtualisation disk image created.48test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]49test # [ 0.000000] Linux version 6.18.46 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sun Aug 23 12:27:10 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 node63second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]64test # [ 0.000000] Early memory node ranges65test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]66second # [ 0.000000] Linux version 6.18.46 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sun Aug 23 12:27:10 UTC 202667test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]68test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000069test # [ 0.000000] psci: probing for conduit method from DT.70test # [ 0.000000] psci: PSCIv1.3 detected in firmware.71test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs72test # [ 0.000000] psci: Trusted OS migration not required73test # [ 0.000000] psci: SMC Calling Convention v1.174test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)75second # [ 0.000000] KASLR enabled76second # [ 0.000000] random: crng init done77second # [ 0.000000] Machine model: linux,dummy-virt78second # [ 0.000000] efi: UEFI not found.79second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT80second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]81second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]82second # [ 0.000000] Zone ranges:83second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]84second # [ 0.000000] DMA32 empty85second # [ 0.000000] Normal empty86test # [ 0.000000] percpu: Embedded 76 pages/cpu s186328 r8192 d116776 u31129687second # [ 0.000000] Device empty88test # [ 0.000000] Detected PIPT I-cache on CPU089second # [ 0.000000] Movable zone start for each node90test # [ 0.000000] CPU features: detected: GICv3 CPU interface91second # [ 0.000000] Early memory node ranges92test # [ 0.000000] CPU features: detected: Spectre-v493second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]94test # [ 0.000000] CPU features: detected: Spectre-BHB95second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]96test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR97second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000098test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)99second # [ 0.000000] psci: probing for conduit method from DT.100test # [ 0.000000] CPU features: detected: ARM erratum 1418040101second # [ 0.000000] psci: PSCIv1.3 detected in firmware.102second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs103test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion104second # [ 0.000000] psci: Trusted OS migration not required105test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing106second # [ 0.000000] psci: SMC Calling Convention v1.1107test # [ 0.000000] alternatives: applying boot alternatives108second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)109second # [ 0.000000] percpu: Embedded 76 pages/cpu s186328 r8192 d116776 u311296110second # [ 0.000000] Detected PIPT I-cache on CPU0111second # [ 0.000000] CPU features: detected: GICv3 CPU interface112second # [ 0.000000] CPU features: detected: Spectre-v4113second # [ 0.000000] CPU features: detected: Spectre-BHB114test # [ 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/y96zhkz48qsvywh9rkrifnaazcrdxp97-nixos-system-test-test/init regInfo=/nix/store/a38y71wf0zlcd5lz42fpjw6qf5hyrwip-closure-info/registration console=ttyAMA0,115200n8 console=tty0115second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR116second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)117test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/a38y71wf0zlcd5lz42fpjw6qf5hyrwip-closure-info/registration", will be passed to user space.118second # [ 0.000000] CPU features: detected: ARM erratum 1418040119test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes120second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion121test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)122second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing123second # [ 0.000000] alternatives: applying boot alternatives124test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)125test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB126test # [ 0.000000] software IO TLB: area num 1.127test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)128test # [ 0.000000] Fallback order for Node 0: 0129test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144130second # [ 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/ji2p1f7bbq8jjvvlp672g6nd60hgywna-nixos-system-second-test/init regInfo=/nix/store/hvifln2c95qlxfyfml346fgxqwzvfabj-closure-info/registration console=ttyAMA0,115200n8 console=tty0131test # [ 0.000000] Policy zone: DMA132test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off133second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/hvifln2c95qlxfyfml346fgxqwzvfabj-closure-info/registration", will be passed to user space.134test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1135test # [ 0.000000] allocated 2097152 bytes of page_ext136second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes137test # [ 0.000000] ftrace: allocating 74759 entries in 294 pages138second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)139test # [ 0.000000] ftrace: allocated 294 pages with 4 groups140test # [ 0.000000] rcu: Hierarchical RCU implementation.141second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)142test # [ 0.000000] rcu: RCU event tracing is enabled.143second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB144test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.145second # [ 0.000000] software IO TLB: area num 1.146test # [ 0.000000] Trampoline variant of Tasks RCU enabled.147second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)148test # [ 0.000000] Rude variant of Tasks RCU enabled.149second # [ 0.000000] Fallback order for Node 0: 0150test # [ 0.000000] Tracing variant of Tasks RCU enabled.151second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144152test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.153second # [ 0.000000] Policy zone: DMA154second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off155test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1156second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1157test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.158second # [ 0.000000] allocated 2097152 bytes of page_ext159second # [ 0.000000] ftrace: allocating 74759 entries in 294 pages160test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.161second # [ 0.000000] ftrace: allocated 294 pages with 4 groups162second # [ 0.000000] rcu: Hierarchical RCU implementation.163test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.164second # [ 0.000000] rcu: RCU event tracing is enabled.165test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0166test # [ 0.000000] GICv3: 256 SPIs implemented167second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.168test # [ 0.000000] GICv3: 0 Extended SPIs implemented169second # [ 0.000000] Trampoline variant of Tasks RCU enabled.170test # [ 0.000000] Root IRQ handler: gic_handle_irq171second # [ 0.000000] Rude variant of Tasks RCU enabled.172test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI173second # [ 0.000000] Tracing variant of Tasks RCU enabled.174test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0175second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.176test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000177second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1178test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]179second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.180test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)181second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.182test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)183test # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000184second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.185second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0186test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000187second # [ 0.000000] GICv3: 256 SPIs implemented188test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.189second # [ 0.000000] GICv3: 0 Extended SPIs implemented190second # [ 0.000000] Root IRQ handler: gic_handle_irq191test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns192second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI193second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0194test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040195second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000196test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).197second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]198test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns199second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)200test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns201test # [ 0.000059] arm-pv: using stolen time PV202second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)203second # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000204test # [ 0.000325] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)205test # [ 0.000556] Console: colour dummy device 80x25206second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000207test # [ 0.000572] printk: legacy console [tty0] enabled208second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.209test # [ 0.000775] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)210second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns211test # [ 0.000780] pid_max: default: 32768 minimum: 301212second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040213test # [ 0.000858] LSM: initializing lsm=capability,landlock,yama,bpf,ima214second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).215test # [ 0.000967] landlock: Up and running.216test # [ 0.000969] Yama: becoming mindful.217test # [ 0.001277] LSM support for eBPF active218second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns219second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns220test # [ 0.001419] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)221second # [ 0.000064] arm-pv: using stolen time PV222test # [ 0.001442] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)223second # [ 0.000489] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)224test # [ 0.003246] cacheinfo: Unable to detect cache hierarchy for CPU 0225second # [ 0.000762] Console: colour dummy device 80x25226test # [ 0.003960] rcu: Hierarchical SRCU implementation.227second # [ 0.000779] printk: legacy console [tty0] enabled228test # [ 0.003965] rcu: Max phase no-delay instances is 1000.229test # [ 0.004984] fsl-mc MSI: its@8080000 domain created230second # [ 0.000991] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)231test # [ 0.005138] EFI services will not be available.232second # [ 0.000995] pid_max: default: 32768 minimum: 301233test # [ 0.005262] smp: Bringing up secondary CPUs ...234second # [ 0.001068] LSM: initializing lsm=capability,landlock,yama,bpf,ima235test # [ 0.005288] smp: Brought up 1 node, 1 CPU236second # [ 0.001202] landlock: Up and running.237test # [ 0.005291] SMP: Total of 1 processors activated.238second # [ 0.001204] Yama: becoming mindful.239test # [ 0.005293] CPU: All CPU(s) started at EL1240second # [ 0.001597] LSM support for eBPF active241test # [ 0.005303] CPU features: detected: 32-bit EL0 Support242second # [ 0.001757] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)243test # [ 0.005306] CPU features: detected: Data cache clean to the PoU not required for I/D coherence244second # [ 0.001777] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)245test # [ 0.005308] CPU features: detected: Common not Private translations246second # [ 0.003610] cacheinfo: Unable to detect cache hierarchy for CPU 0247test # [ 0.005310] CPU features: detected: CRC32 instructions248second # [ 0.004430] rcu: Hierarchical SRCU implementation.249test # [ 0.005312] CPU features: detected: Data cache clean to Point of Persistence250second # [ 0.004435] rcu: Max phase no-delay instances is 1000.251second # [ 0.005352] fsl-mc MSI: its@8080000 domain created252test # [ 0.005315] CPU features: detected: Enhanced Virtualization Traps253second # [ 0.005491] EFI services will not be available.254test # [ 0.005325] CPU features: detected: RCpc load-acquire (LDAPR)255second # [ 0.005567] smp: Bringing up secondary CPUs ...256test # [ 0.005327] CPU features: detected: LSE atomic instructions257second # [ 0.005592] smp: Brought up 1 node, 1 CPU258second # [ 0.005594] SMP: Total of 1 processors activated.259test # [ 0.005329] CPU features: detected: Privileged Access Never260second # [ 0.005596] CPU: All CPU(s) started at EL1261test # [ 0.005330] CPU features: detected: PMUv3262second # [ 0.005607] CPU features: detected: 32-bit EL0 Support263test # [ 0.005332] CPU features: detected: RAS Extension Support264test # [ 0.005336] CPU features: detected: Speculative Store Bypassing Safe (SSBS)265second # [ 0.005609] CPU features: detected: Data cache clean to the PoU not required for I/D coherence266test # [ 0.005355] alternatives: applying system-wide alternatives267second # [ 0.005612] CPU features: detected: Common not Private translations268test # [ 0.006679] CPU features: detected: Hardware dirty bit management on CPU0269second # [ 0.005614] CPU features: detected: CRC32 instructions270second # [ 0.005616] CPU features: detected: Data cache clean to Point of Persistence271second # [ 0.005618] CPU features: detected: Enhanced Virtualization Traps272test # [ 0.006853] Memory: 892988K/1048576K available (24320K kernel code, 7086K rwdata, 26340K rodata, 4736K init, 1103K bss, 114136K reserved, 32768K cma-reserved)273test # [ 0.007320] devtmpfs: initialized274second # [ 0.005627] CPU features: detected: RCpc load-acquire (LDAPR)275test # [ 0.008814] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)276second # [ 0.005629] CPU features: detected: LSE atomic instructions277second # [ 0.005631] CPU features: detected: Privileged Access Never278test # [ 0.008837] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).279second # [ 0.005633] CPU features: detected: PMUv3280test # [ 0.008962] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL281second # [ 0.005634] CPU features: detected: RAS Extension Support282test # [ 0.008966] 0 pages in range for non-PLT usage283second # [ 0.005638] CPU features: detected: Speculative Store Bypassing Safe (SSBS)284test # [ 0.008967] 508336 pages in range for PLT usage285second # [ 0.005668] alternatives: applying system-wide alternatives286test # [ 0.009101] pinctrl core: initialized pinctrl subsystem287test # [ 0.009886] DMI not present or invalid.288second # [ 0.006982] CPU features: detected: Hardware dirty bit management on CPU0289test # [ 0.013440] NET: Registered PF_NETLINK/PF_ROUTE protocol family290test # [ 0.015793] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations291second # [ 0.007130] Memory: 893132K/1048576K available (24320K kernel code, 7086K rwdata, 26340K rodata, 4736K init, 1103K bss, 114136K reserved, 32768K cma-reserved)292second # [ 0.007616] devtmpfs: initialized293test # [ 0.015949] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations294second # [ 0.009083] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)295test # [ 0.016119] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations296test # [ 0.016135] audit: initializing netlink subsys (disabled)297second # [ 0.009106] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).298test # [ 0.016684] thermal_sys: Registered thermal governor 'fair_share'299second # [ 0.009223] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL300second # [ 0.009226] 0 pages in range for non-PLT usage301test # [ 0.016687] thermal_sys: Registered thermal governor 'bang_bang'302second # [ 0.009227] 508336 pages in range for PLT usage303test # [ 0.016690] thermal_sys: Registered thermal governor 'step_wise'304second # [ 0.009353] pinctrl core: initialized pinctrl subsystem305test # [ 0.016692] thermal_sys: Registered thermal governor 'user_space'306second # [ 0.010107] DMI not present or invalid.307test # [ 0.016694] thermal_sys: Registered thermal governor 'power_allocator'308second # [ 0.013231] NET: Registered PF_NETLINK/PF_ROUTE protocol family309test # [ 0.016716] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1310second # [ 0.015758] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations311test # [ 0.016729] cpuidle: using governor ladder312second # [ 0.015909] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations313test # [ 0.016734] cpuidle: using governor menu314test # [ 0.016837] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.315test # [ 0.016856] ASID allocator initialised with 32768 entries316second # [ 0.016104] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations317test # [ 0.017504] Serial: AMBA PL011 UART driver318second # [ 0.016120] audit: initializing netlink subsys (disabled)319second # [ 0.016642] thermal_sys: Registered thermal governor 'fair_share'320test # [ 0.020626] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1321test # [ 0.020749] printk: console [ttyAMA0] enabled322second # [ 0.016644] thermal_sys: Registered thermal governor 'bang_bang'323second # [ 0.016647] thermal_sys: Registered thermal governor 'step_wise'324second # [ 0.016649] thermal_sys: Registered thermal governor 'user_space'325second # [ 0.016651] thermal_sys: Registered thermal governor 'power_allocator'326second # [ 0.016673] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1327second # [ 0.016687] cpuidle: using governor ladder328second # [ 0.016692] cpuidle: using governor menu329second # [ 0.016794] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.330second # [ 0.016812] ASID allocator initialised with 32768 entries331second # [ 0.017494] Serial: AMBA PL011 UART driver332second # [ 0.020514] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1333second # [ 0.020671] printk: console [ttyAMA0] enabled334test # [ 0.094428] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages335test # [ 0.094449] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page336test # [ 0.094455] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages337test # [ 0.094458] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page338test # [ 0.094462] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages339test # [ 0.094465] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page340test # [ 0.094468] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages341test # [ 0.094471] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page342second # [ 0.093143] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages343second # [ 0.093162] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page344test # [ 0.099456] fbcon: Taking over console345second # [ 0.093168] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages346test # [ 0.099474] ACPI: Interpreter disabled.347test # [ 0.100787] iommu: Default domain type: Translated348second # [ 0.093172] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page349test # [ 0.100798] iommu: DMA domain TLB invalidation policy: strict mode350second # [ 0.093175] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages351second # [ 0.093178] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page352test # [ 0.101943] SCSI subsystem initialized353second # [ 0.093181] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages354second # [ 0.093184] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page355second # [ 0.098213] fbcon: Taking over console356second # [ 0.098229] ACPI: Interpreter disabled.357second # [ 0.099445] iommu: Default domain type: Translated358second # [ 0.099455] iommu: DMA domain TLB invalidation policy: strict mode359second # [ 0.100690] SCSI subsystem initialized360test # [ 0.106750] usbcore: registered new interface driver usbfs361second # [ 0.101274] usbcore: registered new interface driver usbfs362test # [ 0.106789] usbcore: registered new interface driver hub363second # [ 0.101310] usbcore: registered new interface driver hub364test # [ 0.106812] usbcore: registered new device driver usb365second # [ 0.101331] usbcore: registered new device driver usb366test # [ 0.107005] pps_core: LinuxPPS API ver. 1 registered367second # [ 0.102797] pps_core: LinuxPPS API ver. 1 registered368test # [ 0.107012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>369test # [ 0.107018] PTP clock support registered370second # [ 0.102810] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>371test # [ 0.107050] EDAC MC: Ver: 3.0.0372second # [ 0.102816] PTP clock support registered373second # [ 0.102845] EDAC MC: Ver: 3.0.0374test # [ 0.110074] scmi_core: SCMI protocol bus registered375test # [ 0.110868] FPGA manager framework376test # [ 0.111872] vgaarb: loaded377test # [ 0.112267] clocksource: Switched to clocksource arch_sys_counter378second # [ 0.109432] scmi_core: SCMI protocol bus registered379second # [ 0.110152] FPGA manager framework380second # [ 0.111122] vgaarb: loaded381second # [ 0.111533] clocksource: Switched to clocksource arch_sys_counter382test # [ 0.122099] VFS: Disk quotas dquot_6.6.0383test # [ 0.122137] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)384test # [ 0.124559] netfs: FS-Cache loaded385test # [ 0.124687] pnp: PnP ACPI: disabled386second # [ 0.121429] VFS: Disk quotas dquot_6.6.0387second # [ 0.121465] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)388test # [ 0.127077] NET: Registered PF_INET protocol family389test # [ 0.127277] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)390second # [ 0.123743] netfs: FS-Cache loaded391second # [ 0.123858] pnp: PnP ACPI: disabled392second # [ 0.126219] NET: Registered PF_INET protocol family393second # [ 0.126399] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)394test # [ 0.152126] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)395test # [ 0.152154] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)396test # [ 0.152166] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)397test # [ 0.152205] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)398second # [ 0.151373] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)399second # [ 0.151398] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)400second # [ 0.151410] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)401second # [ 0.151446] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)402second # [ 0.151513] TCP: Hash tables configured (established 8192 bind 8192)403test # [ 0.154784] TCP: Hash tables configured (established 8192 bind 8192)404test # [ 0.154888] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)405test # [ 0.154948] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)406test # [ 0.155009] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)407test # [ 0.155151] NET: Registered PF_UNIX/PF_LOCAL protocol family408test # [ 0.155199] NET: Registered PF_XDP protocol family409test # [ 0.155217] PCI: CLS 0 bytes, default 64410test # [ 0.155422] Trying to unpack rootfs image as initramfs...411test # [ 0.163953] kvm [1]: HYP mode not available412second # [ 0.154484] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)413second # [ 0.154550] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)414second # [ 0.154604] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)415second # [ 0.154733] NET: Registered PF_UNIX/PF_LOCAL protocol family416second # [ 0.154765] NET: Registered PF_XDP protocol family417second # [ 0.154780] PCI: CLS 0 bytes, default 64418second # [ 0.154979] Trying to unpack rootfs image as initramfs...419second # [ 0.166671] kvm [1]: HYP mode not available420test # [ 0.229414] Initialise system trusted keyrings421test # [ 0.229933] workingset: timestamp_bits=42 max_order=18 bucket_order=0422test # [ 0.230871] squashfs: version 4.0 (2009/01/31) Phillip Lougher423test # [ 0.231402] 9p: Installing v9fs 9p2000 file system support424second # [ 0.231882] Initialise system trusted keyrings425second # [ 0.232416] workingset: timestamp_bits=42 max_order=18 bucket_order=0426second # [ 0.233354] squashfs: version 4.0 (2009/01/31) Phillip Lougher427second # [ 0.233895] 9p: Installing v9fs 9p2000 file system support428test # [ 0.250767] Key type asymmetric registered429test # [ 0.250789] Asymmetric key parser 'x509' registered430test # [ 0.250840] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)431test # [ 0.256320] io scheduler mq-deadline registered432test # [ 0.256334] io scheduler kyber registered433second # [ 0.256826] Key type asymmetric registered434second # [ 0.256846] Asymmetric key parser 'x509' registered435second # [ 0.256891] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)436second # [ 0.258282] io scheduler mq-deadline registered437second # [ 0.258295] io scheduler kyber registered438test # [ 0.264402] pl061_gpio 9030000.pl061: PL061 GPIO chip registered439test # [ 0.264725] ledtrig-cpu: registered to indicate activity on CPUs440test # [ 0.265024] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:441test # [ 0.265045] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000442test # [ 0.265060] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000443test # [ 0.265065] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000444test # [ 0.265099] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits445test # [ 0.265133] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]446test # [ 0.265197] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00447test # [ 0.265216] pci_bus 0000:00: root bus resource [bus 00-ff]448test # [ 0.265221] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]449test # [ 0.265225] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]450test # [ 0.265229] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]451test # [ 0.265326] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint452test # [ 0.265753] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint453test # [ 0.265867] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]454test # [ 0.265878] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]455second # [ 0.267633] pl061_gpio 9030000.pl061: PL061 GPIO chip registered456test # [ 0.265897] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]457second # [ 0.267943] ledtrig-cpu: registered to indicate activity on CPUs458test # [ 0.265908] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]459second # [ 0.268219] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:460test # [ 0.266190] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint461second # [ 0.268240] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000462test # [ 0.266301] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]463second # [ 0.268254] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000464test # [ 0.266312] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]465test # [ 0.266331] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]466second # [ 0.268259] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000467test # [ 0.266611] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint468second # [ 0.268291] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits469test # [ 0.266720] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]470second # [ 0.268322] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]471test # [ 0.266730] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]472test # [ 0.266750] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]473second # [ 0.268392] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00474second # [ 0.268409] pci_bus 0000:00: root bus resource [bus 00-ff]475test # [ 0.267024] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint476second # [ 0.268414] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]477test # [ 0.267134] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]478second # [ 0.268418] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]479test # [ 0.267144] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]480second # [ 0.268422] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]481test # [ 0.267163] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]482second # [ 0.268516] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint483test # [ 0.267443] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint484test # [ 0.267553] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]485second # [ 0.268934] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint486test # [ 0.267579] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]487second # [ 0.269047] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]488test # [ 0.267598] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]489second # [ 0.269058] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]490second # [ 0.269078] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]491test # [ 0.267877] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint492test # [ 0.267988] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]493second # [ 0.269088] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]494test # [ 0.267999] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]495second # [ 0.269365] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint496test # [ 0.268018] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]497second # [ 0.269477] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]498second # [ 0.269487] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]499test # [ 0.268329] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint500second # [ 0.269506] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]501test # [ 0.268441] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]502test # [ 0.268451] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]503second # [ 0.269797] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint504test # [ 0.268474] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]505second # [ 0.269908] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]506second # [ 0.269919] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]507test # [ 0.268485] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]508second # [ 0.269940] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]509test # [ 0.268771] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint510second # [ 0.270212] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint511test # [ 0.268883] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]512second # [ 0.270322] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]513test # [ 0.268901] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]514second # [ 0.270332] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]515test # [ 0.269184] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint516second # [ 0.270353] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]517test # [ 0.269296] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]518second # [ 0.270630] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint519test # [ 0.269314] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]520second # [ 0.270740] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]521test # [ 0.269581] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint522second # [ 0.270751] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]523test # [ 0.269689] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]524second # [ 0.270770] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]525test # [ 0.269847] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint526second # [ 0.271046] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint527test # [ 0.270057] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]528second # [ 0.271156] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]529second # [ 0.271166] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]530test # [ 0.270071] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]531second # [ 0.271187] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]532test # [ 0.270090] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]533second # [ 0.271499] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint534test # [ 0.270390] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint535second # [ 0.271630] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]536test # [ 0.270501] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]537second # [ 0.271641] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]538test # [ 0.270512] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]539second # [ 0.271663] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]540test # [ 0.270531] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]541second # [ 0.271674] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]542test # [ 0.270920] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned543second # [ 0.271949] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint544test # [ 0.270931] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned545second # [ 0.272062] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]546test # [ 0.270936] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned547second # [ 0.272081] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]548test # [ 0.270968] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned549second # [ 0.272354] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint550test # [ 0.270997] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned551second # [ 0.272467] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]552test # [ 0.271025] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned553second # [ 0.272486] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]554test # [ 0.271054] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned555second # [ 0.272743] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint556second # [ 0.272853] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]557test # [ 0.271084] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned558second # [ 0.273015] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint559test # [ 0.271114] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned560second # [ 0.273184] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]561test # [ 0.271144] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned562second # [ 0.273196] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]563second # [ 0.273216] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]564test # [ 0.271174] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned565second # [ 0.273505] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint566test # [ 0.271203] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned567second # [ 0.273617] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]568test # [ 0.271263] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned569second # [ 0.273627] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]570test # [ 0.271357] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned571second # [ 0.273660] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]572test # [ 0.271372] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned573second # [ 0.274060] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned574test # [ 0.271386] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned575second # [ 0.274071] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned576test # [ 0.271401] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned577second # [ 0.274076] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned578test # [ 0.271416] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned579second # [ 0.274108] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned580test # [ 0.271431] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned581test # [ 0.271446] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned582second # [ 0.274138] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned583test # [ 0.271462] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned584second # [ 0.274167] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned585test # [ 0.271477] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned586second # [ 0.274195] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned587test # [ 0.271492] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned588second # [ 0.274224] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned589test # [ 0.271508] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned590second # [ 0.274254] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned591test # [ 0.271523] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned592test # [ 0.271538] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned593second # [ 0.274284] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned594test # [ 0.271553] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned595second # [ 0.274313] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned596test # [ 0.271576] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned597second # [ 0.274342] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned598test # [ 0.271590] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned599test # [ 0.271612] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned600second # [ 0.274435] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned601test # [ 0.271627] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned602second # [ 0.274483] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned603test # [ 0.271641] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned604second # [ 0.274497] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned605test # [ 0.271656] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned606second # [ 0.274511] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned607test # [ 0.271671] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned608second # [ 0.274526] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned609test # [ 0.271690] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]610second # [ 0.274541] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned611test # [ 0.271696] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]612second # [ 0.274556] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned613test # [ 0.271700] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]614second # [ 0.274571] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned615second # [ 0.274587] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned616second # [ 0.274602] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned617second # [ 0.274617] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned618second # [ 0.274632] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned619second # [ 0.274647] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned620second # [ 0.274662] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned621second # [ 0.274677] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned622second # [ 0.274692] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned623second # [ 0.274708] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned624second # [ 0.274729] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned625second # [ 0.274744] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned626second # [ 0.274758] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned627test # [ 0.328428] pci 0000:00:0a.0: enabling device (0000 -> 0002)628second # [ 0.274773] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned629second # [ 0.274787] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned630second # [ 0.274807] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]631second # [ 0.274813] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]632second # [ 0.274816] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]633second # [ 0.275483] pci 0000:00:0a.0: enabling device (0000 -> 0002)634test # [ 0.335168] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)635second # [ 0.331633] pci 0000:00:0a.0: quirk_usb_early_handoff+0x0/0xa60 took 54846 usecs636second # [ 0.337608] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)637test # [ 0.345269] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)638test # [ 0.352409] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)639test # [ 0.354481] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)640second # [ 0.347608] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)641second # [ 0.350964] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)642test # [ 0.364391] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)643test # [ 0.366423] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)644second # [ 0.360549] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)645second # [ 0.364410] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)646second # [ 0.366268] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)647test # [ 0.372399] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)648test # [ 0.374505] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)649second # [ 0.375952] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)650second # [ 0.378661] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)651test # [ 0.385473] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)652test # [ 0.387648] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)653test # [ 0.390807] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)654second # [ 0.384423] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)655second # [ 0.386705] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)656test # [ 0.405032] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled657second # [ 0.396786] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)658test # [ 0.406546] msm_serial: driver initialized659test # [ 0.406648] SuperH (H)SCI(F) driver initialized660test # [ 0.406688] STM32 USART driver initialized661second # [ 0.404190] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled662second # [ 0.405727] msm_serial: driver initialized663second # [ 0.405802] SuperH (H)SCI(F) driver initialized664second # [ 0.405836] STM32 USART driver initialized665test # [ 0.429204] loop: module loaded666test # [ 0.429346] virtio_blk virtio5: 1/0/0 default/read/poll queues667test # [ 0.429891] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)668second # [ 0.428847] loop: module loaded669second # [ 0.428974] virtio_blk virtio5: 1/0/0 default/read/poll queues670second # [ 0.429555] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)671test # [ 0.436658] megasas: 07.734.00.00-rc1672test # [ 0.437110] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]673test # [ 0.439331] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000674test # [ 0.439363] Intel/Sharp Extended Query Table at 0x0031675test # [ 0.441145] Using buffer write method676test # [ 0.441197] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]677test # [ 0.442972] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000678test # [ 0.442991] Intel/Sharp Extended Query Table at 0x0031679second # [ 0.435896] megasas: 07.734.00.00-rc1680second # [ 0.436335] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]681second # [ 0.439347] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000682second # [ 0.439377] Intel/Sharp Extended Query Table at 0x0031683second # [ 0.441107] Using buffer write method684second # [ 0.441137] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]685second # [ 0.442952] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000686second # [ 0.442971] Intel/Sharp Extended Query Table at 0x0031687test # [ 0.460870] Using buffer write method688test # [ 0.460908] Concatenating MTD devices:689test # [ 0.460912] (0): "0.flash"690test # [ 0.460915] (1): "0.flash"691test # [ 0.460918] into device "0.flash"692second # [ 0.460588] Using buffer write method693second # [ 0.460623] Concatenating MTD devices:694second # [ 0.460626] (0): "0.flash"695second # [ 0.460629] (1): "0.flash"696second # [ 0.460632] into device "0.flash"697second # [ 0.633602] Freeing initrd memory: 25528K698test # [ 0.643709] Freeing initrd memory: 25528K699second # [ 0.640430] tun: Universal TUN/TAP device driver, 1.6700second # [ 0.643066] thunder_xcv, ver 1.0701second # [ 0.643093] thunder_bgx, ver 1.0702second # [ 0.643111] nicpf, ver 1.0703second # [ 0.643403] e1000: Intel(R) PRO/1000 Network Driver704second # [ 0.643411] e1000: Copyright (c) 1999-2006 Intel Corporation.705test # [ 0.650723] tun: Universal TUN/TAP device driver, 1.6706second # [ 0.643429] e1000e: Intel(R) PRO/1000 Network Driver707second # [ 0.643435] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.708second # [ 0.643448] igb: Intel(R) Gigabit Ethernet Network Driver709second # [ 0.643452] igb: Copyright (c) 2007-2014 Intel Corporation.710second # [ 0.643463] igbvf: Intel(R) Gigabit Virtual Function Network Driver711second # [ 0.643466] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.712second # [ 0.647891] sky2: driver version 1.30713test # [ 0.653432] thunder_xcv, ver 1.0714second # [ 0.648668] usbcore: registered new interface driver usb-storage715test # [ 0.653462] thunder_bgx, ver 1.0716test # [ 0.653475] nicpf, ver 1.0717second # [ 0.648718] usbcore: registered new interface driver usbserial_generic718test # [ 0.653814] e1000: Intel(R) PRO/1000 Network Driver719second # [ 0.648727] usbserial: USB Serial support registered for generic720test # [ 0.653821] e1000: Copyright (c) 1999-2006 Intel Corporation.721second # [ 0.648995] hv_vmbus: registering driver hyperv_keyboard722test # [ 0.653840] e1000e: Intel(R) PRO/1000 Network Driver723second # [ 0.650093] ehci-pci 0000:00:0a.0: EHCI Host Controller724test # [ 0.653845] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.725second # [ 0.650129] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1726test # [ 0.653858] igb: Intel(R) Gigabit Ethernet Network Driver727second # [ 0.650317] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000728test # [ 0.653861] igb: Copyright (c) 2007-2014 Intel Corporation.729test # [ 0.653872] igbvf: Intel(R) Gigabit Virtual Function Network Driver730test # [ 0.653876] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.731second # [ 0.654060] rtc-pl031 9010000.pl031: registered as rtc0732test # [ 0.653943] sky2: driver version 1.30733test # [ 0.654755] usbcore: registered new interface driver usb-storage734second # [ 0.654102] rtc-pl031 9010000.pl031: setting system clock to 2026-08-27T08:12:46 UTC (1787818366)735test # [ 0.654866] usbcore: registered new interface driver usbserial_generic736second # [ 0.654265] i2c_dev: i2c /dev entries driver737test # [ 0.654875] usbserial: USB Serial support registered for generic738test # [ 0.655163] hv_vmbus: registering driver hyperv_keyboard739test # [ 0.656257] ehci-pci 0000:00:0a.0: EHCI Host Controller740test # [ 0.656321] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1741test # [ 0.656568] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000742test # [ 0.664547] rtc-pl031 9010000.pl031: registered as rtc0743test # [ 0.664588] rtc-pl031 9010000.pl031: setting system clock to 2026-08-27T08:12:46 UTC (1787818366)744test # [ 0.664762] i2c_dev: i2c /dev entries driver745test # [ 0.667572] sdhci: Secure Digital Host Controller Interface driver746test # [ 0.667589] sdhci: Copyright(c) Pierre Ossman747test # [ 0.667729] Synopsys Designware Multimedia Card Interface Driver748test # [ 0.667898] sdhci-pltfm: SDHCI platform and OF driver helper749test # [ 0.670122] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00750test # [ 0.670481] hub 1-0:1.0: USB hub found751test # [ 0.670512] hub 1-0:1.0: 6 ports detected752second # [ 0.657043] sdhci: Secure Digital Host Controller Interface driver753test # [ 0.672437] hid: raw HID events driver (C) Jiri Kosina754second # [ 0.657059] sdhci: Copyright(c) Pierre Ossman755test # [ 0.672581] usbcore: registered new interface driver usbhid756second # [ 0.657187] Synopsys Designware Multimedia Card Interface Driver757test # [ 0.672585] usbhid: USB HID core driver758second # [ 0.657347] sdhci-pltfm: SDHCI platform and OF driver helper759second # [ 0.658216] hid: raw HID events driver (C) Jiri Kosina760second # [ 0.658356] usbcore: registered new interface driver usbhid761second # [ 0.658361] usbhid: USB HID core driver762test # [ 0.697727] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available763test # [ 0.698623] drop_monitor: Initializing network drop monitor service764second # [ 0.693460] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00765test # [ 0.698739] NET: Registered PF_INET6 protocol family766second # [ 0.693890] hub 1-0:1.0: USB hub found767second # [ 0.693922] hub 1-0:1.0: 6 ports detected768test # [ 0.701251] Segment Routing with IPv6769test # [ 0.701281] In-situ OAM (IOAM) with IPv6770second # [ 0.695371] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available771test # [ 0.701334] NET: Registered PF_PACKET protocol family772second # [ 0.696858] drop_monitor: Initializing network drop monitor service773second # [ 0.697013] NET: Registered PF_INET6 protocol family774test # [ 0.702466] 9pnet: Installing 9P2000 support775test # [ 0.704177] Key type dns_resolver registered776second # [ 0.698603] Segment Routing with IPv6777second # [ 0.698632] In-situ OAM (IOAM) with IPv6778second # [ 0.698679] NET: Registered PF_PACKET protocol family779test # [ 0.707735] registered taskstats version 1780test # [ 0.707887] Loading compiled-in X.509 certificates781second # [ 0.699814] 9pnet: Installing 9P2000 support782second # [ 0.701357] Key type dns_resolver registered783second # [ 0.704841] registered taskstats version 1784test # [ 0.713183] Demotion targets for Node 0: null785second # [ 0.704986] Loading compiled-in X.509 certificates786test # [ 0.713260] Key type .fscrypt registered787test # [ 0.713267] Key type fscrypt-provisioning registered788test # [ 0.713369] ima: No TPM chip found, activating TPM-bypass!789test # [ 0.713381] ima: Allocated hash algorithm: sha1790test # [ 0.713433] ima: No architecture policies found791test # [ 0.716072] input: gpio-keys as /devices/platform/gpio-keys/input/input0792second # [ 0.710282] Demotion targets for Node 0: null793second # [ 0.710356] Key type .fscrypt registered794second # [ 0.710361] Key type fscrypt-provisioning registered795second # [ 0.710458] ima: No TPM chip found, activating TPM-bypass!796second # [ 0.710470] ima: Allocated hash algorithm: sha1797second # [ 0.710521] ima: No architecture policies found798second # [ 0.713384] input: gpio-keys as /devices/platform/gpio-keys/input/input0799test # [ 0.726691] clk: Disabling unused clocks800test # [ 0.726710] PM: genpd: Disabling unused power domains801second # [ 0.723507] clk: Disabling unused clocks802second # [ 0.723526] PM: genpd: Disabling unused power domains803test # [ 0.729946] Freeing unused kernel memory: 4736K804test # [ 0.730025] Run /init as init process805second # [ 0.726746] Freeing unused kernel memory: 4736K806second # [ 0.726832] Run /init as init process807test # [ 0.750651] systemd[1]: Successfully made /usr/ read-only.808second # [ 0.749888] systemd[1]: Successfully made /usr/ read-only.809test # [ 0.916314] usb 1-1: new high-speed USB device number 2 using ehci-pci810second # [ 0.939650] usb 1-1: new high-speed USB device number 2 using ehci-pci811test # [ 1.071039] 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/input1812test # [ 1.085129] 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)813test # [ 1.092749] systemd[1]: Detected virtualization qemu.814test # [ 1.093950] systemd[1]: Detected architecture arm64.815test # [ 1.095159] systemd[1]: Running in initrd.816second # [ 1.084578] 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)817test # [ 1.097136] systemd[1]: Initializing machine ID from random generator.818second # [ 1.092620] systemd[1]: Detected virtualization qemu.819test # [ 1.099062] systemd[1]: Hostname set to <test>.820second # [ 1.093830] systemd[1]: Detected architecture arm64.821second # [ 1.095313] systemd[1]: Running in initrd.822second # [ 1.098961] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1823second # [ 1.103013] systemd[1]: Initializing machine ID from random generator.824second # [ 1.105117] systemd[1]: Hostname set to <second>.825test # [ 1.152512] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0826second # [ 1.183781] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0827test # [ 1.206972] systemd[1]: Queued start job for default target Initrd Default Target.828test # [ 1.213714] systemd[1]: Created slice Slice /system/modprobe.829test # [ 1.214488] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.830test # [ 1.215315] systemd[1]: Expecting device /dev/disk/by-label/nixos...831test # [ 1.215975] systemd[1]: Reached target Path Units.832second # [ 1.210156] systemd[1]: Queued start job for default target Initrd Default Target.833test # [ 1.216703] systemd[1]: Reached target Slice Units.834test # [ 1.216734] systemd[1]: Reached target Swaps.835test # [ 1.216754] systemd[1]: Reached target Timer Units.836test # [ 1.216932] systemd[1]: Listening on D-Bus System Message Bus Socket.837test # [ 1.217100] systemd[1]: Listening on Journal Socket (/dev/log).838test # [ 1.217235] systemd[1]: Listening on Journal Sockets.839test # [ 1.217356] systemd[1]: Listening on udev Control Socket.840test # [ 1.217463] systemd[1]: Listening on udev Kernel Socket.841test # [ 1.217491] systemd[1]: Reached target Socket Units.842second # [ 1.217020] systemd[1]: Created slice Slice /system/modprobe.843test # [ 1.223135] systemd[1]: Starting Create List of Static Device Nodes...844second # [ 1.217815] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.845second # [ 1.218637] systemd[1]: Expecting device /dev/disk/by-label/nixos...846second # [ 1.219310] systemd[1]: Reached target Path Units.847second # [ 1.219857] systemd[1]: Reached target Slice Units.848second # [ 1.220388] systemd[1]: Reached target Swaps.849second # [ 1.220842] systemd[1]: Reached target Timer Units.850test # [ 1.227213] systemd[1]: Starting Load Kernel Module 9pnet_virtio...851second # [ 1.221493] systemd[1]: Listening on D-Bus System Message Bus Socket.852second # [ 1.222321] systemd[1]: Listening on Journal Socket (/dev/log).853test # [ 1.227923] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs854second # [ 1.223073] systemd[1]: Listening on Journal Sockets.855second # [ 1.223763] systemd[1]: Listening on udev Control Socket.856second # [ 1.224445] systemd[1]: Listening on udev Kernel Socket.857second # [ 1.225050] systemd[1]: Reached target Socket Units.858second # [ 1.226657] systemd[1]: Starting Create List of Static Device Nodes...859test # [ 1.236351] systemd[1]: Mounting Kernel Configuration File System...860second # [ 1.231497] systemd[1]: Starting Load Kernel Module 9pnet_virtio...861second # [ 1.232259] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs862test # [ 1.241504] systemd[1]: Starting Journal Service...863second # [ 1.238614] systemd[1]: Mounting Kernel Configuration File System...864second # [ 1.246336] systemd[1]: Starting Journal Service...865test # [ 1.257721] systemd[1]: Starting Load Kernel Modules...866test # [ 1.258427] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki867second # [ 1.256950] systemd[1]: Starting Load Kernel Modules...868second # [ 1.257553] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki869test # [ 1.267477] systemd[1]: Starting Coldplug All udev Devices...870second # [ 1.262669] systemd[1]: Starting Coldplug All udev Devices...871test # [ 1.274730] usb 1-2: new high-speed USB device number 3 using ehci-pci872second # [ 1.270283] systemd[1]: Finished Create List of Static Device Nodes.873second # [ 1.272139] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.874test # [ 1.280718] systemd[1]: Finished Create List of Static Device Nodes.875test # [ 1.282120] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.876test # [ 1.286483] systemd-journald[73]: Collecting audit messages is disabled.877second # [ 1.279693] systemd[1]: Finished Load Kernel Module 9pnet_virtio.878second # [ 1.280094] systemd[1]: Mounted Kernel Configuration File System.879second # [ 1.283826] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...880test # [ 1.296435] systemd[1]: Finished Load Kernel Module 9pnet_virtio.881test # [ 1.296876] systemd[1]: Mounted Kernel Configuration File System.882test # [ 1.304585] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...883second # [ 1.303597] usb 1-2: new high-speed USB device number 3 using ehci-pci884test # [ 1.322923] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.885second # [ 1.316728] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.886second # [ 1.321294] systemd-journald[73]: Collecting audit messages is disabled.887second # [ 1.335474] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.888second # [ 1.337089] systemd[1]: Starting Create Static Device Nodes in /dev...889test # [ 1.344950] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev890test # [ 1.349400] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0891second # [ 1.339665] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev892test # [ 1.349620] [drm] features: -virgl +edid -resource_blob -host_visible893second # [ 1.344049] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0894test # [ 1.349626] [drm] features: -context_init895second # [ 1.344277] [drm] features: -virgl +edid -resource_blob -host_visible896test # [ 1.350078] [drm] number of scanouts: 1897second # [ 1.344282] [drm] features: -context_init898test # [ 1.350093] [drm] number of cap sets: 0899second # [ 1.344732] [drm] number of scanouts: 1900second # [ 1.344746] [drm] number of cap sets: 0901test # [ 1.353467] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.902test # [ 1.356481] systemd[1]: Starting Create Static Device Nodes in /dev...903second # [ 1.356248] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic904second # [ 1.356262] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0905test # [ 1.368945] systemd[1]: Started Journal Service.906test # [ 1.369639] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic907test # [ 1.369655] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0908test # [ 1.368323] systemd-modules-load[74]: Inserted module 'dm_mod'909test # [ 1.369292] systemd-modules-load[74]: Module 'virtio_balloon' is built in910test # [ 1.370263] systemd-modules-load[74]: Module 'virtio_console' is built in911second # [ 1.379917] Console: switching to colour frame buffer device 160x50912test # [ 1.382885] systemd[1]: Finished Create Static Device Nodes in /dev.913test # [ 1.383520] systemd[1]: Reached target Preparation for Local File Systems.914test # [ 1.392836] Console: switching to colour frame buffer device 160x50915test # [ 1.390275] systemd[1]: Reached target Local File Systems.916second # [ 1.391949] systemd[1]: Finished Create Static Device Nodes in /dev.917second # [ 1.392077] systemd[1]: Reached target Preparation for Local File Systems.918second # [ 1.392103] systemd[1]: Reached target Local File Systems.919second # [ 1.394522] systemd[1]: Starting Rule-based Manager for Device Events and Files...920second # [ 1.400108] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device921test # [ 1.412488] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device922test # [ 1.404962] systemd[1]: Starting Create System Files and Directories...923test # [ 1.406960] systemd[1]: Starting Rule-based Manager for Device Events and Files...924second # [ 1.406304] systemd-modules-load[74]: Inserted module 'dm_mod'925second # [ 1.407032] systemd-modules-load[74]: Module 'virtio_balloon' is built in926second # [ 1.407748] systemd-modules-load[74]: Module 'virtio_console' is built in927test # [ 1.413758] systemd-modules-load[74]: Inserted module 'virtio_gpu'928second # [ 1.416084] systemd[1]: Started Journal Service.929test # [ 1.414368] systemd-modules-load[74]: Module 'virtio_rng' is built in930test # [ 1.416187] systemd[1]: Finished Load Kernel Modules.931second # [ 1.412283] systemd-modules-load[74]: Inserted module 'virtio_gpu'932test # [ 1.418997] systemd[1]: Starting Apply Kernel Variables...933test # [ 1.430046] 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/input2934test # [ 1.431863] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0935second # [ 1.419604] systemd-modules-load[74]: Module 'virtio_rng' is built in936second # [ 1.424260] systemd[1]: Finished Load Kernel Modules.937second # [ 1.425577] systemd[1]: Starting Apply Kernel Variables...938second # [ 1.429087] systemd[1]: Starting Create System Files and Directories...939test # [ 1.440816] systemd[1]: Finished Create System Files and Directories.940second # [ 1.456425] 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.456588] 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.460689] systemd[1]: Finished Apply Kernel Variables.943second # [ 1.457287] systemd-udevd[80]: Using default interface naming scheme 'v260'.944test # [ 1.470134] systemd-udevd[87]: Using default interface naming scheme 'v260'.945second # [ 1.473118] systemd[1]: Finished Apply Kernel Variables.946second # [ 1.475072] systemd[1]: Finished Create System Files and Directories.947test # [ 1.491796] systemd[1]: Started Rule-based Manager for Device Events and Files.948second # [ 1.489661] systemd[1]: Started Rule-based Manager for Device Events and Files.949test # [ 1.544103] systemd[1]: Starting Virtual Console Setup...950second # [ 1.539564] systemd[1]: Starting Virtual Console Setup...951second # [ 1.580323] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.952second # [ 1.582502] systemd[1]: Finished Virtual Console Setup.953test # [ 1.592351] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.954test # [ 1.594499] systemd[1]: Finished Virtual Console Setup.955second # [ 1.845101] systemd[1]: Finished Coldplug All udev Devices.956second # [ 1.845662] systemd[1]: Reached target System Initialization.957second # [ 1.846167] systemd[1]: Reached target Basic System.958test # [ 1.855288] systemd[1]: Finished Coldplug All udev Devices.959test # [ 1.855862] systemd[1]: Reached target System Initialization.960test # [ 1.856472] systemd[1]: Reached target Basic System.961second # [ 1.938643] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.962test # [ 1.947557] (udev-worker)[103]: Network interface NamePolicy= disabled on kernel command line.963second # [ 1.966545] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.964test # [ 1.980299] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.965second # [ 1.992891] systemd[1]: Found device /dev/disk/by-label/nixos.966second # [ 1.997739] systemd[1]: Reached target Initrd Root Device.967second # [ 2.000200] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...968test # [ 2.015859] systemd[1]: Found device /dev/disk/by-label/nixos.969test # [ 2.020092] systemd[1]: Reached target Initrd Root Device.970test # [ 2.024397] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...971second # [ 2.037021] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks972second # [ 2.045455] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.973test # [ 2.064783] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks974test # [ 2.072440] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.975second # [ 2.258216] systemd[1]: Mounting /sysroot...976test # [ 2.264112] systemd[1]: Mounting /sysroot...977test # [ 2.297197] EXT4-fs (vda): mounted filesystem 5b37b49a-3366-48d9-b441-f34c184c312d r/w with ordered data mode. Quota mode: none.978second # [ 2.291985] EXT4-fs (vda): mounted filesystem 7b0b8769-944d-45f8-b51e-a63990177731 r/w with ordered data mode. Quota mode: none.979test # [ 2.292891] systemd[1]: Mounted /sysroot.980second # [ 2.288824] systemd[1]: Mounted /sysroot.981test # [ 2.294065] systemd[1]: Reached target Initrd Root File System.982second # [ 2.289255] systemd[1]: Reached target Initrd Root File System.983test # [ 2.296438] systemd[1]: Starting Mountpoints Configured in the Real Root...984second # [ 2.292752] systemd[1]: Starting Mountpoints Configured in the Real Root...985test # [ 2.309531] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.986test # [ 2.312240] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...987test # [ 2.315082] systemd[1]: Reloading...988second # [ 2.309548] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.989second # [ 2.314799] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...990second # [ 2.316695] systemd[1]: Reloading...991test # [ 2.483928] systemd[1]: Reloading finished in 169 ms.992second # [ 2.480428] systemd[1]: Reloading finished in 167 ms.993test # [ 2.491988] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...994test # [ 2.494380] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...995second # [ 2.489259] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...996second # [ 2.489997] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...997second # [ 2.492176] systemd[1]: Starting Load Kernel Module 9pnet_virtio...998second # [ 2.495645] systemd[1]: initrd-parse-etc.service: Deactivated successfully.999test # [ 2.502001] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1000second # [ 2.499023] systemd[1]: Finished Mountpoints Configured in the Real Root.1001second # [ 2.499734] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1002test # [ 2.506289] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1003test # [ 2.508375] systemd[1]: Finished Mountpoints Configured in the Real Root.1004test # [ 2.510013] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1005test # [ 2.519963] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1006test # [ 2.520862] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1007second # [ 2.515688] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1008second # [ 2.516442] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1009second # [ 2.804919] (udev-worker)[103]: mtd0ro: Failed to find and pin callout binary "/nix/store/2pprwd9bdjg4ssiam65rgjqfy64h0hbd-systemd-260.2/lib/udev/mtd_probe": No such file or directory1010second # [ 2.808990] (udev-worker)[103]: mtd0ro: /nix/store/2pprwd9bdjg4ssiam65rgjqfy64h0hbd-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 directory1011second # [ 2.822090] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1012second # [ 2.823681] systemd[1]: Stopped Virtual Console Setup.1013second # [ 2.826152] systemd[1]: Stopping Virtual Console Setup...1014second # [ 2.828083] systemd[1]: Starting Virtual Console Setup...1015test # [ 2.849541] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/2pprwd9bdjg4ssiam65rgjqfy64h0hbd-systemd-260.2/lib/udev/mtd_probe": No such file or directory1016test # [ 2.852933] (udev-worker)[94]: mtd0ro: /nix/store/2pprwd9bdjg4ssiam65rgjqfy64h0hbd-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 directory1017second # [ 2.849843] systemd-vconsole-setup[142]: Configuration of first virtual console was skipped, ignoring remaining ones.1018second # [ 2.852293] systemd[1]: Finished Virtual Console Setup.1019test # [ 2.860565] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1020test # [ 2.862146] systemd[1]: Stopped Virtual Console Setup.1021test # [ 2.862815] systemd[1]: Stopping Virtual Console Setup...1022test # [ 2.866270] systemd[1]: Starting Virtual Console Setup...1023test # [ 2.890159] systemd-vconsole-setup[143]: Configuration of first virtual console was skipped, ignoring remaining ones.1024test # [ 2.892607] systemd[1]: Finished Virtual Console Setup.1025test # [ 3.264134] systemd[1]: Mounting /sysroot/nix/.ro-store...1026second # [ 3.261406] systemd[1]: Mounting /sysroot/nix/.ro-store...1027test # [ 3.271048] systemd[1]: Mounting /sysroot/nix/.rw-store...1028second # [ 3.273705] systemd[1]: Mounting /sysroot/nix/.rw-store...1029second # [ 3.276242] systemd[1]: Mounting /sysroot/run...1030test # [ 3.283969] systemd[1]: Mounting /sysroot/run...1031test # [ 3.287243] systemd[1]: Mounting /sysroot/tmp/shared...1032second # [ 3.286786] systemd[1]: Mounting /sysroot/tmp/shared...1033test # [ 3.300637] systemd[1]: Mounting /sysroot/tmp/xchg...1034second # [ 3.301272] systemd[1]: Mounting /sysroot/tmp/xchg...1035second # [ 3.333859] systemd[1]: Mounted /sysroot/nix/.rw-store.1036test # [ 3.346641] systemd[1]: Mounted /sysroot/nix/.ro-store.1037test # [ 3.347945] systemd[1]: Mounted /sysroot/nix/.rw-store.1038second # [ 3.343280] systemd[1]: Mounted /sysroot/nix/.ro-store.1039test # [ 3.350223] systemd[1]: Mounted /sysroot/run.1040second # [ 3.347613] systemd[1]: Mounted /sysroot/run.1041test # [ 3.356916] systemd[1]: Starting rw-sysroot-nix-store.service...1042second # [ 3.352684] systemd[1]: Mounted /sysroot/tmp/shared.1043second # [ 3.354967] systemd[1]: Mounted /sysroot/tmp/xchg.1044test # [ 3.361421] systemd[1]: Mounted /sysroot/tmp/shared.1045test # [ 3.363744] systemd[1]: Mounted /sysroot/tmp/xchg.1046second # [ 3.360309] systemd[1]: Starting rw-sysroot-nix-store.service...1047test # [ 3.381905] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1048second # [ 3.377371] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1049test # [ 3.382739] systemd[1]: Finished rw-sysroot-nix-store.service.1050second # [ 3.378468] systemd[1]: Finished rw-sysroot-nix-store.service.1051test # [ 4.262587] systemd[1]: Mounting /sysroot/nix/store...1052second # [ 4.258625] systemd[1]: Mounting /sysroot/nix/store...1053second # [ 4.301031] systemd[1]: Mounted /sysroot/nix/store.1054test # [ 4.306433] systemd[1]: Mounted /sysroot/nix/store.1055second # [ 4.304223] systemd[1]: Reached target Initrd File Systems.1056test # [ 4.309490] systemd[1]: Reached target Initrd File Systems.1057test # [ 4.312210] systemd[1]: Starting Find NixOS closure...1058second # [ 4.307706] systemd[1]: Starting Find NixOS closure...1059test # [ 4.317328] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1060second # [ 4.316285] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1061second # [ 4.351042] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1062test # [ 4.361069] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1063second # [ 4.356772] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1064test # [ 4.364362] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1065second # [ 4.371064] systemd[1]: Finished Find NixOS closure.1066second # [ 4.373312] systemd[1]: Reached target Initrd Default Target.1067test # [ 4.380692] systemd[1]: Finished Find NixOS closure.1068second # [ 4.376207] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1069test # [ 4.383111] systemd[1]: Reached target Initrd Default Target.1070test # [ 4.388310] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1071second # [ 4.405640] systemd[1]: Stopped target Initrd Default Target.1072second # [ 4.407352] systemd[1]: Stopped target Basic System.1073test # [ 4.416434] systemd[1]: Stopped target Initrd Default Target.1074test # [ 4.417378] systemd[1]: Stopped target Basic System.1075second # [ 4.412111] systemd[1]: Stopped target Initrd Root Device.1076second # [ 4.412990] systemd[1]: Stopped target Path Units.1077test # [ 4.418184] systemd[1]: Stopped target Initrd Root Device.1078test # [ 4.419026] systemd[1]: Stopped target Path Units.1079second # [ 4.413742] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1080second # [ 4.414996] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1081test # [ 4.421484] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1082second # [ 4.417897] systemd[1]: Stopped target Slice Units.1083test # [ 4.423096] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1084second # [ 4.420134] systemd[1]: Stopped target Socket Units.1085second # [ 4.421157] systemd[1]: Stopped target System Initialization.1086test # [ 4.428188] systemd[1]: Stopped target Slice Units.1087second # [ 4.423233] systemd[1]: Stopped target Swaps.1088test # [ 4.428865] systemd[1]: Stopped target Socket Units.1089test # [ 4.429522] systemd[1]: Stopped target System Initialization.1090test # [ 4.430240] systemd[1]: Stopped target Swaps.1091test # [ 4.430831] systemd[1]: Stopped target Timer Units.1092test # [ 4.431605] systemd[1]: dbus.socket: Deactivated successfully.1093second # [ 4.427198] systemd[1]: Stopped target Timer Units.1094second # [ 4.428115] systemd[1]: dbus.socket: Deactivated successfully.1095second # [ 4.429122] systemd[1]: Closed D-Bus System Message Bus Socket.1096second # [ 4.430096] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1097test # [ 4.436196] systemd[1]: Closed D-Bus System Message Bus Socket.1098test # [ 4.436913] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1099test # [ 4.437755] systemd[1]: Stopped Find NixOS closure.1100test # [ 4.438332] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1101test # [ 4.440970] systemd[1]: Starting rw-sysroot-nix-store.service...1102second # [ 4.436524] systemd[1]: Stopped Find NixOS closure.1103second # [ 4.438571] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1104second # [ 4.440408] systemd[1]: Starting rw-sysroot-nix-store.service...1105test # [ 4.445956] systemd[1]: systemd-sysctl.service: Deactivated successfully.1106second # [ 4.441656] systemd[1]: systemd-sysctl.service: Deactivated successfully.1107test # [ 4.447918] systemd[1]: Stopped Apply Kernel Variables.1108second # [ 4.444316] systemd[1]: Stopped Apply Kernel Variables.1109second # [ 4.448315] systemd[1]: systemd-modules-load.service: Deactivated successfully.1110second # [ 4.449253] systemd[1]: Stopped Load Kernel Modules.1111second # [ 4.450471] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1112test # [ 4.455804] systemd[1]: systemd-modules-load.service: Deactivated successfully.1113test # [ 4.458303] systemd[1]: Stopped Load Kernel Modules.1114test # [ 4.458930] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1115second # [ 4.454857] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1116test # [ 4.459922] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1117second # [ 4.456706] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1118second # [ 4.457755] systemd[1]: Stopped Create System Files and Directories.1119second # [ 4.458592] systemd[1]: Stopped target Local File Systems.1120test # [ 4.464178] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1121second # [ 4.459625] systemd[1]: Stopped target Preparation for Local File Systems.1122test # [ 4.466395] systemd[1]: Stopped Create System Files and Directories.1123test # [ 4.467051] systemd[1]: Stopped target Local File Systems.1124test # [ 4.467702] systemd[1]: Stopped target Preparation for Local File Systems.1125test # [ 4.468425] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1126test # [ 4.469077] systemd[1]: Stopped Coldplug All udev Devices.1127test # [ 4.469585] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1128test # [ 4.470262] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1129test # [ 4.470925] systemd[1]: Stopped Virtual Console Setup.1130test # [ 4.471550] systemd[1]: systemd-udevd.service: Deactivated successfully.1131second # [ 4.468479] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1132second # [ 4.469353] systemd[1]: Stopped Coldplug All udev Devices.1133second # [ 4.469973] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1134second # [ 4.470770] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1135test # [ 4.476300] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1136second # [ 4.471451] systemd[1]: Stopped Virtual Console Setup.1137second # [ 4.471926] systemd[1]: systemd-udevd.service: Deactivated successfully.1138test # [ 4.477602] systemd[1]: initrd-cleanup.service: Deactivated successfully.1139test # [ 4.479988] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1140test # [ 4.480685] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1141test # [ 4.483100] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1142test # [ 4.483696] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1143second # [ 4.478812] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1144second # [ 4.479457] systemd[1]: initrd-cleanup.service: Deactivated successfully.1145second # [ 4.480087] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1146test # [ 4.484770] systemd[1]: Finished rw-sysroot-nix-store.service.1147second # [ 4.480676] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1148second # [ 4.481740] systemd[1]: Finished rw-sysroot-nix-store.service.1149second # [ 4.482729] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1150test # [ 4.488144] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1151second # [ 4.483470] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1152test # [ 4.488800] systemd[1]: Closed udev Control Socket.1153test # [ 4.489330] systemd[1]: Starting Cleanup udev Database...1154second # [ 4.484201] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1155second # [ 4.484865] systemd[1]: Closed udev Control Socket.1156test # [ 4.489871] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1157second # [ 4.485329] systemd[1]: Starting Cleanup udev Database...1158test # [ 4.490947] systemd[1]: Stopped Create Static Device Nodes in /dev.1159test # [ 4.491998] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1160test # [ 4.492903] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1161test # [ 4.493550] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1162test # [ 4.494149] systemd[1]: Stopped Create List of Static Device Nodes.1163second # [ 4.485822] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1164second # [ 4.486464] systemd[1]: Stopped Create Static Device Nodes in /dev.1165second # [ 4.486961] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1166second # [ 4.487603] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1167second # [ 4.492215] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1168second # [ 4.492878] systemd[1]: Stopped Create List of Static Device Nodes.1169second # [ 4.503584] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1170second # [ 4.505781] systemd[1]: Finished Cleanup udev Database.1171second # [ 4.506461] systemd[1]: Reached target Switch Root.1172second # [ 4.508789] systemd[1]: Starting NixOS Activation...1173test # [ 4.515504] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1174test # [ 4.517838] systemd[1]: Finished Cleanup udev Database.1175test # [ 4.518354] systemd[1]: Reached target Switch Root.1176test # [ 4.520735] systemd[1]: Starting NixOS Activation...1177second # [ 4.695852] initrd-nixos-activation-start[179]: booting system configuration /nix/store/ji2p1f7bbq8jjvvlp672g6nd60hgywna-nixos-system-second-test1178test # [ 4.712112] initrd-nixos-activation-start[180]: booting system configuration /nix/store/y96zhkz48qsvywh9rkrifnaazcrdxp97-nixos-system-test-test1179second # [ 4.765496] initrd-nixos-activation-start[179]: running activation script...1180test # [ 4.782010] initrd-nixos-activation-start[180]: running activation script...1181second # [ 5.236359] initrd-nixos-activation-start[202]: setting up /etc...1182test # [ 5.255694] initrd-nixos-activation-start[203]: setting up /etc...1183second # [ 5.506865] initrd-nixos-activation-start[202]: setting up age secrets...1184test # [ 5.526992] initrd-nixos-activation-start[203]: setting up age secrets...1185second # [ 5.738997] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1186second # [ 5.740759] systemd[1]: Finished NixOS Activation.1187second # [ 5.744125] systemd[1]: Starting Switch Root...1188second # [ 5.759964] systemd[1]: Switching root.1189second # [ 5.808124] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1190test # [ 5.835883] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1191test # [ 5.837762] systemd[1]: Finished NixOS Activation.1192test # [ 5.840063] systemd[1]: Starting Switch Root...1193test # [ 5.852143] systemd[1]: Switching root.1194test # [ 5.909039] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1195second # [ 6.388576] 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)1196second # [ 6.396101] systemd[1]: Detected virtualization qemu.1197second # [ 6.398428] systemd[1]: Detected architecture arm64.1198second # [ 6.399654] systemd[1]: Detected first boot.1199second # [ 6.404744] systemd[1]: Initializing machine ID from random generator.1200test # [ 6.483009] 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)1201test # [ 6.490576] systemd[1]: Detected virtualization qemu.1202test # [ 6.492416] systemd[1]: Detected architecture arm64.1203test # [ 6.494230] systemd[1]: Detected first boot.1204test # [ 6.500558] systemd[1]: Initializing machine ID from random generator.1205second # [ 6.691884] systemd[1]: bpf-restrict-fs: LSM BPF program attached1206test # [ 6.788828] systemd[1]: bpf-restrict-fs: LSM BPF program attached1207second # [ 6.868947] systemd[1]: Applying preset policy.1208test # [ 6.971235] systemd[1]: Applying preset policy.1209second # [ 7.430944] systemd[1]: Populated /etc with preset unit settings.1210test # [ 7.538377] systemd[1]: Populated /etc with preset unit settings.1211second # [ 7.961000] systemd[1]: initrd-switch-root.service: Deactivated successfully.1212second # [ 7.961879] systemd[1]: Stopped initrd-switch-root.service.1213second # [ 7.963870] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1214second # [ 7.965270] systemd[1]: Created slice Slice /system/getty.1215second # [ 7.966806] systemd[1]: Created slice User and Session Slice.1216second # [ 7.968013] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1217second # [ 7.968565] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1218second # [ 7.968920] systemd[1]: Expecting device /dev/hvc0...1219second # [ 7.969214] systemd[1]: Expecting device /dev/ttyAMA0...1220second # [ 7.969507] systemd[1]: Reached target Local Encrypted Volumes.1221second # [ 7.969823] systemd[1]: Stopped target initrd-fs.target.1222second # [ 7.970110] systemd[1]: Stopped target initrd-root-fs.target.1223second # [ 7.970392] systemd[1]: Stopped target initrd-switch-root.target.1224second # [ 7.970689] systemd[1]: Reached target Virtual Machines and Containers.1225second # [ 7.970976] systemd[1]: Reached target Path Units.1226second # [ 7.971255] systemd[1]: Reached target Remote File Systems.1227second # [ 7.971523] systemd[1]: Reached target Slice Units.1228second # [ 7.977403] systemd[1]: Reached target Swaps.1229second # [ 7.981774] systemd[1]: Listening on Process Core Dump Socket.1230second # [ 7.985227] systemd[1]: Listening on Credential Encryption/Decryption.1231second # [ 7.989474] systemd[1]: Starting Journal Log Access Socket...1232second # [ 7.990989] systemd[1]: Listening on Journal Audit Socket.1233second # [ 7.992618] systemd[1]: Listening on Network Management Metrics Varlink Socket.1234second # [ 7.994313] systemd[1]: Listening on Network Management Netlink Socket.1235second # [ 7.996188] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1236second # [ 7.997765] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1237second # [ 7.999654] systemd[1]: Listening on udev Control Socket.1238second # [ 8.003807] systemd[1]: Mounting Huge Pages File System...1239second # [ 8.007512] systemd[1]: Mounting POSIX Message Queue File System...1240second # [ 8.011897] systemd[1]: Mounting Kernel Debug File System...1241second # [ 8.017594] systemd[1]: Mounting Kernel Trace File System...1242second # [ 8.024951] systemd[1]: Starting Create List of Static Device Nodes...1243second # [ 8.038123] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1244second # [ 8.039940] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1245second # [ 8.049585] systemd[1]: Mounting Kernel Configuration File System...1246second # [ 8.053927] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1247second # [ 8.055899] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1248second # [ 8.063943] systemd[1]: Starting Load Kernel Module fuse...1249test # [ 8.073272] systemd[1]: initrd-switch-root.service: Deactivated successfully.1250second # [ 8.065461] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671251test # [ 8.074144] systemd[1]: Stopped initrd-switch-root.service.1252test # [ 8.076056] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1253test # [ 8.078145] systemd[1]: Created slice Slice /system/getty.1254test # [ 8.079670] systemd[1]: Created slice User and Session Slice.1255test # [ 8.081008] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1256test # [ 8.082340] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1257test # [ 8.083692] systemd[1]: Expecting device /dev/hvc0...1258test # [ 8.084153] systemd[1]: Expecting device /dev/ttyAMA0...1259test # [ 8.085232] systemd[1]: Reached target Local Encrypted Volumes.1260test # [ 8.086305] systemd[1]: Stopped target initrd-fs.target.1261test # [ 8.087275] systemd[1]: Stopped target initrd-root-fs.target.1262test # [ 8.088356] systemd[1]: Stopped target initrd-switch-root.target.1263test # [ 8.089846] systemd[1]: Reached target Virtual Machines and Containers.1264test # [ 8.091357] systemd[1]: Reached target Path Units.1265test # [ 8.092812] systemd[1]: Reached target Remote File Systems.1266test # [ 8.094204] systemd[1]: Reached target Slice Units.1267test # [ 8.095566] systemd[1]: Reached target Swaps.1268test # [ 8.100049] systemd[1]: Listening on Process Core Dump Socket.1269test # [ 8.103888] systemd[1]: Listening on Credential Encryption/Decryption.1270test # [ 8.108653] systemd[1]: Starting Journal Log Access Socket...1271test # [ 8.110381] systemd[1]: Listening on Journal Audit Socket.1272test # [ 8.111938] systemd[1]: Listening on Network Management Metrics Varlink Socket.1273test # [ 8.113635] systemd[1]: Listening on Network Management Netlink Socket.1274test # [ 8.115291] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1275test # [ 8.116945] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1276test # [ 8.118372] systemd[1]: Listening on udev Control Socket.1277test # [ 8.122262] systemd[1]: Mounting Huge Pages File System...1278second # [ 8.117427] systemd[1]: Starting Journal Service...1279test # [ 8.125403] systemd[1]: Mounting POSIX Message Queue File System...1280test # [ 8.132414] systemd[1]: Mounting Kernel Debug File System...1281test # [ 8.137529] systemd[1]: Mounting Kernel Trace File System...1282second # [ 8.142179] systemd[1]: Starting Load Kernel Modules...1283test # [ 8.150482] systemd[1]: Starting Create List of Static Device Nodes...1284test # [ 8.154546] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1285test # [ 8.155864] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1286test # [ 8.164040] systemd[1]: Mounting Kernel Configuration File System...1287test # [ 8.165739] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1288test # [ 8.167703] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1289second # [ 8.163381] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1290test # [ 8.186572] systemd[1]: Starting Load Kernel Module fuse...1291test # [ 8.187895] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671292second # [ 8.185366] systemd[1]: Starting Remount Root and Kernel File Systems...1293second # [ 8.189424] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1294second # [ 8.210995] systemd[1]: Starting Coldplug All udev Devices...1295test # [ 8.224090] systemd[1]: Starting Journal Service...1296second # [ 8.239143] systemd[1]: Listening on Journal Log Access Socket.1297test # [ 8.251638] systemd[1]: Starting Load Kernel Modules...1298second # [ 8.248967] systemd[1]: Mounted Huge Pages File System.1299second # [ 8.249482] systemd[1]: Mounted POSIX Message Queue File System.1300second # [ 8.249903] systemd[1]: Mounted Kernel Debug File System.1301second # [ 8.250282] systemd[1]: Mounted Kernel Trace File System.1302second # [ 8.254989] systemd[1]: Finished Create List of Static Device Nodes.1303second # [ 8.260688] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1304second # [ 8.266632] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1305test # [ 8.272611] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1306second # [ 8.271121] fuse: init (API version 7.45)1307second # [ 8.274161] systemd[1]: Mounted Kernel Configuration File System.1308test # [ 8.288944] systemd[1]: Starting Remount Root and Kernel File Systems...1309second # [ 8.283844] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1310test # [ 8.291170] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1311second # [ 8.289123] systemd[1]: modprobe@fuse.service: Deactivated successfully.1312second # [ 8.291220] systemd[1]: Finished Load Kernel Module fuse.1313second # [ 8.294305] systemd[1]: Finished Load Kernel Modules.1314test # [ 8.303807] systemd[1]: Starting Coldplug All udev Devices...1315second # [ 8.309801] systemd[1]: Mounting FUSE Control File System...1316second # [ 8.314689] systemd-journald[301]: Collecting audit messages is enabled.1317test # [ 8.325825] systemd[1]: Listening on Journal Log Access Socket.1318second # [ 8.315263] systemd[1]: Queued start job for default target Multi-User System.1319second # [ 8.327931] EXT4-fs (vda): re-mounted 7b0b8769-944d-45f8-b51e-a63990177731.1320second # [ 8.330071] systemd[1]: Starting Firewall...1321second # [ 8.324730] systemd[1]: systemd-journald.service: Deactivated successfully.1322test # [ 8.337899] systemd[1]: Mounted Huge Pages File System.1323test # [ 8.340037] systemd[1]: Mounted POSIX Message Queue File System.1324second # [ 8.328159] systemd-modules-load[302]: Module 'atkbd' is built in1325second # [ 8.328900] systemd-modules-load[302]: Module 'ctr' is built in1326second # [ 8.330449] systemd-modules-load[302]: Module 'loop' is built in1327second # [ 8.335522] systemd-oomd[303]: No swap; memory pressure usage will be degraded1328second # [ 8.347150] systemd[1]: Starting Apply Kernel Variables...1329test # [ 8.352628] fuse: init (API version 7.45)1330test # [ 8.353476] systemd[1]: Mounted Kernel Debug File System.1331test # [ 8.353903] systemd[1]: Mounted Kernel Trace File System.1332test # [ 8.354438] systemd[1]: Finished Create List of Static Device Nodes.1333test # [ 8.354931] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1334test # [ 8.355349] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1335test # [ 8.355799] systemd[1]: Mounted Kernel Configuration File System.1336second # [ 8.358062] systemd[1]: Started Journal Service.1337test # [ 8.368221] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1338test # [ 8.373906] systemd[1]: modprobe@fuse.service: Deactivated successfully.1339second # [ 8.362802] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1340test # [ 8.375604] systemd[1]: Finished Load Kernel Module fuse.1341second # [ 8.366163] systemd[1]: Finished Remount Root and Kernel File Systems.1342test # [ 8.382028] systemd-journald[324]: Collecting audit messages is enabled.1343test # [ 8.386687] systemd[1]: Queued start job for default target Multi-User System.1344test # [ 8.399657] systemd[1]: Mounting FUSE Control File System...1345test # [ 8.401752] systemd[1]: Started Journal Service.1346test # [ 8.398082] systemd[1]: systemd-journald.service: Deactivated successfully.1347test # [ 8.404632] systemd-modules-load[325]: Module 'atkbd' is built in1348test # [ 8.408152] systemd-modules-load[325]: Module 'ctr' is built in1349test # [ 8.414821] systemd-modules-load[325]: Module 'loop' is built in1350test # [ 8.415466] systemd[1]: Finished Load Kernel Modules.1351second # [ 8.415921] systemd[1]: Starting Flush Journal to Persistent Storage...1352second # [ 8.417197] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1353second # [ 8.421428] systemd[1]: Starting Load/Save OS Random Seed...1354test # [ 8.429935] systemd[1]: Starting Firewall...1355second # [ 8.426109] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1356test # [ 8.445451] EXT4-fs (vda): re-mounted 5b37b49a-3366-48d9-b441-f34c184c312d.1357test # [ 8.442429] systemd[1]: Starting Apply Kernel Variables...1358second # [ 8.446704] systemd[1]: Mounted FUSE Control File System.1359test # [ 8.455635] systemd[1]: Finished Remount Root and Kernel File Systems.1360test # [ 8.464521] systemd-oomd[326]: No swap; memory pressure usage will be degraded1361test # [ 8.476212] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1362second # [ 8.472487] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1363second # [ 8.479834] systemd[1]: Starting Create Static Device Nodes in /dev...1364second # [ 8.491401] systemd[1]: Finished Apply Kernel Variables.1365test # [ 8.507854] systemd[1]: Starting Flush Journal to Persistent Storage...1366test # [ 8.508871] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1367test # [ 8.513846] systemd[1]: Starting Load/Save OS Random Seed...1368test # [ 8.514525] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1369second # [ 8.512422] systemd[1]: Starting Network Name Resolution...1370test # [ 8.531294] systemd[1]: Mounted FUSE Control File System.1371second # [ 8.540738] systemd-journald[301]: Received client request to flush runtime journal.1372test # [ 8.543643] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1373test # [ 8.555963] systemd[1]: Starting Create Static Device Nodes in /dev...1374test # [ 8.597714] systemd[1]: Finished Apply Kernel Variables.1375second # [ 8.598825] systemd[1]: Finished Load/Save OS Random Seed.1376test # [ 8.609587] systemd[1]: Starting Network Name Resolution...1377second # [ 8.605461] systemd[1]: Reached target First Boot Complete.1378test # [ 8.619513] systemd-journald[324]: Received client request to flush runtime journal.1379second # [ 8.612511] systemd[1]: Finished Flush Journal to Persistent Storage.1380second # [ 8.639334] systemd[1]: Finished Create Static Device Nodes in /dev.1381second # [ 8.639931] systemd[1]: Reached target Preparation for Local File Systems.1382second # [ 8.641979] systemd[1]: Starting Rule-based Manager for Device Events and Files...1383test # [ 8.696765] systemd[1]: Finished Load/Save OS Random Seed.1384test # [ 8.697350] systemd[1]: Reached target First Boot Complete.1385test # [ 8.697913] systemd[1]: Finished Create Static Device Nodes in /dev.1386test # [ 8.698463] systemd[1]: Reached target Preparation for Local File Systems.1387second # [ 8.697044] systemd-resolved[333]: Positive Trust Anchors:1388second # [ 8.698526] systemd-resolved[333]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1389second # [ 8.699834] systemd-resolved[333]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161390test # [ 8.708864] systemd[1]: Starting Rule-based Manager for Device Events and Files...1391test # [ 8.712715] systemd[1]: Finished Flush Journal to Persistent Storage.1392second # [ 8.705266] systemd-resolved[333]: 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 test1393test # [ 8.780337] systemd-resolved[354]: Positive Trust Anchors:1394test # [ 8.781933] systemd-resolved[354]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1395second # [ 8.779087] systemd-udevd[341]: Using default interface naming scheme 'v260'.1396test # [ 8.783445] systemd-resolved[354]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161397test # [ 8.788463] systemd-resolved[354]: 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 test1398test # [ 8.855443] systemd-udevd[360]: Using default interface naming scheme 'v260'.1399second # [ 8.925116] systemd-resolved[333]: Using system hostname 'second'.1400second # [ 8.934364] systemd[1]: Started Network Name Resolution.1401second # [ 8.957899] systemd[1]: Mounting /run/wrappers...1402test # [ 9.009410] systemd-resolved[354]: Using system hostname 'test'.1403test # [ 9.017653] systemd[1]: Started Network Name Resolution.1404second # [ 9.017180] systemd[1]: Mounted /run/wrappers.1405second # [ 9.017994] systemd[1]: Reached target Local File Systems.1406second # [ 9.021617] systemd[1]: Listening on Boot Loader Control Service Socket.1407second # [ 9.025377] systemd[1]: Starting register-nix-paths.service...1408second # [ 9.035579] systemd[1]: Starting Create SUID/SGID Wrappers...1409second # [ 9.036753] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1410second # [ 9.042697] systemd[1]: Starting Save Transient machine-id to Disk...1411test # [ 9.074056] systemd[1]: Mounting /run/wrappers...1412second # [ 9.092300] systemd[1]: Starting Create System Files and Directories...1413second # [ 9.095135] systemd[1]: Started Rule-based Manager for Device Events and Files.1414test # [ 9.129228] systemd[1]: Mounted /run/wrappers.1415test # [ 9.130354] systemd[1]: Reached target Local File Systems.1416test # [ 9.137355] systemd[1]: Listening on Boot Loader Control Service Socket.1417test # [ 9.142357] systemd[1]: Starting register-nix-paths.service...1418test # [ 9.145250] systemd[1]: Starting Create SUID/SGID Wrappers...1419test # [ 9.153102] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1420test # [ 9.168854] systemd[1]: Starting Save Transient machine-id to Disk...1421second # [ 9.173070] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1422second # [ 9.177373] systemd[1]: Finished Save Transient machine-id to Disk.1423test # [ 9.203310] systemd[1]: Starting Create System Files and Directories...1424test # [ 9.204730] systemd[1]: Started Rule-based Manager for Device Events and Files.1425second # [ 9.252624] systemd[1]: Finished Create System Files and Directories.1426second # [ 9.270537] systemd[1]: Starting Rebuild Journal Catalog...1427second # [ 9.275331] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1428test # [ 9.290004] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1429test # [ 9.301747] systemd[1]: Finished Save Transient machine-id to Disk.1430second # [ 9.378538] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1431test # [ 9.389893] systemd[1]: Finished Create System Files and Directories.1432test # [ 9.396138] systemd[1]: Starting Rebuild Journal Catalog...1433test # [ 9.400379] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1434second # [ 9.463239] systemd[1]: Finished Rebuild Journal Catalog.1435second # [ 9.466529] systemd[1]: Starting Update is Completed...1436test # [ 9.516083] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1437second # [ 9.563149] systemd[1]: Finished Update is Completed.1438second # [ 9.574012] systemd[1]: Finished Coldplug All udev Devices.1439test # [ 9.609697] systemd[1]: Finished Rebuild Journal Catalog.1440test # [ 9.617751] systemd[1]: Starting Update is Completed...1441test # [ 9.622772] systemd[1]: Finished Coldplug All udev Devices.1442second # [ 9.677115] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1443test # [ 9.690001] systemd[1]: Finished Update is Completed.1444test # [ 9.760953] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1445second # [ 9.938604] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1446second # [ 10.010336] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1447test # [ 10.018825] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1448second # [ 10.061889] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1449test # [ 10.067874] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1450second # [ 10.064398] systemd[1]: Finished Create SUID/SGID Wrappers.1451test # [ 10.175525] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1452test # [ 10.177461] systemd[1]: Finished Create SUID/SGID Wrappers.1453second # [ 10.239357] systemd[1]: Finished Firewall.1454second # [ 10.239915] systemd[1]: Reached target Preparation for Network.1455second # [ 10.243525] systemd[1]: Starting Network Management...1456second # [ 10.285853] systemd[1]: Finished register-nix-paths.service.1457second # [ 10.287158] systemd[1]: Reached target System Initialization.1458second # [ 10.289905] systemd[1]: Started Discard unused filesystem blocks once a week.1459second # [ 10.293235] systemd[1]: Started Daily Cleanup of Temporary Directories.1460second # [ 10.296465] systemd[1]: Reached target Timer Units.1461second # [ 10.305072] systemd[1]: Listening on D-Bus System Message Bus Socket.1462second # [ 10.305672] systemd[1]: Listening on Nix Daemon Socket.1463second # [ 10.306165] systemd[1]: Listening on Authorization Manager Agent Helper.1464second # [ 10.306799] systemd[1]: Listening on Hostname Service Socket.1465second # [ 10.307296] systemd[1]: Reached target Socket Units.1466second # [ 10.311306] systemd[1]: Starting D-Bus System Message Bus...1467test # [ 10.325012] systemd[1]: Finished Firewall.1468test # [ 10.327075] systemd[1]: Reached target Preparation for Network.1469second # [ 10.322520] (udev-worker)[383]: Network interface NamePolicy= disabled on kernel command line.1470test # [ 10.331311] systemd[1]: Starting Network Management...1471second # [ 10.329933] (udev-worker)[381]: Network interface NamePolicy= disabled on kernel command line.1472test # [ 10.393535] (udev-worker)[402]: Network interface NamePolicy= disabled on kernel command line.1473test # [ 10.403844] (udev-worker)[399]: Network interface NamePolicy= disabled on kernel command line.1474test # [ 10.407573] systemd[1]: Finished register-nix-paths.service.1475test # [ 10.409513] systemd[1]: Reached target System Initialization.1476test # [ 10.412126] systemd[1]: Started Discard unused filesystem blocks once a week.1477test # [ 10.415256] systemd[1]: Started Daily Cleanup of Temporary Directories.1478test # [ 10.416275] systemd[1]: Reached target Timer Units.1479test # [ 10.419018] systemd[1]: Listening on D-Bus System Message Bus Socket.1480test # [ 10.425226] systemd[1]: Listening on Nix Daemon Socket.1481test # [ 10.425703] systemd[1]: Listening on Authorization Manager Agent Helper.1482test # [ 10.426266] systemd[1]: Listening on Hostname Service Socket.1483test # [ 10.426758] systemd[1]: Reached target Socket Units.1484test # [ 10.431079] systemd[1]: Starting D-Bus System Message Bus...1485second # [ 10.473367] cfg80211: Loading compiled-in X.509 certificates for regulatory database1486second # [ 10.490979] dbus-broker-launch[536]: Looking up NSS user entry for 'systemd-timesync'...1487second # [ 10.511289] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1488second # [ 10.512942] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1489second # [ 10.512947] dbus-broker-launch[536]: NSS returned no entry for 'systemd-timesync'1490second # [ 10.513865] dbus-broker-launch[536]: Invalid user-name in /nix/store/94acyx8k2ngbqp5817mh44kc4g90fyhw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1491second # [ 10.530513] mousedev: PS/2 mouse device common for all mice1492test # [ 10.568255] cfg80211: Loading compiled-in X.509 certificates for regulatory database1493second # [ 10.568196] systemd-networkd[533]: /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.1494second # [ 10.572751] systemd-networkd[533]: /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.1495second # [ 10.580330] systemd-networkd[533]: lo: Link UP1496second # [ 10.582248] systemd-networkd[533]: lo: Gained carrier1497second # [ 10.586164] dbus-broker-launch[536]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/nc2n2qi8lq7i3sr8wh2s74k57v8ivb7b-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1498test # [ 10.606328] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1499test # [ 10.606859] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1500second # [ 10.597272] dbus-broker-launch[536]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/nc2n2qi8lq7i3sr8wh2s74k57v8ivb7b-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1501second # [ 10.604259] dbus-broker-launch[536]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/had2hwcdifwqkpz3ji6sxkvg8yb164rc-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1502test # [ 10.618408] dbus-broker-launch[561]: Looking up NSS user entry for 'systemd-timesync'...1503second # [ 10.613625] systemd[1]: Started Network Management.1504second # [ 10.616208] dbus-broker-launch[536]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/42037x5cj27f8dawijnxzb26db99wwxy-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1505second # [ 10.620674] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1506second # [ 10.623436] systemd[1]: Started D-Bus System Message Bus.1507second # [ 10.628866] systemd-networkd[533]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1508second # [ 10.630181] systemd-networkd[533]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1509second # [ 10.630961] systemd-networkd[533]: eth1: Link UP1510second # [ 10.631389] systemd-networkd[533]: eth1: Gained carrier1511test # [ 10.635622] dbus-broker-launch[561]: NSS returned no entry for 'systemd-timesync'1512second # [ 10.631835] systemd-networkd[533]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1513test # [ 10.637612] dbus-broker-launch[561]: Invalid user-name in /nix/store/94acyx8k2ngbqp5817mh44kc4g90fyhw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1514test # [ 10.653670] mousedev: PS/2 mouse device common for all mice1515test # [ 10.660719] systemd-networkd[556]: /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.1516test # [ 10.664152] systemd-networkd[556]: /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.1517test # [ 10.669491] systemd-networkd[556]: lo: Link UP1518test # [ 10.671503] systemd-networkd[556]: lo: Gained carrier1519second # [ 10.670951] dbus-broker-launch[536]: Ready1520test # [ 10.676974] systemd[1]: Started Network Management.1521test # [ 10.680509] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1522second # [ 10.677661] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1523second # [ 10.679966] systemd[1]: Reached target Basic System.1524test # [ 10.685748] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1525second # [ 10.684165] systemd[1]: Starting Network Manager Script Dispatcher Service...1526test # [ 10.690019] systemd-networkd[556]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1527test # [ 10.694944] systemd-networkd[556]: eth1: Link UP1528second # [ 10.689763] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1529test # [ 10.697033] systemd-networkd[556]: eth1: Gained carrier1530second # [ 10.694321] systemd[1]: Starting Network Manager...1531test # [ 10.699372] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1532test # [ 10.709225] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/nc2n2qi8lq7i3sr8wh2s74k57v8ivb7b-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1533second # [ 10.706410] systemd[1]: Started backdoor.service.1534test # [ 10.712624] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/nc2n2qi8lq7i3sr8wh2s74k57v8ivb7b-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1535test # [ 10.718164] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/had2hwcdifwqkpz3ji6sxkvg8yb164rc-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1536test # [ 10.726450] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/42037x5cj27f8dawijnxzb26db99wwxy-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1537second # [ 10.723021] systemd[1]: Starting Import lastlog data into lastlog2 database...1538test # [ 10.730899] systemd[1]: Started D-Bus System Message Bus.1539second # [ 10.732914] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1540test # [ 10.776472] dbus-broker-launch[561]: Ready1541test # [ 10.780075] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1542second # [ 10.777150] systemd[1]: Starting Post-Boot Actions...1543test # [ 10.784149] systemd[1]: Reached target Basic System.1544test # [ 10.789460] systemd[1]: Starting Network Manager Script Dispatcher Service...1545test # [ 10.793370] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1546test # [ 10.797097] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1547second # [ 10.796123] systemd[1]: Started Reset console on configuration changes.1548test # [ 10.802787] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1549test # [ 10.824068] systemd[1]: Starting Network Manager...1550test # [ 10.842000] systemd[1]: Started backdoor.service.1551second # [ 10.841889] rssf13kza7rj2jljq39fngbf0z7n3am5-wifi-secrets[549]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1552test # [ 10.875102] systemd[1]: Starting Import lastlog data into lastlog2 database...1553second # [ 10.883700] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1554second # connecting to host...1555test # [ 10.899372] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1556second # [ 10.900995] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1557second # [ 10.934106] systemd[1]: Finished Post-Boot Actions.1558test # [ 10.950973] systemd[1]: Starting Post-Boot Actions...1559test # [ 10.956819] rssf13kza7rj2jljq39fngbf0z7n3am5-wifi-secrets[572]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1560second # [ 10.960698] systemd[1]: Started Name Service Cache Daemon (nsncd).1561second # [ 10.961366] systemd[1]: Reached target Host and Network Name Lookups.1562second # [ 10.961900] systemd[1]: Reached target User and Group Name Lookups.1563test # [ 10.968758] 8g1rx2i5wgny7771myl54j8za1ccscsg-wifi-secrets[573]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1564second # [ 10.967630] nsncd[553]: Aug 27 08:12:56.817 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1565second # [ 10.972883] systemd[1]: Starting User Login Management...1566second # [ 10.973804] systemd[1]: Finished Import lastlog data into lastlog2 database.1567test # [ 10.982510] 1n5h5lsamc1iqqvp1xy6dcsj8mpn1yiw-wifi-secrets[574]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1568test # [ 10.990636] systemd[1]: Started Reset console on configuration changes.1569test # [ 11.011991] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1570test # [ 11.036539] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1571test # [ 11.043282] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1572test # [ 11.050252] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1573test # [ 11.054327] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1574second # [ 11.052666] systemd[1]: Started Network Manager Script Dispatcher Service.1575test # connecting to host...1576test # [ 11.061921] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1577test # [ 11.116379] systemd[1]: Finished Post-Boot Actions.1578test: Guest shell says: b'Spawning backdoor root shell...\n'1579test: connected to guest root shell1580test: (connecting took 11.40 seconds)1581test: (finished: waiting for the VM to finish booting, in 11.40 seconds)1582second # [ 11.120230] systemd-logind[581]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1583test # [ 11.119375] nsncd[578]: Aug 27 08:12:56.960 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1584test # [ 11.125393] systemd[1]: Started Name Service Cache Daemon (nsncd).1585test # [ 11.131511] systemd[1]: Reached target Host and Network Name Lookups.1586test # [ 11.134753] systemd[1]: Reached target User and Group Name Lookups.1587test # [ 11.141404] systemd[1]: Starting User Login Management...1588second # [ 11.126091] systemd-logind[581]: Watching system buttons on /dev/input/event0 (gpio-keys)1589second # [ 11.126727] systemd-logind[581]: New seat seat0.1590second # [ 11.127102] systemd[1]: Started User Login Management.1591second # [ 11.131066] systemd[1]: Starting linger-users.service...1592second # [ 11.147574] systemd[1]: Starting Hostname Service...1593test # [ 11.182501] systemd[1]: Finished Import lastlog data into lastlog2 database.1594second # [ 11.202313] systemd[1]: linger-users.service: Deactivated successfully.1595second # [ 11.204852] systemd[1]: Finished linger-users.service.1596test # [ 11.222179] systemd[1]: Started Network Manager Script Dispatcher Service.1597second # [ 11.263918] systemd[1]: Started Hostname Service.1598test # [ 11.302355] systemd-logind[615]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1599test # [ 11.308880] systemd-logind[615]: Watching system buttons on /dev/input/event0 (gpio-keys)1600test # [ 11.309559] systemd-logind[615]: New seat seat0.1601test # [ 11.316426] systemd[1]: Started User Login Management.1602test # [ 11.330210] systemd[1]: Starting linger-users.service...1603test # [ 11.333301] systemd[1]: Starting Hostname Service...1604second # [ 11.359121] systemd-networkd[533]: eth0: Link UP1605second # [ 11.359599] systemd-networkd[533]: eth0: Gained carrier1606second # [ 11.377986] systemd[1]: Started Network Manager.1607second # [ 11.380498] systemd[1]: Reached target Network.1608second # [ 11.388043] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1609second # [ 11.393147] systemd[1]: Starting Permit User Sessions...1610test # [ 11.405415] systemd[1]: linger-users.service: Deactivated successfully.1611test # [ 11.407209] systemd[1]: Finished linger-users.service.1612test # [ 11.455839] systemd[1]: Started Hostname Service.1613test # [ 11.548588] systemd-networkd[556]: eth0: Link UP1614test # [ 11.549084] systemd-networkd[556]: eth0: Gained carrier1615second # [ 11.543799] systemd[1]: Finished Permit User Sessions.1616second # [ 11.552479] systemd[1]: Started Getty on tty1.1617second # [ 11.556469] systemd[1]: Reached target Login Prompts.1618test # [ 11.567769] systemd[1]: Started Network Manager.1619test # [ 11.569638] systemd[1]: Reached target Network.1620test # [ 11.574900] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1621test # [ 11.580384] systemd[1]: Starting Permit User Sessions...1622second # [ 11.577575] systemd-resolved[333]: eth0: Bus client set default route setting: yes1623second # [ 11.585924] systemd-resolved[333]: eth0: Bus client set DNS server list to: 10.0.2.31624second # [ 11.690716] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1625second # [ 11.691464] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1626test # [ 11.730161] systemd[1]: Finished Permit User Sessions.1627test # [ 11.747174] systemd[1]: Started Getty on tty1.1628test # [ 11.748987] systemd[1]: Reached target Login Prompts.1629second # [ 11.745571] systemd-networkd[533]: eth1: Gained IPv6LL1630test # [ 11.787203] systemd-resolved[354]: eth0: Bus client set default route setting: yes1631test # [ 11.791814] systemd-resolved[354]: eth0: Bus client set DNS server list to: 10.0.2.31632second # [ 11.840486] systemd[1]: Reached target Multi-User System.1633second # [ 11.844764] systemd[1]: Startup finished in 726ms (kernel) + 5.164s (initrd) + 5.951s (userspace) = 11.841s.1634test # [ 11.859981] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1635test # [ 11.862233] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1636test # [ 11.999359] systemd[1]: Reached target Multi-User System.1637test # [ 12.000976] systemd[1]: Startup finished in 728ms (kernel) + 5.261s (initrd) + 6.009s (userspace) = 12.000s.1638second # [ 12.135453] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31639test # [ 12.225821] systemd-networkd[556]: eth1: Gained IPv6LL1640test # [ 12.301711] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31641second # [ 12.370858] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1642test: (finished: waiting for unit NetworkManager.service, in 12.63 seconds)1643test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1644second # [ 12.407315] systemd[1]: Starting Virtual Console Setup...1645test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds)1646test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1647second # [ 12.437902] systemd-logind[581]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1648test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.04 seconds)1649test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1650test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.04 seconds)1651(finished: run the VM test script, in 13.22 seconds)1652second # [ 12.545276] systemd-networkd[533]: eth0: Gained IPv6LL1653test script finished in 13.27s1654cleanup1655kill QemuMachine (pid 14)1656second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/d6kq2phq0zy5invmkfgg8h35zxaf13s9-python3-3.13.15/bin/python3.13)1657test # [ 12.619242] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1658test # [ 12.655424] systemd[1]: Starting Virtual Console Setup...1659test # [ 12.674672] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1660test # [ 12.676123] systemd[1]: Stopped Virtual Console Setup.1661test # [ 12.680462] systemd-logind[615]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1662test # [ 12.684775] systemd[1]: Starting Virtual Console Setup...1663kill QemuMachine (pid 15)1664test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/d6kq2phq0zy5invmkfgg8h35zxaf13s9-python3-3.13.15/bin/python3.13)1665(finished: cleanup, in 0.28 seconds)