Groups | Search | Server Info | Keyboard shortcuts | Login | Register [http] [https] [nntp] [nntps]


Groups > linux.kernel > #1525151 > unrolled thread

mm: BUG in pgtable_pmd_page_dtor

Started byDmitry Vyukov <dvyukov@google.com>
First post2016-11-18 11:20 +0100
Last post2016-11-25 09:50 +0100
Articles 5 — 3 participants

Back to article view | Back to linux.kernel


Contents

  mm: BUG in pgtable_pmd_page_dtor Dmitry Vyukov <dvyukov@google.com> - 2016-11-18 11:20 +0100
    Re: mm: BUG in pgtable_pmd_page_dtor "Kirill A. Shutemov" <kirill@shutemov.name> - 2016-11-18 12:00 +0100
    Re: mm: BUG in pgtable_pmd_page_dtor Vlastimil Babka <vbabka@suse.cz> - 2016-11-24 15:00 +0100
      Re: mm: BUG in pgtable_pmd_page_dtor Dmitry Vyukov <dvyukov@google.com> - 2016-11-24 15:30 +0100
        Re: mm: BUG in pgtable_pmd_page_dtor Vlastimil Babka <vbabka@suse.cz> - 2016-11-25 09:50 +0100

#1525151 — mm: BUG in pgtable_pmd_page_dtor

FromDmitry Vyukov <dvyukov@google.com>
Date2016-11-18 11:20 +0100
Subjectmm: BUG in pgtable_pmd_page_dtor
Message-ID<sEOqZ-5QP-3@gated-at.bofh.it>
Hello,

I've got the following BUG while running syzkaller on
a25f0944ba9b1d8a6813fd6f1a86f1bd59ac25a6 (4.9-rc5). Unfortunately it's
not reproducible.

kernel BUG at ./include/linux/mm.h:1743!
invalid opcode: 0000 [#1] SMP DEBUG_PAGEALLOC KASAN
Dumping ftrace buffer:
   (ftrace buffer empty)
Modules linked in:
CPU: 3 PID: 4049 Comm: syz-fuzzer Not tainted 4.9.0-rc5+ #43
Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Bochs 01/01/2011
task: ffff88006ad028c0 task.stack: ffff8800667e0000
RIP: 0010:[<ffffffff8130e2ab>]  [<     inline     >]
pgtable_pmd_page_dtor include/linux/mm.h:1743
RIP: 0010:[<ffffffff8130e2ab>]  [<ffffffff8130e2ab>]
___pmd_free_tlb+0x3db/0x5a0 arch/x86/mm/pgtable.c:74
RSP: 0018:ffff8800667e6908  EFLAGS: 00010292
RAX: 0000000000000000 RBX: 1ffff1000ccfcd25 RCX: 0000000000000000
RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffed000ccfcd10
RBP: ffff8800667e6a70 R08: 0000000000000001 R09: 0000000000000000
R10: dffffc0000000000 R11: 0000000000000001 R12: ffff8800667e6ef8
R13: ffff8800667e6a48 R14: ffffea0000e196c0 R15: 000000000003865b
FS:  00007f152a530700(0000) GS:ffff88006d100000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f1514bff9d0 CR3: 0000000009821000 CR4: 00000000000006e0
DR0: 0000000000000400 DR1: 0000000000000400 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000600
Stack:
 0000000000000000 ffff88006ad030e0 ffff88006ad030b8 dffffc0000000000
 0000000041b58ab3 ffffffff894db568 ffffffff8130ded0 ffffffff8156b2a0
 0000000000000082 ffff88006ad030e0 1ffff1000ccfcd30 1ffff1000ccfcd38
Call Trace:
 [<     inline     >] __pmd_free_tlb arch/x86/include/asm/pgalloc.h:110
 [<     inline     >] free_pmd_range mm/memory.c:443
 [<     inline     >] free_pud_range mm/memory.c:461
 [<ffffffff81946458>] free_pgd_range+0xb98/0x1270 mm/memory.c:537
 [<ffffffff81946da5>] free_pgtables+0x275/0x340 mm/memory.c:569
 [<ffffffff81972761>] exit_mmap+0x281/0x4e0 mm/mmap.c:2942
 [<     inline     >] __mmput kernel/fork.c:866
 [<ffffffff813f24ce>] mmput+0x20e/0x4c0 kernel/fork.c:888
 [<     inline     >] exit_mm kernel/exit.c:512
 [<ffffffff814119a0>] do_exit+0x960/0x2640 kernel/exit.c:815
 [<ffffffff8141383e>] do_group_exit+0x14e/0x420 kernel/exit.c:931
 [<ffffffff814429d3>] get_signal+0x663/0x1880 kernel/signal.c:2307
 [<ffffffff81239b45>] do_signal+0xc5/0x2190 arch/x86/kernel/signal.c:807
 [<ffffffff8100666a>] exit_to_usermode_loop+0x1ea/0x2d0
arch/x86/entry/common.c:156
 [<     inline     >] prepare_exit_to_usermode arch/x86/entry/common.c:190
 [<ffffffff81009693>] syscall_return_slowpath+0x4d3/0x570
arch/x86/entry/common.c:259
 [<ffffffff881479a6>] entry_SYSCALL_64_fastpath+0xc4/0xc6
Code: 10 00 00 4c 89 e7 e8 25 6c 63 00 e9 9b fd ff ff e8 0b 9e 3d 00
0f 0b e8 04 9e 3d 00 48 c7 c6 00 ac 27 88 4c 89 f7 e8 85 9e 62 00 <0f>
0b e8 9e 2d 6e 00 e9 1a fe ff ff 48 89 cf 48 89 8d b0 fe ff
RIP  [<     inline     >] pgtable_pmd_page_dtor include/linux/mm.h:1743
RIP  [<ffffffff8130e2ab>] ___pmd_free_tlb+0x3db/0x5a0 arch/x86/mm/pgtable.c:74
 RSP <ffff8800667e6908>
---[ end trace 4ef4b70d88f62f8a ]---

[toc] | [next] | [standalone]


#1525177

From"Kirill A. Shutemov" <kirill@shutemov.name>
Date2016-11-18 12:00 +0100
Message-ID<sEP3I-63e-13@gated-at.bofh.it>
In reply to#1525151
On Fri, Nov 18, 2016 at 11:19:30AM +0100, Dmitry Vyukov wrote:
> Hello,
> 
> I've got the following BUG while running syzkaller on
> a25f0944ba9b1d8a6813fd6f1a86f1bd59ac25a6 (4.9-rc5). Unfortunately it's
> not reproducible.

I don't think there's enough info to track it down :(

Let me know if you will see this again.

-- 
 Kirill A. Shutemov

[toc] | [prev] | [next] | [standalone]


#1529313

FromVlastimil Babka <vbabka@suse.cz>
Date2016-11-24 15:00 +0100
Message-ID<sH2Jc-3tF-31@gated-at.bofh.it>
In reply to#1525151
On 11/18/2016 11:19 AM, Dmitry Vyukov wrote:
> Hello,
>
> I've got the following BUG while running syzkaller on
> a25f0944ba9b1d8a6813fd6f1a86f1bd59ac25a6 (4.9-rc5). Unfortunately it's
> not reproducible.
>
> kernel BUG at ./include/linux/mm.h:1743!
> invalid opcode: 0000 [#1] SMP DEBUG_PAGEALLOC KASAN

Shouldn't there be also dump_page() output? Since you've hit this:
VM_BUG_ON_PAGE(page->pmd_huge_pte, page);

Anyway the output wouldn't contain the value of pmd_huge_pte or stuff 
that's in union with it. I'd suggest adding a local patch that prints 
this in the error case, in case the fuzzer hits it again.

Heck, it might even make sense to print raw contents of struct page in 
dump_page() as a catch-all solution? Should I send a patch?

> Dumping ftrace buffer:
>    (ftrace buffer empty)
> Modules linked in:
> CPU: 3 PID: 4049 Comm: syz-fuzzer Not tainted 4.9.0-rc5+ #43
> Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Bochs 01/01/2011
> task: ffff88006ad028c0 task.stack: ffff8800667e0000
> RIP: 0010:[<ffffffff8130e2ab>]  [<     inline     >]
> pgtable_pmd_page_dtor include/linux/mm.h:1743
> RIP: 0010:[<ffffffff8130e2ab>]  [<ffffffff8130e2ab>]
> ___pmd_free_tlb+0x3db/0x5a0 arch/x86/mm/pgtable.c:74
> RSP: 0018:ffff8800667e6908  EFLAGS: 00010292
> RAX: 0000000000000000 RBX: 1ffff1000ccfcd25 RCX: 0000000000000000
> RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffed000ccfcd10
> RBP: ffff8800667e6a70 R08: 0000000000000001 R09: 0000000000000000
> R10: dffffc0000000000 R11: 0000000000000001 R12: ffff8800667e6ef8
> R13: ffff8800667e6a48 R14: ffffea0000e196c0 R15: 000000000003865b
> FS:  00007f152a530700(0000) GS:ffff88006d100000(0000) knlGS:0000000000000000
> CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> CR2: 00007f1514bff9d0 CR3: 0000000009821000 CR4: 00000000000006e0
> DR0: 0000000000000400 DR1: 0000000000000400 DR2: 0000000000000000
> DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000600
> Stack:
>  0000000000000000 ffff88006ad030e0 ffff88006ad030b8 dffffc0000000000
>  0000000041b58ab3 ffffffff894db568 ffffffff8130ded0 ffffffff8156b2a0
>  0000000000000082 ffff88006ad030e0 1ffff1000ccfcd30 1ffff1000ccfcd38
> Call Trace:
>  [<     inline     >] __pmd_free_tlb arch/x86/include/asm/pgalloc.h:110
>  [<     inline     >] free_pmd_range mm/memory.c:443
>  [<     inline     >] free_pud_range mm/memory.c:461
>  [<ffffffff81946458>] free_pgd_range+0xb98/0x1270 mm/memory.c:537
>  [<ffffffff81946da5>] free_pgtables+0x275/0x340 mm/memory.c:569
>  [<ffffffff81972761>] exit_mmap+0x281/0x4e0 mm/mmap.c:2942
>  [<     inline     >] __mmput kernel/fork.c:866
>  [<ffffffff813f24ce>] mmput+0x20e/0x4c0 kernel/fork.c:888
>  [<     inline     >] exit_mm kernel/exit.c:512
>  [<ffffffff814119a0>] do_exit+0x960/0x2640 kernel/exit.c:815
>  [<ffffffff8141383e>] do_group_exit+0x14e/0x420 kernel/exit.c:931
>  [<ffffffff814429d3>] get_signal+0x663/0x1880 kernel/signal.c:2307
>  [<ffffffff81239b45>] do_signal+0xc5/0x2190 arch/x86/kernel/signal.c:807
>  [<ffffffff8100666a>] exit_to_usermode_loop+0x1ea/0x2d0
> arch/x86/entry/common.c:156
>  [<     inline     >] prepare_exit_to_usermode arch/x86/entry/common.c:190
>  [<ffffffff81009693>] syscall_return_slowpath+0x4d3/0x570
> arch/x86/entry/common.c:259
>  [<ffffffff881479a6>] entry_SYSCALL_64_fastpath+0xc4/0xc6
> Code: 10 00 00 4c 89 e7 e8 25 6c 63 00 e9 9b fd ff ff e8 0b 9e 3d 00
> 0f 0b e8 04 9e 3d 00 48 c7 c6 00 ac 27 88 4c 89 f7 e8 85 9e 62 00 <0f>
> 0b e8 9e 2d 6e 00 e9 1a fe ff ff 48 89 cf 48 89 8d b0 fe ff
> RIP  [<     inline     >] pgtable_pmd_page_dtor include/linux/mm.h:1743
> RIP  [<ffffffff8130e2ab>] ___pmd_free_tlb+0x3db/0x5a0 arch/x86/mm/pgtable.c:74
>  RSP <ffff8800667e6908>
> ---[ end trace 4ef4b70d88f62f8a ]---
>
> --
> To unsubscribe, send a message with 'unsubscribe linux-mm' in
> the body to majordomo@kvack.org.  For more info on Linux MM,
> see: http://www.linux-mm.org/ .
> Don't email: <a href=mailto:"dont@kvack.org"> email@kvack.org </a>
>

[toc] | [prev] | [next] | [standalone]


#1529330

FromDmitry Vyukov <dvyukov@google.com>
Date2016-11-24 15:30 +0100
Message-ID<sH3ce-3Wy-15@gated-at.bofh.it>
In reply to#1529313
On Thu, Nov 24, 2016 at 2:49 PM, Vlastimil Babka <vbabka@suse.cz> wrote:
> On 11/18/2016 11:19 AM, Dmitry Vyukov wrote:
>>
>> Hello,
>>
>> I've got the following BUG while running syzkaller on
>> a25f0944ba9b1d8a6813fd6f1a86f1bd59ac25a6 (4.9-rc5). Unfortunately it's
>> not reproducible.
>>
>> kernel BUG at ./include/linux/mm.h:1743!
>> invalid opcode: 0000 [#1] SMP DEBUG_PAGEALLOC KASAN
>
>
> Shouldn't there be also dump_page() output? Since you've hit this:
> VM_BUG_ON_PAGE(page->pmd_huge_pte, page);

Here it is:

[  250.326131] page:ffffea0000e196c0 count:1 mapcount:0 mapping:
   (null) index:0x0
[  250.343393] flags: 0x1fffc0000000000()
[  250.345328] page dumped because: VM_BUG_ON_PAGE(page->pmd_huge_pte)
[  250.346780] ------------[ cut here ]------------
[  250.347742] kernel BUG at ./include/linux/mm.h:1743!


> Anyway the output wouldn't contain the value of pmd_huge_pte or stuff that's
> in union with it. I'd suggest adding a local patch that prints this in the
> error case, in case the fuzzer hits it again.
>
> Heck, it might even make sense to print raw contents of struct page in
> dump_page() as a catch-all solution? Should I send a patch?

Yes, please send.
We are moving towards continuous build without local patches.



>> Dumping ftrace buffer:
>>    (ftrace buffer empty)
>> Modules linked in:
>> CPU: 3 PID: 4049 Comm: syz-fuzzer Not tainted 4.9.0-rc5+ #43
>> Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Bochs
>> 01/01/2011
>> task: ffff88006ad028c0 task.stack: ffff8800667e0000
>> RIP: 0010:[<ffffffff8130e2ab>]  [<     inline     >]
>> pgtable_pmd_page_dtor include/linux/mm.h:1743
>> RIP: 0010:[<ffffffff8130e2ab>]  [<ffffffff8130e2ab>]
>> ___pmd_free_tlb+0x3db/0x5a0 arch/x86/mm/pgtable.c:74
>> RSP: 0018:ffff8800667e6908  EFLAGS: 00010292
>> RAX: 0000000000000000 RBX: 1ffff1000ccfcd25 RCX: 0000000000000000
>> RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffed000ccfcd10
>> RBP: ffff8800667e6a70 R08: 0000000000000001 R09: 0000000000000000
>> R10: dffffc0000000000 R11: 0000000000000001 R12: ffff8800667e6ef8
>> R13: ffff8800667e6a48 R14: ffffea0000e196c0 R15: 000000000003865b
>> FS:  00007f152a530700(0000) GS:ffff88006d100000(0000)
>> knlGS:0000000000000000
>> CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
>> CR2: 00007f1514bff9d0 CR3: 0000000009821000 CR4: 00000000000006e0
>> DR0: 0000000000000400 DR1: 0000000000000400 DR2: 0000000000000000
>> DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000600
>> Stack:
>>  0000000000000000 ffff88006ad030e0 ffff88006ad030b8 dffffc0000000000
>>  0000000041b58ab3 ffffffff894db568 ffffffff8130ded0 ffffffff8156b2a0
>>  0000000000000082 ffff88006ad030e0 1ffff1000ccfcd30 1ffff1000ccfcd38
>> Call Trace:
>>  [<     inline     >] __pmd_free_tlb arch/x86/include/asm/pgalloc.h:110
>>  [<     inline     >] free_pmd_range mm/memory.c:443
>>  [<     inline     >] free_pud_range mm/memory.c:461
>>  [<ffffffff81946458>] free_pgd_range+0xb98/0x1270 mm/memory.c:537
>>  [<ffffffff81946da5>] free_pgtables+0x275/0x340 mm/memory.c:569
>>  [<ffffffff81972761>] exit_mmap+0x281/0x4e0 mm/mmap.c:2942
>>  [<     inline     >] __mmput kernel/fork.c:866
>>  [<ffffffff813f24ce>] mmput+0x20e/0x4c0 kernel/fork.c:888
>>  [<     inline     >] exit_mm kernel/exit.c:512
>>  [<ffffffff814119a0>] do_exit+0x960/0x2640 kernel/exit.c:815
>>  [<ffffffff8141383e>] do_group_exit+0x14e/0x420 kernel/exit.c:931
>>  [<ffffffff814429d3>] get_signal+0x663/0x1880 kernel/signal.c:2307
>>  [<ffffffff81239b45>] do_signal+0xc5/0x2190 arch/x86/kernel/signal.c:807
>>  [<ffffffff8100666a>] exit_to_usermode_loop+0x1ea/0x2d0
>> arch/x86/entry/common.c:156
>>  [<     inline     >] prepare_exit_to_usermode arch/x86/entry/common.c:190
>>  [<ffffffff81009693>] syscall_return_slowpath+0x4d3/0x570
>> arch/x86/entry/common.c:259
>>  [<ffffffff881479a6>] entry_SYSCALL_64_fastpath+0xc4/0xc6
>> Code: 10 00 00 4c 89 e7 e8 25 6c 63 00 e9 9b fd ff ff e8 0b 9e 3d 00
>> 0f 0b e8 04 9e 3d 00 48 c7 c6 00 ac 27 88 4c 89 f7 e8 85 9e 62 00 <0f>
>> 0b e8 9e 2d 6e 00 e9 1a fe ff ff 48 89 cf 48 89 8d b0 fe ff
>> RIP  [<     inline     >] pgtable_pmd_page_dtor include/linux/mm.h:1743
>> RIP  [<ffffffff8130e2ab>] ___pmd_free_tlb+0x3db/0x5a0
>> arch/x86/mm/pgtable.c:74
>>  RSP <ffff8800667e6908>
>> ---[ end trace 4ef4b70d88f62f8a ]---
>>
>> --
>> To unsubscribe, send a message with 'unsubscribe linux-mm' in
>> the body to majordomo@kvack.org.  For more info on Linux MM,
>> see: http://www.linux-mm.org/ .
>> Don't email: <a href=mailto:"dont@kvack.org"> email@kvack.org </a>
>>
>

[toc] | [prev] | [next] | [standalone]


#1529917

FromVlastimil Babka <vbabka@suse.cz>
Date2016-11-25 09:50 +0100
Message-ID<sHkmK-6J9-15@gated-at.bofh.it>
In reply to#1529330
On 11/24/2016 03:23 PM, Dmitry Vyukov wrote:
> On Thu, Nov 24, 2016 at 2:49 PM, Vlastimil Babka <vbabka@suse.cz> wrote:
>> On 11/18/2016 11:19 AM, Dmitry Vyukov wrote:
>>>
>>> Hello,
>>>
>>> I've got the following BUG while running syzkaller on
>>> a25f0944ba9b1d8a6813fd6f1a86f1bd59ac25a6 (4.9-rc5). Unfortunately it's
>>> not reproducible.
>>>
>>> kernel BUG at ./include/linux/mm.h:1743!
>>> invalid opcode: 0000 [#1] SMP DEBUG_PAGEALLOC KASAN
>>
>>
>> Shouldn't there be also dump_page() output? Since you've hit this:
>> VM_BUG_ON_PAGE(page->pmd_huge_pte, page);
> 
> Here it is:
> 
> [  250.326131] page:ffffea0000e196c0 count:1 mapcount:0 mapping:
>    (null) index:0x0
> [  250.343393] flags: 0x1fffc0000000000()
> [  250.345328] page dumped because: VM_BUG_ON_PAGE(page->pmd_huge_pte)
> [  250.346780] ------------[ cut here ]------------
> [  250.347742] kernel BUG at ./include/linux/mm.h:1743!

Yeah, as expected, not very useful for this particular BUG_ON :/

>> Anyway the output wouldn't contain the value of pmd_huge_pte or stuff that's
>> in union with it. I'd suggest adding a local patch that prints this in the
>> error case, in case the fuzzer hits it again.
>>
>> Heck, it might even make sense to print raw contents of struct page in
>> dump_page() as a catch-all solution? Should I send a patch?
> 
> Yes, please send.
> We are moving towards continuous build without local patches.

Something like this?
-------8<-------
From 2ac2c9b83d7c4c8be076c24246865a2ed01f9032 Mon Sep 17 00:00:00 2001
From: Vlastimil Babka <vbabka@suse.cz>
Date: Fri, 25 Nov 2016 09:08:05 +0100
Subject: [PATCH] mm, debug: print raw struct page data in __dump_page()

The __dump_page() function is used when a page metadata inconsistency is
detected, either by standard runtime checks, or extra checks in CONFIG_DEBUG_VM
builds. It prints some of the relevant metadata, but not the whole struct page,
which is based on unions and interpretation is dependent on the context.

This means that sometimes e.g. a VM_BUG_ON_PAGE() checks certain field, which
is however not printed by __dump_page() and the resulting bug report may then
lack clues that could help in determining the root cause. This patch solves
the problem by simply printing the whole struct page word by word, so no part
is missing, but the interpretation of the data is left to developers. This is
similar to e.g. x86_64 raw stack dumps.

Example output:

 page:ffffea00000475c0 count:1 mapcount:0 mapping:          (null) index:0x0
 flags: 0x100000000000400(reserved)
 raw struct page data:
  0100000000000400 0000000000000000 0000000000000000 00000001ffffffff
  ffffea00000475e0 ffffea00000475e0 0000000000000000 0000000000000000
 page dumped because: VM_BUG_ON_PAGE(1)

Signed-off-by: Vlastimil Babka <vbabka@suse.cz>
---
 mm/debug.c | 17 +++++++++++++++++
 1 file changed, 17 insertions(+)

diff --git a/mm/debug.c b/mm/debug.c
index 9feb699c5d25..9f67ad74d036 100644
--- a/mm/debug.c
+++ b/mm/debug.c
@@ -48,6 +48,8 @@ void __dump_page(struct page *page, const char *reason)
 	 * encode own info.
 	 */
 	int mapcount = PageSlab(page) ? 0 : page_mapcount(page);
+	int i;
+	const int words_per_line = (sizeof(unsigned long) == 8) ? 4 : 8;
 
 	pr_emerg("page:%p count:%d mapcount:%d mapping:%p index:%#lx",
 		  page, page_ref_count(page), mapcount,
@@ -59,6 +61,21 @@ void __dump_page(struct page *page, const char *reason)
 
 	pr_emerg("flags: %#lx(%pGp)\n", page->flags, &page->flags);
 
+	pr_alert("raw struct page data:");
+	for (i = 0; i < sizeof(struct page) / sizeof(unsigned long); i++) {
+		unsigned long *word_ptr;
+
+		word_ptr = ((unsigned long *) page) + i;
+
+		if ((i % words_per_line) == 0) {
+			pr_cont("\n");
+			pr_alert(" %016lx", *word_ptr);
+		} else {
+			pr_cont(" %016lx", *word_ptr);
+		}
+	}
+	pr_cont("\n");
+
 	if (reason)
 		pr_alert("page dumped because: %s\n", reason);
 
-- 
2.10.2

[toc] | [prev] | [standalone]


Back to top | Article view | linux.kernel


csiph-web