Greeting,
FYI, we noticed the following commit (built with gcc-9):
commit: 31c9ccb1389d10c7d5b0d6c74b953b301e7e23ec ("Revert "ANDROID:
vendor_hooks: add waiting information for blocked tasks"")
https://android.googlesource.com/kernel/common android-mainline
in testcase: locktorture
version:
with following parameters:
runtime: 300s
test: default
test-description: This torture test consists of creating a number of kernel threads which
acquire the lock and hold it for specific amount of time, thus simulating different
critical region behaviors.
test-url:
https://www.kernel.org/doc/Documentation/locking/locktorture.txt
on test machine: qemu-system-i386 -enable-kvm -cpu SandyBridge -smp 2 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+------------------------------------------------------------------+------------+------------+
| | bf2290a48a |
31c9ccb138 |
+------------------------------------------------------------------+------------+------------+
| boot_successes | 35 | 0
|
| WARNING:at_kernel/locking/mutex-debug.c:#debug_mutex_free_waiter | 0 | 45
|
| EIP:debug_mutex_free_waiter | 0 | 45
|
| BUG:unable_to_handle_page_fault_for_address | 0 | 45
|
| Oops:#[##] | 0 | 45
|
| EIP:wake_q_add | 0 | 45
|
| Kernel_panic-not_syncing:Fatal_exception | 0 | 45
|
+------------------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <oliver.sang(a)intel.com>
[ 12.186534][ T114] WARNING: CPU: 0 PID: 114 at kernel/locking/mutex-debug.c:47
debug_mutex_free_waiter (kernel/locking/mutex-debug.c:47 (discriminator 9))
[ 12.189117][ T114] Modules linked in:
[ 12.189687][ T114] CPU: 0 PID: 114 Comm: kworker/0:2 Not tainted
5.14.0-03346-g31c9ccb1389d #1
[ 12.190927][ T114] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS
1.12.0-1 04/01/2014
[ 12.192286][ T114] Workqueue: events test_abba_work
[ 12.193046][ T114] EIP: debug_mutex_free_waiter (kernel/locking/mutex-debug.c:47
(discriminator 9))
[ 12.193855][ T114] Code: 5d fc c9 c3 8d 74 26 00 e8 6b da 29 00 85 c0 74 cc 8b 0d 0c 67
a4 82 85 c9 75 c2 68 a5 6e 40 82 68 77 6f 3f 82 e8 57 43 af 00 <0f> 0b 58 5a eb ad
8d b4 26 00 00 00 00 90 55 89 e5 56 56 89 d6 8b
All code
========
0: 5d pop %rbp
1: fc cld
2: c9 leaveq
3: c3 retq
4: 8d 74 26 00 lea 0x0(%rsi,%riz,1),%esi
8: e8 6b da 29 00 callq 0x29da78
d: 85 c0 test %eax,%eax
f: 74 cc je 0xffffffffffffffdd
11: 8b 0d 0c 67 a4 82 mov -0x7d5b98f4(%rip),%ecx # 0xffffffff82a46723
17: 85 c9 test %ecx,%ecx
19: 75 c2 jne 0xffffffffffffffdd
1b: 68 a5 6e 40 82 pushq $0xffffffff82406ea5
20: 68 77 6f 3f 82 pushq $0xffffffff823f6f77
25: e8 57 43 af 00 callq 0xaf4381
2a:* 0f 0b ud2 <-- trapping instruction
2c: 58 pop %rax
2d: 5a pop %rdx
2e: eb ad jmp 0xffffffffffffffdd
30: 8d b4 26 00 00 00 00 lea 0x0(%rsi,%riz,1),%esi
37: 90 nop
38: 55 push %rbp
39: 89 e5 mov %esp,%ebp
3b: 56 push %rsi
3c: 56 push %rsi
3d: 89 d6 mov %edx,%esi
3f: 8b .byte 0x8b
Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: 58 pop %rax
3: 5a pop %rdx
4: eb ad jmp 0xffffffffffffffb3
6: 8d b4 26 00 00 00 00 lea 0x0(%rsi,%riz,1),%esi
d: 90 nop
e: 55 push %rbp
f: 89 e5 mov %esp,%ebp
11: 56 push %rsi
12: 56 push %rsi
13: 89 d6 mov %edx,%esi
15: 8b .byte 0x8b
[ 12.196758][ T114] EAX: 0000002f EBX: 804b9e9c ECX: 00000000 EDX: 00000000
[ 12.197850][ T114] ESI: 804b9ee0 EDI: 00000000 EBP: 804b9e70 ESP: 804b9e64
[ 12.198938][ T114] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068 EFLAGS: 00010286
[ 12.200100][ T114] CR0: 80050033 CR2: 00000000 CR3: 02b53000 CR4: 00040690
[ 12.201109][ T114] Call Trace:
[ 12.201585][ T114] __ww_mutex_lock+0x544/0xb00
[ 12.202403][ T114] ww_mutex_lock (kernel/locking/mutex.c:1193 (discriminator 1))
[ 12.203142][ T114] ? test_abba_work (kernel/locking/test-ww_mutex.c:170)
[ 12.203957][ T114] test_abba_work (kernel/locking/test-ww_mutex.c:170)
[ 12.204681][ T114] process_one_work (arch/x86/include/asm/atomic.h:29
include/asm-generic/atomic-instrumented.h:28 include/linux/jump_label.h:266
include/linux/jump_label.h:276 include/trace/events/workqueue.h:108
kernel/workqueue.c:2285)
[ 12.205423][ T114] worker_thread (include/linux/list.h:282 kernel/workqueue.c:2427)
[ 12.206148][ T114] kthread (kernel/kthread.c:319)
[ 12.206796][ T114] ? process_one_work (kernel/workqueue.c:2369)
[ 12.207671][ T114] ? set_kthread_struct (kernel/kthread.c:272)
[ 12.208440][ T114] ret_from_fork (arch/x86/entry/entry_32.S:775)
[ 12.209112][ T114] irq event stamp: 233629
[ 12.209747][ T114] hardirqs last enabled at (233629): _raw_spin_unlock_irq
(arch/x86/include/asm/irqflags.h:45 arch/x86/include/asm/irqflags.h:80
include/linux/spinlock_api_smp.h:168 kernel/locking/spinlock.c:199)
[ 12.211202][ T114] hardirqs last disabled at (233628): _raw_spin_lock_irq
(include/linux/spinlock_api_smp.h:126 kernel/locking/spinlock.c:167)
[ 12.212760][ T114] softirqs last enabled at (233496): __do_softirq
(arch/x86/include/asm/preempt.h:27 kernel/softirq.c:405 kernel/softirq.c:590)
[ 12.214057][ T114] softirqs last disabled at (233487): do_softirq_own_stack
(arch/x86/kernel/irq_32.c:60 arch/x86/kernel/irq_32.c:149)
[ 12.215447][ T114] ---[ end trace ad0727e888d52121 ]---
To reproduce:
# build kernel
cd linux
cp config-5.14.0-03346-g31c9ccb1389d .config
make HOSTCC=gcc-9 CC=gcc-9 ARCH=i386 olddefconfig prepare modules_prepare bzImage
modules
make HOSTCC=gcc-9 CC=gcc-9 ARCH=i386 INSTALL_MOD_PATH=<mod-install-dir>
modules_install
cd <mod-install-dir>
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
git clone
https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached
in this email
---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/lkp@lists.01.org Intel Corporation
Thanks,
Oliver Sang