INFO: task hung in filename_create

8 views
Skip to first unread message

syzbot

unread,
Dec 11, 2019, 10:32:14 PM12/11/19
to syzkaller...@googlegroups.com
Hello,

syzbot found the following crash on:

HEAD commit: a844dc4c Linux 4.14.158
git tree: linux-4.14.y
console output: https://syzkaller.appspot.com/x/log.txt?x=12aa9edee00000
kernel config: https://syzkaller.appspot.com/x/.config?x=c02bef505ffc02ff
dashboard link: https://syzkaller.appspot.com/bug?extid=b39e4286c56db97c92f4
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+b39e42...@syzkaller.appspotmail.com

Bearer <udp:syz0> rejected, not supported in standalone mode
Bearer <udp:syz0> rejected, not supported in standalone mode
INFO: task syz-executor.2:24907 blocked for more than 140 seconds.
Not tainted 4.14.158-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2 D30112 24907 7085 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2808 [inline]
__schedule+0x7b8/0x1cd0 kernel/sched/core.c:3384
schedule+0x92/0x1c0 kernel/sched/core.c:3428
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:588 [inline]
rwsem_down_write_failed+0x5ce/0xb50 kernel/locking/rwsem-xadd.c:617
call_rwsem_down_write_failed+0x17/0x30 arch/x86/lib/rwsem.S:105
__down_write arch/x86/include/asm/rwsem.h:126 [inline]
down_write_nested+0x57/0x90 kernel/locking/rwsem.c:174
inode_lock_nested include/linux/fs.h:753 [inline]
filename_create+0x141/0x430 fs/namei.c:3671
user_path_create fs/namei.c:3729 [inline]
SYSC_mkdirat fs/namei.c:3861 [inline]
SyS_mkdirat fs/namei.c:3853 [inline]
SYSC_mkdir fs/namei.c:3880 [inline]
SyS_mkdir+0x92/0x200 fs/namei.c:3878
do_syscall_64+0x1e8/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a849
RSP: 002b:00007f25be9aec78 EFLAGS: 00000246 ORIG_RAX: 0000000000000053
RAX: ffffffffffffffda RBX: 0000000000000002 RCX: 000000000045a849
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000180
RBP: 000000000075bfc8 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f25be9af6d4
R13: 00000000004c0244 R14: 00000000004de7f0 R15: 00000000ffffffff
INFO: task syz-executor.2:24908 blocked for more than 140 seconds.
Not tainted 4.14.158-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2 D29520 24908 7085 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2808 [inline]
__schedule+0x7b8/0x1cd0 kernel/sched/core.c:3384
schedule+0x92/0x1c0 kernel/sched/core.c:3428
__rwsem_down_read_failed_common kernel/locking/rwsem-xadd.c:292 [inline]
rwsem_down_read_failed+0x1f6/0x390 kernel/locking/rwsem-xadd.c:309
call_rwsem_down_read_failed+0x18/0x30 arch/x86/lib/rwsem.S:94
__down_read arch/x86/include/asm/rwsem.h:66 [inline]
down_read+0x49/0xb0 kernel/locking/rwsem.c:26
inode_lock_shared include/linux/fs.h:728 [inline]
lookup_slow+0x138/0x440 fs/namei.c:1673
walk_component+0x698/0x1d00 fs/namei.c:1824
lookup_last fs/namei.c:2292 [inline]
path_lookupat.isra.0+0x1bb/0x7b0 fs/namei.c:2342
filename_lookup+0x18e/0x380 fs/namei.c:2376
user_path_at_empty+0x43/0x50 fs/namei.c:2630
user_path_at include/linux/namei.h:57 [inline]
SYSC_chdir fs/open.c:459 [inline]
SyS_chdir+0x86/0x190 fs/open.c:453
do_syscall_64+0x1e8/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a849
RSP: 002b:00007f25be98dc78 EFLAGS: 00000246 ORIG_RAX: 0000000000000050
RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 000000000045a849
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000180
RBP: 000000000075c070 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f25be98e6d4
R13: 00000000004c02ad R14: 00000000004d4b30 R15: 00000000ffffffff
INFO: task syz-executor.2:24909 blocked for more than 140 seconds.
Not tainted 4.14.158-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2 D29344 24909 7085 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2808 [inline]
__schedule+0x7b8/0x1cd0 kernel/sched/core.c:3384
schedule+0x92/0x1c0 kernel/sched/core.c:3428
__rwsem_down_read_failed_common kernel/locking/rwsem-xadd.c:292 [inline]
rwsem_down_read_failed+0x1f6/0x390 kernel/locking/rwsem-xadd.c:309
call_rwsem_down_read_failed+0x18/0x30 arch/x86/lib/rwsem.S:94
__down_read arch/x86/include/asm/rwsem.h:66 [inline]
down_read+0x49/0xb0 kernel/locking/rwsem.c:26
inode_lock_shared include/linux/fs.h:728 [inline]
lookup_slow+0x138/0x440 fs/namei.c:1673
walk_component+0x698/0x1d00 fs/namei.c:1824
lookup_last fs/namei.c:2292 [inline]
path_lookupat.isra.0+0x1bb/0x7b0 fs/namei.c:2342
filename_lookup+0x18e/0x380 fs/namei.c:2376
user_path_at_empty+0x43/0x50 fs/namei.c:2630
user_path include/linux/namei.h:62 [inline]
do_mount+0x12b/0x27d0 fs/namespace.c:2819
SYSC_mount fs/namespace.c:3095 [inline]
SyS_mount+0xab/0x120 fs/namespace.c:3072
do_syscall_64+0x1e8/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a849
RSP: 002b:00007f25be96cc78 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 000000000045a849
RDX: 0000000020000100 RSI: 0000000020000000 RDI: 0000000000000000
RBP: 000000000075c118 R08: 0000000020000340 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f25be96d6d4
R13: 00000000004c7c02 R14: 00000000004deac0 R15: 00000000ffffffff
INFO: task syz-executor.2:24912 blocked for more than 140 seconds.
Not tainted 4.14.158-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2 D30096 24912 7085 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2808 [inline]
__schedule+0x7b8/0x1cd0 kernel/sched/core.c:3384
schedule+0x92/0x1c0 kernel/sched/core.c:3428
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:588 [inline]
rwsem_down_write_failed+0x5ce/0xb50 kernel/locking/rwsem-xadd.c:617
call_rwsem_down_write_failed+0x17/0x30 arch/x86/lib/rwsem.S:105
__down_write arch/x86/include/asm/rwsem.h:126 [inline]
down_write_nested+0x57/0x90 kernel/locking/rwsem.c:174
inode_lock_nested include/linux/fs.h:753 [inline]
filename_create+0x141/0x430 fs/namei.c:3671
user_path_create fs/namei.c:3729 [inline]
SYSC_symlinkat fs/namei.c:4173 [inline]
SyS_symlinkat fs/namei.c:4160 [inline]
SYSC_symlink fs/namei.c:4193 [inline]
SyS_symlink+0xc4/0x210 fs/namei.c:4191
do_syscall_64+0x1e8/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a849
RSP: 002b:00007f25be94bc78 EFLAGS: 00000246 ORIG_RAX: 0000000000000058
RAX: ffffffffffffffda RBX: 0000000000000002 RCX: 000000000045a849
RDX: 0000000000000000 RSI: 00000000200000c0 RDI: 0000000020001000
RBP: 000000000075c1c0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f25be94c6d4
R13: 00000000004caa3d R14: 00000000004e3dd0 R15: 00000000ffffffff

Showing all locks held in the system:
1 lock held by khungtaskd/1045:
#0: (tasklist_lock){.+.+}, at: [<ffffffff8148c8a8>]
debug_show_all_locks+0x7f/0x21f kernel/locking/lockdep.c:4544
2 locks held by getty/7036:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff866549c3>]
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:376
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff83490d76>]
n_tty_read+0x1e6/0x17b0 drivers/tty/n_tty.c:2156
2 locks held by getty/7037:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff866549c3>]
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:376
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff83490d76>]
n_tty_read+0x1e6/0x17b0 drivers/tty/n_tty.c:2156
2 locks held by getty/7038:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff866549c3>]
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:376
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff83490d76>]
n_tty_read+0x1e6/0x17b0 drivers/tty/n_tty.c:2156
2 locks held by getty/7039:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff866549c3>]
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:376
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff83490d76>]
n_tty_read+0x1e6/0x17b0 drivers/tty/n_tty.c:2156
2 locks held by getty/7040:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff866549c3>]
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:376
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff83490d76>]
n_tty_read+0x1e6/0x17b0 drivers/tty/n_tty.c:2156
2 locks held by getty/7041:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff866549c3>]
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:376
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff83490d76>]
n_tty_read+0x1e6/0x17b0 drivers/tty/n_tty.c:2156
2 locks held by getty/7042:
#0: (&tty->ldisc_sem){++++}, at: [<ffffffff866549c3>]
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:376
#1: (&ldata->atomic_read_lock){+.+.}, at: [<ffffffff83490d76>]
n_tty_read+0x1e6/0x17b0 drivers/tty/n_tty.c:2156
2 locks held by syz-executor.2/24867:
#0: (sb_writers#18){.+.+}, at: [<ffffffff8196fd1f>] sb_start_write
include/linux/fs.h:1548 [inline]
#0: (sb_writers#18){.+.+}, at: [<ffffffff8196fd1f>]
mnt_want_write+0x3f/0xb0 fs/namespace.c:386
#1: (&sb->s_type->i_mutex_key#25/1){+.+.}, at: [<ffffffff81934c41>]
inode_lock_nested include/linux/fs.h:753 [inline]
#1: (&sb->s_type->i_mutex_key#25/1){+.+.}, at: [<ffffffff81934c41>]
filename_create+0x141/0x430 fs/namei.c:3671
2 locks held by syz-executor.2/24907:
#0: (sb_writers#18){.+.+}, at: [<ffffffff8196fd1f>] sb_start_write
include/linux/fs.h:1548 [inline]
#0: (sb_writers#18){.+.+}, at: [<ffffffff8196fd1f>]
mnt_want_write+0x3f/0xb0 fs/namespace.c:386
#1: (&sb->s_type->i_mutex_key#25/1){+.+.}, at: [<ffffffff81934c41>]
inode_lock_nested include/linux/fs.h:753 [inline]
#1: (&sb->s_type->i_mutex_key#25/1){+.+.}, at: [<ffffffff81934c41>]
filename_create+0x141/0x430 fs/namei.c:3671
1 lock held by syz-executor.2/24908:
#0: (&sb->s_type->i_mutex_key#26){++++}, at: [<ffffffff8191f8d8>]
inode_lock_shared include/linux/fs.h:728 [inline]
#0: (&sb->s_type->i_mutex_key#26){++++}, at: [<ffffffff8191f8d8>]
lookup_slow+0x138/0x440 fs/namei.c:1673
1 lock held by syz-executor.2/24909:
#0: (&sb->s_type->i_mutex_key#26){++++}, at: [<ffffffff8191f8d8>]
inode_lock_shared include/linux/fs.h:728 [inline]
#0: (&sb->s_type->i_mutex_key#26){++++}, at: [<ffffffff8191f8d8>]
lookup_slow+0x138/0x440 fs/namei.c:1673
2 locks held by syz-executor.2/24912:
#0: (sb_writers#18){.+.+}, at: [<ffffffff8196fd1f>] sb_start_write
include/linux/fs.h:1548 [inline]
#0: (sb_writers#18){.+.+}, at: [<ffffffff8196fd1f>]
mnt_want_write+0x3f/0xb0 fs/namespace.c:386
#1: (&sb->s_type->i_mutex_key#25/1){+.+.}, at: [<ffffffff81934c41>]
inode_lock_nested include/linux/fs.h:753 [inline]
#1: (&sb->s_type->i_mutex_key#25/1){+.+.}, at: [<ffffffff81934c41>]
filename_create+0x141/0x430 fs/namei.c:3671

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

NMI backtrace for cpu 0
CPU: 0 PID: 1045 Comm: khungtaskd Not tainted 4.14.158-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+0x142/0x197 lib/dump_stack.c:58
nmi_cpu_backtrace.cold+0x57/0x94 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x141/0x189 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:140 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:195 [inline]
watchdog+0x5e7/0xb90 kernel/hung_task.c:274
kthread+0x319/0x430 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: 31 Comm: kworker/u4:2 Not tainted 4.14.158-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS
Google 01/01/2011
Workqueue: bat_events batadv_nc_worker
task: ffff8880a9f82100 task.stack: ffff8880a9f88000
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:779
[inline]
RIP: 0010:lock_release+0x44d/0x940 kernel/locking/lockdep.c:4016
RSP: 0018:ffff8880a9f8fbd0 EFLAGS: 00000282
RAX: 1ffffffff0fe2d29 RBX: 1ffff110153f1f80 RCX: 1ffff110153f052e
RDX: dffffc0000000000 RSI: 0000000000000003 RDI: 0000000000000282
RBP: ffff8880a9f8fc68 R08: ffff8880a9f82100 R09: 0000000000000002
R10: 0000000000000000 R11: ffff8880a9f82100 R12: ffff8880a9f82100
R13: ffffffff864c8a0b R14: 0000000000000003 R15: ffff8880a9f8fc40
FS: 0000000000000000(0000) GS:ffff8880aed00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000000c423f44c98 CR3: 000000008e66f000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
rcu_lock_release include/linux/rcupdate.h:247 [inline]
rcu_read_unlock include/linux/rcupdate.h:685 [inline]
batadv_nc_process_nc_paths.part.0+0x222/0x340
net/batman-adv/network-coding.c:707
batadv_nc_process_nc_paths net/batman-adv/network-coding.c:687 [inline]
batadv_nc_worker+0x4bb/0x6d0 net/batman-adv/network-coding.c:735
process_one_work+0x863/0x1600 kernel/workqueue.c:2114
worker_thread+0x5d9/0x1050 kernel/workqueue.c:2248
kthread+0x319/0x430 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
Code: 24 7c 08 00 00 00 00 00 00 48 c1 e8 03 80 3c 10 00 0f 85 36 03 00 00
48 83 3d e7 cb a8 06 00 0f 84 bf 01 00 00 48 8b 7d 88 57 9d <0f> 1f 44 00
00 48 b8 00 00 00 00 00 fc ff df 48 c7 04 03 00 00


---
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 14, 2020, 12:42:11 AM4/14/20
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