Greetings, 0day kernel testing robot got the below dmesg and the first bad commit is git://internal_merge_and_test_tree devel-hourly-2015090915 commit 72c9d8043fdc87832802de0b7a7129d6fc4c4c70 Author: Stephen Rothwell <sfr(a)canb.auug.org.au&gt; AuthorDate: Wed Sep 9 13:23:18 2015 +1000 Commit: Stephen Rothwell <sfr(a)canb.auug.org.au&gt; CommitDate: Wed Sep 9 13:23:18 2015 +1000 Add linux-next specific files for 20150909 Signed-off-by: Stephen Rothwell <sfr(a)canb.auug.org.au&gt; Usage: create-matrix -o matrix_file stats_files... options: -o, --output FILE save matrix to FILE -t, --table show as table -n, --no-source don't add field for tracking data source files -h, --help show this message Kernel tests: Boot OK! [ 45.241168] [ 45.243083] ====================================================== [ 45.244944] [ INFO: possible circular locking dependency detected ] [ 45.246816] 4.2.0-wl-ath-07326-gfd8cc0d #587 Not tainted [ 45.248624] ------------------------------------------------------- [ 45.250543] trinity-main/407 is trying to acquire lock: [ 45.252368] (register_mutex#5){+.+.+.}, at: [<c26a0b03>] snd_rawmidi_kernel_open+0xd9/0x1a7 [ 45.254786] [ 45.254786] but task is already holding lock: [ 45.258042] (&grp->list_mutex/1){+.+...}, at: [<c26af564>] snd_seq_port_connect+0xb1/0x414 [ 45.260577] [ 45.260577] which lock already depends on the new lock. [ 45.260577] [ 45.265769] [ 45.265769] the existing dependency chain (in reverse order) is: [ 45.269536] -> #2 (&grp->list_mutex/1){+.+...}: [ 45.273169] [<c110cf9d>] __lock_acquire+0x15e8/0x170f [ 45.275393] [<c110ef86>] lock_acquire+0x31f/0x55f [ 45.277620] [<c11014b4>] down_write_nested+0x5e/0x98 [ 45.279924] [<c26af564>] snd_seq_port_connect+0xb1/0x414 [ 45.282261] [<c26a64ac>] snd_seq_ioctl_subscribe_port+0x17e/0x267 [ 45.284688] [<c26a1a25>] snd_seq_do_ioctl+0x193/0x25e [ 45.287036] [<c26a6c44>] snd_seq_kernel_client_ctl+0x6d/0x94 [ 45.289550] [<c36fb95e>] snd_seq_oss_create_client+0x138/0x18a [ 45.292095] [<c36fb72e>] alsa_seq_oss_init+0x220/0x318 [ 45.294570] [<c3636c6b>] do_one_initcall+0x23c/0x474 [ 45.296990] [<c36370eb>] kernel_init_freeable+0x248/0x3b9 [ 45.299473] [<c2856750>] kernel_init+0x1b/0x1d9 [ 45.301904] [<c2866340>] ret_from_kernel_thread+0x20/0x30 [ 45.304431] -> #1 (&grp->list_mutex){++++.+}: [ 45.308644] [<c110cf9d>] __lock_acquire+0x15e8/0x170f [ 45.311177] [<c110ef86>] lock_acquire+0x31f/0x55f [ 45.313716] [<c2862147>] down_read+0x5b/0x8a [ 45.316226] [<c26a557a>] snd_seq_deliver_event+0x1a1/0x33f [ 45.318951] [<c26a6baf>] snd_seq_kernel_client_dispatch+0x14b/0x173 [ 45.321753] [<c26adf20>] snd_seq_system_broadcast+0x8b/0xb2 [ 45.324485] [<c26a68bc>] snd_seq_create_kernel_client+0x223/0x23f [ 45.327278] [<c26b91aa>] snd_virmidi_dev_register+0xc0/0x26f [ 45.330130] [<c269d2ea>] snd_rawmidi_dev_register+0x188/0x4a7 [ 45.332987] [<c2674e88>] __snd_device_register+0x60/0x79 [ 45.335774] [<c2675747>] snd_device_register_all+0xe4/0x111 [ 45.338585] [<c266e9eb>] snd_card_register+0x12c/0x419 [ 45.341341] [<c26c0cce>] snd_virmidi_probe+0x1a8/0x200 [ 45.344087] [<c1f8a66b>] platform_drv_probe+0x86/0x115 [ 45.346854] [<c1f86195>] driver_probe_device+0x365/0x7f1 [ 45.349684] [<c1f86a63>] __device_attach_driver+0xd9/0xf3 [ 45.352519] [<c1f836c2>] bus_for_each_drv+0xfe/0x11f [ 45.355366] [<c1f85b84>] __device_attach+0xe6/0x1ff [ 45.358165] [<c1f86aca>] device_initial_probe+0x20/0x30 [ 45.361023] [<c1f83a59>] bus_probe_device+0x55/0xee [ 45.363811] [<c1f81aa3>] device_add+0x8a5/0xb1a [ 45.366520] [<c1f8b3e4>] platform_device_add+0x2d4/0x39b [ 45.369260] [<c1f8b7de>] platform_device_register_full+0x130/0x1af [ 45.372106] [<c36fc00e>] alsa_card_virmidi_init+0x78/0x165 [ 45.374836] [<c3636c6b>] do_one_initcall+0x23c/0x474 [ 45.377471] [<c36370eb>] kernel_init_freeable+0x248/0x3b9 [ 45.380109] [<c2856750>] kernel_init+0x1b/0x1d9 [ 45.382627] [<c2866340>] ret_from_kernel_thread+0x20/0x30 [ 45.385262] -> #0 (register_mutex#5){+.+.+.}: [ 45.389702] [<c1106774>] validate_chain+0x112a/0x1cbe [ 45.392446] [<c110cf9d>] __lock_acquire+0x15e8/0x170f [ 45.395099] [<c110ef86>] lock_acquire+0x31f/0x55f [ 45.397687] [<c285c142>] mutex_lock_nested+0x7d/0x9e5 [ 45.400315] [<c26a0b03>] snd_rawmidi_kernel_open+0xd9/0x1a7 [ 45.403025] [<c26ba0e6>] midisynth_subscribe+0x3f/0x178 [ 45.405663] [<c26ae229>] subscribe_port+0x5d/0xdd [ 45.408241] [<c26af5d1>] snd_seq_port_connect+0x11e/0x414 [ 45.410932] [<c26a64ac>] snd_seq_ioctl_subscribe_port+0x17e/0x267 [ 45.413710] [<c26a1a25>] snd_seq_do_ioctl+0x193/0x25e [ 45.416337] [<c26a6c44>] snd_seq_kernel_client_ctl+0x6d/0x94 [ 45.419053] [<c26b7344>] snd_seq_oss_midi_open+0x1c0/0x23c [ 45.421715] [<c26b73ef>] snd_seq_oss_midi_open_all+0x2f/0x53 [ 45.424336] [<c26b1ef5>] snd_seq_oss_open+0x384/0x417 [ 45.426872] [<c26b11f3>] odev_open+0x60/0x8e [ 45.429440] [<c266bce7>] soundcore_open+0x1be/0x1d3 [ 45.431941] [<c129c529>] chrdev_open+0x21c/0x266 [ 45.434422] [<c1291777>] do_dentry_open+0x4e3/0x699 [ 45.436993] [<c1293518>] vfs_open+0xb4/0xc7 [ 45.439491] [<c12b081d>] path_openat+0x1a6b/0x1ff8 [ 45.442054] [<c12b0e04>] do_filp_open+0x5a/0x13a [ 45.444537] [<c1293e07>] do_sys_open+0x266/0x386 [ 45.446976] [<c1293f52>] SyS_open+0x2b/0x49 [ 45.449382] [<c28664cd>] syscall_after_call+0x0/0x4 [ 45.451840] [ 45.451840] other info that might help us debug this: [ 45.451840] [ 45.458193] Chain exists of: register_mutex#5 --> &grp->list_mutex --> &grp->list_mutex/1 [ 45.464784] Possible unsafe locking scenario: [ 45.464784] [ 45.469205] CPU0 CPU1 [ 45.471594] ---- ---- [ 45.473932] lock(&grp->list_mutex/1); [ 45.476254] lock(&grp->list_mutex); [ 45.478890] lock(&grp->list_mutex/1); [ 45.481564] lock(register_mutex#5); [ 45.483843] [ 45.483843] *** DEADLOCK *** [ 45.483843] [ 45.489596] 3 locks held by trinity-main/407: [ 45.491795] #0: (register_mutex#3){+.+.+.}, at: [<c26b11dc>] odev_open+0x49/0x8e [ 45.494562] #1: (&grp->list_mutex){++++.+}, at: [<c26af541>] snd_seq_port_connect+0x8e/0x414 [ 45.497463] #2: (&grp->list_mutex/1){+.+...}, at: [<c26af564>] snd_seq_port_connect+0xb1/0x414 [ 45.500431] [ 45.500431] stack backtrace: [ 45.504482] CPU: 0 PID: 407 Comm: trinity-main Not tainted 4.2.0-wl-ath-07326-gfd8cc0d #587 [ 45.507351] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014 [ 45.510466] 00000000 c3c53fbc cb26fa58 c180fc6c cb26fa90 c1104201 c3029b51 c3029957 [ 45.513603] c302991f c3029940 c302991f cad31a80 00000002 00000000 cad31f38 cad31f38 [ 45.516718] cad31f1c 00000003 cb26fb0c c1106774 cad31f1c 00000000 ffffffd6 ffffffff [ 45.519966] Call Trace: [ 45.522285] [<c180fc6c>] dump_stack+0x40/0x5e [ 45.524862] [<c1104201>] print_circular_bug+0x539/0x554 [ 45.527571] [<c1106774>] validate_chain+0x112a/0x1cbe [ 45.530316] [<c110cf9d>] __lock_acquire+0x15e8/0x170f [ 45.533007] [<c1102cc2>] ? __lock_is_held+0x46/0x7b [ 45.535665] [<c110ef86>] lock_acquire+0x31f/0x55f [ 45.538331] [<c26a0b03>] ? snd_rawmidi_kernel_open+0xd9/0x1a7 [ 45.541104] [<c285c142>] mutex_lock_nested+0x7d/0x9e5 [ 45.543811] [<c26a0b03>] ? snd_rawmidi_kernel_open+0xd9/0x1a7 [ 45.546618] [<c26a0b03>] snd_rawmidi_kernel_open+0xd9/0x1a7 [ 45.549404] [<c26ba0e6>] midisynth_subscribe+0x3f/0x178 [ 45.552151] [<c110ef86>] ? lock_acquire+0x31f/0x55f [ 45.554858] [<c26af564>] ? snd_seq_port_connect+0xb1/0x414 [ 45.557649] [<c26ba0a7>] ? midisynth_use+0x146/0x146 [ 45.560375] [<c26ae229>] subscribe_port+0x5d/0xdd [ 45.563068] [<c26ae229>] ? subscribe_port+0x5d/0xdd [ 45.565747] [<c26af5d1>] snd_seq_port_connect+0x11e/0x414 [ 45.568493] [<c26a64ac>] snd_seq_ioctl_subscribe_port+0x17e/0x267 [ 45.571349] [<c26a1a25>] snd_seq_do_ioctl+0x193/0x25e [ 45.574045] [<c26a6c44>] snd_seq_kernel_client_ctl+0x6d/0x94 [ 45.576818] [<c26a6c44>] ? snd_seq_kernel_client_ctl+0x6d/0x94 [ 45.579662] [<c26b7344>] snd_seq_oss_midi_open+0x1c0/0x23c [ 45.582488] [<c26b73ef>] snd_seq_oss_midi_open_all+0x2f/0x53 [ 45.585344] [<c26b1ef5>] snd_seq_oss_open+0x384/0x417 [ 45.588084] [<c26b11f3>] odev_open+0x60/0x8e [ 45.590691] [<c266bce7>] soundcore_open+0x1be/0x1d3 [ 45.593350] [<c129c529>] chrdev_open+0x21c/0x266 [ 45.596036] [<c1291777>] do_dentry_open+0x4e3/0x699 [ 45.598852] [<c129c30d>] ? cdev_put+0x3d/0x3d [ 45.601527] [<c1293518>] vfs_open+0xb4/0xc7 [ 45.604131] [<c12b081d>] path_openat+0x1a6b/0x1ff8 [ 45.606862] [<c12b0e04>] do_filp_open+0x5a/0x13a [ 45.609527] [<c2864a2a>] ? _raw_spin_unlock+0x4c/0x71 [ 45.612189] [<c12cf933>] ? __alloc_fd+0x276/0x28e [ 45.614851] [<c1293e07>] do_sys_open+0x266/0x386 [ 45.617513] [<c2860000>] ? __ww_mutex_lock_interruptible+0x1bd/0x1217 [ 45.620420] [<c1293f52>] SyS_open+0x2b/0x49 [ 45.623028] [<c28664cd>] syscall_call+0x7/0x7 [ 45.625624] [<c2860000>] ? __ww_mutex_lock_interruptible+0x1bd/0x1217 [ 62.996063] Writes: Total: 2 Max/Min: 0/0 Fail: 0 [ 91.325867] init: rc main process (343) killed by TERM signal [ 91.333608] init: tty4 main process (344) killed by TERM signal git bisect good fd8cc0d9775501e00b195f6204f6fdf3aebe325c # 20:12 11+ 4 0day head guard for 'devel-hourly-2015090915' git bisect good fd8cc0d9775501e00b195f6204f6fdf3aebe325c # 20:15 11+ 4 0day head guard for 'devel-hourly-2015090915' git bisect good fd8cc0d9775501e00b195f6204f6fdf3aebe325c # 20:17 11+ 4 0day head guard for 'devel-hourly-2015090915' git bisect good fd8cc0d9775501e00b195f6204f6fdf3aebe325c # 20:20 10+ 4 0day head guard for 'devel-hourly-2015090915' git bisect bad fd8cc0d9775501e00b195f6204f6fdf3aebe325c # 20:23 10- 5 0day head guard for 'devel-hourly-2015090915' git bisect bad 64291f7db5bd8150a74ad2036f1037e6a0428df2 # 20:23 0- 5 Linux 4.2 # extra tests on tree/branch linus/master git bisect bad a794b4f3292160bb3fd0f1f90ec8df454e3b17b3 # 20:30 19- 1 Merge tag 'for-linus-4.3' of git://git.code.sf.net/p/openipmi/linux-ipmi # extra tests on tree/branch linux-next/master git bisect bad 72c9d8043fdc87832802de0b7a7129d6fc4c4c70 # 20:41 101- 8 Add linux-next specific files for 20150909 This script may reproduce the error. ---------------------------------------------------------------------------- #!/bin/bash kernel=$1 initrd=quantal-core-i386.cgz wget --no-clobber https://github.com/fengguang/reproduce-kernel-bug/raw/master/initrd/$initrd kvm=( qemu-system-x86_64 -enable-kvm -cpu kvm64 -kernel $kernel -initrd $initrd -m 300 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -serial stdio -display none -monitor null ) append=( hung_task_panic=1 earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw drbd.minor_count=8 ) "${kvm[@]}" --append "${append[*]}" ---------------------------------------------------------------------------- --- 0-DAY kernel test infrastructure Open Source Technology Center https://lists.01.org/pipermail/lkp Intel Corporation