Message-ID: <20170624001738.GB7946@gmail.com>
Date:   Fri, 23 Jun 2017 17:17:44 -0700
From:   Andrei Vagin <avagin@...il.com>
To:     linux-mm@...ck.org, LKML <linux-kernel@...r.kernel.org>,
        Andrew Morton <akpm@...ux-foundation.org>
Cc:     Cyrill Gorcunov <gorcunov@...nvz.org>
Subject: Re: linux-next: BUG: Bad page state in process ip6tables-save
 pfn:1499f4

On Thu, Jun 22, 2017 at 11:21:03PM -0700, Andrei Vagin wrote:
> Hello,
> 
> We run CRIU tests for linux-next and today they triggered a kernel
> bug. I want to mention that this kernel is built with kasan. This bug
> was triggered in travis-ci. I can't reproduce it on my host. Without
> kasan, kernel crashed but it is impossible to get a kernel log for
> this case.

We use this tree
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git/

This issue isn't reproduced on the akpm-base branch and
it is reproduced each time on the akpm branch. I didn't
have time today to bisect it, will do on Monday.

The problem is reproduced on the kernel without kasan too: https://goo.gl/Dxsv4R

[  337.289845] BUG: Bad page state in process criu  pfn:19c601
[  337.295726] page:ffffdf4cc6718040 count:0 mapcount:1 mapping:dead000000000000 index:0x2ab1a9801 compound_mapcount: 1
[  337.306595] flags: 0x17fff8000000000()
[  337.312251] raw: 017fff8000000000 dead000000000000 0000000000000000 00000000ffffffff
[  337.321756] raw: ffffdf4cc6718001 0000000900000003 0000000000000000 0000000000000000
[  337.335768] page dumped because: nonzero compound_mapcount
[  337.342082] Modules linked in:
[  337.342089] CPU: 1 PID: 22242 Comm: criu Not tainted 4.12.0-rc6-next-20170623 #1
[  337.342091] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
[  337.342094] Call Trace:
[  337.342106]  dump_stack+0x85/0xc7
[  337.342113]  bad_page+0xc1/0x120
[  337.342119]  __free_pages_ok+0x3ab/0x570
[  337.342127]  free_compound_page+0x1b/0x20
[  337.342132]  free_transhuge_page+0xa4/0xb0
[  337.342138]  __put_compound_page+0x30/0x50
[  337.342143]  __put_page+0x2c/0x150
[  337.342148]  page_cache_pipe_buf_release+0x3a/0x60
[  337.342151]  iter_file_splice_write+0x2fc/0x3d0
[  337.342169]  SyS_splice+0x331/0x810
[  337.342174]  ? trace_hardirqs_on_caller+0x11f/0x190
[  337.342186]  entry_SYSCALL_64_fastpath+0x23/0xc2
[  337.342190] RIP: 0033:0x2b94bad9d9a3
[  337.342192] RSP: 002b:00007ffdfac31d98 EFLAGS: 00000246 ORIG_RAX: 0000000000000113
[  337.342196] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00002b94bad9d9a3
[  337.342198] RDX: 000000000000000c RSI: 0000000000000000 RDI: 0000000000000015
[  337.342200] RBP: 00007ffdfac31970 R08: 0000000000200000 R09: 0000000000000001
[  337.342202] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[  337.342204] R13: 0000000000000001 R14: 0000000000000001 R15: 0000000000000000
[  337.342241] Disabling lock debugging due to kernel taint
[  337.342253] page:ffffdf4cc671ff40 count:0 mapcount:0 mapping:          (null) index:0x0
[  337.352360] flags: 0x17fff8000000000()
[  337.357600] raw: 017fff8000000000 0000000000000000 0000000000000000 00000000ffffffff
[  337.366628] raw: 0000000000000000 ffffdf4cc671ff60 0000000000000000 0000000000000000
[  337.375506] page dumped because: VM_BUG_ON_PAGE(page_ref_count(page) == 0)
[  337.390656] ------------[ cut here ]------------
[  337.390658] kernel BUG at ./include/linux/mm.h:466!
[  337.406508] invalid opcode: 0000 [#1] SMP
[  337.410958] Modules linked in:
[  337.414135] CPU: 1 PID: 22242 Comm: criu Tainted: G    B           4.12.0-rc6-next-20170623 #1
[  337.424254] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
[  337.433596] task: ffff88f233f230c0 task.stack: ffffaf8f01d30000
[  337.441023] RIP: 0010:page_cache_pipe_buf_release+0x50/0x60
[  337.446712] RSP: 0018:ffffaf8f01d33de8 EFLAGS: 00010246
[  337.453440] RAX: 000000000000003e RBX: 0000000000000000 RCX: 0000000000000000
[  337.460716] RDX: ffff88f29fd152c0 RSI: 0000000000000000 RDI: ffff88f29fd0cfd8
[  337.469356] RBP: ffffaf8f01d33df0 R08: 0000000000000001 R09: 0000000000000000
[  337.476613] R10: 0000000000000000 R11: ffffffff8e11138a R12: ffff88f2286fcfd8
[  337.485277] R13: ffff88f29183c448 R14: 0000000000001000 R15: 00000000000fe000
[  337.492540] FS:  00002b94ba04f040(0000) GS:ffff88f29fd00000(0000) knlGS:0000000000000000
[  337.502144] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  337.508019] CR2: 00007f9e8e153024 CR3: 00000001c7e8f000 CR4: 00000000001406e0
[  337.516665] Call Trace:
[  337.519243]  iter_file_splice_write+0x2fc/0x3d0
[  337.525306]  SyS_splice+0x331/0x810
[  337.530303]  ? trace_hardirqs_on_caller+0x11f/0x190
[  337.535307]  entry_SYSCALL_64_fastpath+0x23/0xc2
[  337.541424] RIP: 0033:0x2b94bad9d9a3
[  337.545128] RSP: 002b:00007ffdfac31d98 EFLAGS: 00000246 ORIG_RAX: 0000000000000113
[  337.554205] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00002b94bad9d9a3
[  337.561471] RDX: 000000000000000c RSI: 0000000000000000 RDI: 0000000000000015
[  337.568727] RBP: 00007ffdfac31970 R08: 0000000000200000 R09: 0000000000000001
[  337.577366] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[  337.584618] R13: 0000000000000001 R14: 0000000000000001 R15: 0000000000000000
[  337.593262] Code: f0 ff 4f 1c 74 06 83 66 18 fe c9 c3 48 89 75 f8 e8 f6 dc f2 ff 48 8b 75 f8 83 66 18 fe c9 c3 48 c7 c6 f0 c1 e8 8e e8 70 5d f5 ff <0f> 0b 0f 1f 40 00 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 
[  337.613641] RIP: page_cache_pipe_buf_release+0x50/0x60 RSP: ffffaf8f01d33de8
[  337.620879] ---[ end trace 3db25f07d52a5dbf ]---


> 
> [  699.207570] BUG: Bad page state in process ip6tables-save  pfn:1499f4
> [  699.214542] page:ffffea0005267d00 count:-1 mapcount:0 mapping:
>     (null) index:0x1
> [  699.222758] flags: 0x17fff8000000000()
> [  699.226632] raw: 017fff8000000000 0000000000000000 0000000000000001
> ffffffffffffffff
> [  699.234495] raw: dead000000000100 dead000000000200 0000000000000000
> 0000000000000000
> [  699.242359] page dumped because: nonzero _count
> [  699.247006] Modules linked in:
> [  699.247022] CPU: 0 PID: 19609 Comm: ip6tables-save Not tainted
> 4.12.0-rc6-next-20170622 #1
> [  699.247029] Hardware name: Google Google Compute Engine/Google
> Compute Engine, BIOS Google 01/01/2011
> [  699.247035] Call Trace:
> [  699.247054]  dump_stack+0x85/0xc2
> [  699.247070]  bad_page+0xea/0x160
> [  699.247086]  check_new_page_bad+0xc2/0xe0
> [  699.247103]  get_page_from_freelist+0xfec/0x1270
> [  699.247161]  __alloc_pages_nodemask+0x1cf/0x4b0
> [  699.247188]  ? __alloc_pages_slowpath+0x1610/0x1610
> [  699.247214]  ? mark_lock+0x6d/0x860
> [  699.247223]  ? alloc_set_pte+0x7db/0x8f0
> [  699.247247]  alloc_pages_vma+0x85/0x250
> [  699.247270]  wp_page_copy+0x13c/0xad0
> [  699.247285]  ? do_wp_page+0x292/0x9a0
> [  699.247309]  ? lock_downgrade+0x2c0/0x2c0
> [  699.247320]  ? __do_fault+0x140/0x140
> [  699.247341]  ? do_raw_spin_unlock+0x88/0x130
> [  699.247361]  do_wp_page+0x29a/0x9a0
> [  699.247386]  ? finish_mkwrite_fault+0x250/0x250
> [  699.247403]  ? do_raw_spin_lock+0x93/0x120
> [  699.247427]  __handle_mm_fault+0xb94/0x1790
> [  699.247450]  ? __pmd_alloc+0x270/0x270
> [  699.247466]  ? find_held_lock+0x119/0x150
> [  699.247528]  handle_mm_fault+0x235/0x490
> [  699.247553]  __do_page_fault+0x332/0x680
> [  699.247586]  do_page_fault+0x22/0x30
> [  699.247601]  page_fault+0x28/0x30
> [  699.247609] RIP: 0033:0x2aaea0abef2b
> [  699.247616] RSP: 002b:00007ffe1deecd20 EFLAGS: 00010206
> [  699.247628] RAX: 00002aaea12f6e60 RBX: 000000037ffff1a0 RCX: 0000000000000028
> [  699.247635] RDX: 00002aaea12f6f10 RSI: 00002aaea0af0040 RDI: 00002aaea10ec000
> [  699.247642] RBP: 00007ffe1deece70 R08: 000000006fffffff R09: 0000000070000021
> [  699.247649] R10: 0000000000000031 R11: 000000006ffffdff R12: 00002aaea0af0000
> [  699.247655] R13: 00007ffe1deecf40 R14: 0000000000000003 R15: 000000006ffffeff
> [  699.247697] Disabling lock debugging due to kernel taint
> 
> Here is a whole log: https://goo.gl/5xekS3
> 
> Thanks,
> Andrei

Powered by blists - more mailing lists