INFO: task hung in packet_release

7 views
Skip to first unread message

syzbot

unread,
Sep 11, 2019, 1:52:07 AM9/11/19
to syzkaller...@googlegroups.com
Hello,

syzbot found the following crash on:

HEAD commit: ee809c7e Linux 4.19.72
git tree: linux-4.19.y
console output: https://syzkaller.appspot.com/x/log.txt?x=122ea6f6600000
kernel config: https://syzkaller.appspot.com/x/.config?x=ad6c5c98f4231da9
dashboard link: https://syzkaller.appspot.com/bug?extid=18255c0fcc5ec484f886
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+18255c...@syzkaller.appspotmail.com

protocol 88fb is buggy, dev hsr_slave_1
protocol 88fb is buggy, dev hsr_slave_0
protocol 88fb is buggy, dev hsr_slave_1
protocol 88fb is buggy, dev hsr_slave_0
protocol 88fb is buggy, dev hsr_slave_1
INFO: task syz-executor.0:30237 blocked for more than 140 seconds.
Not tainted 4.19.72 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.0 D28216 30237 7514 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2826 [inline]
__schedule+0x866/0x1dc0 kernel/sched/core.c:3474
schedule+0x92/0x1c0 kernel/sched/core.c:3518
exp_funnel_lock kernel/rcu/tree_exp.h:320 [inline]
_synchronize_rcu_expedited+0x6b6/0x7f0 kernel/rcu/tree_exp.h:667
synchronize_rcu_expedited kernel/rcu/tree_exp.h:795 [inline]
synchronize_rcu_expedited+0x35/0xb0 kernel/rcu/tree_exp.h:784
synchronize_net+0x3b/0x60 net/core/dev.c:9068
packet_release+0x99b/0xc60 net/packet/af_packet.c:3038
__sock_release+0xce/0x2a0 net/socket.c:579
sock_close+0x1b/0x30 net/socket.c:1140
__fput+0x2dd/0x8b0 fs/file_table.c:278
____fput+0x16/0x20 fs/file_table.c:309
task_work_run+0x145/0x1c0 kernel/task_work.c:113
tracehook_notify_resume include/linux/tracehook.h:193 [inline]
exit_to_usermode_loop+0x273/0x2c0 arch/x86/entry/common.c:167
prepare_exit_to_usermode arch/x86/entry/common.c:198 [inline]
syscall_return_slowpath arch/x86/entry/common.c:271 [inline]
do_syscall_64+0x53d/0x620 arch/x86/entry/common.c:296
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x4135d1
Code: 41 b9 b0 20 41 00 41 b8 0c 98 65 00 ba 05 00 00 00 bf 5c 38 44 00 ff
15 b5 a0 24 00 85 c0 0f 85 6f fd ff ff 31 c9 31 f6 41 b9 <b0> 20 41 00 41
b8 10 98 65 00 ba 04 00 00 00 bf 6b 38 44 00 ff 15
RSP: 002b:00007ffeb63c07f0 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000007 RCX: 00000000004135d1
RDX: 0000000000000000 RSI: 00000000000003ee RDI: 0000000000000006
RBP: 0000000000000001 R08: 00000000675503ee R09: 00000000675503f2
R10: 00007ffeb63c08d0 R11: 0000000000000293 R12: 000000000075c9a0
R13: 000000000075c9a0 R14: 0000000000761ed0 R15: ffffffffffffffff

Showing all locks held in the system:
1 lock held by khungtaskd/1038:
#0: 000000002dfbf6d2 (rcu_read_lock){....}, at:
debug_show_all_locks+0x5f/0x27e kernel/locking/lockdep.c:4435
3 locks held by rs:main Q:Reg/7345:
1 lock held by rsyslogd/7347:
2 locks held by getty/7469:
#0: 00000000b11ab27b (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
#1: 00000000d181be83 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7470:
#0: 00000000de22f9f6 (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
#1: 000000000f2f84b4 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7471:
#0: 00000000e81cfd67 (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
#1: 00000000849bf505 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7472:
#0: 00000000a2d3ac03 (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
#1: 00000000f7b9733a (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7473:
#0: 000000002cdc2236 (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
#1: 00000000dfbe4416 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7474:
#0: 00000000a1da4b1d (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
#1: 00000000b6fae3b4 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7475:
#0: 0000000013c0f7eb (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
#1: 000000004aeba1bc (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
1 lock held by syz-executor.0/30237:
#0: 000000009c0d8920 (&sb->s_type->i_mutex_key#11){+.+.}, at: inode_lock
include/linux/fs.h:747 [inline]
#0: 000000009c0d8920 (&sb->s_type->i_mutex_key#11){+.+.}, at:
__sock_release+0x89/0x2a0 net/socket.c:578

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

NMI backtrace for cpu 0
CPU: 0 PID: 1038 Comm: khungtaskd Not tainted 4.19.72 #0
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+0x172/0x1f0 lib/dump_stack.c:113
nmi_cpu_backtrace.cold+0x63/0xa4 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x1b0/0x1f8 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:203 [inline]
watchdog+0x9df/0xee0 kernel/hung_task.c:287
kthread+0x354/0x420 kernel/kthread.c:246
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 7345 Comm: rs:main Q:Reg Not tainted 4.19.72 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS
Google 01/01/2011
RIP: 0010:__sanitizer_cov_trace_pc+0x4/0x50 kernel/kcov.c:97
Code: ff ff e8 33 66 ea ff 48 c7 05 44 22 cd 08 00 00 00 00 e9 ca e9 ff ff
90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 55 48 89 e5 <48> 8b 75 08 65
48 8b 04 25 40 ee 01 00 65 8b 15 28 97 96 7e 81 e2
RSP: 0018:ffff888086baf408 EFLAGS: 00000296
RAX: ffff88808105a1c0 RBX: ffff88808105a1c0 RCX: ffff888086baf4e8
RDX: ffff888086baf4c8 RSI: ffff8880a91a8480 RDI: ffff8880a3854080
RBP: ffff888086baf408 R08: ffff88808105a1c0 R09: ffffed1015d24733
R10: ffffed1015d24732 R11: ffff8880ae923993 R12: ffff8880a3854080
R13: ffff8880a3854080 R14: 0000000000000000 R15: ffff888086baf4c8
FS: 00007fc78f260700(0000) GS:ffff8880ae900000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000719000 CR3: 00000000a012c000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
get_current arch/x86/include/asm/current.h:15 [inline]
blk_attempt_plug_merge+0x2d/0x310 block/blk-core.c:1902
blk_mq_make_request+0x63f/0x1de0 block/blk-mq.c:1837
generic_make_request+0x747/0x12d0 block/blk-core.c:2460
submit_bio+0xba/0x480 block/blk-core.c:2569
submit_bh_wbc+0x5dc/0x790 fs/buffer.c:3095
submit_bh fs/buffer.c:3101 [inline]
__sync_dirty_buffer+0x111/0x2e0 fs/buffer.c:3187
sync_dirty_buffer+0x1b/0x20 fs/buffer.c:3200
ext4_write_inode+0x475/0x510 fs/ext4/inode.c:5414
write_inode fs/fs-writeback.c:1227 [inline]
__writeback_single_inode+0xc74/0x12c0 fs/fs-writeback.c:1426
writeback_single_inode+0x2c1/0x420 fs/fs-writeback.c:1480
sync_inode fs/fs-writeback.c:2517 [inline]
sync_inode_metadata+0xa8/0xe0 fs/fs-writeback.c:2537
__generic_file_fsync+0x169/0x200 fs/libfs.c:988
ext4_sync_file+0x826/0x1450 fs/ext4/fsync.c:120
vfs_fsync_range+0x141/0x230 fs/sync.c:197
generic_write_sync include/linux/fs.h:2746 [inline]
ext4_file_write_iter+0x7a0/0x1060 fs/ext4/file.c:281
call_write_iter include/linux/fs.h:1820 [inline]
new_sync_write fs/read_write.c:474 [inline]
__vfs_write+0x587/0x810 fs/read_write.c:487
vfs_write+0x20c/0x560 fs/read_write.c:549
ksys_write+0x14f/0x2d0 fs/read_write.c:599
__do_sys_write fs/read_write.c:611 [inline]
__se_sys_write fs/read_write.c:608 [inline]
__x64_sys_write+0x73/0xb0 fs/read_write.c:608
do_syscall_64+0xfd/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fc790cbe19d
Code: d1 20 00 00 75 10 b8 01 00 00 00 0f 05 48 3d 01 f0 ff ff 73 31 c3 48
83 ec 08 e8 be fa ff ff 48 89 04 24 b8 01 00 00 00 0f 05 <48> 8b 3c 24 48
89 c2 e8 07 fb ff ff 48 89 d0 48 83 c4 08 48 3d 01
RSP: 002b:00007fc78f25f000 EFLAGS: 00000293 ORIG_RAX: 0000000000000001
RAX: ffffffffffffffda RBX: 000000000000003d RCX: 00007fc790cbe19d
RDX: 000000000000003d RSI: 0000000000715ce0 RDI: 0000000000000006
RBP: 0000000000715ce0 R08: 36373920205b203a R09: 5d3634383933332e
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000
R13: 00007fc78f25f480 R14: 000000000000000a R15: 0000000000715ae0


---
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,
Jan 8, 2020, 11:52:06 PM1/8/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