* Re: [Syzkaller & bisect] There is BUG: soft lockup in __cleanup_mnt in v6.3-rc1 kernel
2023-03-14 1:45 ` [Syzkaller & bisect] There is BUG: soft lockup in __cleanup_mnt in v6.3-rc1 kernel Darrick J. Wong
@ 2023-03-14 2:15 ` Pengfei Xu
0 siblings, 0 replies; 2+ messages in thread
From: Pengfei Xu @ 2023-03-14 2:15 UTC (permalink / raw)
To: Darrick J. Wong; +Cc: dchinner, linux-kernel, heng.su, lkp, xfs
[-- Attachment #1: Type: text/plain, Size: 17733 bytes --]
Hi Darrick,
On 2023-03-13 at 18:45:43 -0700, Darrick J. Wong wrote:
> On Sun, Mar 12, 2023 at 11:21:04AM +0800, Pengfei Xu wrote:
> > Hi Dave Chinner and kernel expert,
> >
> > Greeting!
>
> <cough> Please cc the xfs list with xfs bug reports.
>
Ah, will send the xfs issue to xfs list next time.
> Have you tried 6.3-rc2?
>
I tried v6.3-rc2 kernel, this issue could be reproduced in v6.3-rc2.
Dmesg is in attached.
"
[ 339.367548] XFS (loop0): Corruption of in-memory data (0x8) detected at _xfs_buf_ioapply+0x67d/0x690 (fs/xfs/xfs_buf.c:1553). Shutting down filesystem.
[ 339.368164] XFS (loop0): Please unmount the filesystem and rectify the problem(s)
[ 364.436316] watchdog: BUG: soft lockup - CPU#0 stuck for 26s! [repro:405]
[ 364.436724] Modules linked in:
[ 364.436868] irq event stamp: 21510
[ 364.437026] hardirqs last enabled at (21509): [<ffffffff8300187e>] irqentry_exit+0x3e/0xa0
[ 364.437406] hardirqs last disabled at (21510): [<ffffffff82fff863>] sysvec_apic_timer_interrupt+0x13/0xe0
[ 364.437826] softirqs last enabled at (8970): [<ffffffff83024a4c>] __do_softirq+0x31c/0x49c
[ 364.438195] softirqs last disabled at (8897): [<ffffffff8112b5a4>] irq_exit_rcu+0xc4/0x100
[ 364.438564] CPU: 0 PID: 405 Comm: repro Not tainted 6.3.0-rc2-eeac8ede1755+ #1
[ 364.438885] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.0-0-gd239552ce722-prebuilt.qemu.org 04/01/2014
[ 364.439382] RIP: 0010:rcu_lockdep_current_cpu_online+0x5b/0x90
[ 364.439657] Code: 5b 41 5c 5d c3 cc cc cc cc 65 ff 05 d7 bb e1 7e e8 fa 94 de 01 48 c7 c3 80 61 03 00 89 c0 48 03 1c c5 00 7b cb 83 48 8b 43 18 <48> 8b 40 70 48 84
[ 364.440449] RSP: 0018:ffffc90000eb3ba8 EFLAGS: 00000282
[ 364.440689] RAX: ffffffff83f64340 RBX: ffff88807dc36180 RCX: 0000000000000000
[ 364.441003] RDX: 0000000000000000 RSI: 0000000000000003 RDI: 0000000000000000
[ 364.441313] RBP: ffffc90000eb3bb8 R08: 0000000000000001 R09: 0000000000000000
[ 364.441626] R10: ffff88800f7e30d8 R11: 0000000000000000 R12: 0000000000000001
[ 364.441938] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 364.442248] FS: 00007f9db4acf740(0000) GS:ffff88807dc00000(0000) knlGS:0000000000000000
[ 364.442597] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 364.442852] CR2: 00007f5e8f6f5518 CR3: 000000000f6da004 CR4: 0000000000770ef0
[ 364.443169] PKRU: 55555554
[ 364.443297] Call Trace:
[ 364.443412] <TASK>
[ 364.443519] rcu_read_lock_held_common+0x33/0x50
[ 364.443741] rcu_read_lock_sched_held+0x29/0x80
[ 364.443956] lock_acquire+0x2ad/0x320
[ 364.444133] ? lock_release+0x141/0x2e0
[ 364.444313] ? write_comp_data+0x2f/0x90
[ 364.444507] _raw_spin_lock+0x36/0x50
[ 364.444681] ? xfs_ail_push_all_sync+0x73/0x110
[ 364.444893] xfs_ail_push_all_sync+0x73/0x110
[ 364.445097] ? __pfx_autoremove_wake_function+0x10/0x10
[ 364.445343] xfs_reclaim_inodes+0xb8/0x140
[ 364.445543] xfs_unmount_flush_inodes+0x63/0x80
[ 364.445757] xfs_unmountfs+0x69/0x1f0
[ 364.445939] xfs_fs_put_super+0x5a/0x120
[ 364.446130] ? __pfx_xfs_fs_put_super+0x10/0x10
[ 364.446356] generic_shutdown_super+0xac/0x240
[ 364.446573] kill_block_super+0x43/0x80
[ 364.446759] deactivate_locked_super+0x52/0xb0
[ 364.446966] deactivate_super+0xb3/0xd0
[ 364.447156] cleanup_mnt+0x15e/0x1e0
[ 364.447335] __cleanup_mnt+0x1f/0x30
[ 364.447513] task_work_run+0xb6/0x120
[ 364.447736] exit_to_user_mode_prepare+0x200/0x210
[ 364.448017] syscall_exit_to_user_mode+0x2d/0x60
[ 364.448293] do_syscall_64+0x4a/0x90
[ 364.448513] entry_SYSCALL_64_after_hwframe+0x72/0xdc
[ 364.448809] RIP: 0033:0x7f9db4bfb7db
[ 364.449033] Code: 96 0c 00 f7 d8 64 89 01 48 83 c8 ff c3 66 90 f3 0f 1e fa 31 f6 e9 05 00 00 00 0f 1f 44 00 00 f3 0f 1e fa b8 a6 00 00 00 0f 05 <48> 3d 01 f0 ff f8
[ 364.449954] RSP: 002b:00007fff5b16db78 EFLAGS: 00000206 ORIG_RAX: 00000000000000a6
[ 364.450376] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00007f9db4bfb7db
[ 364.450745] RDX: 0000000000000000 RSI: 000000000000000a RDI: 00007fff5b16dc20
[ 364.451108] RBP: 00007fff5b16ec60 R08: 0000000001e88333 R09: 0000000000000009
[ 364.451484] R10: 0000000000404071 R11: 0000000000000206 R12: 00000000004012c0
[ 364.451847] R13: 00007fff5b16eda0 R14: 0000000000000000 R15: 0000000000000000
[ 364.452195] </TASK>
[ 364.452301] Kernel panic - not syncing: softlockup: hung tasks
[ 364.452562] CPU: 0 PID: 405 Comm: repro Tainted: G L 6.3.0-rc2-eeac8ede1755+ #1
[ 364.453010] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.0-0-gd239552ce722-prebuilt.qemu.org 04/01/2014
[ 364.453551] Call Trace:
[ 364.453701] <IRQ>
[ 364.453826] dump_stack_lvl+0xa9/0x110
[ 364.454057] dump_stack+0x19/0x20
[ 364.454262] panic+0x4fd/0x520
[ 364.454455] ? watchdog_timer_fn+0x31e/0x390
[ 364.454689] watchdog_timer_fn+0x337/0x390
[ 364.454886] ? __pfx_watchdog_timer_fn+0x10/0x10
[ 364.455101] __hrtimer_run_queues+0x2ed/0x820
[ 364.455355] hrtimer_interrupt+0x134/0x2d0
[ 364.455604] __sysvec_apic_timer_interrupt+0x9c/0x290
[ 364.455902] sysvec_apic_timer_interrupt+0xab/0xe0
[ 364.456185] </IRQ>
[ 364.456314] <TASK>
[ 364.456441] asm_sysvec_apic_timer_interrupt+0x1f/0x30
[ 364.456742] RIP: 0010:rcu_lockdep_current_cpu_online+0x5b/0x90
[ 364.457067] Code: 5b 41 5c 5d c3 cc cc cc cc 65 ff 05 d7 bb e1 7e e8 fa 94 de 01 48 c7 c3 80 61 03 00 89 c0 48 03 1c c5 00 7b cb 83 48 8b 43 18 <48> 8b 40 70 48 84
[ 364.457937] RSP: 0018:ffffc90000eb3ba8 EFLAGS: 00000282
[ 364.458211] RAX: ffffffff83f64340 RBX: ffff88807dc36180 RCX: 0000000000000000
[ 364.458613] RDX: 0000000000000000 RSI: 0000000000000003 RDI: 0000000000000000
[ 364.459006] RBP: ffffc90000eb3bb8 R08: 0000000000000001 R09: 0000000000000000
[ 364.459418] R10: ffff88800f7e30d8 R11: 0000000000000000 R12: 0000000000000001
[ 364.459813] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 364.460213] ? rcu_lockdep_current_cpu_online+0x46/0x90
[ 364.460465] rcu_read_lock_held_common+0x33/0x50
[ 364.460743] rcu_read_lock_sched_held+0x29/0x80
[ 364.460980] lock_acquire+0x2ad/0x320
[ 364.461170] ? lock_release+0x141/0x2e0
[ 364.461391] ? write_comp_data+0x2f/0x90
[ 364.461590] _raw_spin_lock+0x36/0x50
[ 364.461804] ? xfs_ail_push_all_sync+0x73/0x110
[ 364.462018] xfs_ail_push_all_sync+0x73/0x110
[ 364.462228] ? __pfx_autoremove_wake_function+0x10/0x10
[ 364.462479] xfs_reclaim_inodes+0xb8/0x140
[ 364.462682] xfs_unmount_flush_inodes+0x63/0x80
[ 364.462933] xfs_unmountfs+0x69/0x1f0
[ 364.463112] xfs_fs_put_super+0x5a/0x120
[ 364.463304] ? __pfx_xfs_fs_put_super+0x10/0x10
[ 364.463522] generic_shutdown_super+0xac/0x240
[ 364.463741] kill_block_super+0x43/0x80
[ 364.463930] deactivate_locked_super+0x52/0xb0
[ 364.464154] deactivate_super+0xb3/0xd0
[ 364.464348] cleanup_mnt+0x15e/0x1e0
[ 364.464564] __cleanup_mnt+0x1f/0x30
[ 364.464787] task_work_run+0xb6/0x120
[ 364.465002] exit_to_user_mode_prepare+0x200/0x210
[ 364.465226] syscall_exit_to_user_mode+0x2d/0x60
[ 364.465449] do_syscall_64+0x4a/0x90
[ 364.465621] entry_SYSCALL_64_after_hwframe+0x72/0xdc
[ 364.465861] RIP: 0033:0x7f9db4bfb7db
[ 364.466065] Code: 96 0c 00 f7 d8 64 89 01 48 83 c8 ff c3 66 90 f3 0f 1e fa 31 f6 e9 05 00 00 00 0f 1f 44 00 00 f3 0f 1e fa b8 a6 00 00 00 0f 05 <48> 3d 01 f0 ff f8
[ 364.466917] RSP: 002b:00007fff5b16db78 EFLAGS: 00000206 ORIG_RAX: 00000000000000a6
[ 364.467262] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00007f9db4bfb7db
[ 364.467582] RDX: 0000000000000000 RSI: 000000000000000a RDI: 00007fff5b16dc20
[ 364.467902] RBP: 00007fff5b16ec60 R08: 0000000001e88333 R09: 0000000000000009
[ 364.468228] R10: 0000000000404071 R11: 0000000000000206 R12: 00000000004012c0
[ 364.468605] R13: 00007fff5b16eda0 R14: 0000000000000000 R15: 0000000000000000
[ 364.468992] </TASK>
[ 364.469185] Kernel Offset: disabled
[ 364.469398] ---[ end Kernel panic - not syncing: softlockup: hung tasks ]---
"
Thanks!
BR.
> --D
>
> > Platform: x86 platforms
> >
> > There is BUG: soft lockup in __cleanup_mnt in v6.3-rc1 kernel.
> > All detailed info: https://github.com/xupengfe/syzkaller_logs/tree/main/230311_022102___cleanup_mnt
> > Reproduced code: https://github.com/xupengfe/syzkaller_logs/blob/main/230311_022102___cleanup_mnt/repro.c
> > v6.3-rc1 issue dmesg: https://github.com/xupengfe/syzkaller_logs/blob/main/230311_022102___cleanup_mnt/fe15c26ee26efa11741a7b632e9f23b01aca4cc6_dmesg.log
> > Kconfig: https://github.com/xupengfe/syzkaller_logs/blob/main/230311_022102___cleanup_mnt/kconfig_origin
> > Bisect info: https://github.com/xupengfe/syzkaller_logs/blob/main/230311_022102___cleanup_mnt/bisect_info.log
> >
> > "
> > [ 28.803497] XFS (loop0): Corruption of in-memory data (0x8) detected at _xfs_buf_ioapply+0x67d/0x690 (fs/xfs/xfs_buf.c:1553). Shutting down filesystem.
> > [ 28.804766] XFS (loop0): Please unmount the filesystem and rectify the problem(s)
> > [ 56.372468] watchdog: BUG: soft lockup - CPU#1 stuck for 26s! [repro:523]
> > [ 56.372847] Modules linked in:
> > [ 56.372985] irq event stamp: 22822
> > [ 56.373141] hardirqs last enabled at (22821): [<ffffffff8300086e>] irqentry_exit+0x3e/0xa0
> > [ 56.373512] hardirqs last disabled at (22822): [<ffffffff82ffe853>] sysvec_apic_timer_interrupt+0x13/0xe0
> > [ 56.373923] softirqs last enabled at (9032): [<ffffffff83023a3c>] __do_softirq+0x31c/0x49c
> > [ 56.374276] softirqs last disabled at (8975): [<ffffffff8112b5a4>] irq_exit_rcu+0xc4/0x100
> > [ 56.374623] CPU: 1 PID: 523 Comm: repro Not tainted 6.3.0-rc1-fe15c26ee26e+ #1
> > [ 56.374924] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.0-0-gd239552ce722-prebuilt.qemu.org 04/01/2014
> > [ 56.375386] RIP: 0010:rcu_read_lock_sched_held+0x4b/0x80
> > [ 56.375621] Code: 8d 7d f7 e8 c7 fe ff ff 84 c0 74 19 0f b6 45 f7 48 8b 4d f8 65 48 33 0c 25 28 00 00 00 75 38 c9 c3 cc cc cc cc be ff ff ff ff <48> c7 c7 20 3e f6 83 e8 89 ec de 01 89 c2 b8 01 00 00 00 85 d2 75
> > [ 56.376376] RSP: 0018:ffffc9000102fbe0 EFLAGS: 00000246
> > [ 56.376597] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 0000000000000000
> > [ 56.376891] RDX: 0000000000000000 RSI: 00000000ffffffff RDI: 0000000000000000
> > [ 56.377192] RBP: ffffc9000102fbf0 R08: 0000000000000001 R09: 0000000000000000
> > [ 56.377486] R10: ffff88800d01d418 R11: 0000000000000000 R12: ffff88800b0d1158
> > [ 56.377780] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
> > [ 56.378082] FS: 00007fc887d10740(0000) GS:ffff88807dd00000(0000) knlGS:0000000000000000
> > [ 56.378414] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> > [ 56.378655] CR2: 00007faaae519018 CR3: 000000000d3e2004 CR4: 0000000000770ee0
> > [ 56.378950] PKRU: 55555554
> > [ 56.379069] Call Trace:
> > [ 56.379178] <TASK>
> > [ 56.379276] lock_acquire+0x2ad/0x320
> > [ 56.379444] ? lock_release+0x141/0x2e0
> > [ 56.379615] ? write_comp_data+0x2f/0x90
> > [ 56.379799] _raw_spin_lock+0x36/0x50
> > [ 56.379962] ? xfs_ail_push_all_sync+0x73/0x110
> > [ 56.380169] xfs_ail_push_all_sync+0x73/0x110
> > [ 56.380360] ? __pfx_autoremove_wake_function+0x10/0x10
> > [ 56.380591] xfs_reclaim_inodes+0xb8/0x140
> > [ 56.380779] xfs_unmount_flush_inodes+0x63/0x80
> > [ 56.380987] xfs_unmountfs+0x69/0x1f0
> > [ 56.381152] xfs_fs_put_super+0x5a/0x120
> > [ 56.381331] ? __pfx_xfs_fs_put_super+0x10/0x10
> > [ 56.381534] generic_shutdown_super+0xac/0x240
> > [ 56.381740] kill_block_super+0x43/0x80
> > [ 56.381917] deactivate_locked_super+0x52/0xb0
> > [ 56.382112] deactivate_super+0xb3/0xd0
> > [ 56.382282] cleanup_mnt+0x15e/0x1e0
> > [ 56.382451] __cleanup_mnt+0x1f/0x30
> > [ 56.382613] task_work_run+0xb6/0x120
> > [ 56.382783] exit_to_user_mode_prepare+0x200/0x210
> > [ 56.383000] syscall_exit_to_user_mode+0x2d/0x60
> > [ 56.383205] do_syscall_64+0x4a/0x90
> > [ 56.383368] entry_SYSCALL_64_after_hwframe+0x72/0xdc
> > [ 56.383593] RIP: 0033:0x7fc887e3c7db
> > [ 56.383751] Code: 96 0c 00 f7 d8 64 89 01 48 83 c8 ff c3 66 90 f3 0f 1e fa 31 f6 e9 05 00 00 00 0f 1f 44 00 00 f3 0f 1e fa b8 a6 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 95 96 0c 00 f7 d8 64 89 01 48
> > [ 56.384500] RSP: 002b:00007ffc1a5bd1e8 EFLAGS: 00000206 ORIG_RAX: 00000000000000a6
> > [ 56.384815] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00007fc887e3c7db
> > [ 56.385114] RDX: 0000000000000000 RSI: 000000000000000a RDI: 00007ffc1a5bd290
> > [ 56.385407] RBP: 00007ffc1a5be2d0 R08: 0000000001d0a333 R09: 0000000000000009
> > [ 56.385700] R10: 0000000000404071 R11: 0000000000000206 R12: 00000000004012c0
> > [ 56.385994] R13: 00007ffc1a5be410 R14: 0000000000000000 R15: 0000000000000000
> > [ 56.386299] </TASK>
> > [ 56.386400] Kernel panic - not syncing: softlockup: hung tasks
> > [ 56.386643] CPU: 1 PID: 523 Comm: repro Tainted: G L 6.3.0-rc1-fe15c26ee26e+ #1
> > [ 56.387000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.0-0-gd239552ce722-prebuilt.qemu.org 04/01/2014
> > [ 56.387461] Call Trace:
> > [ 56.387569] <IRQ>
> > [ 56.387662] dump_stack_lvl+0xa9/0x110
> > [ 56.387834] dump_stack+0x19/0x20
> > [ 56.387980] panic+0x4fd/0x520
> > [ 56.388119] ? watchdog_timer_fn+0x31e/0x390
> > [ 56.388315] watchdog_timer_fn+0x337/0x390
> > [ 56.388497] ? __pfx_watchdog_timer_fn+0x10/0x10
> > [ 56.388710] __hrtimer_run_queues+0x2ed/0x820
> > [ 56.388924] hrtimer_interrupt+0x134/0x2d0
> > [ 56.389111] __sysvec_apic_timer_interrupt+0x9c/0x290
> > [ 56.389331] sysvec_apic_timer_interrupt+0xab/0xe0
> > [ 56.389535] </IRQ>
> > [ 56.389628] <TASK>
> > [ 56.389722] asm_sysvec_apic_timer_interrupt+0x1f/0x30
> > [ 56.389940] RIP: 0010:rcu_read_lock_sched_held+0x4b/0x80
> > [ 56.390166] Code: 8d 7d f7 e8 c7 fe ff ff 84 c0 74 19 0f b6 45 f7 48 8b 4d f8 65 48 33 0c 25 28 00 00 00 75 38 c9 c3 cc cc cc cc be ff ff ff ff <48> c7 c7 20 3e f6 83 e8 89 ec de 01 89 c2 b8 01 00 00 00 85 d2 75
> > [ 56.390909] RSP: 0018:ffffc9000102fbe0 EFLAGS: 00000246
> > [ 56.391129] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 0000000000000000
> > [ 56.391422] RDX: 0000000000000000 RSI: 00000000ffffffff RDI: 0000000000000000
> > [ 56.391715] RBP: ffffc9000102fbf0 R08: 0000000000000001 R09: 0000000000000000
> > [ 56.392009] R10: ffff88800d01d418 R11: 0000000000000000 R12: ffff88800b0d1158
> > [ 56.392303] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
> > [ 56.392607] lock_acquire+0x2ad/0x320
> > [ 56.392773] ? lock_release+0x141/0x2e0
> > [ 56.392941] ? write_comp_data+0x2f/0x90
> > [ 56.393122] _raw_spin_lock+0x36/0x50
> > [ 56.393285] ? xfs_ail_push_all_sync+0x73/0x110
> > [ 56.393483] xfs_ail_push_all_sync+0x73/0x110
> > [ 56.393672] ? __pfx_autoremove_wake_function+0x10/0x10
> > [ 56.393900] xfs_reclaim_inodes+0xb8/0x140
> > [ 56.394087] xfs_unmount_flush_inodes+0x63/0x80
> > [ 56.394285] xfs_unmountfs+0x69/0x1f0
> > [ 56.394450] xfs_fs_put_super+0x5a/0x120
> > [ 56.394627] ? __pfx_xfs_fs_put_super+0x10/0x10
> > [ 56.394829] generic_shutdown_super+0xac/0x240
> > [ 56.395030] kill_block_super+0x43/0x80
> > [ 56.395207] deactivate_locked_super+0x52/0xb0
> > [ 56.395403] deactivate_super+0xb3/0xd0
> > [ 56.395574] cleanup_mnt+0x15e/0x1e0
> > [ 56.395739] __cleanup_mnt+0x1f/0x30
> > [ 56.395902] task_work_run+0xb6/0x120
> > [ 56.396071] exit_to_user_mode_prepare+0x200/0x210
> > [ 56.396282] syscall_exit_to_user_mode+0x2d/0x60
> > [ 56.396487] do_syscall_64+0x4a/0x90
> > [ 56.396648] entry_SYSCALL_64_after_hwframe+0x72/0xdc
> > [ 56.396865] RIP: 0033:0x7fc887e3c7db
> > [ 56.397020] Code: 96 0c 00 f7 d8 64 89 01 48 83 c8 ff c3 66 90 f3 0f 1e fa 31 f6 e9 05 00 00 00 0f 1f 44 00 00 f3 0f 1e fa b8 a6 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 95 96 0c 00 f7 d8 64 89 01 48
> > [ 56.397761] RSP: 002b:00007ffc1a5bd1e8 EFLAGS: 00000206 ORIG_RAX: 00000000000000a6
> > [ 56.398072] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00007fc887e3c7db
> > [ 56.398365] RDX: 0000000000000000 RSI: 000000000000000a RDI: 00007ffc1a5bd290
> > [ 56.398658] RBP: 00007ffc1a5be2d0 R08: 0000000001d0a333 R09: 0000000000000009
> > [ 56.398951] R10: 0000000000404071 R11: 0000000000000206 R12: 00000000004012c0
> > [ 56.399244] R13: 00007ffc1a5be410 R14: 0000000000000000 R15: 0000000000000000
> > [ 56.399546] </TASK>
> > [ 56.399792] Kernel Offset: disabled
> > [ 56.399972] ---[ end Kernel panic - not syncing: softlockup: hung tasks ]---
> > "
> >
> > I used the key word "__cleanup_mnt" to bisect, I knew there was other BUG
> > info in dmesg with "bisect good" commit log.
> >
> > Anyway, just provided the bad commit and hope it's helpful:
> > "
> > bd4f5d09cc93c8ca51e4efea86ac90a4bb553d6e
> > xfs: refactor the filestreams allocator pick functions
> > "
> > It's just a suspected commit, reverted above commit on top of v6.3-rc1 and
> > no "__cleanup_mnt" related BUG but generated the other BUG info in dmesg.
> >
> > There was no this issue report yet in syzbot:
> > https://syzkaller.appspot.com/upstream
> >
> > I tried that, installed the xfs fixed patch for below issue on top of v6.3-rc1:
> > https://lore.kernel.org/lkml/20230226223403.GU360264@dread.disaster.area/
> > "__cleanup_mnt" BUG issue still existed.
> > Related dmesg: https://github.com/xupengfe/syzkaller_logs/blob/main/230311_022102___cleanup_mnt/v6.3-rc1_xfs_patch_another_cleanup_mnt_issue.log
> > So it should be different than previous xfs related issue.
> >
[-- Attachment #2: v6.3-rc2_cleanup_mnt_issue.log --]
[-- Type: text/plain, Size: 56419 bytes --]
^[c^[[?7l^[[2J^[[0mSeaBIOS (version rel-1.16.0-0-gd239552ce722-prebuilt.qemu.org)
iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+7FF91000+7FEF1000 CA00
Press Ctrl-B to configure iPXE (PCI 00:03.0)...\r
Booting from ROM..^[c^[[?7l^[[2J[ 0.000000] Linux version 6.3.0-rc2-eeac8ede1755+ (root@p-adls01) (gcc (GCC) 8.5.0 20210514 (Red Hat 8.5.0-18), GNU ld version 2.36.1-2.el8) #1 SMP PREEMPT_DYNAMIC Mon Mar 13 19:17:17 CST 2023
[ 0.000000] Command line: console=ttyS0 root=/dev/sda earlyprintk=serial net.ifnames=0 thunderbolt.dyndbg
[ 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] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
[ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
[ 0.000000] x86/fpu: xstate_offset[9]: 832, xstate_sizes[9]: 8
[ 0.000000] x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format.
[ 0.000000] signal: max sigframe size: 3632
[ 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: bootconsole [earlyser0] enabled
[ 0.000000] NX (Execute Disable) protection: active
[ 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 482927573 cycles
[ 0.000452] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[ 0.001814] tsc: Detected 3609.600 MHz processor
[ 0.004000] last_pfn = 0x7ffe0 max_arch_pfn = 0x400000000
[ 0.004000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
[ 0.004000] found SMP MP-table at [mem 0x000f5ba0-0x000f5baf]
[ 0.004000] Using GB pages for direct mapping
[ 0.004000] ACPI: Early table checksum verification disabled
[ 0.004000] ACPI: RSDP 0x00000000000F59C0 000014 (v00 BOCHS )
[ 0.004000] ACPI: RSDT 0x000000007FFE1951 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)
[ 0.004000] ACPI: FACP 0x000000007FFE17FD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)
[ 0.004000] ACPI: DSDT 0x000000007FFE0040 0017BD (v01 BOCHS BXPC 00000001 BXPC 00000001)
[ 0.004000] ACPI: FACS 0x000000007FFE0000 000040
[ 0.004000] ACPI: APIC 0x000000007FFE1871 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001)
[ 0.004000] ACPI: HPET 0x000000007FFE18F1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)
[ 0.004000] ACPI: WAET 0x000000007FFE1929 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)
[ 0.004000] ACPI: Reserving FACP table memory at [mem 0x7ffe17fd-0x7ffe1870]
[ 0.004000] ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe17fc]
[ 0.004000] ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f]
[ 0.004000] ACPI: Reserving APIC table memory at [mem 0x7ffe1871-0x7ffe18f0]
[ 0.004000] ACPI: Reserving HPET table memory at [mem 0x7ffe18f1-0x7ffe1928]
[ 0.004000] ACPI: Reserving WAET table memory at [mem 0x7ffe1929-0x7ffe1950]
[ 0.004000] No NUMA configuration found
[ 0.004000] Faking a node at [mem 0x0000000000000000-0x000000007ffdffff]
[ 0.004000] NODE_DATA(0) allocated [mem 0x7ffb5000-0x7ffdffff]
[ 0.004000] Zone ranges:
[ 0.004000] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.004000] DMA32 [mem 0x0000000001000000-0x000000007ffdffff]
[ 0.004000] Normal empty
[ 0.004000] Device empty
[ 0.004000] Movable zone start for each node
[ 0.004000] Early memory node ranges
[ 0.004000] node 0: [mem 0x0000000000001000-0x000000000009efff]
[ 0.004000] node 0: [mem 0x0000000000100000-0x000000007ffdffff]
[ 0.004000] Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdffff]
[ 0.004000] On node 0, zone DMA: 1 pages in unavailable ranges
[ 0.004000] On node 0, zone DMA: 97 pages in unavailable ranges
[ 0.004000] On node 0, zone DMA32: 32 pages in unavailable ranges
[ 0.004000] ACPI: PM-Timer IO Port: 0x608
[ 0.004000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[ 0.004000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[ 0.004000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.004000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[ 0.004000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.004000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[ 0.004000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[ 0.004000] ACPI: Using ACPI (MADT) for SMP configuration information
[ 0.004000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[ 0.004000] TSC deadline timer available
[ 0.004000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[ 0.004000] kvm-guest: KVM setup pv remote TLB flush
[ 0.004000] kvm-guest: setup PV sched yield
[ 0.004000] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
[ 0.004000] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[ 0.004000] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff]
[ 0.004000] PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[ 0.004000] [mem 0x80000000-0xfeffbfff] available for PCI devices
[ 0.004000] Booting paravirtualized kernel on KVM
[ 0.004000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[ 0.004000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[ 0.004000] percpu: Embedded 65 pages/cpu s229376 r8192 d28672 u1048576
[ 0.004000] kvm-guest: PV spinlocks enabled
[ 0.004000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
[ 0.004000] Fallback order for Node 0: 0
[ 0.004000] Built 1 zonelists, mobility grouping on. Total pages: 515808
[ 0.004000] Policy zone: DMA32
[ 0.004000] Kernel command line: net.ifnames=0 console=ttyS0 root=/dev/sda earlyprintk=serial net.ifnames=0 thunderbolt.dyndbg
[ 0.004000] random: crng init done
[ 0.004000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[ 0.004000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[ 0.004000] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.004000] Memory: 1964888K/2096632K available (36864K kernel code, 4578K rwdata, 9848K rodata, 10508K init, 31780K bss, 131488K reserved, 0K cma-reserved)
[ 0.004000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[ 0.004000] kmemleak: Kernel memory leak detector disabled
[ 0.004000] ftrace: allocating 68737 entries in 269 pages
[ 0.004000] ftrace: allocated 269 pages with 4 groups
[ 0.004000] Dynamic Preempt: voluntary
[ 0.004000] Running RCU self tests
[ 0.004000] Running RCU synchronous self tests
[ 0.004000] rcu: Preemptible hierarchical RCU implementation.
[ 0.004000] rcu: RCU lockdep checking is enabled.
[ 0.004000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2.
[ 0.004000] Trampoline variant of Tasks RCU enabled.
[ 0.004000] Rude variant of Tasks RCU enabled.
[ 0.004000] Tracing variant of Tasks RCU enabled.
[ 0.004000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[ 0.004000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[ 0.004000] Running RCU synchronous self tests
[ 0.004000] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16
[ 0.004000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[ 0.004000] Console: colour VGA+ 80x25
[ 0.004000] printk: console [ttyS0] enabled
[ 0.004000] printk: console [ttyS0] enabled
[ 0.004000] printk: bootconsole [earlyser0] disabled
[ 0.004000] printk: bootconsole [earlyser0] disabled
[ 0.004000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 0.004000] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 0.004000] ... MAX_LOCK_DEPTH: 48
[ 0.004000] ... MAX_LOCKDEP_KEYS: 8192
[ 0.004000] ... CLASSHASH_SIZE: 4096
[ 0.004000] ... MAX_LOCKDEP_ENTRIES: 32768
[ 0.004000] ... MAX_LOCKDEP_CHAINS: 65536
[ 0.004000] ... CHAINHASH_SIZE: 32768
[ 0.004000] memory used by lock dependency info: 6365 kB
[ 0.004000] memory used for stack traces: 4224 kB
[ 0.004000] per task-struct memory footprint: 1920 bytes
[ 0.004000] ACPI: Core revision 20221020
[ 0.004000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[ 0.004000] APIC: Switch to symmetric I/O mode setup
[ 0.004000] x2apic enabled
[ 0.004000] Switched APIC routing to physical x2apic.
[ 0.004000] kvm-guest: setup PV IPIs
[ 0.004000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.004000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3407c077a8d, max_idle_ns: 440795342283 ns
[ 0.192321] Calibrating delay loop (skipped) preset value.. 7219.20 BogoMIPS (lpj=14438400)
[ 0.193054] pid_max: default: 32768 minimum: 301
[ 0.193518] LSM: initializing lsm=capability,yama,integrity
[ 0.194005] Yama: becoming mindful.
[ 0.194479] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[ 0.195118] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[ 0.198537] x86/cpu: User Mode Instruction Prevention (UMIP) activated
[ 0.199561] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.200061] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.200379] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[ 0.201093] Spectre V2 : WARNING: Unprivileged eBPF is enabled with eIBRS on, data leaks possible via Spectre v2 BHB attacks!
[ 0.202025] Spectre V2 : Mitigation: Enhanced / Automatic IBRS
[ 0.202501] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[ 0.203168] Spectre V2 : Spectre v2 / PBRSB-eIBRS: Retire a single CALL on VMEXIT
[ 0.203774] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
[ 0.204313] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
[ 0.310709] Freeing SMP alternatives memory: 56K
[ 0.311300] Running RCU synchronous self tests
[ 0.311751] Running RCU synchronous self tests
[ 0.312688] smpboot: CPU0: 12th Gen Intel(R) Core(TM) i7-12700K (family: 0x6, model: 0x97, stepping: 0x2)
[ 0.314249] cblist_init_generic: Setting adjustable number of callback queues.
[ 0.314987] cblist_init_generic: Setting shift to 1 and lim to 1.
[ 0.315668] cblist_init_generic: Setting shift to 1 and lim to 1.
[ 0.316308] cblist_init_generic: Setting shift to 1 and lim to 1.
[ 0.316380] Running RCU-tasks wait API self tests
[ 0.420613] Performance Events: Alderlake Hybrid events, full-width counters, Intel PMU driver.
[ 0.422632] core: cpu_core PMU driver:
[ 0.423110] ... version: 2
[ 0.423576] ... bit width: 48
[ 0.424043] ... generic registers: 6
[ 0.424322] ... value mask: 0000ffffffffffff
[ 0.424921] ... max period: 00007fffffffffff
[ 0.425520] ... fixed-purpose events: 3
[ 0.425977] ... event mask: 0001000f0000003f
[ 0.426930] rcu: Hierarchical SRCU implementation.
[ 0.427484] rcu: Max phase no-delay instances is 1000.
[ 0.428364] Callback from call_rcu_tasks_trace() invoked.
[ 0.433102] unchecked MSR access error: WRMSR to 0x38f (tried to write 0x0001000f0000003f) at rIP: 0xffffffff810e0c2c (native_write_msr+0xc/0x30)
[ 0.434612] Call Trace:
[ 0.434904] <TASK>
[ 0.435161] __intel_pmu_enable_all.constprop.49+0xb5/0x140
[ 0.435824] intel_pmu_enable_all+0x1e/0x30
[ 0.436308] x86_pmu_enable+0x46d/0x5a0
[ 0.436308] ? write_comp_data+0x2f/0x90
[ 0.436308] perf_pmu_enable+0x53/0x70
[ 0.436308] ctx_resched+0x14d/0x1e0
[ 0.436308] __perf_install_in_context+0x2f7/0x470
[ 0.436308] ? lock_is_held_type+0xe6/0x140
[ 0.436308] ? __pfx_remote_function+0x10/0x10
[ 0.436308] remote_function+0x80/0xa0
[ 0.436308] ? __pfx_remote_function+0x10/0x10
[ 0.436308] generic_exec_single+0x124/0x190
[ 0.436308] smp_call_function_single+0x11c/0x240
[ 0.436308] ? __pfx_remote_function+0x10/0x10
[ 0.436308] ? __sanitizer_cov_trace_pc+0x25/0x60
[ 0.436308] ? write_comp_data+0x2f/0x90
[ 0.436308] perf_install_in_context+0x2d8/0x300
[ 0.436308] ? __pfx___perf_install_in_context+0x10/0x10
[ 0.436308] perf_event_create_kernel_counter+0x284/0x2b0
[ 0.436308] ? __pfx_watchdog_overflow_callback+0x10/0x10
[ 0.436308] hardlockup_detector_event_create+0x46/0xd0
[ 0.436308] hardlockup_detector_perf_init+0x18/0x80
[ 0.436308] watchdog_nmi_probe+0x17/0x20
[ 0.436308] lockup_detector_init+0x40/0xb0
[ 0.436308] kernel_init_freeable+0x37e/0x8c0
[ 0.436308] ? __pfx_kernel_init+0x10/0x10
[ 0.436308] kernel_init+0x24/0x1e0
[ 0.436308] ? __pfx_kernel_init+0x10/0x10
[ 0.436308] ret_from_fork+0x29/0x50
[ 0.436308] </TASK>
[ 0.436679] smp: Bringing up secondary CPUs ...
[ 0.438504] x86: Booting SMP configuration:
[ 0.439019] .... node #0, CPUs: #1
[ 0.439397] smp: Brought up 1 node, 2 CPUs
[ 0.440327] smpboot: Max logical packages: 1
[ 0.440824] smpboot: Total of 2 processors activated (14438.40 BogoMIPS)
[ 0.445947] devtmpfs: initialized
[ 0.445947] x86/mm: Memory block size: 128MB
[ 0.450648] Running RCU synchronous self tests
[ 0.450648] Running RCU synchronous self tests
[ 0.452373] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.453522] futex hash table entries: 512 (order: 4, 65536 bytes, linear)
[ 0.454706] pinctrl core: initialized pinctrl subsystem
[ 0.456155]
[ 0.456318] *************************************************************
[ 0.457103] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE **
[ 0.457888] ** **
[ 0.458668] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL **
[ 0.459447] ** **
[ 0.460230] ** This means that this kernel is built to expose internal **
[ 0.460317] ** IOMMU data structures, which may compromise security on **
[ 0.461100] ** your system. **
[ 0.461883] ** **
[ 0.462666] ** If you see this message and you are not debugging the **
[ 0.463455] ** kernel, report this immediately to your vendor! **
[ 0.464235] ** **
[ 0.464317] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE **
[ 0.465105] *************************************************************
[ 0.465993] PM: RTC time: 01:57:17, date: 2023-03-14
[ 0.471145] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[ 0.472712] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations
[ 0.473559] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[ 0.474480] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[ 0.475493] audit: initializing netlink subsys (disabled)
[ 0.476377] audit: type=2000 audit(1678759038.458:1): state=initialized audit_enabled=0 res=1
[ 0.477352] thermal_sys: Registered thermal governor 'fair_share'
[ 0.477365] thermal_sys: Registered thermal governor 'bang_bang'
[ 0.478086] thermal_sys: Registered thermal governor 'step_wise'
[ 0.478794] thermal_sys: Registered thermal governor 'user_space'
[ 0.479565] cpuidle: using governor ladder
[ 0.480344] cpuidle: using governor menu
[ 0.481235] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[ 0.482463] PCI: Using configuration type 1 for base access
[ 0.484376] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
[ 0.777280] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
[ 0.778069] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
[ 0.778813] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.779571] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
[ 0.784909] ACPI: Added _OSI(Module Device)
[ 0.785434] ACPI: Added _OSI(Processor Device)
[ 0.785959] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.786509] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.794119] ACPI: 1 ACPI AML tables successfully acquired and loaded
[ 2.971040] Callback from call_rcu_tasks_rude() invoked.
[ 3.018033] ACPI: Interpreter enabled
[ 3.018624] ACPI: PM: (supports S0 S3 S4 S5)
[ 3.019130] ACPI: Using IOAPIC for interrupt routing
[ 3.020374] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 3.020963] PCI: Using E820 reservations for host bridge windows
[ 3.022084] ACPI: Enabled 2 GPEs in block 00 to 0F
[ 3.037588] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 3.038140] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI EDR HPX-Type3]
[ 3.038816] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
[ 3.039725] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge
[ 3.042706] acpiphp: Slot [3] registered
[ 3.043108] acpiphp: Slot [4] registered
[ 3.043502] acpiphp: Slot [5] registered
[ 3.043908] acpiphp: Slot [6] registered
[ 3.044301] acpiphp: Slot [7] registered
[ 3.044376] acpiphp: Slot [8] registered
[ 3.044762] acpiphp: Slot [9] registered
[ 3.045155] acpiphp: Slot [10] registered
[ 3.045553] acpiphp: Slot [11] registered
[ 3.045953] acpiphp: Slot [12] registered
[ 3.046359] acpiphp: Slot [13] registered
[ 3.046759] acpiphp: Slot [14] registered
[ 3.047160] acpiphp: Slot [15] registered
[ 3.047555] acpiphp: Slot [16] registered
[ 3.047874] acpiphp: Slot [17] registered
[ 3.048193] acpiphp: Slot [18] registered
[ 3.048376] acpiphp: Slot [19] registered
[ 3.048697] acpiphp: Slot [20] registered
[ 3.049016] acpiphp: Slot [21] registered
[ 3.049334] acpiphp: Slot [22] registered
[ 3.049658] acpiphp: Slot [23] registered
[ 3.049984] acpiphp: Slot [24] registered
[ 3.050326] acpiphp: Slot [25] registered
[ 3.050653] acpiphp: Slot [26] registered
[ 3.050980] acpiphp: Slot [27] registered
[ 3.051311] acpiphp: Slot [28] registered
[ 3.051652] acpiphp: Slot [29] registered
[ 3.052013] acpiphp: Slot [30] registered
[ 3.052384] acpiphp: Slot [31] registered
[ 3.052748] PCI host bridge to bus 0000:00
[ 3.053094] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 3.053663] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 3.054236] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[ 3.054894] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window]
[ 3.055521] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
[ 3.056160] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 3.056447] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[ 3.059658] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[ 3.060897] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[ 3.062714] pci 0000:00:01.1: reg 0x20: [io 0xc040-0xc04f]
[ 3.063760] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
[ 3.064317] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
[ 3.064873] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
[ 3.065469] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
[ 3.066410] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[ 3.067242] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
[ 3.067848] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
[ 3.068612] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[ 3.071286] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[ 3.074098] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[ 3.078832] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[ 3.079991] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[ 3.084588] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[ 3.085925] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[ 3.087238] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f]
[ 3.090796] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[ 3.092390] Callback from call_rcu_tasks() invoked.
[ 3.103286] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
[ 3.104186] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
[ 3.104659] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
[ 3.105645] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
[ 3.106320] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
[ 3.108626] iommu: Default domain type: Translated
[ 3.108938] iommu: DMA domain TLB invalidation policy: lazy mode
[ 3.114018] SCSI subsystem initialized
[ 3.114523] ACPI: bus type USB registered
[ 3.114523] usbcore: registered new interface driver usbfs
[ 3.114523] usbcore: registered new interface driver hub
[ 3.114523] usbcore: registered new device driver usb
[ 3.114523] pps_core: LinuxPPS API ver. 1 registered
[ 3.114523] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 3.115157] PTP clock support registered
[ 3.116419] EDAC MC: Ver: 3.0.0
[ 3.121041] NetLabel: Initializing
[ 3.121348] NetLabel: domain hash size = 128
[ 3.121723] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
[ 3.122263] NetLabel: unlabeled traffic allowed by default
[ 3.122870] PCI: Using ACPI for IRQ routing
[ 3.122870] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[ 3.122870] pci 0000:00:02.0: vgaarb: bridge control possible
[ 3.122870] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[ 3.124322] vgaarb: loaded
[ 3.125003] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[ 3.125041] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[ 3.128369] clocksource: Switched to clocksource kvm-clock
[ 3.285611] VFS: Disk quotas dquot_6.6.0
[ 3.286152] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 3.287454] pnp: PnP ACPI init
[ 3.289639] pnp: PnP ACPI: found 6 devices
[ 3.302470] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[ 3.303810] NET: Registered PF_INET protocol family
[ 3.304568] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
[ 3.306210] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 73728 bytes, linear)
[ 3.307109] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 3.307946] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
[ 3.309030] TCP bind hash table entries: 16384 (order: 9, 2359296 bytes, linear)
[ 3.311519] TCP: Hash tables configured (established 16384 bind 16384)
[ 3.312379] UDP hash table entries: 1024 (order: 5, 163840 bytes, linear)
[ 3.313214] UDP-Lite hash table entries: 1024 (order: 5, 163840 bytes, linear)
[ 3.314175] NET: Registered PF_UNIX/PF_LOCAL protocol family
[ 3.315283] RPC: Registered named UNIX socket transport module.
[ 3.315802] RPC: Registered udp transport module.
[ 3.316204] RPC: Registered tcp transport module.
[ 3.316639] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 3.317345] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
[ 3.317898] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
[ 3.318481] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[ 3.319088] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window]
[ 3.319714] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
[ 3.320618] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[ 3.321116] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 3.321707] PCI: CLS 0 bytes, default 64
[ 3.322127] ACPI: bus type thunderbolt registered
[ 3.322944] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3407c077a8d, max_idle_ns: 440795342283 ns
[ 3.327239] Initialise system trusted keyrings
[ 3.327730] Key type blacklist registered
[ 3.328398] workingset: timestamp_bits=36 max_order=19 bucket_order=0
[ 3.329284] zbud: loaded
[ 3.330576] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 3.332084] NFS: Registering the id_resolver key type
[ 3.332755] Key type id_resolver registered
[ 3.333256] Key type id_legacy registered
[ 3.333785] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[ 3.334604] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[ 3.335753] fuse: init (API version 7.38)
[ 3.336609] SGI XFS with ACLs, security attributes, realtime, quota, no debug enabled
[ 3.337974] 9p: Installing v9fs 9p2000 file system support
[ 3.344358] Key type asymmetric registered
[ 3.344805] Asymmetric key parser 'x509' registered
[ 3.345422] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)
[ 3.346399] io scheduler mq-deadline registered
[ 3.347035] io scheduler bfq registered
[ 3.348051] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[ 3.349544] IPMI message handler: version 39.2
[ 3.350162] ipmi device interface
[ 3.352225] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[ 3.353273] ACPI: button: Power Button [PWRF]
[ 3.354713] ERST DBG: ERST support is disabled.
[ 3.356058] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[ 3.357246] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 3.366709] Linux agpgart interface v0.103
[ 3.368057] ACPI: bus type drm_connector registered
[ 3.382853] brd: module loaded
[ 3.390151] loop: module loaded
[ 3.395504] scsi host0: ata_piix
[ 3.396649] scsi host1: ata_piix
[ 3.397035] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc040 irq 14
[ 3.397477] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc048 irq 15
[ 3.399183] tun: Universal TUN/TAP device driver, 1.6
[ 3.399778] e100: Intel(R) PRO/100 Network Driver
[ 3.400085] e100: Copyright(c) 1999-2006 Intel Corporation
[ 3.400494] e1000: Intel(R) PRO/1000 Network Driver
[ 3.400821] e1000: Copyright (c) 1999-2006 Intel Corporation.
[ 3.556448] ata1: found unknown device (class 0)
[ 3.557083] ata2: found unknown device (class 0)
[ 3.558281] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100
[ 3.558795] ata1.00: 16777216 sectors, multi 16: LBA48
[ 3.559438] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[ 3.561619] scsi 0:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5
[ 3.564240] scsi 0:0:0:0: Attached scsi generic sg0 type 0
[ 3.566257] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
[ 3.568699] sd 0:0:0:0: [sda] 16777216 512-byte logical blocks: (8.59 GB/8.00 GiB)
[ 3.569652] sd 0:0:0:0: [sda] Write Protect is off
[ 3.570300] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 3.571403] sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes
[ 3.581200] ACPI: \_SB_.LNKC: Enabled at IRQ 11
[ 3.589488] sd 1:0:0:0: Attached scsi generic sg1 type 5
[ 3.590950] sd 0:0:0:0: [sda] Attached SCSI disk
[ 3.905049] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[ 3.905917] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[ 3.906849] e1000e: Intel(R) PRO/1000 Network Driver
[ 3.907440] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[ 3.908232] igb: Intel(R) Gigabit Ethernet Network Driver
[ 3.908905] igb: Copyright (c) 2007-2014 Intel Corporation.
[ 3.909712] PPP generic driver version 2.4.2
[ 3.910951] VFIO - User Level meta-driver version: 0.3
[ 3.912157] usbcore: registered new interface driver uas
[ 3.912881] usbcore: registered new interface driver usb-storage
[ 3.913794] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[ 3.915709] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 3.916401] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 3.917901] mousedev: PS/2 mouse device common for all mice
[ 3.919740] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[ 3.920589] rtc_cmos 00:05: RTC can wake from S4
[ 3.922212] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4
[ 3.922655] rtc_cmos 00:05: registered as rtc0
[ 3.923839] rtc_cmos 00:05: setting system clock to 2023-03-14T01:57:21 UTC (1678759041)
[ 3.923980] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3
[ 3.925988] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
[ 3.926904] i2c_dev: i2c /dev entries driver
[ 3.927486] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
[ 3.928934] device-mapper: uevent: version 1.0.3
[ 3.929829] device-mapper: ioctl: 4.47.0-ioctl (2022-07-28) initialised: dm-devel@redhat.com
[ 3.930835] intel_pstate: CPU model not supported
[ 3.931517] sdhci: Secure Digital Host Controller Interface driver
[ 3.932225] sdhci: Copyright(c) Pierre Ossman
[ 3.932882] sdhci-pltfm: SDHCI platform and OF driver helper
[ 3.933621] ledtrig-cpu: registered to indicate activity on CPUs
[ 3.934848] drop_monitor: Initializing network drop monitor service
[ 3.935868] NET: Registered PF_INET6 protocol family
[ 3.940193] Segment Routing with IPv6
[ 3.940709] In-situ OAM (IOAM) with IPv6
[ 3.941233] NET: Registered PF_PACKET protocol family
[ 3.942013] 9pnet: Installing 9P2000 support
[ 3.942581] Key type dns_resolver registered
[ 3.944183] IPI shorthand broadcast: enabled
[ 3.963413] sched_clock: Marking stable (3780005895, 180308989)->(3995821597, -35506713)
[ 3.965266] registered taskstats version 1
[ 3.966072] Loading compiled-in X.509 certificates
[ 3.966860] zswap: loaded using pool lzo/zbud
[ 3.986503] Key type .fscrypt registered
[ 3.986833] Key type fscrypt-provisioning registered
[ 3.988220] Key type encrypted registered
[ 3.988659] ima: No TPM chip found, activating TPM-bypass!
[ 3.989084] ima: Allocated hash algorithm: sha1
[ 3.989488] ima: No architecture policies found
[ 3.989915] evm: Initialising EVM extended attributes:
[ 3.990328] evm: security.selinux
[ 3.990590] evm: security.SMACK64
[ 3.990847] evm: security.SMACK64EXEC
[ 3.991111] evm: security.SMACK64TRANSMUTE
[ 3.991415] evm: security.SMACK64MMAP
[ 3.991675] evm: security.apparmor
[ 3.991925] evm: security.ima
[ 3.992152] evm: security.capability
[ 3.992442] evm: HMAC attrs: 0x1
[ 3.993971] PM: Magic number: 15:577:963
[ 3.994390] memory memory14: hash matches
[ 3.995061] RAS: Correctable Errors collector initialized.
[ 3.996097] md: Waiting for all devices to be available before autodetect
[ 3.996475] md: If you don't use raid, use raid=noautodetect
[ 3.996792] md: Autodetecting RAID arrays.
[ 3.997033] md: autorun ...
[ 3.997193] md: ... autorun DONE.
[ 4.009456] EXT4-fs (sda): INFO: recovery required on readonly filesystem
[ 4.010047] EXT4-fs (sda): write access will be enabled during recovery
[ 4.078758] EXT4-fs (sda): recovery complete
[ 4.085464] EXT4-fs (sda): mounted filesystem 23a53bb9-f815-47a5-b74c-5e08f7731193 with ordered data mode. Quota mode: none.
[ 4.086415] VFS: Mounted root (ext4 filesystem) readonly on device 8:0.
[ 4.087687] devtmpfs: mounted
[ 4.094013] Freeing unused decrypted memory: 2036K
[ 4.101273] Freeing unused kernel image (initmem) memory: 10508K
[ 4.101825] Write protecting the kernel read-only data: 47104k
[ 4.104018] Freeing unused kernel image (rodata/data gap) memory: 392K
[ 4.190099] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[ 4.190433] Run /sbin/init as init process
[ 4.333587] systemd[1]: RTC configured in localtime, applying delta of 0 minutes to system time.
[ 4.374155] systemd[1]: systemd 239 (239-49.el8) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy)
[ 4.376168] systemd[1]: Detected virtualization kvm.
[ 4.376636] systemd[1]: Detected architecture x86-64.
Welcome to ^[[0;31mCentOS Stream 8^[[0m!
[ 4.440968] systemd[1]: Set hostname to <test>.
[ 4.706953] systemd[1]: Created slice system-getty.slice.
[^[[0;32m OK ^[[0m] Created slice system-getty.slice.
[ 4.708124] systemd[1]: Listening on multipathd control socket.
[^[[0;32m OK ^[[0m] Listening on multipathd control socket.
[ 4.709358] systemd[1]: Listening on LVM2 poll daemon socket.
[^[[0;32m OK ^[[0m] Listening on LVM2 poll daemon socket.
[ 4.710245] systemd[1]: Listening on udev Kernel Socket.
[^[[0;32m OK ^[[0m] Listening on udev Kernel Socket.
[ 4.711106] systemd[1]: Listening on Journal Socket (/dev/log).
[^[[0;32m OK ^[[0m] Listening on Journal Socket (/dev/log).
[ 4.718780] systemd[1]: Listening on Process Core Dump Socket.
[^[[0;32m OK ^[[0m] Listening on Process Core Dump Socket.
[^[[0;32m OK ^[[0m] Created slice User and Session Slice.
[^[[0;32m OK ^[[0m] Reached target Swap.
[^[[0;32m OK ^[[0m] Created slice system-sshd\x2dkeygen.slice.
[^[[0;32m OK ^[[0m] Listening on Journal Socket.
Starting Setup Virtual Console...
Mounting Kernel Debug File System...
Starting Load Kernel Modules...
Mounting Huge Pages File System...
[^[[0;32m OK ^[[0m] Reached target Slices.
[^[[0;32m OK ^[[0m] Listening on udev Control Socket.
[^[[0;32m OK ^[[0m] Set up automount Arbitrary Executab…rmats File System Automount Point.
Starting Journal Service...
[^[[0;32m OK ^[[0m] Listening on initctl Compatibility Named Pipe.
[^[[0;32m OK ^[[0m] Started Forward Password Requests to Wall Directory Watch.
Starting udev Coldplug all Devices...
[^[[0;32m OK ^[[0m] Listening on Device-mapper event daemon FIFOs.
[^[[0;32m OK ^[[0m] Created slice system-serial\x2dgetty.slice.
Mounting POSIX Message Queue File System...
Starting Remount Root and Kernel File Systems...
[^[[0;32m OK ^[[0m] Listening on RPCbind Server Activation Socket.
[^[[0;32m OK ^[[0m] Reached target RPC Port Mapper.
Starting Monitoring of LVM2 mirrors…ng dmeventd or progress polling...
[^[[0;32m OK ^[[0m] Mounted Kernel Debug File System.
[^[[0;1;31mFAILED^[[0m] Failed to start Load Kernel Modules.
See 'systemctl status systemd-modules-load.service' for details.
[^[[0;32m OK ^[[0m] Mounted Huge Pages File System.
[^[[0;32m OK ^[[0m] Mounted POSIX Message Queue File System.
Mounting Kernel Configuration File System...
Mounting FUSE Control File System...
Starting Apply Kernel Variables...
[^[[0;32m OK ^[[0m] Mounted Kernel Configuration File System.
[^[[0;32m OK ^[[0m] Mounted FUSE Control File System.
[^[[0;32m OK ^[[0m] Started Setup Virtual Console.
[^[[0;32m OK ^[[0m] Started Apply Kernel Variables.
[^[[0;32m OK ^[[0m] Started Journal Service.
[ 5.299531] EXT4-fs (sda): re-mounted 23a53bb9-f815-47a5-b74c-5e08f7731193. Quota mode: none.
[^[[0;32m OK ^[[0m] Started Remount Root and Kernel File Systems.
Starting Flush Journal to Persistent Storage...
Starting Load/Save Random Seed...
Starting Create Static Device Nodes in /dev...
[^[[0;32m OK ^[[0m] Started Load/Save Random Seed.
[^[[0;32m OK ^[[0m] Started Flush Journal to Persistent Storage.
[^[[0;32m OK ^[[0m] Started Create Static Device Nodes in /dev.
Starting udev Kernel Device Manager...
[^[[0;32m OK ^[[0m] Started udev Coldplug all Devices.
Starting udev Wait for Complete Device Initialization...
[^[[0;32m OK ^[[0m] Started udev Kernel Device Manager.
Starting Show Plymouth Boot Screen...
[^[[0;32m OK ^[[0m] Started Show Plymouth Boot Screen.
[^[[0;32m OK ^[[0m] Reached target Local Encrypted Volumes.
[^[[0;32m OK ^[[0m] Reached target Paths.
[^[[0;32m OK ^[[0m] Started Forward Password Requests to Plymouth Directory Watch.
[^[[0;32m OK ^[[0m] Started udev Wait for Complete Device Initialization.
[^[[0;32m OK ^[[0m] Found device /dev/ttyS0.
[^[[0;32m OK ^[[0m] Started Monitoring of LVM2 mirrors,…sing dmeventd or progress polling.
[^[[0;32m OK ^[[0m] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
[^[[0;32m OK ^[[0m] Reached target Local File Systems (Pre).
[^[[0;32m OK ^[[0m] Reached target Local File Systems.
Starting Tell Plymouth To Write Out Runtime Data...
Starting Create Volatile Files and Directories...
Starting Restore /run/initramfs on shutdown...
[^[[0;32m OK ^[[0m] Started Tell Plymouth To Write Out Runtime Data.
[^[[0;32m OK ^[[0m] Started Restore /run/initramfs on shutdown.
[^[[0;32m OK ^[[0m] Started Create Volatile Files and Directories.
Mounting RPC Pipe File System...
Starting Security Auditing Service...
Starting RPC Bind...
[^[[0;32m OK ^[[0m] Mounted RPC Pipe File System.
[^[[0;32m OK ^[[0m] Reached target rpc_pipefs.target.
[^[[0;32m OK ^[[0m] Started RPC Bind.
[^[[0;32m OK ^[[0m] Started Security Auditing Service.
Starting Update UTMP about System Boot/Shutdown...
[^[[0;32m OK ^[[0m] Started Update UTMP about System Boot/Shutdown.
[^[[0;32m OK ^[[0m] Reached target System Initialization.
[^[[0;32m OK ^[[0m] Listening on SSSD Kerberos Cache Manager responder socket.
[^[[0;32m OK ^[[0m] Started Daily Cleanup of Temporary Directories.
[^[[0;32m OK ^[[0m] Started daily update of the root trust anchor for DNSSEC.
[^[[0;32m OK ^[[0m] Started dnf makecache --timer.
[^[[0;32m OK ^[[0m] Reached target Timers.
[^[[0;32m OK ^[[0m] Listening on D-Bus System Message Bus Socket.
[^[[0;32m OK ^[[0m] Reached target Sockets.
[^[[0;32m OK ^[[0m] Reached target Basic System.
[^[[0;32m OK ^[[0m] Started Machine Check Exception Logging Daemon.
Starting Auto-connect to subsystems…-NVME devices found during boot...
Starting Self Monitoring and Reporting Technology (SMART) Daemon...
[^[[0;32m OK ^[[0m] Started irqbalance daemon.
Starting Login Service...
Starting NTP client/server...
[^[[0;32m OK ^[[0m] Started D-Bus System Message Bus.
Starting Network Manager...
Starting Authorization Manager...
[^[[0;32m OK ^[[0m] Reached target sshd-keygen.target.
[^[[0;32m OK ^[[0m] Started Self Monitoring and Reporting Technology (SMART) Daemon.
[^[[0;32m OK ^[[0m] Started Auto-connect to subsystems …FC-NVME devices found during boot.
Starting update of the root trust a…or DNSSEC validation in unbound...
[^[[0;32m OK ^[[0m] Started Login Service.
[^[[0;32m OK ^[[0m] Started NTP client/server.
[^[[0;32m OK ^[[0m] Started Network Manager.
Starting Network Manager Wait Online...
[^[[0;32m OK ^[[0m] Reached target Network.
Starting OpenSSH server daemon...
Starting Dynamic System Tuning Daemon...
Starting GSSAPI Proxy Daemon...
[^[[0;32m OK ^[[0m] Started update of the root trust an… for DNSSEC validation in unbound.
[^[[0;32m OK ^[[0m] Started Authorization Manager.
Starting Hostname Service...
[^[[0;32m OK ^[[0m] Started GSSAPI Proxy Daemon.
[^[[0;32m OK ^[[0m] Reached target NFS client services.
[^[[0;32m OK ^[[0m] Reached target Remote File Systems (Pre).
[^[[0;32m OK ^[[0m] Reached target Remote File Systems.
Starting Permit User Sessions...
[^[[0;32m OK ^[[0m] Started OpenSSH server daemon.
[^[[0;32m OK ^[[0m] Started Permit User Sessions.
Starting Hold until boot process finishes up...
[^[[0;32m OK ^[[0m] Started Command Scheduler.
Starting Terminate Plymouth Boot Screen...
[ 7.656613] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[ 7.657810] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 7.905297] Spectre V2 : WARNING: Unprivileged eBPF is enabled with eIBRS on, data leaks possible via Spectre v2 BHB attacks!
[ 7.905841] Spectre V2 : WARNING: Unprivileged eBPF is enabled with eIBRS on, data leaks possible via Spectre v2 BHB attacks!
CentOS Stream 8
Kernel 6.3.0-rc2-eeac8ede1755+ on an x86_64
test login: root
[ 12.533569] memfd_create() without MFD_EXEC nor MFD_NOEXEC_SEAL, pid=328 'systemd'
Last login: Fri Mar 10 08:45:17 on ttyS0
*******************************************************
Welcome to the Embargoed Overlay on CentOS
This content is for INTEL INTERNAL USE ONLY.
For support issues, please submit a request:
https://jira.devtools.intel.com/browse/LNXO
*******************************************************
[root@test ~]#
[root@test ~]#
[root@test ~]# ls
anaconda-ks.cfg quick_test.c syzkaller.9FSdCu syzkaller.OjSrkA
cet_ibt repro.c syzkaller.fIIL2o syzkaller.Q5dMMG
cpuid repro.sh syzkaller.G7vfvS syzkaller.qCVHXV
cpuid.c shadow_test_fork syzkaller.IhmgVg syzkaller.TRJkoZ
file0 shstk_cp syzkaller.iVVz3P syzkaller.uq7iOt
id_rsa.pub shstk_cp.c syzkaller.ju9NUO syzkaller.uXhAhf
Install_kernel_record.log syzkaller.2e0D20 syzkaller.KfBl6t
quick_test syzkaller.6TPmw0 syzkaller.mc3H24
[root@test ~]# rm -rf repro
[root@test ~]# ./re\apro
[ 339.307328] loop0: detected capacity change from 0 to 65536
[ 339.307840] XFS: ikeep mount option is deprecated.
[ 339.310052] XFS (loop0): Mounting V5 Filesystem d408de26-55fb-48ab-a8ab-aacedb20f9dd
[ 339.315710] XFS (loop0): Ending clean mount
[ 339.316761] XFS (loop0): Quotacheck needed: Please wait.
[ 339.317833] XFS (loop0): Metadata CRC error detected at xfs_agf_read_verify+0x10e/0x140, xfs_agf block 0x1
[ 339.318319] XFS (loop0): Unmount and run xfs_repair
[ 339.318534] XFS (loop0): First 128 bytes of corrupted metadata buffer:
[ 339.318812] 00000000: 58 41 47 46 00 00 00 01 00 00 00 00 00 00 40 00 XAGF..........@.
[ 339.319151] 00000010: 00 00 00 02 00 00 00 03 00 00 00 00 00 00 00 01 ................
[ 339.319496] 00000020: 00 00 00 01 00 00 00 00 00 00 01 00 00 00 00 04 ................
[ 339.319854] 00000030: 00 00 00 04 00 00 3f ca 00 00 3f c7 00 00 00 00 ......?...?.....
[ 339.320193] 00000040: d4 08 de 26 55 fb 48 ab a8 ab aa ce db 20 f9 dd ...&U.H...... ..
[ 339.320656] 00000050: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 339.320998] 00000060: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 339.321334] 00000070: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 339.322361] XFS (loop0): metadata I/O error in "xfs_read_agf+0xd0/0x2c0" at daddr 0x1 len 1 error 74
[ 339.323346] XFS (loop0): Quotacheck: Unsuccessful (Error -117): Disabling quotas.
[ 339.341420] XFS (loop0): Metadata CRC error detected at xfs_agf_read_verify+0x10e/0x140, xfs_agf block 0x1
[ 339.341888] XFS (loop0): Unmount and run xfs_repair
[ 339.342106] XFS (loop0): First 128 bytes of corrupted metadata buffer:
[ 339.342414] 00000000: 58 41 47 46 00 00 00 01 00 00 00 00 00 00 40 00 XAGF..........@.
[ 339.342760] 00000010: 00 00 00 02 00 00 00 03 00 00 00 00 00 00 00 01 ................
[ 339.343105] 00000020: 00 00 00 01 00 00 00 00 00 00 01 00 00 00 00 04 ................
[ 339.343534] 00000030: 00 00 00 04 00 00 3f ca 00 00 3f c7 00 00 00 00 ......?...?.....
[ 339.343899] 00000040: d4 08 de 26 55 fb 48 ab a8 ab aa ce db 20 f9 dd ...&U.H...... ..
[ 339.344248] 00000050: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 339.344660] 00000060: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 339.345010] 00000070: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 339.347201] XFS (loop0): metadata I/O error in "xfs_read_agf+0xd0/0x2c0" at daddr 0x1 len 1 error 74
[ 339.347823] XFS (loop0): page discard on page 000000007e0ebc83, inode 0x2b, pos 0.
[ 339.352925] XFS (loop0): Unmounting Filesystem d408de26-55fb-48ab-a8ab-aacedb20f9dd
[ 339.363249] XFS (loop0): SB summary counter sanity check failed
[ 339.363557] XFS (loop0): Metadata corruption detected at xfs_sb_write_verify+0x7d/0x180, xfs_sb block 0x0
[ 339.363982] XFS (loop0): Unmount and run xfs_repair
[ 339.364203] XFS (loop0): First 128 bytes of corrupted metadata buffer:
[ 339.364652] 00000000: 58 46 53 42 00 00 04 00 00 00 00 00 00 00 80 00 XFSB............
[ 339.365004] 00000010: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 339.365343] 00000020: d4 08 de 26 55 fb 48 ab a8 ab aa ce db 20 f9 dd ...&U.H...... ..
[ 339.365683] 00000030: 00 00 00 00 00 00 40 08 00 00 00 00 00 00 00 20 ......@........
[ 339.366030] 00000040: 00 00 00 00 00 00 00 21 00 00 00 00 00 00 00 22 .......!......."
[ 339.366381] 00000050: 00 00 00 04 00 00 40 00 00 00 00 02 00 00 00 00 ......@.........
[ 339.366722] 00000060: 00 00 04 98 b4 f5 02 00 02 00 00 02 00 00 00 00 ................
[ 339.367062] 00000070: 00 00 00 00 00 00 00 00 0a 09 09 01 0e 00 00 14 ................
[ 339.367548] XFS (loop0): Corruption of in-memory data (0x8) detected at _xfs_buf_ioapply+0x67d/0x690 (fs/xfs/xfs_buf.c:1553). Shutting down filesystem.
[ 339.368164] XFS (loop0): Please unmount the filesystem and rectify the problem(s)
[ 364.436316] watchdog: BUG: soft lockup - CPU#0 stuck for 26s! [repro:405]
[ 364.436724] Modules linked in:
[ 364.436868] irq event stamp: 21510
[ 364.437026] hardirqs last enabled at (21509): [<ffffffff8300187e>] irqentry_exit+0x3e/0xa0
[ 364.437406] hardirqs last disabled at (21510): [<ffffffff82fff863>] sysvec_apic_timer_interrupt+0x13/0xe0
[ 364.437826] softirqs last enabled at (8970): [<ffffffff83024a4c>] __do_softirq+0x31c/0x49c
[ 364.438195] softirqs last disabled at (8897): [<ffffffff8112b5a4>] irq_exit_rcu+0xc4/0x100
[ 364.438564] CPU: 0 PID: 405 Comm: repro Not tainted 6.3.0-rc2-eeac8ede1755+ #1
[ 364.438885] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.0-0-gd239552ce722-prebuilt.qemu.org 04/01/2014
[ 364.439382] RIP: 0010:rcu_lockdep_current_cpu_online+0x5b/0x90
[ 364.439657] Code: 5b 41 5c 5d c3 cc cc cc cc 65 ff 05 d7 bb e1 7e e8 fa 94 de 01 48 c7 c3 80 61 03 00 89 c0 48 03 1c c5 00 7b cb 83 48 8b 43 18 <48> 8b 40 70 48 85 43 20 74 10 65 ff 0d ac bb e1 7e 75 c0 0f 1f 44
[ 364.440449] RSP: 0018:ffffc90000eb3ba8 EFLAGS: 00000282
[ 364.440689] RAX: ffffffff83f64340 RBX: ffff88807dc36180 RCX: 0000000000000000
[ 364.441003] RDX: 0000000000000000 RSI: 0000000000000003 RDI: 0000000000000000
[ 364.441313] RBP: ffffc90000eb3bb8 R08: 0000000000000001 R09: 0000000000000000
[ 364.441626] R10: ffff88800f7e30d8 R11: 0000000000000000 R12: 0000000000000001
[ 364.441938] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 364.442248] FS: 00007f9db4acf740(0000) GS:ffff88807dc00000(0000) knlGS:0000000000000000
[ 364.442597] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 364.442852] CR2: 00007f5e8f6f5518 CR3: 000000000f6da004 CR4: 0000000000770ef0
[ 364.443169] PKRU: 55555554
[ 364.443297] Call Trace:
[ 364.443412] <TASK>
[ 364.443519] rcu_read_lock_held_common+0x33/0x50
[ 364.443741] rcu_read_lock_sched_held+0x29/0x80
[ 364.443956] lock_acquire+0x2ad/0x320
[ 364.444133] ? lock_release+0x141/0x2e0
[ 364.444313] ? write_comp_data+0x2f/0x90
[ 364.444507] _raw_spin_lock+0x36/0x50
[ 364.444681] ? xfs_ail_push_all_sync+0x73/0x110
[ 364.444893] xfs_ail_push_all_sync+0x73/0x110
[ 364.445097] ? __pfx_autoremove_wake_function+0x10/0x10
[ 364.445343] xfs_reclaim_inodes+0xb8/0x140
[ 364.445543] xfs_unmount_flush_inodes+0x63/0x80
[ 364.445757] xfs_unmountfs+0x69/0x1f0
[ 364.445939] xfs_fs_put_super+0x5a/0x120
[ 364.446130] ? __pfx_xfs_fs_put_super+0x10/0x10
[ 364.446356] generic_shutdown_super+0xac/0x240
[ 364.446573] kill_block_super+0x43/0x80
[ 364.446759] deactivate_locked_super+0x52/0xb0
[ 364.446966] deactivate_super+0xb3/0xd0
[ 364.447156] cleanup_mnt+0x15e/0x1e0
[ 364.447335] __cleanup_mnt+0x1f/0x30
[ 364.447513] task_work_run+0xb6/0x120
[ 364.447736] exit_to_user_mode_prepare+0x200/0x210
[ 364.448017] syscall_exit_to_user_mode+0x2d/0x60
[ 364.448293] do_syscall_64+0x4a/0x90
[ 364.448513] entry_SYSCALL_64_after_hwframe+0x72/0xdc
[ 364.448809] RIP: 0033:0x7f9db4bfb7db
[ 364.449033] Code: 96 0c 00 f7 d8 64 89 01 48 83 c8 ff c3 66 90 f3 0f 1e fa 31 f6 e9 05 00 00 00 0f 1f 44 00 00 f3 0f 1e fa b8 a6 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 95 96 0c 00 f7 d8 64 89 01 48
[ 364.449954] RSP: 002b:00007fff5b16db78 EFLAGS: 00000206 ORIG_RAX: 00000000000000a6
[ 364.450376] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00007f9db4bfb7db
[ 364.450745] RDX: 0000000000000000 RSI: 000000000000000a RDI: 00007fff5b16dc20
[ 364.451108] RBP: 00007fff5b16ec60 R08: 0000000001e88333 R09: 0000000000000009
[ 364.451484] R10: 0000000000404071 R11: 0000000000000206 R12: 00000000004012c0
[ 364.451847] R13: 00007fff5b16eda0 R14: 0000000000000000 R15: 0000000000000000
[ 364.452195] </TASK>
[ 364.452301] Kernel panic - not syncing: softlockup: hung tasks
[ 364.452562] CPU: 0 PID: 405 Comm: repro Tainted: G L 6.3.0-rc2-eeac8ede1755+ #1
[ 364.453010] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.0-0-gd239552ce722-prebuilt.qemu.org 04/01/2014
[ 364.453551] Call Trace:
[ 364.453701] <IRQ>
[ 364.453826] dump_stack_lvl+0xa9/0x110
[ 364.454057] dump_stack+0x19/0x20
[ 364.454262] panic+0x4fd/0x520
[ 364.454455] ? watchdog_timer_fn+0x31e/0x390
[ 364.454689] watchdog_timer_fn+0x337/0x390
[ 364.454886] ? __pfx_watchdog_timer_fn+0x10/0x10
[ 364.455101] __hrtimer_run_queues+0x2ed/0x820
[ 364.455355] hrtimer_interrupt+0x134/0x2d0
[ 364.455604] __sysvec_apic_timer_interrupt+0x9c/0x290
[ 364.455902] sysvec_apic_timer_interrupt+0xab/0xe0
[ 364.456185] </IRQ>
[ 364.456314] <TASK>
[ 364.456441] asm_sysvec_apic_timer_interrupt+0x1f/0x30
[ 364.456742] RIP: 0010:rcu_lockdep_current_cpu_online+0x5b/0x90
[ 364.457067] Code: 5b 41 5c 5d c3 cc cc cc cc 65 ff 05 d7 bb e1 7e e8 fa 94 de 01 48 c7 c3 80 61 03 00 89 c0 48 03 1c c5 00 7b cb 83 48 8b 43 18 <48> 8b 40 70 48 85 43 20 74 10 65 ff 0d ac bb e1 7e 75 c0 0f 1f 44
[ 364.457937] RSP: 0018:ffffc90000eb3ba8 EFLAGS: 00000282
[ 364.458211] RAX: ffffffff83f64340 RBX: ffff88807dc36180 RCX: 0000000000000000
[ 364.458613] RDX: 0000000000000000 RSI: 0000000000000003 RDI: 0000000000000000
[ 364.459006] RBP: ffffc90000eb3bb8 R08: 0000000000000001 R09: 0000000000000000
[ 364.459418] R10: ffff88800f7e30d8 R11: 0000000000000000 R12: 0000000000000001
[ 364.459813] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 364.460213] ? rcu_lockdep_current_cpu_online+0x46/0x90
[ 364.460465] rcu_read_lock_held_common+0x33/0x50
[ 364.460743] rcu_read_lock_sched_held+0x29/0x80
[ 364.460980] lock_acquire+0x2ad/0x320
[ 364.461170] ? lock_release+0x141/0x2e0
[ 364.461391] ? write_comp_data+0x2f/0x90
[ 364.461590] _raw_spin_lock+0x36/0x50
[ 364.461804] ? xfs_ail_push_all_sync+0x73/0x110
[ 364.462018] xfs_ail_push_all_sync+0x73/0x110
[ 364.462228] ? __pfx_autoremove_wake_function+0x10/0x10
[ 364.462479] xfs_reclaim_inodes+0xb8/0x140
[ 364.462682] xfs_unmount_flush_inodes+0x63/0x80
[ 364.462933] xfs_unmountfs+0x69/0x1f0
[ 364.463112] xfs_fs_put_super+0x5a/0x120
[ 364.463304] ? __pfx_xfs_fs_put_super+0x10/0x10
[ 364.463522] generic_shutdown_super+0xac/0x240
[ 364.463741] kill_block_super+0x43/0x80
[ 364.463930] deactivate_locked_super+0x52/0xb0
[ 364.464154] deactivate_super+0xb3/0xd0
[ 364.464348] cleanup_mnt+0x15e/0x1e0
[ 364.464564] __cleanup_mnt+0x1f/0x30
[ 364.464787] task_work_run+0xb6/0x120
[ 364.465002] exit_to_user_mode_prepare+0x200/0x210
[ 364.465226] syscall_exit_to_user_mode+0x2d/0x60
[ 364.465449] do_syscall_64+0x4a/0x90
[ 364.465621] entry_SYSCALL_64_after_hwframe+0x72/0xdc
[ 364.465861] RIP: 0033:0x7f9db4bfb7db
[ 364.466065] Code: 96 0c 00 f7 d8 64 89 01 48 83 c8 ff c3 66 90 f3 0f 1e fa 31 f6 e9 05 00 00 00 0f 1f 44 00 00 f3 0f 1e fa b8 a6 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 95 96 0c 00 f7 d8 64 89 01 48
[ 364.466917] RSP: 002b:00007fff5b16db78 EFLAGS: 00000206 ORIG_RAX: 00000000000000a6
[ 364.467262] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00007f9db4bfb7db
[ 364.467582] RDX: 0000000000000000 RSI: 000000000000000a RDI: 00007fff5b16dc20
[ 364.467902] RBP: 00007fff5b16ec60 R08: 0000000001e88333 R09: 0000000000000009
[ 364.468228] R10: 0000000000404071 R11: 0000000000000206 R12: 00000000004012c0
[ 364.468605] R13: 00007fff5b16eda0 R14: 0000000000000000 R15: 0000000000000000
[ 364.468992] </TASK>
[ 364.469185] Kernel Offset: disabled
[ 364.469398] ---[ end Kernel panic - not syncing: softlockup: hung tasks ]---
^ permalink raw reply [flat|nested] 2+ messages in thread