Hello,
syzbot found the following issue on:
HEAD commit: 7d7d1c0a Linux 4.14.232
git tree: linux-4.14.y
console output:
https://syzkaller.appspot.com/x/log.txt?x=11a55385d00000
kernel config:
https://syzkaller.appspot.com/x/.config?x=17ee8a0e183900d8
dashboard link:
https://syzkaller.appspot.com/bug?extid=870bb7df35785306a4b3
Unfortunately, I don't have any reproducer for this issue yet.
IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by:
syzbot+870bb7...@syzkaller.appspotmail.com
Bluetooth: hci2 command 0x0406 tx timeout
Bluetooth: hci3 command 0x0406 tx timeout
Bluetooth: hci1 command 0x0406 tx timeout
Bluetooth: hci5 command 0x0406 tx timeout
Bluetooth: hci4 command 0x0406 tx timeout
INFO: task syz-executor.3:10904 blocked for more than 140 seconds.
Not tainted 4.14.232-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3 D28384 10904 7994 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
fuse_wait_on_page_writeback fs/fuse/file.c:382 [inline]
fuse_wait_on_page_writeback.isra.0+0xdc/0x120 fs/fuse/file.c:378
fuse_launder_page fs/fuse/file.c:2032 [inline]
fuse_launder_page+0xa2/0xd0 fs/fuse/file.c:2025
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+0x1fc/0x250 fs/fuse/file.c:221
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:0x4665f9
RSP: 002b:00007f76d2b59188 EFLAGS: 00000246 ORIG_RAX: 0000000000000055
RAX: ffffffffffffffda RBX: 000000000056bf60 RCX: 00000000004665f9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000180
RBP: 00000000004bfce1 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007ffdb2364a9f R14: 00007f76d2b59300 R15: 0000000000022000
INFO: task syz-executor.3:10920 blocked for more than 140 seconds.
Not tainted 4.14.232-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3 D29168 10920 7994 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
__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_open_common+0x177/0x250 fs/fuse/file.c:214
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:0x4665f9
RSP: 002b:00007f76d2b38188 EFLAGS: 00000246 ORIG_RAX: 0000000000000055
RAX: ffffffffffffffda RBX: 000000000056c008 RCX: 00000000004665f9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000000
RBP: 00000000004bfce1 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c008
R13: 00007ffdb2364a9f R14: 00007f76d2b38300 R15: 0000000000022000
INFO: task syz-executor.3:10924 blocked for more than 140 seconds.
Not tainted 4.14.232-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3 D29696 10924 7994 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
__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+0x1a1/0x4e0 fs/fuse/file.c:420
filp_close+0x9d/0x140 fs/open.c:1158
do_dup2+0x1ec/0x3d0 fs/file.c:846
SYSC_dup3 fs/file.c:902 [inline]
SyS_dup3+0x218/0x360 fs/file.c:877
SYSC_dup2 fs/file.c:923 [inline]
SyS_dup2+0x25/0x300 fs/file.c:911
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x4665f9
RSP: 002b:00007f76d2b17188 EFLAGS: 00000246 ORIG_RAX: 0000000000000021
RAX: ffffffffffffffda RBX: 000000000056c0b0 RCX: 00000000004665f9
RDX: 0000000000000000 RSI: 0000000000000005 RDI: 0000000000000003
RBP: 00000000004bfce1 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c0b0
R13: 00007ffdb2364a9f R14: 00007f76d2b17300 R15: 0000000000022000
INFO: task syz-executor.3:10927 blocked for more than 140 seconds.
Not tainted 4.14.232-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3 D28104 10927 7994 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
__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]
lock_mount+0xbb/0x450 fs/namespace.c:2119
do_add_mount+0x63/0x4f0 fs/namespace.c:2496
do_new_mount fs/namespace.c:2563 [inline]
do_mount+0x157f/0x2a00 fs/namespace.c:2879
SYSC_mount fs/namespace.c:3095 [inline]
SyS_mount+0xa8/0x120 fs/namespace.c:3072
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x4665f9
RSP: 002b:00007f76d2af6188 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 000000000056c158 RCX: 00000000004665f9
RDX: 0000000020002100 RSI: 00000000200020c0 RDI: 0000000000000000
RBP: 00000000004bfce1 R08: 0000000020002140 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c158
R13: 00007ffdb2364a9f R14: 00007f76d2af6300 R15: 0000000000022000
Showing all locks held in the system:
1 lock held by khungtaskd/1533:
#0: (tasklist_lock){.+.+}, at: [<ffffffff8700b7b7>] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548
1 lock held by in:imklog/7683:
#0: (&f->f_pos_lock){+.+.}, at: [<ffffffff818d39ab>] __fdget_pos+0x1fb/0x2b0 fs/file.c:769
2 locks held by syz-executor.3/10904:
#0: (sb_writers#18){.+.+}, at: [<ffffffff818dc80a>] sb_start_write include/linux/fs.h:1549 [inline]
#0: (sb_writers#18){.+.+}, at: [<ffffffff818dc80a>] mnt_want_write+0x3a/0xb0 fs/namespace.c:386
#1: (&sb->s_type->i_mutex_key#25){+.+.}, at: [<ffffffff822ed7d7>] inode_lock include/linux/fs.h:719 [inline]
#1: (&sb->s_type->i_mutex_key#25){+.+.}, at: [<ffffffff822ed7d7>] fuse_open_common+0x177/0x250 fs/fuse/file.c:214
2 locks held by syz-executor.3/10920:
#0: (sb_writers#18){.+.+}, at: [<ffffffff818dc80a>] sb_start_write include/linux/fs.h:1549 [inline]
#0: (sb_writers#18){.+.+}, at: [<ffffffff818dc80a>] mnt_want_write+0x3a/0xb0 fs/namespace.c:386
#1: (&sb->s_type->i_mutex_key#25){+.+.}, at: [<ffffffff822ed7d7>] inode_lock include/linux/fs.h:719 [inline]
#1: (&sb->s_type->i_mutex_key#25){+.+.}, at: [<ffffffff822ed7d7>] fuse_open_common+0x177/0x250 fs/fuse/file.c:214
1 lock held by syz-executor.3/10924:
#0: (&sb->s_type->i_mutex_key#25){+.+.}, at: [<ffffffff822edb91>] inode_lock include/linux/fs.h:719 [inline]
#0: (&sb->s_type->i_mutex_key#25){+.+.}, at: [<ffffffff822edb91>] fuse_flush+0x1a1/0x4e0 fs/fuse/file.c:420
1 lock held by syz-executor.3/10927:
#0: (&sb->s_type->i_mutex_key#25){+.+.}, at: [<ffffffff818de13b>] inode_lock include/linux/fs.h:719 [inline]
#0: (&sb->s_type->i_mutex_key#25){+.+.}, at: [<ffffffff818de13b>] lock_mount+0xbb/0x450 fs/namespace.c:2119
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 1533 Comm: khungtaskd Not tainted 4.14.232-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 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 4615 Comm: systemd-journal Not tainted 4.14.232-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
task: ffff8880a19dc600 task.stack: ffff8880a19e0000
RIP: 0010:memset_erms+0x9/0x10 arch/x86/lib/memset_64.S:66
RSP: 0018:ffff8880a19e7d10 EFLAGS: 00010046
RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000010
RDX: 0000000000000010 RSI: 0000000000000000 RDI: ffff8880b4b7fa40
RBP: 0000000000000000 R08: 0000000000001000 R09: ffff8880b4b7fa40
R10: 0000000000000000 R11: ffff8880a19dc600 R12: ffffea0002d2df80
R13: ffff8880b4b7ea40 R14: ffff88823f8bb200 R15: ffff88823f8bb200
FS: 00007f84c86c38c0(0000) GS:ffff8880ba500000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f84c5afb000 CR3: 00000000a1884000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 000000000000d0eb
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
cache_init_objs mm/slab.c:2577 [inline]
cache_grow_begin+0x4d4/0x700 mm/slab.c:2703
cache_alloc_refill+0x273/0x350 mm/slab.c:3043
____cache_alloc mm/slab.c:3125 [inline]
__do_cache_alloc mm/slab.c:3347 [inline]
slab_alloc mm/slab.c:3382 [inline]
kmem_cache_alloc+0x333/0x3c0 mm/slab.c:3550
getname_flags+0xc8/0x550 fs/namei.c:138
do_sys_open+0x1ce/0x410 fs/open.c:1075
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7f84c7c52840
RSP: 002b:00007ffc75af8a18 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 00007ffc75af8d20 RCX: 00007f84c7c52840
RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 0000559df52f6800
RBP: 000000000000000d R08: 000000000000c0c1 R09: 00000000ffffffff
R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff
R13: 0000559df52e9040 R14: 00007ffc75af8ce0 R15: 0000559df52f68f0
Code: 48 c1 e9 03 40 0f b6 f6 48 b8 01 01 01 01 01 01 01 01 48 0f af c6 f3 48 ab 89 d1 f3 aa 4c 89 c8 c3 90 49 89 f9 40 88 f0 48 89 d1 <f3> aa 4c 89 c8 c3 90 49 89 fa 40 0f b6 ce 48 b8 01 01 01 01 01
---
This report is generated by a bot. It may contain errors.
See
https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at
syzk...@googlegroups.com.
syzbot will keep track of this issue. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.