Bluetooth: hci4 command 0x0406 tx timeout Bluetooth: hci0 command 0x0406 tx timeout Bluetooth: hci5 command 0x0406 tx timeout Bluetooth: hci3 command 0x0406 tx timeout Bluetooth: hci2 command 0x0406 tx timeout INFO: task syz-executor.4:9335 blocked for more than 140 seconds. Not tainted 4.14.275-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.4 D27800 9335 7971 0x00000004 Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 schedule+0x8d/0x1b0 kernel/sched/core.c:3431 schedule_timeout+0x80a/0xe90 kernel/time/timer.c:1724 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 usb_start_wait_urb+0x125/0x440 drivers/usb/core/message.c:61 usb_bulk_msg+0x1f6/0x500 drivers/usb/core/message.c:252 proc_bulk+0x331/0x6d0 drivers/usb/core/devio.c:1223 usbdev_do_ioctl+0x5b0/0x2b70 drivers/usb/core/devio.c:2449 usbdev_ioctl+0x21/0x30 drivers/usb/core/devio.c:2599 vfs_ioctl fs/ioctl.c:46 [inline] file_ioctl fs/ioctl.c:500 [inline] do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684 SYSC_ioctl fs/ioctl.c:701 [inline] SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f7518891049 RSP: 002b:00007f7517206168 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007f75189a3f60 RCX: 00007f7518891049 RDX: 0000000020000180 RSI: 00000000c0185502 RDI: 0000000000000003 RBP: 00007f75188eb08d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffd260b6cef R14: 00007f7517206300 R15: 0000000000022000 Showing all locks held in the system: 1 lock held by khungtaskd/1532: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548 2 locks held by in:imklog/7683: #0: (&f->f_pos_lock){+.+.}, at: [] __fdget_pos+0x1fb/0x2b0 fs/file.c:819 #1: (rcu_read_lock){....}, at: [] is_bpf_text_address+0x0/0x150 kernel/bpf/core.c:487 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1532 Comm: khungtaskd Not tainted 4.14.275-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/0x281 lib/dump_stack.c:58 nmi_cpu_backtrace.cold+0x57/0x93 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x13a/0x180 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: 4612 Comm: systemd-journal Not tainted 4.14.275-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 task: ffff8880a1fb6540 task.stack: ffff8880a1fb8000 RIP: 0010:__lock_is_held+0x2d/0x160 kernel/locking/lockdep.c:3811 RSP: 0018:ffff8880a1fbfdb8 EFLAGS: 00000806 RAX: dffffc0000000000 RBX: 0000000000000286 RCX: 0000000000000001 RDX: 1ffff110143f6db8 RSI: 00000000ffffffff RDI: ffff8880a1fb6dc0 RBP: ffff8880a2c990d8 R08: ffffffff8b9c8dd8 R09: 00000000000400a1 R10: ffff8880a1fb6dc8 R11: ffff8880a1fb6540 R12: ffff8880a1fb6540 R13: ffff8880a2c99000 R14: ffff8880a2c990d8 R15: ffff8880a1fb6540 FS: 00007fc7d6ae88c0(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007fc7d3ef8000 CR3: 00000000a1835000 CR4: 00000000003406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: lock_is_held_type+0x10d/0x210 kernel/locking/lockdep.c:4036 lock_is_held include/linux/lockdep.h:437 [inline] __put_unused_fd+0x100/0x170 fs/file.c:556 put_unused_fd+0x55/0x70 fs/file.c:566 do_sys_open+0x3e3/0x410 fs/open.c:1083 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7fc7d6077840 RSP: 002b:00007ffc00f7d7a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 RAX: ffffffffffffffda RBX: 00007ffc00f7dab0 RCX: 00007fc7d6077840 RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 0000558e5c56c310 RBP: 000000000000000d R08: 0000000000000000 R09: 00000000ffffffff R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff R13: 0000558e5c560040 R14: 00007ffc00f7da70 R15: 0000558e5c56c090 Code: 00 00 00 00 00 fc ff df 41 57 65 4c 8b 3c 25 c0 7f 02 00 41 56 49 89 fe 49 8d bf 80 08 00 00 41 55 48 89 fa 41 54 48 c1 ea 03 55 <53> 48 83 ec 08 0f b6 04 02 89 74 24 04 84 c0 74 08 3c 03 0f 8e ---------------- Code disassembly (best guess), 7 bytes skipped: 0: df 41 57 filds 0x57(%rcx) 3: 65 4c 8b 3c 25 c0 7f mov %gs:0x27fc0,%r15 a: 02 00 c: 41 56 push %r14 e: 49 89 fe mov %rdi,%r14 11: 49 8d bf 80 08 00 00 lea 0x880(%r15),%rdi 18: 41 55 push %r13 1a: 48 89 fa mov %rdi,%rdx 1d: 41 54 push %r12 1f: 48 c1 ea 03 shr $0x3,%rdx 23: 55 push %rbp * 24: 53 push %rbx <-- trapping instruction 25: 48 83 ec 08 sub $0x8,%rsp 29: 0f b6 04 02 movzbl (%rdx,%rax,1),%eax 2d: 89 74 24 04 mov %esi,0x4(%rsp) 31: 84 c0 test %al,%al 33: 74 08 je 0x3d 35: 3c 03 cmp $0x3,%al 37: 0f .byte 0xf 38: 8e .byte 0x8e