Hello, FYI this happens in mainline kernel 4.14.0-12891-gc8a0739. It looks like a new regression after 4.14. It occurs in 1 out of 2 boots. [ 79.460969] run-job /lkp/scheduled/vm-lkp-wsx03-yocto-x86_64-18/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-c8a0739b185d11d6e2ca7ad9f5835841d1cfc765-20171120-97995-o5usht-0.yaml [ 79.460969] /bin/busybox wget -q http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/vm-lkp-wsx03-yocto-x86_64-18/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-c8a0739b185d11d6e2ca7ad9f5835841d1cfc765-20171120-97995-o5usht-0.yaml&job_state=running -O /dev/null [ 80.864966] Seeding trinity based on x86_64-allyesdebian [ 91.375399] [ 91.380720] ====================================================== [ 91.394163] WARNING: possible circular locking dependency detected [ 91.407453] 4.14.0-12891-gc8a0739 #14 Not tainted [ 91.417852] ------------------------------------------------------ [ 91.431493] trinity/648 is trying to acquire lock: [ 91.442608] (misc_mtx){+.+.}, at: [] misc_seq_start+0x17/0x27: misc_seq_start at drivers/char/misc.c:70 [ 91.583377] [ 91.583377] but task is already holding lock: [ 91.603418] (&p->lock){+.+.}, at: [] seq_read+0x3a/0x341: seq_read at fs/seq_file.c:178 [ 91.613890] [ 91.613890] which lock already depends on the new lock. [ 91.613890] [ 91.627926] [ 91.627926] the existing dependency chain (in reverse order) is: [ 91.639723] [ 91.639723] -> #3 (&p->lock){+.+.}: [ 91.649763] __mutex_lock+0x83/0x3cc: __mutex_lock_common at kernel/locking/mutex.c:758 (inlined by) __mutex_lock at kernel/locking/mutex.c:893 [ 91.657191] seq_read+0x3a/0x341: seq_read at fs/seq_file.c:178 [ 91.665614] proc_reg_read+0x5a/0x71: proc_reg_read at fs/proc/inode.c:217 [ 91.672805] do_loop_readv_writev+0x65/0x9b: do_loop_readv_writev at fs/read_write.c:673 [ 91.682458] do_iter_read+0x83/0x9e: do_iter_read at fs/read_write.c:899 [ 91.689986] vfs_readv+0x71/0x9f: vfs_readv at fs/read_write.c:960 [ 91.696981] default_file_splice_read+0x20a/0x2a5: set_fs at arch/x86/include/asm/uaccess.h:32 (inlined by) kernel_readv at fs/splice.c:362 (inlined by) default_file_splice_read at fs/splice.c:416 [ 91.706631] splice_direct_to_actor+0xc4/0x198: splice_direct_to_actor at fs/splice.c:953 [ 91.714215] do_splice_direct+0x8e/0xa2: do_splice_direct at fs/splice.c:1062 [ 91.722327] do_sendfile+0x172/0x22e: do_sendfile at fs/read_write.c:1414 [ 91.730159] SyS_sendfile64+0x85/0x94 [ 91.739616] entry_SYSCALL_64_fastpath+0x1f/0x96: entry_SYSCALL_64_fastpath at arch/x86/entry/entry_64.S:206 [ 91.749981] [ 91.749981] -> #2 (sb_writers){.+.+}: [ 91.761322] get_page_from_freelist+0x4e9/0xa18: rmqueue at mm/page_alloc.c:2877 (inlined by) get_page_from_freelist at mm/page_alloc.c:3216 [ 91.771794] [ 91.771794] -> #1 ((completion)&req.done){+.+.}: [ 91.787011] __wait_for_common+0x55/0x1fe: spin_lock_irq at include/linux/spinlock.h:340 (inlined by) __wait_for_common at kernel/sched/completion.c:111 [ 91.796573] devtmpfs_create_node+0x139/0x155: devtmpfs_create_node at drivers/base/devtmpfs.c:117 [ 91.806749] device_add+0x3b3/0x4ec: device_add at drivers/base/core.c:1830 [ 91.815072] device_create_groups_vargs+0x98/0xca: device_create_groups_vargs at drivers/base/core.c:2431 [ 91.825957] device_create_with_groups+0x30/0x33: device_create_with_groups at drivers/base/core.c:2552 [ 91.836527] misc_register+0xb8/0x13f: misc_register at drivers/char/misc.c:221 [ 91.845436] vga_arb_device_init+0x1f/0x219: vga_arb_device_init at drivers/gpu/vga/vgaarb.c:1485 [ 91.855096] do_one_initcall+0x89/0x129: do_one_initcall at init/main.c:826 [ 91.864269] kernel_init_freeable+0x1c0/0x241: do_initcall_level at init/main.c:892 (inlined by) do_initcalls at init/main.c:900 (inlined by) do_basic_setup at init/main.c:918 (inlined by) kernel_init_freeable at init/main.c:1066 [ 91.874443] kernel_init+0xa/0xf5: kernel_init at init/main.c:995 [ 91.882739] ret_from_fork+0x24/0x30: ret_from_fork at arch/x86/entry/entry_64.S:443 [ 91.891353] [ 91.891353] -> #0 (misc_mtx){+.+.}: [ 91.902179] lock_acquire+0x128/0x1ca: get_current at arch/x86/include/asm/current.h:15 (inlined by) lock_acquire at kernel/locking/lockdep.c:4006 [ 91.911192] __mutex_lock+0x83/0x3cc: __mutex_lock_common at kernel/locking/mutex.c:758 (inlined by) __mutex_lock at kernel/locking/mutex.c:893 [ 91.919812] misc_seq_start+0x17/0x27: misc_seq_start at drivers/char/misc.c:70 [ 91.929008] seq_read+0x14d/0x341: seq_read at fs/seq_file.c:229 [ 91.937209] proc_reg_read+0x5a/0x71: proc_reg_read at fs/proc/inode.c:217 [ 91.945795] __vfs_read+0x21/0x9f: __vfs_read at fs/read_write.c:411 [ 91.954124] vfs_read+0xad/0xe2: vfs_read at fs/read_write.c:448 [ 91.962029] SyS_read+0x50/0x7e [ 91.970173] entry_SYSCALL_64_fastpath+0x1f/0x96: entry_SYSCALL_64_fastpath at arch/x86/entry/entry_64.S:206 [ 91.977844] [ 91.977844] other info that might help us debug this: [ 91.977844] [ 91.988643] Chain exists of: [ 91.988643] misc_mtx --> sb_writers --> &p->lock [ 91.988643] [ 92.000927] Possible unsafe locking scenario: [ 92.000927] [ 92.009081] CPU0 CPU1 [ 92.015152] ---- ---- [ 92.021367] lock(&p->lock); [ 92.025806] lock(sb_writers); [ 92.033201] lock(&p->lock); [ 92.044927] lock(misc_mtx); [ 92.052352] [ 92.052352] *** DEADLOCK *** [ 92.052352] [ 92.066900] 1 lock held by trinity/648: [ 92.088647] #0: (&p->lock){+.+.}, at: [] seq_read+0x3a/0x341: seq_read at fs/seq_file.c:178 [ 92.105431] [ 92.105431] stack backtrace: [ 92.117324] CPU: 0 PID: 648 Comm: trinity Not tainted 4.14.0-12891-gc8a0739 #14 [ 92.133653] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 92.151902] Call Trace: [ 92.158542] dump_stack+0x79/0xab: dump_stack at lib/dump_stack.c:55 [ 92.166772] print_circular_bug+0x2a1/0x2af: print_circular_bug at kernel/locking/lockdep.c:1274 [ 92.176535] check_prev_add+0x88/0x217: check_prev_add at kernel/locking/lockdep.c:1914 [ 92.185666] ? look_up_lock_class+0xa1/0x11f: look_up_lock_class at kernel/locking/lockdep.c:726 (discriminator 1) [ 92.195517] ? look_up_lock_class+0x11f/0x11f: save_trace at kernel/locking/lockdep.c:413 [ 92.205657] ? register_lock_class+0x5c/0x302: IS_ERR_OR_NULL at include/linux/err.h:41 (discriminator 13) (inlined by) register_lock_class at kernel/locking/lockdep.c:770 (discriminator 13) [ 92.215608] ? look_up_lock_class+0x11f/0x11f: save_trace at kernel/locking/lockdep.c:413 [ 92.225824] ? __lock_acquire+0xacc/0xd02: check_prevs_add at kernel/locking/lockdep.c:2033 (inlined by) validate_chain at kernel/locking/lockdep.c:2473 (inlined by) __lock_acquire at kernel/locking/lockdep.c:3500 [ 92.235379] __lock_acquire+0xacc/0xd02: check_prevs_add at kernel/locking/lockdep.c:2033 (inlined by) validate_chain at kernel/locking/lockdep.c:2473 (inlined by) __lock_acquire at kernel/locking/lockdep.c:3500 [ 92.244544] ? misc_seq_start+0x17/0x27: misc_seq_start at drivers/char/misc.c:70 [ 92.253918] lock_acquire+0x128/0x1ca: get_current at arch/x86/include/asm/current.h:15 (inlined by) lock_acquire at kernel/locking/lockdep.c:4006 [ 92.262735] ? misc_seq_start+0x17/0x27: misc_seq_start at drivers/char/misc.c:70 [ 92.271938] __mutex_lock+0x83/0x3cc: __mutex_lock_common at kernel/locking/mutex.c:758 (inlined by) __mutex_lock at kernel/locking/mutex.c:893 [ 92.280484] ? misc_seq_start+0x17/0x27: misc_seq_start at drivers/char/misc.c:70 [ 92.289659] ? misc_seq_start+0x17/0x27: misc_seq_start at drivers/char/misc.c:70 [ 92.298867] ? rcu_read_lock_sched_held+0x54/0x5a: rcu_read_lock_sched_held at kernel/rcu/update.c:118 [ 92.309588] ? kmem_cache_alloc_node_trace+0x196/0x237: trace_kmalloc_node at include/trace/events/kmem.h:100 (inlined by) kmem_cache_alloc_node_trace at mm/slab.c:3657 [ 92.321089] ? kvmalloc_node+0x48/0x71: kvmalloc_node at mm/util.c:403 [ 92.330136] ? misc_seq_start+0x17/0x27: misc_seq_start at drivers/char/misc.c:70 [ 92.339271] misc_seq_start+0x17/0x27: misc_seq_start at drivers/char/misc.c:70 [ 92.348154] seq_read+0x14d/0x341: seq_read at fs/seq_file.c:229 [ 92.356313] proc_reg_read+0x5a/0x71: proc_reg_read at fs/proc/inode.c:217 [ 92.365128] __vfs_read+0x21/0x9f: __vfs_read at fs/read_write.c:411 [ 92.373262] vfs_read+0xad/0xe2: vfs_read at fs/read_write.c:448 [ 92.381178] SyS_read+0x50/0x7e [ 92.389117] entry_SYSCALL_64_fastpath+0x1f/0x96: entry_SYSCALL_64_fastpath at arch/x86/entry/entry_64.S:206 [ 92.399616] RIP: 0033:0x454d00 [ 92.407193] RSP: 002b:00007ffe38245f68 EFLAGS: 00000246 ORIG_RAX: 0000000000000000 [ 92.424140] RAX: ffffffffffffffda RBX: 0000000000000046 RCX: 0000000000454d00 [ 92.439443] RDX: 0000000000000400 RSI: 00000000022c0440 RDI: 0000000000000004 [ 92.454869] RBP: 00000000004e2720 R08: 0000000001046108 R09: 0000000000000410 [ 92.470254] R10: 0000000000000020 R11: 0000000000000246 R12: 0000000000000000 [ 92.485615] R13: 00000000004b7c09 R14: 0000000000000001 R15: 00000000004b7c19 [ 148.722159] do_dccp_setsockopt: sockopt(CHANGE_L/R) is deprecated: fix your app [ 148.756602] kernel msg: ebtables bug: please report to author: Wrong len argument [ 148.773493] trinity-main uses obsolete (PF_INET,SOCK_PACKET) Attached the full dmesg, kconfig and reproduce scripts. Thanks, Fengguang