Bluetooth: hci2 command 0x0406 tx timeout Bluetooth: hci4 command 0x0406 tx timeout Bluetooth: hci5 command 0x0406 tx timeout random: crng init done random: 7 urandom warning(s) missed due to ratelimiting INFO: task syz-executor.0:7231 blocked for more than 140 seconds. Not tainted 4.14.212-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.0 D26272 7231 5824 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 schedule+0x7f/0x1b0 kernel/sched/core.c:3428 io_schedule+0x1c/0x70 kernel/sched/core.c:5036 bit_wait_io+0xf/0x90 kernel/sched/wait_bit.c:249 __wait_on_bit_lock+0xcb/0x1c0 kernel/sched/wait_bit.c:89 out_of_line_wait_on_bit_lock+0xbd/0xe0 kernel/sched/wait_bit.c:116 wait_on_bit_lock_io include/linux/wait_bit.h:213 [inline] __lock_buffer+0x3c/0x40 fs/buffer.c:72 lock_buffer include/linux/buffer_head.h:374 [inline] __sync_dirty_buffer+0x16f/0x1d0 fs/buffer.c:3235 sync_dirty_buffer+0xe/0x10 fs/buffer.c:3261 __ext4_handle_dirty_metadata+0x134/0x4c0 fs/ext4/ext4_jbd2.c:300 ext4_convert_inline_data_nolock+0x4b7/0xb70 fs/ext4/inline.c:1246 ext4_convert_inline_data+0x296/0x3ab fs/ext4/inline.c:2001 ext4_fallocate+0xdd/0x1a00 fs/ext4/extents.c:4968 vfs_fallocate+0x2ba/0x770 fs/open.c:319 SYSC_fallocate fs/open.c:342 [inline] SyS_fallocate+0x3f/0x60 fs/open.c:336 do_syscall_64+0x1c7/0x5b0 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x45dd99 RSP: 002b:00007fa542449c78 EFLAGS: 00000246 ORIG_RAX: 000000000000011d RAX: ffffffffffffffda RBX: 0000000000002ec0 RCX: 000000000045dd99 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000003 RBP: 000000000118bf68 R08: 0000000000000000 R09: 0000000000000000 R10: 000000000000ffe0 R11: 0000000000000246 R12: 000000000118bf2c R13: 00007fff5dd360bf R14: 00007fa54244a9c0 R15: 000000000118bf2c INFO: task syz-executor.1:7248 blocked for more than 140 seconds. Not tainted 4.14.212-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D26456 7248 5832 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 schedule+0x7f/0x1b0 kernel/sched/core.c:3428 io_schedule+0x1c/0x70 kernel/sched/core.c:5036 bit_wait_io+0xf/0x90 kernel/sched/wait_bit.c:249 __wait_on_bit_lock+0xcb/0x1c0 kernel/sched/wait_bit.c:89 out_of_line_wait_on_bit_lock+0xbd/0xe0 kernel/sched/wait_bit.c:116 wait_on_bit_lock_io include/linux/wait_bit.h:213 [inline] __lock_buffer+0x3c/0x40 fs/buffer.c:72 lock_buffer include/linux/buffer_head.h:374 [inline] __sync_dirty_buffer+0x16f/0x1d0 fs/buffer.c:3235 sync_dirty_buffer+0xe/0x10 fs/buffer.c:3261 __ext4_handle_dirty_metadata+0x134/0x4c0 fs/ext4/ext4_jbd2.c:300 ext4_convert_inline_data_nolock+0x4b7/0xb70 fs/ext4/inline.c:1246 ext4_convert_inline_data+0x296/0x3ab fs/ext4/inline.c:2001 ext4_fallocate+0xdd/0x1a00 fs/ext4/extents.c:4968 vfs_fallocate+0x2ba/0x770 fs/open.c:319 SYSC_fallocate fs/open.c:342 [inline] SyS_fallocate+0x3f/0x60 fs/open.c:336 do_syscall_64+0x1c7/0x5b0 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x45dd99 RSP: 002b:00007f6694f6cc78 EFLAGS: 00000246 ORIG_RAX: 000000000000011d RAX: ffffffffffffffda RBX: 0000000000002ec0 RCX: 000000000045dd99 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000003 RBP: 000000000118bf68 R08: 0000000000000000 R09: 0000000000000000 R10: 000000000000ffe0 R11: 0000000000000246 R12: 000000000118bf2c R13: 00007ffeb5380d3f R14: 00007f6694f6d9c0 R15: 000000000118bf2c INFO: task syz-executor.2:7363 blocked for more than 140 seconds. Not tainted 4.14.212-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D26456 7363 5829 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 schedule+0x7f/0x1b0 kernel/sched/core.c:3428 io_schedule+0x1c/0x70 kernel/sched/core.c:5036 bit_wait_io+0xf/0x90 kernel/sched/wait_bit.c:249 __wait_on_bit_lock+0xcb/0x1c0 kernel/sched/wait_bit.c:89 out_of_line_wait_on_bit_lock+0xbd/0xe0 kernel/sched/wait_bit.c:116 wait_on_bit_lock_io include/linux/wait_bit.h:213 [inline] __lock_buffer+0x3c/0x40 fs/buffer.c:72 lock_buffer include/linux/buffer_head.h:374 [inline] __sync_dirty_buffer+0x16f/0x1d0 fs/buffer.c:3235 sync_dirty_buffer+0xe/0x10 fs/buffer.c:3261 __ext4_handle_dirty_metadata+0x134/0x4c0 fs/ext4/ext4_jbd2.c:300 ext4_convert_inline_data_nolock+0x4b7/0xb70 fs/ext4/inline.c:1246 ext4_convert_inline_data+0x296/0x3ab fs/ext4/inline.c:2001 ext4_fallocate+0xdd/0x1a00 fs/ext4/extents.c:4968 vfs_fallocate+0x2ba/0x770 fs/open.c:319 SYSC_fallocate fs/open.c:342 [inline] SyS_fallocate+0x3f/0x60 fs/open.c:336 do_syscall_64+0x1c7/0x5b0 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x45dd99 RSP: 002b:00007fcac3c7cc78 EFLAGS: 00000246 ORIG_RAX: 000000000000011d RAX: ffffffffffffffda RBX: 0000000000002ec0 RCX: 000000000045dd99 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000003 RBP: 000000000118bf68 R08: 0000000000000000 R09: 0000000000000000 R10: 000000000000ffe0 R11: 0000000000000246 R12: 000000000118bf2c R13: 00007ffc7fef518f R14: 00007fcac3c7d9c0 R15: 000000000118bf2c INFO: task syz-executor.3:7410 blocked for more than 140 seconds. Not tainted 4.14.212-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.3 D26456 7410 5825 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 schedule+0x7f/0x1b0 kernel/sched/core.c:3428 io_schedule+0x1c/0x70 kernel/sched/core.c:5036 bit_wait_io+0xf/0x90 kernel/sched/wait_bit.c:249 __wait_on_bit_lock+0xcb/0x1c0 kernel/sched/wait_bit.c:89 out_of_line_wait_on_bit_lock+0xbd/0xe0 kernel/sched/wait_bit.c:116 wait_on_bit_lock_io include/linux/wait_bit.h:213 [inline] __lock_buffer+0x3c/0x40 fs/buffer.c:72 lock_buffer include/linux/buffer_head.h:374 [inline] __sync_dirty_buffer+0x16f/0x1d0 fs/buffer.c:3235 sync_dirty_buffer+0xe/0x10 fs/buffer.c:3261 __ext4_handle_dirty_metadata+0x134/0x4c0 fs/ext4/ext4_jbd2.c:300 ext4_convert_inline_data_nolock+0x4b7/0xb70 fs/ext4/inline.c:1246 ext4_convert_inline_data+0x296/0x3ab fs/ext4/inline.c:2001 ext4_fallocate+0xdd/0x1a00 fs/ext4/extents.c:4968 vfs_fallocate+0x2ba/0x770 fs/open.c:319 SYSC_fallocate fs/open.c:342 [inline] SyS_fallocate+0x3f/0x60 fs/open.c:336 do_syscall_64+0x1c7/0x5b0 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x45dd99 RSP: 002b:00007f7760a9dc78 EFLAGS: 00000246 ORIG_RAX: 000000000000011d RAX: ffffffffffffffda RBX: 0000000000002ec0 RCX: 000000000045dd99 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000003 RBP: 000000000118bf68 R08: 0000000000000000 R09: 0000000000000000 R10: 000000000000ffe0 R11: 0000000000000246 R12: 000000000118bf2c R13: 00007ffe26f8f57f R14: 00007f7760a9e9c0 R15: 000000000118bf2c INFO: task syz-executor.5:7426 blocked for more than 140 seconds. Not tainted 4.14.212-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.5 D26456 7426 5831 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 schedule+0x7f/0x1b0 kernel/sched/core.c:3428 io_schedule+0x1c/0x70 kernel/sched/core.c:5036 bit_wait_io+0xf/0x90 kernel/sched/wait_bit.c:249 __wait_on_bit_lock+0xcb/0x1c0 kernel/sched/wait_bit.c:89 out_of_line_wait_on_bit_lock+0xbd/0xe0 kernel/sched/wait_bit.c:116 wait_on_bit_lock_io include/linux/wait_bit.h:213 [inline] __lock_buffer+0x3c/0x40 fs/buffer.c:72 lock_buffer include/linux/buffer_head.h:374 [inline] __sync_dirty_buffer+0x16f/0x1d0 fs/buffer.c:3235 sync_dirty_buffer+0xe/0x10 fs/buffer.c:3261 __ext4_handle_dirty_metadata+0x134/0x4c0 fs/ext4/ext4_jbd2.c:300 ext4_convert_inline_data_nolock+0x4b7/0xb70 fs/ext4/inline.c:1246 ext4_convert_inline_data+0x296/0x3ab fs/ext4/inline.c:2001 ext4_fallocate+0xdd/0x1a00 fs/ext4/extents.c:4968 vfs_fallocate+0x2ba/0x770 fs/open.c:319 SYSC_fallocate fs/open.c:342 [inline] SyS_fallocate+0x3f/0x60 fs/open.c:336 do_syscall_64+0x1c7/0x5b0 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x45dd99 RSP: 002b:00007fcd1197bc78 EFLAGS: 00000246 ORIG_RAX: 000000000000011d RAX: ffffffffffffffda RBX: 0000000000002ec0 RCX: 000000000045dd99 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000003 RBP: 000000000118bf68 R08: 0000000000000000 R09: 0000000000000000 R10: 000000000000ffe0 R11: 0000000000000246 R12: 000000000118bf2c R13: 00007ffc7a73a55f R14: 00007fcd1197c9c0 R15: 000000000118bf2c INFO: task syz-executor.4:7440 blocked for more than 140 seconds. Not tainted 4.14.212-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.4 D26456 7440 5826 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 schedule+0x7f/0x1b0 kernel/sched/core.c:3428 io_schedule+0x1c/0x70 kernel/sched/core.c:5036 bit_wait_io+0xf/0x90 kernel/sched/wait_bit.c:249 __wait_on_bit_lock+0xcb/0x1c0 kernel/sched/wait_bit.c:89 out_of_line_wait_on_bit_lock+0xbd/0xe0 kernel/sched/wait_bit.c:116 wait_on_bit_lock_io include/linux/wait_bit.h:213 [inline] __lock_buffer+0x3c/0x40 fs/buffer.c:72 lock_buffer include/linux/buffer_head.h:374 [inline] __sync_dirty_buffer+0x16f/0x1d0 fs/buffer.c:3235 sync_dirty_buffer+0xe/0x10 fs/buffer.c:3261 __ext4_handle_dirty_metadata+0x134/0x4c0 fs/ext4/ext4_jbd2.c:300 ext4_convert_inline_data_nolock+0x4b7/0xb70 fs/ext4/inline.c:1246 ext4_convert_inline_data+0x296/0x3ab fs/ext4/inline.c:2001 ext4_fallocate+0xdd/0x1a00 fs/ext4/extents.c:4968 vfs_fallocate+0x2ba/0x770 fs/open.c:319 SYSC_fallocate fs/open.c:342 [inline] SyS_fallocate+0x3f/0x60 fs/open.c:336 do_syscall_64+0x1c7/0x5b0 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x45dd99 RSP: 002b:00007f8e74514c78 EFLAGS: 00000246 ORIG_RAX: 000000000000011d RAX: ffffffffffffffda RBX: 0000000000002ec0 RCX: 000000000045dd99 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000003 RBP: 000000000118bf68 R08: 0000000000000000 R09: 0000000000000000 R10: 000000000000ffe0 R11: 0000000000000246 R12: 000000000118bf2c R13: 00007fff067119ff R14: 00007f8e745159c0 R15: 000000000118bf2c Showing all locks held in the system: 1 lock held by khungtaskd/1065: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x79/0x218 kernel/locking/lockdep.c:4548 2 locks held by syz-executor.0/7231: #0: (sb_writers#3){.+.+}, at: [] file_start_write include/linux/fs.h:2712 [inline] #0: (sb_writers#3){.+.+}, at: [] vfs_fallocate+0x42c/0x770 fs/open.c:318 #1: (&ei->xattr_sem){++++}, at: [] ext4_write_lock_xattr fs/ext4/xattr.h:141 [inline] #1: (&ei->xattr_sem){++++}, at: [] ext4_convert_inline_data+0x1d3/0x3ab fs/ext4/inline.c:1999 2 locks held by syz-executor.1/7248: #0: (sb_writers#3){.+.+}, at: [] file_start_write include/linux/fs.h:2712 [inline] #0: (sb_writers#3){.+.+}, at: [] vfs_fallocate+0x42c/0x770 fs/open.c:318 #1: (&ei->xattr_sem){++++}, at: [] ext4_write_lock_xattr fs/ext4/xattr.h:141 [inline] #1: (&ei->xattr_sem){++++}, at: [] ext4_convert_inline_data+0x1d3/0x3ab fs/ext4/inline.c:1999 2 locks held by syz-executor.2/7363: #0: (sb_writers#3){.+.+}, at: [] file_start_write include/linux/fs.h:2712 [inline] #0: (sb_writers#3){.+.+}, at: [] vfs_fallocate+0x42c/0x770 fs/open.c:318 #1: (&ei->xattr_sem){++++}, at: [] ext4_write_lock_xattr fs/ext4/xattr.h:141 [inline] #1: (&ei->xattr_sem){++++}, at: [] ext4_convert_inline_data+0x1d3/0x3ab fs/ext4/inline.c:1999 2 locks held by syz-executor.3/7410: #0: (sb_writers#3){.+.+}, at: [] file_start_write include/linux/fs.h:2712 [inline] #0: (sb_writers#3){.+.+}, at: [] vfs_fallocate+0x42c/0x770 fs/open.c:318 #1: (&ei->xattr_sem){++++}, at: [] ext4_write_lock_xattr fs/ext4/xattr.h:141 [inline] #1: (&ei->xattr_sem){++++}, at: [] ext4_convert_inline_data+0x1d3/0x3ab fs/ext4/inline.c:1999 2 locks held by syz-executor.5/7426: #0: (sb_writers#3){.+.+}, at: [] file_start_write include/linux/fs.h:2712 [inline] #0: (sb_writers#3){.+.+}, at: [] vfs_fallocate+0x42c/0x770 fs/open.c:318 #1: (&ei->xattr_sem){++++}, at: [] ext4_write_lock_xattr fs/ext4/xattr.h:141 [inline] #1: (&ei->xattr_sem){++++}, at: [] ext4_convert_inline_data+0x1d3/0x3ab fs/ext4/inline.c:1999 2 locks held by syz-executor.4/7440: #0: (sb_writers#3){.+.+}, at: [] file_start_write include/linux/fs.h:2712 [inline] #0: (sb_writers#3){.+.+}, at: [] vfs_fallocate+0x42c/0x770 fs/open.c:318 #1: (&ei->xattr_sem){++++}, at: [] ext4_write_lock_xattr fs/ext4/xattr.h:141 [inline] #1: (&ei->xattr_sem){++++}, at: [] ext4_convert_inline_data+0x1d3/0x3ab fs/ext4/inline.c:1999 ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1065 Comm: khungtaskd Not tainted 4.14.212-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+0x14b/0x1f1 lib/dump_stack.c:58 nmi_cpu_backtrace.cold.0+0x3c/0x78 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0xf5/0x119 lib/nmi_backtrace.c:62 arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38 trigger_all_cpu_backtrace include/linux/nmi.h:140 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:195 [inline] watchdog+0x51b/0xa70 kernel/hung_task.c:274 kthread+0x338/0x400 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 Sending NMI from CPU 0 to CPUs 1: NMI backtrace for cpu 1 CPU: 1 PID: 5630 Comm: kworker/u4:7 Not tainted 4.14.212-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Workqueue: writeback wb_workfn (flush-7:3) task: ffff8881d3d80180 task.stack: ffff8881d3f20000 RIP: 0010:lock_acquire+0x150/0x3e0 kernel/locking/lockdep.c:3998 RSP: 0018:ffff8881d3f27908 EFLAGS: 00000082 RAX: 0000000000000000 RBX: ffff8881d3d80180 RCX: 0000000000000000 RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffff8881d3d80a04 RBP: ffff8881d3f27958 R08: 0000000000000001 R09: 0000000000000286 R10: 0000000000000078 R11: ffff8881d3d80180 R12: 0000000000000000 R13: 0000000000000000 R14: 0000000000000001 R15: 0000000000000000 FS: 0000000000000000(0000) GS:ffff8881f6700000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007febac4cf000 CR3: 0000000007e6a006 CR4: 00000000001606e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2d/0x40 kernel/locking/spinlock.c:152 spin_lock include/linux/spinlock.h:317 [inline] writeback_sb_inodes+0x566/0xef0 fs/fs-writeback.c:1667 __writeback_inodes_wb+0xec/0x1e0 fs/fs-writeback.c:1712 wb_writeback+0x5e3/0x9f0 fs/fs-writeback.c:1818 wb_check_old_data_flush fs/fs-writeback.c:1931 [inline] wb_do_writeback fs/fs-writeback.c:1955 [inline] wb_workfn+0x7f1/0xdd0 fs/fs-writeback.c:1984 process_one_work+0x74f/0x1620 kernel/workqueue.c:2116 worker_thread+0xcc/0xed0 kernel/workqueue.c:2250 kthread+0x338/0x400 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 Code: 00 00 65 8b 05 92 60 ba 7e 83 f8 3f 0f 87 fe 01 00 00 89 c0 48 0f a3 05 a7 ce 6b 07 0f 82 b1 01 00 00 4c 8b 4d c0 6a 00 44 89 e2 <45> 89 f0 6a 00 44 89 e9 ff 75 10 41 57 48 8b 7d d0 8b 75 cc 49