[<prev] [next>] [day] [month] [year] [list]
Message-ID: <58c2158a.ycdHbmOyALfkcLXf%fengguang.wu@intel.com>
Date: Fri, 10 Mar 2017 10:55:06 +0800
From: kernel test robot <fengguang.wu@...el.com>
To: Chris Wilson <chris@...is-wilson.co.uk>
Cc: LKP <lkp@...org>, linux-kernel@...r.kernel.org,
"Peter Zijlstra (Intel)" <peterz@...radead.org>,
Ingo Molnar <mingo@...nel.org>, wfg@...ux.intel.com
Subject: [locking/ww_mutex] 2b232e0c3b: kworker/u2:0/5 is trying to
contend lock (ww_class_mutex) at:
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 2b232e0c3b3a09f3e33750aa20e314f1b80e5361
Author: Chris Wilson <chris@...is-wilson.co.uk>
AuthorDate: Tue Feb 28 09:40:11 2017 +0000
Commit: Ingo Molnar <mingo@...nel.org>
CommitDate: Thu Mar 2 09:00:38 2017 +0100
locking/ww_mutex: Replace cpu_relax() with cond_resched() for tests
When busy-spinning on a ww_mutex_trylock(), we depend upon the other
thread advancing and releasing the lock. This can not happen on a single
CPU unless we relinquish it:
[ ] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/0:1:18]
...
[ ] Call Trace:
[ ] mutex_trylock()
[ ] test_mutex_work+0x31/0x56
[ ] process_one_work+0x1b4/0x2f9
[ ] worker_thread+0x1b0/0x27c
[ ] kthread+0xd1/0xd3
[ ] ret_from_fork+0x19/0x30
Reported-by: Fengguang Wu <fengguang.wu@...el.com>
Signed-off-by: Chris Wilson <chris@...is-wilson.co.uk>
Signed-off-by: Peter Zijlstra (Intel) <peterz@...radead.org>
Cc: Andrew Morton <akpm@...ux-foundation.org>
Cc: Linus Torvalds <torvalds@...ux-foundation.org>
Cc: Paul E. McKenney <paulmck@...ux.vnet.ibm.com>
Cc: Peter Zijlstra <peterz@...radead.org>
Cc: Thomas Gleixner <tglx@...utronix.de>
Fixes: f2a5fec17395 ("locking/ww_mutex: Begin kselftests for ww_mutex")
Link: http://lkml.kernel.org/r/20170228094011.2595-1-chris@chris-wilson.co.uk
Signed-off-by: Ingo Molnar <mingo@...nel.org>
e2679aecf1 Merge branch 'linus' into locking/urgent, to pick up fixes
2b232e0c3b locking/ww_mutex: Replace cpu_relax() with cond_resched() for tests
+---------------------------------------------+------------+------------+
| | e2679aecf1 | 2b232e0c3b |
+---------------------------------------------+------------+------------+
| boot_successes | 16 | 0 |
| boot_failures | 23 | 26 |
| BUG:workqueue_lockup-pool | 23 | |
| is_trying_to_contend_lock(ww_class_mutex)at | 0 | 26 |
| calltrace:__ww_mutex_lock | 0 | 26 |
| BUG:unable_to_handle_kernel | 0 | 26 |
| Oops:#[##] | 0 | 26 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 26 |
+---------------------------------------------+------------+------------+
[ 18.843654]
[ 18.844009] =================================
[ 18.844009] [ BUG: bad contention detected! ]
[ 18.844009] 4.10.0-11076-g2b232e0 #2 Not tainted
[ 18.844009] ---------------------------------
[ 18.844009] kworker/u2:0/5 is trying to contend lock (ww_class_mutex) at:
[ 18.844009] [<ffffffff85ddf803>] __ww_mutex_lock+0x2bf/0x1837
[ 18.844009] but there are no locks held!
[ 18.844009]
[ 18.844009] other info that might help us debug this:
[ 18.844009] 4 locks held by kworker/u2:0/5:
[ 18.844009] #0: ("test-ww_mutex"){......}, at: [<ffffffff84af9360>] process_one_work+0x3e2/0x915
[ 18.844009] #1: ((&stress->work)){......}, at: [<ffffffff84af9360>] process_one_work+0x3e2/0x915
[ 18.844009] #2: (ww_class_acquire){......}, at: [<ffffffff84af948a>] process_one_work+0x50c/0x915
[ 18.844009] #3: (ww_class_mutex){......}, at: [<ffffffff84b37366>] stress_reorder_work+0x1ca/0x56a
[ 18.844009]
[ 18.844009] stack backtrace:
[ 18.844009] CPU: 0 PID: 5 Comm: kworker/u2:0 Not tainted 4.10.0-11076-g2b232e0 #2
[ 18.844009] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 18.844009] Workqueue: test-ww_mutex stress_reorder_work
[ 18.844009] Call Trace:
[ 18.844009] dump_stack+0x2e/0x3e
[ 18.844009] print_lock_contention_bug+0x148/0x158
[ 18.844009] lock_acquired+0x183/0x3de
[ 18.844009] __ww_mutex_lock+0x2bf/0x1837
[ 18.844009] ? stress_reorder_work+0x1ca/0x56a
[ 18.844009] ww_mutex_lock+0x67/0x94
[ 18.844009] ? ww_mutex_lock+0x67/0x94
[ 18.844009] stress_reorder_work+0x1ca/0x56a
[ 18.844009] process_one_work+0x50c/0x915
[ 18.844009] worker_thread+0x7b7/0xa52
[ 18.844009] ? rescuer_thread+0x576/0x576
[ 18.844009] kthread+0x182/0x191
[ 18.844009] ? __kthread_create_on_node+0x288/0x288
[ 18.844009] ret_from_fork+0x31/0x40
[ 62.432098] Writes: Total: 2 Max/Min: 0/0 Fail: 0
[ 68.532624] torture_init_begin: Refusing rcu init: spin_lock running.
[ 68.534146] torture_init_begin: One torture test at a time!
[ 70.353077] Initialise system trusted keyrings
[ 70.360190] workingset: timestamp_bits=62 max_order=17 bucket_order=0
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 7d91b38e8807c645ff172223192ef507f06596ce c1ae3cfa0e89fa1a7ecc4c99031f5e9ae99d9201 --
git bisect bad 7b00e3d8adeae6bdc23937867921867858171a85 # 01:05 B 0 1 11 0 Merge 'linux-review/Chris-Wilson/drm-i915-userptr-Disallow-wrapping-GTT-into-a-userptr/20170308-142807' into devel-spot-201703082207
git bisect good 4ed50da74d8289f1775fe2844aa7d8aa8f3f9fad # 02:06 G 11 0 5 5 Merge 'drm-tip/drm-tip' into devel-spot-201703082207
git bisect bad 3f898094e0b0e6110122c53184fc3924a6e2467a # 02:19 B 0 4 14 0 Merge 'linux-review/Adrian-Hunter/mmc-Add-Command-Queue-support/20170308-161308' into devel-spot-201703082207
git bisect bad 204018709924800cf4b5ac05262f55535184cb6e # 02:44 B 0 3 13 0 Merge 'pza/reset/simple' into devel-spot-201703082207
git bisect bad e98101f83181466c36847383e346b55b74c0f7ab # 03:06 B 0 1 11 0 Merge 'tip/master' into devel-spot-201703082207
git bisect good 85d6c31de507890cfbbf85bc962481abb3a75e2b # 04:17 G 10 0 4 4 Merge 'jkirsher-next-queue/dev-queue' into devel-spot-201703082207
git bisect bad 8a9172356f747dc3734cc8043a44bbe158f44749 # 04:30 B 0 10 20 0 Merge branch 'timers-urgent-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good f7d6a7283aa1de430c6323a9714d1a787bc2d1ea # 05:23 G 10 0 6 6 Merge tag 'powerpc-4.11-3' of git://git.kernel.org/pub/scm/linux/kernel/git/powerpc/linux
git bisect good 21f85778bd037f6d7652a79a60657599edf39ef6 # 06:11 G 10 0 1 2 Merge branch 'efi-urgent-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect bad 500e1af25209a048daa1f1f86083d4849d5c555b # 06:24 B 0 4 14 0 Merge branch 'locking-urgent-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect bad 7fb4a2cea6b18dab56d609530d077f168169ed6b # 06:42 B 0 3 13 0 locking/lockdep: Add nest_lock integrity test
git bisect good e2679aecf1060063b97bf187ba1c61430aab0701 # 07:35 G 11 0 3 5 Merge branch 'linus' into locking/urgent, to pick up fixes
git bisect bad 2b232e0c3b3a09f3e33750aa20e314f1b80e5361 # 07:50 B 0 2 35 11 locking/ww_mutex: Replace cpu_relax() with cond_resched() for tests
# first bad commit: [2b232e0c3b3a09f3e33750aa20e314f1b80e5361] locking/ww_mutex: Replace cpu_relax() with cond_resched() for tests
git bisect good e2679aecf1060063b97bf187ba1c61430aab0701 # 08:38 G 30 0 17 22 Merge branch 'linus' into locking/urgent, to pick up fixes
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect bad 2b232e0c3b3a09f3e33750aa20e314f1b80e5361 # 08:56 B 0 3 13 0 locking/ww_mutex: Replace cpu_relax() with cond_resched() for tests
# extra tests on HEAD of linux-devel/devel-spot-201703082207
git bisect bad 7d91b38e8807c645ff172223192ef507f06596ce # 09:01 B 0 13 26 0 0day head guard for 'devel-spot-201703082207'
# extra tests on tree/branch linus/master
git bisect bad c1aa905a304e4b5e6a3fe112ec62d9c1c7b0c155 # 09:15 B 0 7 17 0 Merge tag 'pm-4.11-rc2' of git://git.kernel.org/pub/scm/linux/kernel/git/rafael/linux-pm
# extra tests with first bad commit reverted
git bisect good 610df1f2c3a638fc953aa8a5d8b2df029ef77940 # 10:34 G 11 0 5 5 Revert "locking/ww_mutex: Replace cpu_relax() with cond_resched() for tests"
# extra tests on tree/branch linux-next/master
git bisect bad 2e62da1e534da571ffa99cfe36eed5b1eba8c598 # 10:54 B 0 11 21 0 Add linux-next specific files for 20170309
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
Download attachment "dmesg-yocto-kbuild-60:20170310075819:x86_64-randconfig-s4-03082246:4.10.0-11076-g2b232e0:2.gz" of type "application/gzip" (12183 bytes)
Download attachment "dmesg-vm-lkp-nex04-1G-15:20170310073231:x86_64-randconfig-s4-03082246:4.10.0-11075-ge2679ae:1.gz" of type "application/gzip" (7874 bytes)
View attachment "reproduce-yocto-kbuild-60:20170310075819:x86_64-randconfig-s4-03082246:4.10.0-11076-g2b232e0:2" of type "text/plain" (903 bytes)
View attachment "config-4.10.0-11076-g2b232e0" of type "text/plain" (89964 bytes)
Powered by blists - more mailing lists