INFO: task hung in mon_bin_flush

4 views
Skip to first unread message

syzbot

unread,
Sep 27, 2018, 11:14:04 AM9/27/18
to syzkaller-upst...@googlegroups.com
Hello,

syzbot found the following crash on:

HEAD commit: 328c6333ba3d Merge branch 'x86-urgent-for-linus' of git://..
git tree: upstream
console output: https://syzkaller.appspot.com/x/log.txt?x=12f521f1400000
kernel config: https://syzkaller.appspot.com/x/.config?x=5fa12be50bca08d8
dashboard link: https://syzkaller.appspot.com/bug?extid=39e5c528a1ee11f941ca
compiler: gcc (GCC) 8.0.1 20180413 (experimental)
CC: [ar...@arndb.de gre...@linuxfoundation.org
jrdr....@gmail.com kees...@chromium.org kste...@linuxfoundation.org
linux-...@vger.kernel.org linu...@vger.kernel.org
vi...@zeniv.linux.org.uk zai...@redhat.com]

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

INFO: task syz-executor3:24192 blocked for more than 140 seconds.
Not tainted 4.19.0-rc4+ #250
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor3 D25912 24192 19726 0x00000000
Call Trace:
context_switch kernel/sched/core.c:2825 [inline]
__schedule+0x86c/0x1ed0 kernel/sched/core.c:3473
schedule+0xfe/0x460 kernel/sched/core.c:3517
schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3575
__mutex_lock_common kernel/locking/mutex.c:1002 [inline]
__mutex_lock+0xbe7/0x1700 kernel/locking/mutex.c:1072
mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:1087
mon_bin_flush+0x33/0x2d0 drivers/usb/mon/mon_bin.c:878
mon_bin_ioctl+0x744/0xe30 drivers/usb/mon/mon_bin.c:1054
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:501 [inline]
do_vfs_ioctl+0x1de/0x1720 fs/ioctl.c:685
ksys_ioctl+0xa9/0xd0 fs/ioctl.c:702
__do_sys_ioctl fs/ioctl.c:709 [inline]
__se_sys_ioctl fs/ioctl.c:707 [inline]
__x64_sys_ioctl+0x73/0xb0 fs/ioctl.c:707
do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x457679
Code: ff ff e8 5a 28 00 00 e9 45 fe ff ff cc cc cc cc cc 48 8b 4c 24 10 48
8b 11 64 48 8b 1c 25 f8 ff ff ff 8b b2 d8 00 00 00 8d 7e <ff> 89 ba d8 00
00 00 83 fe 01 75 13 0f b6 93 c0 00 00 00 84 d2 74
RSP: 002b:00007f31dcc15c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007f31dcc166d4 RCX: 0000000000457679
RDX: 0000000000000000 RSI: 0000000000009208 RDI: 0000000000000004
RBP: 000000000072bf00 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff
R13: 00000000004d0258 R14: 00000000004c0322 R15: 0000000000000000

Showing all locks held in the system:
1 lock held by khungtaskd/984:
#0: 0000000086ce3e28 (rcu_read_lock){....}, at:
debug_show_all_locks+0xd0/0x424 kernel/locking/lockdep.c:4435
2 locks held by rsyslogd/5217:
#0: 000000000292798d (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x1bb/0x200
fs/file.c:766
#1: 000000006f8df56c (&p->pi_lock){-.-.}, at:
clockevents_program_event+0x158/0x370 kernel/time/clockevents.c:336
2 locks held by getty/5307:
#0: 00000000c8a8a901 (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 00000000dc478a1e (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by getty/5308:
#0: 000000009a91ad6e (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 00000000a449808b (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by getty/5309:
#0: 00000000422924ec (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 000000008f6bce54 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by getty/5310:
#0: 00000000660afc60 (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 00000000c3eb6025 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by getty/5311:
#0: 000000004101e7ac (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 000000005ccee7e4 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by getty/5312:
#0: 000000007156c4b6 (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 000000004e3e580c (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
2 locks held by getty/5313:
#0: 000000005538ed29 (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 000000009062c2e0 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140
3 locks held by syz-executor3/19726:
1 lock held by syz-executor3/24191:
#0: 0000000049cb7caf (&rp->fetch_lock){+.+.}, at: mon_bin_read+0x60/0x640
drivers/usb/mon/mon_bin.c:813
1 lock held by syz-executor3/24192:
#0: 0000000049cb7caf (&rp->fetch_lock){+.+.}, at: mon_bin_flush+0x33/0x2d0
drivers/usb/mon/mon_bin.c:878
1 lock held by syz-executor0/30204:
#0: 0000000088e68833 (&rq->lock){-.-.}, at: rq_lock
kernel/sched/sched.h:1821 [inline]
#0: 0000000088e68833 (&rq->lock){-.-.}, at: __schedule+0x236/0x1ed0
kernel/sched/core.c:3411

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

NMI backtrace for cpu 0
CPU: 0 PID: 984 Comm: khungtaskd Not tainted 4.19.0-rc4+ #250
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+0x1c4/0x2b4 lib/dump_stack.c:113
nmi_cpu_backtrace.cold.3+0x63/0xa2 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x1b3/0x1ed 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:144 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:204 [inline]
watchdog+0xb3e/0x1050 kernel/hung_task.c:265
kthread+0x35a/0x420 kernel/kthread.c:246
ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:413
Sending NMI from CPU 0 to CPUs 1:
INFO: NMI handler (nmi_cpu_backtrace_handler) took too long to run: 1.088
msecs
NMI backtrace for cpu 1
CPU: 1 PID: 30223 Comm: syz-executor3 Not tainted 4.19.0-rc4+ #250
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS
Google 01/01/2011
RIP: 0010:__sanitizer_cov_trace_const_cmp4+0x11/0x20 kernel/kcov.c:188
Code: bf 03 00 00 00 48 89 e5 48 8b 4d 08 e8 88 fe ff ff 5d c3 66 0f 1f 44
00 00 55 89 f2 89 fe bf 05 00 00 00 48 89 e5 48 8b 4d 08 <e8> 6a fe ff ff
5d c3 0f 1f 84 00 00 00 00 00 55 48 89 f2 48 89 fe
RSP: 0018:ffff8801dad07d28 EFLAGS: 00000002
RAX: 0000000000040000 RBX: 0000000000000001 RCX: ffffffff8390876a
RDX: 0000000000000001 RSI: 0000000000000000 RDI: 0000000000000005
RBP: ffff8801dad07d28 R08: ffff88018b20c5c0 R09: 0000000000000016
R10: fffffbfff14fbc09 R11: 0000000000000001 R12: 0000000000000001
R13: ffffffff8844a100 R14: ffffffff8844a0c0 R15: 0000000000000000
FS: 00007f31dcc16700(0000) GS:ffff8801dad00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000000002001d000 CR3: 00000001d8a30000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
<IRQ>
check_preemption_disabled+0x3a/0x200 lib/smp_processor_id.c:15
debug_smp_processor_id+0x1c/0x20 lib/smp_processor_id.c:56
rcu_nmi_exit+0x88/0x280 kernel/rcu/tree.c:786
rcu_irq_exit+0xce/0x100 kernel/rcu/tree.c:839
irq_exit+0xbd/0x1c0 kernel/softirq.c:415
exiting_irq arch/x86/include/asm/apic.h:536 [inline]
smp_apic_timer_interrupt+0x1cb/0x760 arch/x86/kernel/apic/apic.c:1056
apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:864
</IRQ>
RIP: 0010:d_lru_add+0x0/0x120 fs/dcache.c:305
Code: 12 aa ff 84 db 74 c7 e8 be 11 aa ff 48 c7 c6 40 ee d4 81 4c 89 ef e8
0f d5 94 ff eb b1 48 89 df e8 95 72 ed ff eb 9b 0f 1f 00 <55> 48 89 e5 41
55 41 54 53 48 89 fb 48 83 ec 08 e8 8b 11 aa ff 48
RSP: 0018:ffff880191ab7730 EFLAGS: 00000212 ORIG_RAX: ffffffffffffff13
RAX: 0000000000040000 RBX: ffff8801c6a6c080 RCX: ffffc9000cc40000
RDX: 0000000000002e4d RSI: ffffffff81d56578 RDI: ffff8801c6a6c080
RBP: ffff880191ab77d0 R08: ffff88018b20c5c0 R09: 0000000000000000
R10: ffff88018b20ce40 R11: b9b52873e2e4fabe R12: 0000000000000000
R13: 1ffff10032356ee9 R14: ffffed0032356eed R15: 000000000020008c
dput+0x15/0x20 fs/dcache.c:828
path_put fs/namei.c:484 [inline]
terminate_walk+0x2d0/0x5e0 fs/namei.c:598
path_openat+0x1e0d/0x5160 fs/namei.c:3538
do_filp_open+0x255/0x380 fs/namei.c:3564
do_sys_open+0x568/0x700 fs/open.c:1063
__do_sys_open fs/open.c:1081 [inline]
__se_sys_open fs/open.c:1076 [inline]
__x64_sys_open+0x7e/0xc0 fs/open.c:1076
do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x4112d1
Code: 75 14 b8 02 00 00 00 0f 05 48 3d 01 f0 ff ff 0f 83 b4 17 00 00 c3 48
83 ec 08 e8 8a fa ff ff 48 89 04 24 b8 02 00 00 00 0f 05 <48> 8b 3c 24 48
89 c2 e8 d3 fa ff ff 48 89 d0 48 83 c4 08 48 3d 01
RSP: 002b:00007f31dcc15bb0 EFLAGS: 00000293 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 00007f31dcc166d4 RCX: 00000000004112d1
RDX: 00007f31dcc15bdd RSI: 0000000000000002 RDI: 00007f31dcc15bd0
RBP: 000000000072bf00 R08: 0000000000000000 R09: 000000000000000d
R10: 0000000000000002 R11: 0000000000000293 R12: 00000000ffffffff
R13: 00000000004d7ff8 R14: 00000000004c4e4e R15: 0000000000000000


---
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,
Mar 22, 2019, 10:11:03 AM3/22/19
to syzkaller-upst...@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