Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1752137Ab0L3XAZ (ORCPT ); Thu, 30 Dec 2010 18:00:25 -0500 Received: from cantor.suse.de ([195.135.220.2]:36514 "EHLO mx1.suse.de" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1750967Ab0L3XAY convert rfc822-to-8bit (ORCPT ); Thu, 30 Dec 2010 18:00:24 -0500 Date: Fri, 31 Dec 2010 10:00:12 +1100 From: Neil Brown To: =?utf-8?B?UGF3ZcWC?= Sikora Cc: Pekka Enberg , linux-kernel@vger.kernel.org, akpm@linux-foundation.org Subject: Re: [2.6.37-rc8] BUG kmalloc-256: Poison overwritten. Message-ID: <20101231100012.3e6e3c1f@notabene.brown> In-Reply-To: <201012302039.33305.pluto@agmk.net> References: <201012301608.40859.pluto@agmk.net> <1293723098.25156.2.camel@jaguar> <201012302039.33305.pluto@agmk.net> X-Mailer: Claws Mail 3.7.8 (GTK+ 2.20.1; x86_64-unknown-linux-gnu) Mime-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 8BIT Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org Content-Length: 13262 Lines: 183 Please report exactly which kernel you are running (git hash of head) and in particular whether commit 589a594be1fb8815b3f18e517be696c48664f728 is present? It looks like something tried to lock conf->device_lock after conf had been freed. It is possible that that could happen due to the bug fixed by the above commit. Thanks, NeilBrown On Thu, 30 Dec 2010 20:39:33 +0100 Paweł Sikora wrote: > On Thursday 30 of December 2010 16:31:38 Pekka Enberg wrote: > > On Thu, 2010-12-30 at 16:08 +0100, Pawel Sikora wrote: > > > [ 1863.448308] ============================================================================= > > > [ 1863.448313] BUG kmalloc-256: Poison overwritten > > > [ 1863.448315] ----------------------------------------------------------------------------- > > > [ 1863.448316] > > > [ 1863.448319] INFO: 0xffff8807ffc7e7c4-0xffff8807ffc7e7c5. First byte 0x6c instead of 0x6b > > > [ 1863.448331] INFO: Allocated in setup_conf+0x12b/0x360 [raid10] age=554800 cpu=5 pid=2766 > > > [ 1863.448336] INFO: Freed in stop+0x66/0x80 [raid10] age=4271 cpu=3 pid=5266 > > > [ 1863.448339] INFO: Slab 0xffffea001bff3b90 objects=24 used=11 fp=0xffff8807ffc7e7b0 flags=0x6000000000040c1 > > > [ 1863.448341] INFO: Object 0xffff8807ffc7e7b0 @offset=1968 fp=0xffff8807ffc7f338 > > > [ 1863.448343] > > > [ 1863.448345] Bytes b4 0xffff8807ffc7e7a0: a9 c6 fe ff 00 00 00 00 5a 5a 5a 5a 5a 5a 5a 5a ����....ZZZZZZZZ > > > [ 1863.448353] Object 0xffff8807ffc7e7b0: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448362] Object 0xffff8807ffc7e7c0: 6b 6b 6b 6b 6c 6c 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkllkkkkkkkkkk > > > [ 1863.448369] Object 0xffff8807ffc7e7d0: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448377] Object 0xffff8807ffc7e7e0: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448384] Object 0xffff8807ffc7e7f0: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448391] Object 0xffff8807ffc7e800: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448399] Object 0xffff8807ffc7e810: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448406] Object 0xffff8807ffc7e820: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448413] Object 0xffff8807ffc7e830: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448421] Object 0xffff8807ffc7e840: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448428] Object 0xffff8807ffc7e850: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448435] Object 0xffff8807ffc7e860: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448442] Object 0xffff8807ffc7e870: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448450] Object 0xffff8807ffc7e880: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448457] Object 0xffff8807ffc7e890: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > > > [ 1863.448464] Object 0xffff8807ffc7e8a0: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b a5 kkkkkkkkkkkkkkk� > > > [ 1863.448472] Redzone 0xffff8807ffc7e8b0: bb bb bb bb bb bb bb bb �������� > > > [ 1863.448478] Padding 0xffff8807ffc7e8f0: 5a 5a 5a 5a 5a 5a 5a 5a ZZZZZZZZ > > > [ 1863.448487] Pid: 5282, comm: udevd Not tainted 2.6.37-rc8 #1 > > > [ 1863.448489] Call Trace: > > > [ 1863.448499] [] print_trailer+0xfe/0x160 > > > [ 1863.448503] [] check_bytes_and_report+0xf4/0x130 > > > [ 1863.448506] [] check_object+0x22a/0x270 > > > [ 1863.448512] [] ? do_execve+0x59/0x390 > > > [ 1863.448515] [] ? do_execve+0x59/0x390 > > > [ 1863.448519] [] alloc_debug_processing+0x110/0x1f0 > > > [ 1863.448522] [] __slab_alloc+0x3a9/0x410 > > > [ 1863.448528] [] ? do_page_fault+0x1cc/0x4b0 > > > [ 1863.448531] [] ? do_execve+0x59/0x390 > > > [ 1863.448534] [] kmem_cache_alloc_notrace+0xb8/0xc0 > > > [ 1863.448538] [] do_execve+0x59/0x390 > > > [ 1863.448543] [] ? strncpy_from_user+0x31/0x50 > > > [ 1863.448548] [] sys_execve+0x45/0x70 > > > [ 1863.448553] [] stub_execve+0x6c/0xc0 > > > [ 1863.448556] FIX kmalloc-256: Restoring 0xffff8807ffc7e7c4-0xffff8807ffc7e7c5=0x6b > > > [ 1863.448557] > > > [ 1863.448559] FIX kmalloc-256: Marking all objects used > > > > This looks like a use-after-free bug somewhere in drivers/md/raid10.c. > > > > Pekka > > i think it's quite easy to reproduce this problem. here's a mini howto: > > - setup two raid10 matrices. > > [root@odra ~]# cat /proc/mdstat > Personalities : [raid1] [raid0] [raid10] > md3 : active raid10 sdd4[1] sdc4[0] > 424757248 blocks super 1.2 512K chunks 2 far-copies [2/2] [UU] > [>....................] resync = 0.4% (1966592/424757248) finish=82.4min speed=85504K/sec > > md2 : active raid10 sdb4[1] sda4[0] > 424757248 blocks super 1.2 512K chunks 2 far-copies [2/2] [UU] > [>....................] resync = 0.5% (2446080/424757248) finish=97.1min speed=72432K/sec > > - stop matrices. > > [root@odra ~]# mdadm --stop /dev/md2 > mdadm: stopped /dev/md2 > [root@odra ~]# mdadm --stop /dev/md3 > mdadm: stopped /dev/md3 > > - create raid0 on devices previously used by raid10. > > [root@odra ~]# mdadm -C /dev/md2 -l 0 -n 4 /dev/sda4 /dev/sdb4 /dev/sdc4 /dev/sdd4 > > mdadm: Defaulting to version 1.2 metadata > mdadm: array /dev/md2 started. > > [root@odra ~]# cat /proc/mdstat > Personalities : [raid1] [raid0] [raid10] > md2 : active raid0 sdd4[3] sdc4[2] sdb4[1] sda4[0] > 1699028992 blocks super 1.2 512k chunks > > - stop it. > > [root@odra ~]# mdadm --stop /dev/md2 > mdadm: stopped /dev/md2 > > - create one raid10 matrix once more. > > [root@odra ~]# mdadm -C /dev/md2 -l 10 -n 2 --layout f2 /dev/sda4 /dev/sdb4 > mdadm: Defaulting to version 1.2 metadata > mdadm: array /dev/md2 started. > > - in this moment i can see a bug report. > > Dec 30 20:08:46 odra kernel: [12501.627162] ============================================================================= > Dec 30 20:08:46 odra kernel: [12501.627166] BUG kmalloc-256: Poison overwritten > Dec 30 20:08:46 odra kernel: [12501.627168] ----------------------------------------------------------------------------- > Dec 30 20:08:46 odra kernel: [12501.627169] > Dec 30 20:08:46 odra kernel: [12501.627172] INFO: 0xffff8803feb5e15c-0xffff8803feb5e15d. First byte 0x6c instead of 0x6b > Dec 30 20:08:46 odra kernel: [12501.627178] INFO: Allocated in setup_conf+0x12b/0x360 [raid10] age=58297 cpu=2 pid=12007 > Dec 30 20:08:46 odra kernel: [12501.627182] INFO: Freed in stop+0x66/0x80 [raid10] age=47657 cpu=2 pid=12047 > Dec 30 20:08:46 odra kernel: [12501.627185] INFO: Slab 0xffffea000dfb7c90 objects=24 used=2 fp=0xffff8803feb5e148 flags=0x2000000000040c1 > Dec 30 20:08:46 odra kernel: [12501.627188] INFO: Object 0xffff8803feb5e148 @offset=328 fp=0xffff8803feb5e3d8 > Dec 30 20:08:46 odra kernel: [12501.627189] > Dec 30 20:08:46 odra kernel: [12501.627191] Bytes b4 0xffff8803feb5e138: df a8 fe ff 00 00 00 00 5a 5a 5a 5a 5a 5a 5a 5a ߨ��....ZZZZZZZZ > Dec 30 20:08:46 odra kernel: [12501.627199] Object 0xffff8803feb5e148: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627208] Object 0xffff8803feb5e158: 6b 6b 6b 6b 6c 6c 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkllkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627215] Object 0xffff8803feb5e168: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627223] Object 0xffff8803feb5e178: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627230] Object 0xffff8803feb5e188: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627237] Object 0xffff8803feb5e198: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627245] Object 0xffff8803feb5e1a8: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627252] Object 0xffff8803feb5e1b8: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627259] Object 0xffff8803feb5e1c8: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627267] Object 0xffff8803feb5e1d8: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627274] Object 0xffff8803feb5e1e8: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627281] Object 0xffff8803feb5e1f8: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627289] Object 0xffff8803feb5e208: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627296] Object 0xffff8803feb5e218: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627303] Object 0xffff8803feb5e228: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b kkkkkkkkkkkkkkkk > Dec 30 20:08:46 odra kernel: [12501.627311] Object 0xffff8803feb5e238: 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b 6b a5 kkkkkkkkkkkkkkk� > Dec 30 20:08:46 odra kernel: [12501.627318] Redzone 0xffff8803feb5e248: bb bb bb bb bb bb bb bb �������� > Dec 30 20:08:46 odra kernel: [12501.627325] Padding 0xffff8803feb5e288: 5a 5a 5a 5a 5a 5a 5a 5a ZZZZZZZZ > Dec 30 20:08:46 odra kernel: [12501.627334] Pid: 12168, comm: mdadm Not tainted 2.6.37-rc8 #1 > Dec 30 20:08:46 odra kernel: [12501.627336] Call Trace: > Dec 30 20:08:46 odra kernel: [12501.627343] [] print_trailer+0xfe/0x160 > Dec 30 20:08:46 odra kernel: [12501.627347] [] check_bytes_and_report+0xf4/0x130 > Dec 30 20:08:46 odra kernel: [12501.627350] [] check_object+0x22a/0x270 > Dec 30 20:08:46 odra kernel: [12501.627354] [] ? setup_conf+0x12b/0x360 [raid10] > Dec 30 20:08:46 odra kernel: [12501.627358] [] ? setup_conf+0x12b/0x360 [raid10] > Dec 30 20:08:46 odra kernel: [12501.627361] [] alloc_debug_processing+0x110/0x1f0 > Dec 30 20:08:46 odra kernel: [12501.627365] [] __slab_alloc+0x3a9/0x410 > Dec 30 20:08:46 odra kernel: [12501.627369] [] ? mempool_alloc_slab+0x10/0x20 > Dec 30 20:08:46 odra kernel: [12501.627372] [] ? kmem_cache_alloc_node_notrace+0xbf/0xe0 > Dec 30 20:08:46 odra kernel: [12501.627376] [] ? mempool_create_node+0x7e/0x1a0 > Dec 30 20:08:46 odra kernel: [12501.627379] [] ? setup_conf+0x12b/0x360 [raid10] > Dec 30 20:08:46 odra kernel: [12501.627382] [] kmem_cache_alloc_notrace+0xb8/0xc0 > Dec 30 20:08:46 odra kernel: [12501.627386] [] setup_conf+0x12b/0x360 [raid10] > Dec 30 20:08:46 odra kernel: [12501.627390] [] run+0x21/0x3c0 [raid10] > Dec 30 20:08:46 odra kernel: [12501.627413] [] md_run+0x322/0x920 [md_mod] > Dec 30 20:08:46 odra kernel: [12501.627417] [] ? __mutex_lock_interruptible_slowpath+0x1e0/0x2b0 > Dec 30 20:08:46 odra kernel: [12501.627425] [] do_md_run+0x19/0xa0 [md_mod] > Dec 30 20:08:46 odra kernel: [12501.627432] [] md_ioctl+0xa1c/0x1350 [md_mod] > Dec 30 20:08:46 odra kernel: [12501.627435] [] ? check_object+0xaf/0x270 > Dec 30 20:08:46 odra kernel: [12501.627438] [] ? init_object+0x46/0x80 > Dec 30 20:08:46 odra kernel: [12501.627442] [] blkdev_ioctl+0x230/0x720 > Dec 30 20:08:46 odra kernel: [12501.627445] [] ? __slab_free+0x136/0x150 > Dec 30 20:08:46 odra kernel: [12501.627449] [] block_ioctl+0x3c/0x40 > Dec 30 20:08:46 odra kernel: [12501.627453] [] do_vfs_ioctl+0x98/0x580 > Dec 30 20:08:46 odra kernel: [12501.627456] [] ? remove_vma+0x69/0x90 > Dec 30 20:08:46 odra kernel: [12501.627460] [] ? do_munmap+0x2e4/0x360 > Dec 30 20:08:46 odra kernel: [12501.627463] [] sys_ioctl+0x81/0xa0 > Dec 30 20:08:46 odra kernel: [12501.627467] [] system_call_fastpath+0x16/0x1b -- To unsubscribe from this list: send the line "unsubscribe linux-kernel" in the body of a message to majordomo@vger.kernel.org More majordomo info at http://vger.kernel.org/majordomo-info.html Please read the FAQ at http://www.tux.org/lkml/