Bug 10858 - problem with sata_nv and SATA<>PATA bridge (2.6.18/2.6.24)
Summary: problem with sata_nv and SATA<>PATA bridge (2.6.18/2.6.24)
Status: CLOSED INSUFFICIENT_DATA
Alias: None
Product: IO/Storage
Classification: Unclassified
Component: Serial ATA (show other bugs)
Hardware: All Linux
: P1 normal
Assignee: Jeff Garzik
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2008-06-05 06:05 UTC by Mark Paulus
Modified: 2010-01-19 17:57 UTC (History)
3 users (show)

See Also:
Kernel Version: 2.6.18 & 2.6.24
Subsystem:
Regression: No
Bisected commit-id:


Attachments

Description Mark Paulus 2008-06-05 06:05:38 UTC
Latest working kernel version: none
Earliest failing kernel version: all
Distribution: Debian etch / Debian Etch and a Half
Hardware Environment: MSI Neo4-F  / AMD 64 4000+ / 2GB RAM / 320 GB IDE on NV sata port using IDE<>SATA bridge
Software Environment: Debian Etch with 2.6.18 kernel and Debian Etchandahalf 2.6.24 kernel
Problem Description:
Hello,

I have a motherboard with Nvidia sata and
SIL3512 sata controllers.  I also have a brand spanking new 320GB IDE drive that I am
connecting to the mobo via a SATA<>PATA
bridge.  This combo is giving me fits with
the NV controller, but seems to work fine
with the SIL sata controller.

I had this problem on 2.6.22 on the Sil side,
but Tejun found the issue and created a patch.

The action I was trying to accomplish was:
mkfs.jfs -c /dev/sdb1.

Here are the messages from my messages
file:
May 27 16:42:45 storage1 kernel: ata1: SATA max UDMA/133 cmd 0x9F0 ctl 0xBF2 bmdma 0xDC00 irq 177
May 27 16:42:45 storage1 kernel: ata2: SATA max UDMA/133 cmd 0x970 ctl 0xB72 bmdma 0xDC08 irq 177
May 27 16:42:45 storage1 kernel: scsi0 : sata_nv
May 27 16:42:45 storage1 kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:42:45 storage1 kernel: ata1.00: ATA-7, max UDMA/133, 321672960 sectors: LBA48 NCQ (depth 0/32)
May 27 16:42:45 storage1 kernel: ata1.00: ata1: dev 0 multi count 16
May 27 16:42:45 storage1 kernel: ata1.00: configured for UDMA/133
May 27 16:42:45 storage1 kernel: scsi1 : sata_nv
May 27 16:42:45 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:42:45 storage1 kernel: ata2.00: ATA-8, max UDMA/100, 625142448 sectors: LBA48
May 27 16:42:45 storage1 kernel: ata2.00: ata2: dev 0 multi count 16
May 27 16:42:45 storage1 kernel: ata2.00: configured for UDMA/100
May 27 16:42:45 storage1 kernel:   Vendor: ATA       Model: HDT722516DLA380   Rev: V43O
May 27 16:42:45 storage1 kernel:   Type:   Direct-Access                      ANSI SCSI revision: 05
May 27 16:42:45 storage1 kernel:   Vendor: ATA       Model: WDC WD3200AAJB-0  Rev: 00.0
May 27 16:42:45 storage1 kernel:   Type:   Direct-Access                      ANSI SCSI revision: 05
May 27 16:42:45 storage1 kernel: ACPI: PCI Interrupt Link [APC4] enabled at IRQ 19
May 27 16:42:45 storage1 kernel: GSI 19 sharing vector 0xC9 and IRQ 19
May 27 16:42:45 storage1 kernel: ACPI: PCI Interrupt 0000:02:0b.0[A] -> Link [APC4] -> GSI 19 (level, low) -> IRQ 201
May 27 16:42:45 storage1 kernel: skge 1.6 addr 0xf5000000 irq 201 chip Yukon-Lite rev 9
May 27 16:42:45 storage1 kernel: skge eth0: addr 00:0f:ea:85:73:1c
May 27 16:42:45 storage1 kernel: ACPI: PCI Interrupt Link [APC2] enabled at IRQ 17
May 27 16:42:45 storage1 kernel: GSI 20 sharing vector 0xD1 and IRQ 20
May 27 16:42:45 storage1 kernel: ACPI: PCI Interrupt 0000:02:0d.0[A] -> Link [APC2] -> GSI 17 (level, low) -> IRQ 209
May 27 16:42:45 storage1 kernel: ata3: SATA max UDMA/100 cmd 0xFFFFC2000001A080 ctl 0xFFFFC2000001A08A bmdma 0xFFFFC2000001A000 irq 209
May 27 16:42:45 storage1 kernel: ata4: SATA max UDMA/100 cmd 0xFFFFC2000001A0C0 ctl 0xFFFFC2000001A0CA bmdma 0xFFFFC2000001A008 irq 209
May 27 16:42:45 storage1 kernel: scsi2 : sata_sil
May 27 16:42:45 storage1 kernel: Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
May 27 16:42:45 storage1 kernel: ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
May 27 16:42:45 storage1 kernel: ata3: SATA link down (SStatus 0 SControl 310) May 27 16:42:45 storage1 kernel: scsi3 : sata_sil
May 27 16:42:45 storage1 kernel: ata4: SATA link down (SStatus 0 SControl 310)
May 27 16:42:45 storage1 kernel: NFORCE3-250: IDE controller at PCI slot 0000:00:08.0
May 27 16:42:45 storage1 kernel: NFORCE3-250: chipset revision 162
May 27 16:42:45 storage1 kernel: NFORCE3-250: not 100%% native mode: will probe irqs later
May 27 16:42:45 storage1 kernel: NFORCE3-250: 0000:00:08.0 (rev a2) UDMA133 controller
May 27 16:42:45 storage1 kernel:     ide0: BM-DMA at 0xf000-0xf007, BIOS settings: hda:DMA, hdb:DMA
May 27 16:42:45 storage1 kernel:     ide1: BM-DMA at 0xf008-0xf00f, BIOS settings: hdc:DMA, hdd:DMA
May 27 16:42:45 storage1 kernel: SCSI device sda: 321672960 512-byte hdwr sectors (164697 MB)
May 27 16:42:45 storage1 kernel: sda: Write Protect is off
May 27 16:42:45 storage1 kernel: SCSI device sda: drive cache: write back
May 27 16:42:45 storage1 kernel: SCSI device sda: 321672960 512-byte hdwr sectors (164697 MB)
May 27 16:42:45 storage1 kernel: sda: Write Protect is off
May 27 16:42:45 storage1 kernel: SCSI device sda: drive cache: write back
May 27 16:42:45 storage1 kernel:  sda: sda1 sda2 < sda5 > sda3
May 27 16:42:45 storage1 kernel: sd 0:0:0:0: Attached scsi disk sda
May 27 16:42:45 storage1 kernel: SCSI device sdb: 625142448 512-byte hdwr sectors (320073 MB)
May 27 16:42:45 storage1 kernel: sdb: Write Protect is off
May 27 16:42:45 storage1 kernel: SCSI device sdb: drive cache: write back
May 27 16:42:45 storage1 kernel: SCSI device sdb: 625142448 512-byte hdwr sectors (320073 MB)
May 27 16:42:45 storage1 kernel: sdb: Write Protect is off
May 27 16:42:45 storage1 kernel: SCSI device sdb: drive cache: write back
May 27 16:42:45 storage1 kernel:  sdb: unknown partition table
May 27 16:42:45 storage1 kernel: sd 1:0:0:0: Attached scsi disk sdb

May 27 16:42:51 storage1 kernel: NFSD: starting 90-second grace period
May 27 16:47:50 storage1 kernel: ata2: EH complete
May 27 16:47:50 storage1 kernel: SCSI device sdb: 625142448 512-byte hdwr sectors (320073 MB)
May 27 16:47:50 storage1 kernel: sdb: Write Protect is off
May 27 16:47:50 storage1 kernel: SCSI device sdb: drive cache: write back
May 27 16:47:53 storage1 kernel: SCSI device sdb: 625142448 512-byte hdwr sectors (320073 MB)
May 27 16:47:53 storage1 kernel: sdb: Write Protect is off
May 27 16:47:53 storage1 kernel: SCSI device sdb: drive cache: write back
May 27 16:47:53 storage1 kernel:  sdb: sdb1
May 27 16:49:43 storage1 kernel: ata2: port is slow to respond, please be patient
May 27 16:50:06 storage1 kernel: ata2: soft resetting port
May 27 16:50:06 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:50:06 storage1 kernel: ata2.00: configured for UDMA/100
May 27 16:50:06 storage1 kernel: ata2: EH complete
May 27 16:50:43 storage1 kernel: ata2: port is slow to respond, please be patient
May 27 16:51:06 storage1 kernel: ata2: soft resetting port
May 27 16:51:06 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:51:06 storage1 kernel: ata2.00: configured for UDMA/100
May 27 16:51:06 storage1 kernel: ata2: EH complete
May 27 16:51:44 storage1 kernel: ata2: port is slow to respond, please be patient
May 27 16:52:07 storage1 kernel: ata2: soft resetting port
May 27 16:52:07 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:52:07 storage1 kernel: ata2.00: configured for UDMA/100
May 27 16:52:07 storage1 kernel: ata2: EH complete
May 27 16:52:37 storage1 kernel: ata2.00: limiting speed to UDMA/66
May 27 16:52:44 storage1 kernel: ata2: port is slow to respond, please be patient
May 27 16:53:07 storage1 kernel: ata2: soft resetting port
May 27 16:53:07 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:53:07 storage1 kernel: ata2.00: configured for UDMA/66
May 27 16:53:07 storage1 kernel: ata2: EH complete
May 27 16:53:37 storage1 kernel: ata2.00: limiting speed to UDMA/44
May 27 16:53:45 storage1 kernel: ata2: port is slow to respond, please be patient
May 27 16:54:08 storage1 kernel: ata2: soft resetting port
May 27 16:54:08 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:54:08 storage1 kernel: ata2.00: configured for UDMA/44
May 27 16:54:08 storage1 kernel: ata2: EH complete
May 27 16:54:38 storage1 kernel: ata2.00: limiting speed to UDMA/33
May 27 16:54:45 storage1 kernel: ata2: port is slow to respond, please be patient
May 27 16:55:08 storage1 kernel: ata2: soft resetting port
May 27 16:55:08 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:55:08 storage1 kernel: ata2.00: configured for UDMA/33
May 27 16:55:08 storage1 kernel: ata2: EH complete
May 27 16:55:38 storage1 kernel: ata2.00: limiting speed to UDMA/25
May 27 16:55:46 storage1 kernel: ata2: port is slow to respond, please be patient
May 27 16:56:02 storage1 shutdown[3403]: shutting down for system reboot
May 27 16:56:09 storage1 kernel: ata2: soft resetting port
May 27 16:56:09 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:56:09 storage1 kernel: ata2.00: configured for UDMA/25
May 27 16:56:09 storage1 kernel: ata2: EH complete
May 27 16:56:09 storage1 kernel: sd 1:0:0:0: SCSI error: return code = 0x08000002
May 27 16:56:09 storage1 kernel: sdb: Current: sense key: Aborted Command
May 27 16:56:09 storage1 kernel:     Additional sense: No additional sense information
May 27 16:56:09 storage1 kernel: end_request: I/O error, dev sdb, sector 76607
May 27 16:56:09 storage1 kernel: lost page write due to I/O error on sdb1
May 27 16:56:09 storage1 last message repeated 9 times
May 27 16:56:09 storage1 kernel: ata2: EH complete
May 27 16:56:09 storage1 kernel: SCSI device sdb: 625142448 512-byte hdwr sectors (320073 MB)
May 27 16:56:39 storage1 kernel: ata2.00: limiting speed to UDMA/16
May 27 16:56:46 storage1 kernel: ata2: port is slow to respond, please be patient
May 27 16:57:09 storage1 kernel: ata2: soft resetting port
May 27 16:57:09 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:57:09 storage1 kernel: ata2.00: configured for UDMA/16
May 27 16:57:09 storage1 kernel: ata2: EH complete
May 27 16:57:39 storage1 kernel: ata2.00: limiting speed to PIO4
May 27 16:57:47 storage1 kernel: ata2: port is slow to respond, please be patient
May 27 16:58:10 storage1 kernel: ata2: soft resetting port
May 27 16:58:10 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:58:10 storage1 kernel: ata2.00: configured for PIO4
May 27 16:58:10 storage1 kernel: ata2: EH complete
May 27 16:58:10 storage1 kernel: ata2.00: limiting speed to PIO3
May 27 16:58:10 storage1 kernel: ata2: soft resetting port
May 27 16:58:10 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:58:10 storage1 kernel: ata2.00: configured for PIO3
May 27 16:58:10 storage1 kernel: ata2: EH complete
May 27 16:58:10 storage1 kernel: ata2.00: limiting speed to PIO2
May 27 16:58:11 storage1 kernel: ata2: soft resetting port
May 27 16:58:11 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:58:11 storage1 kernel: ata2.00: configured for PIO2
May 27 16:58:11 storage1 kernel: ata2: EH complete
May 27 16:58:11 storage1 kernel: ata2.00: limiting speed to PIO1
May 27 16:58:11 storage1 kernel: ata2: soft resetting port
May 27 16:58:11 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:58:11 storage1 kernel: ata2.00: configured for PIO1
May 27 16:58:11 storage1 kernel: ata2: EH complete
May 27 16:58:11 storage1 kernel: ata2.00: limiting speed to PIO0
May 27 16:58:11 storage1 kernel: ata2: soft resetting port
May 27 16:58:12 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:58:12 storage1 kernel: ata2.00: configured for PIO0
May 27 16:58:12 storage1 kernel: sd 1:0:0:0: SCSI error: return code = 0x08000002
May 27 16:58:12 storage1 kernel: sdb: Current: sense key: Aborted Command
May 27 16:58:12 storage1 kernel:     Additional sense: No additional sense information
May 27 16:58:12 storage1 kernel: end_request: I/O error, dev sdb, sector 625071815
May 27 16:58:12 storage1 kernel: printk: 38 messages suppressed.
May 27 16:58:12 storage1 kernel: lost page write due to I/O error on sdb1
May 27 16:58:12 storage1 last message repeated 9 times
May 27 16:58:12 storage1 kernel: ata2: EH complete
May 27 16:58:12 storage1 kernel: sdb: Write Protect is off
May 27 16:58:12 storage1 kernel: ata2: soft resetting port
May 27 16:58:12 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:58:12 storage1 kernel: ata2.00: configured for PIO0
May 27 16:58:12 storage1 kernel: ata2: EH complete
May 27 16:58:12 storage1 kernel: ata2: soft resetting port
May 27 16:58:13 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:58:13 storage1 kernel: ata2.00: configured for PIO0
May 27 16:58:13 storage1 kernel: ata2: EH complete
May 27 16:58:13 storage1 kernel: ata2: soft resetting port
May 27 16:58:13 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:58:13 storage1 kernel: ata2.00: configured for PIO0
May 27 16:58:13 storage1 kernel: ata2: EH complete
May 27 16:58:13 storage1 kernel: ata2: soft resetting port
May 27 16:58:14 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 27 16:58:14 storage1 kernel: ata2.00: configured for PIO0
May 27 16:58:14 storage1 kernel: ata2: EH complete
May 27 16:58:14 storage1 kernel: ata2: soft resetting port
May 27 16:58:14 storage1 kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)

and so on
and
so
on
ad nauseum


Steps to reproduce:
See above
Comment 1 Robert Hancock 2008-06-05 16:06:31 UTC
Are you sure that this log contains all kernel messages? It would be useful if you could post the output of dmesg itself rather than the output of the kernel logger.
Comment 2 Alan 2010-01-19 17:57:46 UTC
Closing stale idle bugs

Note You need to log in before you can comment on or make changes to this bug.