INFO: task hung in chmod_common

9 views
Skip to first unread message

syzbot

unread,
Apr 14, 2019, 5:30:15 AM4/14/19
to syzkaller-a...@googlegroups.com
Hello,

syzbot found the following crash on:

HEAD commit: 47bbcd6b ANDROID: Fix massive cpufreq_times memory leaks
git tree: android-4.9
console output: https://syzkaller.appspot.com/x/log.txt?x=1035bf94400000
kernel config: https://syzkaller.appspot.com/x/.config?x=f2dbd0c9dd968786
dashboard link: https://syzkaller.appspot.com/bug?extid=99c6c3ad163f238db13a
compiler: gcc (GCC) 8.0.1 20180413 (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+99c6c3...@syzkaller.appspotmail.com

INFO: task syz-executor5:15246 blocked for more than 140 seconds.
Not tainted 4.9.113-g47bbcd6 #10
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor5 D28504 15246 3879 0x00000004
ffff8801aae60000 0000000000000000 ffff8801d95bf480 ffff88019ea8b000
ffff8801db221c18 ffff88019cdc7a78 ffffffff839e909d ffffffff81235647
0000000000000000 ffff8801aae608c0 0000000600000007 ffff8801db2224e8
Call Trace:
[<ffffffff839ea69f>] schedule+0x7f/0x1b0 kernel/sched/core.c:3553
[<ffffffff839f5cd8>] __rwsem_down_write_failed_common
kernel/locking/rwsem-xadd.c:526 [inline]
[<ffffffff839f5cd8>] rwsem_down_write_failed+0x598/0x990
kernel/locking/rwsem-xadd.c:555
[<ffffffff81ee7587>] call_rwsem_down_write_failed+0x17/0x30
arch/x86/lib/rwsem.S:105
[<ffffffff839f3aac>] __down_write arch/x86/include/asm/rwsem.h:125 [inline]
[<ffffffff839f3aac>] down_write+0x5c/0xa0 kernel/locking/rwsem.c:54
[<ffffffff81568e21>] inode_lock include/linux/fs.h:766 [inline]
[<ffffffff81568e21>] chmod_common+0x171/0x450 fs/open.c:543
[<ffffffff8156ccfd>] SYSC_fchmodat fs/open.c:582 [inline]
[<ffffffff8156ccfd>] SyS_fchmodat fs/open.c:574 [inline]
[<ffffffff8156ccfd>] SYSC_chmod fs/open.c:594 [inline]
[<ffffffff8156ccfd>] SyS_chmod+0xad/0x130 fs/open.c:592
[<ffffffff81006316>] do_syscall_64+0x1a6/0x490 arch/x86/entry/common.c:282
[<ffffffff839f9f93>] entry_SYSCALL_64_after_swapgs+0x5d/0xdb

Showing all locks held in the system:
2 locks held by khungtaskd/518:
#0: (rcu_read_lock){......}, at: [<ffffffff8136675c>]
check_hung_uninterruptible_tasks kernel/hung_task.c:168 [inline]
#0: (rcu_read_lock){......}, at: [<ffffffff8136675c>]
watchdog+0x11c/0xa20 kernel/hung_task.c:239
#1: (tasklist_lock){.+.+..}, at: [<ffffffff81425de7>]
debug_show_all_locks+0x79/0x218 kernel/locking/lockdep.c:4336
2 locks held by rs:main Q:Reg/3678:
#0: (&f->f_pos_lock){+.+.+.}, at: [<ffffffff815d81ec>]
__fdget_pos+0xac/0xd0 fs/file.c:781
#1: (sb_writers#3){.+.+.+}, at: [<ffffffff81572cce>] file_start_write
include/linux/fs.h:2640 [inline]
#1: (sb_writers#3){.+.+.+}, at: [<ffffffff81572cce>]
vfs_write+0x3ae/0x530 fs/read_write.c:556
1 lock held by rsyslogd/3680:
#0: (&f->f_pos_lock){+.+.+.}, at: [<ffffffff815d81ec>]
__fdget_pos+0xac/0xd0 fs/file.c:781
2 locks held by getty/3776:
#0: (&tty->ldisc_sem){++++++}, at: [<ffffffff839f8142>]
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:367
#1: (&ldata->atomic_read_lock){+.+...}, at: [<ffffffff8211ee22>]
n_tty_read+0x202/0x16e0 drivers/tty/n_tty.c:2142
2 locks held by syz-executor5/15203:
#0: (sb_writers#18){.+.+.+}, at: [<ffffffff815e0a8f>] sb_start_write
include/linux/fs.h:1573 [inline]
#0: (sb_writers#18){.+.+.+}, at: [<ffffffff815e0a8f>]
mnt_want_write+0x3f/0xb0 fs/namespace.c:391
#1: (&sb->s_type->i_mutex_key#22){++++++}, at: [<ffffffff81568e21>]
inode_lock include/linux/fs.h:766 [inline]
#1: (&sb->s_type->i_mutex_key#22){++++++}, at: [<ffffffff81568e21>]
chmod_common+0x171/0x450 fs/open.c:543
1 lock held by syz-executor5/15245:
#0: (&type->s_umount_key#43/1){+.+.+.}, at: [<ffffffff8157b9f2>]
alloc_super fs/super.c:241 [inline]
#0: (&type->s_umount_key#43/1){+.+.+.}, at: [<ffffffff8157b9f2>]
sget_userns+0x552/0xba0 fs/super.c:503
2 locks held by syz-executor5/15246:
#0: (sb_writers#18){.+.+.+}, at: [<ffffffff815e0a8f>] sb_start_write
include/linux/fs.h:1573 [inline]
#0: (sb_writers#18){.+.+.+}, at: [<ffffffff815e0a8f>]
mnt_want_write+0x3f/0xb0 fs/namespace.c:391
#1: (&sb->s_type->i_mutex_key#22){++++++}, at: [<ffffffff81568e21>]
inode_lock include/linux/fs.h:766 [inline]
#1: (&sb->s_type->i_mutex_key#22){++++++}, at: [<ffffffff81568e21>]
chmod_common+0x171/0x450 fs/open.c:543

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

NMI backtrace for cpu 1
CPU: 1 PID: 518 Comm: khungtaskd Not tainted 4.9.113-g47bbcd6 #10
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS
Google 01/01/2011
ffff8801d898fd08 ffffffff81eb32a9 0000000000000000 0000000000000001
0000000000000001 0000000000000001 ffffffff810b9bd0 ffff8801d898fd40
ffffffff81ebe5a7 0000000000000001 0000000000000000 0000000000000003
Call Trace:
[<ffffffff81eb32a9>] __dump_stack lib/dump_stack.c:15 [inline]
[<ffffffff81eb32a9>] dump_stack+0xc1/0x128 lib/dump_stack.c:51
[<ffffffff81ebe5a7>] nmi_cpu_backtrace.cold.2+0x48/0x87
lib/nmi_backtrace.c:99
[<ffffffff81ebe53a>] nmi_trigger_cpumask_backtrace+0x12a/0x14f
lib/nmi_backtrace.c:60
[<ffffffff810b9cd4>] arch_trigger_cpumask_backtrace+0x14/0x20
arch/x86/kernel/apic/hw_nmi.c:37
[<ffffffff81366cf4>] trigger_all_cpu_backtrace include/linux/nmi.h:58
[inline]
[<ffffffff81366cf4>] check_hung_task kernel/hung_task.c:125 [inline]
[<ffffffff81366cf4>] check_hung_uninterruptible_tasks
kernel/hung_task.c:182 [inline]
[<ffffffff81366cf4>] watchdog+0x6b4/0xa20 kernel/hung_task.c:239
[<ffffffff8119d0ad>] kthread+0x26d/0x300 kernel/kthread.c:211
[<ffffffff839fa15c>] ret_from_fork+0x5c/0x70 arch/x86/entry/entry_64.S:373
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.9.113-g47bbcd6 #10
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS
Google 01/01/2011
task: ffffffff84629800 task.stack: ffffffff84600000
RIP: 0010:[<ffffffff81eb2d00>] c [<ffffffff81eb2d00>]
cpumask_next_and+0x30/0xb0 lib/cpumask.c:19
RSP: 0018:ffff8801db207be0 EFLAGS: 00000202
RAX: 1ffff1003b3227d6 RBX: 00000000ffffffff RCX: 00000000ffffffff
RDX: ffff8801db2185d8 RSI: ffff8801d9913ec0 RDI: 00000000ffffffff
RBP: ffff8801db207c10 R08: ffff8801db207e48 R09: dffffc0000000000
R10: fffffbfff08c1200 R11: 0000000000000001 R12: 0000000000000007
R13: 1ffffffff098bfa8 R14: ffff8801db207d88 R15: ffff8801db2185d8
FS: 0000000000000000(0000) GS:ffff8801db200000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fd50b29e000 CR3: 00000001cbbf9000 CR4: 00000000001606f0
DR0: 0000000020000000 DR1: 0000000020000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600
Stack:
ffff8801d9913ec0 c 0000000000000007 c ffff8801d9913ea0 c dffffc0000000000 c
ffff8801db207d88 c ffff8801d9913f00 c ffff8801db207db0 c ffffffff8120307a c
1ffff1003b640f86 c 0000000000000000 c 0000000041b58ab3 c ffffffff843c3fd0 c
Call Trace:
<IRQ> d [<ffffffff8120307a>] should_we_balance kernel/sched/fair.c:9412
[inline]
<IRQ> d [<ffffffff8120307a>] load_balance+0x33a/0x2fa0
kernel/sched/fair.c:9469
[<ffffffff812081ce>] rebalance_domains+0x6be/0xbc0
kernel/sched/fair.c:10115
[<ffffffff812089c9>] run_rebalance_domains+0x2f9/0x510
kernel/sched/fair.c:10333
[<ffffffff83a00b7b>] __do_softirq+0x20b/0x937 kernel/softirq.c:284
[<ffffffff8114b287>] invoke_softirq kernel/softirq.c:364 [inline]
[<ffffffff8114b287>] irq_exit+0x147/0x190 kernel/softirq.c:405
[<ffffffff839fe721>] smp_reschedule_interrupt+0x71/0x90
arch/x86/kernel/smp.c:270
[<ffffffff839fc290>] reschedule_interrupt+0xa0/0xb0
arch/x86/entry/entry_64.S:671
<EOI> d [<ffffffff839f8ba6>] ? native_safe_halt+0x6/0x10
arch/x86/include/asm/irqflags.h:53
[<ffffffff839f81e5>] arch_safe_halt arch/x86/include/asm/paravirt.h:104
[inline]
[<ffffffff839f81e5>] default_idle+0x55/0x360 arch/x86/kernel/process.c:437
[<ffffffff8106ae00>] arch_cpu_idle+0x10/0x20 arch/x86/kernel/process.c:428
[<ffffffff839f9005>] default_idle_call+0x45/0x60 kernel/sched/idle.c:97
[<ffffffff812231d5>] cpuidle_idle_call kernel/sched/idle.c:155 [inline]
[<ffffffff812231d5>] cpu_idle_loop kernel/sched/idle.c:248 [inline]
[<ffffffff812231d5>] cpu_startup_entry+0x2b5/0x380 kernel/sched/idle.c:303
[<ffffffff839e5e6c>] rest_init+0x183/0x189 init/main.c:409
[<ffffffff84c97943>] start_kernel+0x67e/0x6b2 init/main.c:664
[<ffffffff84c9629a>] x86_64_start_reservations+0x29/0x2b
arch/x86/kernel/head64.c:196
[<ffffffff84c963db>] x86_64_start_kernel+0x13f/0x162
arch/x86/kernel/head64.c:177
Code: c57 c49 c89 cd7 c41 c56 c41 c55 c41 c54 c49 cc7 cc4 c44
cfd cc5 c84 c53 c4d c89 ce5 c89 cfb c41 c83 ce4 c07 c48 c83
cec c08 c49 cc1 ced c03 c48 c89 c75 cd0 c41 c83 cc4 c03
c<e8> c6b cbd c4a cff c48 cb8 c00 c00 c00 c00 c00 cfc cff
cdf c49 c01 cc5 ceb c0e ce8 c


---
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,
Jul 28, 2019, 11:36:04 AM7/28/19
to syzkaller-a...@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