[<prev] [next>] [thread-next>] [day] [month] [year] [list]
Message-ID: <20190619024114.GI7221@shao2-debian>
Date: Wed, 19 Jun 2019 10:41:14 +0800
From: kernel test robot <lkp@...el.com>
To: "Steven Rostedt (VMware)" <rostedt@...dmis.org>
Cc: Ingo Molnar <mingo@...nel.org>, linux-kernel@...r.kernel.org,
LKP <lkp@...org>
Subject: fcc784be83 [ 150.952780] WARNING: held lock freed!
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 fcc784be837714a9173b372ff9fb9b514590dad9
Author: Steven Rostedt (VMware) <rostedt@...dmis.org>
AuthorDate: Wed Apr 4 14:06:30 2018 -0400
Commit: Ingo Molnar <mingo@...nel.org>
CommitDate: Thu Jun 21 18:19:01 2018 +0200
locking/lockdep: Do not record IRQ state within lockdep code
While debugging where things were going wrong with mapping
enabling/disabling interrupts with the lockdep state and actual real
enabling and disabling interrupts, I had to silent the IRQ
disabling/enabling in debug_check_no_locks_freed() because it was
always showing up as it was called before the splat was.
Use raw_local_irq_save/restore() for not only debug_check_no_locks_freed()
but for all internal lockdep functions, as they hide useful information
about where interrupts were used incorrectly last.
Signed-off-by: Steven Rostedt (VMware) <rostedt@...dmis.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>
Cc: Will Deacon <will.deacon@....com>
Link: https://lkml.kernel.org/lkml/20180404140630.3f4f4c7a@gandalf.local.home
Signed-off-by: Ingo Molnar <mingo@...nel.org>
03eeafdd9a locking/rwsem: Fix up_read_non_owner() warning with DEBUG_RWSEMS
fcc784be83 locking/lockdep: Do not record IRQ state within lockdep code
bed3c0d84e Merge tag 'for-5.2-rc5-tag' of git://git.kernel.org/pub/scm/linux/kernel/git/kdave/linux
1c6b40509d Add linux-next specific files for 20190618
+----------------------------------------------------------------------------------+------------+------------+------------+---------------+
| | 03eeafdd9a | fcc784be83 | bed3c0d84e | next-20190618 |
+----------------------------------------------------------------------------------+------------+------------+------------+---------------+
| boot_successes | 874 | 277 | 276 | 21 |
| boot_failures | 14 | 19 | 64 | 7 |
| BUG:soft_lockup-CPU##stuck_for#s | 11 | 2 | 4 | |
| EIP:smp_call_function_single | 1 | 1 | | |
| Kernel_panic-not_syncing:softlockup:hung_tasks | 11 | 2 | 4 | |
| EIP:_raw_spin_unlock_irqrestore | 3 | 0 | 1 | |
| EIP:__copy_user_ll | 2 | 0 | 1 | |
| invoked_oom-killer:gfp_mask=0x | 1 | 2 | 1 | |
| Mem-Info | 1 | 2 | 2 | |
| EIP:wp_page_copy | 3 | | | |
| BUG:kernel_hang_in_early-boot_stage | 1 | | | |
| EIP:shmem_getpage_gfp | 2 | 0 | 1 | |
| BUG:workqueue_lockup-pool | 1 | 0 | 1 | |
| BUG:kernel_hang_in_boot-around-mounting-root_stage | 0 | 1 | | |
| Out_of_memory:Kill_process | 0 | 1 | | |
| WARNING:held_lock_freed | 0 | 13 | 58 | 7 |
| is_freeing_memory#-#,with_a_lock_still_held_there | 0 | 13 | 58 | 7 |
| BUG:kernel_hang_in_early-boot_stage,last_printk:early_console_in_setup_code | 0 | 1 | | |
| EIP:rcu_is_watching | 0 | 1 | | |
| EIP:ring_buffer_consume | 0 | 0 | 1 | |
| page_allocation_failure:order:#,mode:#(GFP_KERNEL|__GFP_NORETRY),nodemask=(null) | 0 | 0 | 1 | |
| BUG:unable_to_handle_page_fault_for_address | 0 | 0 | 1 | |
| Oops:#[##] | 0 | 0 | 1 | |
| EIP:debug_check_no_locks_freed | 0 | 0 | 1 | |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 0 | 1 | |
+----------------------------------------------------------------------------------+------------+------------+------------+---------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp@...el.com>
[ 147.470176] init: tty3 main process (364) terminated with status 1
[ 147.497211] init: tty3 main process ended, respawning
[ 147.612415] init: tty6 main process (365) terminated with status 1
[ 147.687364] init: tty6 main process ended, respawning
[ 150.937355]
[ 150.941927] =========================
[ 150.952780] WARNING: held lock freed!
[ 150.963993] 4.17.0-09747-gfcc784b #1 Tainted: G T
[ 150.989489] -------------------------
[ 151.005112] trinity-main/356 is freeing memory ce93de20-ce93de47, with a lock still held there!
[ 151.040984] (ptrval) (&wq#3){....}, at: __wake_up_common_lock+0x5a/0x140
[ 151.069147] 1 lock held by trinity-main/356:
[ 151.087139] #0: (ptrval) (&sb->s_type->i_mutex_key){....}, at: iterate_dir+0x42/0x2a0
[ 151.117861]
[ 151.117861] stack backtrace:
[ 151.132116] CPU: 1 PID: 356 Comm: trinity-main Tainted: G T 4.17.0-09747-gfcc784b #1
[ 151.162025] Call Trace:
[ 151.170423] dump_stack+0x2bc/0x41a
[ 151.182441] debug_check_no_locks_freed+0x354/0x370
[ 151.199065] __raw_spin_lock_init+0x29/0x80
[ 151.214099] __init_waitqueue_head+0x2e/0x70
[ 151.228615] ? proc_tgid_base_lookup+0x40/0x40
[ 151.243819] proc_fill_cache+0x118/0x360
[ 151.256974] ? format_decode+0x1a6/0x890
[ 151.270037] ? format_decode+0x308/0x890
[ 151.283679] ? vsnprintf+0x622/0x860
[ 151.293724] proc_map_files_readdir+0x648/0x6a0
[ 151.306619] ? proc_tgid_base_lookup+0x40/0x40
[ 151.320722] iterate_dir+0x225/0x2a0
[ 151.331637] ? __fget_light+0x7f/0x100
[ 151.342010] ksys_getdents64+0x1ac/0x2f0
[ 151.353610] ? sys_old_readdir+0x160/0x160
[ 151.366017] sys_getdents64+0x24/0x40
[ 151.377653] do_fast_syscall_32+0x14b/0x780
[ 151.392209] entry_SYSENTER_32+0x53/0x86
[ 151.404786] EIP: 0xa7fc4cd9
[ 151.423581] Code: 08 8b 80 5c cd ff ff 85 d2 74 02 89 02 5d c3 8b 04 24 c3 8b 0c 24 c3 8b 1c 24 c3 8b 3c 24 c3 90 90 51 52 55 89 e5 0f 34 cd 80 <5d> 5a 59 c3 90 90 90 90 8d 76 00 58 b8 77 00 00 00 cd 80 90 8d 76
[ 151.547420] EAX: ffffffda EBX: 0000002b ECX: 08aefedc EDX: 00008000
[ 151.586666] ESI: 08aefedc EDI: ffffffd0 EBP: 00000026 ESP: af88aac4
[ 151.605753] DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b EFLAGS: 00000246
[main] Added 10680 filenames from /proc
[main] Added 13148 filenames from /proc
[main] Added 13151 filenames from /proc
[ 156.784347] init: tty4 main process (366) terminated with status 1
[ 156.849880] init: tty4 main process ended, respawning
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start v4.18 v4.17 --
git bisect bad c81b995f00c7a1c2ca9ad67f5bb4a50d02f98f84 # 23:06 B 6 1 0 0 Merge branch 'perf-urgent-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good 2a70ea5cda00214a1d573acf19fa0cd06d947e38 # 23:43 G 205 0 9 9 Merge tag 'hsi-for-4.18' of git://git.kernel.org/pub/scm/linux/kernel/git/sre/linux-hsi
git bisect good e7655d2b25466c534ed1f539367dae595bb0bd20 # 00:13 G 209 0 15 15 Merge tag 'for-4.18-part2-tag' of git://git.kernel.org/pub/scm/linux/kernel/git/kdave/linux
git bisect good 6d90eb7ba341b3eb035121eff0b69d370cbc251e # 00:44 G 206 0 18 18 Merge tag 'dma-rename-4.18' of git://git.infradead.org/users/hch/dma-mapping
git bisect good 5e2204832b20ef9db859dd6a2e955ac3e33eef27 # 01:07 G 212 0 9 9 Merge tag 'powerpc-4.18-2' of git://git.kernel.org/pub/scm/linux/kernel/git/powerpc/linux
git bisect good 6242258b6b472f8fdd8ed9b735cc1190c185d16d # 01:37 G 206 0 24 24 Merge branch 'timers-urgent-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect bad d4e860eaf0584dfcc1375e06eeb34f85f43c8d34 # 02:10 B 36 1 4 4 Merge branch 'x86-urgent-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect bad 2da2ca24a38f0200111e3b8823c08d02cb59d362 # 03:07 B 90 1 7 7 Merge branch 'locking-urgent-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good a43de489934cadcbc4cc08a6590fdcc833768461 # 04:05 G 302 0 21 21 Merge branch 'ras-urgent-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good 7ea959c45769612aa92557fb6464679f5fec7d9e # 04:37 G 306 0 18 18 locking/refcounts: Implement refcount_dec_and_lock_irqsave()
git bisect bad fcc784be837714a9173b372ff9fb9b514590dad9 # 06:19 B 19 1 3 3 locking/lockdep: Do not record IRQ state within lockdep code
git bisect good 03eeafdd9ab06a770d42c2b264d50dff7e2f4eee # 06:47 G 305 0 18 18 locking/rwsem: Fix up_read_non_owner() warning with DEBUG_RWSEMS
# first bad commit: [fcc784be837714a9173b372ff9fb9b514590dad9] locking/lockdep: Do not record IRQ state within lockdep code
git bisect good 03eeafdd9ab06a770d42c2b264d50dff7e2f4eee # 07:23 G 904 0 40 58 locking/rwsem: Fix up_read_non_owner() warning with DEBUG_RWSEMS
# extra tests on HEAD of internal-eywa/master
git bisect bad b4c6b079156ebc029114a45812d5e5298f51fa01 # 07:23 B 8 3 0 2 Intel Next: Add release files for v5.2-rc5 2019-06-17
# extra tests on tree/branch linus/master
git bisect bad bed3c0d84e7e25c8e0964d297794f4c215b01f33 # 08:52 B 5 2 0 0 Merge tag 'for-5.2-rc5-tag' of git://git.kernel.org/pub/scm/linux/kernel/git/kdave/linux
# extra tests with first bad commit reverted
git bisect bad b0f031ad2fd0758b5bb7c58afe7f8bbaab65c265 # 09:42 B 126 2 10 10 Revert "locking/lockdep: Do not record IRQ state within lockdep code"
# extra tests on tree/branch linux-next/master
git bisect bad 1c6b40509daf5190b1fd2c758649f7df1da4827b # 10:20 B 9 2 0 0 Add linux-next specific files for 20190618
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
Download attachment "dmesg-quantal-vm-quantal-803:20190619061900:i386-randconfig-w0-06181652:4.17.0-09747-gfcc784b:1.gz" of type "application/gzip" (16526 bytes)
Download attachment "dmesg-quantal-vm-quantal-09ce24fb9434:20190619064856:i386-randconfig-w0-06181652:4.17.0-09746-g03eeafd:1.gz" of type "application/gzip" (33062 bytes)
View attachment "reproduce-quantal-vm-quantal-803:20190619061900:i386-randconfig-w0-06181652:4.17.0-09747-gfcc784b:1" of type "text/plain" (898 bytes)
View attachment "config-4.17.0-09747-gfcc784b" of type "text/plain" (102259 bytes)
Powered by blists - more mailing lists