[<<][pool][>>][..]
Sun Apr 17 09:28:46 EDT 2016

compact flash problems

https://forum.pfsense.org/index.php?topic=41312.0

[    1.615476] SCSI subsystem initialized
[    1.662664] libata version 3.00 loaded.
[    1.664392] pata_amd 0000:00:14.2: version 0.4.1
[    1.686896] scsi0 : pata_amd
[    1.697147] scsi1 : pata_amd
[    1.697707] ata1: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0xe000 irq 14
[    1.697725] ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0xe008 irq 15
[    1.782446] Switched to clocksource tsc
[    1.880421] ata1.01: CFA: SanDisk SDCFHSNJC-008G, HDX 7.08, max UDMA/100
[    1.880441] ata1.01: 15625216 sectors, multi 0: LBA48 
[    1.880473] ata1.01: limited to UDMA/33 due to 40-wire cable
[    1.896323] ata1.01: configured for UDMA/33
[    1.896836] scsi 0:0:1:0: Direct-Access     ATA      SanDisk SDCFHSNJ 7.08 PQ: 0 ANSI: 5
[    1.899245] ata2: port disabled--ignoring
[    1.938808] sd 0:0:1:0: [sda] 15625216 512-byte logical blocks: (8.00 GB/7.45 GiB)
[    1.939099] sd 0:0:1:0: [sda] Write Protect is off
[    1.939122] sd 0:0:1:0: [sda] Mode Sense: 00 3a 00 00
[    1.939252] sd 0:0:1:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    1.943327]  sda: sda1
[    1.945489] sd 0:0:1:0: [sda] Attached SCSI removable disk
[    2.454665] EXT4-fs (sda1): mounting ext3 file system using the ext4 subsystem
[    2.476592] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)


[188718.056166] ata1.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[188718.098979] ata1.01: failed command: WRITE DMA
[188718.126942] ata1.01: cmd ca/00:38:b2:15:74/00:00:00:00:00/f0 tag 0 dma 28672 out
         res 40/00:01:00:00:00/00:00:00:00:00/50 Emask 0x4 (timeout)
[188718.217451] ata1.01: status: { DRDY }
[188723.280082] ata1: link is slow to respond, please be patient (ready=0)
[188728.264085] ata1: device not ready (errno=-16), forcing hardreset
[188728.264118] ata1: soft resetting link
[188728.470907] ata1.01: failed to IDENTIFY (I/O error, err_mask=0x3)
[188728.470933] ata1.01: revalidation failed (errno=-5)
[188733.428398] ata1: soft resetting link
[188733.631079] ata1.01: failed to IDENTIFY (I/O error, err_mask=0x3)
[188733.631104] ata1.01: revalidation failed (errno=-5)
[188738.592381] ata1: soft resetting link
[188738.798749] ata1.01: failed to IDENTIFY (I/O error, err_mask=0x3)
[188738.798775] ata1.01: revalidation failed (errno=-5)
[188738.828572] ata1.01: disabled
[188738.828611] ata1.01: device reported invalid CHS sector 0
[188738.828949] ata1: soft resetting link
[188738.995690] ata1: EH complete
[188738.996208] sd 0:0:1:0: [sda] Unhandled error code
[188738.996228] sd 0:0:1:0: [sda]  [    1.884421] ata1.01: CFA: SanDisk SDCFX-008G, HDX 7.05, max UDMA/100
[    1.884441] ata1.01: 15625216 sectors, multi 0: LBA48 
[    1.884474] ata1.01: limited to UDMA/33 due to 40-wire cable
[    1.900323] ata1.01: configured for UDMA/33

[188738.996242] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK
[188738.996259] sd 0:0:1:0: [sda] CDB: 
[188738.996270] Write(10): 2a 00 00 74 15 b2 00 00 38 00
[188738.996317] end_request: I/O error, dev sda, sector 7607730
[188739.032096] Aborting journal on device sda1-8.
[188739.062050] sd 0:0:1:0: [sda] Unhandled error code
[188739.062080] sd 0:0:1:0: [sda]  
[188739.062095] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK
[188739.062113] sd 0:0:1:0: [sda] CDB: 
[188739.062123] Write(10): 2a 00 00 00 01 ba 00 00 08 00
[188739.062169] end_request: I/O error, dev sda, sector 442
[188739.094042] Buffer I/O error on device sda1, logical block 0
[188739.128454] lost page write due to I/O error on sda1
[188739.133064] sd 0:0:1:0: [sda] Unhandled error code
[188739.133088] sd 0:0:1:0: [sda]  
[188739.133103] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK
[188739.133120] sd 0:0:1:0: [sda] CDB: 
[188739.133131] Write(10): 2a 00 00 70 11 b2 00 00 08 00
[188739.133176] end_request: I/O error, dev sda, sector 7344562
[188739.167120] Buffer I/O error on device sda1, logical block 918015
[188739.204163] lost page write due to I/O error on sda1
[188739.204232] JBD2: Error -5 detected when updating journal superblock for sda1-8.
[188739.254370] EXT4-fs (sda1): previous I/O error to superblock detected
[188739.297239] sd 0:0:1:0: [sda] Unhandled error code
[188739.297265] sd 0:0:1:0: [sda]  
[188739.297280] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK
[188739.297297] sd 0:0:1:0: [sda] CDB: 
[188739.297308] Write(10): 2a 00 00 00 82 62 00 00 08 00
[188739.297354] end_request: I/O error, dev sda, sector 33378
[188739.330292] EXT4-fs warning (device sda1): ext4_end_bio:317: I/O error -5 writing to inode 457055 (offset 0 size 0 starting block 4173)
[188739.330313] Buffer I/O error on device sda1, logical block 4117
[188739.369109] sd 0:0:1:0: [sda] Unhandled error code
[188739.369136] sd 0:0:1:0: [sda]  
[188739.369150] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK
[188739.369168] sd 0:0:1:0: [sda] CDB: 
[188739.369179] Write(10): 2a 00 00 0c 28 5a 00 00 10 00
[188739.369224] end_request: I/O error, dev sda, sector 796762
[188739.402641] EXT4-fs warning (device sda1): ext4_end_bio:317: I/O error -5 writing to inode 456360 (offset 0 size 0 starting block 99596)
[188739.402660] Buffer I/O error on device sda1, logical block 99540
[188739.439180] EXT4-fs warning (device sda1): ext4_end_bio:317: I/O error -5 writing to inode 456360 (offset 0 size 0 starting block 99597)
[188739.439198] Buffer I/O error on device sda1, logical block 99541
[188739.475784] JBD2: Detected IO errors while flushing file data on sda1-8
[188739.477471] sd 0:0:1:0: [sda] Unhandled error code
[188739.477495] sd 0:0:1:0: [sda]  
[188739.477508] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK
[188739.477525] sd 0:0:1:0: [sda] CDB: 
[188739.477536] Write(10): 2a 00 00 00 01 ba 00 00 08 00
[188739.477580] end_request: I/O error, dev sda, sector 442
[188739.509421] Buffer I/O error on device sda1, logical block 0
[188739.543861] lost page write due to I/O error on sda1
[188739.543943] EXT4-fs error (device sda1): ext4_journal_check_start:56: Detected aborted journal
[188739.597516] EXT4-fs (sda1): Remounting filesystem read-only
[188739.631470] EXT4-fs (sda1): previous I/O error to superblock detected
[188739.671410] EXT4-fs error (device sda1): ext4_journal_check_start:56: Detected aborted journal
[188739.725857] sd 0:0:1:0: [sda] Unhandled error code
[188739.725879] sd 0:0:1:0: [sda]  
[188739.725893] Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK
[188739.725911] sd 0:0:1:0: [sda] CDB: 
[188739.725921] Write(10): 2a 00 00 00 01 ba 00 00 08 00
[188739.725965] end_request: I/O error, dev sda, sector 442
[188739.757854] Buffer I/O error on device sda1, logical block 0
[188739.792270] lost page write due to I/O error on sda1
[275289.056065] EXT4-fs (sda1): error count since last fsck: 3
[275289.056091] EXT4-fs (sda1): initial error at time 1460148606: ext4_journal_check_start:56
[275289.056110] EXT4-fs (sda1): last error at time 1460148607: ext4_journal_check_start:56
[361796.576099] EXT4-fs (sda1): error count since last fsck: 3
[361796.576126] EXT4-fs (sda1): initial error at time 1460148606: ext4_journal_check_start:56
[361796.576145] EXT4-fs (sda1): last error at time 1460148607: ext4_journal_check_start:56
[448304.096065] EXT4-fs (sda1): error count since last fsck: 3
[448304.096092] EXT4-fs (sda1): initial error at time 1460148606: ext4_journal_check_start:56
[448304.096110] EXT4-fs (sda1): last error at time 1460148607: ext4_journal_check_start:56
[534811.616066] EXT4-fs (sda1): error count since last fsck: 3
[534811.616094] EXT4-fs (sda1): initial error at time 1460148606: ext4_journal_check_start:56
[534811.616112] EXT4-fs (sda1): last error at time 1460148607: ext4_journal_check_start:56
[621319.136066] EXT4-fs (sda1): error count since last fsck: 3
[621319.136093] EXT4-fs (sda1): initial error at time 1460148606: ext4_journal_check_start:56
[621319.136112] EXT4-fs (sda1): last error at time 1460148607: ext4_journal_check_start:56
[707826.656068] EXT4-fs (sda1): error count since last fsck: 3
[707826.656095] EXT4-fs (sda1): initial error at time 1460148606: ext4_journal_check_start:56
[707826.656114] EXT4-fs (sda1): last error at time 1460148607: ext4_journal_check_start:56
[794334.176066] EXT4-fs (sda1): error count since last fsck: 3
[794334.176093] EXT4-fs (sda1): initial error at time 1460148606: ext4_journal_check_start:56
[794334.176112] EXT4-fs (sda1): last error at time 1460148607: ext4_journal_check_start:56
[880841.696073] EXT4-fs (sda1): error count since last fsck: 3
[880841.696100] EXT4-fs (sda1): initial error at time 1460148606: ext4_journal_check_start:56
[880841.696119] EXT4-fs (sda1): last error at time 1460148607: ext4_journal_check_start:56


https://xenomai.org/pipermail/xenomai/2012-September/026052.html

WIW, I have a soekris 4801 (with a Geode sc1100) that gets the same errs.
IIUC, its a board problem with CF, but it could have a deeper (more
common) cause.

Ive added libata.dma=3 to my boot options in grub menu.lst,
which fixes it reliably.
older kernels might want all_generic_ide or libata.nodma=0.0 ( for sda1 )




Could be acpi as well?
http://article.gmane.org/gmane.comp.hardware.soekris.technical/17671/


CHANGELOG:
- added "libata.dma=3" kernel parameter. this made it switch UDMA/33 -> PIO4.

dmesg:
[    1.608525] SCSI subsystem initialized
[    1.665561] libata version 3.00 loaded.
[    1.667367] pata_amd 0000:00:14.2: version 0.4.1
[    1.691786] scsi0 : pata_amd
[    1.699068] scsi1 : pata_amd
[    1.699628] ata1: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0xe000 irq 14
[    1.699646] ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0xe008 irq 15
[    1.786292] Switched to clocksource tsc
[    1.880420] ata1.01: CFA: SanDisk SDCFHSNJC-008G, HDX 7.08, max UDMA/100
[    1.880440] ata1.01: 15625216 sectors, multi 0: LBA48 
[    1.880473] ata1.01: limited to UDMA/33 due to 40-wire cable
[    1.896323] ata1.01: configured for PIO4
[    1.896838] scsi 0:0:1:0: Direct-Access     ATA      SanDisk SDCFHSNJ 7.08 PQ: 0 ANSI: 5
[    1.899253] ata2: port disabled--ignoring
[    1.936261] sd 0:0:1:0: [sda] 15625216 512-byte logical blocks: (8.00 GB/7.45 GiB)
[    1.936558] sd 0:0:1:0: [sda] Write Protect is off
[    1.936581] sd 0:0:1:0: [sda] Mode Sense: 00 3a 00 00
[    1.936710] sd 0:0:1:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    1.940709]  sda: sda1
[    1.945148] sd 0:0:1:0: [sda] Attached SCSI removable disk
[    2.980613] EXT4-fs (sda1): mounting ext3 file system using the ext4 subsystem
[    3.084537] random: nonblocking pool is initialized
[    3.134928] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)


EDIT Apr 24: still trouble. Couldn't login on either ssh or serial
console (respawning too fast).  Nothing in the logs.  Likely disk
problem again.  Ordered Extreme 8GB to replace the Ultra 8GB.


EDIT Apr 27: replacement arrived. same size, so making DD.
root@soekris:~# dmesg |grep '8.00 GB'
[     1.939074] sd 0:0:1:0: [sda] 15625216 512-byte logical blocks: (8.00 GB/7.45 GiB)
[246918.870464] sd 4:0:0:0: [sdb] 15625216 512-byte logical blocks: (8.00 GB/7.45 GiB)

botting:
[    1.884421] ata1.01: CFA: SanDisk SDCFX-008G, HDX 7.05, max UDMA/100
[    1.884441] ata1.01: 15625216 sectors, multi 0: LBA48 
[    1.884474] ata1.01: limited to UDMA/33 due to 40-wire cable
[    1.900323] ata1.01: configured for UDMA/33




[Reply][About]
[<<][pool][>>][..]