lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [thread-next>] [day] [month] [year] [list]
Message-ID: <5f8dc512.pzfR1ahTGDOChwCh%lkp@intel.com>
Date:   Tue, 20 Oct 2020 00:55:46 +0800
From:   kernel test robot <lkp@...el.com>
To:     Peter Zijlstra <peterz@...radead.org>
Cc:     lkp@...el.com, LKP <lkp@...ts.01.org>,
        linux-kernel@...r.kernel.org, linux-kernel@...r.kernel.org,
        Ingo Molnar <mingo@...nel.org>, philip.li@...el.com
Subject: 4d004099a6 ("lockdep: Fix lockdep recursion"):  BUG: using
 __this_cpu_read() in preemptible [00000000] code: trinity-c6/526

Greetings,

0day kernel testing robot got the below dmesg and the first bad commit is

https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master

commit 4d004099a668c41522242aa146a38cc4eb59cb1e
Author:     Peter Zijlstra <peterz@...radead.org>
AuthorDate: Fri Oct 2 11:04:21 2020 +0200
Commit:     Ingo Molnar <mingo@...nel.org>
CommitDate: Fri Oct 9 08:53:30 2020 +0200

    lockdep: Fix lockdep recursion
    
    Steve reported that lockdep_assert*irq*(), when nested inside lockdep
    itself, will trigger a false-positive.
    
    One example is the stack-trace code, as called from inside lockdep,
    triggering tracing, which in turn calls RCU, which then uses
    lockdep_assert_irqs_disabled().
    
    Fixes: a21ee6055c30 ("lockdep: Change hardirq{s_enabled,_context} to per-cpu variables")
    Reported-by: Steven Rostedt <rostedt@...dmis.org>
    Signed-off-by: Peter Zijlstra (Intel) <peterz@...radead.org>
    Signed-off-by: Ingo Molnar <mingo@...nel.org>

2bb8945bcc  lockdep: Fix usage_traceoverflow
4d004099a6  lockdep: Fix lockdep recursion
7cf726a594  Merge tag 'linux-kselftest-kunit-5.10-rc1' of git://git.kernel.org/pub/scm/linux/kernel/git/shuah/linux-kselftest
+--------------------------------------------------------------------+------------+------------+------------+
|                                                                    | 2bb8945bcc | 4d004099a6 | 7cf726a594 |
+--------------------------------------------------------------------+------------+------------+------------+
| boot_successes                                                     | 12         | 0          | 0          |
| boot_failures                                                      | 4          | 11         | 10         |
| Mem-Info                                                           | 4          |            |            |
| invoked_oom-killer:gfp_mask=0x                                     | 3          |            |            |
| EIP:__copy_user_ll                                                 | 1          |            |            |
| EIP:__get_user_4                                                   | 1          |            |            |
| EIP:__put_user_4                                                   | 1          |            |            |
| EIP:clear_user                                                     | 1          |            |            |
| BUG:using__this_cpu_read()in_preemptible                           | 0          | 11         | 10         |
| WARNING:suspicious_RCU_usage                                       | 0          | 6          | 8          |
| kernel/locking/lockdep.c:#RCU-list_traversed_in_non-reader_section | 0          | 6          | 8          |
+--------------------------------------------------------------------+------------+------------+------------+

If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp@...el.com>

[child6:526] fstat64 (197) returned ENOSYS, marking as inactive.
[child4:551] fstatfs (28) returned ENOSYS, marking as inactive.
[child0:520] faccessat (307) returned ENOSYS, marking as inactive.
[child0:520] fstatfs (100) returned ENOSYS, marking as inactive.
[   91.880619] random: 2 urandom warning(s) missed due to ratelimiting
[   92.898145] BUG: using __this_cpu_read() in preemptible [00000000] code: trinity-c6/526
[   92.899792] caller is __this_cpu_preempt_check+0xf/0x11
[   92.900613] CPU: 0 PID: 526 Comm: trinity-c6 Not tainted 5.9.0-rc8-00208-g4d004099a668c4 #1
[   92.901913] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[   92.903305] Call Trace:
[   92.903780]  dump_stack+0x6b/0x89
[   92.904363]  check_preemption_disabled+0x92/0xa4
[   92.905182]  __this_cpu_preempt_check+0xf/0x11
[   92.905968]  lockdep_hardirqs_on_prepare+0x2c/0x18f
[   92.906853]  trace_hardirqs_on+0x49/0x53
[   92.907578]  __bad_area_nosemaphore+0x3a/0x134
[   92.908363]  ? sysvec_kvm_asyncpf_interrupt+0x4b/0x4b
[   92.909051]  bad_area+0x37/0x3d
[   92.909523]  exc_page_fault+0x3ec/0x4bf
[   92.910228]  ? sysvec_kvm_asyncpf_interrupt+0x4b/0x4b
[   92.911156]  handle_exception+0x126/0x126
[   92.911939] EIP: 0x8084bf8
[   92.912445] Code: 90 90 53 8b 1d 20 8b ac 08 39 d9 0f 83 dc 00 00 00 5b 3b 0d 30 8b ac 08 0f 83 84 00 00 00 81 e9 80 00 00 00 81 e9 80 00 00 00 <66> 0f 7f 02 66 0f 7f 42 10 66 0f 7f 42 20 66 0f 7f 42 30 66 0f 7f
[   92.915660] EAX: 00000000 EBX: 08134000 ECX: 00000f00 EDX: b749d000
[   92.916771] ESI: b749d000 EDI: b749d000 EBP: b74a6030 ESP: bfd60e5c
[   92.917884] DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b EFLAGS: 00010206
[   92.919184] BUG: using __this_cpu_read() in preemptible [00000000] code: trinity-c6/526
[   92.920653] caller is __this_cpu_preempt_check+0xf/0x11
[   92.921617] CPU: 0 PID: 526 Comm: trinity-c6 Not tainted 5.9.0-rc8-00208-g4d004099a668c4 #1
[   92.923048] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[   92.924473] Call Trace:
[   92.924948]  dump_stack+0x6b/0x89
[   92.925556]  check_preemption_disabled+0x92/0xa4
[   92.926378]  ? __bad_area_nosemaphore+0x3a/0x134
[   92.927414]  __this_cpu_preempt_check+0xf/0x11
[   92.928225]  lockdep_hardirqs_on+0x36/0x115
[   92.928976]  trace_hardirqs_on+0x51/0x53
[   92.929681]  __bad_area_nosemaphore+0x3a/0x134
[   92.930651]  ? sysvec_kvm_asyncpf_interrupt+0x4b/0x4b
[   92.931576]  bad_area+0x37/0x3d
[   92.932116]  exc_page_fault+0x3ec/0x4bf
[   92.932814]  ? sysvec_kvm_asyncpf_interrupt+0x4b/0x4b
[   92.933694]  handle_exception+0x126/0x126
[   92.934408] EIP: 0x8084bf8
[   92.934895] Code: 90 90 53 8b 1d 20 8b ac 08 39 d9 0f 83 dc 00 00 00 5b 3b 0d 30 8b ac 08 0f 83 84 00 00 00 81 e9 80 00 00 00 81 e9 80 00 00 00 <66> 0f 7f 02 66 0f 7f 42 10 66 0f 7f 42 20 66 0f 7f 42 30 66 0f 7f
[   92.956477] EAX: 00000000 EBX: 08134000 ECX: 00000f00 EDX: b749d000
[   92.957593] ESI: b749d000 EDI: b749d000 EBP: b74a6030 ESP: bfd60e5c
[   92.958675] DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b EFLAGS: 00010206
[   94.314817] caller is __this_cpu_preempt_check+0xf/0x11
[   94.315679] CPU: 1 PID: 712 Comm: trinity-c3 Not tainted 5.9.0-rc8-00208-g4d004099a668c4 #1
[   94.316820] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[   94.317956] Call Trace:
[   94.318323]  dump_stack+0x6b/0x89
[   94.318788]  check_preemption_disabled+0x92/0xa4
[   94.319471]  __this_cpu_preempt_check+0xf/0x11
[   94.320089]  lockdep_hardirqs_on_prepare+0x2c/0x18f
[   94.320766]  trace_hardirqs_on+0x49/0x53
[   94.321305]  __bad_area_nosemaphore+0x3a/0x134
[   94.322042]  ? sysvec_kvm_asyncpf_interrupt+0x4b/0x4b
[   94.322750]  bad_area+0x37/0x3d
[   94.323226]  exc_page_fault+0x3ec/0x4bf
[   94.323786]  ? sysvec_kvm_asyncpf_interrupt+0x4b/0x4b
[   94.324490]  handle_exception+0x126/0x126
[   94.325043] EIP: 0x805bee0
[   94.325432] Code: 48 6a 00 57 e8 f1 c3 fe ff 83 c4 10 e9 b9 fd ff ff 89 f6 8d bc 27 00 00 00 00 8b 54 24 44 85 d2 74 28 31 ed be 64 00 00 00 90 <c6> 04 2f 25 e8 47 02 00 00 a8 01 b8 73 00 00 00 0f 44 c6 88 44 2f
[   94.327965] EAX: 0805bed8 EBX: 08134000 ECX: 00000008 EDX: 03ecb36e
[   94.328835] ESI: 00000064 EDI: b749b000 EBP: 00000000 ESP: bfd60d20
[   94.329671] DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b EFLAGS: 00010246
[   94.330634] BUG: using __this_cpu_read() in preemptible [00000000] code: trinity-c3/712
[   94.331797] caller is __this_cpu_preempt_check+0xf/0x11
[   94.332536] CPU: 1 PID: 712 Comm: trinity-c3 Not tainted 5.9.0-rc8-00208-g4d004099a668c4 #1
[   94.333646] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[   94.334783] Call Trace:
[   94.335140]  dump_stack+0x6b/0x89
[   94.339655]  check_preemption_disabled+0x92/0xa4
[   94.340307]  ? __bad_area_nosemaphore+0x3a/0x134
[   94.341086]  __this_cpu_preempt_check+0xf/0x11
[   94.341708]  lockdep_hardirqs_on+0x36/0x115
[   94.342298]  trace_hardirqs_on+0x51/0x53
[   94.342858]  __bad_area_nosemaphore+0x3a/0x134
[   94.343668]  ? sysvec_kvm_asyncpf_interrupt+0x4b/0x4b
[   94.344367]  bad_area+0x37/0x3d
[   94.344815]  exc_page_fault+0x3ec/0x4bf
[   94.345352]  ? sysvec_kvm_asyncpf_interrupt+0x4b/0x4b
[   94.346049]  handle_exception+0x126/0x126
[   94.346604] EIP: 0x805bee0
[   94.346997] Code: 48 6a 00 57 e8 f1 c3 fe ff 83 c4 10 e9 b9 fd ff ff 89 f6 8d bc 27 00 00 00 00 8b 54 24 44 85 d2 74 28 31 ed be 64 00 00 00 90 <c6> 04 2f 25 e8 47 02 00 00 a8 01 b8 73 00 00 00 0f 44 c6 88 44 2f
[   94.349539] EAX: 0805bed8 EBX: 08134000 ECX: 00000008 EDX: 03ecb36e
[   94.350401] ESI: 00000064 EDI: b749b000 EBP: 00000000 ESP: bfd60d20
[   94.355448] DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b EFLAGS: 00010246
[   94.920644] init: tty5 main process (383) terminated with status 1
[   94.921975] init: tty5 main process ended, respawning
[   94.939382] init: tty4 main process (382) terminated with status 1
[   94.941160] init: tty4 main process ended, respawning
[   94.946647] init: tty2 main process (384) terminated with status 1
[   94.948054] init: tty2 main process ended, respawning
[   94.957691] init: tty3 main process (385) terminated with status 1
[   94.959206] init: tty3 main process ended, respawning
[   95.035891] init: tty6 main process (387) terminated with status 1
[   95.042798] init: tty6 main process ended, respawning
[   95.146323] BUG: using __this_cpu_read() in preemptible [00000000] code: trinity-c7/744
[   95.147923] caller is __this_cpu_preempt_check+0xf/0x11
[   95.148903] CPU: 0 PID: 744 Comm: trinity-c7 Not tainted 5.9.0-rc8-00208-g4d004099a668c4 #1
[   95.150277] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[   95.151702] Call Trace:
[   95.152126]  dump_stack+0x6b/0x89
[   95.152620]  check_preemption_disabled+0x92/0xa4
[   95.153262]  __this_cpu_preempt_check+0xf/0x11
[   95.153893]  lockdep_hardirqs_on_prepare+0x2c/0x18f
[   95.154596]  trace_hardirqs_on+0x49/0x53
[   95.155285]  __bad_area_nosemaphore+0x3a/0x134
[   95.156227]  ? sysvec_kvm_asyncpf_interrupt+0x4b/0x4b
[   95.156991]  bad_area+0x37/0x3d
[   95.157539]  exc_page_fault+0x3ec/0x4bf
[   95.158234]  ? sysvec_kvm_asyncpf_interrupt+0x4b/0x4b
[   95.159010]  handle_exception+0x126/0x126
[   95.159742] EIP: 0x8084bf8
[   95.160193] Code: 90 90 53 8b 1d 20 8b ac 08 39 d9 0f 83 dc 00 00 00 5b 3b 0d 30 8b ac 08 0f 83 84 00 00 00 81 e9 80 00 00 00 81 e9 80 00 00 00 <66> 0f 7f 02 66 0f 7f 42 10 66 0f 7f 42 20 66 0f 7f 42 30 66 0f 7f
[   95.163390] EAX: 00000000 EBX: 08134000 ECX: 00000f00 EDX: b749b000
[   95.164499] ESI: b749b000 EDI: b749b000 EBP: b749f030 ESP: bfd60d0c
[   95.165575] DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b EFLAGS: 00010206
[   95.166889] BUG: using __this_cpu_read() in preemptible [00000000] code: trinity-c7/744
[   95.168327] caller is __this_cpu_preempt_check+0xf/0x11
[   95.169290] CPU: 0 PID: 744 Comm: trinity-c7 Not tainted 5.9.0-rc8-00208-g4d004099a668c4 #1
[   95.170739] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[   95.172231] Call Trace:
[   95.172694]  dump_stack+0x6b/0x89
[   95.173301]  check_preemption_disabled+0x92/0xa4
[   95.174102]  ? __bad_area_nosemaphore+0x3a/0x134
[   95.175095]  __this_cpu_preempt_check+0xf/0x11
[   95.175898]  lockdep_hardirqs_on+0x36/0x115
[   95.176595]  trace_hardirqs_on+0x51/0x53
[   95.177306]  __bad_area_nosemaphore+0x3a/0x134
[   95.178270]  ? sysvec_kvm_asyncpf_interrupt+0x4b/0x4b
[   95.179157]  bad_area+0x37/0x3d
[   95.179753]  exc_page_fault+0x3ec/0x4bf
[   95.180457]  ? sysvec_kvm_asyncpf_interrupt+0x4b/0x4b
[   95.181355]  handle_exception+0x126/0x126
[   95.182068] EIP: 0x8084bf8
[   95.182573] Code: 90 90 53 8b 1d 20 8b ac 08 39 d9 0f 83 dc 00 00 00 5b 3b 0d 30 8b ac 08 0f 83 84 00 00 00 81 e9 80 00 00 00 81 e9 80 00 00 00 <66> 0f 7f 02 66 0f 7f 42 10 66 0f 7f 42 20 66 0f 7f 42 30 66 0f 7f
[   95.185846] EAX: 00000000 EBX: 08134000 ECX: 00000f00 EDX: b749b000
[   95.186911] ESI: b749b000 EDI: b749b000 EBP: b749f030 ESP: bfd60d0c
[   95.188041] DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b EFLAGS: 00010206
[main] 10100 iterations. [F:6736 S:3353 HI:878]
[main] Using pid_max = 32768
[main] futex: 0 owner:0 global:1
[main] Reserved/initialized 10 futexes.
[main] sysv_shm: id:20 size:8192 flags:7b0 ptr:(nil) global:1

                                                          # HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 84caa4e94a3e78425c9c9222c5838dc18569ed53 bbf5c979011a099af5dc76498918ed7df445635b --
git bisect  bad dab538a37ee3643a27fa7dace841695022aa3b51  # 18:56  B      0     1   10   0  Merge 'tip/master' into devel-catchup-202010171728
git bisect good e47272658690b17657a850d466be4a0ece0fa845  # 19:15  G     10     0    8   8  0day base guard for 'devel-catchup-202010171728'
git bisect  bad 694b55be5ab7554ff27e30039c01c0ce777a76b2  # 19:31  B      0     2   11   0  Merge 'mptcp/export' into devel-catchup-202010171728
git bisect  bad 726eb70e0d34dc4bc4dada71f52bba8ed638431e  # 19:50  B      0     1   10   0  Merge tag 'char-misc-5.10-rc1' of git://git.kernel.org/pub/scm/linux/kernel/git/gregkh/char-misc
git bisect  bad 527f6750d92beb9c787d8aba48477b1e834d64e5  # 20:07  B      0     1   10   0  kasan: remove mentions of unsupported Clang versions
git bisect  bad 647412daeb454b6dad12a6c6961ab90aac9e5d29  # 20:31  B      0     6   16   1  Merge tag 'mmc-v5.10' of git://git.kernel.org/pub/scm/linux/kernel/git/ulfh/mmc
git bisect  bad 3bff6112c80cecb76af5fe485506f96e8adb6122  # 20:41  B      0     1   10   0  Merge tag 'perf-core-2020-10-12' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good f5f59336a9ae8f683772d6b8cb2d6732b5e567ea  # 21:11  G     10     0    8   8  Merge tag 'timers-core-2020-10-12' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good edaa5ddf3833669a25654d42c0fb653dfdd906df  # 21:24  G     10     0    9   9  Merge tag 'sched-core-2020-10-12' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect  bad e6412f9833db23740ee848ab3d6e7af18dff82a6  # 21:36  B      0     4   13   0  Merge tag 'efi-core-2020-10-12' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect  bad e705d397965811ac528d7213b42d74ffe43caf38  # 22:01  B      0     2   11   0  Merge branch 'locking/urgent' into locking/core, to pick up fixes
git bisect good ad56450db86413ff911eb527b5a49e04a4345e61  # 22:28  G     11     0    7   7  locking/selftest: Add test cases for queued_read_lock()
git bisect good 5cdd25572a29e46f932d3e6eedbd07429de66431  # 22:53  G     11     0    9   9  seqlock: Use unique prefix for seqcount_t property accessors
git bisect good 6d1823ccc480866e571ab1206665d693aeb600cf  # 23:06  G     10     0    5   5  lockdep: Optimize the memory usage of circular queue
git bisect  bad baffd723e44dc3d7f84f0b8f1fe1ece00ddd2710  # 23:17  B      0     4   13   0  lockdep: Revert "lockdep: Use raw_cpu_*() for per-cpu variables"
git bisect  bad 4d004099a668c41522242aa146a38cc4eb59cb1e  # 23:39  B      0     1   10   0  lockdep: Fix lockdep recursion
git bisect good 2bb8945bcc1a768f2bc402a16c9610bba8d5187d  # 00:12  G     10     0    6   6  lockdep: Fix usage_traceoverflow
# first bad commit: [4d004099a668c41522242aa146a38cc4eb59cb1e] lockdep: Fix lockdep recursion
git bisect good 2bb8945bcc1a768f2bc402a16c9610bba8d5187d  # 00:20  G     33     0   21  27  lockdep: Fix usage_traceoverflow
# extra tests with debug options
git bisect  bad 4d004099a668c41522242aa146a38cc4eb59cb1e  # 00:38  B      0     9   18   0  lockdep: Fix lockdep recursion
# extra tests on head commit of linus/master
git bisect  bad 7cf726a59435301046250c42131554d9ccc566b8  # 00:49  B      0     4   13   0  Merge tag 'linux-kselftest-kunit-5.10-rc1' of git://git.kernel.org/pub/scm/linux/kernel/git/shuah/linux-kselftest
# bad: [7cf726a59435301046250c42131554d9ccc566b8] Merge tag 'linux-kselftest-kunit-5.10-rc1' of git://git.kernel.org/pub/scm/linux/kernel/git/shuah/linux-kselftest
# extra tests on linus/master
# duplicated: [7cf726a59435301046250c42131554d9ccc566b8] Merge tag 'linux-kselftest-kunit-5.10-rc1' of git://git.kernel.org/pub/scm/linux/kernel/git/shuah/linux-kselftest
# extra tests on linux-next/master
# 119: [b2926c108f9fd225d3fe9ea73fb5c35f48735d20] Add linux-next specific files for 20201016

---
0-DAY CI Kernel Test Service, Intel Corporation
https://lists.01.org/hyperkitty/list/lkp@lists.01.org

Download attachment "dmesg-quantal-vm-quantal-75:20201019234025:i386-randconfig-a005-20201017:5.9.0-rc8-00208-g4d004099a668c4:1.gz" of type "application/gzip" (30098 bytes)

Download attachment "dmesg-quantal-vm-quantal-102:20201020000235:i386-randconfig-a005-20201017:5.9.0-rc8-00207-g2bb8945bcc1a76:2.gz" of type "application/gzip" (144285 bytes)

View attachment "reproduce-quantal-vm-quantal-75:20201019234025:i386-randconfig-a005-20201017:5.9.0-rc8-00208-g4d004099a668c4:1" of type "text/plain" (1178 bytes)

Download attachment "84caa4e94a3e78425c9c9222c5838dc18569ed53:gcc-9:i386-randconfig-a005-20201017:BUG:using__this_cpu_read__in_preemptible.xz" of type "application/x-xz" (11016 bytes)

View attachment "config-5.9.0-rc8-00208-g4d004099a668c4" of type "text/plain" (151812 bytes)

View attachment "config-5.9.0-rc8-00208-g4d004099a668c4" of type "text/plain" (151813 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ