[PATCH] perf: Fix perf_event_validate_size() lockdep splat

Mark Rutland posted 1 patch 2 years ago
There is a newer version of this series
kernel/events/core.c | 10 ++++++++++
1 file changed, 10 insertions(+)
[PATCH] perf: Fix perf_event_validate_size() lockdep splat
Posted by Mark Rutland 2 years ago
When lockdep is enabled, the for_each_sibling_event(sibling, event)
macro checks that event->ctx->mutex is held. When creating a new group
leader event, we call perf_event_validate_size() on a partially
initialized event where event->ctx is NULL, and so when
for_each_sibling_event() attempts to check event->ctx->mutex, we get a
splat, as reported by Lucas De Marchi:

  WARNING: CPU: 8 PID: 1471 at kernel/events/core.c:1950 __do_sys_perf_event_open+0xf37/0x1080

This only happens for a new event which is its own group_leader, and in
this case there cannot be any sibling events. Thus it's safe to skip the
check for siblings, which avoids having to make invasive and ugly
changes to for_each_sibling_event().

Avoid the splat by bailing out early when the new event is its own
group_leader.

Fixes: 382c27f4ed28f803 ("perf: Fix perf_event_validate_size()")
Reported-by: Lucas De Marchi <lucas.demarchi@intel.com>
Closes: https://lore.kernel.org/lkml/20231214000620.3081018-1-lucas.demarchi@intel.com/
Reported-by: Pengfei Xu <pengfei.xu@intel.com>
Closes: https://lore.kernel.org/lkml/ZXpm6gQ%2Fd59jGsuW@xpf.sh.intel.com/
Signed-off-by: Mark Rutland <mark.rutland@arm.com>
Cc: Ingo Molnar <mingo@redhat.com>
Cc: Borislav Petkov <bp@alien8.de>
Cc: Peter Zijlstra <peterz@infradead.org>
---
 kernel/events/core.c | 10 ++++++++++
 1 file changed, 10 insertions(+)

Hi Ingo, Boris, Peter,

I'm not sure who's still around and who has disappeared for the
holidays, but I'm hoping at least one of you is able to queue this. I've
tested the patch on arm64 with Syzkaller (and syz-repro); before this
patch it hits the splat near-instantly, and after this patch all seems
well.

The broken commit was merged in v6.7-rc5 via:

  https://lore.kernel.org/lkml/20231210105949.GAZXWaJe6DeHU9+ofl@fat_crate.local/

... in merge commit:

  537ccb5d28d6f398 ("Merge tag 'perf_urgent_for_v6.7_rc5' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip")

Mark.

diff --git a/kernel/events/core.c b/kernel/events/core.c
index c9d123e13b579..9efd0d7775e7c 100644
--- a/kernel/events/core.c
+++ b/kernel/events/core.c
@@ -1947,6 +1947,16 @@ static bool perf_event_validate_size(struct perf_event *event)
 				   group_leader->nr_siblings + 1) > 16*1024)
 		return false;
 
+	/*
+	 * When creating a new group leader, group_leader->ctx is initialized
+	 * after the size has been validated, but we cannot safely use
+	 * for_each_sibling_event() until group_leader->ctx is set. A new group
+	 * leader cannot have any siblings yet, so we can safely skip checking
+	 * the non-existent siblings.
+	 */
+	if (event == group_leader)
+		return true;
+
 	for_each_sibling_event(sibling, group_leader) {
 		if (__perf_event_read_size(sibling->attr.read_format,
 					   group_leader->nr_siblings + 1) > 16*1024)
-- 
2.30.2
Re: [PATCH] perf: Fix perf_event_validate_size() lockdep splat
Posted by Peter Zijlstra 2 years ago
On Fri, Dec 15, 2023 at 11:24:50AM +0000, Mark Rutland wrote:
> When lockdep is enabled, the for_each_sibling_event(sibling, event)
> macro checks that event->ctx->mutex is held. When creating a new group
> leader event, we call perf_event_validate_size() on a partially
> initialized event where event->ctx is NULL, and so when
> for_each_sibling_event() attempts to check event->ctx->mutex, we get a
> splat, as reported by Lucas De Marchi:
> 
>   WARNING: CPU: 8 PID: 1471 at kernel/events/core.c:1950 __do_sys_perf_event_open+0xf37/0x1080
> 
> This only happens for a new event which is its own group_leader, and in
> this case there cannot be any sibling events. Thus it's safe to skip the
> check for siblings, which avoids having to make invasive and ugly
> changes to for_each_sibling_event().
> 
> Avoid the splat by bailing out early when the new event is its own
> group_leader.
> 
> Fixes: 382c27f4ed28f803 ("perf: Fix perf_event_validate_size()")
> Reported-by: Lucas De Marchi <lucas.demarchi@intel.com>
> Closes: https://lore.kernel.org/lkml/20231214000620.3081018-1-lucas.demarchi@intel.com/
> Reported-by: Pengfei Xu <pengfei.xu@intel.com>
> Closes: https://lore.kernel.org/lkml/ZXpm6gQ%2Fd59jGsuW@xpf.sh.intel.com/
> Signed-off-by: Mark Rutland <mark.rutland@arm.com>

Urgh, my bad indeed.

> ---
>  kernel/events/core.c | 10 ++++++++++
>  1 file changed, 10 insertions(+)
> 
> Hi Ingo, Boris, Peter,
> 
> I'm not sure who's still around and who has disappeared for the
> holidays, but I'm hoping at least one of you is able to queue this. I've
> tested the patch on arm64 with Syzkaller (and syz-repro); before this
> patch it hits the splat near-instantly, and after this patch all seems
> well.

I'm still here today, let me stick this in perf/urgent.

> diff --git a/kernel/events/core.c b/kernel/events/core.c
> index c9d123e13b579..9efd0d7775e7c 100644
> --- a/kernel/events/core.c
> +++ b/kernel/events/core.c
> @@ -1947,6 +1947,16 @@ static bool perf_event_validate_size(struct perf_event *event)
>  				   group_leader->nr_siblings + 1) > 16*1024)
>  		return false;
>  
> +	/*
> +	 * When creating a new group leader, group_leader->ctx is initialized
> +	 * after the size has been validated, but we cannot safely use
> +	 * for_each_sibling_event() until group_leader->ctx is set. A new group
> +	 * leader cannot have any siblings yet, so we can safely skip checking
> +	 * the non-existent siblings.
> +	 */
> +	if (event == group_leader)
> +		return true;
> +
>  	for_each_sibling_event(sibling, group_leader) {
>  		if (__perf_event_read_size(sibling->attr.read_format,
>  					   group_leader->nr_siblings + 1) > 16*1024)
> -- 
> 2.30.2
>
[tip: perf/urgent] perf: Fix perf_event_validate_size() lockdep splat
Posted by tip-bot2 for Mark Rutland 2 years ago
The following commit has been merged into the perf/urgent branch of tip:

Commit-ID:     7e2c1e4b34f07d9aa8937fab88359d4a0fce468e
Gitweb:        https://git.kernel.org/tip/7e2c1e4b34f07d9aa8937fab88359d4a0fce468e
Author:        Mark Rutland <mark.rutland@arm.com>
AuthorDate:    Fri, 15 Dec 2023 11:24:50 
Committer:     Peter Zijlstra <peterz@infradead.org>
CommitterDate: Fri, 15 Dec 2023 12:33:23 +01:00

perf: Fix perf_event_validate_size() lockdep splat

When lockdep is enabled, the for_each_sibling_event(sibling, event)
macro checks that event->ctx->mutex is held. When creating a new group
leader event, we call perf_event_validate_size() on a partially
initialized event where event->ctx is NULL, and so when
for_each_sibling_event() attempts to check event->ctx->mutex, we get a
splat, as reported by Lucas De Marchi:

  WARNING: CPU: 8 PID: 1471 at kernel/events/core.c:1950 __do_sys_perf_event_open+0xf37/0x1080

This only happens for a new event which is its own group_leader, and in
this case there cannot be any sibling events. Thus it's safe to skip the
check for siblings, which avoids having to make invasive and ugly
changes to for_each_sibling_event().

Avoid the splat by bailing out early when the new event is its own
group_leader.

Fixes: 382c27f4ed28f803 ("perf: Fix perf_event_validate_size()")
Closes: https://lore.kernel.org/lkml/20231214000620.3081018-1-lucas.demarchi@intel.com/
Closes: https://lore.kernel.org/lkml/ZXpm6gQ%2Fd59jGsuW@xpf.sh.intel.com/
Reported-by: Lucas De Marchi <lucas.demarchi@intel.com>
Reported-by: Pengfei Xu <pengfei.xu@intel.com>
Signed-off-by: Mark Rutland <mark.rutland@arm.com>
Signed-off-by: Peter Zijlstra (Intel) <peterz@infradead.org>
Link: https://lkml.kernel.org/r/20231215112450.3972309-1-mark.rutland@arm.com
---
 kernel/events/core.c | 10 ++++++++++
 1 file changed, 10 insertions(+)

diff --git a/kernel/events/core.c b/kernel/events/core.c
index c9d123e..9efd0d7 100644
--- a/kernel/events/core.c
+++ b/kernel/events/core.c
@@ -1947,6 +1947,16 @@ static bool perf_event_validate_size(struct perf_event *event)
 				   group_leader->nr_siblings + 1) > 16*1024)
 		return false;
 
+	/*
+	 * When creating a new group leader, group_leader->ctx is initialized
+	 * after the size has been validated, but we cannot safely use
+	 * for_each_sibling_event() until group_leader->ctx is set. A new group
+	 * leader cannot have any siblings yet, so we can safely skip checking
+	 * the non-existent siblings.
+	 */
+	if (event == group_leader)
+		return true;
+
 	for_each_sibling_event(sibling, group_leader) {
 		if (__perf_event_read_size(sibling->attr.read_format,
 					   group_leader->nr_siblings + 1) > 16*1024)
Re: [tip: perf/urgent] perf: Fix perf_event_validate_size() lockdep splat
Posted by Pengfei Xu 2 years ago
Hi,

I tested the following patch on top of v6.7-rc5 kernel.
This perf issue was gone.
It's fixed.
Attached is the fixed dmesg log.

Thanks!


On 2023-12-15 at 19:42:58 +0800, tip-bot2 for Mark Rutland wrote:
> The following commit has been merged into the perf/urgent branch of tip:
> 
> Commit-ID:     7e2c1e4b34f07d9aa8937fab88359d4a0fce468e
> Gitweb:        https://git.kernel.org/tip/7e2c1e4b34f07d9aa8937fab88359d4a0fce468e
> Author:        Mark Rutland <mark.rutland@arm.com>
> AuthorDate:    Fri, 15 Dec 2023 11:24:50 
> Committer:     Peter Zijlstra <peterz@infradead.org>
> CommitterDate: Fri, 15 Dec 2023 12:33:23 +01:00
> 
> perf: Fix perf_event_validate_size() lockdep splat
> 
> When lockdep is enabled, the for_each_sibling_event(sibling, event)
> macro checks that event->ctx->mutex is held. When creating a new group
> leader event, we call perf_event_validate_size() on a partially
> initialized event where event->ctx is NULL, and so when
> for_each_sibling_event() attempts to check event->ctx->mutex, we get a
> splat, as reported by Lucas De Marchi:
> 
>   WARNING: CPU: 8 PID: 1471 at kernel/events/core.c:1950 __do_sys_perf_event_open+0xf37/0x1080
> 
> This only happens for a new event which is its own group_leader, and in
> this case there cannot be any sibling events. Thus it's safe to skip the
> check for siblings, which avoids having to make invasive and ugly
> changes to for_each_sibling_event().
> 
> Avoid the splat by bailing out early when the new event is its own
> group_leader.
> 
> Fixes: 382c27f4ed28f803 ("perf: Fix perf_event_validate_size()")
> Closes: https://lore.kernel.org/lkml/20231214000620.3081018-1-lucas.demarchi@intel.com/
> Closes: https://lore.kernel.org/lkml/ZXpm6gQ%2Fd59jGsuW@xpf.sh.intel.com/
> Reported-by: Lucas De Marchi <lucas.demarchi@intel.com>
> Reported-by: Pengfei Xu <pengfei.xu@intel.com>
> Signed-off-by: Mark Rutland <mark.rutland@arm.com>
> Signed-off-by: Peter Zijlstra (Intel) <peterz@infradead.org>
> Link: https://lkml.kernel.org/r/20231215112450.3972309-1-mark.rutland@arm.com
> ---
>  kernel/events/core.c | 10 ++++++++++
>  1 file changed, 10 insertions(+)
> 
> diff --git a/kernel/events/core.c b/kernel/events/core.c
> index c9d123e..9efd0d7 100644
> --- a/kernel/events/core.c
> +++ b/kernel/events/core.c
> @@ -1947,6 +1947,16 @@ static bool perf_event_validate_size(struct perf_event *event)
>  				   group_leader->nr_siblings + 1) > 16*1024)
>  		return false;
>  
> +	/*
> +	 * When creating a new group leader, group_leader->ctx is initialized
> +	 * after the size has been validated, but we cannot safely use
> +	 * for_each_sibling_event() until group_leader->ctx is set. A new group
> +	 * leader cannot have any siblings yet, so we can safely skip checking
> +	 * the non-existent siblings.
> +	 */
> +	if (event == group_leader)
> +		return true;
> +
>  	for_each_sibling_event(sibling, group_leader) {
>  		if (__perf_event_read_size(sibling->attr.read_format,
>  					   group_leader->nr_siblings + 1) > 16*1024)

Tested-by: Pengfei Xu <pengfei.xu@intel.com>

[root@test ~]# ./repro






ot@test ~]# dmesg
[    0.000000] Linux version 6.7.0-rc5-fix-dirty (root@xpf.sh.intel.com) (gcc (GCC) 8.5.0 20210514 (Red Hat 8.5.0-20), GNU ld version 2.36.1-2.el8)3
[    0.000000] Command line: console=ttyS0 root=/dev/sda earlyprintk=serial net.ifnames=0 thunderbolt.dyndbg  plymouth.enable=0
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Hygon HygonGenuine
[    0.000000]   Centaur CentaurHauls
[    0.000000]   zhaoxin   Shanghai  
[    0.000000] BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000007ffdffff] usable
[    0.000000] BIOS-e820: [mem 0x000000007ffe0000-0x000000007fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] printk: legacy bootconsole [earlyser0] enabled
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] APIC: Static calls initialized
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.0-0-gd239552ce722-prebuilt.qemu.org 04/01/2014
[    0.000000] Hypervisor detected: KVM
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000003] kvm-clock: using sched offset of 687721526 cycles
[    0.000535] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.002189] tsc: Detected 1881.600 MHz processor
[    0.008492] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.008520] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.008541] last_pfn = 0x7ffe0 max_arch_pfn = 0x400000000
[    0.009149] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
[    0.009996] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.014980] found SMP MP-table at [mem 0x000f5ba0-0x000f5baf]
[    0.015620] Using GB pages for direct mapping
[    0.017888] ACPI: Early table checksum verification disabled
[    0.018467] ACPI: RSDP 0x00000000000F59C0 000014 (v00 BOCHS )
[    0.019059] ACPI: RSDT 0x000000007FFE1951 000034 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.019935] ACPI: FACP 0x000000007FFE17FD 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.020805] ACPI: DSDT 0x000000007FFE0040 0017BD (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.021683] ACPI: FACS 0x000000007FFE0000 000040
[    0.022157] ACPI: APIC 0x000000007FFE1871 000080 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.023019] ACPI: HPET 0x000000007FFE18F1 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.023879] ACPI: WAET 0x000000007FFE1929 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.024741] ACPI: Reserving FACP table memory at [mem 0x7ffe17fd-0x7ffe1870]
[    0.025456] ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe17fc]
[    0.026170] ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f]
[    0.026886] ACPI: Reserving APIC table memory at [mem 0x7ffe1871-0x7ffe18f0]
[    0.027599] ACPI: Reserving HPET table memory at [mem 0x7ffe18f1-0x7ffe1928]
[    0.028309] ACPI: Reserving WAET table memory at [mem 0x7ffe1929-0x7ffe1950]
[    0.029504] No NUMA configuration found
[    0.029896] Faking a node at [mem 0x0000000000000000-0x000000007ffdffff]
[    0.030589] NODE_DATA(0) allocated [mem 0x7ffb5000-0x7ffdffff]
[    0.032521] Zone ranges:
[    0.032783]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.033416]   DMA32    [mem 0x0000000001000000-0x000000007ffdffff]
[    0.034038]   Normal   empty
[    0.034331]   Device   empty
[    0.034623] Movable zone start for each node
[    0.035052] Early memory node ranges
[    0.035409]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.036038]   node   0: [mem 0x0000000000100000-0x000000007ffdffff]
[    0.036665] Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdffff]
[    0.037703] On node 0, zone DMA: 1 pages in unavailable ranges
[    0.037872] On node 0, zone DMA: 97 pages in unavailable ranges
[    0.063427] On node 0, zone DMA32: 32 pages in unavailable ranges
[    0.243588] kasan: KernelAddressSanitizer initialized
[    0.244976] ACPI: PM-Timer IO Port: 0x608
[    0.245406] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.246029] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.246713] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.247330] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.247975] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.248652] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.249347] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.250042] ACPI: Using ACPI (MADT) for SMP configuration information
[    0.250684] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.251211] TSC deadline timer available
[    0.251600] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    0.252136] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
[    0.252848] kvm-guest: KVM setup pv remote TLB flush
[    0.253361] kvm-guest: setup PV sched yield
[    0.253822] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.254567] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    0.255311] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    0.256064] PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    0.256827] [mem 0x80000000-0xfeffbfff] available for PCI devices
[    0.257456] Booting paravirtualized kernel on KVM
[    0.257939] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[    0.258998] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    0.261319] percpu: Embedded 82 pages/cpu s299008 r8192 d28672 u1048576
[    0.262021] pcpu-alloc: s299008 r8192 d28672 u1048576 alloc=1*2097152
[    0.262036] pcpu-alloc: [0] 0 1 
[    0.262142] kvm-guest: PV spinlocks enabled
[    0.262558] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.263291] Kernel command line: net.ifnames=0 console=ttyS0 root=/dev/sda earlyprintk=serial net.ifnames=0 thunderbolt.dyndbg  plymouth.enable=0
[    0.264963] random: crng init done
[    0.266749] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.268435] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.269488] Fallback order for Node 0: 0 
[    0.269504] Built 1 zonelists, mobility grouping on.  Total pages: 515808
[    0.270548] Policy zone: DMA32
[    0.270849] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.271445] stackdepot: allocating hash table via alloc_large_system_hash
[    0.277404] stackdepot hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[    0.317507] Memory: 1627232K/2096632K available (73728K kernel code, 14095K rwdata, 16700K rodata, 14912K init, 21912K bss, 469144K reserved, 0K)
[    0.320284] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.320937] kmemleak: Kernel memory leak detector disabled
[    0.321664] ftrace: allocating 72459 entries in 284 pages
[    0.347134] ftrace: allocated 284 pages with 4 groups
[    0.351570] Dynamic Preempt: voluntary
[    0.352539] Running RCU self tests
[    0.352883] Running RCU synchronous self tests
[    0.353347] rcu: Preemptible hierarchical RCU implementation.
[    0.353914] rcu:     RCU lockdep checking is enabled.
[    0.354386] rcu:     RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2.
[    0.355054]  Trampoline variant of Tasks RCU enabled.
[    0.355514]  Rude variant of Tasks RCU enabled.
[    0.355966]  Tracing variant of Tasks RCU enabled.
[    0.356454] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.357228] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.357986] Running RCU synchronous self tests
[    0.402417] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16
[    0.403885] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.412867] Console: colour VGA+ 80x25
[    0.413437] printk: legacy console [ttyS0] enabled
[    0.414554] printk: legacy bootconsole [earlyser0] disabled
[    0.415855] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.416734] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.417236] ... MAX_LOCK_DEPTH:          48
[    0.417719] ... MAX_LOCKDEP_KEYS:        8192
[    0.418221] ... CLASSHASH_SIZE:          4096
[    0.418728] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.419270] ... MAX_LOCKDEP_CHAINS:      65536
[    0.419802] ... CHAINHASH_SIZE:          32768
[    0.420322]  memory used by lock dependency info: 6493 kB
[    0.420946]  memory used for stack traces: 4224 kB
[    0.421517]  per task-struct memory footprint: 1920 bytes
[    0.422321] ACPI: Core revision 20230628
[    0.423464] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.424857] APIC: Switch to symmetric I/O mode setup
[    0.425636] x2apic enabled
[    0.426358] APIC: Switched APIC routing to: physical x2apic
[    0.427037] kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask()
[    0.427888] kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself()
[    0.429103] kvm-guest: setup PV IPIs
[    0.430868] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.431617] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x363e8c91135, max_idle_ns: 881590568389 ns
[    0.433147] Calibrating delay loop (skipped) preset value.. 3763.20 BogoMIPS (lpj=7526400)
[    0.434614] CPU0: Thermal monitoring enabled (TM1)
[    0.435439] x86/cpu: User Mode Instruction Prevention (UMIP) activated
[    0.436348] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.437117] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.437848] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[    0.438840] Spectre V2 : WARNING: Unprivileged eBPF is enabled with eIBRS on, data leaks possible via Spectre v2 BHB attacks!
[    0.440163] Spectre V2 : Mitigation: Enhanced / Automatic IBRS
[    0.441119] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.442067] Spectre V2 : Spectre v2 / PBRSB-eIBRS: Retire a single CALL on VMEXIT
[    0.442937] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
[    0.443909] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
[    0.444889] MMIO Stale Data: Unknown: No mitigations
[    0.445178] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[    0.446109] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[    0.446853] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[    0.447599] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
[    0.449118] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    0.449860] x86/fpu: xstate_offset[9]:  832, xstate_sizes[9]:    8
[    0.450593] x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format.
[    0.584795] Freeing SMP alternatives memory: 56K
[    0.585118] pid_max: default: 32768 minimum: 301
[    0.585989] LSM: initializing lsm=capability,yama,integrity
[    0.586652] Yama: becoming mindful.
[    0.587517] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.588382] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.591253] Running RCU synchronous self tests
[    0.591789] Running RCU synchronous self tests
[    0.594787] smpboot: CPU0: Genuine Intel(R) 0000 (family: 0x6, model: 0xba, stepping: 0x2)
[    0.596956] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
[    0.597105] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
[    0.597272] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
[    0.598259] Running RCU Tasks wait API self tests
[    0.599075] Running RCU Tasks Rude wait API self tests
[    0.599820] Running RCU Tasks Trace wait API self tests
[    0.600485] Performance Events: unsupported p6 CPU model 186 no PMU driver, software events only.
[    0.601162] signal: max sigframe size: 3632
[    0.601951] rcu: Hierarchical SRCU implementation.
[    0.602522] rcu:     Max phase no-delay instances is 1000.
[    0.605250] Callback from call_rcu_tasks_trace() invoked.
[    0.610604] NMI watchdog: Perf NMI watchdog permanently disabled
[    0.611725] smp: Bringing up secondary CPUs ...
[    0.613436] smpboot: x86: Booting SMP configuration:
[    0.614063] .... node  #0, CPUs:      #1
[    0.614326] smp: Brought up 1 node, 2 CPUs
[    0.614326] smpboot: Max logical packages: 1
[    0.614656] smpboot: Total of 2 processors activated (7526.40 BogoMIPS)
[    0.619161] devtmpfs: initialized
[    0.622064] x86/mm: Memory block size: 128MB
[    0.632095] Running RCU synchronous self tests
[    0.633164] Running RCU synchronous self tests
[    0.633787] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.634919] futex hash table entries: 512 (order: 4, 65536 bytes, linear)
[    0.636463] pinctrl core: initialized pinctrl subsystem

[    0.638422] *************************************************************
[    0.639202] **     NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE    **
[    0.639989] **                                                         **
[    0.640768] **  IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL  **
[    0.641115] **                                                         **
[    0.641893] ** This means that this kernel is built to expose internal **
[    0.642672] ** IOMMU data structures, which may compromise security on **
[    0.643452] ** your system.                                            **
[    0.644241] **                                                         **
[    0.645027] ** If you see this message and you are not debugging the   **
[    0.645126] ** kernel, report this immediately to your vendor!         **
[    0.645908] **                                                         **
[    0.646693] **     NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE    **
[    0.647476] *************************************************************
[    0.648382] PM: RTC time: 12:51:22, date: 2023-12-15
[    0.654282] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.656976] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations
[    0.657156] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.658095] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.659194] audit: initializing netlink subsys (disabled)
[    0.659936] audit: type=2000 audit(1702644683.099:1): state=initialized audit_enabled=0 res=1
[    0.663019] thermal_sys: Registered thermal governor 'fair_share'
[    0.663031] thermal_sys: Registered thermal governor 'bang_bang'
[    0.663814] thermal_sys: Registered thermal governor 'step_wise'
[    0.664512] thermal_sys: Registered thermal governor 'user_space'
[    0.665262] cpuidle: using governor ladder
[    0.666482] cpuidle: using governor menu
[    0.667550] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.669546] PCI: Using configuration type 1 for base access
[    0.671750] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
[    0.980364] Callback from call_rcu_tasks_rude() invoked.
[    0.978765] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
[    0.980204] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
[    0.981149] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
[    0.982576] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
[    0.993776] ACPI: Added _OSI(Module Device)
[    0.994717] ACPI: Added _OSI(Processor Device)
[    0.995619] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.996620] ACPI: Added _OSI(Processor Aggregator Device)
[    1.058015] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    1.075681] ACPI: _OSC evaluation for CPUs failed, trying _PDC
[    1.084028] ACPI: Interpreter enabled
[    1.085209] ACPI: PM: (supports S0 S3 S4 S5)
[    1.086073] ACPI: Using IOAPIC for interrupt routing
[    1.087296] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    1.089125] PCI: Using E820 reservations for host bridge windows
[    1.094195] ACPI: Enabled 2 GPEs in block 00 to 0F
[    1.298871] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    1.299790] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI EDR HPX-Type3]
[    1.300775] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
[    1.301534] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge
[    1.312673] acpiphp: Slot [3] registered
[    1.313425] acpiphp: Slot [4] registered
[    1.314253] acpiphp: Slot [5] registered
[    1.315038] acpiphp: Slot [6] registered
[    1.315810] acpiphp: Slot [7] registered
[    1.316583] acpiphp: Slot [8] registered
[    1.317418] acpiphp: Slot [9] registered
[    1.318193] acpiphp: Slot [10] registered
[    1.319002] acpiphp: Slot [11] registered
[    1.319805] acpiphp: Slot [12] registered
[    1.320627] acpiphp: Slot [13] registered
[    1.321415] acpiphp: Slot [14] registered
[    1.322187] acpiphp: Slot [15] registered
[    1.322966] acpiphp: Slot [16] registered
[    1.323779] acpiphp: Slot [17] registered
[    1.324591] acpiphp: Slot [18] registered
[    1.325384] acpiphp: Slot [19] registered
[    1.326160] acpiphp: Slot [20] registered
[    1.326983] acpiphp: Slot [21] registered
[    1.327808] acpiphp: Slot [22] registered
[    1.328595] acpiphp: Slot [23] registered
[    1.329375] acpiphp: Slot [24] registered
[    1.330184] acpiphp: Slot [25] registered
[    1.330987] acpiphp: Slot [26] registered
[    1.331778] acpiphp: Slot [27] registered
[    1.332576] acpiphp: Slot [28] registered
[    1.333440] acpiphp: Slot [29] registered
[    1.334298] acpiphp: Slot [30] registered
[    1.335090] acpiphp: Slot [31] registered
[    1.335772] PCI host bridge to bus 0000:00
[    1.336299] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    1.337133] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    1.337984] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    1.338906] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window]
[    1.339834] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
[    1.340783] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.341509] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    1.367129] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    1.370545] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    1.373567] pci 0000:00:01.1: reg 0x20: [io  0xc040-0xc04f]
[    1.375197] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    1.376068] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    1.376886] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    1.377118] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    1.378758] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    1.379963] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    1.380853] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    1.381995] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    1.389519] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    1.392526] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    1.401630] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    1.402815] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    1.429598] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    1.431374] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    1.433144] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    1.438071] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    1.479312] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
[    1.482564] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
[    1.485730] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
[    1.488789] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
[    1.490119] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
[    1.496751] iommu: Default domain type: Translated
[    1.497118] iommu: DMA domain TLB invalidation policy: lazy mode
[    1.502291] SCSI subsystem initialized
[    1.505106] libata version 3.00 loaded.
[    1.505106] ACPI: bus type USB registered
[    1.505106] usbcore: registered new interface driver usbfs
[    1.505249] usbcore: registered new interface driver hub
[    1.506020] usbcore: registered new device driver usb
[    1.507168] pps_core: LinuxPPS API ver. 1 registered
[    1.507782] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    1.508944] PTP clock support registered
[    1.513105] EDAC MC: Ver: 3.0.0
[    1.513105] EDAC DEBUG: edac_mc_sysfs_init: device mc created
[    1.515651] NetLabel: Initializing
[    1.516138] NetLabel:  domain hash size = 128
[    1.516678] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    1.517439] NetLabel:  unlabeled traffic allowed by default
[    1.519067] PCI: Using ACPI for IRQ routing
[    1.519604] PCI: pci_cache_line_size set to 64 bytes
[    1.519717] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    1.519746] e820: reserve RAM buffer [mem 0x7ffe0000-0x7fffffff]
[    1.520243] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    1.521008] pci 0000:00:02.0: vgaarb: bridge control possible
[    1.521105] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    1.525132] vgaarb: loaded
[    1.526915] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    1.527558] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    1.552797] clocksource: Switched to clocksource kvm-clock
[    2.854929] VFS: Disk quotas dquot_6.6.0
[    2.855635] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    2.857308] pnp: PnP ACPI init
[    2.858990] pnp 00:02: [dma 2]
[    2.864579] pnp: PnP ACPI: found 6 devices
[    2.884662] Callback from call_rcu_tasks() invoked.
[    2.903979] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    2.908089] NET: Registered PF_INET protocol family
[    2.909354] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    2.911945] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 73728 bytes, linear)
[    2.913036] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    2.914120] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    2.915370] TCP bind hash table entries: 16384 (order: 9, 2359296 bytes, linear)
[    2.918665] TCP: Hash tables configured (established 16384 bind 16384)
[    2.919790] UDP hash table entries: 1024 (order: 5, 163840 bytes, linear)
[    2.920788] UDP-Lite hash table entries: 1024 (order: 5, 163840 bytes, linear)
[    2.922168] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    2.924738] RPC: Registered named UNIX socket transport module.
[    2.925478] RPC: Registered udp transport module.
[    2.926045] RPC: Registered tcp transport module.
[    2.926586] RPC: Registered tcp-with-tls transport module.
[    2.927219] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    2.927988] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    2.928742] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    2.929457] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    2.930251] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window]
[    2.931044] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
[    2.932470] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    2.933153] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    2.933958] PCI: CLS 0 bytes, default 64
[    2.934557] ACPI: bus type thunderbolt registered
[    2.935744] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x363e8c91135, max_idle_ns: 881590568389 ns
[    2.946364] Initialise system trusted keyrings
[    2.947127] Key type blacklist registered
[    2.948351] workingset: timestamp_bits=36 max_order=19 bucket_order=0
[    2.949713] zbud: loaded
[    2.954683] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    2.959237] NFS: Registering the id_resolver key type
[    2.960195] Key type id_resolver registered
[    2.960992] Key type id_legacy registered
[    2.961854] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    2.963128] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    2.965183] fuse: init (API version 7.39)
[    2.967272] SGI XFS with ACLs, security attributes, realtime, quota, no debug enabled
[    2.971900] 9p: Installing v9fs 9p2000 file system support
[    2.991824] Key type asymmetric registered
[    2.992677] Asymmetric key parser 'x509' registered
[    2.993810] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)
[    2.995852] io scheduler mq-deadline registered
[    2.996977] io scheduler bfq registered
[    2.999962] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    3.003910] IPMI message handler: version 39.2
[    3.005019] ipmi device interface
[    3.010217] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    3.012297] ACPI: button: Power Button [PWRF]
[    3.016388] ERST DBG: ERST support is disabled.
[    3.020166] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[    3.021820] serial 00:04: using ACPI '\_SB.PCI0.ISA.COM1' for 'rs485-term' GPIO lookup
[    3.021879] acpi PNP0501:00: GPIO: looking up rs485-term-gpios
[    3.021895] acpi PNP0501:00: GPIO: looking up rs485-term-gpio
[    3.021909] serial 00:04: using lookup tables for GPIO lookup
[    3.021960] serial 00:04: No GPIO consumer rs485-term found
[    3.021974] serial 00:04: using ACPI '\_SB.PCI0.ISA.COM1' for 'rs485-rx-during-tx' GPIO lookup
[    3.022001] acpi PNP0501:00: GPIO: looking up rs485-rx-during-tx-gpios
[    3.022015] acpi PNP0501:00: GPIO: looking up rs485-rx-during-tx-gpio
[    3.022028] serial 00:04: using lookup tables for GPIO lookup
[    3.022040] serial 00:04: No GPIO consumer rs485-rx-during-tx found
[    3.024844] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    3.084046] Linux agpgart interface v0.103
[    3.089620] ACPI: bus type drm_connector registered
[    3.129935] brd: module loaded
[    3.152816] loop: module loaded
[    3.165638] ata_piix 0000:00:01.1: version 2.13
[    3.175449] scsi host0: ata_piix
[    3.179266] scsi host1: ata_piix
[    3.181120] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc040 irq 14 lpm-pol 0
[    3.182458] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc048 irq 15 lpm-pol 0
[    3.186995] mdio_bus fixed-0: using lookup tables for GPIO lookup
[    3.187019] mdio_bus fixed-0: No GPIO consumer reset found
[    3.189116] tun: Universal TUN/TAP device driver, 1.6
[    3.191145] e100: Intel(R) PRO/100 Network Driver
[    3.191722] e100: Copyright(c) 1999-2006 Intel Corporation
[    3.192479] e1000: Intel(R) PRO/1000 Network Driver
[    3.193038] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    3.344966] ata2: found unknown device (class 0)
[    3.346845] ata1: found unknown device (class 0)
[    3.349555] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100
[    3.350950] ata1.00: 17196647 sectors, multi 16: LBA48 
[    3.352691] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[    3.358295] scsi 0:0:0:0: Direct-Access     ATA      QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
[    3.369841] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    3.371837] sd 0:0:0:0: [sda] 17196647 512-byte logical blocks: (8.80 GB/8.20 GiB)
[    3.373449] sd 0:0:0:0: [sda] Write Protect is off
[    3.374200] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    3.375493] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.376920] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[    3.379037] sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes
[    3.390459] sd 0:0:0:0: [sda] Attached SCSI disk
[    3.403689] scsi 1:0:0:0: Attached scsi generic sg1 type 5
[    5.211318] ACPI: \_SB_.LNKC: Enabled at IRQ 11
[    5.545091] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[    5.545817] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[    5.546687] e1000e: Intel(R) PRO/1000 Network Driver
[    5.547157] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[    5.547860] igb: Intel(R) Gigabit Ethernet Network Driver
[    5.548366] igb: Copyright (c) 2007-2014 Intel Corporation.
[    5.549201] PPP generic driver version 2.4.2
[    5.551367] VFIO - User Level meta-driver version: 0.3
[    5.554318] usbcore: registered new interface driver uas
[    5.554970] usbcore: registered new interface driver usb-storage
[    5.555950] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    5.558069] serio: i8042 KBD port at 0x60,0x64 irq 1
[    5.558661] serio: i8042 AUX port at 0x60,0x64 irq 12
[    5.561172] mousedev: PS/2 mouse device common for all mice
[    5.564555] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[    5.564843] rtc_cmos 00:05: RTC can wake from S4
[    5.568042] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4
[    5.571404] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3
[    5.571787] rtc_cmos 00:05: registered as rtc0
[    5.573464] rtc_cmos 00:05: setting system clock to 2023-12-15T12:51:27 UTC (1702644687)
[    5.574895] rtc_cmos 00:05: using ACPI '\_SB.PCI0.ISA.RTC' for 'wp' GPIO lookup
[    5.574931] acpi PNP0B00:00: GPIO: looking up wp-gpios
[    5.574946] acpi PNP0B00:00: GPIO: looking up wp-gpio
[    5.574958] rtc_cmos 00:05: using lookup tables for GPIO lookup
[    5.574973] rtc_cmos 00:05: No GPIO consumer wp found
[    5.575578] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
[    5.577052] i2c_dev: i2c /dev entries driver
[    5.578587] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
[    5.580745] device-mapper: uevent: version 1.0.3
[    5.582706] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com
[    5.583889] intel_pstate: CPU model not supported
[    5.584872] sdhci: Secure Digital Host Controller Interface driver
[    5.585556] sdhci: Copyright(c) Pierre Ossman
[    5.586252] sdhci-pltfm: SDHCI platform and OF driver helper
[    5.587201] ledtrig-cpu: registered to indicate activity on CPUs
[    5.588511] drop_monitor: Initializing network drop monitor service
[    5.589856] NET: Registered PF_INET6 protocol family
[    5.600353] Segment Routing with IPv6
[    5.600953] In-situ OAM (IOAM) with IPv6
[    5.601521] NET: Registered PF_PACKET protocol family
[    5.602609] 9pnet: Installing 9P2000 support
[    5.603672] Key type dns_resolver registered
[    5.608111] IPI shorthand broadcast: enabled
[    5.670395] sched_clock: Marking stable (5652009998, 16434902)->(5683764879, -15319979)
[    5.672707] registered taskstats version 1
[    5.674003] Loading compiled-in X.509 certificates
[    5.737390] Key type .fscrypt registered
[    5.738154] Key type fscrypt-provisioning registered
[    5.747693] Key type encrypted registered
[    5.748313] ima: No TPM chip found, activating TPM-bypass!
[    5.749027] ima: Allocated hash algorithm: sha1
[    5.749698] ima: No architecture policies found
[    5.750492] evm: Initialising EVM extended attributes:
[    5.751078] evm: security.selinux
[    5.751461] evm: security.SMACK64
[    5.751844] evm: security.SMACK64EXEC
[    5.752260] evm: security.SMACK64TRANSMUTE
[    5.752793] evm: security.SMACK64MMAP
[    5.753223] evm: security.apparmor
[    5.753623] evm: security.ima
[    5.753984] evm: security.capability
[    5.754392] evm: HMAC attrs: 0x1
[    5.759153] PM:   Magic number: 3:775:885
[    5.762046] RAS: Correctable Errors collector initialized.
[    5.763756] clk: Disabling unused clocks
[    5.766684] md: Waiting for all devices to be available before autodetect
[    5.767490] md: If you don't use raid, use raid=noautodetect
[    5.768159] md: Autodetecting RAID arrays.
[    5.768724] md: autorun ...
[    5.769070] md: ... autorun DONE.
[    5.787921] EXT4-fs (sda): mounted filesystem 088740a9-c8b4-422c-999d-a804eb68a4da ro with ordered data mode. Quota mode: none.
[    5.789213] VFS: Mounted root (ext4 filesystem) readonly on device 8:0.
[    5.792869] devtmpfs: mounted
[    5.818102] Freeing unused decrypted memory: 2028K
[    5.822351] Freeing unused kernel image (initmem) memory: 14912K
[    5.822962] Write protecting the kernel read-only data: 92160k
[    5.825623] Freeing unused kernel image (rodata/data gap) memory: 1732K
[    5.891038] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    5.891663] Run /sbin/init as init process
[    5.892013]   with arguments:
[    5.892020]     /sbin/init
[    5.892025]   with environment:
[    5.892030]     HOME=/
[    5.892035]     TERM=linux
[    6.158084] systemd[1]: systemd 252-15.el9 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL )
[    6.161817] systemd[1]: Detected virtualization kvm.
[    6.162442] systemd[1]: Detected architecture x86-64.
[    6.173993] systemd[1]: Hostname set to <test>.
[    6.632825] systemd-rc-local-generator[123]: /etc/rc.d/rc.local is not marked executable, skipping.
[    7.132053] systemd[1]: Queued start job for default target Multi-User System.
[    7.156507] systemd[1]: Created slice Virtual Machine and Container Slice.
[    7.165822] systemd[1]: Created slice Slice /system/getty.
[    7.170282] systemd[1]: Created slice Slice /system/modprobe.
[    7.174578] systemd[1]: Created slice Slice /system/serial-getty.
[    7.179963] systemd[1]: Created slice Slice /system/sshd-keygen.
[    7.184079] systemd[1]: Created slice User and Session Slice.
[    7.186554] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    7.190490] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    7.192889] systemd[1]: Reached target Local Integrity Protected Volumes.
[    7.194904] systemd[1]: Reached target Slice Units.
[    7.196380] systemd[1]: Reached target Swaps.
[    7.197818] systemd[1]: Reached target Local Verity Protected Volumes.
[    7.200248] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[    7.203266] systemd[1]: Listening on LVM2 poll daemon socket.
[    7.205209] systemd[1]: multipathd control socket was skipped because of an unmet condition check (ConditionPathExists=/etc/multipath.conf).
[    7.301625] systemd[1]: Listening on RPCbind Server Activation Socket.
[    7.304130] systemd[1]: Reached target RPC Port Mapper.
[    7.310904] systemd[1]: Listening on Process Core Dump Socket.
[    7.313895] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    7.317884] systemd[1]: Listening on Journal Socket (/dev/log).
[    7.321331] systemd[1]: Listening on Journal Socket.
[    7.324768] systemd[1]: Listening on udev Control Socket.
[    7.327505] systemd[1]: Listening on udev Kernel Socket.
[    7.369154] systemd[1]: Mounting Huge Pages File System...
[    7.379868] systemd[1]: Mounting POSIX Message Queue File System...
[    7.397959] systemd[1]: Mounting Kernel Debug File System...
[    7.416605] systemd[1]: Mounting Kernel Trace File System...
[    7.419213] systemd[1]: Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keyta.
[    7.421649] systemd[1]: Create List of Static Device Nodes was skipped because of an unmet condition check (ConditionFileNotEmpty=/lib/modules/6.
[    7.437662] systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
[    7.456811] systemd[1]: Starting Load Kernel Module configfs...
[    7.474150] systemd[1]: Starting Load Kernel Module drm...
[    7.498857] systemd[1]: Starting Load Kernel Module fuse...
[    7.517304] systemd[1]: Starting Read and set NIS domainname from /etc/sysconfig/network...
[    7.564289] systemd[1]: Starting Journal Service...
[    7.613537] systemd[1]: Starting Load Kernel Modules...
[    7.653582] systemd[1]: Starting Generate network units from Kernel command line...
[    7.714292] systemd[1]: Starting Remount Root and Kernel File Systems...
[    7.721733] systemd[1]: Repartition Root Disk was skipped because no trigger condition checks were met.
[    7.769500] systemd[1]: Starting Coldplug All udev Devices...
[    7.822099] systemd[1]: Mounted Huge Pages File System.
[    7.825561] systemd[1]: Mounted POSIX Message Queue File System.
[    7.830906] systemd[1]: Mounted Kernel Debug File System.
[    7.833561] EXT4-fs (sda): re-mounted 088740a9-c8b4-422c-999d-a804eb68a4da r/w. Quota mode: none.
[    7.856062] systemd[1]: Started Journal Service.
[    8.111324] systemd-journald[145]: Received client request to flush runtime journal.
[   13.852812] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX