NOHZ: local_softirq_pending 08 Bluetooth: hci0 command 0x0406 tx timeout NOHZ: local_softirq_pending 08 NOHZ: local_softirq_pending 08 NOHZ: local_softirq_pending 08 INFO: task kworker/u5:3:7758 blocked for more than 140 seconds. Not tainted 4.14.192-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u5:3 D28600 7758 2 0x80000000 Workqueue: hci5 hci_rx_work Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3384 schedule+0x8d/0x1b0 kernel/sched/core.c:3428 schedule_timeout+0x80a/0xe90 kernel/time/timer.c:1723 do_wait_for_common kernel/sched/completion.c:91 [inline] __wait_for_common kernel/sched/completion.c:112 [inline] wait_for_common+0x272/0x430 kernel/sched/completion.c:123 __synchronize_srcu+0x10a/0x1d0 kernel/rcu/srcutree.c:898 debugfs_remove_recursive fs/debugfs/inode.c:744 [inline] debugfs_remove_recursive+0x2e0/0x3b0 fs/debugfs/inode.c:686 hci_conn_cleanup+0x2d8/0x550 net/bluetooth/hci_conn.c:130 hci_conn_del+0x235/0x620 net/bluetooth/hci_conn.c:611 hci_phy_link_complete_evt.isra.0+0x4d0/0x6c0 net/bluetooth/hci_event.c:4355 hci_event_packet+0x2592/0x7c7a net/bluetooth/hci_event.c:5429 hci_rx_work+0x3e6/0x970 net/bluetooth/hci_core.c:4244 process_one_work+0x793/0x14a0 kernel/workqueue.c:2116 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2250 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 INFO: task syz-executor.3:8521 blocked for more than 140 seconds. Not tainted 4.14.192-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.3 D28848 8521 6388 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3384 schedule+0x8d/0x1b0 kernel/sched/core.c:3428 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 get_l2cap_conn+0xa3/0x430 net/bluetooth/6lowpan.c:1013 lowpan_control_write+0x135/0x490 net/bluetooth/6lowpan.c:1138 full_proxy_write+0xfb/0x1a0 fs/debugfs/file.c:163 __vfs_write+0xe4/0x630 fs/read_write.c:480 vfs_write+0x17f/0x4d0 fs/read_write.c:544 SYSC_write fs/read_write.c:590 [inline] SyS_write+0xf2/0x210 fs/read_write.c:582 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x45ccd9 RSP: 002b:00007ffdb19a9028 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 0000000000035900 RCX: 000000000045ccd9 RDX: 000000000000001b RSI: 00000000200000c0 RDI: 0000000000000003 RBP: 000000000078bf40 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000007901a8 R13: 0000000000000000 R14: 0000000000000d64 R15: 000000000078bf0c INFO: task syz-executor.4:8522 blocked for more than 140 seconds. Not tainted 4.14.192-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.4 D28912 8522 6390 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3384 schedule+0x8d/0x1b0 kernel/sched/core.c:3428 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 get_l2cap_conn+0xa3/0x430 net/bluetooth/6lowpan.c:1013 lowpan_control_write+0x135/0x490 net/bluetooth/6lowpan.c:1138 full_proxy_write+0xfb/0x1a0 fs/debugfs/file.c:163 __vfs_write+0xe4/0x630 fs/read_write.c:480 vfs_write+0x17f/0x4d0 fs/read_write.c:544 SYSC_write fs/read_write.c:590 [inline] SyS_write+0xf2/0x210 fs/read_write.c:582 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x45ccd9 RSP: 002b:00007ffe5ba07708 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 0000000000035900 RCX: 000000000045ccd9 RDX: 000000000000001b RSI: 00000000200000c0 RDI: 0000000000000003 RBP: 000000000078bf40 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000007901a8 R13: 0000000000000000 R14: 0000000000000d64 R15: 000000000078bf0c INFO: task syz-executor.5:8523 blocked for more than 140 seconds. Not tainted 4.14.192-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.5 D28912 8523 6392 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3384 schedule+0x8d/0x1b0 kernel/sched/core.c:3428 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 get_l2cap_conn+0xa3/0x430 net/bluetooth/6lowpan.c:1013 lowpan_control_write+0x135/0x490 net/bluetooth/6lowpan.c:1138 full_proxy_write+0xfb/0x1a0 fs/debugfs/file.c:163 __vfs_write+0xe4/0x630 fs/read_write.c:480 vfs_write+0x17f/0x4d0 fs/read_write.c:544 SYSC_write fs/read_write.c:590 [inline] SyS_write+0xf2/0x210 fs/read_write.c:582 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x45ccd9 RSP: 002b:00007ffc072fad68 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 0000000000035900 RCX: 000000000045ccd9 RDX: 000000000000001b RSI: 00000000200000c0 RDI: 0000000000000003 RBP: 000000000078bf40 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000007901a8 R13: 0000000000000000 R14: 0000000000000d64 R15: 000000000078bf0c INFO: task syz-executor.1:8525 blocked for more than 140 seconds. Not tainted 4.14.192-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D28912 8525 6395 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3384 schedule+0x8d/0x1b0 kernel/sched/core.c:3428 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 get_l2cap_conn+0xa3/0x430 net/bluetooth/6lowpan.c:1013 lowpan_control_write+0x135/0x490 net/bluetooth/6lowpan.c:1138 full_proxy_write+0xfb/0x1a0 fs/debugfs/file.c:163 __vfs_write+0xe4/0x630 fs/read_write.c:480 vfs_write+0x17f/0x4d0 fs/read_write.c:544 SYSC_write fs/read_write.c:590 [inline] SyS_write+0xf2/0x210 fs/read_write.c:582 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x45ccd9 RSP: 002b:00007fff8dd2bbe8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 0000000000035900 RCX: 000000000045ccd9 RDX: 000000000000001b RSI: 00000000200000c0 RDI: 0000000000000003 RBP: 000000000078bf40 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000007901a8 R13: 0000000000000000 R14: 0000000000000d64 R15: 000000000078bf0c INFO: task syz-executor.0:8526 blocked for more than 140 seconds. Not tainted 4.14.192-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.0 D28912 8526 6394 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3384 schedule+0x8d/0x1b0 kernel/sched/core.c:3428 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 get_l2cap_conn+0xa3/0x430 net/bluetooth/6lowpan.c:1013 lowpan_control_write+0x135/0x490 net/bluetooth/6lowpan.c:1138 full_proxy_write+0xfb/0x1a0 fs/debugfs/file.c:163 __vfs_write+0xe4/0x630 fs/read_write.c:480 vfs_write+0x17f/0x4d0 fs/read_write.c:544 SYSC_write fs/read_write.c:590 [inline] SyS_write+0xf2/0x210 fs/read_write.c:582 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x45ccd9 RSP: 002b:00007ffe85d0bd48 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 0000000000035900 RCX: 000000000045ccd9 RDX: 000000000000001b RSI: 00000000200000c0 RDI: 0000000000000003 RBP: 000000000078bf40 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000007901a8 R13: 0000000000000000 R14: 0000000000000d64 R15: 000000000078bf0c INFO: task syz-executor.2:8527 blocked for more than 140 seconds. Not tainted 4.14.192-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D28912 8527 6385 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3384 schedule+0x8d/0x1b0 kernel/sched/core.c:3428 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 get_l2cap_conn+0xa3/0x430 net/bluetooth/6lowpan.c:1013 lowpan_control_write+0x135/0x490 net/bluetooth/6lowpan.c:1138 full_proxy_write+0xfb/0x1a0 fs/debugfs/file.c:163 __vfs_write+0xe4/0x630 fs/read_write.c:480 vfs_write+0x17f/0x4d0 fs/read_write.c:544 SYSC_write fs/read_write.c:590 [inline] SyS_write+0xf2/0x210 fs/read_write.c:582 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x45ccd9 RSP: 002b:00007ffc892c6108 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 0000000000035900 RCX: 000000000045ccd9 RDX: 000000000000001b RSI: 00000000200000c0 RDI: 0000000000000003 RBP: 000000000078bf40 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000007901a8 R13: 0000000000000000 R14: 0000000000000d64 R15: 000000000078bf0c Showing all locks held in the system: 1 lock held by khungtaskd/1068: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548 3 locks held by kworker/u5:3/7758: #0: ("%s"hdev->name#2){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2087 #1: ((&hdev->rx_work)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2091 #2: (&hdev->lock){+.+.}, at: [] hci_phy_link_complete_evt.isra.0+0x27/0x6c0 net/bluetooth/hci_event.c:4346 3 locks held by syz-executor.3/8521: #0: (sb_writers#13){.+.+}, at: [] file_start_write include/linux/fs.h:2708 [inline] #0: (sb_writers#13){.+.+}, at: [] vfs_write+0x3d8/0x4d0 fs/read_write.c:543 #1: (debugfs_srcu){....}, at: [] debugfs_real_fops include/linux/debugfs.h:62 [inline] #1: (debugfs_srcu){....}, at: [] full_proxy_write+0x65/0x1a0 fs/debugfs/file.c:163 #2: (&hdev->lock){+.+.}, at: [] get_l2cap_conn+0xa3/0x430 net/bluetooth/6lowpan.c:1013 3 locks held by syz-executor.4/8522: #0: (sb_writers#13){.+.+}, at: [] file_start_write include/linux/fs.h:2708 [inline] #0: (sb_writers#13){.+.+}, at: [] vfs_write+0x3d8/0x4d0 fs/read_write.c:543 #1: (debugfs_srcu){....}, at: [] debugfs_real_fops include/linux/debugfs.h:62 [inline] #1: (debugfs_srcu){....}, at: [] full_proxy_write+0x65/0x1a0 fs/debugfs/file.c:163 #2: (&hdev->lock){+.+.}, at: [] get_l2cap_conn+0xa3/0x430 net/bluetooth/6lowpan.c:1013 3 locks held by syz-executor.5/8523: #0: (sb_writers#13){.+.+}, at: [] file_start_write include/linux/fs.h:2708 [inline] #0: (sb_writers#13){.+.+}, at: [] vfs_write+0x3d8/0x4d0 fs/read_write.c:543 #1: (debugfs_srcu){....}, at: [] debugfs_real_fops include/linux/debugfs.h:62 [inline] #1: (debugfs_srcu){....}, at: [] full_proxy_write+0x65/0x1a0 fs/debugfs/file.c:163 #2: (&hdev->lock){+.+.}, at: [] get_l2cap_conn+0xa3/0x430 net/bluetooth/6lowpan.c:1013 3 locks held by syz-executor.1/8525: #0: (sb_writers#13){.+.+}, at: [] file_start_write include/linux/fs.h:2708 [inline] #0: (sb_writers#13){.+.+}, at: [] vfs_write+0x3d8/0x4d0 fs/read_write.c:543 #1: (debugfs_srcu){....}, at: [] debugfs_real_fops include/linux/debugfs.h:62 [inline] #1: (debugfs_srcu){....}, at: [] full_proxy_write+0x65/0x1a0 fs/debugfs/file.c:163 #2: (&hdev->lock){+.+.}, at: [] get_l2cap_conn+0xa3/0x430 net/bluetooth/6lowpan.c:1013 3 locks held by syz-executor.0/8526: #0: (sb_writers#13){.+.+}, at: [] file_start_write include/linux/fs.h:2708 [inline] #0: (sb_writers#13){.+.+}, at: [] vfs_write+0x3d8/0x4d0 fs/read_write.c:543 #1: (debugfs_srcu){....}, at: [] debugfs_real_fops include/linux/debugfs.h:62 [inline] #1: (debugfs_srcu){....}, at: [] full_proxy_write+0x65/0x1a0 fs/debugfs/file.c:163 #2: (&hdev->lock){+.+.}, at: [] get_l2cap_conn+0xa3/0x430 net/bluetooth/6lowpan.c:1013 3 locks held by syz-executor.2/8527: #0: (sb_writers#13){.+.+}, at: [] file_start_write include/linux/fs.h:2708 [inline] #0: (sb_writers#13){.+.+}, at: [] vfs_write+0x3d8/0x4d0 fs/read_write.c:543 #1: (debugfs_srcu){....}, at: [] debugfs_real_fops include/linux/debugfs.h:62 [inline] #1: (debugfs_srcu){....}, at: [] full_proxy_write+0x65/0x1a0 fs/debugfs/file.c:163 #2: (&hdev->lock){+.+.}, at: [] get_l2cap_conn+0xa3/0x430 net/bluetooth/6lowpan.c:1013 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1068 Comm: khungtaskd Not tainted 4.14.192-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x283 lib/dump_stack.c:58 nmi_cpu_backtrace.cold+0x57/0x93 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x13a/0x17f lib/nmi_backtrace.c:62 trigger_all_cpu_backtrace include/linux/nmi.h:140 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:195 [inline] watchdog+0x5b9/0xb40 kernel/hung_task.c:274 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 Sending NMI from CPU 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 3645 Comm: systemd-journal Not tainted 4.14.192-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 task: ffff88809447e180 task.stack: ffff888094480000 RIP: 0010:do_syscall_64+0x3fd/0x640 arch/x86/entry/common.c:298 RSP: 0018:ffff888094487f00 EFLAGS: 000000d7 RAX: ffff88809447e180 RBX: ffff88809447e180 RCX: 0000000000000000 RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffff88809447e188 RBP: ffff888094487f58 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000100 R13: ffff888094487fd0 R14: 1ffffffff0fa2d20 R15: 0000000000000000 FS: 00007f9ca38778c0(0000) GS:ffff8880aea00000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f9ca0c7c000 CR3: 0000000094af7000 CR4: 00000000001406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f9ca2e07840 RSP: 002b:00007ffebaf83468 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 RAX: fffffffffffffffe RBX: 00007ffebaf83770 RCX: 00007f9ca2e07840 RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 0000564ea30a65e0 RBP: 000000000000000d R08: 0000000000000000 R09: 00000000ffffffff R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff R13: 0000564ea309b060 R14: 00007ffebaf83730 R15: 0000564ea30a9200 Code: 89 fa 48 c1 ea 03 0f b6 04 02 84 c0 74 08 3c 03 0f 8e c0 00 00 00 83 63 08 f9 0f 1f 44 00 00 e8 2a 25 5b 00 0f 00 2d b3 e4 9f 05 <48> 83 c4 28 5b 5d 41 5c 41 5d 41 5e e9 12 25 5b 00 e8 0d 25 5b