Consistent PHYRdyChg error on second mSATA (BIOS / HW problem ?)

Dan Podeanu <[email protected]>
Newsgroups gmane.comp.hardware.soekris.technical
Message-ID <[email protected]>
I have just received two net6501-70 with identical configurations:

- net6501-70 Board and 1 Slot Standard Case
- Intel 30GB MLC mSATA SSD - SSDMCEAC030B3 (2 per box - for software RAID 1)
- lan1841, PCI Express Quad Gigabit ethernet board

While installing Linux x32 (3.15.1), during the initial software raid setup the second mSATA (scsi 1:0:0:0) drops out of raid after a few minutes of raid building. The behavior is 100% reproducible on both boxes. Furthermore, after encountering the error and soft-rebooting, the BIOS no longer sees the second mSATA drive (it does NOT appear in comBIOS device listing)- a hardware reboot (i.e. from button) or power cycle makes the disk available again.

The first mSATA (scsi 0:0:0:0) is stable.

The distro is Linux Gentoo i686 and I can reproduce it with both the supplied kernel or custom compiled. I can provide .config and any and all other information, or try anything you might suggest in order to further debug the issue.

Details below; please advise.

Thank you,
Dan


uname:

Linux speedy 3.15.1-net6501 #1 SMP PREEMPT Sat Jun 21 20:21:31 UTC 2014 i686 Genuine Intel(R) CPU @ 1.60GHz GenuineIntel GNU/Linux

dmesg error snippet:

Jun 21 21:49:58 speedy kernel: [ 2045.111438] ata2.00: exception Emask 0x10 SAct 0x18000 SErr 0x10300 action 0xe frozen
Jun 21 21:49:58 speedy kernel: [ 2045.111447] ata2.00: irq_stat 0x08400000, interface fatal error, PHY RDY changed
Jun 21 21:49:58 speedy kernel: [ 2045.111454] ata2: SError: { UnrecovData Persist PHYRdyChg }
Jun 21 21:49:58 speedy kernel: [ 2045.111462] ata2.00: failed command: WRITE FPDMA QUEUED
Jun 21 21:49:58 speedy kernel: [ 2045.111475] ata2.00: cmd 61/00:78:00:66:f4/04:00:00:00:00/40 tag 15 ncq 524288 out
Jun 21 21:49:58 speedy kernel: [ 2045.111475]          res 40/00:78:00:66:f4/00:00:00:00:00/40 Emask 0x10 (ATA bus error)
Jun 21 21:49:58 speedy kernel: [ 2045.111482] ata2.00: status: { DRDY }
Jun 21 21:49:58 speedy kernel: [ 2045.111488] ata2.00: failed command: WRITE FPDMA QUEUED
Jun 21 21:49:58 speedy kernel: [ 2045.111500] ata2.00: cmd 61/80:80:00:6a:f4/03:00:00:00:00/40 tag 16 ncq 458752 out
Jun 21 21:49:58 speedy kernel: [ 2045.111500]          res 40/00:78:00:66:f4/00:00:00:00:00/40 Emask 0x10 (ATA bus error)
Jun 21 21:49:58 speedy kernel: [ 2045.111507] ata2.00: status: { DRDY }
Jun 21 21:49:58 speedy kernel: [ 2045.111517] ata2: hard resetting link
Jun 21 21:49:59 speedy kernel: [ 2046.093300] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jun 21 21:49:59 speedy kernel: [ 2046.108273] ata2.00: configured for UDMA/133
Jun 21 21:49:59 speedy kernel: [ 2046.108319] ata2: EH complete
. . .
Jun 21 21:50:01 speedy kernel: [ 2048.015017] ata2: SATA link down (SStatus 0 SControl 300)
Jun 21 21:50:06 speedy kernel: [ 2053.014228] ata2: hard resetting link
Jun 21 21:50:06 speedy kernel: [ 2053.319216] ata2: SATA link down (SStatus 0 SControl 300)
Jun 21 21:50:06 speedy kernel: [ 2053.319239] ata2: limiting SATA link speed to 1.5 Gbps
Jun 21 21:50:11 speedy kernel: [ 2058.318433] ata2: hard resetting link
Jun 21 21:50:12 speedy kernel: [ 2058.623403] ata2: SATA link down (SStatus 0 SControl 310)
Jun 21 21:50:12 speedy kernel: [ 2058.623423] ata2.00: disabled
Jun 21 21:50:12 speedy kernel: [ 2058.623474] sd 1:0:0:0: [sdb]
Jun 21 21:50:12 speedy kernel: [ 2058.623479] Result: hostbyte=0x00 driverbyte=0x08
Jun 21 21:50:12 speedy kernel: [ 2058.623485] sd 1:0:0:0: [sdb]
Jun 21 21:50:12 speedy kernel: [ 2058.623488] Sense Key : 0xb [current] [descriptor]
Jun 21 21:50:12 speedy kernel: [ 2058.623495] Descriptor sense data with sense descriptors (in hex):
Jun 21 21:50:12 speedy kernel: [ 2058.623499]         72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
Jun 21 21:50:12 speedy kernel: [ 2058.623518]         00 f5 c2 80
Jun 21 21:50:12 speedy kernel: [ 2058.623527] sd 1:0:0:0: [sdb]
Jun 21 21:50:12 speedy kernel: [ 2058.623531] ASC=0x0 ASCQ=0x0
Jun 21 21:50:12 speedy kernel: [ 2058.623536] sd 1:0:0:0: [sdb] CDB:
Jun 21 21:50:12 speedy kernel: [ 2058.623540] cdb[0]=0x2a: 2a 00 00 f5 c2 80 00 04 00 00
Jun 21 21:50:12 speedy kernel: [ 2058.623556] end_request: I/O error, dev sdb, sector 16106112
Jun 21 21:50:12 speedy kernel: [ 2058.623622] sd 1:0:0:0: rejecting I/O to offline device
Jun 21 21:50:12 speedy kernel: [ 2058.623630] sd 1:0:0:0: [sdb] killing request
Jun 21 21:50:12 speedy kernel: [ 2058.623645] sd 1:0:0:0: rejecting I/O to offline device
Jun 21 21:50:12 speedy kernel: [ 2058.623694] sd 1:0:0:0: [sdb]
Jun 21 21:50:12 speedy kernel: [ 2058.623699] Result: hostbyte=0x00 driverbyte=0x08
Jun 21 21:50:12 speedy kernel: [ 2058.623704] sd 1:0:0:0: [sdb]
. . .
Jun 21 21:50:12 speedy kernel: [ 2058.623978] end_request: I/O error, dev sdb, sector 16108160
Jun 21 21:50:12 speedy kernel: [ 2058.624011] ata2.00: detaching (SCSI 1:0:0:0)
Jun 21 21:50:12 speedy kernel: [ 2058.631674] sd 1:0:0:0: [sdb] Synchronizing SCSI cache
Jun 21 21:50:12 speedy kernel: [ 2058.631818] sd 1:0:0:0: [sdb]
Jun 21 21:50:12 speedy kernel: [ 2058.631827] Result: hostbyte=0x04 driverbyte=0x00
Jun 21 21:50:12 speedy kernel: [ 2058.631835] sd 1:0:0:0: [sdb] Stopping disk
Jun 21 21:50:12 speedy kernel: [ 2058.631875] sd 1:0:0:0: [sdb] START_STOP FAILED
Jun 21 21:50:12 speedy kernel: [ 2058.631885] sd 1:0:0:0: [sdb]
Jun 21 21:50:12 speedy kernel: [ 2058.631891] Result: hostbyte=0x04 driverbyte=0x00

dmesg boot snippet:

Jun 21 21:07:36 speedy kernel: [    4.260721] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jun 21 21:07:36 speedy kernel: [    4.271704] ata2.00: ATA-9: INTEL SSDMCEAC030B3, LLLi, max UDMA/133
Jun 21 21:07:36 speedy kernel: [    4.271704] ata2.00: 58626288 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
Jun 21 21:07:36 speedy kernel: [    4.281700] ata2.00: configured for UDMA/133
Jun 21 21:07:36 speedy kernel: [    4.281927] scsi 1:0:0:0: Direct-Access     ATA      INTEL SSDMCEAC03 LLLi PQ: 0 ANSI: 5
Jun 21 21:07:36 speedy kernel: [    4.283428] sd 1:0:0:0: [sdb] 58626288 512-byte logical blocks: (30.0 GB/27.9 GiB)
Jun 21 21:07:36 speedy kernel: [    4.283701] sd 1:0:0:0: [sdb] Write Protect is off
Jun 21 21:07:36 speedy kernel: [    4.283707] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Jun 21 21:07:36 speedy kernel: [    4.283790] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Jun 21 21:07:36 speedy kernel: [    4.284545] sd 1:0:0:0: Attached scsi generic sg1 type 0
Jun 21 21:07:36 speedy kernel: [    4.284692]  sdb: unknown partition table
Jun 21 21:07:36 speedy kernel: [    4.285311] sd 1:0:0:0: [sdb] Attached SCSI disk
lmpx.com only provides a reader for public news (NNTP) servers. It is not affiliated with the servers or forums shown here and is not responsible for the content of articles, which is written by their respective authors.