Bug#391867: "port is slow to respond" on computer with SATA disk
Hullo
I am having the same problem.
I built a kernel from the sources here:
http://people.debian.org/~nobse/kernel/linux-2.6/i386/
The problem is less severe but still a showstopper for production use. I
too am looking forward to getting this fixed.
When I user dd to fill one of the partitions I still triggered the
error. I was able to fill disk without errors once.
Then when I was removing the test file it triggered. It's a 10GB file.
Here is the log:
Jan 8 13:35:49 raidserver kernel: process `syslogd' is using obsolete
setsockopt SO_BSDCOMPAT
Jan 8 13:37:02 raidserver kernel: ata2.00: exception Emask 0x0 SAct 0x1
SErr 0x0 action 0x2 frozen
Jan 8 13:37:02 raidserver kernel: ata2.00: tag 0 cmd 0x61 Emask 0x4
stat 0x40 err 0x0 (timeout)
Jan 8 13:37:02 raidserver kernel: ata2: soft resetting port
Jan 8 13:37:02 raidserver kernel: ata2: softreset failed (1st FIS failed)
Jan 8 13:37:02 raidserver kernel: ata2: softreset failed, retrying in 5
secs
Jan 8 13:37:02 raidserver kernel: ata2: hard resetting port
Jan 8 13:37:02 raidserver kernel: ata2: port is slow to respond, please
be patient
Jan 8 13:37:02 raidserver kernel: ata2: port failed to respond (30 secs)
Jan 8 13:37:02 raidserver kernel: ata2: COMRESET failed (device not ready)
Jan 8 13:37:02 raidserver kernel: ata2: hardreset failed, retrying in 5
secs
Jan 8 13:37:02 raidserver kernel: ata2: hard resetting port
Jan 8 13:37:02 raidserver kernel: ata2: SATA link up 1.5 Gbps (SStatus
113 SControl 300)
Jan 8 13:37:02 raidserver kernel: ata2.00: configured for UDMA/133
Jan 8 13:37:02 raidserver kernel: ata2: EH complete
Jan 8 13:37:02 raidserver kernel: SCSI device sdc: 156301488 512-byte
hdwr sectors (80026 MB)
Jan 8 13:37:02 raidserver kernel: sdc: Write Protect is off
Jan 8 13:37:02 raidserver kernel: sdc: Mode Sense: 00 3a 00 00
Jan 8 13:37:02 raidserver kernel: SCSI device sdc: drive cache: write back
Jan 8 13:54:07 raidserver kernel: ata2.00: exception Emask 0x0 SAct 0x1
SErr 0x0 action 0x2 frozen
Jan 8 13:54:07 raidserver kernel: ata2.00: tag 0 cmd 0x61 Emask 0x4
stat 0x40 err 0x0 (timeout)
Jan 8 13:54:07 raidserver kernel: ata2: soft resetting port
Jan 8 13:54:07 raidserver kernel: ata2: softreset failed (1st FIS failed)
Jan 8 13:54:07 raidserver kernel: ata2: softreset failed, retrying in 5
secs
Jan 8 13:54:07 raidserver kernel: ata2: hard resetting port
Jan 8 13:54:07 raidserver kernel: ata2: port is slow to respond, please
be patient
Jan 8 13:54:07 raidserver kernel: ata2: port failed to respond (30 secs)
Jan 8 13:54:07 raidserver kernel: ata2: COMRESET failed (device not ready)
Jan 8 13:54:07 raidserver kernel: ata2: hardreset failed, retrying in 5
secs
Jan 8 13:54:07 raidserver kernel: ata2: hard resetting port
Jan 8 13:54:07 raidserver kernel: ata2: SATA link up 1.5 Gbps (SStatus
113 SControl 300)
Jan 8 13:54:07 raidserver kernel: ata2.00: configured for UDMA/133
Jan 8 13:54:07 raidserver kernel: ata2: EH complete
Jan 8 13:54:07 raidserver kernel: SCSI device sdc: 156301488 512-byte
hdwr sectors (80026 MB)
Jan 8 13:54:07 raidserver kernel: sdc: Write Protect is off
Jan 8 13:54:07 raidserver kernel: sdc: Mode Sense: 00 3a 00 00
Jan 8 13:54:07 raidserver kernel: SCSI device sdc: drive cache: write back
Jan 8 13:54:56 raidserver kernel: ata2.00: exception Emask 0x0 SAct 0x1
SErr 0x0 action 0x2 frozen
Jan 8 13:54:59 raidserver kernel: ata2.00: tag 0 cmd 0x61 Emask 0x4
stat 0x40 err 0x0 (timeout)
Jan 8 13:55:35 raidserver kernel: ata2: soft resetting port
Jan 8 13:55:35 raidserver kernel: ata2: softreset failed (1st FIS failed)
Jan 8 13:55:35 raidserver kernel: ata2: softreset failed, retrying in 5
secs
Jan 8 13:55:35 raidserver kernel: ata2: hard resetting port
Jan 8 13:55:35 raidserver kernel: ata2: port is slow to respond, please
be patient
Jan 8 13:55:35 raidserver kernel: ata2: port failed to respond (30 secs)
Jan 8 13:55:35 raidserver kernel: ata2: COMRESET failed (device not ready)
Jan 8 13:55:35 raidserver kernel: ata2: hardreset failed, retrying in 5
secs
Jan 8 13:55:35 raidserver kernel: ata2: hard resetting port
Jan 8 13:55:35 raidserver kernel: ata2: SATA link up 1.5 Gbps (SStatus
113 SControl 300)
Jan 8 13:55:35 raidserver kernel: ata2.00: configured for UDMA/133
Jan 8 13:55:35 raidserver kernel: ata2: EH complete
Jan 8 13:55:35 raidserver kernel: SCSI device sdc: 156301488 512-byte
hdwr sectors (80026 MB)
Jan 8 13:55:35 raidserver kernel: sdc: Write Protect is off
Jan 8 13:55:35 raidserver kernel: sdc: Mode Sense: 00 3a 00 00
Jan 8 13:55:35 raidserver kernel: SCSI device sdc: drive cache: write back
Jan 8 13:59:36 raidserver kernel: ata1.00: exception Emask 0x0 SAct 0x1
SErr 0x0 action 0x2 frozen
Jan 8 13:59:36 raidserver kernel: ata1.00: tag 0 cmd 0x61 Emask 0x4
stat 0x40 err 0x0 (timeout)
Jan 8 14:00:18 raidserver kernel: ata1: soft resetting port
Jan 8 14:00:18 raidserver kernel: ata1: softreset failed (1st FIS failed)
Jan 8 14:00:18 raidserver kernel: ata1: softreset failed, retrying in 5
secs
Jan 8 14:00:18 raidserver kernel: ata1: hard resetting port
Jan 8 14:00:18 raidserver kernel: ata1: port is slow to respond, please
be patient
Jan 8 14:00:18 raidserver kernel: ata1: port failed to respond (30 secs)
Jan 8 14:00:18 raidserver kernel: ata1: COMRESET failed (device not ready)
Jan 8 14:00:18 raidserver kernel: ata1: hardreset failed, retrying in 5
secs
Jan 8 14:00:18 raidserver kernel: ata1: hard resetting port
Jan 8 14:00:18 raidserver kernel: ata1: SATA link up 1.5 Gbps (SStatus
113 SControl 300)
Jan 8 14:00:18 raidserver kernel: ata1.00: configured for UDMA/133
Jan 8 14:00:18 raidserver kernel: ata1: EH complete
Jan 8 14:00:18 raidserver kernel: SCSI device sdb: 156301488 512-byte
hdwr sectors (80026 MB)
Jan 8 14:00:18 raidserver kernel: sdb: Write Protect is off
Jan 8 14:00:18 raidserver kernel: sdb: Mode Sense: 00 3a 00 00
Jan 8 14:00:18 raidserver kernel: SCSI device sdb: drive cache: write back
Here is the AHCI driver initialization phase:
Jan 8 13:35:49 raidserver kernel: libata version 2.00 loaded.
Jan 8 13:35:49 raidserver kernel: ahci 0000:00:1f.2: version 2.0
Jan 8 13:35:49 raidserver kernel: ACPI: PCI Interrupt 0000:00:1f.2[B]
-> GSI 19 (level, low) -> IRQ 193
Jan 8 13:35:49 raidserver kernel: PCI: Setting latency timer of device
0000:00:1f.2 to 64
Jan 8 13:35:49 raidserver kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32
slots 4 ports 1.5 Gbps 0xf impl SATA mode
Jan 8 13:35:49 raidserver kernel: ahci 0000:00:1f.2: flags: 64bit ncq
pm led slum part
Jan 8 13:35:49 raidserver kernel: ata1: SATA max UDMA/133 cmd
0xF8804100 ctl 0x0 bmdma 0x0 irq 193
Jan 8 13:35:49 raidserver kernel: ata2: SATA max UDMA/133 cmd
0xF8804180 ctl 0x0 bmdma 0x0 irq 193
Jan 8 13:35:49 raidserver kernel: ata3: SATA max UDMA/133 cmd
0xF8804200 ctl 0x0 bmdma 0x0 irq 193
Jan 8 13:35:49 raidserver kernel: ata4: SATA max UDMA/133 cmd
0xF8804280 ctl 0x0 bmdma 0x0 irq 193
Jan 8 13:35:49 raidserver kernel: scsi1 : ahci
Jan 8 13:35:49 raidserver kernel: ata1: SATA link up 1.5 Gbps (SStatus
113 SControl 300)
Jan 8 13:35:49 raidserver kernel: ata1.00: ATA-6, max UDMA/133,
156301488 sectors: LBA48 NCQ (depth 31/32)
Jan 8 13:35:49 raidserver kernel: ata1.00: ata1: dev 0 multi count 16
Jan 8 13:35:49 raidserver kernel: ata1.00: configured for UDMA/133
Jan 8 13:35:49 raidserver kernel: scsi2 : ahci
Jan 8 13:35:49 raidserver kernel: ata2: SATA link up 1.5 Gbps (SStatus
113 SControl 300)
Jan 8 13:35:49 raidserver kernel: ata2.00: ATA-6, max UDMA/133,
156301488 sectors: LBA48 NCQ (depth 31/32)
Jan 8 13:35:49 raidserver kernel: ata2.00: ata2: dev 0 multi count 16
Jan 8 13:35:49 raidserver kernel: ata2.00: configured for UDMA/133
Jan 8 13:35:49 raidserver kernel: scsi3 : ahci
Jan 8 13:35:49 raidserver kernel: ata3: SATA link down (SStatus 0
SControl 300)
Jan 8 13:35:49 raidserver kernel: scsi4 : ahci
Jan 8 13:35:49 raidserver kernel: ata4: SATA link down (SStatus 0
SControl 300)
Jan 8 13:35:49 raidserver kernel: Vendor: ATA Model:
ST380817AS Rev: 3.42
Jan 8 13:35:49 raidserver kernel: Type:
Direct-Access ANSI SCSI revision: 05
Jan 8 13:35:49 raidserver kernel: Vendor: ATA Model:
ST380817AS Rev: 3.42
Jan 8 13:35:49 raidserver kernel: Type:
Direct-Access ANSI SCSI revision: 05
Jan 8 13:35:49 raidserver kernel: sda : very big device. try to use
READ CAPACITY(16).
Jan 8 13:35:49 raidserver kernel: SCSI device sda: 5859164160 512-byte
hdwr sectors (2999892 MB)
lspci:
0000:00:00.0 Host bridge: Intel Corp. Server Memory Controller Hub (rev 05)
0000:00:01.0 PCI bridge: Intel Corp. Server Memory Controller Hub PCI
Express Port (rev 05)
0000:00:02.0 VGA compatible controller: Intel Corp. Graphics Controller
(rev 05)
0000:00:1c.0 PCI bridge: Intel Corp. 82801FB/FBM/FR/FW/FRW (ICH6 Family)
PCI Express Port 1 (rev 03)
0000:00:1c.1 PCI bridge: Intel Corp. 82801FB/FBM/FR/FW/FRW (ICH6 Family)
PCI Express Port 2 (rev 03)
0000:00:1c.2 PCI bridge: Intel Corp. 82801FB/FBM/FR/FW/FRW (ICH6 Family)
PCI Express Port 3 (rev 03)
0000:00:1c.3 PCI bridge: Intel Corp. 82801FB/FBM/FR/FW/FRW (ICH6 Family)
PCI Express Port 4 (rev 03)
0000:00:1d.0 USB Controller: Intel Corp. 82801FB/FBM/FR/FW/FRW (ICH6
Family) USB UHCI #1 (rev 03)
0000:00:1d.1 USB Controller: Intel Corp. 82801FB/FBM/FR/FW/FRW (ICH6
Family) USB UHCI #2 (rev 03)
0000:00:1d.2 USB Controller: Intel Corp. 82801FB/FBM/FR/FW/FRW (ICH6
Family) USB UHCI #3 (rev 03)
0000:00:1d.3 USB Controller: Intel Corp. 82801FB/FBM/FR/FW/FRW (ICH6
Family) USB UHCI #4 (rev 03)
0000:00:1d.7 USB Controller: Intel Corp. 82801FB/FBM/FR/FW/FRW (ICH6
Family) USB2 EHCI Controller (rev 03)
0000:00:1e.0 PCI bridge: Intel Corp. 82801 PCI Bridge (rev d3)
0000:00:1f.0 ISA bridge: Intel Corp. 82801FB/FR (ICH6/ICH6R) LPC
Interface Bridge (rev 03)
0000:00:1f.1 IDE interface: Intel Corp. 82801FB/FBM/FR/FW/FRW (ICH6
Family) IDE Controller (rev 03)
0000:00:1f.2 0106: Intel Corp. 82801FR/FRW (ICH6R/ICH6RW) SATA
Controller (rev 03)
0000:00:1f.3 SMBus: Intel Corp. 82801FB/FBM/FR/FW/FRW (ICH6 Family)
SMBus Controller (rev 03)
0000:01:00.0 PCI bridge: Intel Corp. PCI Bridge Hub (rev 09)
0000:01:00.1 PIC: Intel Corp. PCI Bridge Hub I/OxAPIC Interrupt
Controller A (rev 09)
0000:02:03.0 RAID bus controller: 3ware Inc 3ware ATA-RAID
0000:04:00.0 Ethernet controller: Broadcom Corporation NetXtreme BCM5721
Gigabit Ethernet PCI Express (rev 01)
0000:05:00.0 Ethernet controller: Broadcom Corporation NetXtreme BCM5721
Gigabit Ethernet PCI Express (rev 01)
0000:07:01.0 FireWire (IEEE 1394): NEC Corporation IEEE 1394 Host
Controller (rev 01)
Reply to: