From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1760145AbYDYML5 (ORCPT ); Fri, 25 Apr 2008 08:11:57 -0400 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S1753914AbYDYMLu (ORCPT ); Fri, 25 Apr 2008 08:11:50 -0400 Received: from bombadil.infradead.org ([18.85.46.34]:37211 "EHLO bombadil.infradead.org" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1753905AbYDYMLs (ORCPT ); Fri, 25 Apr 2008 08:11:48 -0400 Subject: Re: [ INFO: iconsistent lock state ] From: Peter Zijlstra To: Justin Mattock Cc: Linux Kernel Mailing List , Ingo Molnar , Venki Pallipadi , "Brown, Len" In-Reply-To: References: Content-Type: text/plain Date: Fri, 25 Apr 2008 14:11:10 +0200 Message-Id: <1209125470.24931.10.camel@lappy> Mime-Version: 1.0 X-Mailer: Evolution 2.22.1 Content-Transfer-Encoding: 7bit Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org On Fri, 2008-04-25 at 00:24 +0000, Justin Mattock wrote: > [ 13.269763] ================================= > [ 13.270954] [ INFO: inconsistent lock state ] > [ 13.271865] 2.6.25-04569-gb69d398 #3 What tree is that? git checkout gb69d398 doesn't want to happen here. > [ 13.272614] --------------------------------- > [ 13.273521] inconsistent {in-hardirq-W} -> {hardirq-on-W} usage. > [ 13.274745] swapper/0 [HC0[0]:SC0[0]:HE1:SE1] takes: > [ 13.275787] (&rq->rq_lock_key){++..}, at: [] sched_clock_idle_wakeup_event+0x43/0x74 > [ 13.276859] {in-hardirq-W} state was registered at: > [ 13.276859] [] __lock_acquire+0x419/0xb70 > [ 13.276859] [] lock_acquire+0x7f/0xa6 > [ 13.280188] [] _spin_lock+0x1c/0x49 > [ 13.280188] [] scheduler_tick+0x43/0x1bd > [ 13.280188] [] update_process_times+0x3d/0x49 > [ 13.283524] [] tick_periodic+0x66/0x72 > [ 13.283524] [] tick_handle_periodic+0x19/0x6a > [ 13.283524] [] timer_interrupt+0x47/0x4e > [ 13.286855] [] handle_IRQ_event+0x1a/0x4f > [ 13.286855] [] handle_level_irq+0x7f/0xca > [ 13.286855] [] do_IRQ+0x71/0x8a > [ 13.290190] [] common_interrupt+0x2e/0x34 > [ 13.290190] [] calibrate_delay+0x8f/0x276 > [ 13.290190] [] start_kernel+0x27c/0x2f8 > [ 13.293520] [] __init_begin+0x8/0xa > [ 13.293520] [] 0xffffffff > [ 13.293520] irq event stamp: 253965 > [ 13.296856] hardirqs last enabled at (253965): [] native_sched_clock+0xe7/0xff > [ 13.296856] hardirqs last disabled at (253964): [] native_sched_clock+0x6d/0xff > [ 13.300190] softirqs last enabled at (253958): [] __do_softirq+0xf9/0xff > [ 13.300190] softirqs last disabled at (253953): [] do_softirq+0x4d/0x79 > [ 13.303522] > [ 13.303522] other info that might help us debug this: > [ 13.303522] no locks held by swapper/0. > [ 13.303522] > [ 13.303522] stack backtrace: > [ 13.306852] Pid: 0, comm: swapper Not tainted 2.6.25-04569-gb69d398 #3 > [ 13.336851] [] print_usage_bug+0x106/0x113 > [ 13.340185] [] mark_lock+0x1ed/0x3a5 > [ 13.343519] [] __lock_acquire+0x48e/0xb70 > [ 13.346852] [] lock_acquire+0x7f/0xa6 > [ 13.350185] [] ? sched_clock_idle_wakeup_event+0x43/0x74 > [ 13.353519] [] _spin_lock+0x1c/0x49 > [ 13.360185] [] ? sched_clock_idle_wakeup_event+0x43/0x74 > [ 13.363518] [] sched_clock_idle_wakeup_event+0x43/0x74 > [ 13.366851] [] acpi_idle_enter_bm+0x2b3/0x333 [processor] > [ 13.370184] [] cpuidle_idle_call+0x63/0x92 > [ 13.373517] [] ? cpuidle_idle_call+0x0/0x92 > [ 13.380184] [] cpu_idle+0xb6/0xd6 > [ 13.383517] [] rest_init+0x49/0x4b > [ 13.386850] ======================= looking at current -linus I'm not directly seeing how we can get there with interrupts enabled.