INFO: task hung in lo_release (2)

9 views
Skip to first unread message

syzbot

unread,
Mar 18, 2020, 8:28:15 PM3/18/20
to syzkaller...@googlegroups.com
Hello,

syzbot found the following crash on:

HEAD commit: 12cd844a Linux 4.14.173
git tree: linux-4.14.y
console output: https://syzkaller.appspot.com/x/log.txt?x=1557bfc3e00000
kernel config: https://syzkaller.appspot.com/x/.config?x=8a9d0602a0f7791e
dashboard link: https://syzkaller.appspot.com/bug?extid=d6eecbbc7e8a6b686616
compiler: gcc (GCC) 9.0.0 20181231 (experimental)

Unfortunately, I don't have any reproducer for this crash yet.

IMPORTANT: if you fix the bug, please add the following tag to the commit:
Reported-by: syzbot+d6eecb...@syzkaller.appspotmail.com

NOHZ: local_softirq_pending 08
NOHZ: local_softirq_pending 08
NOHZ: local_softirq_pending 08
NOHZ: local_softirq_pending 08
NOHZ: local_softirq_pending 08
INFO: task syz-executor.5:7392 blocked for more than 140 seconds.
Not tainted 4.14.173-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.5 D25440 7392 1 0x00000004
Call Trace:
schedule+0x8d/0x1b0 kernel/sched/core.c:3428
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x73c/0x1470 kernel/locking/mutex.c:893
lo_release+0x1b/0x190 drivers/block/loop.c:1677
__blkdev_put+0x4ec/0x6a0 fs/block_dev.c:1791
blkdev_close+0x86/0xb0 fs/block_dev.c:1863
__fput+0x25f/0x790 fs/file_table.c:210
task_work_run+0x113/0x190 kernel/task_work.c:113
tracehook_notify_resume include/linux/tracehook.h:191 [inline]
exit_to_usermode_loop+0x1d6/0x220 arch/x86/entry/common.c:164
prepare_exit_to_usermode arch/x86/entry/common.c:199 [inline]
syscall_return_slowpath arch/x86/entry/common.c:270 [inline]
do_syscall_64+0x4a3/0x640 arch/x86/entry/common.c:297
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x4163c0
RSP: 002b:00007ffee180a488 EFLAGS: 00000246 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000003 RCX: 00000000004163c0
RDX: 0000000000000000 RSI: 0000000000004c01 RDI: 0000000000000003
RBP: 000000000000032b R08: 0000000000000000 R09: 000000000000000a
R10: 0000000000000075 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffee180a4c0 R14: 000000000005a2c7 R15: 00007ffee180a4d0
INFO: task syz-executor.4:7394 blocked for more than 140 seconds.
Not tainted 4.14.173-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4 D25440 7394 1 0x00000004
Call Trace:
schedule+0x8d/0x1b0 kernel/sched/core.c:3428
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x73c/0x1470 kernel/locking/mutex.c:893
lo_open+0x19/0xb0 drivers/block/loop.c:1634
__blkdev_get+0xa70/0x10d0 fs/block_dev.c:1537
blkdev_get+0x84/0x8a0 fs/block_dev.c:1612
blkdev_open+0x1cc/0x250 fs/block_dev.c:1770
do_dentry_open+0x732/0xe90 fs/open.c:777
vfs_open+0x105/0x220 fs/open.c:891
do_last fs/namei.c:3428 [inline]
path_openat+0x8ca/0x3c50 fs/namei.c:3569
do_filp_open+0x18e/0x250 fs/namei.c:3603
do_sys_open+0x29d/0x3f0 fs/open.c:1084
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x4165c0
RSP: 002b:00007fff5d855278 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 000000000005aec2 RCX: 00000000004165c0
RDX: 00007fff5d85530a RSI: 0000000000000002 RDI: 00007fff5d855300
RBP: 0000000000000370 R08: 0000000000000000 R09: 000000000000000a
R10: 0000000000000075 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fff5d8552b0 R14: 000000000005ab6f R15: 00007fff5d8552c0
INFO: task syz-executor.3:7396 blocked for more than 140 seconds.
Not tainted 4.14.173-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3 D25440 7396 1 0x00000004
Call Trace:
schedule+0x8d/0x1b0 kernel/sched/core.c:3428
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x73c/0x1470 kernel/locking/mutex.c:893
lo_open+0x19/0xb0 drivers/block/loop.c:1634
__blkdev_get+0x2a6/0x10d0 fs/block_dev.c:1472
blkdev_get+0x84/0x8a0 fs/block_dev.c:1612
blkdev_open+0x1cc/0x250 fs/block_dev.c:1770
do_dentry_open+0x732/0xe90 fs/open.c:777
vfs_open+0x105/0x220 fs/open.c:891
do_last fs/namei.c:3428 [inline]
path_openat+0x8ca/0x3c50 fs/namei.c:3569
do_filp_open+0x18e/0x250 fs/namei.c:3603
do_sys_open+0x29d/0x3f0 fs/open.c:1084
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x4165c0
RSP: 002b:00007ffd6a1ce468 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 000000000005ab7c RCX: 00000000004165c0
RDX: 00007ffd6a1ce4fa RSI: 0000000000000002 RDI: 00007ffd6a1ce4f0
RBP: 0000000000000382 R08: 0000000000000000 R09: 000000000000000a
R10: 0000000000000075 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffd6a1ce4a0 R14: 0000000000059f6c R15: 00007ffd6a1ce4b0
INFO: task syz-executor.2:15185 blocked for more than 140 seconds.
Not tainted 4.14.173-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2 D28688 15185 7395 0x00000004
Call Trace:
schedule+0x8d/0x1b0 kernel/sched/core.c:3428
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x73c/0x1470 kernel/locking/mutex.c:893
loop_control_ioctl+0x15b/0x2d0 drivers/block/loop.c:2007
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0x75a/0xfe0 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45c849
RSP: 002b:00007ff74d566c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007ff74d5676d4 RCX: 000000000045c849
RDX: 0000000000000000 RSI: 0000000000004c81 RDI: 0000000000000005
RBP: 000000000076bfa0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff
R13: 00000000000003ed R14: 00000000004c6821 R15: 000000000076bfac
INFO: task blkid:15187 blocked for more than 140 seconds.
Not tainted 4.14.173-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
blkid D26432 15187 8055 0x00000004
Call Trace:
schedule+0x8d/0x1b0 kernel/sched/core.c:3428
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x73c/0x1470 kernel/locking/mutex.c:893
lo_open+0x19/0xb0 drivers/block/loop.c:1634
__blkdev_get+0xa70/0x10d0 fs/block_dev.c:1537
blkdev_get+0x84/0x8a0 fs/block_dev.c:1612
blkdev_open+0x1cc/0x250 fs/block_dev.c:1770
do_dentry_open+0x732/0xe90 fs/open.c:777
vfs_open+0x105/0x220 fs/open.c:891
do_last fs/namei.c:3428 [inline]
path_openat+0x8ca/0x3c50 fs/namei.c:3569
do_filp_open+0x18e/0x250 fs/namei.c:3603
do_sys_open+0x29d/0x3f0 fs/open.c:1084
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x7fae50c91120
RSP: 002b:00007ffed1309568 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007fae50c91120
RDX: 00007ffed130af34 RSI: 0000000000000000 RDI: 00007ffed130af34
RBP: 0000000000000000 R08: 0000000000000078 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000002354030
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000005
INFO: task syz-executor.0:15190 blocked for more than 140 seconds.
Not tainted 4.14.173-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.0 D28640 15190 7393 0x00000004
Call Trace:
schedule+0x8d/0x1b0 kernel/sched/core.c:3428
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x73c/0x1470 kernel/locking/mutex.c:893
blkdev_reread_part+0x1b/0x40 block/ioctl.c:192
loop_reread_partitions+0x72/0x80 drivers/block/loop.c:624
loop_set_status+0xbfc/0x11f0 drivers/block/loop.c:1193
loop_set_status64+0x92/0xe0 drivers/block/loop.c:1311
lo_ioctl+0x590/0x1c40 drivers/block/loop.c:1441
__blkdev_driver_ioctl block/ioctl.c:297 [inline]
blkdev_ioctl+0x91d/0x17d0 block/ioctl.c:594
block_ioctl+0xd9/0x120 fs/block_dev.c:1881
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0x75a/0xfe0 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45c6b7
RSP: 002b:00007f22803669d8 EFLAGS: 00000202 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 000000000045c6b7
RDX: 00007f2280366ac0 RSI: 0000000000004c04 RDI: 0000000000000004
RBP: 000000000076bf00 R08: 0000000000000000 R09: 000000000000000a
R10: 0000000000000075 R11: 0000000000000202 R12: 00000000ffffffff
R13: 0000000000000c1b R14: 00000000004c1905 R15: 000000000076bf0c
INFO: task blkid:15195 blocked for more than 140 seconds.
Not tainted 4.14.173-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
blkid D28656 15195 8094 0x00000004
Call Trace:
schedule+0x8d/0x1b0 kernel/sched/core.c:3428
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x73c/0x1470 kernel/locking/mutex.c:893
__blkdev_get+0x131/0x10d0 fs/block_dev.c:1457
blkdev_get+0x84/0x8a0 fs/block_dev.c:1612
blkdev_open+0x1cc/0x250 fs/block_dev.c:1770
do_dentry_open+0x732/0xe90 fs/open.c:777
vfs_open+0x105/0x220 fs/open.c:891
do_last fs/namei.c:3428 [inline]
path_openat+0x8ca/0x3c50 fs/namei.c:3569
do_filp_open+0x18e/0x250 fs/namei.c:3603
do_sys_open+0x29d/0x3f0 fs/open.c:1084
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x7ff118528120
RSP: 002b:00007ffec8e84718 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007ff118528120
RDX: 00007ffec8e85f34 RSI: 0000000000000000 RDI: 00007ffec8e85f34
RBP: 0000000000000000 R08: 0000000000000078 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000022a1030
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000005

Showing all locks held in the system:
1 lock held by khungtaskd/1051:
#0: (tasklist_lock){.+.+}, at: [<ffffffff81465b23>] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4544
2 locks held by getty/7346:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff833ad812>] tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:284
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff833a1454>] n_tty_read+0x1e4/0x16f0 drivers/tty/n_tty.c:2156
2 locks held by getty/7347:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff833ad812>] tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:284
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff833a1454>] n_tty_read+0x1e4/0x16f0 drivers/tty/n_tty.c:2156
2 locks held by getty/7348:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff833ad812>] tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:284
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff833a1454>] n_tty_read+0x1e4/0x16f0 drivers/tty/n_tty.c:2156
2 locks held by getty/7349:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff833ad812>] tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:284
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff833a1454>] n_tty_read+0x1e4/0x16f0 drivers/tty/n_tty.c:2156
2 locks held by getty/7350:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff833ad812>] tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:284
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff833a1454>] n_tty_read+0x1e4/0x16f0 drivers/tty/n_tty.c:2156
2 locks held by getty/7351:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff833ad812>] tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:284
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff833a1454>] n_tty_read+0x1e4/0x16f0 drivers/tty/n_tty.c:2156
2 locks held by getty/7352:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff833ad812>] tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:284
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff833a1454>] n_tty_read+0x1e4/0x16f0 drivers/tty/n_tty.c:2156
2 locks held by syz-executor.5/7392:
#0: (&bdev->bd_mutex){+.+.}, at: [<ffffffff8197c40e>] __blkdev_put+0x9e/0x6a0 fs/block_dev.c:1778
#1: (loop_index_mutex){+.+.}, at: [<ffffffff839d2c8b>] lo_release+0x1b/0x190 drivers/block/loop.c:1677
2 locks held by syz-executor.4/7394:
#0: (&bdev->bd_mutex){+.+.}, at: [<ffffffff8197cb41>] __blkdev_get+0x131/0x10d0 fs/block_dev.c:1457
#1: (loop_index_mutex){+.+.}, at: [<ffffffff839ce259>] lo_open+0x19/0xb0 drivers/block/loop.c:1634
2 locks held by syz-executor.3/7396:
#0: (&bdev->bd_mutex){+.+.}, at: [<ffffffff8197cb41>] __blkdev_get+0x131/0x10d0 fs/block_dev.c:1457
#1: (loop_index_mutex){+.+.}, at: [<ffffffff839ce259>] lo_open+0x19/0xb0 drivers/block/loop.c:1634
2 locks held by syz-executor.2/15185:
#0: (loop_index_mutex){+.+.}, at: [<ffffffff839d4411>] loop_control_ioctl+0x61/0x2d0 drivers/block/loop.c:1993
#1: (&lo->lo_ctl_mutex#2){+.+.}, at: [<ffffffff839d450b>] loop_control_ioctl+0x15b/0x2d0 drivers/block/loop.c:2007
2 locks held by blkid/15187:
#0: (&bdev->bd_mutex){+.+.}, at: [<ffffffff8197cb41>] __blkdev_get+0x131/0x10d0 fs/block_dev.c:1457
#1: (loop_index_mutex){+.+.}, at: [<ffffffff839ce259>] lo_open+0x19/0xb0 drivers/block/loop.c:1634
2 locks held by syz-executor.0/15190:
#0: (&lo->lo_ctl_mutex/1){+.+.}, at: [<ffffffff839d4867>] lo_ioctl+0x87/0x1c40 drivers/block/loop.c:1414
#1: (&bdev->bd_mutex){+.+.}, at: [<ffffffff82f0bcab>] blkdev_reread_part+0x1b/0x40 block/ioctl.c:192
1 lock held by blkid/15195:
#0: (&bdev->bd_mutex){+.+.}, at: [<ffffffff8197cb41>] __blkdev_get+0x131/0x10d0 fs/block_dev.c:1457

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

NMI backtrace for cpu 1
CPU: 1 PID: 1051 Comm: khungtaskd Not tainted 4.14.173-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+0x13e/0x194 lib/dump_stack.c:58
nmi_cpu_backtrace.cold+0x57/0x93 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x139/0x17e 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+0x5e2/0xb80 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: 22 Comm: kworker/u4:1 Not tainted 4.14.173-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: bat_events batadv_nc_worker
task: ffff8880a9a1e5c0 task.stack: ffff8880a9a28000
RIP: 0010:__lock_acquire+0x61e/0x4620 kernel/locking/lockdep.c:3464
RSP: 0018:ffff8880a9a2fa50 EFLAGS: 00000806
RAX: dffffc0000000000 RBX: 0000000000000000 RCX: 1ffff11015343dd6
RDX: 1ffffffff14b0f60 RSI: ffff8880a9a1ee90 RDI: ffffffff8a1156b0
RBP: ffff8880a9a2fc00 R08: 0000000000000000 R09: 000000000004056d
R10: ffff8880a9a1ee90 R11: ffff8880a9a1e5c0 R12: ffff88805f0f4840
R13: 000000000000056d R14: 0000000000000002 R15: ffff8880a9a1eeb2
FS: 0000000000000000(0000) GS:ffff8880aea00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f67cf7c6000 CR3: 0000000091eca000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3994
__raw_spin_lock_bh include/linux/spinlock_api_smp.h:135 [inline]
_raw_spin_lock_bh+0x2f/0x40 kernel/locking/spinlock.c:176
spin_lock_bh include/linux/spinlock.h:322 [inline]
batadv_nc_purge_paths+0xcf/0x360 net/batman-adv/network-coding.c:450
batadv_nc_worker+0x238/0x6d0 net/batman-adv/network-coding.c:727
process_one_work+0x813/0x1540 kernel/workqueue.c:2114
worker_thread+0x5d1/0x1070 kernel/workqueue.c:2248
kthread+0x30d/0x420 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
Code: c9 e2 ff ff 4c 8b 5c 24 78 85 c0 4c 8b 54 24 70 0f 84 63 fb ff ff 48 c7 c2 00 7b 58 8a 48 b8 00 00 00 00 00 fc ff df 48 c1 ea 03 <0f> b6 14 02 48 c7 c0 00 7b 58 8a 83 e0 07 83 c0 03 38 d0 7c 08


---
This bug 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 bug report. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.

syzbot

unread,
Apr 6, 2021, 7:44:19 PM4/6/21
to syzkaller...@googlegroups.com
syzbot has found a reproducer for the following issue on:

HEAD commit: bd634aa6 Linux 4.14.228
git tree: linux-4.14.y
console output: https://syzkaller.appspot.com/x/log.txt?x=152aa2a6d00000
kernel config: https://syzkaller.appspot.com/x/.config?x=5fab202d11a349c3
dashboard link: https://syzkaller.appspot.com/bug?extid=d6eecbbc7e8a6b686616
syz repro: https://syzkaller.appspot.com/x/repro.syz?x=1448d196d00000
C reproducer: https://syzkaller.appspot.com/x/repro.c?x=171223ced00000

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+d6eecb...@syzkaller.appspotmail.com

INFO: task systemd-udevd:8094 blocked for more than 140 seconds.
Not tainted 4.14.228-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
systemd-udevd D28216 8094 4631 0x00000104
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
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
lo_release+0x1b/0x190 drivers/block/loop.c:1677
__blkdev_put+0x5aa/0x800 fs/block_dev.c:1803
blkdev_close+0x86/0xb0 fs/block_dev.c:1875
__fput+0x25f/0x7a0 fs/file_table.c:210
task_work_run+0x11f/0x190 kernel/task_work.c:113
tracehook_notify_resume include/linux/tracehook.h:191 [inline]
exit_to_usermode_loop+0x1ad/0x200 arch/x86/entry/common.c:164
prepare_exit_to_usermode arch/x86/entry/common.c:199 [inline]
syscall_return_slowpath arch/x86/entry/common.c:270 [inline]
do_syscall_64+0x4a3/0x640 arch/x86/entry/common.c:297
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7fc309265270
RSP: 002b:00007ffd53f78f78 EFLAGS: 00000246 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 000000000000000f RCX: 00007fc309265270
RDX: 00007fc30924fb58 RSI: 0000000000000000 RDI: 000000000000000f
RBP: 00007fc30a11f710 R08: 8f7682fa2ad78bac R09: 0000000000000002
R10: fae179eca07d17e7 R11: 0000000000000246 R12: 0000000000000002
R13: 0000000000000000 R14: 0000557baae6a6a0 R15: 000000000000000f
INFO: task syz-executor408:8915 blocked for more than 140 seconds.
Not tainted 4.14.228-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor408 D28912 8915 7980 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
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
loop_control_ioctl+0x181/0x3f0 drivers/block/loop.c:2007
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x43ffb9
RSP: 002b:00007ffdcbc466c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00000000000f4240 RCX: 000000000043ffb9
RDX: 0000000000000000 RSI: 0000000000004c81 RDI: 0000000000000003
RBP: 0000000000000000 R08: 000000000000000d R09: 000000000000000d
R10: 000000000000000d R11: 0000000000000246 R12: 0000000000096468
R13: 00007ffdcbc466f0 R14: 00007ffdcbc466dc R15: 00007ffdcbc466e0
INFO: task syz-executor408:8919 blocked for more than 140 seconds.
Not tainted 4.14.228-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor408 D28912 8919 7976 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
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
blkdev_reread_part+0x1b/0x40 block/ioctl.c:192
loop_reread_partitions drivers/block/loop.c:624 [inline]
loop_set_status+0xeeb/0x12b0 drivers/block/loop.c:1193
loop_set_status64+0x92/0xe0 drivers/block/loop.c:1311
lo_ioctl+0x587/0x1cd0 drivers/block/loop.c:1441
__blkdev_driver_ioctl block/ioctl.c:297 [inline]
blkdev_ioctl+0x540/0x1830 block/ioctl.c:594
block_ioctl+0xd9/0x120 fs/block_dev.c:1893
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x43fe67
RSP: 002b:00007ffdcbc46468 EFLAGS: 00000202 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007ffdcbc46490 RCX: 000000000043fe67
RDX: 00007ffdcbc465a0 RSI: 0000000000004c04 RDI: 0000000000000004
RBP: 0000000000000004 R08: 00007ffdcbc46300 R09: 00000000fbad8001
R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000003
R13: 0000000001a842c0 R14: 00007ffdcbc465a0 R15: 00007ffdcbc466e0
INFO: task syz-executor408:8920 blocked for more than 140 seconds.
Not tainted 4.14.228-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor408 D28912 8920 7978 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
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
loop_control_ioctl+0x67/0x3f0 drivers/block/loop.c:1993
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x43ffb9
RSP: 002b:00007ffdcbc466c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00000000000f4240 RCX: 000000000043ffb9
RDX: 0000000000000000 RSI: 0000000000004c82 RDI: 0000000000000003
RBP: 0000000000000000 R08: 000000000000000d R09: 000000000000000d
R10: 00000000fbad8001 R11: 0000000000000246 R12: 0000000000096413
R13: 00007ffdcbc466f0 R14: 00007ffdcbc466dc R15: 00007ffdcbc466e0
INFO: task syz-executor408:8921 blocked for more than 140 seconds.
Not tainted 4.14.228-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor408 D28912 8921 7979 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
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
loop_control_ioctl+0x67/0x3f0 drivers/block/loop.c:1993
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x43ffb9
RSP: 002b:00007ffdcbc466c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00000000000f4240 RCX: 000000000043ffb9
RDX: 0000000000000000 RSI: 0000000000004c82 RDI: 0000000000000003
RBP: 0000000000000000 R08: 000000000000000d R09: 000000000000000d
R10: 00000000fbad8001 R11: 0000000000000246 R12: 000000000009648f
R13: 00007ffdcbc466f0 R14: 00007ffdcbc466dc R15: 00007ffdcbc466e0
INFO: task syz-executor408:8922 blocked for more than 140 seconds.
Not tainted 4.14.228-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor408 D28912 8922 7981 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
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
loop_control_ioctl+0x67/0x3f0 drivers/block/loop.c:1993
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x43ffb9
RSP: 002b:00007ffdcbc466c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00000000000f4240 RCX: 000000000043ffb9
RDX: 0000000000000000 RSI: 0000000000004c82 RDI: 0000000000000003
RBP: 0000000000000000 R08: 000000000000000d R09: 000000000000000d
R10: 00000000fbad8001 R11: 0000000000000246 R12: 00000000000964b6
R13: 00007ffdcbc466f0 R14: 00007ffdcbc466dc R15: 00007ffdcbc466e0
INFO: task syz-executor408:8923 blocked for more than 140 seconds.
Not tainted 4.14.228-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor408 D28912 8923 7977 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
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
loop_control_ioctl+0x67/0x3f0 drivers/block/loop.c:1993
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x43ffb9
RSP: 002b:00007ffdcbc466c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00000000000f4240 RCX: 000000000043ffb9
RDX: 0000000000000000 RSI: 0000000000004c82 RDI: 0000000000000003
RBP: 0000000000000000 R08: 000000000000000d R09: 000000000000000d
R10: 00000000fbad8001 R11: 0000000000000246 R12: 00000000000964f0
R13: 00007ffdcbc466f0 R14: 00007ffdcbc466dc R15: 00007ffdcbc466e0

Showing all locks held in the system:
1 lock held by khungtaskd/1534:
#0: (tasklist_lock){.+.+}, at: [<ffffffff87003f47>] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548
2 locks held by systemd-udevd/8094:
#0: (&bdev->bd_mutex){+.+.}, at: [<ffffffff819399f8>] __blkdev_put+0xd8/0x800 fs/block_dev.c:1790
#1: (loop_index_mutex){+.+.}, at: [<ffffffff838d19eb>] lo_release+0x1b/0x190 drivers/block/loop.c:1677
2 locks held by syz-executor408/8915:
#0: (loop_index_mutex){+.+.}, at: [<ffffffff838d02f7>] loop_control_ioctl+0x67/0x3f0 drivers/block/loop.c:1993
#1: (&lo->lo_ctl_mutex){+.+.}, at: [<ffffffff838d0411>] loop_control_ioctl+0x181/0x3f0 drivers/block/loop.c:2007
2 locks held by syz-executor408/8919:
#0: (&lo->lo_ctl_mutex/1){+.+.}, at: [<ffffffff838d3247>] lo_ioctl+0x87/0x1cd0 drivers/block/loop.c:1414
#1: (&bdev->bd_mutex){+.+.}, at: [<ffffffff830b2c6b>] blkdev_reread_part+0x1b/0x40 block/ioctl.c:192
1 lock held by syz-executor408/8920:
#0: (loop_index_mutex){+.+.}, at: [<ffffffff838d02f7>] loop_control_ioctl+0x67/0x3f0 drivers/block/loop.c:1993
1 lock held by syz-executor408/8921:
#0: (loop_index_mutex){+.+.}, at: [<ffffffff838d02f7>] loop_control_ioctl+0x67/0x3f0 drivers/block/loop.c:1993
1 lock held by syz-executor408/8922:
#0: (loop_index_mutex){+.+.}, at: [<ffffffff838d02f7>] loop_control_ioctl+0x67/0x3f0 drivers/block/loop.c:1993
1 lock held by syz-executor408/8923:
#0: (loop_index_mutex){+.+.}, at: [<ffffffff838d02f7>] loop_control_ioctl+0x67/0x3f0 drivers/block/loop.c:1993

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

NMI backtrace for cpu 0
CPU: 0 PID: 1534 Comm: khungtaskd Not tainted 4.14.228-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: 8067 Comm: kworker/1:2 Not tainted 4.14.228-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: events_power_efficient fb_flashcursor
task: ffff8880b0bf04c0 task.stack: ffff8880951c0000
RIP: 0010:__orc_find+0x8b/0xf0 arch/x86/kernel/unwind_orc.c:53
RSP: 0018:ffff8880951c7898 EFLAGS: 00000087
RAX: ffffffff8141be8e RBX: ffffffff8a14f4a0 RCX: ffffffff8141be6f
RDX: 0000000000000000 RSI: ffffffff8a6c07bc RDI: ffffffff8a14f48c
RBP: ffffffff8a14f4a0 R08: ffffffff8a6c07bc R09: ffffffff8a6c0810
R10: 000000000000e842 R11: 0000000000066071 R12: ffffffff8a14f4a0
R13: ffffffff8a14f48c R14: ffffffff8a14f49c R15: dffffc0000000000
FS: 0000000000000000(0000) GS:ffff8880ba500000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000001a842c0 CR3: 00000000abcdc000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
orc_find arch/x86/kernel/unwind_orc.c:118 [inline]
unwind_next_frame+0x59a/0x17d0 arch/x86/kernel/unwind_orc.c:348
__save_stack_trace+0xc7/0x160 arch/x86/kernel/stacktrace.c:44
save_trace+0xd6/0x290 kernel/locking/lockdep.c:407
check_prev_add kernel/locking/lockdep.c:1953 [inline]
check_prevs_add kernel/locking/lockdep.c:2022 [inline]
validate_chain kernel/locking/lockdep.c:2464 [inline]
__lock_acquire+0x35e5/0x3f20 kernel/locking/lockdep.c:3491
lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998
__raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline]
_raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:152
console_lock_spinning_enable kernel/printk/printk.c:1583 [inline]
console_unlock+0x332/0xf20 kernel/printk/printk.c:2397
fb_flashcursor+0x35b/0x3f0 drivers/video/fbdev/core/fbcon.c:375
process_one_work+0x793/0x14a0 kernel/workqueue.c:2116
worker_thread+0x5cc/0xff0 kernel/workqueue.c:2250
kthread+0x30d/0x420 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
Code: 5c 85 00 48 89 d8 48 c1 e8 03 42 0f b6 14 38 48 89 d8 83 e0 07 83 c0 03 38 d0 7c 04 84 d2 75 48 48 63 03 48 01 d8 48 39 c1 73 b0 <4c> 8d 63 fc 49 39 ec 73 b3 4d 29 ee 49 c1 fe 02 4b 8d 04 76 48

Reply all
Reply to author
Forward
0 new messages