Arch Linux

neverov

Member

Registered: 2012-03-28

Posts: 4

Problem with ext4 on SSD

Hi!

I'm getting following errors on OCZ Vertex3 in my laptop:

errors.log:

Apr  1 16:08:46 localhost kernel: [20782.731284] ata1.00: exception Emask 0x42 SAct 0x1fff SErr 0x1880c00 action 0x6 frozen
Apr  1 16:08:46 localhost kernel: [20782.731292] ata1: SError: { Proto HostInt 10B8B LinkSeq TrStaTrns }
Apr  1 16:08:46 localhost kernel: [20782.731298] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731308] ata1.00: cmd 61/58:00:00:1c:38/02:00:00:00:00/40 tag 0 ncq 307200 out
Apr  1 16:08:46 localhost kernel: [20782.731310]          res 40/00:00:00:19:bd/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731315] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731318] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731327] ata1.00: cmd 61/08:08:00:08:00/00:00:00:00:00/40 tag 1 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731329]          res 40/00:08:60:90:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731334] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731337] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731346] ata1.00: cmd 61/08:10:f0:1f:34/00:00:09:00:00/40 tag 2 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731348]          res 40/00:10:18:c1:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731352] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731356] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731364] ata1.00: cmd 61/08:18:08:08:00/00:00:00:00:00/40 tag 3 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731366]          res 40/00:48:18:c1:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731371] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731374] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731383] ata1.00: cmd 61/08:20:28:08:00/00:00:00:00:00/40 tag 4 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731385]          res 40/00:48:18:c1:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731389] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731393] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731401] ata1.00: cmd 61/08:28:00:08:38/00:00:00:00:00/40 tag 5 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731403]          res 40/00:30:00:08:fc/00:00:03:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731407] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731411] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731420] ata1.00: cmd 61/08:30:08:08:34/00:00:09:00:00/40 tag 6 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731422]          res 40/00:30:00:08:fc/00:00:03:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731426] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731430] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731438] ata1.00: cmd 61/10:38:50:08:34/00:00:09:00:00/40 tag 7 ncq 8192 out
Apr  1 16:08:46 localhost kernel: [20782.731440]          res 40/00:48:18:c1:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731444] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731448] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731456] ata1.00: cmd 61/08:40:68:08:34/00:00:09:00:00/40 tag 8 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731459]          res 40/00:48:18:c1:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731463] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731466] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731475] ata1.00: cmd 61/08:48:50:1c:34/00:00:09:00:00/40 tag 9 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731477]          res 40/00:48:18:c1:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731481] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731485] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731494] ata1.00: cmd 61/08:50:30:1d:fe/00:00:03:00:00/40 tag 10 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731496]          res 40/00:60:50:1c:34/00:00:09:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731500] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731504] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731512] ata1.00: cmd 61/10:58:50:1d:fe/00:00:03:00:00/40 tag 11 ncq 8192 out
Apr  1 16:08:46 localhost kernel: [20782.731514]          res 40/00:60:50:1c:34/00:00:09:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731518] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731522] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731530] ata1.00: cmd 61/c8:60:80:d1:28/01:00:00:00:00/40 tag 12 ncq 233472 out
Apr  1 16:08:46 localhost kernel: [20782.731533]          res 40/00:60:50:1c:34/00:00:09:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731537] ata1.00: status: { DRDY }

messages.log:

Apr  1 16:08:46 localhost kernel: [20782.731543] ata1: hard resetting link
Apr  1 16:08:46 localhost kernel: [20783.051140] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Apr  1 16:08:46 localhost kernel: [20783.052889] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Apr  1 16:08:46 localhost kernel: [20783.052895] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Apr  1 16:08:46 localhost kernel: [20783.072825] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Apr  1 16:08:46 localhost kernel: [20783.072831] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Apr  1 16:08:46 localhost kernel: [20783.082795] ata1.00: configured for UDMA/33
Apr  1 16:08:46 localhost kernel: [20783.082826] ata1: EH complete

kernel.log:

Apr  1 16:08:46 localhost kernel: [20782.731284] ata1.00: exception Emask 0x42 SAct 0x1fff SErr 0x1880c00 action 0x6 frozen
Apr  1 16:08:46 localhost kernel: [20782.731292] ata1: SError: { Proto HostInt 10B8B LinkSeq TrStaTrns }
Apr  1 16:08:46 localhost kernel: [20782.731298] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731308] ata1.00: cmd 61/58:00:00:1c:38/02:00:00:00:00/40 tag 0 ncq 307200 out
Apr  1 16:08:46 localhost kernel: [20782.731310]          res 40/00:00:00:19:bd/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731315] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731318] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731327] ata1.00: cmd 61/08:08:00:08:00/00:00:00:00:00/40 tag 1 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731329]          res 40/00:08:60:90:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731334] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731337] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731346] ata1.00: cmd 61/08:10:f0:1f:34/00:00:09:00:00/40 tag 2 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731348]          res 40/00:10:18:c1:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731352] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731356] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731364] ata1.00: cmd 61/08:18:08:08:00/00:00:00:00:00/40 tag 3 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731366]          res 40/00:48:18:c1:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731371] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731374] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731383] ata1.00: cmd 61/08:20:28:08:00/00:00:00:00:00/40 tag 4 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731385]          res 40/00:48:18:c1:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731389] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731393] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731401] ata1.00: cmd 61/08:28:00:08:38/00:00:00:00:00/40 tag 5 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731403]          res 40/00:30:00:08:fc/00:00:03:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731407] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731411] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731420] ata1.00: cmd 61/08:30:08:08:34/00:00:09:00:00/40 tag 6 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731422]          res 40/00:30:00:08:fc/00:00:03:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731426] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731430] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731438] ata1.00: cmd 61/10:38:50:08:34/00:00:09:00:00/40 tag 7 ncq 8192 out
Apr  1 16:08:46 localhost kernel: [20782.731440]          res 40/00:48:18:c1:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731444] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731448] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731456] ata1.00: cmd 61/08:40:68:08:34/00:00:09:00:00/40 tag 8 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731459]          res 40/00:48:18:c1:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731463] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731466] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731475] ata1.00: cmd 61/08:48:50:1c:34/00:00:09:00:00/40 tag 9 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731477]          res 40/00:48:18:c1:bc/00:00:07:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731481] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731485] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731494] ata1.00: cmd 61/08:50:30:1d:fe/00:00:03:00:00/40 tag 10 ncq 4096 out
Apr  1 16:08:46 localhost kernel: [20782.731496]          res 40/00:60:50:1c:34/00:00:09:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731500] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731504] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731512] ata1.00: cmd 61/10:58:50:1d:fe/00:00:03:00:00/40 tag 11 ncq 8192 out
Apr  1 16:08:46 localhost kernel: [20782.731514]          res 40/00:60:50:1c:34/00:00:09:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731518] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731522] ata1.00: failed command: WRITE FPDMA QUEUED
Apr  1 16:08:46 localhost kernel: [20782.731530] ata1.00: cmd 61/c8:60:80:d1:28/01:00:00:00:00/40 tag 12 ncq 233472 out
Apr  1 16:08:46 localhost kernel: [20782.731533]          res 40/00:60:50:1c:34/00:00:09:00:00/40 Emask 0x46 (timeout)
Apr  1 16:08:46 localhost kernel: [20782.731537] ata1.00: status: { DRDY }
Apr  1 16:08:46 localhost kernel: [20782.731543] ata1: hard resetting link
Apr  1 16:08:46 localhost kernel: [20783.051140] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
Apr  1 16:08:46 localhost kernel: [20783.052881] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Apr  1 16:08:46 localhost kernel: [20783.052889] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Apr  1 16:08:46 localhost kernel: [20783.052895] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Apr  1 16:08:46 localhost kernel: [20783.072819] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Apr  1 16:08:46 localhost kernel: [20783.072825] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Apr  1 16:08:46 localhost kernel: [20783.072831] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Apr  1 16:08:46 localhost kernel: [20783.082795] ata1.00: configured for UDMA/33
Apr  1 16:08:46 localhost kernel: [20783.082826] ata1: EH complete

My fstab is:

tmpfs		/tmp	tmpfs	nodev,nosuid	0	0
/dev/sda1 / ext4 defaults,noatime,discard,commit=60 0 1

I found a following bug with similiar errors (created 2 years ago): https://bugs.launchpad.net/ubuntu/+sour … bug/550559, but it doesn't contain solution..

Sometimes I see the following errors:

EXT4-fs error (device sda1): ext4_read_lock_bitmap:394: comm flush-8:0 Cannot read block bitmap_block group=548, block bitmap = 17956864
EXT4-fs (sda1): delayed block allocation failed for inode 1706540 at logical offset 593 with max blocks 9 with error -5
EXT4-fs (sda1): This should not happen!!! Data will be lost

S.M.A.R.T doesn't show any errors, also I get errors like the latter with my previous SSD OCZ Vertex2, so I don't think the problem is in SSD itself.

Can somebody explain what cause these errors and what to do about them?

neverov

Member

Registered: 2012-03-28

Posts: 4

Re: Problem with ext4 on SSD

Now it hangs with different error :

rejecting I/O to offline device.
__ext4_get_inode_loc: 3649: inode #1706556: block 6815909: comm syslog_ng: unable to read itable block.

I suspect that the problem is in motherboard. Because after reboot everything works fine and disk pass SMART test. How can I diagnose motherboard defects?

depeha

Member

Registered: 2011-06-20

Posts: 9

Re: Problem with ext4 on SSD

Same problem here. I´ve bought OCZ Agility3 yesterday. Since first first boot, everything was OK. But today I've got long error logs all around.

uname:

Linux matej-arch 3.3.1-1-ARCH #1 SMP PREEMPT Tue Apr 3 06:46:17 UTC 2012 x86_64 Intel(R) Core(TM) i5 CPU M 450 @ 2.40GHz GenuineIntel GNU/Linux

current dmesg:

[   71.286830] ata1: hard resetting link
[   72.004761] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[   72.026792] ata1.00: configured for UDMA/33
[   72.027213] ata1: EH complete
[   72.049552] EXT4-fs (sda1): re-mounted. Opts: commit=0
[   72.055470] ata1.00: exception Emask 0x10 SAct 0x1 SErr 0x48c0000 action 0xe frozen
[   72.055475] ata1.00: irq_stat 0x08000040, interface fatal error, connection status changed
[   72.055479] ata1: SError: { CommWake 10B8B LinkSeq DevExch }
[   72.055484] ata1.00: failed command: WRITE FPDMA QUEUED
[   72.055491] ata1.00: cmd 61/08:00:70:20:ff/00:00:03:00:00/40 tag 0 ncq 4096 out
[   72.055492]          res 40/00:00:70:20:ff/00:00:03:00:00/40 Emask 0x10 (ATA bus error)
[   72.055495] ata1.00: status: { DRDY }
[   72.055502] ata1: hard resetting link
[   72.773531] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[   72.795631] ata1.00: configured for UDMA/33
[   72.796093] ata1: EH complete
[   72.805831] EXT4-fs (sda2): re-mounted. Opts: user_xattr,commit=0
[  271.476801] ata1.00: exception Emask 0x10 SAct 0x7 SErr 0x48d0000 action 0xe frozen
[  271.476808] ata1.00: irq_stat 0x08400040, interface fatal error, connection status changed
[  271.476814] ata1: SError: { PHYRdyChg CommWake 10B8B LinkSeq DevExch }
[  271.476820] ata1.00: failed command: WRITE FPDMA QUEUED
[  271.476830] ata1.00: cmd 61/30:00:d8:10:08/00:00:04:00:00/40 tag 0 ncq 24576 out
[  271.476832]          res 40/00:00:d8:10:08/00:00:04:00:00/40 Emask 0x10 (ATA bus error)
[  271.476837] ata1.00: status: { DRDY }
[  271.476841] ata1.00: failed command: WRITE FPDMA QUEUED
[  271.476850] ata1.00: cmd 61/08:08:40:ff:07/00:00:04:00:00/40 tag 1 ncq 4096 out
[  271.476852]          res 40/00:00:d8:10:08/00:00:04:00:00/40 Emask 0x10 (ATA bus error)
[  271.476856] ata1.00: status: { DRDY }
[  271.476860] ata1.00: failed command: WRITE FPDMA QUEUED
[  271.476868] ata1.00: cmd 61/08:10:b8:29:05/00:00:04:00:00/40 tag 2 ncq 4096 out
[  271.476870]          res 40/00:00:d8:10:08/00:00:04:00:00/40 Emask 0x10 (ATA bus error)
[  271.476875] ata1.00: status: { DRDY }
[  271.476884] ata1: hard resetting link
[  272.195555] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  272.217625] ata1.00: configured for UDMA/33
[  272.228823] ata1: EH complete
[matej@matej-arch log]$ uptime
15:24:21 up 26 min

full dmesg here

now it looks OK, no new errors. But before last reboot this messages showed up all time, until reboot. (uptime was around 1 hour)

fstab:

tmpfs           /tmp    tmpfs   nodev,nosuid    0       0
/dev/sda1 / ext4 defaults,noatime 0 1
/dev/sda2 /home ext4 defaults,noatime,user_xattr 0 1
debugfs /sys/kernel/debug debugfs 0 0

other logs are basically same as neverov's.
All I have found so far, is this might be disk's fault, but after first day?

EDIT:after 3 reboots and 3 shutdown-startup just found out: after reboot everything is OK, no errors or "ata1"-anything in dmesg. after cold startup it took 10 to X seconds until "ata1: EH complete" shows up in dmesg and later everything is OK.

Last edited by depeha (2012-04-13 14:01:18)