INFO: task hung in mount_bdev

9 views
Skip to first unread message

syzbot

unread,
Dec 14, 2018, 8:01:03 AM12/14/18
to syzkaller-upst...@googlegroups.com
Hello,

syzbot found the following crash on:

HEAD commit: f5d582777bcb Merge branch 'for-linus' of git://git.kernel...
git tree: upstream
console output: https://syzkaller.appspot.com/x/log.txt?x=129a57a3400000
kernel config: https://syzkaller.appspot.com/x/.config?x=c8970c89a0efbb23
dashboard link: https://syzkaller.appspot.com/bug?extid=7a12e0360626f8077fbf
compiler: gcc (GCC) 8.0.1 20180413 (experimental)
CC: [linux-...@vger.kernel.org linux-...@vger.kernel.org
vi...@zeniv.linux.org.uk]

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

binder: send failed reply for transaction 7 to 23226:23227
binder: undelivered TRANSACTION_COMPLETE
binder: undelivered TRANSACTION_ERROR: 29189
INFO: task syz-executor0:23999 blocked for more than 140 seconds.
Not tainted 4.20.0-rc6+ #150
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor0 D23288 23999 6110 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2831 [inline]
__schedule+0x8cf/0x21d0 kernel/sched/core.c:3472
schedule+0xfe/0x460 kernel/sched/core.c:3516
schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3574
__mutex_lock_common kernel/locking/mutex.c:1002 [inline]
__mutex_lock+0xaff/0x16f0 kernel/locking/mutex.c:1072
mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:1087
mount_bdev+0xa5/0x3e0 fs/super.c:1125
isofs_mount+0x34/0x40 fs/isofs/inode.c:1570
mount_fs+0xae/0x31d fs/super.c:1261
vfs_kern_mount.part.35+0xdc/0x4f0 fs/namespace.c:961
vfs_kern_mount fs/namespace.c:951 [inline]
do_new_mount fs/namespace.c:2469 [inline]
do_mount+0x581/0x31f0 fs/namespace.c:2801
ksys_mount+0x12d/0x140 fs/namespace.c:3017
__do_sys_mount fs/namespace.c:3031 [inline]
__se_sys_mount fs/namespace.c:3028 [inline]
__x64_sys_mount+0xbe/0x150 fs/namespace.c:3028
do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45a0ea
Code: Bad RIP value.
RSP: 002b:00007f1a0b43ea88 EFLAGS: 00000206 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 00007f1a0b43eb30 RCX: 000000000045a0ea
RDX: 00007f1a0b43ead0 RSI: 00000000200000c0 RDI: 00007f1a0b43eaf0
RBP: 00000000200000c0 R08: 00007f1a0b43eb30 R09: 00007f1a0b43ead0
R10: 0000000000000005 R11: 0000000000000206 R12: 0000000000000006
R13: 0000000000000005 R14: 00000000004d9b50 R15: 00000000ffffffff
INFO: task syz-executor0:24040 blocked for more than 140 seconds.
Not tainted 4.20.0-rc6+ #150
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor0 D24296 24040 6110 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2831 [inline]
__schedule+0x8cf/0x21d0 kernel/sched/core.c:3472
schedule+0xfe/0x460 kernel/sched/core.c:3516
__rwsem_down_write_failed_common+0xc9a/0x15e0
kernel/locking/rwsem-xadd.c:577
rwsem_down_write_failed+0xe/0x10 kernel/locking/rwsem-xadd.c:606
call_rwsem_down_write_failed+0x17/0x30 arch/x86/lib/rwsem.S:117
__down_write arch/x86/include/asm/rwsem.h:142 [inline]
down_write+0xa5/0x130 kernel/locking/rwsem.c:72
grab_super+0xcc/0x400 fs/super.c:383
sget_userns+0x433/0xed0 fs/super.c:511
sget+0x10b/0x150 fs/super.c:570
mount_bdev+0x111/0x3e0 fs/super.c:1131
isofs_mount+0x34/0x40 fs/isofs/inode.c:1570
mount_fs+0xae/0x31d fs/super.c:1261
vfs_kern_mount.part.35+0xdc/0x4f0 fs/namespace.c:961
vfs_kern_mount fs/namespace.c:951 [inline]
do_new_mount fs/namespace.c:2469 [inline]
do_mount+0x581/0x31f0 fs/namespace.c:2801
ksys_mount+0x12d/0x140 fs/namespace.c:3017
__do_sys_mount fs/namespace.c:3031 [inline]
__se_sys_mount fs/namespace.c:3028 [inline]
__x64_sys_mount+0xbe/0x150 fs/namespace.c:3028
do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x457679
Code: Bad RIP value.
RSP: 002b:00007f1a0b3fcc78 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 0000000000457679
RDX: 0000000020000040 RSI: 0000000020000000 RDI: 0000000020000240
RBP: 000000000072c040 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f1a0b3fd6d4
R13: 00000000004c2dcd R14: 00000000004d5158 R15: 00000000ffffffff

Showing all locks held in the system:
1 lock held by khungtaskd/1019:
#0: 000000002f15c297 (rcu_read_lock){....}, at:
debug_show_all_locks+0xd0/0x424 kernel/locking/lockdep.c:4379
1 lock held by rsyslogd/5978:
#0: 00000000d17ba87b (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x1bb/0x200
fs/file.c:766
2 locks held by getty/6068:
#0: 00000000b01c35f7 (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 000000005e000669 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
2 locks held by getty/6069:
#0: 000000009af9553e (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 000000004618fe72 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
2 locks held by getty/6070:
#0: 0000000041199f38 (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 000000002c7ad04a (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
2 locks held by getty/6071:
#0: 0000000099598d88 (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 000000009d2c62db (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
2 locks held by getty/6072:
#0: 000000000a5df98e (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 000000005304dc94 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
2 locks held by getty/6073:
#0: 000000007bee51fd (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 00000000b244e0fc (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
2 locks held by getty/6074:
#0: 0000000058e5150a (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 00000000ebe7779e (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
1 lock held by syz-executor0/23999:
#0: 000000004728983a (&bdev->bd_fsfreeze_mutex){+.+.}, at:
mount_bdev+0xa5/0x3e0 fs/super.c:1125
2 locks held by syz-executor0/24009:
2 locks held by syz-executor0/24040:
#0: 000000004728983a (&bdev->bd_fsfreeze_mutex){+.+.}, at:
mount_bdev+0xa5/0x3e0 fs/super.c:1125
#1: 00000000d0e6aa33 (&type->s_umount_key#82){+.+.}, at:
grab_super+0xcc/0x400 fs/super.c:383

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

NMI backtrace for cpu 1
CPU: 1 PID: 1019 Comm: khungtaskd Not tainted 4.20.0-rc6+ #150
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+0x244/0x39d lib/dump_stack.c:113
nmi_cpu_backtrace.cold.2+0x5c/0xa1 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x1e8/0x22a 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:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:205 [inline]
watchdog+0xb51/0x1060 kernel/hung_task.c:289
kthread+0x35a/0x440 kernel/kthread.c:246
ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352
Sending NMI from CPU 1 to CPUs 0:
INFO: NMI handler (nmi_cpu_backtrace_handler) took too long to run: 1.660
msecs
NMI backtrace for cpu 0
CPU: 0 PID: 24009 Comm: syz-executor0 Not tainted 4.20.0-rc6+ #150
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS
Google 01/01/2011
RIP: 0010:check_memory_region+0xc2/0x1b0 mm/kasan/kasan.c:268
Code: 0f 84 a8 00 00 00 48 83 38 00 74 ed 4c 8d 40 08 eb 09 48 83 c0 01 49
39 c0 74 0a 80 38 00 74 f2 48 85 c0 75 77 5b 41 5c 41 5d <5d> c3 41 bd 08
00 00 00 4d 89 c8 41 29 dd 4b 8d 5c 0d 00 eb 0c 49
RSP: 0018:ffff88817c7e6a78 EFLAGS: 00000246
RAX: ffffed103b5c5b60 RBX: 1ffff1102f8fcd54 RCX: ffffffff816a6b32
RDX: 0000000000000000 RSI: 0000000000000004 RDI: ffff8881dae2daf8
RBP: ffff88817c7e6a78 R08: ffffed103b5c5b60 R09: ffffed103b5c5b5f
R10: ffffed103b5c5b5f R11: ffff8881dae2dafb R12: ffff8881dae2da40
R13: ffff88817c7e6b00 R14: ffff8881dae2daf8 R15: ffff88817c7e6c10
FS: 00007f1a0b41e700(0000) GS:ffff8881dae00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: ffffffffff600400 CR3: 00000001cb6bb000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
kasan_check_read+0x11/0x20 mm/kasan/kasan.c:272
atomic_read include/asm-generic/atomic-instrumented.h:21 [inline]
rcu_dynticks_curr_cpu_in_eqs+0xa2/0x170 kernel/rcu/tree.c:292
rcu_is_watching+0x10/0x30 kernel/rcu/tree.c:906
rcu_read_lock_held+0x87/0xc0 kernel/rcu/update.c:277
xa_entry include/linux/xarray.h:848 [inline]
xas_descend+0x413/0x510 lib/xarray.c:198
xas_load+0x145/0x1e0 lib/xarray.c:234
find_get_entry+0x3ca/0x1120 mm/filemap.c:1415
pagecache_get_page+0x12f/0xf00 mm/filemap.c:1518
find_get_page_flags include/linux/pagemap.h:278 [inline]
__find_get_block_slow fs/buffer.c:205 [inline]
__find_get_block+0x63a/0xf20 fs/buffer.c:1294
__getblk_slow fs/buffer.c:1039 [inline]
__getblk_gfp+0x2b3/0xd50 fs/buffer.c:1320
__bread_gfp+0x2d/0x310 fs/buffer.c:1354
sb_bread include/linux/buffer_head.h:307 [inline]
isofs_fill_super+0x12d0/0x37e8 fs/isofs/inode.c:678
mount_bdev+0x30c/0x3e0 fs/super.c:1158
isofs_mount+0x34/0x40 fs/isofs/inode.c:1570
mount_fs+0xae/0x31d fs/super.c:1261
vfs_kern_mount.part.35+0xdc/0x4f0 fs/namespace.c:961
vfs_kern_mount fs/namespace.c:951 [inline]
do_new_mount fs/namespace.c:2469 [inline]
do_mount+0x581/0x31f0 fs/namespace.c:2801
ksys_mount+0x12d/0x140 fs/namespace.c:3017
__do_sys_mount fs/namespace.c:3031 [inline]
__se_sys_mount fs/namespace.c:3028 [inline]
__x64_sys_mount+0xbe/0x150 fs/namespace.c:3028
do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x457679
Code: fd b3 fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 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 0f 83 cb b3 fb ff c3 66 2e 0f 1f 84 00 00 00 00
RSP: 002b:00007f1a0b41dc78 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 0000000000457679
RDX: 0000000020000040 RSI: 0000000020000000 RDI: 0000000020000240
RBP: 000000000072bfa0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f1a0b41e6d4
R13: 00000000004c2dcd R14: 00000000004d5158 R15: 00000000ffffffff


---
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#bug-status-tracking for how to communicate with
syzbot.

syzbot

unread,
Dec 14, 2018, 10:35:11 AM12/14/18
to Tetsuo Handa, penguin...@i-love.sakura.ne.jp, syzkaller-upst...@googlegroups.com
> Stalling inside __getblk_gfp()...

> #syz dup: INFO: rcu detected stall in sys_creat

Your 'dup:' command is accepted, but please keep
syzkaller-upst...@googlegroups.com mailing list in CC next
time. It serves as a history of what happened with each bug report. Thank
you.

Reply all
Reply to author
Forward
0 new messages