INFO: task hung in lock_sock_nested (2)

10 views
Skip to first unread message

syzbot

unread,
May 14, 2019, 11:13:06 AM5/14/19
to syzkaller-a...@googlegroups.com
Hello,

syzbot found the following crash on:

HEAD commit: 8fe42840 Merge 4.9.141 into android-4.9
git tree: android-4.9
console output: https://syzkaller.appspot.com/x/log.txt?x=177e3ff0a00000
kernel config: https://syzkaller.appspot.com/x/.config?x=22a5ba9f73b6da1d
dashboard link: https://syzkaller.appspot.com/bug?extid=90fda4971e6c0f57526f
compiler: gcc (GCC) 8.0.1 20180413 (experimental)
userspace arch: i386

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+90fda4...@syzkaller.appspotmail.com

Free memory is -13260kB above reserved
lowmemorykiller: Killing 'syz-executor.3' (25345) (tgid 25329), adj 1000,
to free 51376kB on behalf of 'rs:main Q:Reg' (1899) because
cache 132kB is below limit 6144kB for oom_score_adj 0
Free memory is -13260kB above reserved
INFO: task syz-executor.0:25335 blocked for more than 140 seconds.
Not tainted 4.9.141+ #23
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.0 D29368 25335 2103 0x20020004
ffff88008f3e4740 ffff8801a9dda100 ffff88009933b180 ffff8801c7815f00
ffff8801db621018 ffff88013e02fa88 ffffffff828075c2 ffff88013e02fb08
ffff88008f3e4740 ffffffff810efada 00fffc0000000000 ffff8801db6218f0
Call Trace:
lowmemorykiller: Killing 'syz-executor.3' (25345) (tgid 25329), adj 1000,
to free 51376kB on behalf of 'udevd' (3679) because
cache 132kB is below limit 6144kB for oom_score_adj 0
Free memory is -13260kB above reserved
[<ffffffff82808aef>] schedule+0x7f/0x1b0 kernel/sched/core.c:3553
[<ffffffff822acffc>] __lock_sock+0x13c/0x250 net/core/sock.c:2035
[<ffffffff822ad206>] lock_sock_nested+0xf6/0x120 net/core/sock.c:2505
[<ffffffff827d2640>] lock_sock include/net/sock.h:1404 [inline]
[<ffffffff827d2640>] packet_setsockopt+0xc10/0x2630
net/packet/af_packet.c:3856
lowmemorykiller: Killing 'syz-executor.3' (25345) (tgid 25329), adj 1000,
to free 51376kB on behalf of 'syz-fuzzer' (2072) because
cache 280kB is below limit 6144kB for oom_score_adj 0
Free memory is -13324kB above reserved
[<ffffffff827d4141>] compat_packet_setsockopt+0xe1/0x140
net/packet/af_packet.c:4016
[<ffffffff8238f7d9>] C_SYSC_setsockopt net/compat.c:399 [inline]
[<ffffffff8238f7d9>] compat_SyS_setsockopt+0x169/0x550 net/compat.c:382
lowmemorykiller: Killing 'syz-executor.3' (25345) (tgid 25329), adj 1000,
to free 51376kB on behalf of 'getty' (25380) because
cache 260kB is below limit 6144kB for oom_score_adj 0
Free memory is -13288kB above reserved
[<ffffffff81006311>] do_syscall_32_irqs_on arch/x86/entry/common.c:328
[inline]
[<ffffffff81006311>] do_fast_syscall_32+0x2f1/0xa10
arch/x86/entry/common.c:390
[<ffffffff82818de0>] entry_SYSENTER_compat+0x90/0xa2
arch/x86/entry/entry_64_compat.S:137

Showing all locks held in the system:
3 locks held by kworker/1:1/22:
#0: ("%s"("ipv6_addrconf")){.+.+..}, at: [<ffffffff81130f0c>]
process_one_work+0x73c/0x15f0 kernel/workqueue.c:2085
#1: ((addr_chk_work).work){+.+...}, at: [<ffffffff81130f44>]
process_one_work+0x774/0x15f0 kernel/workqueue.c:2089
#2: (rtnl_mutex){+.+.+.}, at: [<ffffffff823412d7>] rtnl_lock+0x17/0x20
net/core/rtnetlink.c:70
2 locks held by kworker/0:1/23:
#0: ("events"){.+.+.+}, at: [<ffffffff81130f0c>]
process_one_work+0x73c/0x15f0 kernel/workqueue.c:2085
#1: ((&rew.rew_work)){+.+...}, at: [<ffffffff81130f44>]
process_one_work+0x774/0x15f0 kernel/workqueue.c:2089
2 locks held by khungtaskd/24:
#0: (rcu_read_lock){......}, at: [<ffffffff8131c0cc>]
check_hung_uninterruptible_tasks kernel/hung_task.c:168 [inline]
#0: (rcu_read_lock){......}, at: [<ffffffff8131c0cc>]
watchdog+0x11c/0xa20 kernel/hung_task.c:239
#1: (tasklist_lock){.+.+..}, at: [<ffffffff813fe63f>]
debug_show_all_locks+0x79/0x218 kernel/locking/lockdep.c:4336
2 locks held by getty/2029:
#0: (&tty->ldisc_sem){++++++}, at: [<ffffffff82815952>]
ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:367
#1: (&ldata->atomic_read_lock){+.+.+.}, at: [<ffffffff81d37362>]
n_tty_read+0x202/0x16e0 drivers/tty/n_tty.c:2142
1 lock held by syz-executor.2/12801:
#0: (&sig->cred_guard_mutex){+.+.+.}, at: [<ffffffff81520d73>]
prepare_bprm_creds+0x53/0x110 fs/exec.c:1369
5 locks held by kworker/u4:13/14243:
#0: ("%s""netns"){.+.+.+}, at: [<ffffffff81130f0c>]
process_one_work+0x73c/0x15f0 kernel/workqueue.c:2085
#1: (net_cleanup_work){+.+.+.}, at: [<ffffffff81130f44>]
process_one_work+0x774/0x15f0 kernel/workqueue.c:2089
#2: (net_mutex){+.+.+.}, at: [<ffffffff822e681f>] cleanup_net+0x13f/0x8b0
net/core/net_namespace.c:439
#3: (rtnl_mutex){+.+.+.}, at: [<ffffffff823412d7>] rtnl_lock+0x17/0x20
net/core/rtnetlink.c:70
#4: (rcu_preempt_state.exp_mutex){+.+...}, at: [<ffffffff8124a749>]
exp_funnel_lock kernel/rcu/tree_exp.h:256 [inline]
#4: (rcu_preempt_state.exp_mutex){+.+...}, at: [<ffffffff8124a749>]
_synchronize_rcu_expedited+0x339/0x840 kernel/rcu/tree_exp.h:569
1 lock held by syz-executor.4/25344:
#0: (net_mutex){+.+.+.}, at: [<ffffffff822e70e5>] copy_net_ns+0x155/0x330
net/core/net_namespace.c:406
1 lock held by syz-executor.3/25345:
#0: (net_mutex){+.+.+.}, at: [<ffffffff822e70e5>] copy_net_ns+0x155/0x330
net/core/net_namespace.c:406
1 lock held by syz-executor.3/25362:
#0: (net_mutex){+.+.+.}, at: [<ffffffff822e70e5>] copy_net_ns+0x155/0x330
net/core/net_namespace.c:406

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

lowmemorykiller: Killing 'syz-executor.3' (25345) (tgid 25329), adj 1000,
to free 51376kB on behalf of 'init' (1) because
cache 260kB is below limit 6144kB for oom_score_adj 0
Free memory is -13288kB above reserved
lowmemorykiller: Killing 'syz-executor.3' (25345) (tgid 25329), adj 1000,
to free 51376kB on behalf of 'getty' (25381) because
cache 260kB is below limit 6144kB for oom_score_adj 0
Free memory is -13288kB above reserved
lowmemorykiller: Killing 'syz-executor.3' (25345) (tgid 25329), adj 1000,
to free 51376kB on behalf of 'syz-executor.5' (2104) because
cache 332kB is below limit 6144kB for oom_score_adj 0
Free memory is -13256kB above reserved
lowmemorykiller: Killing 'syz-executor.3' (25345) (tgid 25329), adj 1000,
to free 51376kB on behalf of 'rs:main Q:Reg' (1899) because
cache 332kB is below limit 6144kB for oom_score_adj 0
Free memory is -13256kB above reserved
NMI backtrace for cpu 1
CPU: 1 PID: 24 Comm: khungtaskd Not tainted 4.9.141+ #23
ffff8801d9907d08 ffffffff81b42e79 0000000000000000 0000000000000001
0000000000000001 0000000000000001 ffffffff810983b0 ffff8801d9907d40
ffffffff81b4df89 0000000000000001 0000000000000000 0000000000000003
Call Trace:
[<ffffffff81b42e79>] __dump_stack lib/dump_stack.c:15 [inline]
[<ffffffff81b42e79>] dump_stack+0xc1/0x128 lib/dump_stack.c:51
[<ffffffff81b4df89>] nmi_cpu_backtrace.cold.0+0x48/0x87
lib/nmi_backtrace.c:99
[<ffffffff81b4df1c>] nmi_trigger_cpumask_backtrace+0x12c/0x151
lib/nmi_backtrace.c:60
[<ffffffff810984b4>] arch_trigger_cpumask_backtrace+0x14/0x20
arch/x86/kernel/apic/hw_nmi.c:37
[<ffffffff8131c65d>] trigger_all_cpu_backtrace include/linux/nmi.h:58
[inline]
[<ffffffff8131c65d>] check_hung_task kernel/hung_task.c:125 [inline]
[<ffffffff8131c65d>] check_hung_uninterruptible_tasks
kernel/hung_task.c:182 [inline]
[<ffffffff8131c65d>] watchdog+0x6ad/0xa20 kernel/hung_task.c:239
[<ffffffff81142c3d>] kthread+0x26d/0x300 kernel/kthread.c:211
[<ffffffff82817a5c>] 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: 25377 Comm: getty Not tainted 4.9.141+ #23
task: ffff8800975117c0 task.stack: ffff8801bb008000
RIP: 0010:[<ffffffff81205a50>] c [<ffffffff81205a50>] mark_lock+0x0/0x1290
kernel/locking/lockdep.c:3032
RSP: 0000:ffff8801bb00f0c8 EFLAGS: 00000002
RAX: 0000000000000000 RBX: ffff880097512138 RCX: ffff880097512159
RDX: 0000000000000006 RSI: ffff880097512138 RDI: ffff8800975117c0
RBP: ffff8801bb00f280 R08: ffff880097512158 R09: 0000000000000001
R10: ffff8800975117c0 R11: 1ffff10012ea2426 R12: 0000000000000075
R13: 0000000000000005 R14: 0000000000000000 R15: ffff88009751215a
FS: 00007f1763739700(0000) GS:ffff8801db600000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000459e00 CR3: 000000013a389000 CR4: 00000000001606b0
Stack:
ffffffff81207f8d c ffff88009751205c c 0000000000000000 c ffff8801bb00f2a0 c
ffffffff81207a04 c ffff880097512060 c ffff880097512130 c ffffffff83c73d80 c
ffff8800975120e8 c 000000000000536e c ffff880097512128 c ffff880097512060 c
Call Trace:
[<ffffffff8120c8d0>] lock_acquire+0x130/0x3e0 kernel/locking/lockdep.c:3756
[<ffffffff82816c26>] __raw_spin_lock include/linux/spinlock_api_smp.h:144
[inline]
[<ffffffff82816c26>] _raw_spin_lock+0x36/0x50 kernel/locking/spinlock.c:151
[<ffffffff8141a061>] spin_lock include/linux/spinlock.h:302 [inline]
[<ffffffff8141a061>] task_lock include/linux/sched.h:3257 [inline]
[<ffffffff8141a061>] find_lock_task_mm+0xf1/0x270 mm/oom_kill.c:115
[<ffffffff821effdf>] lowmem_scan+0x34f/0xaf0
drivers/staging/android/lowmemorykiller.c:134
[<ffffffff81449cc6>] do_shrink_slab mm/vmscan.c:398 [inline]
[<ffffffff81449cc6>] shrink_slab.part.8+0x3c6/0xa00 mm/vmscan.c:501
[<ffffffff814557fd>] shrink_slab mm/vmscan.c:465 [inline]
[<ffffffff814557fd>] shrink_node+0x1ed/0x740 mm/vmscan.c:2602
[<ffffffff814560c7>] shrink_zones mm/vmscan.c:2749 [inline]
[<ffffffff814560c7>] do_try_to_free_pages mm/vmscan.c:2791 [inline]
[<ffffffff814560c7>] try_to_free_pages+0x377/0xb80 mm/vmscan.c:3002
[<ffffffff81428a01>] __perform_reclaim mm/page_alloc.c:3324 [inline]
[<ffffffff81428a01>] __alloc_pages_direct_reclaim mm/page_alloc.c:3345
[inline]
[<ffffffff81428a01>] __alloc_pages_slowpath mm/page_alloc.c:3697 [inline]
[<ffffffff81428a01>] __alloc_pages_nodemask+0x981/0x1bd0
mm/page_alloc.c:3862
[<ffffffff8143564a>] __alloc_pages include/linux/gfp.h:433 [inline]
[<ffffffff8143564a>] __alloc_pages_node include/linux/gfp.h:446 [inline]
[<ffffffff8143564a>] alloc_pages_node include/linux/gfp.h:460 [inline]
[<ffffffff8143564a>] __page_cache_alloc include/linux/pagemap.h:208
[inline]
[<ffffffff8143564a>] __do_page_cache_readahead+0x21a/0x8b0
mm/readahead.c:183
[<ffffffff81415534>] ra_submit mm/internal.h:59 [inline]
[<ffffffff81415534>] do_sync_mmap_readahead mm/filemap.c:2066 [inline]
[<ffffffff81415534>] filemap_fault+0x924/0x1110 mm/filemap.c:2143
[<ffffffff816e7721>] ext4_filemap_fault+0x71/0xa0 fs/ext4/inode.c:5853
[<ffffffff81492ef3>] __do_fault+0x223/0x500 mm/memory.c:2833
[<ffffffff814a3696>] do_read_fault mm/memory.c:3180 [inline]
[<ffffffff814a3696>] do_fault mm/memory.c:3315 [inline]
[<ffffffff814a3696>] handle_pte_fault mm/memory.c:3516 [inline]
[<ffffffff814a3696>] __handle_mm_fault mm/memory.c:3603 [inline]
[<ffffffff814a3696>] handle_mm_fault+0x1326/0x2350 mm/memory.c:3640
[<ffffffff810b2b33>] __do_page_fault+0x403/0xa60 arch/x86/mm/fault.c:1406
[<ffffffff810b31e7>] do_page_fault+0x27/0x30 arch/x86/mm/fault.c:1469
[<ffffffff828188b5>] page_fault+0x25/0x30 arch/x86/entry/entry_64.S:951
Code: c17 cf3 c03 c03 c85 cc0 c0f c84 c01 ca9 c1f c00 c48 c83
cc4 c40 c31 cc0 c5b c41 c5c c41 c5d c41 c5e c41 c5f c5d cc3
c4c c89 cff ce8 cf6 cd5 c2e c00 ceb cd7 c0f c1f c40 c00
c<55> c4c c8d c46 c20 c89 cd1 c48 cb8 c00 c00 c00 c00 c00
cfc cff cdf c48 c89 ce5 c41 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,
Jan 8, 2020, 10:18:06 AM1/8/20
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