Bug 8923 (BootDelay-QC_timeout) - When booting the kernel without a second HDD attached to SATA port, boot hangs
Summary: When booting the kernel without a second HDD attached to SATA port, boot hangs
Status: CLOSED CODE_FIX
Alias: BootDelay-QC_timeout
Product: IO/Storage
Classification: Unclassified
Component: Serial ATA (show other bugs)
Hardware: All Linux
: P1 normal
Assignee: Tejun Heo
URL:
Keywords:
: 9142 (view as bug list)
Depends on:
Blocks:
 
Reported: 2007-08-22 09:31 UTC by Bjoern Olausson
Modified: 2008-04-23 12:32 UTC (History)
1 user (show)

See Also:
Kernel Version: 2.6.23
Tree: Mainline
Regression: ---


Attachments
asus-p5w-dh-quirk.patch (3.25 KB, patch)
2007-08-22 20:55 UTC, Tejun Heo
Details | Diff
asus-p5w-dh-quirk-take-2.patch (4.72 KB, patch)
2007-08-26 22:41 UTC, Tejun Heo
Details | Diff
bootlog (29.52 KB, text/plain)
2007-08-27 02:52 UTC, Bjoern Olausson
Details
bootlog1 (29.42 KB, application/octet-stream)
2007-08-27 02:57 UTC, Bjoern Olausson
Details
bootlog_with_disk+hotplug (42.99 KB, text/plain)
2007-08-27 02:59 UTC, Bjoern Olausson
Details
bootlog-2.6.23-rc3-boot_with_disk+hotplug (39.76 KB, text/plain)
2007-08-27 04:48 UTC, Bjoern Olausson
Details
bootlog-2.6.23-rc3-boot_without_disk+hotplug (45.02 KB, text/plain)
2007-08-27 04:49 UTC, Bjoern Olausson
Details
bootlog-2.6.22.5-boot_with_disk+hotplug (41.47 KB, text/plain)
2007-08-27 04:50 UTC, Bjoern Olausson
Details
bootlog-2.6.22.5-boot_without_disk+hotplug (30.52 KB, text/plain)
2007-08-27 04:51 UTC, Bjoern Olausson
Details
bootlog (62.42 KB, application/octet-stream)
2007-10-10 06:00 UTC, Bjoern Olausson
Details
p5wdh-workaround-2.6.23.1.patch (8.17 KB, patch)
2007-10-24 03:14 UTC, Tejun Heo
Details | Diff
dmesg using p-ata (22.77 KB, text/plain)
2007-10-25 03:32 UTC, Fabian Zeindl
Details
p5w-DH-delux_dmesg.log (47.43 KB, application/octet-stream)
2008-04-22 14:26 UTC, Bjoern Olausson
Details
lshw.log (5.34 KB, application/octet-stream)
2008-04-22 14:26 UTC, Bjoern Olausson
Details

Description Bjoern Olausson 2007-08-22 09:31:34 UTC
Most recent kernel where this bug did not occur:
-/-

Distribution:
Gentoo

Hardware Environment:
00:00.0 Host bridge: Intel Corporation 975X Express Memory Controller Hub (rev c0)
00:01.0 PCI bridge: Intel Corporation 975X Express PCI Express Root Port (rev c0)
00:1b.0 Audio device: Intel Corporation 82801G (ICH7 Family) High Definition Audio Controller (rev 01)
00:1c.0 PCI bridge: Intel Corporation 82801G (ICH7 Family) PCI Express Port 1 (rev 01)
00:1c.3 PCI bridge: Intel Corporation 82801G (ICH7 Family) PCI Express Port 4 (rev 01)
00:1d.0 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI #1 (rev 01)
00:1d.1 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI #2 (rev 01)
00:1d.2 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI #3 (rev 01)
00:1d.3 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI #4 (rev 01)
00:1d.7 USB Controller: Intel Corporation 82801G (ICH7 Family) USB2 EHCI Controller (rev 01)
00:1e.0 PCI bridge: Intel Corporation 82801 PCI Bridge (rev e1)
00:1f.0 ISA bridge: Intel Corporation 82801GB/GR (ICH7 Family) LPC Interface Bridge (rev 01)
00:1f.1 IDE interface: Intel Corporation 82801G (ICH7 Family) IDE Controller (rev 01)
00:1f.2 SATA controller: Intel Corporation 82801GR/GH (ICH7 Family) Serial ATA Storage Controller AHCI (rev 01)
00:1f.3 SMBus: Intel Corporation 82801G (ICH7 Family) SMBus Controller (rev 01)
01:00.0 Multimedia audio controller: Creative Labs Unknown device 0005
01:01.0 Multimedia video controller: Brooktree Corporation Bt878 Video Capture (rev 11)
01:01.1 Multimedia controller: Brooktree Corporation Bt878 Audio Capture (rev 11)
01:02.0 Ethernet controller: Atheros Communications, Inc. AR5212 802.11abg NIC (rev 01)
01:03.0 FireWire (IEEE 1394): Texas Instruments TSB43AB22/A IEEE-1394a-2000 Controller (PHY/Link)
02:00.0 Ethernet controller: Marvell Technology Group Ltd. 88E8053 PCI-E Gigabit Ethernet Controller (rev 20)
04:00.0 VGA compatible controller: nVidia Corporation G70 [GeForce 7600 GT] (rev a1)

H/W path                   Device       Class       Description
===============================================================
                                        system      P5W DH Deluxe
/0                                      bus         P5W DH Deluxe
/0/0                                    memory      64KB BIOS
/0/4                                    processor   Intel(R) Core(TM)2 CPU          6600  @ 2.4
/0/4/5                                  memory      32KB L1 cache
/0/4/6                                  memory      4MB L2 cache
/0/4/7                                  memory      L3 cache
/0/3c                                   memory      2GB System Memory
/0/3c/0                                 memory      1GB DIMM SDRAM Synchronous
/0/3c/1                                 memory      DIMM [empty]
/0/3c/2                                 memory      1GB DIMM SDRAM Synchronous
/0/3c/3                                 memory      DIMM [empty]
/0/100                                  bridge      975X Express Memory Controller Hub
/0/100/1                                bridge      975X Express PCI Express Root Port
/0/100/1/0                              display     G70 [GeForce 7600 GT]
/0/100/1b                               multimedia  82801G (ICH7 Family) High Definition Audio
/0/100/1c                               bridge      82801G (ICH7 Family) PCI Express Port 1
/0/100/1c.3                             bridge      82801G (ICH7 Family) PCI Express Port 4
/0/100/1c.3/0              eth0         network     88E8053 PCI-E Gigabit Ethernet Controller
/0/100/1d                               bus         82801G (ICH7 Family) USB UHCI #1
/0/100/1d/1                usb2         bus         UHCI Host Controller
/0/100/1d/1/2                           generic     USB RECEIVER
/0/100/1d.1                             bus         82801G (ICH7 Family) USB UHCI #2
/0/100/1d.1/1              usb3         bus         UHCI Host Controller
/0/100/1d.1/1/1                         generic     ASUS DH Remote
/0/100/1d.1/1/2                         bus         Logitech BT Mini-Receiver
/0/100/1d.1/1/2/2                       generic     Logitech BT Mini-Receiver
/0/100/1d.1/1/2/3                       generic     Logitech BT Mini-Receiver
/0/100/1d.2                             bus         82801G (ICH7 Family) USB UHCI #3
/0/100/1d.2/1              usb4         bus         UHCI Host Controller
/0/100/1d.2/1/1                         generic     Bluetooth Device
/0/100/1d.3                             bus         82801G (ICH7 Family) USB UHCI #4
/0/100/1d.3/1              usb5         bus         UHCI Host Controller
/0/100/1d.7                             bus         82801G (ICH7 Family) USB2 EHCI Controller
/0/100/1d.7/1              usb1         bus         EHCI Host Controller
/0/100/1d.7/1/1                         bus         USB2.0 Hub
/0/100/1d.7/1/1/1                       generic     USB Receiver
/0/100/1d.7/1/1/2                       generic     iP5200
/0/100/1d.7/1/1/3                       generic     Astra 2200/2200SU
/0/100/1d.7/1/7                         bus         USB2.0 Hub
/0/100/1d.7/1/7/1                       bus         USB2.0 Hub
/0/100/1d.7/1/7/2          scsi6        generic     USB Storage
/0/100/1d.7/1/7/2/0.0.0    /dev/sdd     disk        STORAGE DEVICE
/0/100/1d.7/1/7/2/0.0.0/0  /dev/sdd     disk
/0/100/1d.7/1/7/2/0.0.1    /dev/sde     disk        STORAGE DEVICE
/0/100/1d.7/1/7/2/0.0.1/0  /dev/sde     disk
/0/100/1d.7/1/7/2/0.0.2    /dev/sdf     disk        STORAGE DEVICE
/0/100/1d.7/1/7/2/0.0.2/0  /dev/sdf     disk
/0/100/1d.7/1/7/2/0.0.3    /dev/sdg     disk        STORAGE DEVICE
/0/100/1d.7/1/7/2/0.0.3/0  /dev/sdg     disk
/0/100/1d.7/1/7/3                       generic     RTL8187_Wireless
/0/100/1e                               bridge      82801 PCI Bridge
/0/100/1e/0                             multimedia  SB X-Fi
/0/100/1e/1                             multimedia  Bt878 Video Capture
/0/100/1e/1.1                           multimedia  Bt878 Audio Capture
/0/100/1e/2                wifi0        network     AR5212 802.11abg NIC
/0/100/1e/3                             bus         TSB43AB22/A IEEE-1394a-2000 Controller (PHY
/0/100/1f                               bridge      82801GB/GR (ICH7 Family) LPC Interface Brid
/0/100/1f.1                scsi4        storage     82801G (ICH7 Family) IDE Controller
/0/100/1f.1/0.0.0          /dev/cdrom   disk        DVDR   PX-760A
/0/100/1f.1/0.1.0          /dev/cdrom1  disk        DVD-ROM PX-130A
/0/100/1f.2                scsi0        storage     82801GR/GH (ICH7 Family) Serial ATA Storage
/0/100/1f.2/0              /dev/sda     disk        69GB WDC WD740ADFD-00
/0/100/1f.2/0/1            /dev/sda1    volume      47MB Linux filesystem partition
/0/100/1f.2/0/2            /dev/sda2    volume      956MB Linux swap / Solaris partition
/0/100/1f.2/0/3            /dev/sda3    volume      68GB Linux filesystem partition
/0/100/1f.2/1              /dev/sdh     disk        149GB External Disk 0
/0/100/1f.2/1/1            /dev/sdh1    volume      149GB Linux filesystem partition
/0/100/1f.2/0.0.0          /dev/sdc     disk        69GB WDC WD740ADFD-00
/0/100/1f.2/0.0.0/1        /dev/sdc1    volume      69GB HPFS/NTFS partition
/0/100/1f.3                             bus         82801G (ICH7 Family) SMBus Controller
/1                         irlan0       network     Ethernet interface


Software Environment:
Portage 2.1.2.12 (default-linux/amd64/2007.0/desktop, gcc-4.1.2, glibc-2.5-r4, 2.6.22.4 x86_64)
=================================================================
System uname: 2.6.22.4 x86_64 Intel(R) Core(TM)2 CPU 6600 @ 2.40GHz
Gentoo Base System release 1.12.9
Timestamp of tree: Wed, 22 Aug 2007 12:50:01 +0000
distcc 2.18.3 x86_64-pc-linux-gnu (protocols 1 and 2) (default port 3632) [disabled]
ccache version 2.4 [enabled]
dev-java/java-config: 1.3.7, 2.0.33-r1
dev-lang/python:     2.4.4-r4
dev-python/pycrypto: 2.0.1-r6
dev-util/ccache:     2.4-r7
sys-apps/sandbox:    1.2.17
sys-devel/autoconf:  2.13, 2.61
sys-devel/automake:  1.4_p6, 1.5, 1.6.3, 1.7.9-r1, 1.8.5-r3, 1.9.6-r2, 1.10
sys-devel/binutils:  2.17
sys-devel/gcc-config: 1.3.16
sys-devel/libtool:   1.5.24
virtual/os-headers:  2.6.21
ACCEPT_KEYWORDS="amd64"
AUTOCLEAN="yes"
CBUILD="x86_64-pc-linux-gnu"
CFLAGS="-march=nocona -O2 -pipe -fomit-frame-pointer"
CHOST="x86_64-pc-linux-gnu"

Problem Description:
I had this Problem a long time, but worked around it with an other HDD attached to the 3rd SATA port of the mainboard. (Asus P5W-DH Delux).

Removing this device results in a long hang during boot:
scsi0 : ahci
scsi1 : ahci
scsi2 : ahci
scsi3 : ahci
ata1: SATA max UDMA/133 cmd 0xffffc20001738900 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1276
ata2: SATA max UDMA/133 cmd 0xffffc20001738980 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1276
ata3: SATA max UDMA/133 cmd 0xffffc20001738a00 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1276
ata4: SATA max UDMA/133 cmd 0xffffc20001738a80 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1276
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata1.00: ATA-7: WDC WD740ADFD-00NLR1, 20.07P20, max UDMA/133
ata1.00: 145226112 sectors, multi 16: LBA48 NCQ (not used)
ata1.00: configured for UDMA/133
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: qc timeout (cmd 0xec)
ata2.00: failed to IDENTIFY (I/O error, err_mask=0x5)
ata2: port is slow to respond, please be patient (Status 0x80)
ata2: COMRESET failed (errno=-16)
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: ATA-6: Config  Disk, RGL10364, max UDMA/133
ata2.00: 640 sectors, multi 1: LBA
ata2.00: 312581808 sectors, multi 1: LBA48 


with attached drive it looks like this (just diffed dmsg with and without attached hdd)

scsi0 : ahci
scsi1 : ahci
scsi2 : ahci
scsi3 : ahci
ata1: SATA max UDMA/133 cmd 0xffffc20001738900 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1276
ata2: SATA max UDMA/133 cmd 0xffffc20001738980 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1276
ata3: SATA max UDMA/133 cmd 0xffffc20001738a00 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1276
ata4: SATA max UDMA/133 cmd 0xffffc20001738a80 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1276
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata1.00: ATA-7: WDC WD740ADFD-00NLR1, 20.07P20, max UDMA/133
ata1.00: 145226112 sectors, multi 16: LBA48 NCQ (not used)
ata1.00: configured for UDMA/133
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: ATA-6: External Disk 0, RGL10364, max UDMA/133
ata2.00: 312581808 sectors, multi 1: LBA48 
ata2.00: configured for UDMA/133

Steps to reproduce:
Boot without SATA Drive on 3rd SATA connector.

complete dmsg with attached disk on port 3 can be found here:
http://paste.olausson.de/1d9d9f9194.html

complete dmsg without disk on port 3 can be found here:
http://paste.olausson.de/2db6f76723.html

Thanks and regards
Bjoern Olausson
Comment 1 Tejun Heo 2007-08-22 20:55:10 UTC
Created attachment 12495 [details]
asus-p5w-dh-quirk.patch

Please test the attached patch and report result.  The patch may be applied on top of 2.6.23-rc3 or 2.6.22.5.  Thanks.
Comment 2 Bjoern Olausson 2007-08-23 02:54:17 UTC
Applied the patch but still takes it time without a attached disk

Complete dmsg without disk at: http://paste.olausson.de/ade5e44a2d.html
Complete dmsg with disk at: http://paste.olausson.de/3512881a98.html

Now it takes even with disk a bit longer to pass the AHCI section but still faster than with no disk attached.

ahci 0000:00:1f.2: version 2.2
ACPI: PCI Interrupt 0000:00:1f.2[B] -> GSI 23 (level, low) -> IRQ 23
ahci 0000:00:1f.2: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf impl SATA mode
ahci 0000:00:1f.2: flags: 64bit ncq led clo pio slum part 
PCI: Setting latency timer of device 0000:00:1f.2 to 64
ahci 0000:00:1f.2: enabling ASUS on-board PMP workaround
scsi0 : ahci
scsi1 : ahci
scsi2 : ahci
scsi3 : ahci
ata1: SATA max UDMA/133 cmd 0xffffc20001738900 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1276
ata2: SATA max UDMA/133 cmd 0xffffc20001738980 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1276
ata3: SATA max UDMA/133 cmd 0xffffc20001738a00 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1276
ata4: SATA max UDMA/133 cmd 0xffffc20001738a80 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1276
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata1.00: ATA-7: WDC WD740ADFD-00NLR1, 20.07P20, max UDMA/133
ata1.00: 145226112 sectors, multi 16: LBA48 NCQ (not used)
ata1.00: configured for UDMA/133
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: ATA-6: Config  Disk, RGL10364, max UDMA/133
ata2.00: 640 sectors, multi 1: LBA 
ata2.00: failed to IDENTIFY (I/O error, err_mask=0x100)
ata2.00: revalidation failed (errno=-5)
ata2: failed to recover some devices, retrying in 5 secs
ata2: SATA link down (SStatus 0 SControl 300)
ata2.00: limiting speed to UDMA/133:PIO3
ata2: failed to recover some devices, retrying in 5 secs
ata2: port is slow to respond, please be patient (Status 0x80)
ata2: COMRESET failed (errno=-16)
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: serial number mismatch \'0A19EAF__________0_A\' != \'0000000__________0_A\'
ata2.00: revalidation failed (errno=-19)
ata2.00: disabled
ata2: EH pending after completion, repeating EH (cnt=4)
ata2: exception Emask 0x10 SAct 0x0 SErr 0x4010000 action 0x3
ata2: (irq_stat 0x00000040, connection status changed)
ata2: hard resetting port
ata2: port is slow to respond, please be patient (Status 0x80)
ata2: COMRESET failed (errno=-16)
ata2: hard resetting port
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: ATA-6: Config  Disk, RGL10364, max UDMA/133
ata2.00: 640 sectors, multi 1: LBA 
ata2.00: configured for UDMA/133
ata2: EH complete
ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata3.00: ATA-7: WDC WD740ADFD-00NLR1, 20.07P20, max UDMA/133
ata3.00: 145226112 sectors, multi 16: LBA48 NCQ (not used)
ata3.00: configured for UDMA/133
ata4: SATA link down (SStatus 0 SControl 300)

regards
Bjoern 
Comment 3 Bjoern Olausson 2007-08-23 10:32:28 UTC
Now I realised some problems with SATA hotplug after adding your patch:

http://paste.olausson.de/2ef80e1657.html

I'll try recompiling the Kernel without your patch to see if it is really your patch.

regards
blubbi
Comment 4 Bjoern Olausson 2007-08-23 11:50:42 UTC
Wired... to test the SATA hotplug i reverted to 2.6.22.4. I got the drive two days ago and everything worked fine.

Last day I tryed your patch and now my hotplugging is no longer working... not with 2.6.22.4, 2.6.22.5 without patch and 2.6.22.5 with patch.

Even if tur of the PC, attache the drive via SATA and boot, it is not accessible. The same error as below in messages.

cfdisk gives me the following:

cfdisk /dev/sdb
FATALER FEHLER: Beschädigte primäre Partition 0: Partition fängt hinter dem Ende der Festpla
(FATAL ERROR:Demage primary partition 0: Partition starts after the end of the drive)
(tried to get this in en but thougt setting LC_ALL and LC-MESSAGES to "en_US.utf-8" I just got the german message....)


Using USB to access the drive works fine.
I even repartitioned the drive via USB... but still the same problem.

I am using the following case for the drive:
http://www.bigbruin.com/2007/icybox_1
(External combo case for 2,5" SATA HDDs with display & docking station, USB2.0 & eSATA interface.)

Removing this drive gives the following:

Aug 23 19:48:40 freax ata2: exception Emask 0x10 SAct 0x0 SErr 0x10000 action 0x2 frozen
Aug 23 19:48:40 freax ata2: (irq_stat 0x00400000, PHY RDY changed)
Aug 23 19:48:43 freax ata2: soft resetting port
Aug 23 19:48:43 freax ata2: SATA link down (SStatus 21 SControl 300)
Aug 23 19:48:43 freax ata2: failed to recover some devices, retrying in 5 secs
Aug 23 19:48:48 freax ata2: hard resetting port
Aug 23 19:48:48 freax ata2: SATA link down (SStatus 0 SControl 300)
Aug 23 19:48:48 freax ata2.00: limiting speed to UDMA/133:PIO3
Aug 23 19:48:48 freax ata2: failed to recover some devices, retrying in 5 secs
Aug 23 19:48:53 freax ata2: hard resetting port
Aug 23 19:48:59 freax ata2: port is slow to respond, please be patient (Status 0x80)
Aug 23 19:49:03 freax ata2: COMRESET failed (errno=-16)
Aug 23 19:49:03 freax ata2: hard resetting port
Aug 23 19:49:04 freax ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Aug 23 19:49:04 freax ata2.00: model number mismatch 'External Disk 0' != 'Config  Disk'
Aug 23 19:49:04 freax ata2.00: revalidation failed (errno=-19)
Aug 23 19:49:04 freax ata2.00: disabled
Aug 23 19:49:05 freax ata2: soft resetting port
Aug 23 19:49:06 freax ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Aug 23 19:49:06 freax ata2: EH pending after completion, repeating EH (cnt=4)
Aug 23 19:49:06 freax ata2: exception Emask 0x10 SAct 0x0 SErr 0x4000000 action 0x3
Aug 23 19:49:06 freax ata2: (irq_stat 0x00000040, connection status changed)
Aug 23 19:49:06 freax ata2: soft resetting port
Aug 23 19:49:06 freax ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Aug 23 19:49:06 freax ata2: EH complete
Aug 23 19:49:06 freax ata2.00: detaching (SCSI 1:0:0:0)
Aug 23 19:49:06 freax sd 1:0:0:0: [sdb] Synchronizing SCSI cache
Aug 23 19:49:06 freax sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Aug 23 19:49:06 freax sd 1:0:0:0: [sdb] Stopping disk
Aug 23 19:49:06 freax sd 1:0:0:0: [sdb] START_STOP FAILED
Aug 23 19:49:06 freax sd 1:0:0:0: [sdb] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK


Now readding the drive.

Aug 23 19:50:57 freax ata2: exception Emask 0x10 SAct 0x0 SErr 0x10000 action 0x2 frozen
Aug 23 19:50:57 freax ata2: (irq_stat 0x00400000, PHY RDY changed)
Aug 23 19:50:59 freax ata2: soft resetting port
Aug 23 19:50:59 freax ata2: SATA link down (SStatus 21 SControl 300)
Aug 23 19:50:59 freax ata2: EH complete
Aug 23 19:51:05 freax ata2: exception Emask 0x10 SAct 0x0 SErr 0x4050002 action 0x2 frozen
Aug 23 19:51:05 freax ata2: (irq_stat 0x00400040, connection status changed)
Aug 23 19:51:06 freax ata2: soft resetting port
Aug 23 19:51:06 freax ata2: softreset failed (1st FIS failed)
Aug 23 19:51:06 freax ata2: reset failed (errno=-5), retrying in 10 secs
Aug 23 19:51:16 freax ata2: hard resetting port
Aug 23 19:51:21 freax ata2: port is slow to respond, please be patient (Status 0x80)
Aug 23 19:51:26 freax ata2: COMRESET failed (errno=-16)
Aug 23 19:51:26 freax ata2: hard resetting port
Aug 23 19:51:27 freax ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Aug 23 19:51:27 freax ata2.00: ATA-6: External Disk 0, RGL10364, max UDMA/133
Aug 23 19:51:27 freax ata2.00: 1 sectors, multi 1: LBA48
Aug 23 19:51:27 freax ata2.00: configured for UDMA/133
Aug 23 19:51:27 freax ata2: EH complete
Aug 23 19:51:27 freax scsi 1:0:0:0: Direct-Access     ATA      External Disk 0  RGL1 PQ: 0 ANSI: 5
Aug 23 19:51:27 freax sd 1:0:0:0: [sdb] 1 512-byte hardware sectors (0 MB)
Aug 23 19:51:27 freax sd 1:0:0:0: [sdb] Write Protect is off
Aug 23 19:51:27 freax sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Aug 23 19:51:27 freax sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 23 19:51:27 freax sd 1:0:0:0: [sdb] 1 512-byte hardware sectors (0 MB)
Aug 23 19:51:27 freax sd 1:0:0:0: [sdb] Write Protect is off
Aug 23 19:51:27 freax sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Aug 23 19:51:27 freax sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 23 19:51:27 freax sdb: sdb1
Aug 23 19:51:27 freax sdb: p1 exceeds device capacity
Aug 23 19:51:27 freax sd 1:0:0:0: [sdb] Attached SCSI disk
Aug 23 19:51:27 freax sd 1:0:0:0: Attached scsi generic sg1 type 0
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576577, limit=1
Aug 23 19:51:27 freax printk: 136 messages suppressed.
Aug 23 19:51:27 freax Buffer I/O error on device sdb1, logical block 156288256
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576579, limit=1
Aug 23 19:51:27 freax Buffer I/O error on device sdb1, logical block 156288257
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576581, limit=1
Aug 23 19:51:27 freax Buffer I/O error on device sdb1, logical block 156288258
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576583, limit=1
Aug 23 19:51:27 freax Buffer I/O error on device sdb1, logical block 156288259
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576577, limit=1
Aug 23 19:51:27 freax Buffer I/O error on device sdb1, logical block 156288256
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576579, limit=1
Aug 23 19:51:27 freax Buffer I/O error on device sdb1, logical block 156288257
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576581, limit=1
Aug 23 19:51:27 freax Buffer I/O error on device sdb1, logical block 156288258
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576583, limit=1
Aug 23 19:51:27 freax Buffer I/O error on device sdb1, logical block 156288259
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576577, limit=1
Aug 23 19:51:27 freax Buffer I/O error on device sdb1, logical block 156288256
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576579, limit=1
Aug 23 19:51:27 freax Buffer I/O error on device sdb1, logical block 156288257
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576581, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576583, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576641, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576643, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576645, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576647, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576689, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576691, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576693, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576695, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576577, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576579, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576581, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576583, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576577, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576579, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576581, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576583, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576577, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576579, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576581, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576583, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576641, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576643, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576645, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576647, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576689, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576691, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576693, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576695, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=312576705, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=65, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=67, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=69, limit=1
Aug 23 19:51:27 freax attempt to access beyond end of device
Aug 23 19:51:27 freax sdb: rw=0, want=71, limit=1
Comment 5 Bjoern Olausson 2007-08-23 11:57:21 UTC
Some more info:
The above error only happens whan I attache te eSATA drive to the third SATA port. If I disconnect th HDD from port two, and attach the eSATA drive to this port. it works. Attaching the drive from port two to the third port works too.

So why did the eSATA drive stop workink on port 3 but works on port 2?

Thanks you so much for your time and patians
Bjoern
Comment 6 Bjoern Olausson 2007-08-23 12:07:56 UTC
removig and readding the eSATA drive from port2:

Aug 23 21:01:45 freax ata3: exception Emask 0x10 SAct 0x0 SErr 0x10000 action 0x2 frozen
Aug 23 21:01:45 freax ata3: (irq_stat 0x00400000, PHY RDY changed)
Aug 23 21:01:48 freax ata3: soft resetting port
Aug 23 21:01:48 freax ata3: SATA link down (SStatus 11 SControl 300)
Aug 23 21:01:48 freax ata3: failed to recover some devices, retrying in 5 secs
Aug 23 21:01:53 freax ata3: hard resetting port
Aug 23 21:01:53 freax ata3: SATA link down (SStatus 0 SControl 300)
Aug 23 21:01:53 freax ata3.00: limiting speed to UDMA/133:PIO3
Aug 23 21:01:53 freax ata3: failed to recover some devices, retrying in 5 secs
Aug 23 21:01:58 freax ata3: hard resetting port
Aug 23 21:01:59 freax ata3: SATA link down (SStatus 0 SControl 300)
Aug 23 21:01:59 freax ata3.00: disabled
Aug 23 21:02:00 freax ata3: EH complete
Aug 23 21:02:00 freax ata3.00: detaching (SCSI 2:0:0:0)
Aug 23 21:02:00 freax sd 2:0:0:0: [sdc] Synchronizing SCSI cache
Aug 23 21:02:00 freax sd 2:0:0:0: [sdc] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Aug 23 21:02:00 freax sd 2:0:0:0: [sdc] Stopping disk
Aug 23 21:02:00 freax sd 2:0:0:0: [sdc] START_STOP FAILED
Aug 23 21:02:00 freax sd 2:0:0:0: [sdc] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Aug 23 21:03:10 freax ata3: exception Emask 0x10 SAct 0x0 SErr 0x4040000 action 0x2 frozen
Aug 23 21:03:10 freax ata3: (irq_stat 0x00000040, connection status changed)
Aug 23 21:03:10 freax ata3: soft resetting port
Aug 23 21:03:11 freax ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Aug 23 21:03:11 freax ata3.00: ATA-7: WDC WD1600BEVS-00RST0, 04.01G04, max UDMA/133
Aug 23 21:03:11 freax ata3.00: 312581808 sectors, multi 0: LBA48 NCQ (depth 31/32)
Aug 23 21:03:11 freax ata3.00: configured for UDMA/133
Aug 23 21:03:11 freax ata3: EH complete
Aug 23 21:03:11 freax scsi 2:0:0:0: Direct-Access     ATA      WDC WD1600BEVS-0 04.0 PQ: 0 ANSI: 5
Aug 23 21:03:11 freax sd 2:0:0:0: [sdc] 312581808 512-byte hardware sectors (160042 MB)
Aug 23 21:03:11 freax sd 2:0:0:0: [sdc] Write Protect is off
Aug 23 21:03:11 freax sd 2:0:0:0: [sdc] Mode Sense: 00 3a 00 00
Aug 23 21:03:11 freax sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 23 21:03:11 freax sd 2:0:0:0: [sdc] 312581808 512-byte hardware sectors (160042 MB)
Aug 23 21:03:11 freax sd 2:0:0:0: [sdc] Write Protect is off
Aug 23 21:03:11 freax sd 2:0:0:0: [sdc] Mode Sense: 00 3a 00 00
Aug 23 21:03:11 freax sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 23 21:03:11 freax sdc: sdc1
Aug 23 21:03:11 freax sd 2:0:0:0: [sdc] Attached SCSI disk
Aug 23 21:03:11 freax sd 2:0:0:0: Attached scsi generic sg2 type 0





Removing a normal HDD from port3 and readding it:

Aug 23 21:05:08 freax ata2: exception Emask 0x10 SAct 0x0 SErr 0x10000 action 0x2 frozen
Aug 23 21:05:08 freax ata2: (irq_stat 0x00400000, PHY RDY changed)
Aug 23 21:05:10 freax ata2: soft resetting port
Aug 23 21:05:10 freax ata2: SATA link down (SStatus 21 SControl 300)
Aug 23 21:05:10 freax ata2: EH complete
Aug 23 21:05:16 freax ata2: exception Emask 0x10 SAct 0x0 SErr 0x4000000 action 0x2 frozen
Aug 23 21:05:16 freax ata2: (irq_stat 0x00000040, connection status changed)
Aug 23 21:05:16 freax ata2: soft resetting port
Aug 23 21:05:17 freax ata2: softreset failed (1st FIS failed)
Aug 23 21:05:17 freax ata2: reset failed (errno=-5), retrying in 10 secs
Aug 23 21:05:26 freax ata2: hard resetting port
Aug 23 21:05:27 freax ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Aug 23 21:05:27 freax ata2.00: ATA-6: External Disk 0, RGL10364, max UDMA/133
Aug 23 21:05:27 freax ata2.00: 145226112 sectors, multi 1: LBA48
Aug 23 21:05:27 freax ata2.00: configured for UDMA/133
Aug 23 21:05:27 freax ata2: EH complete
Aug 23 21:05:27 freax scsi 1:0:0:0: Direct-Access     ATA      External Disk 0  RGL1 PQ: 0 ANSI: 5
Aug 23 21:05:27 freax sd 1:0:0:0: [sdb] 145226112 512-byte hardware sectors (74356 MB)
Aug 23 21:05:27 freax sd 1:0:0:0: [sdb] Write Protect is off
Aug 23 21:05:27 freax sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Aug 23 21:05:27 freax sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 23 21:05:27 freax sd 1:0:0:0: [sdb] 145226112 512-byte hardware sectors (74356 MB)
Aug 23 21:05:27 freax sd 1:0:0:0: [sdb] Write Protect is off
Aug 23 21:05:27 freax sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Aug 23 21:05:27 freax sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 23 21:05:27 freax sdb: sdb1
Aug 23 21:05:27 freax sd 1:0:0:0: [sdb] Attached SCSI disk
Aug 23 21:05:27 freax sd 1:0:0:0: Attached scsi generic sg1 type 0
Comment 7 Bjoern Olausson 2007-08-23 12:25:59 UTC
Okay, now I got confuesd of the boards (Asus P5W DH Delux) layout:

ICH7 (3 Ports):
port1, port3, port4 (port2 is used as bridge for SI4723)

Silicon Image 4723 Hardware Raid Controller (Asus EZ-Backup)(two Ports):
ez_raid1, ez_raid22

Jmicron JMB363 (2 Ports but controller is disabled):
SATA_RAID1, eSATA(on the rear of the case)

Port2 of the ICH7 is bridged to the SI4723.

So when I was speaking of port3 i was referin to the first port of the SI4723.

So adding the drives directly to the ICH7 (port1,3 or 4) works fine.

I am starting to hate that MB....

Bjoern
Comment 8 Bjoern Olausson 2007-08-23 12:44:35 UTC
The SI4723 is the one which is IMHO responsible for the boot delay and is also behaving somewhat nasty with hotplug. Hopfully you can restrain that beast.

Thanks Tejun for your help and support.

regards
Bjoern
Comment 9 Tejun Heo 2007-08-26 22:41:50 UTC
Created attachment 12556 [details]
asus-p5w-dh-quirk-take-2.patch

Please test the attached patch and report the result.

The on-board 4726 reports a virtual device which maps to the the device attached behind it and it seems that the virtual device reports a different geometry from the original one thus rendering the existing partition table invalid.  If you give '-z' option to cfdisk, it ignores the existing partition table and you'll be able to continue but I don't recommend that.  Partition table created that way would be invalid when the disk is attached to a different port.  Just don't move disks between the 4726 ports and other ports.  Well, if I were you, I would just stay away from the ports behind the 4726.  I just want to make the initial detection succeed without timing out.  Thanks.
Comment 10 Bjoern Olausson 2007-08-27 02:17:17 UTC
Thats the  point I have come to. I found a forumpaste where it was writen, that when removing the RAID config jumpers for the SIL, it would be deactivated and with the enable "EZ Backup RAID Mode Change" in the BIOS you get back the full speed ICH7 port.

I tryed it, but it still hangs at boot. So now I am gonna test your patch.

But for time being I now run the three disks on the ICH7 Ports 1, 3, 5 and maybe I'll give the second Port to see if the deaktivation of the SIL worked.

But priority has your patch to get rid of that boot delay.

Just for my own satisfaction: What is causing the delay, and what is your patch doing agiainst that?


Your patch does apply completely against 2.6.22.5 so I had to add the SIL manually:

patch -p0 < asus-p5w-dh-quirk-take-2.patch
patching file drivers/ata/ahci.c
Hunk #1 succeeded at 44 with fuzz 1.
Hunk #2 succeeded at 1527 (offset -105 lines).
Hunk #3 succeeded at 1560 (offset -105 lines).
patching file drivers/ata/libata-core.c
Hunk #1 FAILED at 3762.
1 out of 1 hunk FAILED -- saving rejects to file drivers/ata/libata-core.c.rej
patching file drivers/ata/libata-eh.c
Hunk #1 succeeded at 1594 (offset -165 lines).
Hunk #2 succeeded at 1630 (offset -165 lines).
Hunk #3 succeeded at 1693 (offset -166 lines).
patching file drivers/ata/libata-scsi.c
patching file include/linux/libata.h
Hunk #1 succeeded at 174 (offset -3 lines).
Hunk #2 succeeded at 299 (offset -5 lines).



rejectfile
***************
*** 3762,3767 ****
        { "IOMEGA  ZIP 250       ATAPI", NULL,  ATA_HORKAGE_NODMA }, /* temporary fix */
        { "IOMEGA  ZIP 250       ATAPI       Floppy",
                                NULL,           ATA_HORKAGE_NODMA },

        /* Weird ATAPI devices */
        { "TORiSAN DVD-ROM DRD-N216", NULL,     ATA_HORKAGE_MAX_SEC_128 },
--- 3762,3770 ----
        { "IOMEGA  ZIP 250       ATAPI", NULL,  ATA_HORKAGE_NODMA }, /* temporary fix */
        { "IOMEGA  ZIP 250       ATAPI       Floppy",
                                NULL,           ATA_HORKAGE_NODMA },
+       /* Odd clown on sil3726/4726 PMPs */
+       { "Config  Disk",       NULL,           ATA_HORKAGE_NODMA |
+                                               ATA_HORKAGE_SKIP_PM },

        /* Weird ATAPI devices */
        { "TORiSAN DVD-ROM DRD-N216", NULL,     ATA_HORKAGE_MAX_SEC_128 },



*reboot*

regards
Bjoern
Comment 11 Bjoern Olausson 2007-08-27 02:52:24 UTC
Created attachment 12565 [details]
bootlog

First reboot with patch asus-p5w-dh-quirk-take-2.patch
Comment 12 Bjoern Olausson 2007-08-27 02:57:32 UTC
Created attachment 12566 [details]
bootlog1

Second boot with asus-p5w-dh-quirk-take-2.patch.

Diffing the files showd that for the mentioned delay in the first boot was the ata5 (the ICH7 PATA port responsible)

ata5: port is slow to respond, please be patient (Status 0x80)
ata5: SRST failed (errno=-16)

Dont' know why, was the first time have seen this. And this did ony occure once.
Comment 13 Bjoern Olausson 2007-08-27 02:59:17 UTC
Created attachment 12567 [details]
bootlog_with_disk+hotplug

Bootlog with Disk attached to port 2. At the end theres a hotremove and hoplug event for the device on that port.
Comment 14 Tejun Heo 2007-08-27 03:44:56 UTC
Oh... crap it seems to be failing every other COMRESETs.  The patch was against 2.6.23-rc3.  Sorry about the inconvenience.

* The delay is caused by the virtual device implemented on the PMP chip.  The device is very picky about how it's driven and behaves very erratically when it doesn't like something the driver tells it.  :-(

* The ata5 timeout seems to be a separate issue.  How reproducible is the problem?

* Can you please build 2.6.23-rc3 + take 2 patch and try the same hot plug/unplug test?
Comment 15 Bjoern Olausson 2007-08-27 03:50:34 UTC
- Thanks for the info.

- The ata5 timeout occured only once... I'll keep an eye on that. if I'll find a way to reproduce it for shure, I'll let you know.

- compiling 2.6.23-rc3 with your patch.

Expect the results very soon ;-)
Comment 16 Bjoern Olausson 2007-08-27 04:45:49 UTC
during my reboots I could not reproduce the ata5 timeout... so I guess that was just a small bobo of the DVD-Drive... nothing to be worried about I think.

So below my bootlogs with comments for the actual bug.
Comment 17 Bjoern Olausson 2007-08-27 04:48:21 UTC
Created attachment 12572 [details]
bootlog-2.6.23-rc3-boot_with_disk+hotplug

Booted 2.6.23-rc3 with disk on the port2 and hotremoved/added the disk.
Comment 18 Bjoern Olausson 2007-08-27 04:49:49 UTC
Created attachment 12573 [details]
bootlog-2.6.23-rc3-boot_without_disk+hotplug

Booted 2.6.23-rc3 without disk on the port2 and hotremoved/added the disk.
Comment 19 Bjoern Olausson 2007-08-27 04:50:48 UTC
Created attachment 12574 [details]
bootlog-2.6.22.5-boot_with_disk+hotplug

Booted 2.6.22.5 with disk on the port2 and hotremoved/added the disk.
(just for completeness)
Comment 20 Bjoern Olausson 2007-08-27 04:51:56 UTC
Created attachment 12575 [details]
bootlog-2.6.22.5-boot_without_disk+hotplug

Booted 2.6.22.5 without disk on the port2 and hotremoved/added the disk.
(just for completeness)

What does
model number mismatch 'Config  Disk' != 'WDC WD1600BEVS-00RST0'
mean? You'll find this in all Logs
Comment 21 Bjoern Olausson 2007-08-27 04:55:11 UTC
By the way, now I can access my partitions (created on the disk while on ICH7 port4) when the disk is on port2 (if you want I can test to create a partition while the disk is on SIL and try to access it on the ICH7 port 4)

Thanks for your help!
Comment 22 Bjoern Olausson 2007-09-11 11:02:51 UTC
The boot "hang" is fixed in 2.6.23-rc6

But I think hotpluging is behaving a bit strange... it works.. but it produces a lot of weired output.

For this log the Disk was attached while booting, than removed and attached again:

ata2: exception Emask 0x10 SAct 0x0 SErr 0x10000 action 0x2 frozen
ata2: irq_stat 0x00400000, PHY RDY changed
ata2: soft resetting port
ata2: SATA link down (SStatus 21 SControl 300)
ata2: failed to recover some devices, retrying in 5 secs
ata2: hard resetting port
ata2: SATA link down (SStatus 0 SControl 300)
ata2: limiting SATA link speed to 1.5 Gbps
ata2.00: limiting speed to UDMA/133:PIO3
ata2: failed to recover some devices, retrying in 5 secs
ata2: hard resetting port
ata2: port is slow to respond, please be patient (Status 0x80)
ata2: COMRESET failed (errno=-16)
ata2: hard resetting port
ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
ata2.00: model number mismatch 'WDC WD1600BEVS-00RST0' != 'Config  Disk'
ata2.00: revalidation failed (errno=-19)
ata2.00: disabled
ata2: hard resetting port
ata2: port is slow to respond, please be patient (Status 0x80)
ata2: COMRESET failed (errno=-16)
ata2: hard resetting port
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: ATA-6: Config  Disk, RGL10364, max UDMA/133
ata2.00: 640 sectors, multi 1: LBA 
ata2.00: configured for UDMA/133
ata2: EH pending after completion, repeating EH (cnt=4)
ata2: exception Emask 0x10 SAct 0x0 SErr 0x4050002 action 0x3
ata2: irq_stat 0x00400040, connection status changed
ata2: soft resetting port
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: qc timeout (cmd 0xec)
ata2.00: failed to IDENTIFY (I/O error, err_mask=0x5)
ata2.00: revalidation failed (errno=-5)
ata2: failed to recover some devices, retrying in 5 secs
ata2: hard resetting port
ata2: port is slow to respond, please be patient (Status 0x80)
ata2: COMRESET failed (errno=-16)
ata2: hard resetting port
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: configured for UDMA/133
ata2: EH pending after completion, repeating EH (cnt=3)
ata2: EH complete
ata2.00: detaching (SCSI 1:0:0:0)
sd 1:0:0:0: [sdb] Synchronizing SCSI cache
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e7/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
sd 1:0:0:0: [sdb] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
sd 1:0:0:0: [sdb] Sense Key : Aborted Command [current] [descriptor]
sd 1:0:0:0: [sdb] Add. Sense: No additional sense information
sd 1:0:0:0: [sdb] Stopping disk
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e0/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e0/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e0/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e0/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e0/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0
ata2.00: irq_stat 0x40000001
ata2.00: cmd e0/00:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x0 data 0 
         res 51/04:00:01:01:80/00:00:00:00:00/a0 Emask 0x1 (device error)
ata2.00: configured for UDMA/133
ata2: EH complete
sd 1:0:0:0: [sdb] START_STOP FAILED
sd 1:0:0:0: [sdb] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
sd 1:0:0:0: [sdb] Sense Key : Aborted Command [current] [descriptor]
sd 1:0:0:0: [sdb] Add. Sense: No additional sense information
scsi 1:0:0:0: Direct-Access     ATA      Config  Disk     RGL1 PQ: 0 ANSI: 5
sd 1:0:0:0: [sdb] 640 512-byte hardware sectors (0 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
sd 1:0:0:0: [sdb] 640 512-byte hardware sectors (0 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
 sdb: unknown partition table
sd 1:0:0:0: [sdb] Attached SCSI disk
sd 1:0:0:0: Attached scsi generic sg1 type 0
sd 1:0:0:0: [sdb] 640 512-byte hardware sectors (0 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
ata2: exception Emask 0x10 SAct 0x0 SErr 0x4010000 action 0x2 frozen
ata2: irq_stat 0x00400000, PHY RDY changed
ata2: soft resetting port
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: qc timeout (cmd 0xec)
ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4)
ata2.00: revalidation failed (errno=-5)
ata2: failed to recover some devices, retrying in 5 secs
ata2: hard resetting port
ata2: port is slow to respond, please be patient (Status 0x80)
ata2: COMRESET failed (errno=-16)
ata2: hard resetting port
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: model number mismatch 'Config  Disk' != 'WDC WD1600BEVS-00RST0'
ata2.00: revalidation failed (errno=-19)
ata2: limiting SATA link speed to 1.5 Gbps
ata2.00: limiting speed to UDMA/133:PIO3
ata2: failed to recover some devices, retrying in 5 secs
ata2: hard resetting port
ata2: port is slow to respond, please be patient (Status 0x80)
ata2: COMRESET failed (errno=-16)
ata2: hard resetting port
ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
ata2.00: model number mismatch 'Config  Disk' != 'WDC WD1600BEVS-00RST0'
ata2.00: revalidation failed (errno=-19)
ata2.00: disabled
ata2: hard resetting port
ata2: port is slow to respond, please be patient (Status 0x80)
ata2: COMRESET failed (errno=-16)
ata2: hard resetting port
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: ATA-7: WDC WD1600BEVS-00RST0, 04.01G04, max UDMA/133
ata2.00: 312581808 sectors, multi 0: LBA48 NCQ (depth 31/32)
ata2.00: configured for UDMA/133
ata2: EH pending after completion, repeating EH (cnt=4)
ata2: EH complete
ata2.00: detaching (SCSI 1:0:0:0)
sd 1:0:0:0: [sdb] Stopping disk
scsi 1:0:0:0: Direct-Access     ATA      WDC WD1600BEVS-0 04.0 PQ: 0 ANSI: 5
sd 1:0:0:0: [sdb] 312581808 512-byte hardware sectors (160042 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
sd 1:0:0:0: [sdb] 312581808 512-byte hardware sectors (160042 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
 sdb: sdb1
sd 1:0:0:0: [sdb] Attached SCSI disk
sd 1:0:0:0: Attached scsi generic sg1 type 0


full log can be found on:
http://paste.olausson.de/00fefaea1b.html

Should I open a new bug for the hotplugging stuff concerning devices on the on-board SIL-4726?

Thanks and regards
Bjoern
Comment 23 Tejun Heo 2007-09-11 18:24:47 UTC
Yes, please open a separate bug report and cc me.  That seems like a dangerous bug.  SCSI device hasn't been offlined properly and it's trying to cache data from the previous device to the new one.  I think I've seen it once myself too.  Didn't pay much attention then.  Thanks.
Comment 24 Bjoern Olausson 2007-09-11 23:22:31 UTC
Okay, so would you agree to close this bug, or take a closer look at the

ata2.00: model number mismatch 'Config  Disk' != 'WDC WD1600BEVS-00RST0'

This first occured when you introduced the asus-p5w-dh-quirk patch.

regards
Bjoern
Comment 25 Tejun Heo 2007-09-27 11:17:45 UTC
bugme-daemon@bugzilla.kernel.org wrote:
> Okay, so would you agree to close this bug, or take a closer look at the
> 
> ata2.00: model number mismatch 'Config  Disk' != 'WDC WD1600BEVS-00RST0'
> 
> This first occured when you introduced the asus-p5w-dh-quirk patch.

That's the normal response from libata EH.  As IDENTIFY may deliver
corrupt data at times, it tries several times before giving up on the
existing device.  The P5W-DH problem isn't solved yet so I think we
should keep this bugzilla entry open.

Thanks.
Comment 26 Tito 2007-10-02 15:14:52 UTC
Set the following flags for the ICH7 controller in the Asus P5W SH Deluxe BIOS:
(in Main->IDE Configuration->Standard IDE)
Configure SATA as Standard IDE
Enhanced mode
PATA+SATA

and there will be no boot delays.
This also cured some weird raid corruption I experienced,
basically my sata drives were put offline one after the other 
with no reason after a series of soft and hard resets always
originating from the port controlled by the SIL 4723.
This happened with Configure SATA set to AHCI.
It seems to me that the ich7 and/or Sil 4723 didn't like the ahci driver.

Ciao.

PS: the quirk patch didn't solve my problems just slowed the corruption
    a little, I even wrote my own patch to disable fully the 2 sata port 
    on boot but also this didn't work on the long run....
Comment 27 Bjoern Olausson 2007-10-03 08:57:17 UTC
I know, but this is a unpleasant workaround....

By the way, I removed the disk on the SIL and there's again the delay... so no changes here.
Comment 28 Bjoern Olausson 2007-10-10 06:00:16 UTC
Created attachment 13097 [details]
bootlog

With 2.6.23 things got worse...
Still the delay while booting
and I can't access the partitions on the disk connected to the SIL port.

Reomoving the disk on the fly and connecting it directly to the ICH7 port works flawless (as you can see on the end of the log)


attempt to access beyond end of device
sdb: rw=0, want=312576577, limit=1
Buffer I/O error on device sdb1, logical block 156288256
attempt to access beyond end of device
sdb: rw=0, want=312576579, limit=1
Buffer I/O error on device sdb1, logical block 156288257
attempt to access beyond end of device
sdb: rw=0, want=312576581, limit=1
Buffer I/O error on device sdb1, logical block 156288258
attempt to access beyond end of device
sdb: rw=0, want=312576583, limit=1
Buffer I/O error on device sdb1, logical block 156288259
attempt to access beyond end of device
sdb: rw=0, want=312576577, limit=1
Buffer I/O error on device sdb1, logical block 156288256
attempt to access beyond end of device
sdb: rw=0, want=312576579, limit=1
Buffer I/O error on device sdb1, logical block 156288257
attempt to access beyond end of device
sdb: rw=0, want=312576581, limit=1
Buffer I/O error on device sdb1, logical block 156288258
attempt to access beyond end of device
sdb: rw=0, want=312576583, limit=1
Buffer I/O error on device sdb1, logical block 156288259
attempt to access beyond end of device
sdb: rw=0, want=312576689, limit=1
Buffer I/O error on device sdb1, logical block 156288312
attempt to access beyond end of device
sdb: rw=0, want=312576691, limit=1
attempt to access beyond end of device
sdb: rw=0, want=312576693, limit=1
attempt to access beyond end of device
sdb: rw=0, want=312576695, limit=1
attempt to access beyond end of device
sdb: rw=0, want=312576689, limit=1
attempt to access beyond end of device
sdb: rw=0, want=312576691, limit=1
attempt to access beyond end of device
sdb: rw=0, want=312576693, limit=1
attempt to access beyond end of device
sdb: rw=0, want=312576695, limit=1
attempt to access beyond end of device
sdb: rw=0, want=65, limit=1
attempt to access beyond end of device
sdb: rw=0, want=67, limit=1
Comment 29 Tejun Heo 2007-10-18 07:26:02 UTC
*** Bug 9142 has been marked as a duplicate of this bug. ***
Comment 30 Tejun Heo 2007-10-24 03:14:46 UTC
Created attachment 13258 [details]
p5wdh-workaround-2.6.23.1.patch

Please apply the attached patch on top of 2.6.23.1 and see whether the boot delay goes away.  Thanks.
Comment 31 Fabian Zeindl 2007-10-24 06:39:40 UTC
With the patch everything works:

scsi0 : ahci
scsi1 : ahci
scsi2 : ahci
scsi3 : ahci
ata1: SATA max UDMA/133 cmd 0xf8834900 ctl 0x00000000 bmdma 0x00000000 irq 216
ata2: SATA max UDMA/133 cmd 0xf8834980 ctl 0x00000000 bmdma 0x00000000 irq 216
ata3: SATA max UDMA/133 cmd 0xf8834a00 ctl 0x00000000 bmdma 0x00000000 irq 216
ata4: SATA max UDMA/133 cmd 0xf8834a80 ctl 0x00000000 bmdma 0x00000000 irq 216
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata1.00: ATA-7: ST3400620AS, 3.AAK, max UDMA/133
ata1.00: 781422768 sectors, multi 16: LBA48 NCQ (depth 31/32)
ata1.00: configured for UDMA/133
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: ATA-6: Config  Disk, RGL10364, max UDMA/133
ata2.00: 640 sectors, multi 1: LBA 
ata2.00: configured for UDMA/133
ata3: SATA link down (SStatus 0 SControl 300)
ata4: SATA link down (SStatus 0 SControl 300)

The raidcontroller on ata2 is still recognized as disk (/dev/sdb at my system), but I think that doesn't matter...
Comment 32 Tejun Heo 2007-10-24 06:51:35 UTC
Yeah, that's normal.  That's the pseudo configuration device for SIMG4726 which shows up if there's no device attached downstream.  Can other people confirm the fix too?

Thanks.
Comment 33 Bjoern Olausson 2007-10-24 14:17:09 UTC
Confirmed!

ahci 0000:00:1f.2: version 2.3
ACPI: PCI Interrupt 0000:00:1f.2[B] -> GSI 23 (level, low) -> IRQ 23
ahci 0000:00:1f.2: enabling ASUS P5W DH Deluxe on-board SIMG4726 workaround
ahci 0000:00:1f.2: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf impl SATA mode
ahci 0000:00:1f.2: flags: 64bit ncq led clo pio slum part
PCI: Setting latency timer of device 0000:00:1f.2 to 64
scsi0 : ahci
scsi1 : ahci
scsi2 : ahci
scsi3 : ahci
ata1: SATA max UDMA/133 cmd 0xffffc20001740900 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1275
ata2: SATA max UDMA/133 cmd 0xffffc20001740980 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1275
ata3: SATA max UDMA/133 cmd 0xffffc20001740a00 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1275
ata4: SATA max UDMA/133 cmd 0xffffc20001740a80 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1275
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata1.00: ATA-7: WDC WD740ADFD-00NLR1, 20.07P20, max UDMA/133
ata1.00: 145226112 sectors, multi 16: LBA48 NCQ (not used)
ata1.00: configured for UDMA/133
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: ATA-6: Config  Disk, RGL10364, max UDMA/133
ata2.00: 640 sectors, multi 1: LBA
ata2.00: configured for UDMA/133
ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata3.00: ATA-7: WDC WD740ADFD-00NLR1, 20.07P20, max UDMA/133
ata3.00: 145226112 sectors, multi 16: LBA48 NCQ (not used)
ata3.00: configured for UDMA/133
ata4: SATA link down (SStatus 0 SControl 300)
scsi 0:0:0:0: Direct-Access     ATA      WDC WD740ADFD-00 20.0 PQ: 0 ANSI: 5
sd 0:0:0:0: [sda] 145226112 512-byte hardware sectors (74356 MB)
sd 0:0:0:0: [sda] Write Protect is off
sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
sd 0:0:0:0: [sda] 145226112 512-byte hardware sectors (74356 MB)
sd 0:0:0:0: [sda] Write Protect is off
sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
 sda: sda1 sda2 sda3
sd 0:0:0:0: [sda] Attached SCSI disk
sd 0:0:0:0: Attached scsi generic sg0 type 0
scsi 1:0:0:0: Direct-Access     ATA      Config  Disk     RGL1 PQ: 0 ANSI: 5
sd 1:0:0:0: [sdb] 640 512-byte hardware sectors (0 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
sd 1:0:0:0: [sdb] 640 512-byte hardware sectors (0 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
 sdb: unknown partition table
sd 1:0:0:0: [sdb] Attached SCSI disk
sd 1:0:0:0: Attached scsi generic sg1 type 0
scsi 2:0:0:0: Direct-Access     ATA      WDC WD740ADFD-00 20.0 PQ: 0 ANSI: 5
sd 2:0:0:0: [sdc] 145226112 512-byte hardware sectors (74356 MB)
sd 2:0:0:0: [sdc] Write Protect is off
sd 2:0:0:0: [sdc] Mode Sense: 00 3a 00 00
sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
sd 2:0:0:0: [sdc] 145226112 512-byte hardware sectors (74356 MB)
sd 2:0:0:0: [sdc] Write Protect is off
sd 2:0:0:0: [sdc] Mode Sense: 00 3a 00 00
sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
 sdc: sdc1
sd 2:0:0:0: [sdc] Attached SCSI disk
sd 2:0:0:0: Attached scsi generic sg2 type 0
ACPI: PCI Interrupt 0000:02:00.0[A] -> GSI 17 (level, low) -> IRQ 17
ahci 0000:02:00.0: AHCI 0001.0000 32 slots 2 ports 3 Gbps 0x3 impl SATA mode
ahci 0000:02:00.0: flags: 64bit ncq pm led clo pmp pio slum part
PCI: Setting latency timer of device 0000:02:00.0 to 64
scsi4 : ahci
scsi5 : ahci
ata5: SATA max UDMA/133 cmd 0xffffc20001744100 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 17
ata6: SATA max UDMA/133 cmd 0xffffc20001744180 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 17
ata5: SATA link down (SStatus 0 SControl 300)
ata6: SATA link down (SStatus 0 SControl 300)
ata_piix 0000:00:1f.1: version 2.12
ACPI: PCI Interrupt 0000:00:1f.1[A] -> GSI 22 (level, low) -> IRQ 22
PCI: Setting latency timer of device 0000:00:1f.1 to 64
scsi6 : ata_piix
scsi7 : ata_piix
ata7: PATA max UDMA/133 cmd 0x00000000000101f0 ctl 0x00000000000103f6 bmdma 0x000000000001ffa0 irq 14
ata8: PATA max UDMA/133 cmd 0x0000000000010170 ctl 0x0000000000010376 bmdma 0x000000000001ffa8 irq 15
ata7.00: ATAPI: PLEXTOR DVDR   PX-760A, 1.07, max UDMA/66
ata7.00: configured for UDMA/66
scsi 6:0:0:0: CD-ROM            PLEXTOR  DVDR   PX-760A   1.07 PQ: 0 ANSI: 5
sr0: scsi3-mmc drive: 40x/40x writer cd/rw xa/form2 cdda tray
Uniform CD-ROM driver Revision: 3.20
sr 6:0:0:0: Attached scsi CD-ROM sr0
sr 6:0:0:0: Attached scsi generic sg3 type 5
PCI: Enabling device 0000:02:00.1 (0000 -> 0001)
ACPI: PCI Interrupt 0000:02:00.1[B] -> GSI 18 (level, low) -> IRQ 18
PCI: Setting latency timer of device 0000:02:00.1 to 64
scsi8 : pata_jmicron
scsi9 : pata_jmicron
ata9: PATA max UDMA/100 cmd 0x000000000001ac00 ctl 0x000000000001a882 bmdma 0x000000000001a400 irq 18
ata10: PATA max UDMA/100 cmd 0x000000000001a800 ctl 0x000000000001a482 bmdma 0x000000000001a408 irq 18
ata9.00: ATAPI: PLEXTOR DVD-ROM PX-130A, 1.03, max UDMA/33
ata9.00: configured for UDMA/33
scsi 8:0:0:0: CD-ROM            PLEXTOR  DVD-ROM PX-130A  1.03 PQ: 0 ANSI: 5
sr1: scsi3-mmc drive: 0x/50x cd/rw xa/form2 cdda tray
sr 8:0:0:0: Attached scsi CD-ROM sr1
sr 8:0:0:0: Attached scsi generic sg4 type 5


Thanks for the work!
regards
Bjoern
Comment 34 Tejun Heo 2007-10-24 17:07:34 UTC
Alright, it was about time, wasn't it?  Sorry about the delay.  I didn't have access to the specific motherboard and didn't know how it was configured till recently and when I realized it I was knee deep in a series of personal occasions.

I'll push the patch for 2.6.24 merge.  I'm not sure whether it can be included in 2.6.23.X.  The change is rather big but I'll give it a try.

Thanks.
Comment 35 Tejun Heo 2007-10-24 23:18:35 UTC
Okay, improved patch is scheduled for 2.6.24.  Can somebody please post boot log when the controller is in IDE mode?  Does it work without problem?
Comment 36 Fabian Zeindl 2007-10-25 03:32:46 UTC
Created attachment 13273 [details]
dmesg using p-ata

I don't know where the two ahci lines come from, cause I've disabled AHCI everywhere in biossetup. Is the bootlog ok for you?
Comment 37 Bjoern Olausson 2007-10-25 07:35:04 UTC
Tested with BIOS settings:

Config. SATA As --> Standard IDE
Onboard operate mode --> Enhanced Mode (in Compatible mode the BIOS will not detect my SATA-Disks)
Enhanced mode on --> S-ATA and P-ATA


Works fine.



[   47.603164] ahci 0000:02:00.0: version 2.3
[   47.603190] ACPI: PCI Interrupt 0000:02:00.0[A] -> GSI 17 (level, low) -> IRQ 17
[   48.602287] ahci 0000:02:00.0: AHCI 0001.0000 32 slots 2 ports 3 Gbps 0x3 impl SATA mode
[   48.602340] ahci 0000:02:00.0: flags: 64bit ncq pm led clo pmp pio slum part
[   48.602386] PCI: Setting latency timer of device 0000:02:00.0 to 64
[   48.602613] scsi0 : ahci
[   48.602747] scsi1 : ahci
[   48.602853] ata1: SATA max UDMA/133 cmd 0xffffc20001740100 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 17
[   48.602904] ata2: SATA max UDMA/133 cmd 0xffffc20001740180 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 17
[   48.904756] ata1: SATA link down (SStatus 0 SControl 300)
[   49.208340] ata2: SATA link down (SStatus 0 SControl 300)
[   49.208485] ata_piix 0000:00:1f.1: version 2.12
[   49.208514] ACPI: PCI Interrupt 0000:00:1f.1[A] -> GSI 22 (level, low) -> IRQ 22
[   49.208610] PCI: Setting latency timer of device 0000:00:1f.1 to 64
[   49.208648] scsi2 : ata_piix
[   49.208762] scsi3 : ata_piix
[   49.208853] ata3: PATA max UDMA/133 cmd 0x000000000001d800 ctl 0x000000000001d482 bmdma 0x000000000001d000 irq 22
[   49.208902] ata4: PATA max UDMA/133 cmd 0x000000000001d400 ctl 0x000000000001d082 bmdma 0x000000000001d008 irq 22
[   49.514214] ata3.00: ATAPI: PLEXTOR DVDR   PX-760A, 1.07, max UDMA/66
[   49.668626] ata3.00: configured for UDMA/66
[   49.831079] scsi 2:0:0:0: CD-ROM            PLEXTOR  DVDR   PX-760A   1.07 PQ: 0 ANSI: 5
[   49.834564] sr0: scsi3-mmc drive: 40x/40x writer cd/rw xa/form2 cdda tray
[   49.834607] Uniform CD-ROM driver Revision: 3.20
[   49.834841] sr 2:0:0:0: Attached scsi CD-ROM sr0
[   49.834957] sr 2:0:0:0: Attached scsi generic sg0 type 5
[   49.835028] ata_piix 0000:00:1f.2: MAP [ P0 P2 P1 P3 ]
[   49.985968] ACPI: PCI Interrupt 0000:00:1f.2[B] -> GSI 23 (level, low) -> IRQ 23
[   49.986073] PCI: Setting latency timer of device 0000:00:1f.2 to 64
[   49.986128] scsi4 : ata_piix
[   49.986238] scsi5 : ata_piix
[   49.986328] ata5: SATA max UDMA/133 cmd 0x000000000001e400 ctl 0x000000000001e082 bmdma 0x000000000001d880 irq 23
[   49.986377] ata6: SATA max UDMA/133 cmd 0x000000000001e000 ctl 0x000000000001dc02 bmdma 0x000000000001d888 irq 23
[   50.145254] ata5.00: ATA-7: WDC WD740ADFD-00NLR1, 20.07P20, max UDMA/133
[   50.145296] ata5.00: 145226112 sectors, multi 16: LBA48 NCQ (not used)
[   50.146038] ata5.01: ATA-7: WDC WD740ADFD-00NLR1, 20.07P20, max UDMA/133
[   50.146079] ata5.01: 145226112 sectors, multi 16: LBA48 NCQ (not used)
[   50.154411] ata5.00: configured for UDMA/133
[   50.160518] ata5.01: configured for UDMA/133
[   50.872461] ata6.00: ATA-6: Config  Disk, RGL10364, max UDMA/133
[   50.872500] ata6.00: 640 sectors, multi 1: LBA
[   50.872536] ata6.00: Drive reports diagnostics failure. This may indicate a drive
[   50.872582] ata6.00: fault or invalid emulation. Contact drive vendor for information.
[   50.872972] ata6.00: configured for UDMA/133
[   50.873093] scsi 4:0:0:0: Direct-Access     ATA      WDC WD740ADFD-00 20.0 PQ: 0 ANSI: 5
[   50.873332] sd 4:0:0:0: [sda] 145226112 512-byte hardware sectors (74356 MB)
[   50.873384] sd 4:0:0:0: [sda] Write Protect is off
[   50.873421] sd 4:0:0:0: [sda] Mode Sense: 00 3a 00 00
[   50.873444] sd 4:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   50.873724] sd 4:0:0:0: [sda] 145226112 512-byte hardware sectors (74356 MB)
[   50.873775] sd 4:0:0:0: [sda] Write Protect is off
[   50.873812] sd 4:0:0:0: [sda] Mode Sense: 00 3a 00 00
[   50.873836] sd 4:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   50.873899]  sda: sda1 sda2 sda3
[   50.882616] sd 4:0:0:0: [sda] Attached SCSI disk
[   50.882741] sd 4:0:0:0: Attached scsi generic sg1 type 0
[   50.882862] scsi 4:0:1:0: Direct-Access     ATA      WDC WD740ADFD-00 20.0 PQ: 0 ANSI: 5
[   50.883066] sd 4:0:1:0: [sdb] 145226112 512-byte hardware sectors (74356 MB)
[   50.883117] sd 4:0:1:0: [sdb] Write Protect is off
[   50.883154] sd 4:0:1:0: [sdb] Mode Sense: 00 3a 00 00
[   50.883178] sd 4:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   50.883320] sd 4:0:1:0: [sdb] 145226112 512-byte hardware sectors (74356 MB)
[   50.883371] sd 4:0:1:0: [sdb] Write Protect is off
[   50.883408] sd 4:0:1:0: [sdb] Mode Sense: 00 3a 00 00
[   50.883431] sd 4:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   50.883481]  sdb: sdb1
[   50.895980] sd 4:0:1:0: [sdb] Attached SCSI disk
[   50.896105] sd 4:0:1:0: Attached scsi generic sg2 type 0
[   50.896227] scsi 5:0:0:0: Direct-Access     ATA      Config  Disk     RGL1 PQ: 0 ANSI: 5
[   50.896384] sd 5:0:0:0: [sdc] 640 512-byte hardware sectors (0 MB)
[   50.896434] sd 5:0:0:0: [sdc] Write Protect is off
[   50.896471] sd 5:0:0:0: [sdc] Mode Sense: 00 3a 00 00
[   50.896495] sd 5:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   50.896591] sd 5:0:0:0: [sdc] 640 512-byte hardware sectors (0 MB)
[   50.896641] sd 5:0:0:0: [sdc] Write Protect is off
[   50.896681] sd 5:0:0:0: [sdc] Mode Sense: 00 3a 00 00
[   50.896705] sd 5:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   50.896753]  sdc: unknown partition table
[   50.897117] sd 5:0:0:0: [sdc] Attached SCSI disk
[   50.897261] sd 5:0:0:0: Attached scsi generic sg3 type 0
[   50.897403] PCI: Enabling device 0000:02:00.1 (0000 -> 0001)
[   50.897446] ACPI: PCI Interrupt 0000:02:00.1[B] -> GSI 18 (level, low) -> IRQ 18
[   50.897545] PCI: Setting latency timer of device 0000:02:00.1 to 64
[   50.897589] scsi6 : pata_jmicron
[   50.897703] scsi7 : pata_jmicron
[   50.897797] ata7: PATA max UDMA/100 cmd 0x000000000001ac00 ctl 0x000000000001a882 bmdma 0x000000000001a400 irq 18
[   50.897846] ata8: PATA max UDMA/100 cmd 0x000000000001a800 ctl 0x000000000001a482 bmdma 0x000000000001a408 irq 18
[   51.204704] ata7.00: ATAPI: PLEXTOR DVD-ROM PX-130A, 1.03, max UDMA/33
[   51.362115] ata7.00: configured for UDMA/33
[   51.525071] scsi 6:0:0:0: CD-ROM            PLEXTOR  DVD-ROM PX-130A  1.03 PQ: 0 ANSI: 5
[   51.530639] sr1: scsi3-mmc drive: 0x/50x cd/rw xa/form2 cdda tray
[   51.530766] sr 6:0:0:0: Attached scsi CD-ROM sr1
[   51.530853] sr 6:0:0:0: Attached scsi generic sg4 type 5
Comment 38 Tejun Heo 2007-10-25 18:13:36 UTC
Alright.  It's all set then.  As written above, improved version of the workaround patch is merged for 2.6.24.  Also, it's been agreed to include it in 2.6.23.x stable release.  We'll wait one or two weeks such that the merged devel version gets some testing in -rc tree and will include the stable workaround.

Closing the bug.  Thanks.
Comment 39 Bjoern Olausson 2007-10-25 19:03:47 UTC
Thanks for the fix!

regards
Bjeorn
Comment 40 Fabian Zeindl 2007-10-26 02:20:19 UTC
Thanks from me too.
Comment 41 Matthew 2007-10-26 11:07:00 UTC
Thanks alot, Tejun, that finally fixes it (and gains at least 35 seconds during boot-up for me) =)
Comment 42 Bjoern Olausson 2007-10-29 18:48:51 UTC
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
!!!!!!Please be aware, there is still a hotpluging problem!!!!!!
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!

http://bugzilla.kernel.org/show_bug.cgi?id=9010
Comment 43 Tejun Heo 2007-10-29 18:52:52 UTC
Bjoern, you're talking about bug 9010, right?  Don't worry it's firmly stuck in my todo list.  :-)
Comment 44 Bjoern Olausson 2007-10-30 05:04:44 UTC
Okay, just wanted to let the others know... ;-) Cause the drive is not accessible when plugged twice. Thanks Tejun.
Comment 45 dino99 2008-04-17 02:27:15 UTC
That mobo don't boot correctly with 2.6.24-16 (ubuntu hardy)

https://bugs.launchpad.net/ubuntu/+source/linux/+bug/212834
https://bugs.launchpad.net/ubuntu/+source/linux/+bug/218079
https://bugs.launchpad.net/ubuntu/+source/linux/+bug/211346

i need these grub options: irqpoll & noprobe=ata4 to boot
Comment 46 Bjoern Olausson 2008-04-22 09:28:19 UTC
(In reply to comment #44)
> Okay, just wanted to let the others know... ;-) Cause the drive is not
> accessible when plugged twice. Thanks Tejun.
> 

(In reply to comment #45)
> That mobo don't boot correctly with 2.6.24-16 (ubuntu hardy)
> 
> https://bugs.launchpad.net/ubuntu/+source/linux/+bug/212834
> https://bugs.launchpad.net/ubuntu/+source/linux/+bug/218079
> https://bugs.launchpad.net/ubuntu/+source/linux/+bug/211346
> 
> i need these grub options: irqpoll & noprobe=ata4 to boot
> 

I will check this with 2.6.25.

Expect my results in a day or two.

regards
Bjoern
Comment 47 Bjoern Olausson 2008-04-22 14:25:53 UTC
Actually it does boot very fine.

BIOS 2602

Kernel 2.6.25 stock vanilla

Linux freax 2.6.25-unpatched #2 SMP PREEMPT Mon Apr 21 20:46:51 CEST 2008 x86_64 Intel(R) Core(TM)2 CPU 6600 @ 2.40GHz GenuineIntel GNU/Linux

boot options:
kernel (hd0,0)/vmlinuz root=/dev/sda3 video=nvidiafb:1280x1024-32@85,mtrr,ywrap


See attached p5w-DH-delux_dmesg.log and lshw.log for details

The only error I could find is (thought I don't know what RGL10364 is):
[    1.252722] ata2: SATA max UDMA/133 irq_stat 0x00400040, connection status changed irq 1274
[    4.783300] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[    4.783408] ata2.00: ATA-6: Config  Disk, RGL10364, max UDMA/133
[    4.783459] ata2.00: 640 sectors, multi 1: LBA
[    4.783500] ata2.00: device is on DMA blacklist, disabling DMA
[    4.783628] ata2.00: configured for PIO4
[   27.194668] ata2.00: exception Emask 0x10 SAct 0x0 SErr 0x10000 action 0xa frozen
[   27.194674] ata2.00: irq_stat 0x00400000, PHY RDY changed
[   27.194677] ata2: SError: { PHYRdyChg }
[   27.194684] ata2.00: cmd c4/00:08:80:01:00/00:00:00:00:00/e0 tag 0 pio 4096 in
[   27.194689] ata2.00: status: { DRDY }
[   27.194697] ata2: hard resetting link
[   27.916803] ata2: SATA link down (SStatus 0 SControl 300)
[   27.916813] ata2: failed to recover some devices, retrying in 5 secs
[   32.917045] ata2: hard resetting link
[   33.222000] ata2: SATA link down (SStatus 0 SControl 300)
[   33.222007] ata2: failed to recover some devices, retrying in 5 secs
[   38.222244] ata2: hard resetting link
[   40.680697] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[   40.680856] ata2.00: device is on DMA blacklist, disabling DMA
[   40.680988] ata2.00: configured for PIO4
[   40.680998] ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
[   40.681000] ata2: irq_stat 0x00000040, connection status changed
[   40.681173] ata2.00: device is on DMA blacklist, disabling DMA
[   40.681301] ata2.00: configured for PIO4
[   40.681307] ata2: EH complete


The coretemp message looks okay:

[   13.753094] coretemp coretemp.0: Using relative temperature scale!
[   13.753288] coretemp coretemp.1: Using relative temperature scale!
[   13.768994] w83627ehf: Found W83627DHG chip at 0x290


kind regards
Bjoern
Comment 48 Bjoern Olausson 2008-04-22 14:26:27 UTC
Created attachment 15850 [details]
p5w-DH-delux_dmesg.log
Comment 49 Bjoern Olausson 2008-04-22 14:26:40 UTC
Created attachment 15851 [details]
lshw.log
Comment 50 Tejun Heo 2008-04-22 22:50:27 UTC
Does switching to ahci mode help?
Comment 51 Bjoern Olausson 2008-04-23 12:32:10 UTC
On 4/23/08, bugme-daemon@bugzilla.kernel.org
<bugme-daemon@bugzilla.kernel.org> wrote:
> http://bugzilla.kernel.org/show_bug.cgi?id=8923
>
>
>
>
>
>
> ------- Comment #50 from htejun@gmail.com  2008-04-22 22:50 -------
>  Does switching to ahci mode help?
>
>

In my case, I am already running in AHCI mode.
Comment 52 Bjoern Olausson 2008-04-23 12:32:30 UTC
In my case, I am already running AHCI mode (I should have mentioned this)

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