From owner-freebsd-current@FreeBSD.ORG Sat Nov 8 11:48:46 2003 Return-Path: Delivered-To: freebsd-current@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 6793616A4CE for ; Sat, 8 Nov 2003 11:48:46 -0800 (PST) Received: from kundenserver16.yws-admin.de (kundenserver16.yws-admin.de [217.115.154.106]) by mx1.FreeBSD.org (Postfix) with ESMTP id 4461C43F85 for ; Sat, 8 Nov 2003 11:48:44 -0800 (PST) (envelope-from flo@kasimir.com) Received: from kasimir.com (pD9E1D488.dip.t-dialin.net [217.225.212.136]) by kundenserver16.yws-admin.de (Postfix) with ESMTP id 3BD48352587 for ; Sat, 8 Nov 2003 20:48:22 +0100 (CET) Message-ID: <3FAD4885.4020404@kasimir.com> Date: Sat, 08 Nov 2003 20:48:21 +0100 From: "Florian C. Smeets" User-Agent: Mozilla/5.0 (X11; U; FreeBSD i386; en-US; rv:1.5) Gecko/20031021 Thunderbird/0.3 X-Accept-Language: en-us, en MIME-Version: 1.0 To: current@freebsd.org Content-Type: multipart/mixed; boundary="------------000500070301020607030106" Subject: hard lockup with new interrupt code, possible cause irq14: ata0 X-BeenThere: freebsd-current@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list List-Id: Discussions about the use of FreeBSD-current List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sat, 08 Nov 2003 19:48:46 -0000 This is a multi-part message in MIME format. --------------000500070301020607030106 Content-Type: text/plain; charset=us-ascii; format=flowed Content-Transfer-Encoding: 7bit Hi! I have a problem with an SMP machine. The motherboard is a bp6. Mostly the machine already locksup during boot, it is not even resonding to serial console. One time i was able to login, i could see (in top) that this process root 20 0.0 0.0 0 12 ?? WL 8:20PM 0:00.31 (irq14: ata0) started gaining all the cpu time and then the machine freezes. I tried a vmstat -i but about a second before it locks up there was nothing special there the rate for irq14: ata0 is 14 and there is a total of 1105. I tried booting in safe mode and without acpi, and there it still happens. The kernel was built from sources from about 10 hours ago. Attached you can find the output of a boot -v If you need anthing else let me know and i'll try to provide it as soon as i get up. Regards, flo --------------000500070301020607030106 Content-Type: text/plain; name="boot-v.txt" Content-Transfer-Encoding: 7bit Content-Disposition: inline; filename="boot-v.txt" Type '?' for a list of commands, 'help' for more detailed help. OK un  bpo   boot -v \|/-\|/SMAP type=01 base=0000000000000000 len=000000000009fc00 SMAP type=01 base=000000000009fc00 len=0000000000000400 SMAP type=02 base=00000000000f0000 len=0000000000010000 SMAP type=02 base=00000000fec00000 len=0000000000001000 SMAP type=02 base=00000000fee00000 len=0000000000001000 SMAP type=02 base=00000000ffff0000 len=0000000000010000 SMAP type=01 base=0000000000100000 len=000000000fef0000 SMAP type=03 base=000000000fff3000 len=000000000000d000 SMAP type=04 base=000000000fff0000 len=0000000000003000 Copyright (c) 1992-2003 The FreeBSD Project. Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 The Regents of the University of California. All rights reserved. FreeBSD 5.1-CURRENT #77: Sat Nov 8 09:59:02 CET 2003 root@bender:/space/obj/space/src/sys/BENDER Preloaded elf kernel "/boot/kernel/kernel" at 0xc07c0000. Preloaded acpi_dsdt "/boot/bp6.aml" at 0xc07c0250. MPTable: Calibrating clock(s) ... i8254 clock: 1193249 Hz CLK_USE_I8254_CALIBRATION not specified - using default frequency Timecounter "i8254" frequency 1193182 Hz quality 0 Calibrating TSC clock ... TSC clock: 501138902 Hz CPU: Pentium II/Pentium II Xeon/Celeron (501.14-MHz 686-class CPU) Origin = "GenuineIntel" Id = 0x665 Stepping = 5 Features=0x183fbff real memory = 268369920 (255 MB) Physical memory chunk(s): 0x0000000000001000 - 0x000000000009efff, 647168 bytes (158 pages) 0x0000000000100000 - 0x00000000003fffff, 3145728 bytes (768 pages) 0x0000000000829000 - 0x000000000fb49fff, 254939136 bytes (62241 pages) avail memory = 255336448 (243 MB) APIC ID: physical 0, logical 0:0 APIC ID: physical 1, logical 0:1 FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs cpu0 (BSP): APIC ID: 0 cpu1 (AP): APIC ID: 1 bios32: Found BIOS32 Service Directory header at 0xc00fb110 bios32: Entry = 0xfb590 (c00fb590) Rev = 0 Len = 1 pcibios: PCI BIOS entry at 0xf0000+0xb5c0 pnpbios: Found PnP BIOS data at 0xc00fc1e0 pnpbios: Entry = f0000:c208 Rev = 1.0 Other BIOS signatures found: ioapic0: Assuming intbase of 0 ioapic0: intpin 0 -> ExtINT (edge, activehi) ioapic0: intpin 1 -> irq 1 (edge, activehi) ioapic0: intpin 2 -> irq 2 (edge, activehi) ioapic0: intpin 3 -> irq 3 (edge, activehi) ioapic0: intpin 4 -> irq 4 (edge, activehi) ioapic0: intpin 5 -> irq 5 (edge, activehi) ioapic0: intpin 6 -> irq 6 (edge, activehi) ioapic0: intpin 7 -> irq 7 (edge, activehi) ioapic0: intpin 8 -> irq 8 (edge, activehi) ioapic0: intpin 9 -> irq 9 (edge, activehi) ioapic0: intpin 10 -> irq 10 (edge, activehi) ioapic0: intpin 11 -> irq 11 (edge, activehi) ioapic0: intpin 12 -> irq 12 (edge, activehi) ioapic0: intpin 13 -> irq 13 (edge, activehi) ioapic0: intpin 14 -> irq 14 (edge, activehi) ioapic0: intpin 15 -> irq 15 (edge, activehi) ioapic0: intpin 16 -> irq 16 (level, activelo) ioapic0: intpin 17 -> irq 17 (level, activelo) ioapic0: intpin 18 -> irq 18 (level, activelo) ioapic0: intpin 19 -> irq 19 (level, activelo) ioapic0: intpin 20 -> irq 20 (level, activelo) ioapic0: intpin 21 -> irq 21 (level, activelo) ioapic0: intpin 22 -> irq 22 (level, activelo) ioapic0: intpin 23 -> irq 23 (level, activelo) ioapic0: intpin 1 trigger: edge ioapic0: intpin 1 polarity: active-hi ioapic0: Routing IRQ 0 -> intpin 2 ioapic0: intpin 2 trigger: edge ioapic0: intpin 2 polarity: active-hi ioapic0: intpin 3 trigger: edge ioapic0: intpin 3 polarity: active-hi ioapic0: intpin 4 trigger: edge ioapic0: intpin 4 polarity: active-hi ioapic0: intpin 5 trigger: edge ioapic0: intpin 5 polarity: active-hi ioapic0: intpin 6 trigger: edge ioapic0: intpin 6 polarity: active-hi ioapic0: intpin 7 trigger: edge ioapic0: intpin 7 polarity: active-hi ioapic0: intpin 8 trigger: edge ioapic0: intpin 8 polarity: active-hi ioapic0: intpin 9 trigger: edge ioapic0: intpin 9 polarity: active-hi ioapic0: intpin 13 trigger: edge ioapic0: intpin 13 polarity: active-hi ioapic0: intpin 14 trigger: edge ioapic0: intpin 14 polarity: active-hi ioapic0: intpin 19 trigger: level ioapic0: intpin 19 polarity: active-lo ioapic0: intpin 17 trigger: level ioapic0: intpin 17 polarity: active-lo ioapic0: intpin 16 trigger: level ioapic0: intpin 16 polarity: active-lo ioapic0: intpin 18 trigger: level ioapic0: intpin 18 polarity: active-lo ioapic0: intpin 18 trigger: level ioapic0: intpin 18 polarity: active-lo ioapic0: Routing SMI -> intpin 23 lapic: Routing ExtINT -> LINT0 lapic: Routing NMI -> LINT1 ioapic0 irqs 0-23 on motherboard cpu0 BSP: ID: 0x00000000 VER: 0x00040011 LDR: 0x01000000 DFR: 0x0fffffff lint0: 0x00010700 lint1: 0x00000400 TPR: 0x00000000 SVR: 0x000001ff random: mem: Pentium Pro MTRR support enabled null: npx0: [FAST] npx0: on motherboard npx0: INT 16 interface pci_open(1): mode 1 addr port (0x0cf8) is 0x80000058 pci_open(1a): mode1res=0x80000000 (0x80000000) pci_cfgcheck: device 0 [class=060000] [hdr=00] is there (id=71908086) pcibios: BIOS version 2.10 Using $PIR table, 8 entries at 0xc00fd7e0 PCI-Only Interrupts: 10 11 12 15 Location Bus Device Pin Link IRQs slot 1 0 15 A 0x60 3 4 5 7 9 10 11 12 14 15 slot 1 0 15 B 0x61 3 4 5 7 9 10 11 12 14 15 slot 1 0 15 C 0x62 3 4 5 7 9 10 11 12 14 15 slot 1 0 15 D 0x63 3 4 5 7 9 10 11 12 14 15 slot 2 0 13 A 0x61 3 4 5 7 9 10 11 12 14 15 slot 2 0 13 B 0x62 3 4 5 7 9 10 11 12 14 15 slot 2 0 13 C 0x63 3 4 5 7 9 10 11 12 14 15 slot 2 0 13 D 0x60 3 4 5 7 9 10 11 12 14 15 slot 3 0 19 A 0x62 3 4 5 7 9 10 11 12 14 15 slot 3 0 19 B 0x62 3 4 5 7 9 10 11 12 14 15 slot 3 0 19 C 0x60 3 4 5 7 9 10 11 12 14 15 slot 3 0 19 D 0x61 3 4 5 7 9 10 11 12 14 15 slot 4 0 9 A 0x63 3 4 5 7 9 10 11 12 14 15 slot 4 0 9 B 0x60 3 4 5 7 9 10 11 12 14 15 slot 4 0 9 C 0x61 3 4 5 7 9 10 11 12 14 15 slot 4 0 9 D 0x62 3 4 5 7 9 10 11 12 14 15 slot 5 0 17 A 0x63 3 4 5 7 9 10 11 12 14 15 slot 5 0 17 B 0x60 3 4 5 7 9 10 11 12 14 15 slot 5 0 17 C 0x61 3 4 5 7 9 10 11 12 14 15 slot 5 0 17 D 0x62 3 4 5 7 9 10 11 12 14 15 slot 6 0 11 A 0x62 3 4 5 7 9 10 11 12 14 15 slot 6 0 11 B 0x63 3 4 5 7 9 10 11 12 14 15 slot 6 0 11 C 0x60 3 4 5 7 9 10 11 12 14 15 slot 6 0 11 D 0x61 3 4 5 7 9 10 11 12 14 15 embedded 0 7 A 0x60 3 4 5 7 9 10 11 12 14 15 embedded 0 7 B 0x61 3 4 5 7 9 10 11 12 14 15 embedded 0 7 C 0x62 3 4 5 7 9 10 11 12 14 15 embedded 0 7 D 0x63 3 4 5 7 9 10 11 12 14 15 embedded 0 1 A 0x60 3 4 5 7 9 10 11 12 14 15 embedded 0 1 B 0x61 3 4 5 7 9 10 11 12 14 15 embedded 0 1 C 0x62 3 4 5 7 9 10 11 12 14 15 embedded 0 1 D 0x63 3 4 5 7 9 10 11 12 14 15 pcib0: at pcibus 0 on motherboard pci0: on pcib0 pci0: physical bus=0 map[10]: type 3, range 32, base d2000000, size 22, enabled found-> vendor=0x8086, dev=0x7190, revid=0x03 bus=0, slot=0, func=0 class=06-00-00, hdrtype=0x00, mfdev=0 cmdreg=0x0006, statreg=0x2210, cachelnsz=0 (dwords) lattimer=0x20 (960 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) found-> vendor=0x8086, dev=0x7191, revid=0x03 bus=0, slot=1, func=0 class=06-04-00, hdrtype=0x01, mfdev=0 cmdreg=0x0107, statreg=0x0220, cachelnsz=0 (dwords) lattimer=0x40 (1920 ns), mingnt=0x80 (32000 ns), maxlat=0x00 (0 ns) found-> vendor=0x8086, dev=0x7110, revid=0x02 bus=0, slot=7, func=0 class=06-01-00, hdrtype=0x00, mfdev=1 cmdreg=0x000f, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) map[20]: type 4, range 32, base 0000f000, size 4, enabled found-> vendor=0x8086, dev=0x7111, revid=0x01 bus=0, slot=7, func=1 class=01-01-80, hdrtype=0x00, mfdev=0 cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x20 (960 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) map[20]: type 4, range 32, base 0000d000, size 5, enabled pcib0: slot 7 INTD routed to irq 19 found-> vendor=0x8086, dev=0x7112, revid=0x01 bus=0, slot=7, func=2 class=0c-03-00, hdrtype=0x00, mfdev=0 cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x20 (960 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=d, irq=19 map[90]: type 4, range 32, base 00005000, size 4, enabled found-> vendor=0x8086, dev=0x7113, revid=0x02 bus=0, slot=7, func=3 class=06-80-00, hdrtype=0x00, mfdev=0 cmdreg=0x0003, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) map[10]: type 4, range 32, base 0000d400, size 6, enabled pcib0: slot 13 INTA routed to irq 17 found-> vendor=0x10b7, dev=0x9050, revid=0x00 bus=0, slot=13, func=0 class=02-00-00, hdrtype=0x00, mfdev=0 cmdreg=0x0007, statreg=0x0200, cachelnsz=0 (dwords) lattimer=0x20 (960 ns), mingnt=0x03 (750 ns), maxlat=0x08 (2000 ns) intpin=a, irq=17 map[10]: type 3, range 32, base d1000000, size 24, enabled pcib0: slot 15 INTA routed to irq 16 found-> vendor=0x1013, dev=0x00a0, revid=0x2d bus=0, slot=15, func=0 class=03-00-00, hdrtype=0x00, mfdev=0 cmdreg=0x0003, statreg=0x0000, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=a, irq=16 map[10]: type 4, range 32, base 0000d800, size 3, enabled map[14]: type 4, range 32, base 0000dc00, size 2, enabled map[20]: type 4, range 32, base 0000e000, size 8, enabled pcib0: slot 19 INTA routed to irq 18 found-> vendor=0x1103, dev=0x0004, revid=0x01 bus=0, slot=19, func=0 class=01-80-00, hdrtype=0x00, mfdev=1 cmdreg=0x0005, statreg=0x0200, cachelnsz=8 (dwords) lattimer=0xf8 (7440 ns), mingnt=0x08 (2000 ns), maxlat=0x08 (2000 ns) intpin=a, irq=18 map[10]: type 4, range 32, base 0000e400, size 3, enabled map[14]: type 4, range 32, base 0000e800, size 2, enabled map[20]: type 4, range 32, base 0000ec00, size 8, enabled pcib0: slot 19 INTB routed to irq 18 found-> vendor=0x1103, dev=0x0004, revid=0x01 bus=0, slot=19, func=1 class=01-80-00, hdrtype=0x00, mfdev=1 cmdreg=0x0007, statreg=0x0200, cachelnsz=8 (dwords) lattimer=0xf8 (7440 ns), mingnt=0x08 (2000 ns), maxlat=0x08 (2000 ns) intpin=b, irq=18 agp0: mem 0xd2000000-0xd23fffff at device 0.0 on pci0 agp0: allocating GATT for aperture of size 4M pcib1: at device 1.0 on pci0 pcib1: secondary bus 1 pcib1: subordinate bus 1 pcib1: I/O decode 0xf000-0xfff pcib1: memory decode 0xfff00000-0xfffff pcib1: prefetched decode 0xfff00000-0xfffff pci1: on pcib1 pci1: physical bus=1 isab0: at device 7.0 on pci0 isa0: on isab0 atapci0: port 0xf000-0xf00f at device 7.1 on pci0 ata0: reset tp1 mask=03 ostat0=9e ostat1=50 ata0-master: stat=0x7f err=0x5e lsb=0x7f msb=0x7f ata0-slave: stat=0x50 err=0x01 lsb=0x00 msb=0x00 ata0: reset tp2 mask=03 stat0=7f stat1=50 devices=0x2 ata0: at 0x1f0 irq 14 on atapci0 ata0: [MPSAFE] ata1: at 0x170 irq 15 on atapci0 ata1: [MPSAFE] pci0: at device 7.2 (no driver attached) piix0: port 0x5000-0x500f at device 7.3 on pci0 Timecounter "PIIX" frequency 3579545 Hz quality 0 xl0: <3Com 3c905-TX Fast Etherlink XL> port 0xd400-0xd43f irq 17 at device 13.0 on pci0 xl0: using port I/O xl0: Ethernet address: 00:60:08:6f:68:7d xl0: media options word: e040 xl0: found MII/AUTO miibus0: on xl0 nsphy0: on miibus0 nsphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto xl0: bpf attached pci0: at device 15.0 (no driver attached) atapci1: port 0xe000-0xe0ff,0xdc00-0xdc03,0xd800-0xd807 irq 18 at device 19.0 on pci0 atapci1: [MPSAFE] ata2: reset tp1 mask=03 ostat0=20 ostat1=30 ata2-master: stat=0x20 err=0x20 lsb=0x20 msb=0x20 ata2-slave: stat=0x30 err=0x30 lsb=0x30 msb=0x30 ata2: reset tp2 mask=03 stat0=20 stat1=30 devices=0x0 ata2: at 0xd800 on atapci1 ata2: [MPSAFE] atapci2: port 0xec00-0xecff,0xe800-0xe803,0xe400-0xe407 irq 18 at device 19.1 on pci0 atapci2: [MPSAFE] ata3: reset tp1 mask=03 ostat0=50 ostat1=00 ata3-master: stat=0x50 err=0x01 lsb=0x00 msb=0x00 ata3-slave: stat=0x00 err=0x01 lsb=0x00 msb=0x00 ata3: reset tp2 mask=03 stat0=50 stat1=00 devices=0x1 ata3: at 0xe400 on atapci2 ata3: [MPSAFE] ata: ata0 already exists; skipping it ata: ata1 already exists; skipping it Trying Read_Port at 203 Trying Read_Port at 243 Trying Read_Port at 283 Trying Read_Port at 2c3 Trying Read_Port at 303 Trying Read_Port at 343 Trying Read_Port at 383 Trying Read_Port at 3c3 pnpbios: 14 devices, largest 78 bytes PNP0200: adding dma mask 0x10 PNP0200: adding io range 0-0xf, size=0x10, align=0 PNP0200: adding io range 0x81-0x83, size=0x3, align=0 PNP0200: adding io range 0x87-0x87, size=0x1, align=0 PNP0200: adding io range 0x89-0x8b, size=0x3, align=0 PNP0200: adding io range 0x8f-0x91, size=0x3, align=0 PNP0200: adding io range 0xc0-0xdf, size=0x20, align=0 pnpbios: handle 1 device ID PNP0200 (0002d041) PNP0100: adding irq mask 0x1 PNP0100: adding io range 0x40-0x43, size=0x4, align=0 pnpbios: handle 2 device ID PNP0100 (0001d041) PNP0b00: adding irq mask 0x100 PNP0b00: adding io range 0x70-0x71, size=0x2, align=0 pnpbios: handle 3 device ID PNP0b00 (000bd041) PNP0303: adding irq mask 0x2 PNP0303: adding io range 0x60-0x60, size=0x1, align=0 PNP0303: adding io range 0x64-0x64, size=0x1, align=0 pnpbios: handle 4 device ID PNP0303 (0303d041) PNP0800: adding io range 0x61-0x61, size=0x1, align=0 pnpbios: handle 5 device ID PNP0800 (0008d041) PNP0c04: adding irq mask 0x2000 PNP0c04: adding io range 0xf0-0xff, size=0x10, align=0 pnpbios: handle 6 device ID PNP0c04 (040cd041) PNP0c01: adding fixed memory32 range 0-0x9ffff, size=0xa0000 PNP0c01: adding fixed memory32 range 0xfffe0000-0xffffffff, size=0x20000 PNP0c01: adding fixed memory32 range 0xfec00000-0xfec0ffff, size=0x10000 PNP0c01: adding fixed memory32 range 0xfee00000-0xfee0ffff, size=0x10000 PNP0c01: adding fixed memory32 range 0x100000-0xfffffff, size=0xff00000 pnpbios: handle 7 device ID PNP0c01 (010cd041) PNP0c02: adding fixed memory32 range 0xe0000-0xeffff, size=0x10000 PNP0c02: adding fixed memory32 range 0xf0000-0xf3fff, size=0x4000 PNP0c02: adding fixed memory32 range 0xf4000-0xf7fff, size=0x4000 PNP0c02: adding fixed memory32 range 0xf8000-0xfffff, size=0x8000 PNP0c02: adding fixed memory32 range 0xc9800-0xcbfff, size=0x2800 pnpbios: handle 8 device ID PNP0c02 (020cd041) PNP0a03: adding io range 0x294-0x297, size=0x4, align=0 PNP0a03: adding io range 0x4d0-0x4d1, size=0x2, align=0 PNP0a03: adding io range 0xcf8-0xcff, size=0x8, align=0 PNP0a03: adding io range 0x480-0x48f, size=0x10, align=0 PNP0a03: adding io range 0x4000-0x403f, size=0x40, align=0 PNP0a03: adding io range 0x5000-0x501f, size=0x20, align=0 pnpbios: handle 9 device ID PNP0a03 (030ad041) PNP0501: adding irq mask 0x10 PNP0501: adding io range 0x3f8-0x3ff, size=0x8, align=0 pnpbios: handle 12 device ID PNP0501 (0105d041) PNP0700: adding dma mask 0x4 PNP0700: adding io range 0x3f2-0x3f5, size=0x4, align=0 PNP0700: adding irq mask 0x40 pnpbios: handle 13 device ID PNP0700 (0007d041) PNP0400: adding irq mask 0x80 PNP0400: adding io range 0x378-0x37f, size=0x8, align=0 pnpbios: handle 14 device ID PNP0400 (0004d041) PNP0501: adding irq mask 0x8 PNP0501: adding io range 0x2f8-0x2ff, size=0x8, align=0 pnpbios: handle 16 device ID PNP0501 (0105d041) sc: sc0 already exists; skipping it vga: vga0 already exists; skipping it isa_probe_children: disabling PnP devices isa_probe_children: probing non-PnP devices orm0: