Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1761436AbXEJO6V (ORCPT ); Thu, 10 May 2007 10:58:21 -0400 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S1755062AbXEJO6J (ORCPT ); Thu, 10 May 2007 10:58:09 -0400 Received: from gw.goop.org ([64.81.55.164]:37291 "EHLO mail.goop.org" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1752140AbXEJO6I (ORCPT ); Thu, 10 May 2007 10:58:08 -0400 Message-ID: <46433309.7010507@goop.org> Date: Thu, 10 May 2007 07:58:17 -0700 From: Jeremy Fitzhardinge User-Agent: Thunderbird 1.5.0.10 (X11/20070302) MIME-Version: 1.0 To: Michal Piotrowski CC: Andrew Morton , LKML , Andi Kleen , Ingo Molnar Subject: Re: [BUG] Re: mm snapshot broken-out-2007-05-10-03-06.tar.gz uploaded References: <200705101009.l4AA9kY8017872@shell0.pdx.osdl.net> <464315FF.3060203@googlemail.com> In-Reply-To: <464315FF.3060203@googlemail.com> Content-Type: text/plain; charset=ISO-8859-2 Content-Transfer-Encoding: 8bit Sender: linux-kernel-owner@vger.kernel.org X-Mailing-List: linux-kernel@vger.kernel.org Content-Length: 14939 Lines: 229 Michal Piotrowski wrote: > Hi Jeremy, > > akpm@linux-foundation.org napisa?(a): > >> The mm snapshot broken-out-2007-05-10-03-06.tar.gz has been uploaded to >> >> ftp://ftp.kernel.org/pub/linux/kernel/people/akpm/mm/broken-out-2007-05-10-03-06.tar.gz >> >> > > Bug reported here http://lkml.org/lkml/2007/4/25/400 is back. > Yes, we never worked out what was going wrong. It seems to be a strange interaction between lock API testing and Andi's sched_clock() changes, which is triggered by my softlockup changes. But given that lock API testing has no explicit interaction with either sched_clock() or the softlockup watchdog, its completely mysterious that it should be affected in this way. We ended up with an appeal to Ingo, but he's ignored it so far. J > [ 0.000000] ------------------------ > [ 0.000000] | Locking API testsuite: > [ 0.000000] ---------------------------------------------------------------------------- > [ 0.000000] | spin |wlock |rlock |mutex | wsem | rsem | > [ 0.000000] -------------------------------------------------------------------------- > [ 0.000000] A-A deadlock: ok | ok | ok | ok | ok | ok | > [ 0.000000] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | > [ 0.000001] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | > [ 0.000001] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | > [ 0.000002] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | > [ 0.000003] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | > [ 0.000004] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | > [ 0.000006] double unlock: ok | ok | ok | ok | ok | ok | > [ 0.000006] initialize held: ok | ok | ok | ok | ok | ok | > [ 0.000006] bad unlock order: ok | ok | ok | ok | ok | ok | > [ 0.000007] -------------------------------------------------------------------------- > [ 0.000007] recursive read-lock: | ok | | ok | > [ 0.000007] recursive read-lock #2: | ok | | ok | > [ 0.000007] mixed read-write-lock: | ok | | ok | > [ 0.000007] mixed write-read-lock: | ok | | ok | > [ 0.000007] -------------------------------------------------------------------------- > [ 0.000007] hard-irqs-on + irq-safe-A/12: ok | ok | ok | > [ 0.000007] soft-irqs-on + irq-safe-A/12: ok | ok | ok | > [ 0.000007] hard-irqs-on + irq-safe-A/21: ok | ok | ok | > [ 0.000007] soft-irqs-on + irq-safe-A/21: ok | ok | ok | > [ 0.000007] sirq-safe-A => hirqs-on/12: ok | ok |irq event stamp: 472 > [ 0.000008] hardirqs last enabled at (472): [] irqsafe2A_rlock_12+0xb6/0xc7 > [ 0.000008] hardirqs last disabled at (471): [] native_sched_clock+0x70/0x118 > [ 0.000008] softirqs last enabled at (468): [] irqsafe2A_rlock_12+0xa1/0xc7 > [ 0.000008] softirqs last disabled at (464): [] irqsafe2A_rlock_12+0xc/0xc7 > [ 0.000008] FAILED| [] dump_trace+0x63/0x1eb > [ 0.000008] [] show_trace_log_lvl+0x1a/0x30 > [ 0.000008] [] show_trace+0x12/0x14 > [ 0.000008] [] dump_stack+0x16/0x18 > [ 0.000008] [] dotest+0x8d/0x3f4 > [ 0.000008] [] locking_selftest+0x915/0x1a58 > [ 0.000008] [] start_kernel+0x27d/0x34f > [ 0.000008] ======================= > [ 0.000008] > [ 0.000008] sirq-safe-A => hirqs-on/21:irq event stamp: 476 > [ 0.000008] hardirqs last enabled at (476): [] irqsafe2A_spin_21+0x1c/0xc7 > [ 0.000008] hardirqs last disabled at (475): [] native_sched_clock+0x70/0x118 > [ 0.000008] softirqs last enabled at (468): [] irqsafe2A_rlock_12+0xa1/0xc7 > [ 0.000008] softirqs last disabled at (464): [] irqsafe2A_rlock_12+0xc/0xc7 > [ 0.000008] ok |irq event stamp: 480 > [ 0.000008] hardirqs last enabled at (480): [] irqsafe2A_wlock_21+0x1c/0xc7 > [ 0.000008] hardirqs last disabled at (479): [] native_sched_clock+0x70/0x118 > [ 0.000008] softirqs last enabled at (468): [] irqsafe2A_rlock_12+0xa1/0xc7 > [ 0.000008] softirqs last disabled at (464): [] irqsafe2A_rlock_12+0xc/0xc7 > [ 0.000008] ok |irq event stamp: 484 > [ 0.000008] hardirqs last enabled at (484): [] irqsafe2A_rlock_21+0x1c/0xc7 > [ 0.000008] hardirqs last disabled at (483): [] native_sched_clock+0x70/0x118 > [ 0.000008] softirqs last enabled at (468): [] irqsafe2A_rlock_12+0xa1/0xc7 > [ 0.000008] softirqs last disabled at (464): [] irqsafe2A_rlock_12+0xc/0xc7 > [ 0.000008] FAILED| [] dump_trace+0x63/0x1eb > [ 0.000008] [] show_trace_log_lvl+0x1a/0x30 > [ 0.000008] [] show_trace+0x12/0x14 > [ 0.000008] [] dump_stack+0x16/0x18 > [ 0.000008] [] dotest+0x8d/0x3f4 > [ 0.000008] [] locking_selftest+0x96b/0x1a58 > [ 0.000008] [] start_kernel+0x27d/0x34f > [ 0.000008] ======================= > [ 0.000008] > [ 0.000008] hard-safe-A + irqs-on/12: ok | ok |irq event stamp: 500 > [ 0.000008] hardirqs last enabled at (500): [] irqsafe2B_hard_rlock_12+0xaf/0xc0 > [ 0.000008] hardirqs last disabled at (499): [] native_sched_clock+0x70/0x118 > [ 0.000008] softirqs last enabled at (468): [] irqsafe2A_rlock_12+0xa1/0xc7 > [ 0.000008] softirqs last disabled at (464): [] irqsafe2A_rlock_12+0xc/0xc7 > [ 0.000008] FAILED| [] dump_trace+0x63/0x1eb > [ 0.000008] [] show_trace_log_lvl+0x1a/0x30 > [ 0.000008] [] show_trace+0x12/0x14 > [ 0.000008] [] dump_stack+0x16/0x18 > [ 0.000008] [] dotest+0x8d/0x3f4 > [ 0.000008] [] locking_selftest+0x9c1/0x1a58 > [ 0.000008] [] start_kernel+0x27d/0x34f > [ 0.000008] ======================= > [ 0.000008] > [ 0.000008] soft-safe-A + irqs-on/12: ok | ok |irq event stamp: 534 > [ 0.000008] hardirqs last enabled at (534): [] local_bh_enable+0x1b9/0x1e5 > [ 0.000008] hardirqs last disabled at (533): [] native_sched_clock+0x70/0x118 > [ 0.000008] softirqs last enabled at (532): [] irqsafe2B_soft_rlock_12+0xb5/0xc5 > [ 0.000008] softirqs last disabled at (530): [] irqsafe2B_soft_rlock_12+0xa6/0xc5 > [ 0.000008] FAILED| [] dump_trace+0x63/0x1eb > [ 0.000008] [] show_trace_log_lvl+0x1a/0x30 > [ 0.000008] [] show_trace+0x12/0x14 > [ 0.000008] [] dump_stack+0x16/0x18 > [ 0.000008] [] dotest+0x8d/0x3f4 > [ 0.000008] [] locking_selftest+0xa17/0x1a58 > [ 0.000008] [] start_kernel+0x27d/0x34f > [ 0.000008] ======================= > [ 0.000008] > [ 0.000008] hard-safe-A + irqs-on/21:irq event stamp: 538 > [ 0.000008] hardirqs last enabled at (538): [] irqsafe2B_hard_spin_21+0x1c/0xc0 > [ 0.000008] hardirqs last disabled at (537): [] native_sched_clock+0x70/0x118 > [ 0.000008] softirqs last enabled at (532): [] irqsafe2B_soft_rlock_12+0xb5/0xc5 > [ 0.000008] softirqs last disabled at (530): [] irqsafe2B_soft_rlock_12+0xa6/0xc5 > [ 0.000008] ok |irq event stamp: 542 > [ 0.000008] hardirqs last enabled at (542): [] irqsafe2B_hard_wlock_21+0x1c/0xc0 > [ 0.000008] hardirqs last disabled at (541): [] native_sched_clock+0x70/0x118 > [ 0.000008] softirqs last enabled at (532): [] irqsafe2B_soft_rlock_12+0xb5/0xc5 > [ 0.000008] softirqs last disabled at (530): [] irqsafe2B_soft_rlock_12+0xa6/0xc5 > [ 0.000008] ok |irq event stamp: 546 > [ 0.000008] hardirqs last enabled at (546): [] irqsafe2B_hard_rlock_21+0x1c/0xc0 > [ 0.000008] hardirqs last disabled at (545): [] native_sched_clock+0x70/0x118 > [ 0.000008] softirqs last enabled at (532): [] irqsafe2B_soft_rlock_12+0xb5/0xc5 > [ 0.000008] softirqs last disabled at (530): [] irqsafe2B_soft_rlock_12+0xa6/0xc5 > [ 0.000008] FAILED| [] dump_trace+0x63/0x1eb > [ 0.000008] [] show_trace_log_lvl+0x1a/0x30 > [ 0.000008] [] show_trace+0x12/0x14 > [ 0.000008] [] dump_stack+0x16/0x18 > [ 0.000008] [] dotest+0x8d/0x3f4 > [ 0.000008] [] locking_selftest+0xa6d/0x1a58 > [ 0.000008] [] start_kernel+0x27d/0x34f > [ 0.000008] ======================= > [ 0.000008] > [ 0.000008] soft-safe-A + irqs-on/21:irq event stamp: 552 > [ 0.000008] hardirqs last enabled at (552): [] local_bh_enable+0x1b9/0x1e5 > [ 0.000008] hardirqs last disabled at (551): [] native_sched_clock+0x70/0x118 > [ 0.000008] softirqs last enabled at (550): [] irqsafe2B_soft_spin_21+0x1b/0xc5 > [ 0.000008] softirqs last disabled at (548): [] irqsafe2B_soft_spin_21+0xc/0xc5 > [ 0.000008] ok |irq event stamp: 558 > [ 0.000008] hardirqs last enabled at (558): [] local_bh_enable+0x1b9/0x1e5 > [ 0.000008] hardirqs last disabled at (557): [] native_sched_clock+0x70/0x118 > [ 0.000008] softirqs last enabled at (556): [] irqsafe2B_soft_wlock_21+0x1b/0xc5 > [ 0.000008] softirqs last disabled at (554): [] irqsafe2B_soft_wlock_21+0xc/0xc5 > [ 0.000008] ok |irq event stamp: 564 > [ 0.000008] hardirqs last enabled at (564): [] local_bh_enable+0x1b9/0x1e5 > [ 0.000008] hardirqs last disabled at (563): [] native_sched_clock+0x70/0x118 > [ 0.000008] softirqs last enabled at (562): [] irqsafe2B_soft_rlock_21+0x1b/0xc5 > [ 0.000008] softirqs last disabled at (560): [] irqsafe2B_soft_rlock_21+0xc/0xc5 > [ 0.000008] FAILED| [] dump_trace+0x63/0x1eb > [ 0.000008] [] show_trace_log_lvl+0x1a/0x30 > [ 0.000008] [] show_trace+0x12/0x14 > [ 0.000008] [] dump_stack+0x16/0x18 > [ 0.000008] [] dotest+0x8d/0x3f4 > [ 0.000008] [] locking_selftest+0xac3/0x1a58 > [ 0.000008] [] start_kernel+0x27d/0x34f > [ 0.000008] ======================= > [ 0.000008] > [ 0.000008] hard-safe-A + unsafe-B #1/123: ok | ok | ok | > [ 0.000008] soft-safe-A + unsafe-B #1/123: ok | ok | ok | > [ 0.000009] hard-safe-A + unsafe-B #1/132: ok | ok | ok | > [ 0.000009] soft-safe-A + unsafe-B #1/132: ok | ok | ok | > [ 0.000009] hard-safe-A + unsafe-B #1/213: ok | ok | ok | > [ 0.000010] soft-safe-A + unsafe-B #1/213: ok | ok | ok | > [ 0.000010] hard-safe-A + unsafe-B #1/231: ok | ok | ok | > [ 0.000010] soft-safe-A + unsafe-B #1/231: ok | ok | ok | > [ 0.000010] hard-safe-A + unsafe-B #1/312: ok | ok | ok | > [ 0.000010] soft-safe-A + unsafe-B #1/312: ok | ok | ok | > [ 0.000011] hard-safe-A + unsafe-B #1/321: ok | ok | ok | > [ 0.000011] soft-safe-A + unsafe-B #1/321: ok | ok | ok | > [ 0.000011] hard-safe-A + unsafe-B #2/123: ok | ok | ok | > [ 0.000011] soft-safe-A + unsafe-B #2/123: ok | ok | ok | > [ 0.000011] hard-safe-A + unsafe-B #2/132: ok | ok | ok | > [ 0.000012] soft-safe-A + unsafe-B #2/132: ok | ok | ok | > [ 0.000012] hard-safe-A + unsafe-B #2/213: ok | ok | ok | > [ 0.000012] soft-safe-A + unsafe-B #2/213: ok | ok | ok | > [ 0.000012] hard-safe-A + unsafe-B #2/231: ok | ok | ok | > [ 0.000013] soft-safe-A + unsafe-B #2/231: ok | ok | ok | > [ 0.000013] hard-safe-A + unsafe-B #2/312: ok | ok | ok | > [ 0.000013] soft-safe-A + unsafe-B #2/312: ok | ok | ok | > [ 0.000013] hard-safe-A + unsafe-B #2/321: ok | ok | ok | > [ 0.000013] soft-safe-A + unsafe-B #2/321: ok | ok | ok | > [ 0.000014] hard-irq lock-inversion/123: ok | ok | ok | > [ 0.000014] soft-irq lock-inversion/123: ok | ok | ok | > [ 0.000014] hard-irq lock-inversion/132: ok | ok | ok | > [ 0.000014] soft-irq lock-inversion/132: ok | ok | ok | > [ 0.000014] hard-irq lock-inversion/213: ok | ok | ok | > [ 0.000015] soft-irq lock-inversion/213: ok | ok | ok | > [ 0.000015] hard-irq lock-inversion/231: ok | ok | ok | > [ 0.000015] soft-irq lock-inversion/231: ok | ok | ok | > [ 0.000015] hard-irq lock-inversion/312: ok | ok | ok | > [ 0.000015] soft-irq lock-inversion/312: ok | ok | ok | > [ 0.000016] hard-irq lock-inversion/321: ok | ok | ok | > [ 0.000016] soft-irq lock-inversion/321: ok | ok | ok | > [ 0.000016] hard-irq read-recursion/123: ok | > [ 0.000016] soft-irq read-recursion/123: ok | > [ 0.000016] hard-irq read-recursion/132: ok | > [ 0.000016] soft-irq read-recursion/132: ok | > [ 0.000016] hard-irq read-recursion/213: ok | > [ 0.000016] soft-irq read-recursion/213: ok | > [ 0.000016] hard-irq read-recursion/231: ok | > [ 0.000017] soft-irq read-recursion/231: ok | > [ 0.000017] hard-irq read-recursion/312: ok | > [ 0.000017] soft-irq read-recursion/312: ok | > [ 0.000017] hard-irq read-recursion/321: ok | > [ 0.000017] soft-irq read-recursion/321: ok | > [ 0.000017] ----------------------------------------------------------------- > [ 0.000017] BUG: 6 unexpected failures (out of 218) - debugging disabled! | > [ 0.000017] ----------------------------------------------------------------- > > http://www.stardust.webpages.pl/files/tbf/bitis-gabonica/broken-out-2007-05-10-03-06/mm-config > http://www.stardust.webpages.pl/files/tbf/bitis-gabonica/broken-out-2007-05-10-03-06/mm-dmesg > > Regards, > Michal > > - 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/