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>] [day] [month] [year] [list]
Message-ID: <20171009155225.7to6zkdfesziro7u@wfg-t540p.sh.intel.com>
Date:   Mon, 9 Oct 2017 23:52:25 +0800
From:   Fengguang Wu <fengguang.wu@...el.com>
To:     "Levin, Alexander (Sasha Levin)" <alexander.levin@...izon.com>
Cc:     wfg@...ux.intel.com, Ingo Molnar <mingo@...nel.org>,
        linux-kernel@...r.kernel.org, LKP <lkp@...org>
Subject: [rtmutex] cde50a6739 [    3.661979] WARNING: bad unlock balance
 detected!

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 cde50a67397c0da7d11795d4b4418384022ab8e6
Author:     Levin, Alexander (Sasha Levin) <alexander.levin@...izon.com>
AuthorDate: Sun Jun 18 14:06:01 2017 +0000
Commit:     Ingo Molnar <mingo@...nel.org>
CommitDate: Tue Jun 20 11:53:09 2017 +0200

     locking/rtmutex: Don't initialize lockdep when not required
     
     pi_mutex isn't supposed to be tracked by lockdep, but just
     passing NULLs for name and key will cause lockdep to spew a
     warning and die, which is not what we want it to do.
     
     Skip lockdep initialization if the caller passed NULLs for
     name and key, suggesting such initialization isn't desired.
     
     Signed-off-by: Sasha Levin <alexander.levin@...izon.com>
     Cc: Linus Torvalds <torvalds@...ux-foundation.org>
     Cc: Peter Zijlstra <peterz@...radead.org>
     Cc: Thomas Gleixner <tglx@...utronix.de>
     Fixes: f5694788ad8d ("rt_mutex: Add lockdep annotations")
     Link: http://lkml.kernel.org/r/20170618140548.4763-1-alexander.levin@verizon.com
     Signed-off-by: Ingo Molnar <mingo@...nel.org>

018956d641  locking/selftest: Add RT-mutex support
cde50a6739  locking/rtmutex: Don't initialize lockdep when not required
42b76d0e6b  Merge tag 'armsoc-fixes' of git://git.kernel.org/pub/scm/linux/kernel/git/arm/arm-soc
1418b85217  Add linux-next specific files for 20170929
+------------------------------------------------------------------+------------+------------+------------+---------------+
|                                                                  | 018956d641 | cde50a6739 | 42b76d0e6b | next-20170929 |
+------------------------------------------------------------------+------------+------------+------------+---------------+
| boot_successes                                                   | 745        | 259        | 126        | 0             |
| boot_failures                                                    | 138        | 26         | 167        | 300           |
| WARNING:at_kernel/locking/lockdep.c:#lockdep_init_map            | 55         |            |            |               |
| BUG:kernel_hang_in_test_stage                                    | 32         | 3          | 10         |               |
| BUG:kernel_reboot-without-warning_in_test_stage                  | 4          | 2          | 1          |               |
| BUG:workqueue_lockup-pool                                        | 47         | 11         | 25         | 32            |
| WARNING:suspicious_RCU_usage                                     | 3          | 0          | 3          | 4             |
| kernel/rcu/rcutorture.c:#suspicious_rcu_dereference_check()usage | 3          | 0          | 3          | 4             |
| INFO:rcu_preempt_detected_stalls_on_CPUs/tasks                   | 1          | 0          | 0          | 4             |
| WARNING:bad_unlock_balance_detected                              | 0          | 10         | 139        |               |
| is_trying_to_release_lock(rcu_preempt_state)at                   | 0          | 9          | 139        |               |
| INFO:rcu_preempt_self-detected_stall_on_CPU                      | 0          | 0          | 1          |               |
| kernel_BUG_at_arch/x86/mm/physaddr.c                             | 0          | 0          | 0          | 270           |
| invalid_opcode:#[##]                                             | 0          | 0          | 0          | 270           |
| Kernel_panic-not_syncing:Fatal_exception                         | 0          | 0          | 0          | 270           |
+------------------------------------------------------------------+------------+------------+------------+---------------+

[    3.637153] rcu-torture: Creating rcu_torture_cbflood task
[    3.638063] rcu-torture: rcu_torture_boost started
[    3.643372] rcu-torture: rcu_torture_cbflood task started
[    3.660861] 
[    3.661161] =====================================
[    3.661979] WARNING: bad unlock balance detected!
[    3.662795] 4.12.0-rc4-00028-gcde50a6 #1 Not tainted
[    3.667742] -------------------------------------
[    3.668564] swapper/0/1 is trying to release lock (rcu_preempt_state) at:
[    3.669754] [<ffffffffa4d3dabd>] rcu_read_unlock_special+0x54d/0x6f0
[    3.670838] but there are no more locks to release!
[    3.672117] 
[    3.672117] other info that might help us debug this:
[    3.673055] 4 locks held by swapper/0/1:
[    3.673616]  #0:  (gcov_lock){+.+.+.}, at: [<ffffffffa4d7b20c>] gcov_enable_events+0x1c/0x90
[    3.674805]  #1:  (node_lock){+.+.+.}, at: [<ffffffffa4d7c0ac>] gcov_event+0x2c/0x620
[    3.675927]  #2:  (&sb->s_type->i_mutex_key#3){+.+.+.}, at: [<ffffffffa4efa36e>] start_creating+0x6e/0x140
[    3.677409]  #3:  (rcu_read_lock){......}, at: [<ffffffffa4e554c0>] __d_lookup+0x0/0x390
[    3.678660] 
[    3.678660] stack backtrace:
[    3.679331] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 4.12.0-rc4-00028-gcde50a6 #1
[    3.680514] Call Trace:
[    3.680916]  dump_stack+0x168/0x215
[    3.681480]  ? rcu_read_unlock_special+0x54d/0x6f0
[    3.682222]  print_unlock_imbalance_bug+0x175/0x190
[    3.693175]  lock_release+0x44a/0x5a0
[    3.693768]  rt_mutex_unlock+0x3e/0x110
[    3.694370]  ? trace_hardirqs_on+0x1b/0x30
[    3.695021]  rcu_read_unlock_special+0x54d/0x6f0
[    3.695776]  __rcu_read_unlock+0x9a/0xb0
[    3.696472]  __d_lookup+0x14c/0x390
[    3.697088]  ? __d_lookup_rcu+0x2a0/0x2a0
[    3.697797]  d_lookup+0x11b/0x1e0
[    3.698379]  ? lookup_dcache+0x28/0xb0
[    3.699042]  lookup_dcache+0x28/0xb0
[    3.699662]  __lookup_hash+0x29/0x100
[    3.700236]  lookup_one_len+0x15f/0x1a0
[    3.700841]  start_creating+0x8a/0x140
[    3.701439]  __debugfs_create_file+0x50/0x150
[    3.702119]  debugfs_create_file+0x22/0x40
[    3.702766]  new_node+0x140/0x570
[    3.703289]  ? __kmalloc_track_caller+0x8f/0xc0
[    3.704058]  gcov_event+0x54b/0x620
[    3.704679]  gcov_enable_events+0x40/0x90
[    3.705384]  ? gcov_persist_setup+0x71/0x71
[    3.706795]  gcov_fs_init+0xee/0x128
[    3.707337]  do_one_initcall+0xd5/0x21a
[    3.707929]  kernel_init_freeable+0x39f/0x489
[    3.708707]  ? do_early_param+0xd7/0xd7
[    3.709391]  ? rest_init+0x1e0/0x1e0
[    3.710011]  kernel_init+0x10/0x1b0
[    3.710523]  ret_from_fork+0x2a/0x40
[    4.587976] tsc: Refined TSC clocksource calibration: 2693.508 MHz
[    4.601228] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d349e8249, max_idle_ns: 440795288087 ns
[    4.888892] workingset: timestamp_bits=46 max_order=17 bucket_order=0
[    4.889890] zbud: loaded
[    4.890654] fuse init (API version 7.26)

                                                           # HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start v4.13 v4.12 --
git bisect  bad 63a86362130f4c17eaa57f3ef5171ec43111a54e  # 17:11  B     29     6    3   3  Merge tag 'pm-4.13-rc2' of git://git.kernel.org/pub/scm/linux/kernel/git/rafael/linux-pm
git bisect  bad 090a81d8766e21d33ab3e4d24e6c8e5eedf086dd  # 17:29  B     17     1    0   0  Merge branch 'for-spi' of git://git.kernel.org/pub/scm/linux/kernel/git/viro/vfs
git bisect  bad 3bad2f1c676581d01e7645eb03e9b27e28b0a92e  # 17:51  B     21     4    0   0  Merge branch 'work.misc-set_fs' of git://git.kernel.org/pub/scm/linux/kernel/git/viro/vfs
git bisect  bad 362f6729cbb1d6bbab59e069f19441b0622ff7ec  # 18:10  B     12    12    2   2  Merge tag 'usb-4.13-rc1' of git://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb
git bisect  bad 6553698be05983056f5561cef6aaee0cc6afe7a0  # 18:22  B      0    18   30   0  Merge branch 'x86-cleanups-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect  bad 892ad5acca0b2ddb514fae63fa4686bf726d2471  # 18:49  B     53     1    6   6  Merge branch 'locking-core-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good 26d3a77d2cb3cb31bbaa2de37b7a4e6375f204ee  # 09:18  G    275     0   28  28  Merge tag 'edac_for_4.13' of git://git.kernel.org/pub/scm/linux/kernel/git/bp/bp
git bisect good 330e9e46253cbfab178450c976aa90ef0f3ae940  # 22:52  G    275     0   25  25  Merge branch 'core-rcu-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good 162b246eb420d2ca2002a50917c897b10c9aba09  # 06:29  G    276     0   16  16  Merge branch 'efi-core-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good 12d83f7361724e5aa4299bb28e4caadc62ae482b  # 07:29  G    281     0   21  21  tools/include: Add (mostly) empty include/linux/sched/mm.h
git bisect good 3942b77121986519ee52ab4dd4ae8f4383dfe765  # 08:46  G    281     0   41  41  MAINTAINERS: Claim atomic*_t maintainership
git bisect  bad cde50a67397c0da7d11795d4b4418384022ab8e6  # 09:09  B     36     2    2   2  locking/rtmutex: Don't initialize lockdep when not required
git bisect good cfb6133399a490419cda55fcdcf9dbbca65eacba  # 11:59  G    285     0   59  59  locking/selftest: Remove the bad unlock ordering test
git bisect good 018956d6419be3e5ff5aacc60b2a3cff585adee1  # 13:04  G    282     0   45  45  locking/selftest: Add RT-mutex support
# first bad commit: [cde50a67397c0da7d11795d4b4418384022ab8e6] locking/rtmutex: Don't initialize lockdep when not required
git bisect good 018956d6419be3e5ff5aacc60b2a3cff585adee1  # 13:57  G    825     0   84 129  locking/selftest: Add RT-mutex support
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect  bad cde50a67397c0da7d11795d4b4418384022ab8e6  # 14:23  B     62     1    1   1  locking/rtmutex: Don't initialize lockdep when not required
# extra tests on HEAD of linux-devel/devel-hourly-2017092912
git bisect  bad 24b0ce22bf476fe0e1c1a867bf79de69cdfc0e17  # 14:29  B      0     2   28  11  0day head guard for 'devel-hourly-2017092912'
# extra tests on tree/branch linus/master
git bisect  bad 42b76d0e6b1fe0fcb90e0ff6b4d053d50597b031  # 14:42  B      1     1    0   0  Merge tag 'armsoc-fixes' of git://git.kernel.org/pub/scm/linux/kernel/git/arm/arm-soc
# extra tests with first bad commit reverted
git bisect good aa92526f37dc05abd018ccd5dc59f463f87009a4  # 15:47  G    280     0  107 107  Revert "locking/rtmutex: Don't initialize lockdep when not required"
# extra tests on tree/branch linux-next/master
git bisect good 1418b852174ad50b3cb4738b8801626aefdc0bd9  # 16:42  G    280     0  280 280  Add linux-next specific files for 20170929

---
0-DAY kernel test infrastructure                Open Source Technology Center
https://lists.01.org/pipermail/lkp                          Intel Corporation

Download attachment "dmesg-quantal-vp-12:20171005090750:x86_64-randconfig-n0-09291732:4.12.0-rc4-00028-gcde50a6:1.gz" of type "application/gzip" (20579 bytes)

Download attachment "dmesg-quantal-vp-10:20171005131332:x86_64-randconfig-n0-09291732:4.12.0-rc4-00027-g018956d6419be:1.gz" of type "application/gzip" (21185 bytes)

View attachment "reproduce-quantal-vp-12:20171005090750:x86_64-randconfig-n0-09291732:4.12.0-rc4-00028-gcde50a6:1" of type "text/plain" (888 bytes)

View attachment "config-4.12.0-rc4-00028-gcde50a6" of type "text/plain" (88332 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ