From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1755355AbZETRuo (ORCPT ); Wed, 20 May 2009 13:50:44 -0400 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S1754101AbZETRue (ORCPT ); Wed, 20 May 2009 13:50:34 -0400 Received: from mx-out.daemonmail.net ([216.104.160.38]:55227 "EHLO mx-out.daemonmail.net" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1753985AbZETRud (ORCPT ); Wed, 20 May 2009 13:50:33 -0400 From: "Michael S. Zick" Reply-To: lkml@morethan.org To: Linux Kernel Subject: Lockdep dump, 2.6.30 repository head. Date: Wed, 20 May 2009 12:50:25 -0500 User-Agent: KMail/1.9.9 MIME-Version: 1.0 Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: 7bit Content-Disposition: inline Message-Id: <200905201250.29380.lkml@morethan.org> Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org git head @ 279e677faa775ad16e75c32e1bf4a37f8158bc61 Possible significant background - - ehci_hcd driver flooding printk buffer (cause of flood is separate problem) - - kernel/printk.c has some bit-rot, causing problems handling message floods (separate problem) - - kernel configured "tickless" disabled, "preempt" disabled, Uni-processor - (required to work-around the printk.c problems) usb 1-5: USB disconnect, address 3 ehci_hcd 0000:00:10.4: force halt; handhake dc724014 00004000 00004000 -> -19 irq 23: nobody cared (try booting with the "irqpoll" option) Pid: 3281, comm: vlc Not tainted 2.6.30-rc6-ce1200v-09140db #19 Call Trace: [] __report_bad_irq+0x27/0x90 [] note_interrupt+0x157/0x1a0 [] handle_fasteoi_irq+0xac/0xe0 [] handle_irq+0x34/0x60 [] do_IRQ+0x39/0x90 [] common_interrupt+0x2e/0x34 [] ? tcp_v4_do_rcv+0xf/0x330 [] ? _spin_lock_nested+0x37/0x40 [] tcp_v4_rcv+0x593/0x800 [] ip_local_deliver_finish+0xca/0x1f0 [] ? ip_local_deliver_finish+0x30/0x1f0 [] ip_local_deliver+0x30/0xa0 [] ip_rcv_finish+0xfe/0x2f0 [] ip_rcv+0x168/0x240 [] ? ip_rcv_finish+0x0/0x2f0 [] ? ip_rcv+0x0/0x240 [] netif_receive_skb+0x313/0x5e0 [] ? netif_receive_skb+0x120/0x5e0 [] rtl8139_poll+0x271/0x400 [] net_rx_action+0x167/0x1e0 [] ? net_rx_action+0x4e/0x1e0 [] __do_softirq+0x68/0x110 [] ? _spin_unlock+0x1d/0x20 [] ? handle_fasteoi_irq+0x8c/0xe0 [] do_softirq+0x46/0x50 [] irq_exit+0x35/0x40 [] do_IRQ+0x42/0x90 [] ? trace_hardirqs_on_caller+0xee/0x150 [] common_interrupt+0x2e/0x34 handlers: [] (usb_hcd_irq+0x0/0x90) Disabling IRQ #23 ================================= [ INFO: inconsistent lock state ] 2.6.30-rc6-ce1200v-09140db #19 --------------------------------- inconsistent {IN-HARDIRQ-W} -> {HARDIRQ-ON-W} usage. pulseaudio/3088 [HC0[0]:SC1[1]:HE1:SE0] takes: (&irq_desc_lock_class){?.-...}, at: [] try_one_irq+0x18/0x110 {IN-HARDIRQ-W} state was registered at: [] __lock_acquire+0x8e0/0x9f0 [] lock_acquire+0x5c/0x80 [] _spin_lock+0x32/0x40 [] handle_level_irq+0x18/0xd0 [] handle_irq+0x34/0x60 [] do_IRQ+0x39/0x90 [] common_interrupt+0x2e/0x34 [] __setup_irq+0x177/0x250 [] setup_irq+0x21/0x30 [] x86_quirk_time_init+0x2b/0x30 [] hpet_time_init+0x16/0x20 [] start_kernel+0x1e0/0x2f0 [] i386_start_kernel+0x84/0x90 [] 0xffffffff irq event stamp: 14245306 hardirqs last enabled at (14245306): [] _spin_unlock_irq+0x22/0x30 hardirqs last disabled at (14245305): [] _spin_lock_irq+0xf/0x50 softirqs last enabled at (14245280): [] __do_softirq+0xbb/0x110 softirqs last disabled at (14245303): [] do_softirq+0x46/0x50 other info that might help us debug this: 1 lock held by pulseaudio/3088: #0: (kernel/irq/spurious.c:21){+.-...}, at: [] run_timer_softirq+0xe0/0x200 stack backtrace: Pid: 3088, comm: pulseaudio Not tainted 2.6.30-rc6-ce1200v-09140db #19 Call Trace: [] print_usage_bug+0x16b/0x1b0 [] mark_lock+0x566/0x5a0 [] ? check_usage_backwards+0x0/0xa0 [] __lock_acquire+0x300/0x9f0 [] lock_acquire+0x5c/0x80 [] ? try_one_irq+0x18/0x110 [] _spin_lock+0x32/0x40 [] ? try_one_irq+0x18/0x110 [] try_one_irq+0x18/0x110 [] poll_spurious_irqs+0x4b/0x70 [] run_timer_softirq+0x181/0x200 [] ? run_timer_softirq+0xe0/0x200 [] ? poll_spurious_irqs+0x0/0x70 [] __do_softirq+0x68/0x110 [] ? tick_program_event+0x17/0x20 [] ? hrtimer_interrupt+0xbe/0x1b0 [] do_softirq+0x46/0x50 [] irq_exit+0x35/0x40 [] smp_apic_timer_interrupt+0x47/0x80 [] ? trace_hardirqs_off_thunk+0xc/0x18 [] apic_timer_interrupt+0x2f/0x34 Enjoy - this one is beyond my skill-level to fix. Mike