INFO: task hung in get_info

10 views
Skip to first unread message

syzbot

unread,
Jan 21, 2021, 1:12:25 AM1/21/21
to syzkaller...@googlegroups.com
Hello,

syzbot found the following issue on:

HEAD commit: 43d555d8 Linux 4.19.169
git tree: linux-4.19.y
console output: https://syzkaller.appspot.com/x/log.txt?x=16e40900d00000
kernel config: https://syzkaller.appspot.com/x/.config?x=2c45e5cf79a63e8f
dashboard link: https://syzkaller.appspot.com/bug?extid=240f556416233f5f94bc
compiler: gcc (GCC) 10.1.0-syz 20200507
syz repro: https://syzkaller.appspot.com/x/repro.syz?x=16d31b84d00000
C reproducer: https://syzkaller.appspot.com/x/repro.c?x=139387e7500000

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+240f55...@syzkaller.appspotmail.com

neighbour: ndisc_cache: neighbor table overflow!
INFO: task syz-executor976:8125 blocked for more than 140 seconds.
Not tainted 4.19.169-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor976 D27704 8125 8122 0x00000000
Call Trace:
context_switch kernel/sched/core.c:2828 [inline]
__schedule+0x887/0x2040 kernel/sched/core.c:3517
schedule+0x8d/0x1b0 kernel/sched/core.c:3561
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
__mutex_lock_common kernel/locking/mutex.c:1002 [inline]
__mutex_lock+0x647/0x1260 kernel/locking/mutex.c:1072
xt_find_table_lock+0x3d/0x430 net/netfilter/x_tables.c:1211
xt_request_find_table_lock+0x27/0xd0 net/netfilter/x_tables.c:1258
get_info+0x12f/0x4e0 net/ipv6/netfilter/ip6_tables.c:984
do_ip6t_get_ctl+0x14e/0x910 net/ipv6/netfilter/ip6_tables.c:1708
nf_sockopt net/netfilter/nf_sockopt.c:104 [inline]
nf_getsockopt+0x72/0xd0 net/netfilter/nf_sockopt.c:122
ipv6_getsockopt+0x1b6/0x260 net/ipv6/ipv6_sockglue.c:1388
tcp_getsockopt+0x86/0xd0 net/ipv4/tcp.c:3618
__sys_getsockopt+0x135/0x210 net/socket.c:1938
__do_sys_getsockopt net/socket.c:1949 [inline]
__se_sys_getsockopt net/socket.c:1946 [inline]
__x64_sys_getsockopt+0xba/0x150 net/socket.c:1946
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44a24a
Code: Bad RIP value.
RSP: 002b:00007ffea4551788 EFLAGS: 00000246 ORIG_RAX: 0000000000000037
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000044a24a
RDX: 0000000000000040 RSI: 0000000000000029 RDI: 0000000000000003
RBP: 00007ffea45518b0 R08: 00007ffea45517ac R09: 0000000000004000
R10: 00007ffea45517b0 R11: 0000000000000246 R12: 00007ffea45517b0
R13: 00000000006ded60 R14: 0000000000000029 R15: 00000000006dcba0
INFO: task syz-executor976:8128 blocked for more than 140 seconds.
Not tainted 4.19.169-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor976 D26880 8128 8124 0x00000000
Call Trace:
context_switch kernel/sched/core.c:2828 [inline]
__schedule+0x887/0x2040 kernel/sched/core.c:3517
schedule+0x8d/0x1b0 kernel/sched/core.c:3561
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
__mutex_lock_common kernel/locking/mutex.c:1002 [inline]
__mutex_lock+0x647/0x1260 kernel/locking/mutex.c:1072
xt_find_table_lock+0x3d/0x430 net/netfilter/x_tables.c:1211
xt_request_find_table_lock+0x27/0xd0 net/netfilter/x_tables.c:1258
get_info+0x12f/0x4e0 net/ipv6/netfilter/ip6_tables.c:984
do_ip6t_get_ctl+0x14e/0x910 net/ipv6/netfilter/ip6_tables.c:1708
nf_sockopt net/netfilter/nf_sockopt.c:104 [inline]
nf_getsockopt+0x72/0xd0 net/netfilter/nf_sockopt.c:122
ipv6_getsockopt+0x1b6/0x260 net/ipv6/ipv6_sockglue.c:1388
tcp_getsockopt+0x86/0xd0 net/ipv4/tcp.c:3618
__sys_getsockopt+0x135/0x210 net/socket.c:1938
__do_sys_getsockopt net/socket.c:1949 [inline]
__se_sys_getsockopt net/socket.c:1946 [inline]
__x64_sys_getsockopt+0xba/0x150 net/socket.c:1946
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44a24a
Code: Bad RIP value.
RSP: 002b:00007ffea4551788 EFLAGS: 00000246 ORIG_RAX: 0000000000000037
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000044a24a
RDX: 0000000000000040 RSI: 0000000000000029 RDI: 0000000000000003
RBP: 00007ffea45518b0 R08: 00007ffea45517ac R09: 0000000000004000
R10: 00007ffea45517b0 R11: 0000000000000246 R12: 00007ffea45517b0
R13: 00000000006ded60 R14: 0000000000000029 R15: 00000000006dcba0
INFO: task syz-executor976:8130 blocked for more than 140 seconds.
Not tainted 4.19.169-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor976 D27296 8130 8126 0x00000000
Call Trace:
context_switch kernel/sched/core.c:2828 [inline]
__schedule+0x887/0x2040 kernel/sched/core.c:3517
schedule+0x8d/0x1b0 kernel/sched/core.c:3561
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
__mutex_lock_common kernel/locking/mutex.c:1002 [inline]
__mutex_lock+0x647/0x1260 kernel/locking/mutex.c:1072
xt_find_table_lock+0x3d/0x430 net/netfilter/x_tables.c:1211
xt_request_find_table_lock+0x27/0xd0 net/netfilter/x_tables.c:1258
get_info+0x12f/0x4e0 net/ipv6/netfilter/ip6_tables.c:984
do_ip6t_get_ctl+0x14e/0x910 net/ipv6/netfilter/ip6_tables.c:1708
nf_sockopt net/netfilter/nf_sockopt.c:104 [inline]
nf_getsockopt+0x72/0xd0 net/netfilter/nf_sockopt.c:122
ipv6_getsockopt+0x1b6/0x260 net/ipv6/ipv6_sockglue.c:1388
tcp_getsockopt+0x86/0xd0 net/ipv4/tcp.c:3618
__sys_getsockopt+0x135/0x210 net/socket.c:1938
__do_sys_getsockopt net/socket.c:1949 [inline]
__se_sys_getsockopt net/socket.c:1946 [inline]
__x64_sys_getsockopt+0xba/0x150 net/socket.c:1946
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44a24a
Code: Bad RIP value.
RSP: 002b:00007ffea4551788 EFLAGS: 00000246 ORIG_RAX: 0000000000000037
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000044a24a
RDX: 0000000000000040 RSI: 0000000000000029 RDI: 0000000000000003
RBP: 00007ffea45518b0 R08: 00007ffea45517ac R09: 0000000000004000
R10: 00007ffea45517b0 R11: 0000000000000246 R12: 00007ffea45517b0
R13: 00000000006ded60 R14: 0000000000000029 R15: 00000000006dcba0
INFO: task syz-executor976:8132 blocked for more than 140 seconds.
Not tainted 4.19.169-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor976 D27704 8132 8123 0x00000000
Call Trace:
context_switch kernel/sched/core.c:2828 [inline]
__schedule+0x887/0x2040 kernel/sched/core.c:3517
schedule+0x8d/0x1b0 kernel/sched/core.c:3561
exp_funnel_lock kernel/rcu/tree_exp.h:320 [inline]
_synchronize_rcu_expedited+0x60c/0x6f0 kernel/rcu/tree_exp.h:667
synchronize_rcu+0xc6/0x160 kernel/rcu/tree_plugin.h:818
xt_replace_table+0x2ff/0x640 net/netfilter/x_tables.c:1394
__do_replace+0x1d7/0x870 net/ipv6/netfilter/ip6_tables.c:1087
do_replace net/ipv6/netfilter/ip6_tables.c:1160 [inline]
do_ip6t_set_ctl+0x2d2/0x430 net/ipv6/netfilter/ip6_tables.c:1684
nf_sockopt net/netfilter/nf_sockopt.c:106 [inline]
nf_setsockopt+0x6f/0xc0 net/netfilter/nf_sockopt.c:115
ipv6_setsockopt+0x103/0x160 net/ipv6/ipv6_sockglue.c:945
tcp_setsockopt+0x86/0xd0 net/ipv4/tcp.c:3097
__sys_setsockopt+0x14d/0x240 net/socket.c:1901
__do_sys_setsockopt net/socket.c:1912 [inline]
__se_sys_setsockopt net/socket.c:1909 [inline]
__x64_sys_setsockopt+0xba/0x150 net/socket.c:1909
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44a26a
Code: Bad RIP value.
RSP: 002b:00007ffea4551788 EFLAGS: 00000282 ORIG_RAX: 0000000000000036
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000044a26a
RDX: 0000000000000040 RSI: 0000000000000029 RDI: 0000000000000003
RBP: 00007ffea45518b0 R08: 00000000000003b8 R09: 0000000000004000
R10: 00000000006dcb40 R11: 0000000000000282 R12: 00007ffea45517b0
R13: 00000000006ded60 R14: 0000000000000029 R15: 00000000006dcba0
INFO: task syz-executor976:8133 blocked for more than 140 seconds.
Not tainted 4.19.169-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor976 D27832 8133 8127 0x00000000
Call Trace:
context_switch kernel/sched/core.c:2828 [inline]
__schedule+0x887/0x2040 kernel/sched/core.c:3517
schedule+0x8d/0x1b0 kernel/sched/core.c:3561
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
__mutex_lock_common kernel/locking/mutex.c:1002 [inline]
__mutex_lock+0x647/0x1260 kernel/locking/mutex.c:1072
xt_find_table_lock+0x3d/0x430 net/netfilter/x_tables.c:1211
xt_request_find_table_lock+0x27/0xd0 net/netfilter/x_tables.c:1258
get_info+0x12f/0x4e0 net/ipv6/netfilter/ip6_tables.c:984
do_ip6t_get_ctl+0x14e/0x910 net/ipv6/netfilter/ip6_tables.c:1708
nf_sockopt net/netfilter/nf_sockopt.c:104 [inline]
nf_getsockopt+0x72/0xd0 net/netfilter/nf_sockopt.c:122
ipv6_getsockopt+0x1b6/0x260 net/ipv6/ipv6_sockglue.c:1388
tcp_getsockopt+0x86/0xd0 net/ipv4/tcp.c:3618
__sys_getsockopt+0x135/0x210 net/socket.c:1938
__do_sys_getsockopt net/socket.c:1949 [inline]
__se_sys_getsockopt net/socket.c:1946 [inline]
__x64_sys_getsockopt+0xba/0x150 net/socket.c:1946
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44a24a
Code: Bad RIP value.
RSP: 002b:00007ffea4551788 EFLAGS: 00000246 ORIG_RAX: 0000000000000037
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000044a24a
RDX: 0000000000000040 RSI: 0000000000000029 RDI: 0000000000000003
RBP: 00007ffea45518b0 R08: 00007ffea45517ac R09: 0000000000004000
R10: 00007ffea45517b0 R11: 0000000000000246 R12: 00007ffea45517b0
R13: 00000000006ded60 R14: 0000000000000029 R15: 00000000006dcba0
INFO: task syz-executor976:11330 blocked for more than 140 seconds.
Not tainted 4.19.169-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor976 D27344 11330 8131 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2828 [inline]
__schedule+0x887/0x2040 kernel/sched/core.c:3517
schedule+0x8d/0x1b0 kernel/sched/core.c:3561
exp_funnel_lock kernel/rcu/tree_exp.h:320 [inline]
_synchronize_rcu_expedited+0x60c/0x6f0 kernel/rcu/tree_exp.h:667
synchronize_net+0x37/0x50 net/core/dev.c:9266
__unregister_prot_hook+0x270/0x2f0 net/packet/af_packet.c:349
packet_do_bind+0x67a/0xc00 net/packet/af_packet.c:3145
packet_bind+0x152/0x1b0 net/packet/af_packet.c:3228
__sys_bind+0x1e9/0x250 net/socket.c:1482
__do_sys_bind net/socket.c:1493 [inline]
__se_sys_bind net/socket.c:1491 [inline]
__x64_sys_bind+0x6f/0xb0 net/socket.c:1491
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x447799
Code: Bad RIP value.
RSP: 002b:00007f403316ed98 EFLAGS: 00000246 ORIG_RAX: 0000000000000031
RAX: ffffffffffffffda RBX: 00000000006e29f8 RCX: 0000000000447799
RDX: 0000000000000014 RSI: 0000000020000300 RDI: 0000000000000003
RBP: 00000000006e29f0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006e29fc
R13: 00000000000001fd R14: ffffffffffffee00 R15: 040100100000003c

Showing all locks held in the system:
1 lock held by khungtaskd/1566:
#0: 000000002de5b835 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4440
2 locks held by in:imklog/7817:
#0: 000000004e38fd2e (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767
#1: 00000000d5c6bbd1 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1824 [inline]
#1: 00000000d5c6bbd1 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor976/8125:
#0: 00000000c3b9a299 (&xt[i].mutex){+.+.}, at: xt_find_table_lock+0x3d/0x430 net/netfilter/x_tables.c:1211
1 lock held by syz-executor976/8128:
#0: 00000000c3b9a299 (&xt[i].mutex){+.+.}, at: xt_find_table_lock+0x3d/0x430 net/netfilter/x_tables.c:1211
1 lock held by syz-executor976/8130:
#0: 00000000c3b9a299 (&xt[i].mutex){+.+.}, at: xt_find_table_lock+0x3d/0x430 net/netfilter/x_tables.c:1211
1 lock held by syz-executor976/8132:
#0: 00000000c3b9a299 (&xt[i].mutex){+.+.}, at: xt_find_table_lock+0x3d/0x430 net/netfilter/x_tables.c:1211
1 lock held by syz-executor976/8133:
#0: 00000000c3b9a299 (&xt[i].mutex){+.+.}, at: xt_find_table_lock+0x3d/0x430 net/netfilter/x_tables.c:1211
1 lock held by syz-executor976/11330:
#0: 000000002d675ceb (sk_lock-AF_PACKET){+.+.}, at: lock_sock include/net/sock.h:1510 [inline]
#0: 000000002d675ceb (sk_lock-AF_PACKET){+.+.}, at: packet_do_bind+0x2f/0xc00 net/packet/af_packet.c:3107

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

NMI backtrace for cpu 0
CPU: 0 PID: 1566 Comm: khungtaskd Not tainted 4.19.169-syzkaller #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+0x1fc/0x2ef lib/dump_stack.c:118
nmi_cpu_backtrace.cold+0x63/0xa2 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x1a6/0x1f0 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline]
watchdog+0x991/0xe60 kernel/hung_task.c:287
kthread+0x33f/0x460 kernel/kthread.c:259
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: 4694 Comm: systemd-journal Not tainted 4.19.169-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:preempt_count arch/x86/include/asm/preempt.h:23 [inline]
RIP: 0010:preempt_latency_start kernel/sched/core.c:3215 [inline]
RIP: 0010:preempt_count_add+0x8f/0x190 kernel/sched/core.c:3241
Code: b6 04 02 38 c3 7c 08 84 c0 0f 85 da 00 00 00 8b 15 86 47 e2 0b 85 d2 75 11 65 8b 05 6b 6f c0 7e 0f b6 c0 3d f4 00 00 00 7f 64 <65> 8b 05 5a 6f c0 7e 25 ff ff ff 7f 39 c5 74 03 5b 5d c3 48 8b 5c
RSP: 0018:ffff8880a090fd78 EFLAGS: 00000293
RAX: 0000000000000002 RBX: 0000000000000003 RCX: 0000000000000000
RDX: 0000000000000000 RSI: 0000000000000004 RDI: 0000000000000001
RBP: 0000000000000001 R08: 0000000000000000 R09: ffffed1017424552
R10: ffff8880ba122a93 R11: 000000003c9c153f R12: ffff888063358980
R13: 0000000000001000 R14: 0000000000001100 R15: ffffffff81a663fe
FS: 00007f21bbf898c0(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f21b9394000 CR3: 00000000a16fd000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
rcu_lockdep_current_cpu_online kernel/rcu/tree.c:1076 [inline]
rcu_lockdep_current_cpu_online+0x32/0x1b0 kernel/rcu/tree.c:1068
rcu_read_lock_sched_held+0xd3/0x1d0 kernel/rcu/update.c:115
trace_kmem_cache_alloc include/trace/events/kmem.h:54 [inline]
kmem_cache_alloc+0x315/0x370 mm/slab.c:3560
getname_flags+0xce/0x590 fs/namei.c:140
do_sys_open+0x26c/0x520 fs/open.c:1079
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7f21bb519840
Code: 73 01 c3 48 8b 0d 68 77 20 00 f7 d8 64 89 01 48 83 c8 ff c3 66 0f 1f 44 00 00 83 3d 89 bb 20 00 00 75 10 b8 02 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 1e f6 ff ff 48 89 04 24
RSP: 002b:00007ffcc83ab0b8 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 00007ffcc83ab3c0 RCX: 00007f21bb519840
RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 0000563d6b211340
RBP: 000000000000000d R08: 000000000000ffc0 R09: 00000000ffffffff
R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff
R13: 0000563d6b20d040 R14: 00007ffcc83ab380 R15: 0000563d6b218280


---
This report 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 issue. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.
syzbot can test patches for this issue, for details see:
https://goo.gl/tpsmEJ#testing-patches
Reply all
Reply to author
Forward
0 new messages