Bluetooth: hci5 command 0x0406 tx timeout Bluetooth: hci4 command 0x0406 tx timeout Bluetooth: hci0 command 0x0406 tx timeout random: crng init done random: 7 urandom warning(s) missed due to ratelimiting INFO: task syz-executor.1:7229 blocked for more than 140 seconds. Not tainted 4.14.217-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D26272 7229 5818 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:5032 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/0x3b0 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:00007f3036874c78 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: 00007ffd5a3af7bf R14: 00007f30368759c0 R15: 000000000118bf2c INFO: task syz-executor.0:7281 blocked for more than 140 seconds. Not tainted 4.14.217-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.0 D26456 7281 5820 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:5032 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/0x3b0 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:00007fe549b12c78 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: 00007ffd4e77946f R14: 00007fe549b139c0 R15: 000000000118bf2c INFO: task syz-executor.3:7311 blocked for more than 140 seconds. Not tainted 4.14.217-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.3 D26456 7311 5811 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:5032 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/0x3b0 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:00007f35cd088c78 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: 00007ffd7a89191f R14: 00007f35cd0899c0 R15: 000000000118bf2c INFO: task syz-executor.2:7392 blocked for more than 140 seconds. Not tainted 4.14.217-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D26456 7392 5813 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:5032 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/0x3b0 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:00007fde55961c78 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: 00007fffb4b86eaf R14: 00007fde559629c0 R15: 000000000118bf2c INFO: task syz-executor.4:7420 blocked for more than 140 seconds. Not tainted 4.14.217-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.4 D26456 7420 5819 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:5032 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/0x3b0 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:00007f592eaf8c78 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: 00007ffd3c45d06f R14: 00007f592eaf99c0 R15: 000000000118bf2c INFO: task syz-executor.5:7429 blocked for more than 140 seconds. Not tainted 4.14.217-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.5 D26456 7429 5821 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:5032 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/0x3b0 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:00007f296800cc78 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: 00007ffebedbb2df R14: 00007f296800d9c0 R15: 000000000118bf2c Showing all locks held in the system: 1 lock held by khungtaskd/1068: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x79/0x218 kernel/locking/lockdep.c:4548 2 locks held by syz-executor.1/7229: #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/0x3b0 fs/ext4/inline.c:1999 2 locks held by syz-executor.0/7281: #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/0x3b0 fs/ext4/inline.c:1999 2 locks held by syz-executor.3/7311: #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/0x3b0 fs/ext4/inline.c:1999 2 locks held by syz-executor.2/7392: #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/0x3b0 fs/ext4/inline.c:1999 2 locks held by syz-executor.4/7420: #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/0x3b0 fs/ext4/inline.c:1999 2 locks held by syz-executor.5/7429: #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/0x3b0 fs/ext4/inline.c:1999 ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1068 Comm: khungtaskd Not tainted 4.14.217-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/0x1e7 lib/dump_stack.c:58 nmi_cpu_backtrace.cold.0+0x3c/0x78 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0xf5/0x120 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: 8 Comm: rcu_preempt Not tainted 4.14.217-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 task: ffff8881f5388200 task.stack: ffff8881f5390000 RIP: 0010:lookup_chain_cache kernel/locking/lockdep.c:2361 [inline] RIP: 0010:lookup_chain_cache_add kernel/locking/lockdep.c:2381 [inline] RIP: 0010:validate_chain kernel/locking/lockdep.c:2435 [inline] RIP: 0010:__lock_acquire+0xbc3/0x42d0 kernel/locking/lockdep.c:3491 RSP: 0018:ffff8881f5397980 EFLAGS: 00000046 RAX: dffffc0000000000 RBX: ffffffff88f32f28 RCX: 1ffff1103ea71155 RDX: 1ffffffff12a80f8 RSI: ffff8881f5388a88 RDI: ffffffff895809c0 RBP: ffff8881f5397b48 R08: 0000000000000ff8 R09: ffffffff895407c0 R10: 0000000000000000 R11: ffff8881f5388200 R12: 00000000725172ed R13: 000000007e7cfc77 R14: a8b925c7f0ce6f64 R15: ffff8881f5388a88 FS: 0000000000000000(0000) GS:ffff8881f6700000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f1c7b1b8000 CR3: 0000000007e6a001 CR4: 00000000001606e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: lock_acquire+0x17e/0x3e0 kernel/locking/lockdep.c:3998 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline] _raw_spin_lock_irqsave+0x99/0xd0 kernel/locking/spinlock.c:160 free_object+0x20/0x250 lib/debugobjects.c:223 debug_object_free+0x235/0x390 lib/debugobjects.c:614 destroy_timer_on_stack kernel/time/timer.c:724 [inline] schedule_timeout+0x441/0xcc0 kernel/time/timer.c:1751 rcu_gp_kthread+0xb69/0x1df0 kernel/rcu/tree.c:2255 kthread+0x338/0x400 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 Code: ff df 4d 0f af c6 49 c1 e8 31 4e 8d 0c c5 00 88 53 89 4c 89 ca 48 c1 ea 03 80 3c 02 00 0f 85 1a 2a 00 00 4a 8b 1c c5 00 88 53 89 <48> 85 db 0f 84 a6 00 00 00 48 83 fb 08 0f 84 9c 00 00 00 48 8d