From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1751883AbWGAAtW (ORCPT ); Fri, 30 Jun 2006 20:49:22 -0400 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S1751884AbWGAAtW (ORCPT ); Fri, 30 Jun 2006 20:49:22 -0400 Received: from smtp.osdl.org ([65.172.181.4]:23746 "EHLO smtp.osdl.org") by vger.kernel.org with ESMTP id S1751883AbWGAAtV (ORCPT ); Fri, 30 Jun 2006 20:49:21 -0400 Date: Fri, 30 Jun 2006 17:52:41 -0700 From: Andrew Morton To: "Jesse Brandeburg" Cc: linux-kernel@vger.kernel.org, johnstul@us.ibm.com Subject: Re: 2.6.17-mm4 Message-Id: <20060630175241.29c4323d.akpm@osdl.org> In-Reply-To: <4807377b0606301717t35d783cbgad6d67daeb163f5a@mail.gmail.com> References: <20060629013643.4b47e8bd.akpm@osdl.org> <4807377b0606291053g602f3413gb3a60d1432a62242@mail.gmail.com> <20060629120518.e47e73a9.akpm@osdl.org> <4807377b0606301653n68bee302t33c2cc28b8c5040@mail.gmail.com> <20060630171212.50630182.akpm@osdl.org> <4807377b0606301717t35d783cbgad6d67daeb163f5a@mail.gmail.com> X-Mailer: Sylpheed version 1.0.0 (GTK+ 1.2.10; i386-vine-linux-gnu) Mime-Version: 1.0 Content-Type: text/plain; charset=US-ASCII Content-Transfer-Encoding: 7bit Sender: linux-kernel-owner@vger.kernel.org X-Mailing-List: linux-kernel@vger.kernel.org "Jesse Brandeburg" wrote: > > > > > I guess it has to be dying in timekeeping_init(). Have you tried > > earlyprintk=vga or, better, earlyprintk=serial,ttyS0,9600? > > wish you would have said that earlier, i looked in > kernel-parameters.txt and didn't find earlyprinkt when searching for > debug :-( sorry. > two traces follow, one with the stock 2.6.17-mm4 kernel and the other > with the bisect kernel. > > > Linux version 2.6.17-mm4-jesse (root@lindenhurst-2.jf.intel.com) (gcc version 3. > 4.4 20050721 (Red Hat 3.4.4-2)) #1 SMP Thu Jun 29 08:53:59 PDT 2006 > BIOS-provided physical RAM map: > BIOS-e820: 0000000000000000 - 000000000009fc00 (usable) > BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved) > BIOS-e820: 00000000000ebaf0 - 0000000000100000 (reserved) > BIOS-e820: 0000000000100000 - 000000005ffd0000 (usable) > BIOS-e820: 000000005ffd0000 - 000000005ffdf000 (ACPI data) > BIOS-e820: 000000005ffdf000 - 0000000060000000 (ACPI NVS) > BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved) > BIOS-e820: 00000000ffc00000 - 0000000100000000 (reserved) > kernel direct mapping tables up to 100000000 @ 8000-d000 > DMI not present or invalid. > No NUMA configuration found > Faking a node at 0000000000000000-000000005ffd0000 > Bootmem setup node 0 0000000000000000-000000005ffd0000 > ACPI: PM-Timer IO Port: 0x408 > ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled) > Processor #0 15:3 APIC version 20 > ACPI: LAPIC (acpi_id[0x02] lapic_id[0x06] enabled) > Processor #6 15:3 APIC version 20 > ACPI: LAPIC (acpi_id[0x03] lapic_id[0x01] enabled) > Processor #1 15:3 APIC version 20 > ACPI: LAPIC (acpi_id[0x04] lapic_id[0x07] enabled) > Processor #7 15:3 APIC version 20 > ACPI: IOAPIC (id[0x08] address[0xfec00000] gsi_base[0]) > IOAPIC[0]: apic_id 8, version 32, address 0xfec00000, GSI 0-23 > ACPI: IOAPIC (id[0x09] address[0xfec80000] gsi_base[24]) > IOAPIC[1]: apic_id 9, version 32, address 0xfec80000, GSI 24-47 > ACPI: IOAPIC (id[0x0a] address[0xfec80400] gsi_base[48]) > IOAPIC[2]: apic_id 10, version 32, address 0xfec80400, GSI 48-71 > ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) > ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) > Setting APIC routing to flat > ACPI: HPET id: 0x8086a202 base: 0xfed00000 > Using ACPI (MADT) for SMP configuration information > Allocating PCI resources starting at 68000000 (gap: 60000000:80000000) > Built 1 zonelists. Total pages: 386918 > Kernel command line: ro root=LABEL=/1 rhgb hdc=none video=atyfb:1024x768M-32@70 > console=ttyS0,115200n8 console=tty1 panic=30 earlyprintk=serial,ttyS0,115200 ini > tcall_debug debug > ide_setup: hdc=none > Initializing CPU#0 > PID hash table entries: 4096 (order: 12, 32768 bytes) > time.c: Using 14.318180 MHz WALL HPET GTOD HPET/TSC timer. > time.c: Detected 3600.267 MHz processor. > Unable to handle kernel NULL pointer dereference at 0000000000000034 RIP: > [] do_timer+0x40/0x556 > PGD 0 > Oops: 0000 [1] SMP > last sysfs file: > CPU 0 > Modules linked in: > Pid: 0, comm: swapper Not tainted 2.6.17-mm4-jesse #1 > RIP: 0010:[] [] do_timer+0x40/0x556 > RSP: 0018:ffffffff8130ce80 EFLAGS: 00010002 > RAX: 0000000000000000 RBX: 000000000036ef8b RCX: 0000005135618814 > RDX: 0000000000000000 RSI: 00000000001233fd RDI: ffffffff813adec8 > RBP: 0000000000000000 R08: 00000000fffffffe R09: 0000000000000000 > R10: 0000000000000000 R11: 0000000000000246 R12: 00000000000019fb > R13: 0000000000000000 R14: ffffffff813adec8 R15: ffffffff813adec8 > FS: 0000000000000000(0000) GS:ffffffff813a0000(0000) knlGS:0000000000000000 > CS: 0010 DS: 0018 ES: 0018 CR0: 000000008005003b > CR2: 0000000000000034 CR3: 0000000001001000 CR4: 00000000000006a0 > Process swapper (pid: 0, threadinfo ffffffff813ac000, task ffffffff8128be00) > Stack: 0000000000000000 0000000000000000 0000000000000001 0000000000000000 > 0000000000000000 000000000036ef8b 0000000000000000 00000000000019fb > 0000000000000000 ffffffff813adec8 ffffffff813adec8 ffffffff8100d442 > Call Trace: > [] main_timer_handler+0x1ed/0x3ad > [] timer_interrupt+0x12/0x27 > [] handle_IRQ_event+0x29/0x5a > [] __do_IRQ+0x9c/0xfd > [] do_IRQ+0x63/0x71 > [] ret_from_intr+0x0/0xa > Right. This kernel presumably has the shiny new stack unwinding code enabled, so we didn't get half the backtrace (dammit). Are you able to recall whether the 2.6.17-mm4 build had CONFIG_UNWIND_INFO enabled? > Code: 8b 4a 34 48 d3 e0 48 01 42 58 48 8b 2d b5 3b 31 00 4c 8b 6d > RIP [] do_timer+0x40/0x556 > RSP > CR2: 0000000000000034 > <0>Kernel panic - not syncing: Aiee, killing interrupt handler! > > and with the bisect kernel, slightly different > Bootdata ok (command line is ro root=LABEL=/1 rhgb hdc=none video=atyfb:1024x768 > M-32@70 console=ttyS0,115200n8 console=tty1 panic=30 earlyprintk=serial,ttyS0,11 > 5200 initcall_debug debug) > Linux version 2.6.17-bisect (root@lindenhurst-2.jf.intel.com) (gcc version 3.4.4 > 20050721 (Red Hat 3.4.4-2)) #15 SMP Fri Jun 30 16:09:50 PDT 2006 > BIOS-provided physical RAM map: > BIOS-e820: 0000000000000000 - 000000000009fc00 (usable) > BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved) > BIOS-e820: 00000000000ebaf0 - 0000000000100000 (reserved) > BIOS-e820: 0000000000100000 - 000000005ffd0000 (usable) > BIOS-e820: 000000005ffd0000 - 000000005ffdf000 (ACPI data) > BIOS-e820: 000000005ffdf000 - 0000000060000000 (ACPI NVS) > BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved) > BIOS-e820: 00000000ffc00000 - 0000000100000000 (reserved) > kernel direct mapping tables up to 100000000 @ 8000-8000 > DMI not present or invalid. > ACPI: PM-Timer IO Port: 0x408 > ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled) > Processor #0 15:3 APIC version 20 > ACPI: LAPIC (acpi_id[0x02] lapic_id[0x06] enabled) > Processor #6 15:3 APIC version 20 > ACPI: LAPIC (acpi_id[0x03] lapic_id[0x01] enabled) > Processor #1 15:3 APIC version 20 > ACPI: LAPIC (acpi_id[0x04] lapic_id[0x07] enabled) > Processor #7 15:3 APIC version 20 > ACPI: IOAPIC (id[0x08] address[0xfec00000] gsi_base[0]) > IOAPIC[0]: apic_id 8, version 32, address 0xfec00000, GSI 0-23 > ACPI: IOAPIC (id[0x09] address[0xfec80000] gsi_base[24]) > IOAPIC[1]: apic_id 9, version 32, address 0xfec80000, GSI 24-47 > ACPI: IOAPIC (id[0x0a] address[0xfec80400] gsi_base[48]) > IOAPIC[2]: apic_id 10, version 32, address 0xfec80400, GSI 48-71 > ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) > ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) > Setting APIC routing to flat > ACPI: HPET id: 0x8086a202 base: 0xfed00000 > Using ACPI (MADT) for SMP configuration information > Allocating PCI resources starting at 68000000 (gap: 60000000:80000000) > Checking aperture... > Built 1 zonelists. Total pages: 385592 > Kernel command line: ro root=LABEL=/1 rhgb hdc=none video=atyfb:1024x768M-32@70 > console=ttyS0,115200n8 console=tty1 panic=30 earlyprintk=serial,ttyS0,115200 ini > tcall_debug debug > ide_setup: hdc=none > Initializing CPU#0 > PID hash table entries: 4096 (order: 12, 32768 bytes) > time.c: Using 14.318180 MHz WALL HPET GTOD HPET/TSC timer. > time.c: Detected 3600.266 MHz processor. > Unable to handle kernel NULL pointer dereference at 0000000000000020 RIP: > {do_timer+49} > PGD 0 > Oops: 0000 [1] SMP > CPU 0 > Modules linked in: > Pid: 0, comm: swapper Not tainted 2.6.17-bisect #15 > RIP: 0010:[] {do_timer+49} > RSP: 0018:ffffffff80564e98 EFLAGS: 00010002 > RAX: 0000000000000000 RBX: 000000000036ef8a RCX: 00000064e38a4032 > RDX: 0000000000000000 RSI: 00000000001233fe RDI: ffffffff805f3e48 > RBP: ffffffff80564eb8 R08: 0000000000000036 R09: 0000000000000000 > R10: ffffffff805f3e70 R11: 0000000000000000 R12: 0000000000000000 > R13: 00000000000019fa R14: 0000000000000001 R15: ffffffff805f3e48 > FS: 0000000000000000(0000) GS:ffffffff805e5000(0000) knlGS:0000000000000000 > CS: 0010 DS: 0018 ES: 0018 CR0: 000000008005003b > CR2: 0000000000000020 CR3: 0000000000201000 CR4: 00000000000006a0 > Process swapper (pid: 0, threadinfo ffffffff805f2000, task ffffffff804adb00) > Stack: 000000000036ef8a 0000000000000000 00000000000019fa 0000000000000000 > ffffffff80564ef8 ffffffff8020e1c3 0000000000000000 ffffffff804afb00 > 0000000000000000 ffffffff805f3e48 > Call Trace: {main_timer_handler+547} > {timer_interrupt+21} {handle_IRQ_even > t+51} > {__do_IRQ+178} {do_IRQ+69} > {ret_from_intr+0} {_spin_unlock > _irqrestore+19} > {setup_irq+222} {time_init+990} > {start_kernel+250} {_sinittext+661} > > Code: ff 50 20 4c 8b 2d 3d 2b 36 00 48 8b 15 3e 2b 36 00 49 89 c4 > RIP {do_timer+49} RSP > CR2: 0000000000000020 > <0>Kernel panic - not syncing: Aiee, killing interrupt handler! And this is the traditional backtrace which is actually useful. You've hit about seventeen bugs all at the same time here :( - There's something wrong in do_timer(). Everything's been inlined so it's hard to tell where it oopsed. If you're able to build with CONFIG_DEBUG_INFO and do gdb vmlinux (gdb) l *0xNNNNNNNNN where NNNNNNNN is the oopsing RIP address then it'll tell us the file-n-line. - Somebody enabled local interrupts. The early boot code is _supposed_ to hold interrupts off until start_kernel() does local_irq_eanble(). But we do heaps of things which make that unreliable. We don't know where this happened, and fixing that might make the oops go away. I suspect that either local interrupts were enabled before you entered time_init(), or something under time_init() went and enabled interrupts, and the kernel will crash if it takes a timer interrupt before timekeeping_init() has run. As an experiment, try adding a local_irq_disable() in start_kernel(), just before it calls time_init(). This stuff really really sucks. One guess would be that something under time_init()->setup_irq() is enabling interrupts. You could sprinkle if (!irqs_disabled() printk("%s:%d: BAD", __FILE__, __LINE__); throughout kernel/irq/manage.c:setup_irq(). Boy we've made a mess in there.