Bluetooth: hci4 command 0x0406 tx timeout
Bluetooth: hci5 command 0x0406 tx timeout
Bluetooth: hci0 command 0x0406 tx timeout
Bluetooth: hci3 command 0x0406 tx timeout
Bluetooth: hci2 command 0x0406 tx timeout
INFO: task syz-executor.3:9972 blocked for more than 140 seconds.
Not tainted 4.14.277-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3 D28912 9972 8010 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
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:588 [inline]
rwsem_down_write_failed+0x343/0x6d0 kernel/locking/rwsem-xadd.c:617
call_rwsem_down_write_failed+0x13/0x20 arch/x86/lib/rwsem.S:105
__down_write arch/x86/include/asm/rwsem.h:126 [inline]
down_write+0x4f/0x90 kernel/locking/rwsem.c:56
inode_lock include/linux/fs.h:719 [inline]
fuse_flush+0x1c7/0x520 fs/fuse/file.c:423
filp_close+0x9d/0x140 fs/open.c:1158
__close_fd+0x151/0x230 fs/file.c:639
SYSC_close fs/open.c:1177 [inline]
SyS_close+0x42/0x90 fs/open.c:1175
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7f0f50c0fd2b
RSP: 002b:00007fff6e4994c0 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007f0f50c0fd2b
RDX: 0000001b30120000 RSI: 0000001b30125aac RDI: 0000000000000004
RBP: 00007f0f50d71960 R08: 0000000000000000 R09: 000000004770aae7
R10: 001dbe244caa3dad R11: 0000000000000293 R12: 0000000000021b0d
R13: 00007fff6e4995c0 R14: 00007f0f50d70030 R15: 0000000000000032
INFO: task syz-executor.3:9977 blocked for more than 140 seconds.
Not tainted 4.14.277-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3 D28536 9977 8010 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
fuse_wait_on_page_writeback fs/fuse/file.c:385 [inline]
fuse_wait_on_page_writeback.isra.0+0xdc/0x120 fs/fuse/file.c:381
fuse_launder_page fs/fuse/file.c:2042 [inline]
fuse_launder_page+0xa2/0xd0 fs/fuse/file.c:2035
do_launder_page mm/truncate.c:609 [inline]
invalidate_inode_pages2_range+0x547/0xc00 mm/truncate.c:685
fuse_finish_open+0x262/0x500 fs/fuse/file.c:181
fuse_open_common+0x234/0x290 fs/fuse/file.c:224
do_dentry_open+0x44b/0xec0 fs/open.c:777
vfs_open+0x105/0x220 fs/open.c:888
do_last fs/namei.c:3428 [inline]
path_openat+0x628/0x2970 fs/namei.c:3569
do_filp_open+0x179/0x3c0 fs/namei.c:3603
do_sys_open+0x296/0x410 fs/open.c:1081
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7f0f50c5d0e9
RSP: 002b:00007f0f4f5d2168 EFLAGS: 00000246 ORIG_RAX: 0000000000000055
RAX: ffffffffffffffda RBX: 00007f0f50d6ff60 RCX: 00007f0f50c5d0e9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00000000200001c0
RBP: 00007f0f50cb708d R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fff6e49945f R14: 00007f0f4f5d2300 R15: 0000000000022000
Showing all locks held in the system:
1 lock held by khungtaskd/1522:
#0: (tasklist_lock){.+.+}, at: [<ffffffff8702740c>] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548
1 lock held by in:imklog/7687:
#0: (&f->f_pos_lock){+.+.}, at: [<ffffffff818d6c6b>] __fdget_pos+0x1fb/0x2b0 fs/file.c:819
1 lock held by syz-executor.3/9972:
#0: (&sb->s_type->i_mutex_key#21){+.+.}, at: [<ffffffff822f30f7>] inode_lock include/linux/fs.h:719 [inline]
#0: (&sb->s_type->i_mutex_key#21){+.+.}, at: [<ffffffff822f30f7>] fuse_flush+0x1c7/0x520 fs/fuse/file.c:423
2 locks held by syz-executor.3/9977:
#0: (sb_writers#13){.+.+}, at: [<ffffffff818dfcda>] sb_start_write include/linux/fs.h:1551 [inline]
#0: (sb_writers#13){.+.+}, at: [<ffffffff818dfcda>] mnt_want_write+0x3a/0xb0 fs/namespace.c:386
#1: (&sb->s_type->i_mutex_key#21){+.+.}, at: [<ffffffff822f2d04>] inode_lock include/linux/fs.h:719 [inline]
#1: (&sb->s_type->i_mutex_key#21){+.+.}, at: [<ffffffff822f2d04>] fuse_open_common+0x1a4/0x290 fs/fuse/file.c:217
=============================================
NMI backtrace for cpu 1
CPU: 1 PID: 1522 Comm: khungtaskd Not tainted 4.14.277-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: 4621 Comm: systemd-journal Not tainted 4.14.277-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
task: ffff8880a1740080 task.stack: ffff8880a1748000
RIP: 0010:preempt_latency_start kernel/sched/core.c:3090 [inline]
RIP: 0010:preempt_count_add+0xd8/0x170 kernel/sched/core.c:3113
RSP: 0018:ffff8880a174fa48 EFLAGS: 00000246
RAX: dffffc0000000000 RBX: ffffffff81237042 RCX: 0000000000000000
RDX: 1ffff110142e8257 RSI: ffff8880a174fa10 RDI: ffff8880a17412b8
RBP: ffff8880a1740080 R08: 0000000000000001 R09: 0000000000000001
R10: ffff8880a174ff58 R11: 0000000000000001 R12: ffff8880a174fba8
R13: ffff8880a174fb58 R14: ffff88823f8bb200 R15: ffff8880a174fb20
FS: 00007f375fff38c0(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f375d409000 CR3: 00000000a164d000 CR4: 00000000003406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
unwind_next_frame+0xc2/0x17d0 arch/x86/kernel/unwind_orc.c:333
__save_stack_trace+0x90/0x160 arch/x86/kernel/stacktrace.c:44
save_stack mm/kasan/kasan.c:447 [inline]
set_track mm/kasan/kasan.c:459 [inline]
kasan_kmalloc+0xeb/0x160 mm/kasan/kasan.c:551
slab_post_alloc_hook mm/slab.h:442 [inline]
slab_alloc mm/slab.c:3390 [inline]
kmem_cache_alloc+0x111/0x3c0 mm/slab.c:3550
getname_flags+0xc8/0x550 fs/namei.c:138
getname fs/namei.c:209 [inline]
user_path_create fs/namei.c:3732 [inline]
SYSC_mkdirat fs/namei.c:3864 [inline]
SyS_mkdirat+0x83/0x270 fs/namei.c:3856
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7f375f2ae687
RSP: 002b:00007ffd5c95b188 EFLAGS: 00000293 ORIG_RAX: 0000000000000053
RAX: ffffffffffffffda RBX: 00007ffd5c95e0a0 RCX: 00007f375f2ae687
RDX: 00007f375fd1fa00 RSI: 00000000000001ed RDI: 000055bc5e1be8a0
RBP: 00007ffd5c95b1c0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000069 R11: 0000000000000293 R12: 0000000000000000
R13: 0000000000000000 R14: 00007ffd5c95e0a0 R15: 00007ffd5c95b6b0
Code: 08 00 85 c0 75 31 65 48 8b 2c 25 c0 7f 02 00 48 8d bd 38 12 00 00 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 <75> 79 48 89 9d 38 12 00 00 5b 5d c3 31 ff 31 db e8 e3 d9 08 00
----------------
Code disassembly (best guess):
0: 08 00 or %al,(%rax)
2: 85 c0 test %eax,%eax
4: 75 31 jne 0x37
6: 65 48 8b 2c 25 c0 7f mov %gs:0x27fc0,%rbp
d: 02 00
f: 48 8d bd 38 12 00 00 lea 0x1238(%rbp),%rdi
16: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax
1d: fc ff df
20: 48 89 fa mov %rdi,%rdx
23: 48 c1 ea 03 shr $0x3,%rdx
27: 80 3c 02 00 cmpb $0x0,(%rdx,%rax,1)
* 2b: 75 79 jne 0xa6 <-- trapping instruction
2d: 48 89 9d 38 12 00 00 mov %rbx,0x1238(%rbp)
34: 5b pop %rbx
35: 5d pop %rbp
36: c3 retq
37: 31 ff xor %edi,%edi
39: 31 db xor %ebx,%ebx
3b: e8 e3 d9 08 00 callq 0x8da23