| 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
| ||
|
Message-ID: <20170308010854.GA17420@yexl-desktop>
Date: Wed, 8 Mar 2017 09:08:54 +0800
From: kernel test robot <xiaolong.ye@...el.com>
To: Boqun Feng <boqun.feng@...il.com>
Cc: Ingo Molnar <mingo@...nel.org>,
Peter Zijlstra <peterz@...radead.org>,
Andrew Morton <akpm@...ux-foundation.org>,
Chris Wilson <chris@...is-wilson.co.uk>,
Fengguang Wu <fengguang.wu@...el.com>,
Linus Torvalds <torvalds@...ux-foundation.org>,
Nicolai Hähnle <Nicolai.Haehnle@....com>,
"Paul E. McKenney" <paulmck@...ux.vnet.ibm.com>,
Thomas Gleixner <tglx@...utronix.de>,
LKML <linux-kernel@...r.kernel.org>, lkp@...org
Subject: [lkp-robot] [locking/ww_mutex] 857811a371:
INFO:task_blocked_for_more_than#seconds
FYI, we noticed the following commit:
commit: 857811a37129f5d2ba162d7be3986eff44724014 ("locking/ww_mutex: Adjust the lock number for stress test")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
in testcase: boot
on test machine: qemu-system-i386 -enable-kvm -m 320M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------------------------------------+------------+------------+
| | 7fb4a2cea6 | 857811a371 |
+-----------------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 8 | 8 |
| WARNING:at_kernel/locking/lockdep.c:#__lock_acquire | 8 | |
| WARNING:at_drivers/gpu/drm/drm_mode_config.c:#drm_mode_config_cleanup | 8 | 4 |
| INFO:task_blocked_for_more_than#seconds | 0 | 4 |
| Kernel_panic-not_syncing:hung_task:blocked_tasks | 0 | 4 |
| calltrace:debug_show_all_locks | 0 | 2 |
+-----------------------------------------------------------------------+------------+------------+
[ 246.752394] INFO: task swapper:1 blocked for more than 120 seconds.
[ 246.765907] Not tainted 4.10.0-11078-g857811a #1
[ 246.777618] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 246.793776] swapper D 5948 1 0 0x00000000
[ 246.806182] Call Trace:
[ 246.812532] __schedule+0x369/0xdb0
[ 246.821768] schedule+0x3c/0xd0
[ 246.830251] schedule_timeout+0x105/0x410
[ 246.837524] ? trace_hardirqs_on_caller+0x12c/0x1d0
[ 246.849060] ? do_raw_spin_unlock+0xd9/0x170
[ 246.859553] ? trace_hardirqs_on+0xb/0x10
[ 246.867883] wait_for_completion+0x11a/0x190
[ 246.878229] ? do_task_dead+0x70/0x70
[ 246.887266] flush_workqueue+0x2fc/0xc30
[ 246.896894] ? flush_workqueue+0xa0/0xc30
[ 246.906836] ? kmalloc_array+0x70/0x70
[ 246.915459] stress+0x18c/0x214
[ 246.923946] test_ww_mutex_init+0x3b2/0x3d9
[ 246.934226] ? lock_torture_init+0x7f0/0x7f0
[ 246.944458] do_one_initcall+0x9f/0x1b6
[ 246.951910] ? parse_args+0x465/0x540
[ 246.959370] kernel_init_freeable+0x109/0x1cb
[ 246.967281] ? rest_init+0x130/0x130
[ 246.976223] kernel_init+0x11/0x190
[ 246.982703] ? schedule_tail+0x11/0x70
[ 246.990237] ? rest_init+0x130/0x130
[ 246.999942] ? rest_init+0x130/0x130
[ 247.008987] ret_from_fork+0x21/0x2c
[ 247.018186]
[ 247.018186] Showing all locks held in the system:
[ 247.033511] 2 locks held by khungtaskd/11:
[ 247.040956] #0: (rcu_read_lock){......}, at: [<c114a607>] watchdog+0xc7/0x7b0
[ 247.054513] #1: (tasklist_lock){.+.+..}, at: [<c10df617>] debug_show_all_locks+0x37/0x1a0
[ 247.073278] 4 locks held by kworker/u2:1/30:
[ 247.083586] #0: ("test-ww_mutex"){++++.+}, at: [<c10ae10c>] process_one_work+0x21c/0xab0
[ 247.101437] #1: ((&stress->work)){+.+.+.}, at: [<c10ae10c>] process_one_work+0x21c/0xab0
[ 247.117544] #2: (ww_class_acquire){+.+...}, at: [<c10ae1d3>] process_one_work+0x2e3/0xab0
[ 247.132833] #3: (ww_class_mutex){+.+...}, at: [<c10e6f5b>] stress_reorder_work+0x1cb/0x4f0
[ 247.152234]
[ 247.156751] =============================================
[ 247.156751]
[ 247.173280] NMI backtrace for cpu 0
[ 247.182489] CPU: 0 PID: 11 Comm: khungtaskd Not tainted 4.10.0-11078-g857811a #1
[ 247.199866] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 247.220997] Call Trace:
[ 247.228372] dump_stack+0x16/0x20
[ 247.236300] nmi_cpu_backtrace+0xb3/0xc0
[ 247.245947] nmi_trigger_cpumask_backtrace+0x10a/0x150
[ 247.256543] ? debug_show_all_locks+0x15b/0x1a0
[ 247.267307] ? setup_vector_irq+0x280/0x280
[ 247.277455] arch_trigger_cpumask_backtrace+0x15/0x20
[ 247.287045] watchdog+0x4fd/0x7b0
[ 247.296058] ? hung_task_panic+0x20/0x20
[ 247.306052] kthread+0x153/0x160
[ 247.314949] ? hung_task_panic+0x20/0x20
[ 247.324960] ? __kthread_create_on_node+0x230/0x230
[ 247.336396] ret_from_fork+0x21/0x2c
[ 247.345793] Kernel panic - not syncing: hung_task: blocked tasks
[ 247.346565] CPU: 0 PID: 11 Comm: khungtaskd Not tainted 4.10.0-11078-g857811a #1
[ 247.346565] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 247.346565] Call Trace:
[ 247.346565] dump_stack+0x16/0x20
[ 247.346565] panic+0xda/0x295
[ 247.346565] watchdog+0x509/0x7b0
[ 247.346565] ? hung_task_panic+0x20/0x20
[ 247.346565] kthread+0x153/0x160
[ 247.346565] ? hung_task_panic+0x20/0x20
[ 247.346565] ? __kthread_create_on_node+0x230/0x230
[ 247.346565] ret_from_fork+0x21/0x2c
[ 247.346565] Kernel Offset: disabled
Elapsed time: 260
initrds=(
/osimage/openwrt/openwrt-i386-2016-03-16.cgz
/lkp/scheduled/vm-lkp-wsx03-openwrt-i386-26/boot-1-openwrt-i386-2016-03-16.cgz-857811a37129f5d2ba162d7be3986eff44724014-20170308-43760-1dsuaev-0.cgz
/lkp/lkp/lkp-i386.cgz
)
cat "${initrds[@]}" > /fs/sdc1/initrd-vm-lkp-wsx03-openwrt-i386-26
kvm=(
qemu-system-i386
-enable-kvm
-kernel /pkg/linux/i386-randconfig-c0-03071508/gcc-4.9/857811a37129f5d2ba162d7be3986eff44724014/vmlinuz-4.10.0-11078-g857811a
-initrd /fs/sdc1/initrd-vm-lkp-wsx03-openwrt-i386-26
-m 320
-smp 1
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-watchdog-action debug
-rtc base=localtime
-drive file=/fs/sdc1/disk0-vm-lkp-wsx03-openwrt-i386-26,media=disk,if=virtio
-drive file=/fs/sdc1/disk1-vm-lkp-wsx03-openwrt-i386-26,media=disk,if=virtio
-pidfile /dev/shm/kboot/pid-vm-lkp-wsx03-openwrt-i386-26
-serial file:/dev/shm/kboot/vm-lkp-wsx03-openwrt-i386-26/serial
-serial file:/dev/shm/kboot/vm-lkp-wsx03-openwrt-i386-26/kmsg
-daemonize
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
View attachment "config-4.10.0-11078-g857811a" of type "text/plain" (109815 bytes)
View attachment "job-script" of type "text/plain" (3884 bytes)
Download attachment "dmesg.xz" of type "application/octet-stream" (9280 bytes)
Powered by blists - more mailing lists