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
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.
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
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
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
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
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
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
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
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.
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
Created attachment 12565 [details] bootlog First reboot with patch asus-p5w-dh-quirk-take-2.patch
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.
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.
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?
- 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 ;-)
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.
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.
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.
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)
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
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!
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
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.
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
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.
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....
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.
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
*** Bug 9142 has been marked as a duplicate of this bug. ***
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.
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...
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.
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
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.
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?
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?
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
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.
Thanks for the fix! regards Bjeorn
Thanks from me too.
Thanks alot, Tejun, that finally fixes it (and gains at least 35 seconds during boot-up for me) =)
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! !!!!!!Please be aware, there is still a hotpluging problem!!!!!! !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! http://bugzilla.kernel.org/show_bug.cgi?id=9010
Bjoern, you're talking about bug 9010, right? Don't worry it's firmly stuck in my todo list. :-)
Okay, just wanted to let the others know... ;-) Cause the drive is not accessible when plugged twice. Thanks Tejun.
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
(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
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
Created attachment 15850 [details] p5w-DH-delux_dmesg.log
Created attachment 15851 [details] lshw.log
Does switching to ahci mode help?
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.
In my case, I am already running AHCI mode (I should have mentioned this)