Bug#437344: linux-image-2.6.21-2-686: seemingly random SATA disk lockup with ICH5 intel bridge
Package: linux-image-2.6.21-2-686
Version: 2.6.21-6
Severity: important
Hello,
I'm trying to get to run a Seagate 250GB hard disk. It works with Microsoft Windows XP so it is not a hardware issue.
Sometimes the system boots perfectly, sometimes an error show up soon in the boot process an then the hard disk, while recognized, does not allow access to anything on it, including its partition table, making the system temporarily locked each time it tries to access it.
It is not a partition issue or whatever, the partition model is simple and was erased and cleaned several times.
The report is made against the latest kernel I have installed but it fails also with kernel 2.6.15.
Here comes a boot process, the interesting part is the following. If it does not show up, then the disk works perfectly. Considering the problem is random to me (maybe there a logic, but I do not get it)
Aug 10 12:15:29 eos kernel: SCSI subsystem initialized
Aug 10 12:15:29 eos kernel: [<c014b67e>] __report_bad_irq+0x36/0x75
Aug 10 12:15:29 eos kernel: [<c014b865>] note_interrupt+0x1a8/0x1e3
Aug 10 12:15:29 eos kernel: [<f885d156>] usb_hcd_irq+0x21/0x4e [usbcore]
Aug 10 12:15:29 eos kernel: [<c014adc5>] handle_IRQ_event+0x23/0x51
Aug 10 12:15:29 eos kernel: [<c014bfcb>] handle_fasteoi_irq+0x86/0xa6
Aug 10 12:15:29 eos kernel: [<c0106486>] do_IRQ+0x55/0x6f
Aug 10 12:15:29 eos kernel: [<c012642a>] irq_exit+0x53/0x6b
Aug 10 12:15:29 eos kernel: [<c0114bc2>] smp_apic_timer_interrupt+0x71/0x7d
Aug 10 12:15:29 eos kernel: [<c010475f>] common_interrupt+0x23/0x28
Aug 10 12:15:29 eos kernel: =======================
Aug 10 12:15:29 eos kernel: hda: ST380021A, ATA DISK drive
later comes up things like:
Aug 10 12:15:29 eos kernel: ATA: abnormal status 0x7F on port 0x0001c007
Aug 10 12:15:29 eos kernel: scsi1 : ata_piix
Aug 10 12:15:29 eos kernel: ata2.00: ATA-7: ST3250620AS, 3.AAK, max UDMA/133
Aug 10 12:15:29 eos kernel: ata2.00: 488397168 sectors, multi 16: LBA48 NCQ (depth 0/32)
Aug 10 12:15:29 eos kernel: ata2.00: qc timeout (cmd 0xef)
Aug 10 12:15:29 eos kernel: ata2: failed to recover some devices, retrying in 5 secs
Aug 10 12:15:29 eos kernel: ata2.00: qc timeout (cmd 0xef)
Aug 10 12:15:29 eos kernel: ata2.00: limiting speed to UDMA/133:PIO3
Aug 10 12:15:29 eos kernel: ata2: failed to recover some devices, retrying in 5 secs
Aug 10 12:15:29 eos kernel: ata2.00: qc timeout (cmd 0xef)
Aug 10 12:15:29 eos kernel: ata2.00: disabled
It comes from this boot messages (ending here by a CTRL-ALT-DEL)
#----------------------------------------------------------------
Aug 10 12:15:29 eos syslog-ng[2986]: syslog-ng starting up; version='2.0.0'
Aug 10 12:15:29 eos kernel: Linux version 2.6.21-2-686 (Debian 2.6.21-6) (waldi@debian.org) (gcc version 4.1.3 20070629 (prerelease) (Debian 4.1.2-13)) #1 SMP Wed Jul 11 03:53:02 UTC 2007
Aug 10 12:15:29 eos kernel: BIOS-provided physical RAM map:
Aug 10 12:15:29 eos kernel: sanitize start
Aug 10 12:15:29 eos kernel: sanitize end
Aug 10 12:15:29 eos kernel: copy_e820_map() start: 0000000000000000 size: 000000000009f800 end: 000000000009f800 type: 1
Aug 10 12:15:29 eos kernel: copy_e820_map() type is E820_RAM
Aug 10 12:15:29 eos kernel: copy_e820_map() start: 000000000009f800 size: 0000000000000800 end: 00000000000a0000 type: 2
Aug 10 12:15:29 eos kernel: copy_e820_map() start: 00000000000f0000 size: 0000000000010000 end: 0000000000100000 type: 2
Aug 10 12:15:29 eos kernel: copy_e820_map() start: 0000000000100000 size: 000000003fef0000 end: 000000003fff0000 type: 1
Aug 10 12:15:29 eos kernel: copy_e820_map() type is E820_RAM
Aug 10 12:15:29 eos kernel: copy_e820_map() start: 000000003fff0000 size: 0000000000003000 end: 000000003fff3000 type: 4
Aug 10 12:15:29 eos kernel: copy_e820_map() start: 000000003fff3000 size: 000000000000d000 end: 0000000040000000 type: 3
Aug 10 12:15:29 eos kernel: copy_e820_map() start: 00000000fec00000 size: 0000000001400000 end: 0000000100000000 type: 2
Aug 10 12:15:29 eos kernel: BIOS-e820: 0000000000000000 - 000000000009f800 (usable)
Aug 10 12:15:29 eos kernel: BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved)
Aug 10 12:15:29 eos kernel: BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
Aug 10 12:15:29 eos kernel: BIOS-e820: 0000000000100000 - 000000003fff0000 (usable)
Aug 10 12:15:29 eos kernel: BIOS-e820: 000000003fff0000 - 000000003fff3000 (ACPI NVS)
Aug 10 12:15:29 eos kernel: BIOS-e820: 000000003fff3000 - 0000000040000000 (ACPI data)
Aug 10 12:15:29 eos kernel: BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved)
Aug 10 12:15:29 eos kernel: 127MB HIGHMEM available.
Aug 10 12:15:29 eos kernel: 896MB LOWMEM available.
Aug 10 12:15:29 eos kernel: found SMP MP-table at 000f5350
Aug 10 12:15:29 eos kernel: Zone PFN ranges:
Aug 10 12:15:29 eos kernel: DMA 0 -> 4096
Aug 10 12:15:29 eos kernel: Normal 4096 -> 229376
Aug 10 12:15:29 eos kernel: HighMem 229376 -> 262128
Aug 10 12:15:29 eos kernel: early_node_map[1] active PFN ranges
Aug 10 12:15:29 eos kernel: 0: 0 -> 262128
Aug 10 12:15:29 eos kernel: DMI 2.2 present.
Aug 10 12:15:29 eos kernel: ACPI: RSDP 000F7100, 0014 (r0 IntelR)
Aug 10 12:15:29 eos kernel: ACPI: RSDT 3FFF3000, 002C (r1 IntelR AWRDACPI 42302E31 AWRD 0)
Aug 10 12:15:29 eos kernel: ACPI: FACP 3FFF3040, 0074 (r1 IntelR AWRDACPI 42302E31 AWRD 0)
Aug 10 12:15:29 eos kernel: ACPI: DSDT 3FFF30C0, 405F (r1 INTELR AWRDACPI 1000 MSFT 100000E)
Aug 10 12:15:29 eos kernel: ACPI: FACS 3FFF0000, 0040
Aug 10 12:15:29 eos kernel: ACPI: APIC 3FFF7140, 007C (r1 IntelR AWRDACPI 42302E31 AWRD 0)
Aug 10 12:15:29 eos kernel: ACPI: PM-Timer IO Port: 0x408
Aug 10 12:15:29 eos kernel: ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
Aug 10 12:15:29 eos kernel: Processor #0 15:3 APIC version 20
Aug 10 12:15:29 eos kernel: ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled)
Aug 10 12:15:29 eos kernel: Processor #1 15:3 APIC version 20
Aug 10 12:15:29 eos kernel: ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
Aug 10 12:15:29 eos kernel: ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
Aug 10 12:15:29 eos kernel: ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
Aug 10 12:15:29 eos kernel: IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-23
Aug 10 12:15:29 eos kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Aug 10 12:15:29 eos kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Aug 10 12:15:29 eos kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 14 global_irq 14 high edge)
Aug 10 12:15:29 eos kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 15 global_irq 15 high edge)
Aug 10 12:15:29 eos kernel: Enabling APIC mode: Flat. Using 1 I/O APICs
Aug 10 12:15:29 eos kernel: Using ACPI (MADT) for SMP configuration information
Aug 10 12:15:29 eos kernel: Allocating PCI resources starting at 50000000 (gap: 40000000:bec00000)
Aug 10 12:15:29 eos kernel: Built 1 zonelists. Total pages: 260081
Aug 10 12:15:29 eos kernel: Kernel command line: root=/dev/hda5
Aug 10 12:15:29 eos kernel: Enabling fast FPU save and restore... done.
Aug 10 12:15:29 eos kernel: Enabling unmasked SIMD FPU exception support... done.
Aug 10 12:15:29 eos kernel: Initializing CPU#0
Aug 10 12:15:29 eos kernel: PID hash table entries: 4096 (order: 12, 16384 bytes)
Aug 10 12:15:29 eos kernel: Detected 2992.856 MHz processor.
Aug 10 12:15:29 eos kernel: Console: colour VGA+ 80x25
Aug 10 12:15:29 eos kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Aug 10 12:15:29 eos kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Aug 10 12:15:29 eos kernel: Memory: 1030496k/1048512k available (1661k kernel code, 17272k reserved, 636k data, 212k init, 131008k highmem)
Aug 10 12:15:29 eos kernel: virtual kernel memory layout:
Aug 10 12:15:29 eos kernel: fixmap : 0xfff4f000 - 0xfffff000 ( 704 kB)
Aug 10 12:15:29 eos kernel: pkmap : 0xff800000 - 0xffc00000 (4096 kB)
Aug 10 12:15:29 eos kernel: vmalloc : 0xf8800000 - 0xff7fe000 ( 111 MB)
Aug 10 12:15:29 eos kernel: lowmem : 0xc0000000 - 0xf8000000 ( 896 MB)
Aug 10 12:15:29 eos kernel: .init : 0xc0345000 - 0xc037a000 ( 212 kB)
Aug 10 12:15:29 eos kernel: .data : 0xc029f5e5 - 0xc033e834 ( 636 kB)
Aug 10 12:15:29 eos kernel: .text : 0xc0100000 - 0xc029f5e5 (1661 kB)
Aug 10 12:15:29 eos kernel: Checking if this processor honours the WP bit even in supervisor mode... Ok.
Aug 10 12:15:29 eos kernel: Calibrating delay using timer specific routine.. 5989.71 BogoMIPS (lpj=11979424)
Aug 10 12:15:29 eos kernel: Security Framework v1.0.0 initialized
Aug 10 12:15:29 eos kernel: SELinux: Disabled at boot.
Aug 10 12:15:29 eos kernel: Capability LSM initialized
Aug 10 12:15:29 eos kernel: Mount-cache hash table entries: 512
Aug 10 12:15:29 eos kernel: monitor/mwait feature present.
Aug 10 12:15:29 eos kernel: using mwait in idle threads.
Aug 10 12:15:29 eos kernel: CPU: Trace cache: 12K uops, L1 D cache: 16K
Aug 10 12:15:29 eos kernel: CPU: L2 cache: 1024K
Aug 10 12:15:29 eos kernel: CPU: Physical Processor ID: 0
Aug 10 12:15:29 eos kernel: Intel machine check architecture supported.
Aug 10 12:15:29 eos kernel: Intel machine check reporting enabled on CPU#0.
Aug 10 12:15:29 eos kernel: CPU0: Intel P4/Xeon Extended MCE MSRs (12) available
Aug 10 12:15:29 eos kernel: CPU0: Thermal monitoring enabled
Aug 10 12:15:29 eos kernel: Compat vDSO mapped to ffffe000.
Aug 10 12:15:29 eos kernel: Checking 'hlt' instruction... OK.
Aug 10 12:15:29 eos kernel: SMP alternatives: switching to UP code
Aug 10 12:15:29 eos kernel: ACPI: Core revision 20070126
Aug 10 12:15:29 eos kernel: CPU0: Intel(R) Pentium(R) 4 CPU 3.00GHz stepping 04
Aug 10 12:15:29 eos kernel: SMP alternatives: switching to SMP code
Aug 10 12:15:29 eos kernel: Booting processor 1/1 eip 3000
Aug 10 12:15:29 eos kernel: Initializing CPU#1
Aug 10 12:15:29 eos kernel: Calibrating delay using timer specific routine.. 5985.47 BogoMIPS (lpj=11970952)
Aug 10 12:15:29 eos kernel: monitor/mwait feature present.
Aug 10 12:15:29 eos kernel: CPU: Trace cache: 12K uops, L1 D cache: 16K
Aug 10 12:15:29 eos kernel: CPU: L2 cache: 1024K
Aug 10 12:15:29 eos kernel: CPU: Physical Processor ID: 0
Aug 10 12:15:29 eos kernel: Intel machine check architecture supported.
Aug 10 12:15:29 eos kernel: Intel machine check reporting enabled on CPU#1.
Aug 10 12:15:29 eos kernel: CPU1: Intel P4/Xeon Extended MCE MSRs (12) available
Aug 10 12:15:29 eos kernel: CPU1: Thermal monitoring enabled
Aug 10 12:15:29 eos kernel: CPU1: Intel(R) Pentium(R) 4 CPU 3.00GHz stepping 04
Aug 10 12:15:29 eos kernel: Total of 2 processors activated (11975.18 BogoMIPS).
Aug 10 12:15:29 eos kernel: ENABLING IO-APIC IRQs
Aug 10 12:15:29 eos kernel: ..TIMER: vector=0x31 apic1=0 pin1=2 apic2=-1 pin2=-1
Aug 10 12:15:29 eos kernel: checking TSC synchronization [CPU#0 -> CPU#1]: passed.
Aug 10 12:15:29 eos kernel: Brought up 2 CPUs
Aug 10 12:15:29 eos kernel: migration_cost=10
Aug 10 12:15:29 eos kernel: Booting paravirtualized kernel on bare hardware
Aug 10 12:15:29 eos kernel: NET: Registered protocol family 16
Aug 10 12:15:29 eos kernel: ACPI: bus type pci registered
Aug 10 12:15:29 eos kernel: PCI: PCI BIOS revision 2.10 entry at 0xfb450, last bus=2
Aug 10 12:15:29 eos kernel: PCI: Using configuration type 1
Aug 10 12:15:29 eos kernel: Setting up standard PCI resources
Aug 10 12:15:29 eos kernel: ACPI: Interpreter enabled
Aug 10 12:15:29 eos kernel: ACPI: (supports S0 S1 S4 S5)
Aug 10 12:15:29 eos kernel: ACPI: Using IOAPIC for interrupt routing
Aug 10 12:15:29 eos kernel: ACPI: PCI Root Bridge [PCI0] (0000:00)
Aug 10 12:15:29 eos kernel: PCI quirk: region 0400-047f claimed by ICH4 ACPI/GPIO/TCO
Aug 10 12:15:29 eos kernel: PCI quirk: region 0480-04bf claimed by ICH4 GPIO
Aug 10 12:15:29 eos kernel: PCI: Transparent bridge - 0000:00:1e.0
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 7 9 10 11 *12 14 15)
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 7 *9 10 11 12 14 15)
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 *5 7 9 10 11 12 14 15)
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt Link [LNKD] (IRQs *3 4 5 7 9 10 11 12 14 15)
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 7 9 10 11 12 14 15) *0, disabled.
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 7 9 10 11 12 14 15) *0, disabled.
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt Link [LNK0] (IRQs 3 4 5 7 9 10 *11 12 14 15)
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt Link [LNK1] (IRQs 3 4 5 7 9 *10 11 12 14 15)
Aug 10 12:15:29 eos kernel: Linux Plug and Play Support v0.97 (c) Adam Belay
Aug 10 12:15:29 eos kernel: pnp: PnP ACPI init
Aug 10 12:15:29 eos kernel: pnp: PnP ACPI: found 11 devices
Aug 10 12:15:29 eos kernel: PnPBIOS: Disabled by ACPI PNP
Aug 10 12:15:29 eos kernel: PCI: Using ACPI for IRQ routing
Aug 10 12:15:29 eos kernel: PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report
Aug 10 12:15:29 eos kernel: NET: Registered protocol family 8
Aug 10 12:15:29 eos kernel: NET: Registered protocol family 20
Aug 10 12:15:29 eos kernel: pnp: 00:09: ioport range 0x400-0x4bf could not be reserved
Aug 10 12:15:29 eos kernel: pnp: 00:0a: iomem range 0xf0000-0xf3fff could not be reserved
Aug 10 12:15:29 eos kernel: pnp: 00:0a: iomem range 0xf4000-0xf7fff could not be reserved
Aug 10 12:15:29 eos kernel: pnp: 00:0a: iomem range 0xf8000-0xfbfff could not be reserved
Aug 10 12:15:29 eos kernel: pnp: 00:0a: iomem range 0xfc000-0xfffff could not be reserved
Aug 10 12:15:29 eos kernel: Time: tsc clocksource has been installed.
Aug 10 12:15:29 eos kernel: PCI: Bridge: 0000:00:01.0
Aug 10 12:15:29 eos kernel: IO window: 9000-9fff
Aug 10 12:15:29 eos kernel: MEM window: f8000000-f9ffffff
Aug 10 12:15:29 eos kernel: PREFETCH window: e0000000-efffffff
Aug 10 12:15:29 eos kernel: PCI: Bridge: 0000:00:1e.0
Aug 10 12:15:29 eos kernel: IO window: a000-afff
Aug 10 12:15:29 eos kernel: MEM window: fa000000-fa0fffff
Aug 10 12:15:29 eos kernel: PREFETCH window: disabled.
Aug 10 12:15:29 eos kernel: NET: Registered protocol family 2
Aug 10 12:15:29 eos kernel: IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
Aug 10 12:15:29 eos kernel: TCP established hash table entries: 131072 (order: 8, 1572864 bytes)
Aug 10 12:15:29 eos kernel: TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
Aug 10 12:15:29 eos kernel: TCP: Hash tables configured (established 131072 bind 65536)
Aug 10 12:15:29 eos kernel: TCP reno registered
Aug 10 12:15:29 eos kernel: checking if image is initramfs... it is
Aug 10 12:15:29 eos kernel: Freeing initrd memory: 5329k freed
Aug 10 12:15:29 eos kernel: audit: initializing netlink socket (disabled)
Aug 10 12:15:29 eos kernel: audit(1186740806.848:1): initialized
Aug 10 12:15:29 eos kernel: highmem bounce pool size: 64 pages
Aug 10 12:15:29 eos kernel: VFS: Disk quotas dquot_6.5.1
Aug 10 12:15:29 eos kernel: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Aug 10 12:15:29 eos kernel: io scheduler noop registered
Aug 10 12:15:29 eos kernel: io scheduler anticipatory registered
Aug 10 12:15:29 eos kernel: io scheduler deadline registered
Aug 10 12:15:29 eos kernel: io scheduler cfq registered (default)
Aug 10 12:15:29 eos kernel: Switched to high resolution mode on CPU 0
Aug 10 12:15:29 eos kernel: isapnp: Scanning for PnP cards...
Aug 10 12:15:29 eos kernel: Switched to high resolution mode on CPU 1
Aug 10 12:15:29 eos kernel: isapnp: No Plug & Play device found
Aug 10 12:15:29 eos kernel: Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
Aug 10 12:15:29 eos kernel: serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
Aug 10 12:15:29 eos kernel: 00:06: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
Aug 10 12:15:29 eos kernel: RAMDISK driver initialized: 16 RAM disks of 8192K size 1024 blocksize
Aug 10 12:15:29 eos kernel: PNP: PS/2 Controller [PNP0303:PS2K] at 0x60,0x64 irq 1
Aug 10 12:15:29 eos kernel: PNP: PS/2 controller doesn't have AUX irq; using default 12
Aug 10 12:15:29 eos kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Aug 10 12:15:29 eos kernel: mice: PS/2 mouse device common for all mice
Aug 10 12:15:29 eos kernel: TCP bic registered
Aug 10 12:15:29 eos kernel: NET: Registered protocol family 1
Aug 10 12:15:29 eos kernel: NET: Registered protocol family 17
Aug 10 12:15:29 eos kernel: Using IPI No-Shortcut mode
Aug 10 12:15:29 eos kernel: Freeing unused kernel memory: 212k freed
Aug 10 12:15:29 eos kernel: input: AT Translated Set 2 keyboard as /class/input/input0
Aug 10 12:15:29 eos kernel: ACPI: Fan [FAN] (on)
Aug 10 12:15:29 eos kernel: ACPI: Thermal Zone [THRM] (49 C)
Aug 10 12:15:29 eos kernel: usbcore: registered new interface driver usbfs
Aug 10 12:15:29 eos kernel: usbcore: registered new interface driver hub
Aug 10 12:15:29 eos kernel: usbcore: registered new device driver usb
Aug 10 12:15:29 eos kernel: USB Universal Host Controller Interface driver v3.0
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt 0000:00:1d.0[A] -> GSI 16 (level, low) -> IRQ 16
Aug 10 12:15:29 eos kernel: uhci_hcd 0000:00:1d.0: UHCI Host Controller
Aug 10 12:15:29 eos kernel: uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 1
Aug 10 12:15:29 eos kernel: uhci_hcd 0000:00:1d.0: irq 16, io base 0x0000bc00
Aug 10 12:15:29 eos kernel: usb usb1: configuration #1 chosen from 1 choice
Aug 10 12:15:29 eos kernel: hub 1-0:1.0: USB hub found
Aug 10 12:15:29 eos kernel: hub 1-0:1.0: 2 ports detected
Aug 10 12:15:29 eos kernel: Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
Aug 10 12:15:29 eos kernel: ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt 0000:00:1d.1[B] -> GSI 19 (level, low) -> IRQ 17
Aug 10 12:15:29 eos kernel: uhci_hcd 0000:00:1d.1: UHCI Host Controller
Aug 10 12:15:29 eos kernel: uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 2
Aug 10 12:15:29 eos kernel: uhci_hcd 0000:00:1d.1: irq 17, io base 0x0000b000
Aug 10 12:15:29 eos kernel: usb usb2: configuration #1 chosen from 1 choice
Aug 10 12:15:29 eos kernel: hub 2-0:1.0: USB hub found
Aug 10 12:15:29 eos kernel: hub 2-0:1.0: 2 ports detected
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt 0000:00:1d.2[C] -> GSI 18 (level, low) -> IRQ 18
Aug 10 12:15:29 eos kernel: uhci_hcd 0000:00:1d.2: UHCI Host Controller
Aug 10 12:15:29 eos kernel: uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 3
Aug 10 12:15:29 eos kernel: uhci_hcd 0000:00:1d.2: irq 18, io base 0x0000b400
Aug 10 12:15:29 eos kernel: usb usb3: configuration #1 chosen from 1 choice
Aug 10 12:15:29 eos kernel: hub 3-0:1.0: USB hub found
Aug 10 12:15:29 eos kernel: hub 3-0:1.0: 2 ports detected
Aug 10 12:15:29 eos kernel: 8139cp: 10/100 PCI Ethernet driver v1.3 (Mar 22, 2004)
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt 0000:00:1d.3[A] -> GSI 16 (level, low) -> IRQ 16
Aug 10 12:15:29 eos kernel: uhci_hcd 0000:00:1d.3: UHCI Host Controller
Aug 10 12:15:29 eos kernel: uhci_hcd 0000:00:1d.3: new USB bus registered, assigned bus number 4
Aug 10 12:15:29 eos kernel: uhci_hcd 0000:00:1d.3: irq 16, io base 0x0000b800
Aug 10 12:15:29 eos kernel: usb usb4: configuration #1 chosen from 1 choice
Aug 10 12:15:29 eos kernel: hub 4-0:1.0: USB hub found
Aug 10 12:15:29 eos kernel: hub 4-0:1.0: 2 ports detected
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt 0000:00:1d.7[D] -> GSI 23 (level, low) -> IRQ 19
Aug 10 12:15:29 eos kernel: ehci_hcd 0000:00:1d.7: EHCI Host Controller
Aug 10 12:15:29 eos kernel: ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 5
Aug 10 12:15:29 eos kernel: ehci_hcd 0000:00:1d.7: irq 19, io mem 0xfa100000
Aug 10 12:15:29 eos kernel: ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
Aug 10 12:15:29 eos kernel: usb usb5: configuration #1 chosen from 1 choice
Aug 10 12:15:29 eos kernel: hub 5-0:1.0: USB hub found
Aug 10 12:15:29 eos kernel: hub 5-0:1.0: 8 ports detected
Aug 10 12:15:29 eos kernel: usb 2-1: new low speed USB device using uhci_hcd and address 2
Aug 10 12:15:29 eos kernel: 8139too Fast Ethernet driver 0.9.28
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt 0000:02:04.0[A] -> GSI 22 (level, low) -> IRQ 20
Aug 10 12:15:29 eos kernel: eth0: RealTek RTL8139 at 0xa400, 00:40:f4:8e:49:49, IRQ 20
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt 0000:02:09.0[A] -> GSI 23 (level, low) -> IRQ 19
Aug 10 12:15:29 eos kernel: eth1: RealTek RTL8139 at 0xa800, 00:0d:87:39:fe:3c, IRQ 19
Aug 10 12:15:29 eos kernel: ICH5: IDE controller at PCI slot 0000:00:1f.1
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt 0000:00:1f.1[A] -> GSI 18 (level, low) -> IRQ 18
Aug 10 12:15:29 eos kernel: ICH5: chipset revision 2
Aug 10 12:15:29 eos kernel: ICH5: not 100% native mode: will probe irqs later
Aug 10 12:15:29 eos kernel: ide0: BM-DMA at 0xf000-0xf007, BIOS settings: hda:DMA, hdb:pio
Aug 10 12:15:29 eos kernel: ide1: BM-DMA at 0xf008-0xf00f, BIOS settings: hdc:DMA, hdd:DMA
Aug 10 12:15:29 eos kernel: SCSI subsystem initialized
Aug 10 12:15:29 eos kernel: [<c014b67e>] __report_bad_irq+0x36/0x75
Aug 10 12:15:29 eos kernel: [<c014b865>] note_interrupt+0x1a8/0x1e3
Aug 10 12:15:29 eos kernel: [<f885d156>] usb_hcd_irq+0x21/0x4e [usbcore]
Aug 10 12:15:29 eos kernel: [<c014adc5>] handle_IRQ_event+0x23/0x51
Aug 10 12:15:29 eos kernel: [<c014bfcb>] handle_fasteoi_irq+0x86/0xa6
Aug 10 12:15:29 eos kernel: [<c0106486>] do_IRQ+0x55/0x6f
Aug 10 12:15:29 eos kernel: [<c012642a>] irq_exit+0x53/0x6b
Aug 10 12:15:29 eos kernel: [<c0114bc2>] smp_apic_timer_interrupt+0x71/0x7d
Aug 10 12:15:29 eos kernel: [<c010475f>] common_interrupt+0x23/0x28
Aug 10 12:15:29 eos kernel: =======================
Aug 10 12:15:29 eos kernel: hda: ST380021A, ATA DISK drive
Aug 10 12:15:29 eos kernel: usb 2-1: new low speed USB device using uhci_hcd and address 3
Aug 10 12:15:29 eos kernel: usb 2-1: configuration #1 chosen from 1 choice
Aug 10 12:15:29 eos kernel: usbcore: registered new interface driver hiddev
Aug 10 12:15:29 eos kernel: input: Logitech USB-PS/2 Optical Mouse as /class/input/input1
Aug 10 12:15:29 eos kernel: input: USB HID v1.10 Mouse [Logitech USB-PS/2 Optical Mouse] on usb-0000:00:1d.1-1
Aug 10 12:15:29 eos kernel: usbcore: registered new interface driver usbhid
Aug 10 12:15:29 eos kernel: drivers/usb/input/hid-core.c: v2.6:USB HID core driver
Aug 10 12:15:29 eos kernel: ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
Aug 10 12:15:29 eos kernel: hdd: HL-DT-STDVD-ROM GDR8163B, ATAPI CD/DVD-ROM drive
Aug 10 12:15:29 eos kernel: ide1 at 0x170-0x177,0x376 on irq 15
Aug 10 12:15:29 eos kernel: ata_piix 0000:00:1f.2: MAP [ P0 -- P1 -- ]
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt 0000:00:1f.2[A] -> GSI 18 (level, low) -> IRQ 18
Aug 10 12:15:29 eos kernel: ata1: SATA max UDMA/133 cmd 0x0001c000 ctl 0x0001c402 bmdma 0x0001d000 irq 18
Aug 10 12:15:29 eos kernel: ata2: SATA max UDMA/133 cmd 0x0001c800 ctl 0x0001cc02 bmdma 0x0001d008 irq 18
Aug 10 12:15:29 eos kernel: scsi0 : ata_piix
Aug 10 12:15:29 eos kernel: hda: max request size: 128KiB
Aug 10 12:15:29 eos kernel: hda: 156301488 sectors (80026 MB) w/2048KiB Cache, CHS=65535/16/63, UDMA(100)
Aug 10 12:15:29 eos kernel: hda: cache flushes not supported
Aug 10 12:15:29 eos kernel: hda: hda1 hda2 < hda5 hda6 > hda3
Aug 10 12:15:29 eos kernel: hdd: ATAPI 52X DVD-ROM drive, 256kB Cache, UDMA(33)
Aug 10 12:15:29 eos kernel: Uniform CD-ROM driver Revision: 3.20
Aug 10 12:15:29 eos kernel: ATA: abnormal status 0x7F on port 0x0001c007
Aug 10 12:15:29 eos kernel: scsi1 : ata_piix
Aug 10 12:15:29 eos kernel: ata2.00: ATA-7: ST3250620AS, 3.AAK, max UDMA/133
Aug 10 12:15:29 eos kernel: ata2.00: 488397168 sectors, multi 16: LBA48 NCQ (depth 0/32)
Aug 10 12:15:29 eos kernel: ata2.00: qc timeout (cmd 0xef)
Aug 10 12:15:29 eos kernel: ata2: failed to recover some devices, retrying in 5 secs
Aug 10 12:15:29 eos kernel: ata2.00: qc timeout (cmd 0xef)
Aug 10 12:15:29 eos kernel: ata2.00: limiting speed to UDMA/133:PIO3
Aug 10 12:15:29 eos kernel: ata2: failed to recover some devices, retrying in 5 secs
Aug 10 12:15:29 eos kernel: ata2.00: qc timeout (cmd 0xef)
Aug 10 12:15:29 eos kernel: ata2.00: disabled
Aug 10 12:15:29 eos kernel: Attempting manual resume
Aug 10 12:15:29 eos kernel: kjournald starting. Commit interval 5 seconds
Aug 10 12:15:29 eos kernel: EXT3-fs: mounted filesystem with ordered data mode.
Aug 10 12:15:29 eos kernel: hda: cache flushes not supported
Aug 10 12:15:29 eos kernel: Real Time Clock Driver v1.12ac
Aug 10 12:15:29 eos kernel: pci_hotplug: PCI Hot Plug PCI Core version: 0.5
Aug 10 12:15:29 eos kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
Aug 10 12:15:29 eos kernel: input: PC Speaker as /class/input/input2
Aug 10 12:15:29 eos kernel: iTCO_wdt: Intel TCO WatchDog Timer Driver v1.01 (21-Jan-2007)
Aug 10 12:15:29 eos kernel: iTCO_wdt: Found a ICH5 or ICH5R TCO device (Version=1, TCOBASE=0x0460)
Aug 10 12:15:29 eos kernel: iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)
Aug 10 12:15:29 eos kernel: Linux agpgart interface v0.102 (c) Dave Jones
Aug 10 12:15:29 eos kernel: parport: PnPBIOS parport detected.
Aug 10 12:15:29 eos kernel: parport0: PC-style at 0x378 (0x778), irq 7, dma 3 [PCSPP,TRISTATE,COMPAT,EPP,ECP,DMA]
Aug 10 12:15:29 eos kernel: agpgart: Detected an Intel 865 Chipset.
Aug 10 12:15:29 eos kernel: agpgart: AGP aperture is 128M @ 0xf0000000
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt 0000:00:1f.3[B] -> GSI 17 (level, low) -> IRQ 21
Aug 10 12:15:29 eos kernel: ACPI: PCI Interrupt 0000:02:01.0[A] -> GSI 19 (level, low) -> IRQ 17
Aug 10 12:15:29 eos kernel: snd-ca0106: Model 1006 Rev 00000000 Serial 10061102
Aug 10 12:15:29 eos kernel: hda: cache flushes not supported
Aug 10 12:15:29 eos kernel: Adding 798800k swap on /dev/hda1. Priority:-1 extents:1 across:798800k
Aug 10 12:15:29 eos kernel: EXT3 FS on hda5, internal journal
Aug 10 12:15:29 eos kernel: Initializing USB Mass Storage driver...
Aug 10 12:15:29 eos kernel: usbcore: registered new interface driver usb-storage
Aug 10 12:15:29 eos kernel: USB Mass Storage support registered.
Aug 10 12:15:29 eos kernel: device-mapper: ioctl: 4.11.0-ioctl (2006-10-12) initialised: dm-devel@redhat.com
Aug 10 12:15:29 eos kernel: fuse init (API version 7.8)
Aug 10 12:15:29 eos kernel: kjournald starting. Commit interval 5 seconds
Aug 10 12:15:29 eos kernel: EXT3 FS on hda6, internal journal
Aug 10 12:15:29 eos kernel: EXT3-fs: mounted filesystem with ordered data mode.
Aug 10 12:15:29 eos kernel: eth0: link up, 100Mbps, full-duplex, lpa 0x45E1
Aug 10 12:15:29 eos kernel: NET: Registered protocol family 10
Aug 10 12:15:29 eos kernel: lo: Disabled Privacy Extensions
Aug 10 12:15:29 eos kernel: ttyS1: LSR safety check engaged!
Aug 10 12:15:29 eos kernel: ttyS1: LSR safety check engaged!
Aug 10 12:15:29 eos kernel: input: Power Button (FF) as /class/input/input3
Aug 10 12:15:29 eos kernel: ACPI: Power Button (FF) [PWRF]
Aug 10 12:15:29 eos kernel: input: Power Button (CM) as /class/input/input4
Aug 10 12:15:29 eos kernel: ACPI: Power Button (CM) [PWRB]
Aug 10 12:15:29 eos kernel: input: Sleep Button (CM) as /class/input/input5
Aug 10 12:15:29 eos kernel: ACPI: Sleep Button (CM) [SLPB]
Aug 10 12:15:35 eos kernel: process `snmpd' is using deprecated sysctl (syscall) net.ipv6.neigh.lo.retrans_time; Use net.ipv6.neigh.lo.retrans_time_ms instead.
Aug 10 12:15:35 eos shutdown[3339]: shutting down for system reboot
Aug 10 12:15:46 eos syslog-ng[2986]: SIGTERM received, terminating;
Aug 10 12:15:46 eos syslog-ng[2986]: syslog-ng shutting down; version='2.0.0
#--------------------------------------
Here comes another example, made with the 2.6.15 kernel, ending with a failure to read the partition table:
#----------------------------------------
Aug 10 20:28:19 eos kernel: hub 5-0:1.0: USB hub found
Aug 10 20:28:19 eos kernel: hub 5-0:1.0: 8 ports detected
Aug 10 20:28:19 eos kernel: [<c01331ea>] __report_bad_irq+0x31/0x73
Aug 10 20:28:19 eos kernel: [<c01332b9>] note_interrupt+0x75/0x98
Aug 10 20:28:19 eos kernel: [<c0132c9f>] __do_IRQ+0x9c/0xcd
Aug 10 20:28:19 eos kernel: [<c0104815>] do_IRQ+0x1d/0x28
Aug 10 20:28:19 eos kernel: [<c01031fa>] common_interrupt+0x1a/0x20
Aug 10 20:28:19 eos kernel: [<c0100d29>] mwait_idle+0x1f/0x33
Aug 10 20:28:19 eos kernel: [<c0100bca>] cpu_idle+0x59/0x6e
Aug 10 20:28:19 eos kernel: [<c02fe79f>] start_kernel+0x18a/0x18e
Aug 10 20:28:19 eos kernel: ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
Aug 10 20:28:19 eos kernel: usb 2-1: new low speed USB device using uhci_hcd and address 3
Aug 10 20:28:19 eos kernel: hdd: HL-DT-STDVD-ROM GDR8163B, ATAPI CD/DVD-ROM drive
Aug 10 20:28:19 eos kernel: ide1 at 0x170-0x177,0x376 on irq 15
Aug 10 20:28:19 eos kernel: ACPI: PCI Interrupt 0000:00:1f.2[A] -> GSI 18 (level, low) -> IRQ 169
Aug 10 20:28:19 eos kernel: ata1: SATA max UDMA/133 cmd 0xC000 ctl 0xC402 bmdma 0xD000 irq 169
Aug 10 20:28:19 eos kernel: ata2: SATA max UDMA/133 cmd 0xC800 ctl 0xCC02 bmdma 0xD008 irq 169
Aug 10 20:28:19 eos kernel: usbcore: registered new driver hiddev
Aug 10 20:28:19 eos kernel: hdd: ATAPI 52X DVD-ROM drive, 256kB Cache, UDMA(33)
Aug 10 20:28:19 eos kernel: Uniform CD-ROM driver Revision: 3.20
Aug 10 20:28:19 eos kernel: hda: max request size: 128KiB
Aug 10 20:28:19 eos kernel: hda: 156301488 sectors (80026 MB) w/2048KiB Cache, CHS=65535/16/63, UDMA(100)
Aug 10 20:28:19 eos kernel: hda: cache flushes not supported
Aug 10 20:28:19 eos kernel: hda: hda1 hda2 < hda5<6>input: Logitech USB-PS/2 Optical Mouse as /class/input/input1
Aug 10 20:28:19 eos kernel: input: USB HID v1.10 Mouse [Logitech USB-PS/2 Optical Mouse] on usb-0000:00:1d.1-1
Aug 10 20:28:19 eos kernel: usbcore: registered new driver usbhid
Aug 10 20:28:19 eos kernel: drivers/usb/input/hid-core.c: v2.6:USB HID core driver
Aug 10 20:28:19 eos kernel: hda6 > hda3
Aug 10 20:28:19 eos kernel: ATA: abnormal status 0x7F on port 0xC007
Aug 10 20:28:19 eos kernel: scsi0 : ata_piix
Aug 10 20:28:19 eos kernel: ata2: dev 0 ATA-7, max UDMA/133, 488397168 sectors: LBA48
Aug 10 20:28:19 eos kernel: ata2: slow completion (cmd ef)
Aug 10 20:28:19 eos kernel: ata2: dev 0 configured for UDMA/133
Aug 10 20:28:19 eos kernel: scsi1 : ata_piix
Aug 10 20:28:19 eos kernel: Vendor: ATA Model: ST3250620AS Rev: 3.AA
Aug 10 20:28:19 eos kernel: Type: Direct-Access ANSI SCSI revision: 05
Aug 10 20:28:19 eos kernel: ACPI: PCI Interrupt 0000:02:04.0[A] -> GSI 22 (level, low) -> IRQ 201
Aug 10 20:28:19 eos kernel: eth0: RealTek RTL8139 at 0xa400, 00:40:f4:8e:49:49, IRQ 201
Aug 10 20:28:19 eos kernel: ACPI: PCI Interrupt 0000:02:09.0[A] -> GSI 23 (level, low) -> IRQ 193
Aug 10 20:28:19 eos kernel: eth1: RealTek RTL8139 at 0xa800, 00:0d:87:39:fe:3c, IRQ 193
Aug 10 20:28:19 eos kernel: SCSI device sda: 488397168 512-byte hdwr sectors (250059 MB)
Aug 10 20:28:19 eos kernel: SCSI device sda: drive cache: write back
Aug 10 20:28:19 eos kernel: SCSI device sda: 488397168 512-byte hdwr sectors (250059 MB)
Aug 10 20:28:19 eos kernel: SCSI device sda: drive cache: write back
Aug 10 20:28:19 eos kernel: sda:<3>ata2: command 0x25 timeout, stat 0xd0 host_stat 0x64
Aug 10 20:28:19 eos kernel: ata2: status=0xd0 { Busy }
Aug 10 20:28:19 eos kernel: sd 1:0:0:0: SCSI error: return code = 0x8000002
Aug 10 20:28:19 eos kernel: sda: Current: sense key: Aborted Command
Aug 10 20:28:19 eos kernel: Additional sense: Scsi parity error
Aug 10 20:28:19 eos kernel: end_request: I/O error, dev sda, sector 0
Aug 10 20:28:19 eos kernel: ATA: abnormal status 0xD0 on port 0xC807
Aug 10 20:28:19 eos kernel: ATA: abnormal status 0xD0 on port 0xC807
Aug 10 20:28:19 eos kernel: ATA: abnormal status 0xD0 on port 0xC807
Aug 10 20:28:19 eos kernel: unable to read partition table
Aug 10 20:28:19 eos kernel: sd 1:0:0:0: Attached scsi disk sda
#-------------------------------------
In order to get the system to work without physically removing the hard disk, I'm forced to shutdown SATA via the BIOS. Note that I tried all the possible SATA options in BIOS (auto, combined mode, enhanced mode, sata only mode) with no conclusive result.
So lspci wont show the SATA interface here, but here's the lspci part for the IDE interface, which is also part of the intel ICH5:
00:1f.1 IDE interface: Intel Corporation 82801EB/ER (ICH5/ICH5R) IDE Controller (rev 02) (prog-if 8a [Master SecP PriP])
Subsystem: Elitegroup Computer Systems Unknown device 1875
Flags: bus master, medium devsel, latency 0, IRQ 185
I/O ports at <unassigned>
I/O ports at <unassigned>
I/O ports at <unassigned>
I/O ports at <unassigned>
I/O ports at f000 [size=16]
Memory at 50000000 (32-bit, non-prefetchable) [size=1K]
Tell me if there is any other information I can provide.
-- Package-specific info:
-- System Information:
Debian Release: lenny/sid
APT prefers testing
APT policy: (500, 'testing'), (500, 'stable')
Architecture: i386 (i686)
Kernel: Linux 2.6.15-1-686-smp (SMP w/2 CPU cores)
Locale: LANG=fr_FR@euro, LC_CTYPE=fr_FR@euro (charmap=ISO-8859-15)
Shell: /bin/sh linked to /bin/bash
Versions of packages linux-image-2.6.21-2-686 depends on:
ii initramfs-tools [linux-initr 0.89 tools for generating an initramfs
ii module-init-tools 3.3-pre11-4 tools for managing Linux kernel mo
Versions of packages linux-image-2.6.21-2-686 recommends:
ii libc6-i686 2.6-2 GNU C Library: Shared libraries [i
-- debconf information excluded
Reply to: