INFO: task hung in lru_add_drain_all

12 views
Skip to first unread message

syzbot

unread,
Feb 4, 2021, 10:54:17 AM2/4/21
to syzkaller...@googlegroups.com
Hello,

syzbot found the following issue on:

HEAD commit: 811218ec Linux 4.19.172
git tree: linux-4.19.y
console output: https://syzkaller.appspot.com/x/log.txt?x=17fcd1c4d00000
kernel config: https://syzkaller.appspot.com/x/.config?x=c1cb1d27398c8808
dashboard link: https://syzkaller.appspot.com/bug?extid=05a1b690987a7dc26a6e

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+05a1b6...@syzkaller.appspotmail.com

Bluetooth: hci1: command 0x0406 tx timeout
Bluetooth: hci2: command 0x0406 tx timeout
Bluetooth: hci3: command 0x0406 tx timeout
Bluetooth: hci4: command 0x0406 tx timeout
Bluetooth: hci5: command 0x0406 tx timeout
INFO: task syz-executor.1:8140 blocked for more than 140 seconds.
Not tainted 4.19.172-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.1 D24200 8140 1 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2828 [inline]
__schedule+0x887/0x2040 kernel/sched/core.c:3517
schedule+0x8d/0x1b0 kernel/sched/core.c:3561
schedule_timeout+0x92d/0xfe0 kernel/time/timer.c:1794
do_wait_for_common kernel/sched/completion.c:83 [inline]
__wait_for_common kernel/sched/completion.c:104 [inline]
wait_for_common+0x29c/0x470 kernel/sched/completion.c:115
__flush_work+0x4bb/0x8b0 kernel/workqueue.c:2928
lru_add_drain_all+0x374/0x4d0 mm/swap.c:699
invalidate_bdev+0x96/0xd0 fs/block_dev.c:97
ext4_put_super+0x745/0x1010 fs/ext4/super.c:1034
generic_shutdown_super+0x144/0x370 fs/super.c:456
kill_block_super+0x97/0xf0 fs/super.c:1185
deactivate_locked_super+0x94/0x160 fs/super.c:329
deactivate_super+0x174/0x1a0 fs/super.c:360
cleanup_mnt+0x1a8/0x290 fs/namespace.c:1098
task_work_run+0x148/0x1c0 kernel/task_work.c:113
tracehook_notify_resume include/linux/tracehook.h:193 [inline]
exit_to_usermode_loop+0x251/0x2a0 arch/x86/entry/common.c:167
prepare_exit_to_usermode arch/x86/entry/common.c:198 [inline]
syscall_return_slowpath arch/x86/entry/common.c:271 [inline]
do_syscall_64+0x538/0x620 arch/x86/entry/common.c:296
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x466f67
Code: Bad RIP value.
RSP: 002b:00007ffc00b61e58 EFLAGS: 00000246 ORIG_RAX: 00000000000000a6
RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000466f67
RDX: 00007ffc00b61f2b RSI: 0000000000000002 RDI: 00007ffc00b61f20
RBP: 00007ffc00b61f20 R08: 00000000ffffffff R09: 00007ffc00b61cf0
R10: 0000000001eca8ab R11: 0000000000000246 R12: 00000000004af4e4
R13: 00007ffc00b62ff0 R14: 0000000001eca810 R15: 00007ffc00b63030
INFO: task syz-executor.3:15650 blocked for more than 140 seconds.
Not tainted 4.19.172-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3 D26656 15650 8144 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2828 [inline]
__schedule+0x887/0x2040 kernel/sched/core.c:3517
schedule+0x8d/0x1b0 kernel/sched/core.c:3561
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
__mutex_lock_common kernel/locking/mutex.c:1002 [inline]
__mutex_lock+0x647/0x1260 kernel/locking/mutex.c:1072
sock_ioctl+0x29e/0x5d0 net/socket.c:1039
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:501 [inline]
do_vfs_ioctl+0xcdb/0x12e0 fs/ioctl.c:688
ksys_ioctl+0x9b/0xc0 fs/ioctl.c:705
__do_sys_ioctl fs/ioctl.c:712 [inline]
__se_sys_ioctl fs/ioctl.c:710 [inline]
__x64_sys_ioctl+0x6f/0xb0 fs/ioctl.c:710
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x465b09
Code: 44 24 30 48 8b 08 48 89 0c 24 48 89 44 24 08 c6 44 24 10 01 e8 28 78 fa ff 48 8b 44 24 18 48 89 44 24 38 48 8b 6c 24 20 48 83 <c4> 28 c3 e8 0f 4d 00 00 eb ad cc cc cc cc cc cc cc cc cc cc cc cc
RSP: 002b:00007fe80b628188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 000000000056bf60 RCX: 0000000000465b09
RDX: 0000000020000440 RSI: 00000000000089a0 RDI: 0000000000000004
RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007ffc2120cc7f R14: 00007fe80b628300 R15: 0000000000022000
INFO: task syz-executor.3:15652 blocked for more than 140 seconds.
Not tainted 4.19.172-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3 D28640 15652 8144 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2828 [inline]
__schedule+0x887/0x2040 kernel/sched/core.c:3517
schedule+0x8d/0x1b0 kernel/sched/core.c:3561
schedule_timeout+0x92d/0xfe0 kernel/time/timer.c:1794
do_wait_for_common kernel/sched/completion.c:83 [inline]
__wait_for_common kernel/sched/completion.c:104 [inline]
wait_for_common+0x29c/0x470 kernel/sched/completion.c:115
__flush_work+0x4bb/0x8b0 kernel/workqueue.c:2928
flush_all_backlogs net/core/dev.c:5283 [inline]
rollback_registered_many+0x4b1/0xe70 net/core/dev.c:8173
rollback_registered+0xe9/0x1b0 net/core/dev.c:8230
unregister_netdevice_queue+0x1de/0x3e0 net/core/dev.c:9297
br_del_bridge+0xc8/0x110 net/bridge/br_if.c:488
br_ioctl_deviceless_stub+0x27f/0x730 net/bridge/br_ioctl.c:380
sock_ioctl+0x2b9/0x5d0 net/socket.c:1041
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:501 [inline]
do_vfs_ioctl+0xcdb/0x12e0 fs/ioctl.c:688
ksys_ioctl+0x9b/0xc0 fs/ioctl.c:705
__do_sys_ioctl fs/ioctl.c:712 [inline]
__se_sys_ioctl fs/ioctl.c:710 [inline]
__x64_sys_ioctl+0x6f/0xb0 fs/ioctl.c:710
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x465b09
Code: Bad RIP value.
RSP: 002b:00007fe80b607188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 000000000056c008 RCX: 0000000000465b09
RDX: 0000000020000300 RSI: 00000000000089a1 RDI: 0000000000000004
RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c008
R13: 00007ffc2120cc7f R14: 00007fe80b607300 R15: 0000000000022000

Showing all locks held in the system:
1 lock held by khungtaskd/1567:
#0: 00000000bd49ebff (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4440
1 lock held by in:imklog/7847:
#0: 000000005f4e0e74 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767
2 locks held by syz-executor.1/8140:
#0: 000000007dfcd904 (&type->s_umount_key#39){++++}, at: deactivate_super+0x16c/0x1a0 fs/super.c:359
#1: 00000000fd51f5fb (lock#6){+.+.}, at: lru_add_drain_all+0x5a/0x4d0 mm/swap.c:681
3 locks held by kworker/0:4/9360:
1 lock held by syz-executor.0/15632:
1 lock held by syz-executor.3/15650:
#0: 000000001cb134ec (br_ioctl_mutex){+.+.}, at: sock_ioctl+0x29e/0x5d0 net/socket.c:1039
3 locks held by syz-executor.3/15652:
#0: 000000001cb134ec (br_ioctl_mutex){+.+.}, at: sock_ioctl+0x29e/0x5d0 net/socket.c:1039
#1: 00000000b4a64b5f (rtnl_mutex){+.+.}, at: br_del_bridge+0x14/0x110 net/bridge/br_if.c:472
#2: 00000000486de663 (cpu_hotplug_lock.rw_sem){++++}, at: get_online_cpus include/linux/cpu.h:137 [inline]
#2: 00000000486de663 (cpu_hotplug_lock.rw_sem){++++}, at: flush_all_backlogs net/core/dev.c:5276 [inline]
#2: 00000000486de663 (cpu_hotplug_lock.rw_sem){++++}, at: rollback_registered_many+0x3a8/0xe70 net/core/dev.c:8173

=============================================

NMI backtrace for cpu 1
CPU: 1 PID: 1567 Comm: khungtaskd Not tainted 4.19.172-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:77 [inline]
dump_stack+0x1fc/0x2ef lib/dump_stack.c:118
nmi_cpu_backtrace.cold+0x63/0xa2 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x1a6/0x1f0 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline]
watchdog+0x991/0xe60 kernel/hung_task.c:287
kthread+0x33f/0x460 kernel/kthread.c:259
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 15632 Comm: syz-executor.0 Not tainted 4.19.172-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:__sanitizer_cov_trace_pc+0x27/0x50 kernel/kcov.c:104
Code: 90 90 90 48 8b 34 24 65 48 8b 04 25 80 df 01 00 65 8b 15 3c 85 9f 7e 81 e2 00 01 1f 00 75 2b 8b 90 60 13 00 00 83 fa 02 75 20 <48> 8b 88 68 13 00 00 8b 80 64 13 00 00 48 8b 11 48 83 c2 01 48 39
RSP: 0018:ffff888092667830 EFLAGS: 00000246
RAX: ffff8880566e6480 RBX: ffff8880a38889d0 RCX: ffffc90005c3f000
RDX: 0000000000000002 RSI: ffffffff83ee1bb9 RDI: ffff8880a38889d0
RBP: ffff888239b19180 R08: 0000000000000000 R09: 00000000fffffe00
R10: 0000000000000005 R11: 0000000000000000 R12: ffff8880a3888940
R13: ffff888239b19250 R14: ffff8880a3888988 R15: ffff888239b19198
FS: 00007fd7274f3700(0000) GS:ffff8880ba000000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000001b3052e000 CR3: 00000000ab9c2000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
put_device+0x9/0x30 drivers/base/core.c:2265
disk_put_part include/linux/genhd.h:256 [inline]
__blkdev_get+0x3c1/0x1480 fs/block_dev.c:1500
blkdev_get+0xb0/0x940 fs/block_dev.c:1627
blkdev_open+0x202/0x290 fs/block_dev.c:1788
do_dentry_open+0x4aa/0x1160 fs/open.c:796
do_last fs/namei.c:3421 [inline]
path_openat+0x793/0x2df0 fs/namei.c:3537
do_filp_open+0x18c/0x3f0 fs/namei.c:3567
do_sys_open+0x3b3/0x520 fs/open.c:1085
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x465b09
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007fd7274f3188 EFLAGS: 00000246 ORIG_RAX: 0000000000000101
RAX: ffffffffffffffda RBX: 000000000056bf60 RCX: 0000000000465b09
RDX: 0000000000000000 RSI: 00000000200001c0 RDI: ffffffffffffff9c
RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007ffe02ef74af R14: 00007fd7274f3300 R15: 0000000000022000


---
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.

syzbot

unread,
Jun 4, 2021, 11:54:19 AM6/4/21
to syzkaller...@googlegroups.com
Auto-closing this bug as obsolete.
Crashes did not happen for a while, no reproducer and no activity.
Reply all
Reply to author
Forward
0 new messages