@Joseph
Thanks for the help! I did use the same cable for all my testing.
I've also finally made the time to test the kernels up to v3.1-rc9-oneiric.
This bug seems to persist upstream. Here is the relevant info from my dmesg on
v3.1-rc9:
[ 11.432788] ata10: softreset failed (device not ready)
[ 21.423641] ata10: softreset failed (device not ready)
[ 31.973317] ata10: link is slow to respond, please be patient (ready=0)
[ 56.393626] ata10: softreset failed (device not ready)
[ 56.393692] ata10: limiting SATA link speed to 1.5 Gbps
[ 61.570669] ata10: softreset failed (device not ready)
[ 61.570735] ata10: reset failed, giving up
** Description changed:
Hello Developers,
So I've had a ton of problems with my eSata external HD using the
JMicron JMB363 chipset on my motherboard. This drive works fine under
Windows 7 on the same computer and Ubuntu 11.04x64 on a different
computer. It definitely seems to be the JMicron drivers that are
failing.
After a lot of guess and checking, I finally figured out a procedure the
"reliably" gets the drive to link, attach, and mount:
1. Power on drive enclosure
2. Check dmesg for errors
3. If there are errors, power off drive enclosure
4. Repeat until successful mount
The drive always fails to attach if I don't power it off while it is
negotiating the link. When booting the computer, it always hangs until
the kernel finally gives up trying to attach the drive. Here are my
relevant dmesg Logs to show you the typical process:
<---------------------------------------------------------------------------------------------------------------------------------->
Power On Drive Enclosure After System Has Booted:
<---------------------------------------------------------------------------------------------------------------------------------->
[ 9958.483128] ata10: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe
frozen
[ 9958.483134] ata10: irq_stat 0x00400040, connection status changed
[ 9958.483139] ata10: SError: { PHYRdyChg CommWake DevExch }
[ 9958.483149] ata10: hard resetting link
[ 9968.480612] ata10: softreset failed (device not ready)
[ 9968.480619] ata10: hard resetting link
[ 9971.045226] ata10: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 9971.061300] ata10.00: ATA-6: WDC WD10EACS-00D6B0, 01.01A01, max UDMA/133
[ 9971.061306] ata10.00: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
[ 9971.079132] ata10.00: configured for UDMA/133
[ 9971.079141] ata10: EH complete
[ 9971.079219] scsi 9:0:0:0: Direct-Access ATA WDC WD10EACS-00D 01.0
PQ: 0 ANSI: 5
[ 9971.079363] sd 9:0:0:0: Attached scsi generic sg5 type 0
[ 9971.079399] sd 9:0:0:0: [sde] 1953525168 512-byte logical blocks: (1.00
TB/931 GiB)
[ 9971.079915] sd 9:0:0:0: [sde] Write Protect is off
[ 9971.079918] sd 9:0:0:0: [sde] Mode Sense: 00 3a 00 00
[ 9971.080257] sd 9:0:0:0: [sde] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 9971.094446] sde: sde1
[ 9971.094906] sd 9:0:0:0: [sde] Attached SCSI disk
[ 9971.154226] ata10.00: exception Emask 0x10 SAct 0x1 SErr 0x780100 action
0x6
[ 9971.154232] ata10.00: irq_stat 0x08000000
[ 9971.154238] ata10: SError: { UnrecovData 10B8B Dispar BadCRC Handshk }
[ 9971.154243] ata10.00: failed command: READ FPDMA QUEUED
[ 9971.154252] ata10.00: cmd 60/08:00:20:6c:70/00:00:74:00:00/40 tag 0 ncq
4096 in
[ 9971.154254] res 40/00:04:20:6c:70/00:00:74:00:00/40 Emask 0x10
(ATA bus error)
[ 9971.154258] ata10.00: status: { DRDY }
[ 9971.154265] ata10: hard resetting link
[ 9971.494187] ata10: SATA link down (SStatus 0 SControl 300)
[ 9971.664264] ata10: hard resetting link
[ 9981.682657] ata10: softreset failed (device not ready)
[ 9981.682661] ata10: hard resetting link
[ 9991.721471] ata10: softreset failed (device not ready)
[ 9991.721478] ata10: hard resetting link
[10002.358879] ata10: link is slow to respond, please be patient (ready=0)
[10026.677542] ata10: softreset failed (device not ready)
[10026.677551] ata10: limiting SATA link speed to 1.5 Gbps
[10026.677555] ata10: hard resetting link
[10031.876402] ata10: softreset failed (device not ready)
[10031.876408] ata10: reset failed, giving up
[10031.876412] ata10.00: disabled
[10031.876429] ata10: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe
frozen t4
[10031.876433] ata10: irq_stat 0x00400040, connection status changed
[10031.876438] ata10: SError: { PHYRdyChg CommWake DevExch }
[10031.876446] ata10: hard resetting link
[10041.875270] ata10: softreset failed (device not ready)
[10041.875277] ata10: hard resetting link
[10051.874161] ata10: softreset failed (device not ready)
[10051.874168] ata10: hard resetting link
[10062.910707] ata10: link is slow to respond, please be patient (ready=0)
[10086.820143] ata10: softreset failed (device not ready)
[10086.820152] ata10: limiting SATA link speed to 1.5 Gbps
[10086.820156] ata10: hard resetting link
[10092.009146] ata10: softreset failed (device not ready)
[10092.009153] ata10: reset failed, giving up
[10092.009167] sd 9:0:0:0: [sde] Result: hostbyte=DID_OK
driverbyte=DRIVER_SENSE
[10092.009173] sd 9:0:0:0: [sde] Sense Key : Aborted Command [current]
[descriptor]
[10092.009179] Descriptor sense data with sense descriptors (in hex):
- [10092.009182] 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
- [10092.009195] 74 70 6c 20
+ [10092.009182] 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
+ [10092.009195] 74 70 6c 20
[10092.009198] sd 9:0:0:0: [sde] Add. Sense: No additional sense information
[10092.009201] sd 9:0:0:0: [sde] CDB: Read(10): 28 00 74 70 6c 20 00 00 08 00
[10092.009206] end_request: I/O error, dev sde, sector 1953524768
[10092.009208] quiet_error: 30 callbacks suppressed
[10092.009210] Buffer I/O error on device sde, logical block 244190596
[10092.009220] ata10: EH complete
[10092.009226] ata10.00: detaching (SCSI 9:0:0:0)
[10092.049300] sd 9:0:0:0: [sde] Synchronizing SCSI cache
[10092.049333] sd 9:0:0:0: [sde] Result: hostbyte=DID_BAD_TARGET
driverbyte=DRIVER_OK
[10092.049336] sd 9:0:0:0: [sde] Stopping disk
[10092.049342] sd 9:0:0:0: [sde] START_STOP FAILED
[10092.049344] sd 9:0:0:0: [sde] Result: hostbyte=DID_BAD_TARGET
driverbyte=DRIVER_OK
-
<---------------------------------------------------------------------------------------------------------------------------------->
Cycle Power On Drive Enclosure and run: echo "- - - " >
/sys/class/scsi_host/host9/scan:
<---------------------------------------------------------------------------------------------------------------------------------->
[10352.398234] ata10: exception Emask 0x10 SAct 0x0 SErr 0x49d0000 action 0xe
frozen
[10352.398238] ata10: SError: { PHYRdyChg CommWake 10B8B Dispar LinkSeq
DevExch }
[10352.398243] ata10: hard resetting link
[10357.197939] ata10: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[10357.213968] ata10.00: ATA-6: WDC WD10EACS-00D6B0, 01.01A01, max UDMA/133
[10357.213974] ata10.00: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
[10357.231866] ata10.00: configured for UDMA/133
[10357.231879] ata10: EH complete
[10357.231996] scsi 9:0:0:0: Direct-Access ATA WDC WD10EACS-00D 01.0
PQ: 0 ANSI: 5
[10357.232151] sd 9:0:0:0: Attached scsi generic sg5 type 0
[10357.232183] sd 9:0:0:0: [sde] 1953525168 512-byte logical blocks: (1.00
TB/931 GiB)
[10357.232563] sd 9:0:0:0: [sde] Write Protect is off
[10357.232566] sd 9:0:0:0: [sde] Mode Sense: 00 3a 00 00
[10357.232726] sd 9:0:0:0: [sde] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[10357.248108] sde: sde1
[10357.248589] sd 9:0:0:0: [sde] Attached SCSI disk
[10357.265339] ata10.00: exception Emask 0x10 SAct 0x0 SErr 0x780100 action
0x6
[10357.265345] ata10.00: irq_stat 0x08000000
[10357.265350] ata10: SError: { UnrecovData 10B8B Dispar BadCRC Handshk }
[10357.265355] ata10.00: failed command: IDENTIFY DEVICE
[10357.265363] ata10.00: cmd ec/00:01:00:00:00/00:00:00:00:00/00 tag 0 pio
512 in
[10357.265365] res 40/00:04:00:00:00/00:00:00:00:00/40 Emask 0x10
(ATA bus error)
[10357.265370] ata10.00: status: { DRDY }
[10357.265376] ata10: hard resetting link
[10361.508732] ata10: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[10361.524726] ata10.00: failed to IDENTIFY (I/O error, err_mask=0x100)
[10361.524731] ata10.00: revalidation failed (errno=-5)
[10366.498217] ata10: hard resetting link
[10367.845405] ata10: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[10367.879251] ata10.00: configured for UDMA/133
[10367.879278] ata10: EH complete
[10367.903677] ata10.00: exception Emask 0x10 SAct 0x1 SErr 0x780100 action
0x6
[10367.903682] ata10.00: irq_stat 0x08000000
[10367.903688] ata10: SError: { UnrecovData 10B8B Dispar BadCRC Handshk }
[10367.903693] ata10.00: failed command: READ FPDMA QUEUED
[10367.903702] ata10.00: cmd 60/08:00:00:00:00/00:00:00:00:00/40 tag 0 ncq
4096 in
[10367.903704] res 40/00:04:00:00:00/00:00:00:00:00/40 Emask 0x10
(ATA bus error)
[10367.903708] ata10.00: status: { DRDY }
[10367.903714] ata10: hard resetting link
[10368.244444] ata10: SATA link down (SStatus 0 SControl 300)
[10368.413724] ata10: hard resetting link
[10378.393011] ata10: softreset failed (device not ready)
[10378.393017] ata10: hard resetting link
[10388.381792] ata10: softreset failed (device not ready)
[10388.381799] ata10: hard resetting link
[10398.999262] ata10: link is slow to respond, please be patient (ready=0)
[10423.347892] ata10: softreset failed (device not ready)
[10423.347900] ata10: limiting SATA link speed to 1.5 Gbps
[10423.347904] ata10: hard resetting link
[10428.546766] ata10: softreset failed (device not ready)
[10428.546772] ata10: reset failed, giving up
[10428.546776] ata10.00: disabled
[10428.546794] ata10: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe
frozen t4
[10428.546798] ata10: irq_stat 0x00400040, connection status changed
[10428.546803] ata10: SError: { PHYRdyChg CommWake DevExch }
[10428.546812] ata10: hard resetting link
[10438.535561] ata10: softreset failed (device not ready)
[10438.535565] ata10: hard resetting link
[10448.524459] ata10: softreset failed (device not ready)
[10448.524466] ata10: hard resetting link
[10459.551143] ata10: link is slow to respond, please be patient (ready=0)
[10483.490444] ata10: softreset failed (device not ready)
[10483.490453] ata10: limiting SATA link speed to 1.5 Gbps
[10483.490456] ata10: hard resetting link
[10488.669499] ata10: softreset failed (device not ready)
[10488.669503] ata10: reset failed, giving up
[10488.669519] sd 9:0:0:0: [sde] Result: hostbyte=DID_OK
driverbyte=DRIVER_SENSE
[10488.669525] sd 9:0:0:0: [sde] Sense Key : Aborted Command [current]
[descriptor]
[10488.669531] Descriptor sense data with sense descriptors (in hex):
- [10488.669534] 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
- [10488.669547] 00 00 00 00
+ [10488.669534] 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
+ [10488.669547] 00 00 00 00
[10488.669552] sd 9:0:0:0: [sde] Add. Sense: No additional sense information
[10488.669558] sd 9:0:0:0: [sde] CDB: Read(10): 28 00 00 00 00 00 00 00 08 00
[10488.669569] end_request: I/O error, dev sde, sector 0
[10488.669574] Buffer I/O error on device sde, logical block 0
[10488.669589] ata10: EH complete
[10488.669595] ata10.00: detaching (SCSI 9:0:0:0)
[10488.699638] sd 9:0:0:0: [sde] Synchronizing SCSI cache
[10488.699669] sd 9:0:0:0: [sde] Result: hostbyte=DID_BAD_TARGET
driverbyte=DRIVER_OK
[10488.699672] sd 9:0:0:0: [sde] Stopping disk
[10488.699678] sd 9:0:0:0: [sde] START_STOP FAILED
[10488.699680] sd 9:0:0:0: [sde] Result: hostbyte=DID_BAD_TARGET
driverbyte=DRIVER_OK
<---------------------------------------------------------------------------------------------------------------------------------->
Run echo "- - - " > /sys/class/scsi_host/host9/scan:
<---------------------------------------------------------------------------------------------------------------------------------->
[10684.515312] ata10: hard resetting link
[10694.553704] ata10: softreset failed (device not ready)
[10694.553708] ata10: hard resetting link
<---------------------------------------------------------------------------------------------------------------------------------->
Cycle Power On Drive Enclosure Again:
<---------------------------------------------------------------------------------------------------------------------------------->
[10700.800556] ata10: SATA link down (SStatus 0 SControl 300)
[10700.800562] ata10: illegal qc_active transition (00000000->00000001)
[10700.800583] ata10: EH complete
[10704.230891] ata10: exception Emask 0x10 SAct 0x0 SErr 0x4050000 action 0xe
frozen
[10704.230894] ata10: irq_stat 0x00400040, connection status changed
[10704.230897] ata10: SError: { PHYRdyChg CommWake DevExch }
[10704.230902] ata10: hard resetting link
[10713.872802] ata10: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[10713.888789] ata10.00: ATA-6: WDC WD10EACS-00D6B0, 01.01A01, max UDMA/133
[10713.888795] ata10.00: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32)
[10713.906681] ata10.00: configured for UDMA/133
[10713.906689] ata10: EH complete
[10713.906757] scsi 9:0:0:0: Direct-Access ATA WDC WD10EACS-00D 01.0
PQ: 0 ANSI: 5
[10713.906906] sd 9:0:0:0: Attached scsi generic sg5 type 0
[10713.907049] sd 9:0:0:0: [sde] 1953525168 512-byte logical blocks: (1.00
TB/931 GiB)
[10713.907865] sd 9:0:0:0: [sde] Write Protect is off
[10713.907868] sd 9:0:0:0: [sde] Mode Sense: 00 3a 00 00
[10713.907978] sd 9:0:0:0: [sde] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[10713.922416] sde: sde1
[10713.923079] sd 9:0:0:0: [sde] Attached SCSI disk
[10713.973617] ata10.00: exception Emask 0x10 SAct 0x1 SErr 0x780100 action
0x6
[10713.973623] ata10.00: irq_stat 0x08000000
[10713.973629] ata10: SError: { UnrecovData 10B8B Dispar BadCRC Handshk }
[10713.973634] ata10.00: failed command: READ FPDMA QUEUED
[10713.973643] ata10.00: cmd 60/08:00:a8:6d:70/00:00:74:00:00/40 tag 0 ncq
4096 in
[10713.973645] res 40/00:04:a8:6d:70/00:00:74:00:00/40 Emask 0x10
(ATA bus error)
[10713.973649] ata10.00: status: { DRDY }
[10713.973656] ata10: hard resetting link
[10714.321865] ata10: SATA link down (SStatus 0 SControl 300)
[10714.483675] ata10: hard resetting link
[10724.500427] ata10: softreset failed (device not ready)
[10724.500435] ata10: hard resetting link
[10734.529142] ata10: softreset failed (device not ready)
[10734.529149] ata10: hard resetting link
<---------------------------------------------------------------------------------------------------------------------------------->
Cycle Power On Drive Enclosure Again:
<---------------------------------------------------------------------------------------------------------------------------------->
[10745.176555] ata10: link is slow to respond, please be patient (ready=0)
[10764.226341] ata10: SATA link down (SStatus 0 SControl 300)
[10764.226355] ata10: limiting SATA link speed to 1.5 Gbps
[10767.741953] ata10: hard resetting link
[10777.458144] ata10: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[10777.492085] ata10.00: configured for UDMA/133
[10777.492104] ata10: EH complete
<---------------------------------------------------------------------------------------------------------------------------------->
Success!
<---------------------------------------------------------------------------------------------------------------------------------->
Anyway we can get this to be a little more auto-magical?
ProblemType: Bug
DistroRelease: Ubuntu 11.04
Package: linux-image-2.6.38-11-generic 2.6.38-11.50
ProcVersionSignature: Ubuntu 2.6.38-11.50-generic 2.6.38.8
Uname: Linux 2.6.38-11-generic x86_64
NonfreeKernelModules: nvidia
AlsaVersion: Advanced Linux Sound Architecture Driver Version 1.0.23.
Architecture: amd64
ArecordDevices:
- **** List of CAPTURE Hardware Devices ****
- card 0: Intel [HDA Intel], device 0: VT1828S Analog [VT1828S Analog]
- Subdevices: 2/2
- Subdevice #0: subdevice #0
- Subdevice #1: subdevice #1
+ **** List of CAPTURE Hardware Devices ****
+ card 0: Intel [HDA Intel], device 0: VT1828S Analog [VT1828S Analog]
+ Subdevices: 2/2
+ Subdevice #0: subdevice #0
+ Subdevice #1: subdevice #1
AudioDevicesInUse:
- USER PID ACCESS COMMAND
- /dev/snd/controlC0: matt 1819 F.... pulseaudio
+ USER PID ACCESS COMMAND
+ /dev/snd/controlC0: matt 1819 F.... pulseaudio
CRDA: Error: [Errno 2] No such file or directory
Card0.Amixer.info:
- Card hw:0 'Intel'/'HDA Intel at 0xf3ff8000 irq 47'
- Mixer name : 'VIA VT1828S'
- Components : 'HDA:11064441,104383d0,00100100'
- Controls : 36
- Simple ctrls : 21
+ Card hw:0 'Intel'/'HDA Intel at 0xf3ff8000 irq 47'
+ Mixer name : 'VIA VT1828S'
+ Components : 'HDA:11064441,104383d0,00100100'
+ Controls : 36
+ Simple ctrls : 21
Date: Fri Oct 7 15:08:58 2011
HibernationDevice: RESUME=UUID=444e9f37-3e28-48b1-a421-8215fb39fe5c
HotplugNewDevices:
-
+
HotplugNewMounts: /dev/sde1 /media/ProDrive fuseblk
rw,nosuid,nodev,relatime,user_id=0,group_id=0,default_permissions,allow_other,blksize=4096
0 0
InstallationMedia: Ubuntu 11.04 "Natty Narwhal" - Release amd64 (20110427.1)
IwConfig:
- lo no wireless extensions.
-
- eth0 no wireless extensions.
-
- vboxnet0 no wireless extensions.
+ lo no wireless extensions.
+
+ eth0 no wireless extensions.
+
+ vboxnet0 no wireless extensions.
MachineType: System manufacturer System Product Name
ProcEnviron:
- LANGUAGE=en_US:en
- LANG=en_US.UTF-8
- SHELL=/bin/bash
+ LANGUAGE=en_US:en
+ LANG=en_US.UTF-8
+ SHELL=/bin/bash
ProcKernelCmdLine: BOOT_IMAGE=/boot/vmlinuz-2.6.38-11-generic
root=UUID=a38c41c6-ad2a-4714-9167-fc1e8e0e7e54 ro quiet splash vt.handoff=7
RelatedPackageVersions:
- linux-restricted-modules-2.6.38-11-generic N/A
- linux-backports-modules-2.6.38-11-generic N/A
- linux-firmware 1.52
+ linux-restricted-modules-2.6.38-11-generic N/A
+ linux-backports-modules-2.6.38-11-generic N/A
+ linux-firmware 1.52
RfKill:
- 0: hci0: Bluetooth
- Soft blocked: no
- Hard blocked: no
+ 0: hci0: Bluetooth
+ Soft blocked: no
+ Hard blocked: no
SourcePackage: linux
Symptom: storage
UpgradeStatus: No upgrade log present (probably fresh install)
dmi.bios.date: 07/22/2010
dmi.bios.vendor: American Megatrends Inc.
dmi.bios.version: 1807
dmi.board.asset.tag: To Be Filled By O.E.M.
dmi.board.name: P7P55D
dmi.board.vendor: ASUSTeK Computer INC.
dmi.board.version: Rev 1.xx
dmi.chassis.asset.tag: Asset-1234567890
dmi.chassis.type: 3
dmi.chassis.vendor: Chassis Manufacture
dmi.chassis.version: Chassis Version
dmi.modalias:
dmi:bvnAmericanMegatrendsInc.:bvr1807:bd07/22/2010:svnSystemmanufacturer:pnSystemProductName:pvrSystemVersion:rvnASUSTeKComputerINC.:rnP7P55D:rvrRev1.xx:cvnChassisManufacture:ct3:cvrChassisVersion:
dmi.product.name: System Product Name
dmi.product.version: System Version
dmi.sys.vendor: System manufacturer
** Tags removed: needs-upstream-testing
--
You received this bug notification because you are a member of Ubuntu
Bugs, which is subscribed to Ubuntu.
https://bugs.launchpad.net/bugs/870246
Title:
JMicron JMB363 eSata HotSwap - SATA Link Failing
To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/linux/+bug/870246/+subscriptions
--
ubuntu-bugs mailing list
[email protected]
https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs
