Pierre Beyssac
2010-Mar-12 12:14 UTC
8-STABLE interrupt storm on atapci(?), Dell Inspiron 580
Hello, I'm having "interrupt storm detected" messages on a Dell Inspiron 580 running up-to-date 8-STABLE (amd64 arch). The interrupts seem to come from one of the atapci controllers, apparently atapci0 (main controller, with a SATA disk and an ATAPI optical drive). ata_interrupt gets called at a variable rate, between 1000-150000 times per second, constantly, even when the disk is not used.>From adding debug sysctl code in ata-all.c:ata_interrupt_locked()I have been able to check that: ch->running is NULL (breaks loop in "do we have a running request") ch->state=0 ch->unit=0 ch->devices=1 (ATA_ATA_MASTER) most of the time. Here's attached dmesg output, pciconf -lv output, kernel configuration and vmstat -i output. A -current kernel exhibits the same behaviour. Any hint/idea how to debug this further would be really appreciated... -- Pierre Beyssac pb@fasterix.frmug.org -------------- next part -------------- Copyright (c) 1992-2010 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 is a registered trademark of The FreeBSD Foundation. FreeBSD 8.0-STABLE #14: Fri Mar 12 01:37:24 CET 2010 root@inspiron:/usr/src/sys/amd64/compile/INSP580 amd64 WARNING: WITNESS option enabled, expect reduced performance. Preloaded elf kernel "/boot/kernel/kernel" at 0xffffffff80a2a000. Preloaded elf obj module "/boot/kernel/ehci.ko" at 0xffffffff80a2a240. Preloaded elf obj module "/boot/kernel/usb.ko" at 0xffffffff80a2a868. Preloaded elf obj module "/boot/kernel/ukbd.ko" at 0xffffffff80a2af10. Timecounter "i8254" frequency 1193182 Hz quality 0 Calibrating TSC clock ... TSC clock: 2660007980 Hz CPU: Intel(R) Core(TM) i5 CPU 750 @ 2.67GHz (2660.01-MHz K8-class CPU) Origin = "GenuineIntel" Id = 0x106e5 Stepping = 5 Features=0xbfebfbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE> Features2=0x98e3fd<SSE3,DTES64,MON,DS_CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,SSE4.1,SSE4.2,POPCNT> AMD Features=0x28100800<SYSCALL,NX,RDTSCP,LM> AMD Features2=0x1<LAHF> TSC: P-state invariant real memory = 8589934592 (8192 MB) Physical memory chunk(s): 0x0000000000001000 - 0x000000000009bfff, 634880 bytes (155 pages) 0x0000000000a5e000 - 0x00000000bd77ffff, 3167887360 bytes (773410 pages) 0x0000000100000000 - 0x000000022f12ffff, 5084741632 bytes (1241392 pages) avail memory = 8210997248 (7830 MB) ACPI APIC Table: <DELL FX09 > INTR: Adding local APIC 2 as a target INTR: Adding local APIC 4 as a target INTR: Adding local APIC 6 as a target FreeBSD/SMP: Multiprocessor System Detected: 4 CPUs FreeBSD/SMP: 1 package(s) x 4 core(s) cpu0 (BSP): APIC ID: 0 cpu1 (AP): APIC ID: 2 cpu2 (AP): APIC ID: 4 cpu3 (AP): APIC ID: 6 APIC: CPU 0 has ACPI ID 1 APIC: CPU 1 has ACPI ID 2 APIC: CPU 2 has ACPI ID 3 APIC: CPU 3 has ACPI ID 4 ULE: setup cpu 0 ULE: setup cpu 1 ULE: setup cpu 2 ULE: setup cpu 3 ACPI: RSDP 0xf9b00 00024 (v2 ACPIAM) ACPI: XSDT 0xbd780100 0006C (v1 DELL FX09 20091130 MSFT 00000097) ACPI: FACP 0xbd780290 000F4 (v4 DELL FX09 20091130 MSFT 00000097) ACPI: DSDT 0xbd780660 05B02 (v2 1AAAA 1AAAA000 00000000 INTL 20051117) ACPI: FACS 0xbd78e000 00040 ACPI: APIC 0xbd780390 0008C (v2 DELL FX09 20091130 MSFT 00000097) ACPI: MCFG 0xbd780420 0003C (v1 DELL OEMMCFG 20091130 MSFT 00000097) ACPI: SLIC 0xbd780460 00176 (v1 DELL FX09 20091130 MSFT 00000097) ACPI: OSFR 0xbd7805e0 00080 (v1 DELL FX09 20091130 MSFT 00000097) ACPI: OEMB 0xbd78e040 00072 (v1 DELL FX09 20091130 MSFT 00000097) ACPI: HPET 0xbd78a660 00038 (v1 DELL OEMHPET 20091130 MSFT 00000097) ACPI: ASF! 0xbd78a6a0 00099 (v32 LEGEND I865PASF 00000001 INTL 20051117) ACPI: SSDT 0xbd78f6f0 00363 (v1 DpgPmm CpuPm 00000012 INTL 20051117) MADT: Found IO APIC ID 7, Interrupt 0 at 0xfec00000 ioapic0: Changing APIC ID to 7 ioapic0: Routing external 8259A's -> intpin 0 MADT: Interrupt override: source 0, irq 2 ioapic0: Routing IRQ 0 -> intpin 2 MADT: Interrupt override: source 9, irq 9 ioapic0: intpin 9 trigger: level ioapic0 <Version 2.0> irqs 0-23 on motherboard cpu0 BSP: ID: 0x00000000 VER: 0x00060015 LDR: 0x00000000 DFR: 0xffffffff lint0: 0x00010700 lint1: 0x00000400 TPR: 0x00000000 SVR: 0x000001ff timer: 0x000100ef therm: 0x00010000 err: 0x0001000f pcm: 0x00010400 null: <null device, zero device> random: <entropy source, Software, Yarrow> VESA: information block 0000 56 45 53 41 00 03 f0 01 00 c0 01 00 00 00 44 00 0010 00 01 00 01 0f 0c 29 01 00 c0 bb 00 00 c0 3a 4d 0020 00 c0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0030 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0040 00 00 00 00 00 01 01 01 03 01 05 01 07 01 10 01 0050 11 01 13 01 14 01 16 01 17 01 19 01 1a 01 0d 01 0060 0e 01 20 01 93 01 95 01 96 01 b3 01 b5 01 b6 01 0070 c3 01 c5 01 c6 01 33 01 35 01 36 01 53 01 55 01 0080 56 01 63 01 65 01 66 01 21 01 22 01 23 01 24 01 0090 43 01 45 01 46 01 73 01 75 01 76 01 83 01 85 01 00a0 86 01 d3 01 d5 01 d6 01 e3 01 e5 01 e6 01 ff ff 00b0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00c0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00d0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00e0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00f0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0100 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0110 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0120 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0130 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0140 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0150 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0160 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0170 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0180 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0190 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01a0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01b0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01c0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01d0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01e0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01f0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 VESA: 53 mode(s) found VESA: v3.0, 16384k memory, flags:0x1, mode table:0xffffff8000099044 (1000044) VESA: ATI ATOMBIOS VESA: (C) 1988-2005, ATI Technologies Inc. CEDAR 01.00 io: <I/O> kbd: new array size 4 kbd1 at kbdmux0 mem: <memory> acpi0: <DELL FX09 > on motherboard PCIe: Memory Mapped configuration base @ 0xe0000000 ioapic0: routing intpin 9 (ISA IRQ 9) to lapic 0 vector 48 acpi0: [MPSAFE] acpi0: [ITHREAD] ACPI: Executed 1 blocks of module-level executable AML code acpi0: Power Button (fixed) acpi0: wakeup code va 0xffffff800001b000 pa 0x4000 AcpiOsDerivePciId: \\_SB_.PCI0.SBRG.IELK.RXA0 -> bus 0 dev 0 func 0 AcpiOsDerivePciId: \\_SB_.PCI0.SBRG.FHR0 -> bus 0 dev 31 func 0 AcpiOsDerivePciId: \\_SB_.PCI0.SBRG.PIX0 -> bus 0 dev 31 func 0 acpi0: reservation of fee00000, 1000 (3) failed acpi0: reservation of 0, a0000 (3) failed acpi0: reservation of 100000, bdf00000 (3) failed ACPI timer: 1/1 0/223 1/1 1/1 1/1 1/1 1/1 1/1 1/1 1/1 -> 9 Timecounter "ACPI-safe" frequency 3579545 Hz quality 850 acpi_timer0: <24-bit timer at 3.579545MHz> port 0x808-0x80b on acpi0 cpu0: <ACPI CPU> on acpi0 ACPI: SSDT 0xbd78e0c0 01130 (v1 DpgPmm P001Ist 00000011 INTL 20051117) ACPI: SSDT 0xbd78f1f0 004F4 (v1 PmRef P001Cst 00003001 INTL 20051117) cpu1: <ACPI CPU> on acpi0 cpu2: <ACPI CPU> on acpi0 cpu3: <ACPI CPU> on acpi0 pci_link0: Index IRQ Rtd Ref IRQs Initial Probe 0 10 N 0 3 4 6 7 10 11 12 14 15 Validation 0 10 N 0 3 4 6 7 10 11 12 14 15 After Disable 0 255 N 0 3 4 6 7 10 11 12 14 15 pci_link1: Index IRQ Rtd Ref IRQs Initial Probe 0 5 N 0 5 Validation 0 5 N 0 5 After Disable 0 255 N 0 5 pci_link2: Index IRQ Rtd Ref IRQs Initial Probe 0 3 N 0 3 4 6 7 10 11 12 14 15 Validation 0 3 N 0 3 4 6 7 10 11 12 14 15 After Disable 0 255 N 0 3 4 6 7 10 11 12 14 15 pci_link3: Index IRQ Rtd Ref IRQs Initial Probe 0 11 N 0 3 4 6 7 10 11 12 14 15 Validation 0 11 N 0 3 4 6 7 10 11 12 14 15 After Disable 0 255 N 0 3 4 6 7 10 11 12 14 15 pci_link4: Index IRQ Rtd Ref IRQs Initial Probe 0 255 N 0 3 4 6 7 10 11 12 14 15 Validation 0 255 N 0 3 4 6 7 10 11 12 14 15 After Disable 0 255 N 0 3 4 6 7 10 11 12 14 15 pci_link5: Index IRQ Rtd Ref IRQs Initial Probe 0 255 N 0 3 4 6 7 10 11 12 14 15 Validation 0 255 N 0 3 4 6 7 10 11 12 14 15 After Disable 0 255 N 0 3 4 6 7 10 11 12 14 15 pci_link6: Index IRQ Rtd Ref IRQs Initial Probe 0 15 N 0 3 4 6 7 10 11 12 14 15 Validation 0 15 N 0 3 4 6 7 10 11 12 14 15 After Disable 0 255 N 0 3 4 6 7 10 11 12 14 15 pci_link7: Index IRQ Rtd Ref IRQs Initial Probe 0 14 N 0 3 4 6 7 10 11 12 14 15 Validation 0 14 N 0 3 4 6 7 10 11 12 14 15 After Disable 0 255 N 0 3 4 6 7 10 11 12 14 15 acpi_hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0 acpi_hpet0: vend: 0x8086 rev: 0x1 num: 8 hz: 14318180 opts: legacy_route 64-bit Timecounter "HPET" frequency 14318180 Hz quality 900 pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0 pci0: <ACPI PCI bus> on pcib0 pci0: domain=0, physical bus=0 found-> vendor=0x8086, dev=0xd131, revid=0x11 domain=0, bus=0, slot=0, func=0 class=06-00-00, hdrtype=0x00, mfdev=0 cmdreg=0x0000, statreg=0x0010, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) found-> vendor=0x8086, dev=0xd138, revid=0x11 domain=0, bus=0, slot=3, func=0 class=06-04-00, hdrtype=0x01, mfdev=0 cmdreg=0x0107, statreg=0x0010, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x1a (6500 ns), maxlat=0x00 (0 ns) intpin=a, irq=10 powerspec 3 supports D0 D3 current D0 MSI supports 2 messages, vector masks pcib0: matched entry for 0.3.INTA pcib0: slot 3 INTA hardwired to IRQ 16 found-> vendor=0x8086, dev=0xd155, revid=0x11 domain=0, bus=0, slot=8, func=0 class=08-80-00, hdrtype=0x00, mfdev=1 cmdreg=0x0000, statreg=0x0010, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) found-> vendor=0x8086, dev=0xd156, revid=0x11 domain=0, bus=0, slot=8, func=1 class=08-80-00, hdrtype=0x00, mfdev=1 cmdreg=0x0000, statreg=0x0010, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) found-> vendor=0x8086, dev=0xd157, revid=0x11 domain=0, bus=0, slot=8, func=2 class=08-80-00, hdrtype=0x00, mfdev=1 cmdreg=0x0000, statreg=0x0010, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) found-> vendor=0x8086, dev=0xd158, revid=0x11 domain=0, bus=0, slot=8, func=3 class=08-80-00, hdrtype=0x00, mfdev=1 cmdreg=0x0000, statreg=0x0000, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) found-> vendor=0x8086, dev=0xd150, revid=0x11 domain=0, bus=0, slot=16, func=0 class=08-80-00, hdrtype=0x00, mfdev=1 cmdreg=0x0000, statreg=0x0000, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) found-> vendor=0x8086, dev=0xd151, revid=0x11 domain=0, bus=0, slot=16, func=1 class=08-80-00, hdrtype=0x00, mfdev=1 cmdreg=0x0000, statreg=0x0000, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) found-> vendor=0x8086, dev=0x3b64, revid=0x06 domain=0, bus=0, slot=22, func=0 class=07-80-00, hdrtype=0x00, mfdev=1 cmdreg=0x0006, statreg=0x0010, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=a, irq=10 powerspec 3 supports D0 D3 current D0 MSI supports 1 message, 64 bit map[10]: type Memory, range 64, base 0xfbdfe000, size 4, enabled pcib0: matched entry for 0.22.INTA pcib0: slot 22 INTA hardwired to IRQ 16 found-> vendor=0x8086, dev=0x3b3c, revid=0x06 domain=0, bus=0, slot=26, func=0 class=0c-03-20, hdrtype=0x00, mfdev=0 cmdreg=0x0006, statreg=0x0290, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=a, irq=10 powerspec 2 supports D0 D3 current D0 map[10]: type Memory, range 32, base 0xfbdfc000, size 10, enabled pcib0: matched entry for 0.26.INTA pcib0: slot 26 INTA hardwired to IRQ 16 unknown: Reserved 0x400 bytes for rid 0x10 type 3 at 0xfbdfc000 found-> vendor=0x8086, dev=0x3b56, revid=0x06 domain=0, bus=0, slot=27, func=0 class=04-03-00, hdrtype=0x00, mfdev=0 cmdreg=0x0006, statreg=0x0010, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=a, irq=15 powerspec 2 supports D0 D3 current D0 MSI supports 1 message, 64 bit map[10]: type Memory, range 64, base 0xfbdf8000, size 14, enabled pcib0: matched entry for 0.27.INTA pcib0: slot 27 INTA hardwired to IRQ 22 found-> vendor=0x8086, dev=0x3b42, revid=0x06 domain=0, bus=0, slot=28, func=0 class=06-04-00, hdrtype=0x01, mfdev=1 cmdreg=0x0104, statreg=0x0010, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x02 (500 ns), maxlat=0x00 (0 ns) intpin=a, irq=5 powerspec 2 supports D0 D3 current D0 MSI supports 1 message pcib0: matched entry for 0.28.INTA pcib0: slot 28 INTA hardwired to IRQ 17 found-> vendor=0x8086, dev=0x3b4c, revid=0x06 domain=0, bus=0, slot=28, func=5 class=06-04-00, hdrtype=0x01, mfdev=1 cmdreg=0x0106, statreg=0x0010, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x02 (500 ns), maxlat=0x00 (0 ns) intpin=b, irq=10 powerspec 2 supports D0 D3 current D0 MSI supports 1 message pcib0: matched entry for 0.28.INTB pcib0: slot 28 INTB hardwired to IRQ 16 found-> vendor=0x8086, dev=0x3b34, revid=0x06 domain=0, bus=0, slot=29, func=0 class=0c-03-20, hdrtype=0x00, mfdev=0 cmdreg=0x0006, statreg=0x0290, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=a, irq=14 powerspec 2 supports D0 D3 current D0 map[10]: type Memory, range 32, base 0xfbdf6000, size 10, enabled pcib0: matched entry for 0.29.INTA pcib0: slot 29 INTA hardwired to IRQ 23 unknown: Reserved 0x400 bytes for rid 0x10 type 3 at 0xfbdf6000 found-> vendor=0x8086, dev=0x244e, revid=0xa6 domain=0, bus=0, slot=30, func=0 class=06-04-01, hdrtype=0x01, mfdev=0 cmdreg=0x0104, statreg=0x0010, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x02 (500 ns), maxlat=0x00 (0 ns) found-> vendor=0x8086, dev=0x3b08, revid=0x06 domain=0, bus=0, slot=31, func=0 class=06-01-00, hdrtype=0x00, mfdev=1 cmdreg=0x0007, statreg=0x0210, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) found-> vendor=0x8086, dev=0x3b20, revid=0x06 domain=0, bus=0, slot=31, func=2 class=01-01-8f, hdrtype=0x00, mfdev=0 cmdreg=0x0005, statreg=0x02b0, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=b, irq=11 powerspec 3 supports D0 D3 current D0 map[10]: type I/O Port, range 32, base 0xdc00, size 3, enabled map[14]: type I/O Port, range 32, base 0xd880, size 2, enabled map[18]: type I/O Port, range 32, base 0xd800, size 3, enabled map[1c]: type I/O Port, range 32, base 0xd480, size 2, enabled map[20]: type I/O Port, range 32, base 0xd400, size 4, enabled map[24]: type I/O Port, range 32, base 0xd080, size 4, enabled pcib0: matched entry for 0.31.INTB pcib0: slot 31 INTB hardwired to IRQ 19 found-> vendor=0x8086, dev=0x3b30, revid=0x06 domain=0, bus=0, slot=31, func=3 class=0c-05-00, hdrtype=0x00, mfdev=0 cmdreg=0x0003, statreg=0x0280, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=c, irq=3 map[10]: type Memory, range 64, base 0xfbdf4000, size 8, enabled map[20]: type I/O Port, range 32, base 0x400, size 5, enabled pcib0: matched entry for 0.31.INTC pcib0: slot 31 INTC hardwired to IRQ 18 found-> vendor=0x8086, dev=0x3b26, revid=0x06 domain=0, bus=0, slot=31, func=5 class=01-01-85, hdrtype=0x00, mfdev=0 cmdreg=0x0005, statreg=0x02b0, cachelnsz=0 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=b, irq=11 powerspec 3 supports D0 D3 current D0 map[10]: type I/O Port, range 32, base 0xcc00, size 3, enabled map[14]: type I/O Port, range 32, base 0xc880, size 2, enabled map[18]: type I/O Port, range 32, base 0xc800, size 3, enabled map[1c]: type I/O Port, range 32, base 0xc480, size 2, enabled map[20]: type I/O Port, range 32, base 0xc400, size 4, enabled map[24]: type I/O Port, range 32, base 0xc080, size 4, enabled pcib0: matched entry for 0.31.INTB pcib0: slot 31 INTB hardwired to IRQ 19 pcib1: <ACPI PCI-PCI bridge> irq 16 at device 3.0 on pci0 pcib1: domain 0 pcib1: secondary bus 1 pcib1: subordinate bus 1 pcib1: I/O decode 0xe000-0xefff pcib1: memory decode 0xfbe00000-0xfbefffff pcib1: prefetched decode 0xd0000000-0xdfffffff pcib1: could not get PCI interrupt routing table for \\_SB_.PCI0.P0P3 - AE_NOT_FOUND pci1: <ACPI PCI bus> on pcib1 pci1: domain=0, physical bus=1 found-> vendor=0x1002, dev=0x68f9, revid=0x00 domain=0, bus=1, slot=0, func=0 class=03-00-00, hdrtype=0x00, mfdev=1 cmdreg=0x0007, statreg=0x0010, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=a, irq=10 powerspec 3 supports D0 D1 D2 D3 current D0 MSI supports 1 message, 64 bit map[10]: type Prefetchable Memory, range 64, base 0xd0000000, size 28, enabled pcib1: requested memory range 0xd0000000-0xdfffffff: good map[18]: type Memory, range 64, base 0xfbee0000, size 17, enabled pcib1: requested memory range 0xfbee0000-0xfbefffff: good map[20]: type I/O Port, range 32, base 0xe000, size 8, enabled pcib1: requested I/O range 0xe000-0xe0ff: in range pcib0: matched entry for 0.3.INTA pcib0: slot 3 INTA hardwired to IRQ 16 pcib1: slot 0 INTA is routed to irq 16 found-> vendor=0x1002, dev=0xaa68, revid=0x00 domain=0, bus=1, slot=0, func=1 class=04-03-00, hdrtype=0x00, mfdev=1 cmdreg=0x0007, statreg=0x0010, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=b, irq=5 powerspec 3 supports D0 D1 D2 D3 current D0 MSI supports 1 message, 64 bit map[10]: type Memory, range 64, base 0xfbebc000, size 14, enabled pcib1: requested memory range 0xfbebc000-0xfbebffff: good pcib0: matched entry for 0.3.INTB pcib0: slot 3 INTB hardwired to IRQ 17 pcib1: slot 0 INTB is routed to irq 17 vgapci0: <VGA-compatible display> port 0xe000-0xe0ff mem 0xd0000000-0xdfffffff,0xfbee0000-0xfbefffff irq 16 at device 0.0 on pci1 pci1: <multimedia, HDA> at device 0.1 (no driver attached) pci0: <base peripheral> at device 8.0 (no driver attached) pci0: <base peripheral> at device 8.1 (no driver attached) pci0: <base peripheral> at device 8.2 (no driver attached) pci0: <base peripheral> at device 8.3 (no driver attached) pci0: <base peripheral> at device 16.0 (no driver attached) pci0: <base peripheral> at device 16.1 (no driver attached) pci0: <simple comms> at device 22.0 (no driver attached) ehci0: <Intel PCH USB 2.0 controller USB-B> mem 0xfbdfc000-0xfbdfc3ff irq 16 at device 26.0 on pci0 ioapic0: routing intpin 16 (PCI IRQ 16) to lapic 0 vector 49 ehci0: [MPSAFE] ehci0: [ITHREAD] usbus0: EHCI version 1.0 usbus0: <Intel PCH USB 2.0 controller USB-B> on ehci0 pci0: <multimedia, HDA> at device 27.0 (no driver attached) pcib2: <ACPI PCI-PCI bridge> irq 17 at device 28.0 on pci0 pcib2: domain 0 pcib2: secondary bus 2 pcib2: subordinate bus 2 pcib2: I/O decode 0x0-0x0 pcib2: no prefetched decode pci2: <ACPI PCI bus> on pcib2 pci2: domain=0, physical bus=2 pcib3: <ACPI PCI-PCI bridge> irq 16 at device 28.5 on pci0 pcib3: domain 0 pcib3: secondary bus 3 pcib3: subordinate bus 3 pcib3: I/O decode 0x0-0x0 pcib3: memory decode 0xfbf00000-0xfbffffff pcib3: no prefetched decode pci3: <ACPI PCI bus> on pcib3 pci3: domain=0, physical bus=3 found-> vendor=0x14e4, dev=0x1691, revid=0x01 domain=0, bus=3, slot=0, func=0 class=02-00-00, hdrtype=0x00, mfdev=0 cmdreg=0x0006, statreg=0x0010, cachelnsz=8 (dwords) lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns) intpin=a, irq=5 powerspec 3 supports D0 D3 current D0 MSI supports 1 message, 64 bit map[10]: type Memory, range 64, base 0xfbff0000, size 16, enabled pcib3: requested memory range 0xfbff0000-0xfbffffff: good pcib3: matched entry for 3.0.INTA pcib3: slot 0 INTA hardwired to IRQ 17 bge0: <Broadcom BCM57780 A1, ASIC rev. 0x57780001> mem 0xfbff0000-0xfbffffff irq 17 at device 0.0 on pci3 bge0: Reserved 0x10000 bytes for rid 0x10 type 3 at 0xfbff0000 bge0: adjust device control 0x2000 -> 0x5000 bge0: attempting to allocate 1 MSI vectors (1 supported) msi: routing MSI IRQ 256 to local APIC 0 vector 50 bge0: using IRQ 256 for MSI bge0: CHIP ID 0x57780001; ASIC REV 0x57780; CHIP REV 0x577800; PCI-E bge0: Disabling fastboot bge0: Disabling fastboot miibus0: <MII bus> on bge0 ukphy0: <Generic IEEE 802.3u media interface> PHY 1 on miibus0 ukphy0: OUI 0x00d897, model 0x0019, rev. 1 ukphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-FDX, auto bge0: bpf attached bge0: Ethernet address: 00:25:64:f4:27:26 bge0: [MPSAFE] bge0: [FILTER] ehci1: <Intel PCH USB 2.0 controller USB-A> mem 0xfbdf6000-0xfbdf63ff irq 23 at device 29.0 on pci0 ioapic0: routing intpin 23 (PCI IRQ 23) to lapic 0 vector 51 ehci1: [MPSAFE] ehci1: [ITHREAD] usbus1: EHCI version 1.0 usbus1: <Intel PCH USB 2.0 controller USB-A> on ehci1 pcib4: <ACPI PCI-PCI bridge> at device 30.0 on pci0 pcib4: domain 0 pcib4: secondary bus 4 pcib4: subordinate bus 4 pcib4: I/O decode 0x0-0x0 pcib4: no prefetched decode pcib4: Subtractively decoded bridge. pci4: <ACPI PCI bus> on pcib4 pci4: domain=0, physical bus=4 isab0: <PCI-ISA bridge> at device 31.0 on pci0 isa0: <ISA bus> on isab0 atapci0: <Intel ATA controller> port 0xdc00-0xdc07,0xd880-0xd883,0xd800-0xd807,0xd480-0xd483,0xd400-0xd40f,0xd080-0xd08f irq 19 at device 31.2 on pci0 atapci0: Reserved 0x10 bytes for rid 0x20 type 4 at 0xd400 ioapic0: routing intpin 19 (PCI IRQ 19) to lapic 0 vector 52 atapci0: [MPSAFE] atapci0: [ITHREAD] ata2: <ATA channel 0> on atapci0 atapci0: Reserved 0x8 bytes for rid 0x10 type 4 at 0xdc00 atapci0: Reserved 0x4 bytes for rid 0x14 type 4 at 0xd880 ata2: [MPSAFE] ata2: [ITHREAD] ata3: <ATA channel 1> on atapci0 atapci0: Reserved 0x8 bytes for rid 0x18 type 4 at 0xd800 atapci0: Reserved 0x4 bytes for rid 0x1c type 4 at 0xd480 ata3: [MPSAFE] ata3: [ITHREAD] pci0: <serial bus, SMBus> at device 31.3 (no driver attached) atapci1: <Intel ATA controller> port 0xcc00-0xcc07,0xc880-0xc883,0xc800-0xc807,0xc480-0xc483,0xc400-0xc40f,0xc080-0xc08f irq 19 at device 31.5 on pci0 atapci1: Reserved 0x10 bytes for rid 0x20 type 4 at 0xc400 atapci1: [MPSAFE] atapci1: [ITHREAD] ata4: <ATA channel 0> on atapci1 atapci1: Reserved 0x8 bytes for rid 0x10 type 4 at 0xcc00 atapci1: Reserved 0x4 bytes for rid 0x14 type 4 at 0xc880 ata4: [MPSAFE] ata4: [ITHREAD] ata5: <ATA channel 1> on atapci1 atapci1: Reserved 0x8 bytes for rid 0x18 type 4 at 0xc800 atapci1: Reserved 0x4 bytes for rid 0x1c type 4 at 0xc480 ata5: [MPSAFE] ata5: [ITHREAD] acpi_button0: <Power Button> on acpi0 atrtc0: <AT realtime clock> port 0x70-0x71 irq 8 on acpi0 atrtc0: registered as a time-of-day clock (resolution 1000000us) isa_probe_children: disabling PnP devices atrtc: atrtc0 already exists; skipping it sc: sc0 already exists; skipping it isa_probe_children: probing non-PnP devices orm0: <ISA Option ROM> at iomem 0xc0000-0xce7ff on isa0 sc0: <System console> at flags 0x100 on isa0 sc0: VGA <16 virtual consoles, flags=0x300> sc0: fb0, kbd1, terminal emulator: scteken (teken terminal) vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0 atkbd0: <AT Keyboard> irq 1 on atkbdc0 kbd0 at atkbd0 kbd0: atkbd0, generic (0), config:0x0, flags:0x3f0000 ioapic0: routing intpin 1 (ISA IRQ 1) to lapic 0 vector 53 atkbd0: [GIANT-LOCKED] atkbd0: [ITHREAD] psm0: current command byte:0065 psm0: failed to reset the aux device. fdc0 failed to probe at port 0x3f0 irq 6 drq 2 on isa0 ppc0 failed to probe at irq 7 on isa0 uart0: <ns8250> failed to probe at port 0x3f8-0x3ff irq 4 on isa0 uart1: <ns8250> failed to probe at port 0x2f8-0x2ff irq 3 on isa0 isa_probe_children: probing PnP devices est0: <Enhanced SpeedStep Frequency Control> on cpu0 est0: Invalid id16 (set, cur) = (19, 20) est0: Can't check freq 2533, it may be invalid est0: Invalid id16 (set, cur) = (18, 20) est0: Can't check freq 2400, it may be invalid est0: Invalid id16 (set, cur) = (17, 20) est0: Can't check freq 2267, it may be invalid est0: Invalid id16 (set, cur) = (16, 20) est0: Can't check freq 2133, it may be invalid est0: Invalid id16 (set, cur) = (15, 20) est0: Can't check freq 2000, it may be invalid est0: Invalid id16 (set, cur) = (14, 20) est0: Can't check freq 1867, it may be invalid est0: Invalid id16 (set, cur) = (13, 20) est0: Can't check freq 1733, it may be invalid est0: Invalid id16 (set, cur) = (12, 20) est0: Can't check freq 1600, it may be invalid est0: Invalid id16 (set, cur) = (11, 20) est0: Can't check freq 1467, it may be invalid est0: Invalid id16 (set, cur) = (10, 20) est0: Can't check freq 1333, it may be invalid est0: Invalid id16 (set, cur) = (9, 20) est0: Can't check freq 1200, it may be invalid p4tcc0: <CPU Frequency Thermal Control> on cpu0 est1: <Enhanced SpeedStep Frequency Control> on cpu1 est1: Invalid id16 (set, cur) = (19, 20) est1: Can't check freq 2533, it may be invalid est1: Invalid id16 (set, cur) = (18, 20) est1: Can't check freq 2400, it may be invalid est1: Invalid id16 (set, cur) = (17, 20) est1: Can't check freq 2267, it may be invalid est1: Invalid id16 (set, cur) = (16, 20) est1: Can't check freq 2133, it may be invalid est1: Invalid id16 (set, cur) = (15, 20) est1: Can't check freq 2000, it may be invalid est1: Invalid id16 (set, cur) = (14, 20) est1: Can't check freq 1867, it may be invalid est1: Invalid id16 (set, cur) = (13, 20) est1: Can't check freq 1733, it may be invalid est1: Invalid id16 (set, cur) = (12, 20) est1: Can't check freq 1600, it may be invalid est1: Invalid id16 (set, cur) = (11, 20) est1: Can't check freq 1467, it may be invalid est1: Invalid id16 (set, cur) = (10, 20) est1: Can't check freq 1333, it may be invalid est1: Invalid id16 (set, cur) = (9, 20) est1: Can't check freq 1200, it may be invalid p4tcc1: <CPU Frequency Thermal Control> on cpu1 est2: <Enhanced SpeedStep Frequency Control> on cpu2 est2: Invalid id16 (set, cur) = (19, 20) est2: Can't check freq 2533, it may be invalid est2: Invalid id16 (set, cur) = (18, 20) est2: Can't check freq 2400, it may be invalid est2: Invalid id16 (set, cur) = (17, 20) est2: Can't check freq 2267, it may be invalid est2: Invalid id16 (set, cur) = (16, 20) est2: Can't check freq 2133, it may be invalid est2: Invalid id16 (set, cur) = (15, 20) est2: Can't check freq 2000, it may be invalid est2: Invalid id16 (set, cur) = (14, 20) est2: Can't check freq 1867, it may be invalid est2: Invalid id16 (set, cur) = (13, 20) est2: Can't check freq 1733, it may be invalid est2: Invalid id16 (set, cur) = (12, 20) est2: Can't check freq 1600, it may be invalid est2: Invalid id16 (set, cur) = (11, 20) est2: Can't check freq 1467, it may be invalid est2: Invalid id16 (set, cur) = (10, 20) est2: Can't check freq 1333, it may be invalid est2: Invalid id16 (set, cur) = (9, 20) est2: Can't check freq 1200, it may be invalid p4tcc2: <CPU Frequency Thermal Control> on cpu2 est3: <Enhanced SpeedStep Frequency Control> on cpu3 est3: Invalid id16 (set, cur) = (19, 20) est3: Can't check freq 2533, it may be invalid est3: Invalid id16 (set, cur) = (18, 20) est3: Can't check freq 2400, it may be invalid est3: Invalid id16 (set, cur) = (17, 20) est3: Can't check freq 2267, it may be invalid est3: Invalid id16 (set, cur) = (16, 20) est3: Can't check freq 2133, it may be invalid est3: Invalid id16 (set, cur) = (15, 20) est3: Can't check freq 2000, it may be invalid est3: Invalid id16 (set, cur) = (14, 20) est3: Can't check freq 1867, it may be invalid est3: Invalid id16 (set, cur) = (13, 20) est3: Can't check freq 1733, it may be invalid est3: Invalid id16 (set, cur) = (12, 20) est3: Can't check freq 1600, it may be invalid est3: Invalid id16 (set, cur) = (11, 20) est3: Can't check freq 1467, it may be invalid est3: Invalid id16 (set, cur) = (10, 20) est3: Can't check freq 1333, it may be invalid est3: Invalid id16 (set, cur) = (9, 20) est3: Can't check freq 1200, it may be invalid p4tcc3: <CPU Frequency Thermal Control> on cpu3 Device configuration finished. Reducing kern.maxvnodes 509903 -> 100000 procfs registered lapic: Divisor 2, Frequency 66500198 Hz Timecounter "TSC" frequency 2660007980 Hz quality -100 Timecounters tick every 1.000 msec lo0: bpf attached usbus0: 480Mbps High Speed USB v2.0 usbus1: 480Mbps High Speed USB v2.0 ata2: reset tp1 mask=03 ostat0=50 ostat1=00 ugen0.1: <Intel> at usbus0 uhub0: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus0 ugen1.1: <Intel> at usbus1 uhub1: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus1 ata2: stat0=0x50 err=0x01 lsb=0x00 msb=0x00 ata2: stat1=0x00 err=0x01 lsb=0x00 msb=0x00 ata2: reset tp2 stat0=50 stat1=00 devices=0x1 (aprobe0:ata2:0:0:0): SIGNATURE: 0000 ata3: reset tp1 mask=03 ostat0=50 ostat1=00 ata3: stat0=0x00 err=0x01 lsb=0x14 msb=0xeb ata3: stat1=0x00 err=0x01 lsb=0x14 msb=0xeb ata3: reset tp2 stat0=00 stat1=00 devices=0x30000 (aprobe1:ata3:0:0:0): SIGNATURE: eb14 ata4: reset tp1 mask=03 ostat0=7f ostat1=7f ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat0=0x7f err=0xff lsb=0xff msb=0xff ata4: stat1=0x7f err=0xff lsb=0xff msb=0xff ata4: reset tp2 stat0=ff stat1=ff devices=0x0 ata5: reset tp1 mask=03 ostat0=7f ostat1=7f ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat0=0x7f err=0xff lsb=0xff msb=0xff ata5: stat1=0x7f err=0xff lsb=0xff msb=0xff ata5: reset tp2 stat0=ff stat1=ff devices=0x0 uhub0: 2 ports with 2 removable, self powered uhub1: 2 ports with 2 removable, self powered (aprobe0:ata3:0:1:0): SIGNATURE: eb14 ata3: timeout waiting for read DRQ (aprobe0:ata3:0:1:0): Request completed with CAM_REQ_CMP_ERR (aprobe0:ata3:0:1:0): Retrying command ata3: timeout waiting for read DRQ (aprobe0:ata3:0:1:0): Request completed with CAM_REQ_CMP_ERR (aprobe0:ata3:0:1:0): Error 5, Retries exhausted GEOM: new disk ada0 ada0 at ata2 bus 0 scbus0 target 0 lun 0 ada0: <ST31000528AS CC45> ATA-8 SATA 2.x device ada0: Serial Number ........ ada0: 33.300MB/s transfers (UDMA2, PIO 8192bytes) ada0: 953869MB (1953525168 512 byte sectors: 16H 63S/T 16383C) pass0 at ata2 bus 0 scbus0 target 0 lun 0 pass0: <ST31000528AS CC45> ATA-8 SATA 2.x device pass0: Serial Number ........ pass0: 33.300MB/s transfers (UDMA2, PIO 8192bytes) pass1 at ata3 bus 0 scbus1 target 0 lun 0 pass1: <PLDS DVDRWBD DH-6E2S 7D12> Removable CD-ROM SCSI-0 device pass1: Serial Number .................... pass1: 33.300MB/s transfers (UDMA2, ATAPI 12bytes, PIO 8192bytes) SMP: AP CPU #3 Launched! cpu3 AP: ID: 0x06000000 VER: 0x00060015 LDR: 0x00000000 DFR: 0xffffffff lint0: 0x00010700 lint1: 0x00000400 TPR: 0x00000000 SVR: 0x000001ff timer: 0x000200ef therm: 0x00010000 err: 0x00010000 pcm: 0x00010400 (cd0:ata3:0:0:0): SCSI status error (cd0:ata3:0:0:0): Requesting SCSI sense data SMP: AP CPU #2 Launched! cpu2 AP: ID: 0x04000000 VER: 0x00060015 LDR: 0x00000000 DFR: 0xffffffff lint0: 0x00010700 lint1: 0x00000400 TPR: 0x00000000 SVR: 0x000001ff(cd0: timer: 0x000200ef therm: 0x00010000 err: 0x00010000 pcm: 0x00010400ata3:0: 0:0): SCSI status error (cd0:ata3:0:0:0): READ CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 (cd0:ata3:0:0:0): CAM status: SCSI Status Error (cd0:ata3:0:0:0): SCSI status: Check Condition (cd0:ata3:0:0:0): SCSI sense: NOT READY asc:3a,1 (Medium not present - tray closed) (cd0:ata3:0:0:0): Error 6, Unretryable errorSMP: AP CPU #1 Launched! cd0 at ata3 bus 0 scbus1 target 0 lun 0 cpu1 AP: cd0: ID: 0x02000000 VER: 0x00060015 LDR: 0x00000000 DFR: 0xffffffff<PLDS DVDRWBD DH-6E2S 7D12> Removable CD-ROM SCSI-0 device lint0: 0x00010700 lint1: 0x00000400 TPR: 0x00000000 SVR: 0x000001ff cd0: Serial Number .................... timer: 0x000200ef therm: 0x00010000 err: 0x00010000 pcm: 0x00010400 cd0: 33.300MB/s transfers ioapic0: routing intpin 9 ( (ISA IRQ 9UDMA2, ) to lapic 2 vector 48ATAPI 12bytes, PIO 8192bytes) cd0: Attempt to query device size failed: NOT READY, Medium not present - tray closed ioapic0: routing intpin 16 (PCI IRQ 16) to lapic 4 vector 48 ioapic0: routing intpin 19 (PCI IRQ 19) to lapic 6 vector 48 msi: Assigning MSI IRQ 256 to local APIC 2 vector 49 WARNING: WITNESS option enabled, expect reduced performance. GEOM: ada0: partition 3 does not start on a track boundary. GEOM: ada0: partition 3 does not end on a track boundary. GEOM: ada0: partition 2 does not start on a track boundary. GEOM: ada0: partition 2 does not end on a track boundary. GEOM: new disk cd0 (cd0:ata3:0:0:0): SCSI status error (cd0:ata3:0:0:0): Requesting SCSI sense data (cd0:ata3:0:0:0): SCSI status error (cd0:ata3:0:0:0): READ CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 (cd0:ata3:0:0:0): CAM status: SCSI Status Error (cd0:ata3:0:0:0): SCSI status: Check Condition (cd0:ata3:0:0:0): SCSI sense: NOT READY asc:3a,1 (Medium not present - tray closed) (cd0:ata3:0:0:0): Error 6, Unretryable error (cd0:ata3:0:0:0): SCSI status error (cd0:ata3:0:0:0): Requesting SCSI sense data (cd0:ata3:0:0:0): SCSI status error (cd0:ata3:0:0:0): READ CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 (cd0:ata3:0:0:0): CAM status: SCSI Status Error (cd0:ata3:0:0:0): SCSI status: Check Condition (cd0:ata3:0:0:0): SCSI sense: NOT READY asc:3a,1 (Medium not present - tray closed) (cd0:ata3:0:0:0): Error 6, Unretryable error Root mount waiting for: usbus1 usbus0 interrupt storm detected on "irq19:"; throttling interrupt source ugen0.2: <vendor 0x8087> at usbus0 uhub2: <vendor 0x8087 product 0x0020, class 9/0, rev 2.00/0.00, addr 2> on usbus0 ugen1.2: <vendor 0x8087> at usbus1 uhub3: <vendor 0x8087 product 0x0020, class 9/0, rev 2.00/0.00, addr 2> on usbus1 Root mount waiting for: usbus1 usbus0 uhub2: 6 ports with 6 removable, self powered interrupt storm detected on "irq19:"; throttling interrupt source uhub3: 8 ports with 8 removable, self powered Root mount waiting for: usbus1 usbus0 ugen0.3: <Dell> at usbus0 ukbd0: <Dell Dell USB Entry Keyboard, class 0/0, rev 1.10/1.78, addr 3> on usbus0 kbd2 at ukbd0 kbd2: ukbd0, generic (0), config:0x0, flags:0x3d0000 ugen1.3: <Generic> at usbus1 Trying to mount root from ufs:/dev/ufs/root ct_to_ts([2010-03-12 09:48:15]) = 1268387295.000000000 start_init: trying /sbin/init interrupt storm detected on "irq19:"; throttling interrupt source interrupt storm detected on "irq19:"; throttling interrupt source interrupt storm detected on "irq19:"; throttling interrupt source interrupt storm detected on "irq19:"; throttling interrupt source interrupt storm detected on "irq19:"; throttling interrupt source interrupt storm detected on "irq19:"; throttling interrupt source interrupt storm detected on "irq19:"; throttling interrupt source interrupt storm detected on "irq19:"; throttling interrupt source interrupt storm detected on "irq19:"; throttling interrupt source interrupt storm detected on "irq19:"; throttling interrupt source bge0: Disabling fastboot bge0: Disabling fastboot interrupt storm detected on "irq19:"; throttling interrupt source bge0: link UP interrupt storm detected on "irq19:"; throttling interrupt source interrupt storm detected on "irq19:"; throttling interrupt source -------------- next part -------------- cpu HAMMER ident INSP580 # To statically compile in device wiring instead of /boot/device.hints #hints "GENERIC.hints" # Default places to look for devices. # Use the following to compile in values accessible to the kernel # through getenv() (or kenv(1) in userland). The format of the file # is 'variable=value', see kenv(1) # # env "GENERIC.env" makeoptions DEBUG=-g # Build kernel with gdb(1) debug symbols options SCHED_ULE # ULE scheduler options PREEMPTION # Enable kernel thread preemption options INET # InterNETworking options INET6 # IPv6 communications protocols options SCTP # Stream Control Transmission Protocol options FFS # Berkeley Fast Filesystem options SOFTUPDATES # Enable FFS soft updates support options UFS_ACL # Support for access control lists options UFS_DIRHASH # Improve performance on big directories options UFS_GJOURNAL # Enable gjournal-based UFS journaling options PROCFS # Process filesystem (requires PSEUDOFS) options PSEUDOFS # Pseudo-filesystem framework options GEOM_PART_GPT # GUID Partition Tables. options GEOM_LABEL # Provides labelization options COMPAT_43TTY # BSD 4.3 TTY compat (sgtty) options COMPAT_IA32 # Compatible with i386 binaries options COMPAT_FREEBSD4 # Compatible with FreeBSD4 options COMPAT_FREEBSD5 # Compatible with FreeBSD5 options COMPAT_FREEBSD6 # Compatible with FreeBSD6 options COMPAT_FREEBSD7 # Compatible with FreeBSD7 options SCSI_DELAY=5000 # Delay (in ms) before probing SCSI options KTRACE # ktrace(1) support options STACK # stack(9) support options SYSVSHM # SYSV-style shared memory options SYSVMSG # SYSV-style message queues options SYSVSEM # SYSV-style semaphores options P1003_1B_SEMAPHORES # POSIX-style semaphores options _KPOSIX_PRIORITY_SCHEDULING # POSIX P1003_1B real-time extensions options PRINTF_BUFR_SIZE=128 # Prevent printf output being interspersed. options KBD_INSTALL_CDEV # install a CDEV entry in /dev options HWPMC_HOOKS # Necessary kernel hooks for hwpmc(4) options AUDIT # Security event auditing options MAC # TrustedBSD MAC Framework options FLOWTABLE # per-cpu routing cache #options KDTRACE_FRAME # Ensure frames are compiled in #options KDTRACE_HOOKS # Kernel DTrace hooks options INCLUDE_CONFIG_FILE # Include this file in kernel options WITNESS options INVARIANTS options INVARIANT_SUPPORT options DEADLKRES options KDB options KDB_UNATTENDED options DDB # Make an SMP-capable kernel by default options SMP # Symmetric MultiProcessor Kernel # CPU frequency control device cpufreq # Bus support. device acpi device pci # ATA and ATAPI devices device atacore device atapci device ataintel device atadisk # ATA disk drives device atapicd # ATAPI CDROM drives options ATA_STATIC_ID # Static device numbering options ATA_CAM # SCSI peripherals device scbus # SCSI bus (required for SCSI) device ch # SCSI media changers device da # Direct Access (disks) device sa # Sequential Access (tape etc) device cd # CD device pass # Passthrough device (direct SCSI access) device ses # SCSI Environmental Services (and SAF-TE) # atkbdc0 controls both the keyboard and the PS/2 mouse device atkbdc # AT keyboard controller device atkbd # AT keyboard device psm # PS/2 mouse device kbdmux # keyboard multiplexer device vga # VGA video card driver options VESA device splash # Splash screen and screen saver support # syscons is the default console driver, resembling an SCO console device sc # Serial (COM) ports device uart # Generic UART driver # PCI Ethernet NICs that use the common MII bus controller code. # NOTE: Be sure to keep the 'device miibus' line in order to use these NICs! device miibus # MII bus support device bge # Broadcom BCM570xx Gigabit Ethernet # Pseudo devices. device loop # Network loopback device random # Entropy device device ether # Ethernet support device pty # BSD-style compatibility pseudo ttys device firmware # firmware assist module # The `bpf' device enables the Berkeley Packet Filter. # Be aware of the administrative consequences of enabling this! # Note that 'bpf' is required for DHCP. device bpf # Berkeley packet filter -------------- next part -------------- interrupt total rate irq16: ehci0 3495 3 irq19: atapci0+ 54180102 48943 irq23: ehci1 2237 2 cpu0: timer 2191716 1979 irq256: bge0 390 0 cpu3: timer 2181718 1970 cpu2: timer 2181705 1970 cpu1: timer 2181707 1970 Total 62923070 56841 -------------- next part -------------- hostb0@pci0:0:0:0: class=0x060000 card=0x04381028 chip=0xd1318086 rev=0x11 hdr=0x00 vendor = 'Intel Corporation' class = bridge subclass = HOST-PCI pcib1@pci0:0:3:0: class=0x060400 card=0x04381028 chip=0xd1388086 rev=0x11 hdr=0x01 vendor = 'Intel Corporation' class = bridge subclass = PCI-PCI none0@pci0:0:8:0: class=0x088000 card=0x00380028 chip=0xd1558086 rev=0x11 hdr=0x00 vendor = 'Intel Corporation' class = base peripheral none1@pci0:0:8:1: class=0x088000 card=0x00380028 chip=0xd1568086 rev=0x11 hdr=0x00 vendor = 'Intel Corporation' class = base peripheral none2@pci0:0:8:2: class=0x088000 card=0x00380028 chip=0xd1578086 rev=0x11 hdr=0x00 vendor = 'Intel Corporation' class = base peripheral none3@pci0:0:8:3: class=0x088000 card=0x00380028 chip=0xd1588086 rev=0x11 hdr=0x00 vendor = 'Intel Corporation' class = base peripheral none4@pci0:0:16:0: class=0x088000 card=0x00380028 chip=0xd1508086 rev=0x11 hdr=0x00 vendor = 'Intel Corporation' class = base peripheral none5@pci0:0:16:1: class=0x088000 card=0x00380028 chip=0xd1518086 rev=0x11 hdr=0x00 vendor = 'Intel Corporation' class = base peripheral none6@pci0:0:22:0: class=0x078000 card=0x04381028 chip=0x3b648086 rev=0x06 hdr=0x00 vendor = 'Intel Corporation' class = simple comms ehci0@pci0:0:26:0: class=0x0c0320 card=0x04381028 chip=0x3b3c8086 rev=0x06 hdr=0x00 vendor = 'Intel Corporation' class = serial bus subclass = USB none7@pci0:0:27:0: class=0x040300 card=0x04381028 chip=0x3b568086 rev=0x06 hdr=0x00 vendor = 'Intel Corporation' class = multimedia subclass = HDA pcib2@pci0:0:28:0: class=0x060400 card=0x04381028 chip=0x3b428086 rev=0x06 hdr=0x01 vendor = 'Intel Corporation' class = bridge subclass = PCI-PCI pcib3@pci0:0:28:5: class=0x060400 card=0x04381028 chip=0x3b4c8086 rev=0x06 hdr=0x01 vendor = 'Intel Corporation' class = bridge subclass = PCI-PCI ehci1@pci0:0:29:0: class=0x0c0320 card=0x04381028 chip=0x3b348086 rev=0x06 hdr=0x00 vendor = 'Intel Corporation' class = serial bus subclass = USB pcib4@pci0:0:30:0: class=0x060401 card=0x04381028 chip=0x244e8086 rev=0xa6 hdr=0x01 vendor = 'Intel Corporation' device = '82801 Family (ICH2/3/4/5/6/7/8/9,63xxESB) Hub Interface to PCI Bridge' class = bridge subclass = PCI-PCI isab0@pci0:0:31:0: class=0x060100 card=0x04381028 chip=0x3b088086 rev=0x06 hdr=0x00 vendor = 'Intel Corporation' class = bridge subclass = PCI-ISA atapci0@pci0:0:31:2: class=0x01018f card=0x04381028 chip=0x3b208086 rev=0x06 hdr=0x00 vendor = 'Intel Corporation' device = 'IBEX SATA Controller' class = mass storage subclass = ATA none8@pci0:0:31:3: class=0x0c0500 card=0x04381028 chip=0x3b308086 rev=0x06 hdr=0x00 vendor = 'Intel Corporation' class = serial bus subclass = SMBus atapci1@pci0:0:31:5: class=0x010185 card=0x04381028 chip=0x3b268086 rev=0x06 hdr=0x00 vendor = 'Intel Corporation' device = 'IBEX SATA Controller' class = mass storage subclass = ATA vgapci0@pci0:1:0:0: class=0x030000 card=0x010e1028 chip=0x68f91002 rev=0x00 hdr=0x00 vendor = 'ATI Technologies Inc. / Advanced Micro Devices, Inc.' class = display subclass = VGA none9@pci0:1:0:1: class=0x040300 card=0xaa681028 chip=0xaa681002 rev=0x00 hdr=0x00 vendor = 'ATI Technologies Inc. / Advanced Micro Devices, Inc.' class = multimedia subclass = HDA bge0@pci0:3:0:0: class=0x020000 card=0x04381028 chip=0x169114e4 rev=0x01 hdr=0x00 vendor = 'Broadcom Corporation' class = network subclass = ethernet
Jeremy Chadwick
2010-Mar-12 13:18 UTC
8-STABLE interrupt storm on atapci(?), Dell Inspiron 580
On Fri, Mar 12, 2010 at 01:14:09PM +0100, Pierre Beyssac wrote:> I'm having "interrupt storm detected" messages on a Dell Inspiron > 580 running up-to-date 8-STABLE (amd64 arch). The interrupts seem > to come from one of the atapci controllers, apparently atapci0 (main > controller, with a SATA disk and an ATAPI optical drive).I'm a little confused by the kernel output. It appears as if you're using the new SATA-to-CAM layer (ahci.ko) for your SATA disks, rather than the ataahci.ko layer... but I don't see any indication of AHCI being available/used on your southbridge chipset. Possibly this is the source of the problem (specifically, it looks like FreeBSD doesn't have proper device ID knowledge of what this controller is. I believe that's because this system is *very* new, a Core i3/i5/i7 system)? If you disable use of ahci.ko and use the standard ata(4) layer, does the interrupt storm go away? -- | Jeremy Chadwick jdc@parodius.com | | Parodius Networking http://www.parodius.com/ | | UNIX Systems Administrator Mountain View, CA, USA | | Making life hard for others since 1977. PGP: 4BD6C0CB |
Pyun YongHyeon
2010-Mar-12 17:47 UTC
8-STABLE interrupt storm on atapci(?), Dell Inspiron 580
On Fri, Mar 12, 2010 at 01:14:09PM +0100, Pierre Beyssac wrote:> Hello, > > I'm having "interrupt storm detected" messages on a Dell Inspiron > 580 running up-to-date 8-STABLE (amd64 arch). The interrupts seem > to come from one of the atapci controllers, apparently atapci0 (main > controller, with a SATA disk and an ATAPI optical drive). > > ata_interrupt gets called at a variable rate, between 1000-150000 > times per second, constantly, even when the disk is not used. > > >From adding debug sysctl code in ata-all.c:ata_interrupt_locked() > I have been able to check that: > ch->running is NULL (breaks loop in "do we have a running request") > ch->state=0 > ch->unit=0 ch->devices=1 (ATA_ATA_MASTER) most of the time. > > Here's attached dmesg output, pciconf -lv output, kernel configuration > and vmstat -i output. A -current kernel exhibits the same behaviour. > > Any hint/idea how to debug this further would be really appreciated... > -- > Pierre Beyssac pb@fasterix.frmug.org[...]> bge0: <Broadcom BCM57780 A1, ASIC rev. 0x57780001> mem 0xfbff0000-0xfbffffff irq 17 at device 0.0 on pci3 > bge0: Reserved 0x10000 bytes for rid 0x10 type 3 at 0xfbff0000 > bge0: adjust device control 0x2000 -> 0x5000 > bge0: attempting to allocate 1 MSI vectors (1 supported) > msi: routing MSI IRQ 256 to local APIC 0 vector 50 > bge0: using IRQ 256 for MSI > bge0: CHIP ID 0x57780001; ASIC REV 0x57780; CHIP REV 0x577800; PCI-E > bge0: Disabling fastboot > bge0: Disabling fastboot > miibus0: <MII bus> on bge0 > ukphy0: <Generic IEEE 802.3u media interface> PHY 1 on miibus0 > ukphy0: OUI 0x00d897, model 0x0019, rev. 1^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ This is not related with your interrupt storm issue but something is wrong here. I think brgphy(4) should be used for bge(4). Have no idea why the OUI has a different value. Would you try attached patch and let me know whether brgphy(4) get attached to the PHY?> ukphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-FDX, auto > bge0: bpf attached > bge0: Ethernet address: 00:25:64:f4:27:26 > bge0: [MPSAFE] > bge0: [FILTER][...] -------------- next part -------------- A non-text attachment was scrubbed... Name: BCM57780.diff Type: text/x-diff Size: 1355 bytes Desc: not available Url : http://lists.freebsd.org/pipermail/freebsd-stable/attachments/20100312/51b7b882/BCM57780.bin
Pyun YongHyeon
2010-Mar-12 22:38 UTC
8-STABLE interrupt storm on atapci(?), Dell Inspiron 580
On Fri, Mar 12, 2010 at 09:28:52PM +0100, Pierre Beyssac wrote:> On Fri, Mar 12, 2010 at 12:02:24PM -0800, Pyun YongHyeon wrote: > > Hmm, try this one and let me know it make any differences. > > No, still the same, negotiates at 10baseT/UTP.It seems the PHY has no BRGPHY_MII_AUXSTS register as it does not seem to manufactured by Broadcom. Adding a special case to brgphy(4) does not look right. Does ukphy(4) work without problem? If so I think you can live with ukphy(4).
Pierre Beyssac
2010-Mar-13 13:34 UTC
8-STABLE interrupt storm on atapci(?), Dell Inspiron 580
On Fri, Mar 12, 2010 at 02:38:11PM -0800, Pyun YongHyeon wrote:> It seems the PHY has no BRGPHY_MII_AUXSTS register as it does not > seem to manufactured by Broadcom. Adding a special case to > brgphy(4) does not look right. Does ukphy(4) work without problem? > If so I think you can live with ukphy(4).Oh yes, it works like a charm, no worry. -- Pierre Beyssac pb@fasterix.frmug.org