2007-11-01 22:04:38

by Denys Fedoryschenko

Subject: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

Finally i got full DMESG with 1GB card till end. Seems not readable too.

Linux version 2.6.24-rc1-git10-embedded ([email protected]) (gcc
version 4.1.2 (Gentoo 4.1.2 p1.0.1)) #1 Thu Nov 1 23:12:53 EET 2007
BIOS-provided physical RAM map:
BIOS-e801: 0000000000000000 - 000000000009f000 (usable)
BIOS-e801: 0000000000100000 - 0000000004000000 (usable)
64MB LOWMEM available.
Zone PFN ranges:
DMA 0 -> 4096
Normal 4096 -> 16384
Movable zone start PFN for each node
early_node_map[1] active PFN ranges
0: 0 -> 16384
DMI not present or invalid.
Allocating PCI resources starting at 10000000 (gap: 04000000:fc000000)
Built 1 zonelists in Zone order, mobility grouping on. Total pages: 16256
Kernel command line: console=ttyS0,38400n8
Initializing CPU#0
PID hash table entries: 256 (order: 8, 1024 bytes)
Detected 266.627 MHz processor.
Console: colour dummy device 80x25
console [ttyS0] enabled
Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
Memory: 62836k/65536k available (1020k kernel code, 2292k reserved, 317k
data, 112k init, 0k highmem)
virtual kernel memory layout:
fixmap : 0xffffb000 - 0xfffff000 ( 16 kB)
vmalloc : 0xc4800000 - 0xffff9000 ( 951 MB)
lowmem : 0xc0000000 - 0xc4000000 ( 64 MB)
.init : 0xc0252000 - 0xc026e000 ( 112 kB)
.data : 0xc01ff111 - 0xc024e6f4 ( 317 kB)
.text : 0xc0100000 - 0xc01ff111 (1020 kB)
Checking if this processor honours the WP bit even in supervisor mode... Ok.
SLUB: Genslabs=11, HWalign=32, Order=0-1, MinObjects=4, CPUs=1, Nodes=1
Calibrating delay using timer specific routine.. 534.41 BogoMIPS (lpj=1068836)
Mount-cache hash table entries: 512
Compat vDSO mapped to ffffe000.
CPU: NSC Unknown stepping 01
Checking 'hlt' instruction... OK.
Freeing SMP alternatives: 0k freed
net_namespace: 64 bytes
NET: Registered protocol family 16
PCI: PCI BIOS revision 2.10 entry at 0xfc3ad, last bus=0
PCI: Using configuration type 1
Setting up standard PCI resources
SCSI subsystem initialized
PCI: Probing PCI hardware
PCI: Device 0000:00:12.5 not found by BIOS
Time: tsc clocksource has been installed.
NET: Registered protocol family 2
IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
TCP established hash table entries: 2048 (order: 2, 16384 bytes)
TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
TCP: Hash tables configured (established 2048 bind 2048)
TCP reno registered
scx200: NatSemi SCx200 Driver
scx200: GPIO base 0xf400
scx200: Configuration Block base 0x9000
io scheduler noop registered (default)
Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled
serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a NS16550A
serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a NS16550A
natsemi dp8381x driver, version 2.1, Sept 11, 2006
originally by Donald Becker <[email protected]>
2.4.x kernel port by Jeff Garzik, Tjeerd Mulder
natsemi eth0: NatSemi DP8381[56] at 0x80000000 (0000:00:0e.0),
00:0d:b9:00:8a:30, IRQ 10, port TP.
scsi0 : sc1200
scsi1 : sc1200
ata1: PATA max UDMA/33 cmd 0x1f0 ctl 0x3f6 bmdma 0xfc00 irq 14
ata2: DUMMY
ata1.00: CFA: SanDisk SDCFH-1024, HDX 3.07, max MWDMA2
ata1.00: 2001888 sectors, multi 0: LBA
ata1.00: configured for MWDMA2
scsi 0:0:0:0: Direct-Access ATA SanDisk SDCFH-10 HDX PQ: 0 ANSI: 5
sd 0:0:0:0: [sda] 2001888 512-byte hardware sectors (1025 MB)
sd 0:0:0:0: [sda] Write Protect is off
sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support
DPO or FUA
sd 0:0:0:0: [sda] 2001888 512-byte hardware sectors (1025 MB)
sd 0:0:0:0: [sda] Write Protect is off
sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support
DPO or FUA
sda:<4>Clocksource tsc unstable (delta = -334501841 ns)
Time: pit clocksource has been installed.
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
ata1.00: status: { DRDY }
ata1: soft resetting link
ata1.00: configured for MWDMA2
ata1: EH complete
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
ata1.00: status: { DRDY }
ata1: soft resetting link
ata1.00: configured for MWDMA2
ata1: EH complete
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
ata1.00: status: { DRDY }
ata1: soft resetting link
ata1.00: configured for MWDMA2
ata1: EH complete
ata1.00: limiting speed to MWDMA1:PIO4
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
ata1.00: status: { DRDY }
ata1: soft resetting link
ata1.00: configured for MWDMA1
ata1: EH complete
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
ata1.00: status: { DRDY }
ata1: soft resetting link
ata1.00: configured for MWDMA1
ata1: EH complete
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
ata1.00: status: { DRDY }
ata1: soft resetting link
ata1.00: configured for MWDMA1
sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
Descriptor sense data with sense descriptors (in hex):
72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
00 00 00 00
sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
end_request: I/O error, dev sda, sector 0
Buffer I/O error on device sda, logical block 0
ata1: EH complete
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
ata1.00: status: { DRDY }
ata1: soft resetting link
ata1.00: configured for MWDMA1
ata1: EH complete
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
ata1.00: status: { DRDY }
ata1: soft resetting link
ata1.00: configured for MWDMA1
ata1: EH complete
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
ata1.00: status: { DRDY }
ata1: soft resetting link
ata1.00: configured for MWDMA1
ata1: EH complete
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
ata1.00: status: { DRDY }
ata1: soft resetting link
ata1.00: configured for MWDMA1
ata1: EH complete
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
ata1.00: status: { DRDY }
ata1: soft resetting link
ata1.00: configured for MWDMA1
ata1: EH complete
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
ata1.00: status: { DRDY }
ata1: soft resetting link
ata1.00: configured for MWDMA1
sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
Descriptor sense data with sense descriptors (in hex):
72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
00 00 00 00
sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
end_request: I/O error, dev sda, sector 0
Buffer I/O error on device sda, logical block 0
ata1: EH complete
unable to read partition table
sd 0:0:0:0: [sda] Attached SCSI removable disk
scx200_wdt: timer margin 60 seconds
cpuidle: using governor ladder
enabling scx200 high-res timer (1 MHz +0 ppm)
TCP cubic registered
Time: scx200_hrt clocksource has been installed.
NET: Registered protocol family 1
NET: Registered protocol family 17
Using IPI Shortcut mode
VFS: Cannot open root device "<NULL>" or unknown-block(8,18)
Please append a correct "root=" boot option; here are the available
partitions:
0800 1000944 sda driver: sd
Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-
block(8,18)



--
Denys Fedoryshchenko
Technical Manager
Virtual ISP S.A.L.

2007-11-07 06:16:24

by Andrew Morton

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

> On Thu, 1 Nov 2007 23:30:13 +0200 "Denys" <[email protected]> wrote:
> Finally i got full DMESG with 1GB card till end. Seems not readable too.
>
> Linux version 2.6.24-rc1-git10-embedded ([email protected]) (gcc
> version 4.1.2 (Gentoo 4.1.2 p1.0.1)) #1 Thu Nov 1 23:12:53 EET 2007
> BIOS-provided physical RAM map:
> BIOS-e801: 0000000000000000 - 000000000009f000 (usable)
> BIOS-e801: 0000000000100000 - 0000000004000000 (usable)
> 64MB LOWMEM available.
> Zone PFN ranges:
> DMA 0 -> 4096
> Normal 4096 -> 16384
> Movable zone start PFN for each node
> early_node_map[1] active PFN ranges
> 0: 0 -> 16384
> DMI not present or invalid.
> Allocating PCI resources starting at 10000000 (gap: 04000000:fc000000)
> Built 1 zonelists in Zone order, mobility grouping on. Total pages: 16256
> Kernel command line: console=ttyS0,38400n8
> Initializing CPU#0
> PID hash table entries: 256 (order: 8, 1024 bytes)
> Detected 266.627 MHz processor.
> Console: colour dummy device 80x25
> console [ttyS0] enabled
> Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
> Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
> Memory: 62836k/65536k available (1020k kernel code, 2292k reserved, 317k
> data, 112k init, 0k highmem)
> virtual kernel memory layout:
> fixmap : 0xffffb000 - 0xfffff000 ( 16 kB)
> vmalloc : 0xc4800000 - 0xffff9000 ( 951 MB)
> lowmem : 0xc0000000 - 0xc4000000 ( 64 MB)
> .init : 0xc0252000 - 0xc026e000 ( 112 kB)
> .data : 0xc01ff111 - 0xc024e6f4 ( 317 kB)
> .text : 0xc0100000 - 0xc01ff111 (1020 kB)
> Checking if this processor honours the WP bit even in supervisor mode... Ok.
> SLUB: Genslabs=11, HWalign=32, Order=0-1, MinObjects=4, CPUs=1, Nodes=1
> Calibrating delay using timer specific routine.. 534.41 BogoMIPS (lpj=1068836)
> Mount-cache hash table entries: 512
> Compat vDSO mapped to ffffe000.
> CPU: NSC Unknown stepping 01
> Checking 'hlt' instruction... OK.
> Freeing SMP alternatives: 0k freed
> net_namespace: 64 bytes
> NET: Registered protocol family 16
> PCI: PCI BIOS revision 2.10 entry at 0xfc3ad, last bus=0
> PCI: Using configuration type 1
> Setting up standard PCI resources
> SCSI subsystem initialized
> PCI: Probing PCI hardware
> PCI: Device 0000:00:12.5 not found by BIOS
> Time: tsc clocksource has been installed.
> NET: Registered protocol family 2
> IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
> TCP established hash table entries: 2048 (order: 2, 16384 bytes)
> TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
> TCP: Hash tables configured (established 2048 bind 2048)
> TCP reno registered
> scx200: NatSemi SCx200 Driver
> scx200: GPIO base 0xf400
> scx200: Configuration Block base 0x9000
> io scheduler noop registered (default)
> Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled
> serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a NS16550A
> serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a NS16550A
> natsemi dp8381x driver, version 2.1, Sept 11, 2006
> originally by Donald Becker <[email protected]>
> 2.4.x kernel port by Jeff Garzik, Tjeerd Mulder
> natsemi eth0: NatSemi DP8381[56] at 0x80000000 (0000:00:0e.0),
> 00:0d:b9:00:8a:30, IRQ 10, port TP.
> scsi0 : sc1200
> scsi1 : sc1200
> ata1: PATA max UDMA/33 cmd 0x1f0 ctl 0x3f6 bmdma 0xfc00 irq 14
> ata2: DUMMY
> ata1.00: CFA: SanDisk SDCFH-1024, HDX 3.07, max MWDMA2
> ata1.00: 2001888 sectors, multi 0: LBA
> ata1.00: configured for MWDMA2
> scsi 0:0:0:0: Direct-Access ATA SanDisk SDCFH-10 HDX PQ: 0 ANSI: 5
> sd 0:0:0:0: [sda] 2001888 512-byte hardware sectors (1025 MB)
> sd 0:0:0:0: [sda] Write Protect is off
> sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support
> DPO or FUA
> sd 0:0:0:0: [sda] 2001888 512-byte hardware sectors (1025 MB)
> sd 0:0:0:0: [sda] Write Protect is off
> sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support
> DPO or FUA
> sda:<4>Clocksource tsc unstable (delta = -334501841 ns)
> Time: pit clocksource has been installed.
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA2
> ata1: EH complete
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA2
> ata1: EH complete
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA2
> ata1: EH complete
> ata1.00: limiting speed to MWDMA1:PIO4
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA1
> ata1: EH complete
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA1
> ata1: EH complete
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA1
> sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
> sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
> Descriptor sense data with sense descriptors (in hex):
> 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
> 00 00 00 00
> sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
> end_request: I/O error, dev sda, sector 0
> Buffer I/O error on device sda, logical block 0
> ata1: EH complete
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA1
> ata1: EH complete
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA1
> ata1: EH complete
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA1
> ata1: EH complete
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA1
> ata1: EH complete
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA1
> ata1: EH complete
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA1
> sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
> sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
> Descriptor sense data with sense descriptors (in hex):
> 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
> 00 00 00 00
> sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
> end_request: I/O error, dev sda, sector 0
> Buffer I/O error on device sda, logical block 0
> ata1: EH complete
> unable to read partition table
> sd 0:0:0:0: [sda] Attached SCSI removable disk
> scx200_wdt: timer margin 60 seconds
> cpuidle: using governor ladder
> enabling scx200 high-res timer (1 MHz +0 ppm)
> TCP cubic registered
> Time: scx200_hrt clocksource has been installed.
> NET: Registered protocol family 1
> NET: Registered protocol family 17
> Using IPI Shortcut mode
> VFS: Cannot open root device "<NULL>" or unknown-block(8,18)
> Please append a correct "root=" boot option; here are the available
> partitions:
> 0800 1000944 sda driver: sd
> Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-
> block(8,18)
>

(+linux-ide)

So this has never worked on any known kernel?

2007-11-07 13:27:30

by Denys Fedoryschenko

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

On Tue, 6 Nov 2007 22:15:21 -0800, Andrew Morton wrote
> > On Thu, 1 Nov 2007 23:30:13 +0200 "Denys" <[email protected]> wrote:
> > Finally i got full DMESG with 1GB card till end. Seems not readable too.
> >
> > Linux version 2.6.24-rc1-git10-embedded ([email protected]) (gcc
> > version 4.1.2 (Gentoo 4.1.2 p1.0.1)) #1 Thu Nov 1 23:12:53 EET 2007
> > BIOS-provided physical RAM map:
> > BIOS-e801: 0000000000000000 - 000000000009f000 (usable)
> > BIOS-e801: 0000000000100000 - 0000000004000000 (usable)
> > 64MB LOWMEM available.
> > Zone PFN ranges:
> > DMA 0 -> 4096
> > Normal 4096 -> 16384
> > Movable zone start PFN for each node
> > early_node_map[1] active PFN ranges
> > 0: 0 -> 16384
> > DMI not present or invalid.
> > Allocating PCI resources starting at 10000000 (gap: 04000000:fc000000)
> > Built 1 zonelists in Zone order, mobility grouping on. Total pages: 16256
> > Kernel command line: console=ttyS0,38400n8
> > Initializing CPU#0
> > PID hash table entries: 256 (order: 8, 1024 bytes)
> > Detected 266.627 MHz processor.
> > Console: colour dummy device 80x25
> > console [ttyS0] enabled
> > Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
> > Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
> > Memory: 62836k/65536k available (1020k kernel code, 2292k reserved, 317k
> > data, 112k init, 0k highmem)
> > virtual kernel memory layout:
> > fixmap : 0xffffb000 - 0xfffff000 ( 16 kB)
> > vmalloc : 0xc4800000 - 0xffff9000 ( 951 MB)
> > lowmem : 0xc0000000 - 0xc4000000 ( 64 MB)
> > .init : 0xc0252000 - 0xc026e000 ( 112 kB)
> > .data : 0xc01ff111 - 0xc024e6f4 ( 317 kB)
> > .text : 0xc0100000 - 0xc01ff111 (1020 kB)
> > Checking if this processor honours the WP bit even in supervisor mode...
Ok.
> > SLUB: Genslabs=11, HWalign=32, Order=0-1, MinObjects=4, CPUs=1, Nodes=1
> > Calibrating delay using timer specific routine.. 534.41 BogoMIPS
(lpj=1068836)
> > Mount-cache hash table entries: 512
> > Compat vDSO mapped to ffffe000.
> > CPU: NSC Unknown stepping 01
> > Checking 'hlt' instruction... OK.
> > Freeing SMP alternatives: 0k freed
> > net_namespace: 64 bytes
> > NET: Registered protocol family 16
> > PCI: PCI BIOS revision 2.10 entry at 0xfc3ad, last bus=0
> > PCI: Using configuration type 1
> > Setting up standard PCI resources
> > SCSI subsystem initialized
> > PCI: Probing PCI hardware
> > PCI: Device 0000:00:12.5 not found by BIOS
> > Time: tsc clocksource has been installed.
> > NET: Registered protocol family 2
> > IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
> > TCP established hash table entries: 2048 (order: 2, 16384 bytes)
> > TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
> > TCP: Hash tables configured (established 2048 bind 2048)
> > TCP reno registered
> > scx200: NatSemi SCx200 Driver
> > scx200: GPIO base 0xf400
> > scx200: Configuration Block base 0x9000
> > io scheduler noop registered (default)
> > Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled
> > serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a NS16550A
> > serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a NS16550A
> > natsemi dp8381x driver, version 2.1, Sept 11, 2006
> > originally by Donald Becker <[email protected]>
> > 2.4.x kernel port by Jeff Garzik, Tjeerd Mulder
> > natsemi eth0: NatSemi DP8381[56] at 0x80000000 (0000:00:0e.0),
> > 00:0d:b9:00:8a:30, IRQ 10, port TP.
> > scsi0 : sc1200
> > scsi1 : sc1200
> > ata1: PATA max UDMA/33 cmd 0x1f0 ctl 0x3f6 bmdma 0xfc00 irq 14
> > ata2: DUMMY
> > ata1.00: CFA: SanDisk SDCFH-1024, HDX 3.07, max MWDMA2
> > ata1.00: 2001888 sectors, multi 0: LBA
> > ata1.00: configured for MWDMA2
> > scsi 0:0:0:0: Direct-Access ATA SanDisk SDCFH-10 HDX PQ: 0
ANSI: 5
> > sd 0:0:0:0: [sda] 2001888 512-byte hardware sectors (1025 MB)
> > sd 0:0:0:0: [sda] Write Protect is off
> > sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't
support
> > DPO or FUA
> > sd 0:0:0:0: [sda] 2001888 512-byte hardware sectors (1025 MB)
> > sd 0:0:0:0: [sda] Write Protect is off
> > sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't
support
> > DPO or FUA
> > sda:<4>Clocksource tsc unstable (delta = -334501841 ns)
> > Time: pit clocksource has been installed.
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA2
> > ata1: EH complete
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA2
> > ata1: EH complete
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA2
> > ata1: EH complete
> > ata1.00: limiting speed to MWDMA1:PIO4
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA1
> > ata1: EH complete
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA1
> > ata1: EH complete
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA1
> > sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
> > sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
> > Descriptor sense data with sense descriptors (in hex):
> > 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
> > 00 00 00 00
> > sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
> > end_request: I/O error, dev sda, sector 0
> > Buffer I/O error on device sda, logical block 0
> > ata1: EH complete
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA1
> > ata1: EH complete
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA1
> > ata1: EH complete
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA1
> > ata1: EH complete
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA1
> > ata1: EH complete
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA1
> > ata1: EH complete
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA1
> > sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
> > sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
> > Descriptor sense data with sense descriptors (in hex):
> > 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
> > 00 00 00 00
> > sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
> > end_request: I/O error, dev sda, sector 0
> > Buffer I/O error on device sda, logical block 0
> > ata1: EH complete
> > unable to read partition table
> > sd 0:0:0:0: [sda] Attached SCSI removable disk
> > scx200_wdt: timer margin 60 seconds
> > cpuidle: using governor ladder
> > enabling scx200 high-res timer (1 MHz +0 ppm)
> > TCP cubic registered
> > Time: scx200_hrt clocksource has been installed.
> > NET: Registered protocol family 1
> > NET: Registered protocol family 17
> > Using IPI Shortcut mode
> > VFS: Cannot open root device "<NULL>" or unknown-block(8,18)
> > Please append a correct "root=" boot option; here are the available
> > partitions:
> > 0800 1000944 sda driver: sd
> > Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-
> > block(8,18)
> >
>
> (+linux-ide)
>
> So this has never worked on any known kernel?

It is working now on old ide code (not libata).

--
Denys Fedoryshchenko
Technical Manager
Virtual ISP S.A.L.

2007-11-07 15:56:23

by James Andrewartha

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

Denys Fedoryshchenko wrote:
> On Tue, 6 Nov 2007 22:15:21 -0800, Andrew Morton wrote
>>> On Thu, 1 Nov 2007 23:30:13 +0200 "Denys" <[email protected]> wrote:
>>> Finally i got full DMESG with 1GB card till end. Seems not readable too.
>>> scsi0 : sc1200
>>> scsi1 : sc1200
>>> ata1: PATA max UDMA/33 cmd 0x1f0 ctl 0x3f6 bmdma 0xfc00 irq 14
>>> ata2: DUMMY
>>> ata1.00: CFA: SanDisk SDCFH-1024, HDX 3.07, max MWDMA2
>>> ata1.00: 2001888 sectors, multi 0: LBA
>>> ata1.00: configured for MWDMA2
>>> scsi 0:0:0:0: Direct-Access ATA SanDisk SDCFH-10 HDX PQ: 0
> ANSI: 5
>>> sd 0:0:0:0: [sda] 2001888 512-byte hardware sectors (1025 MB)
>>> sd 0:0:0:0: [sda] Write Protect is off
>>> sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't
> support
>>> DPO or FUA
>>> sd 0:0:0:0: [sda] 2001888 512-byte hardware sectors (1025 MB)
>>> sd 0:0:0:0: [sda] Write Protect is off
>>> sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't
> support
>>> DPO or FUA
>>> sda:<4>Clocksource tsc unstable (delta = -334501841 ns)
>>> Time: pit clocksource has been installed.
>>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
>>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
> in
>>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
>>> ata1.00: status: { DRDY }
>>> ata1: soft resetting link
>>> ata1.00: configured for MWDMA2
>>> ata1: EH complete
>>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
>>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
> in
>>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
>>> ata1.00: status: { DRDY }
>>> ata1: soft resetting link
>>> ata1.00: configured for MWDMA2
>>> ata1: EH complete
>>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
>>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
> in
>>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
>>> ata1.00: status: { DRDY }
>>> ata1: soft resetting link
>>> ata1.00: configured for MWDMA2
>>> ata1: EH complete
>>> ata1.00: limiting speed to MWDMA1:PIO4
>>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
>>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
> in
>>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
>>> ata1.00: status: { DRDY }
>>> ata1: soft resetting link
>>> ata1.00: configured for MWDMA1
>>> ata1: EH complete
>>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
>>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
> in
>>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
>>> ata1.00: status: { DRDY }
>>> ata1: soft resetting link
>>> ata1.00: configured for MWDMA1
>>> ata1: EH complete
>>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
>>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
> in
>>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
>>> ata1.00: status: { DRDY }
>>> ata1: soft resetting link
>>> ata1.00: configured for MWDMA1
>>> sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
>>> sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
>>> Descriptor sense data with sense descriptors (in hex):
>>> 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
>>> 00 00 00 00
>>> sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
>>> end_request: I/O error, dev sda, sector 0
>>> Buffer I/O error on device sda, logical block 0
>>> ata1: EH complete
>>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
>>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
> in
>>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
>>> ata1.00: status: { DRDY }
>>> ata1: soft resetting link
>>> ata1.00: configured for MWDMA1
>>> ata1: EH complete
>>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
>>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
> in
>>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
>>> ata1.00: status: { DRDY }
>>> ata1: soft resetting link
>>> ata1.00: configured for MWDMA1
>>> ata1: EH complete
>>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
>>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
> in
>>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
>>> ata1.00: status: { DRDY }
>>> ata1: soft resetting link
>>> ata1.00: configured for MWDMA1
>>> ata1: EH complete
>>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
>>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
> in
>>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
>>> ata1.00: status: { DRDY }
>>> ata1: soft resetting link
>>> ata1.00: configured for MWDMA1
>>> ata1: EH complete
>>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
>>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
> in
>>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
>>> ata1.00: status: { DRDY }
>>> ata1: soft resetting link
>>> ata1.00: configured for MWDMA1
>>> ata1: EH complete
>>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
>>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
> in
>>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
>>> ata1.00: status: { DRDY }
>>> ata1: soft resetting link
>>> ata1.00: configured for MWDMA1
>>> sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
>>> sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
>>> Descriptor sense data with sense descriptors (in hex):
>>> 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
>>> 00 00 00 00
>>> sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
>>> end_request: I/O error, dev sda, sector 0
>>> Buffer I/O error on device sda, logical block 0
>>> ata1: EH complete
>>> unable to read partition table
>>> sd 0:0:0:0: [sda] Attached SCSI removable disk
>>> scx200_wdt: timer margin 60 seconds
>>> cpuidle: using governor ladder
>>> enabling scx200 high-res timer (1 MHz +0 ppm)
>>> TCP cubic registered
>>> Time: scx200_hrt clocksource has been installed.
>>> NET: Registered protocol family 1
>>> NET: Registered protocol family 17
>>> Using IPI Shortcut mode
>>> VFS: Cannot open root device "<NULL>" or unknown-block(8,18)
>>> Please append a correct "root=" boot option; here are the available
>>> partitions:
>>> 0800 1000944 sda driver: sd
>>> Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-
>>> block(8,18)
>>>
>> (+linux-ide)
(+alan, tejun)
>> So this has never worked on any known kernel?
>
> It is working now on old ide code (not libata).

Can you post the dmesg and hdparm -I of a working boot using the old ide
code? The thing that stuck out to me (as just a reader of linux-ide) was
that the problem involves MWDMA and CF, which have had problems (although
not the same as this one) in the past:
http://thread.gmane.org/gmane.linux.ide/22058 and
http://thread.gmane.org/gmane.linux.ide/22400

--
James Andrewartha

2007-11-07 16:06:34

by Denys Fedoryschenko

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

On Thu, 08 Nov 2007 00:23:10 +0900, James Andrewartha wrote
> Denys Fedoryshchenko wrote:
> > On Tue, 6 Nov 2007 22:15:21 -0800, Andrew Morton wrote
> >>> On Thu, 1 Nov 2007 23:30:13 +0200 "Denys" <[email protected]> wrote:
> >>> Finally i got full DMESG with 1GB card till end. Seems not readable too.
> >>> scsi0 : sc1200
> >>> scsi1 : sc1200
> >>> ata1: PATA max UDMA/33 cmd 0x1f0 ctl 0x3f6 bmdma 0xfc00 irq 14
> >>> ata2: DUMMY
> >>> ata1.00: CFA: SanDisk SDCFH-1024, HDX 3.07, max MWDMA2
> >>> ata1.00: 2001888 sectors, multi 0: LBA
> >>> ata1.00: configured for MWDMA2
> >>> scsi 0:0:0:0: Direct-Access ATA SanDisk SDCFH-10 HDX PQ: 0
> > ANSI: 5
> >>> sd 0:0:0:0: [sda] 2001888 512-byte hardware sectors (1025 MB)
> >>> sd 0:0:0:0: [sda] Write Protect is off
> >>> sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't
> > support
> >>> DPO or FUA
> >>> sd 0:0:0:0: [sda] 2001888 512-byte hardware sectors (1025 MB)
> >>> sd 0:0:0:0: [sda] Write Protect is off
> >>> sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't
> > support
> >>> DPO or FUA
> >>> sda:<4>Clocksource tsc unstable (delta = -334501841 ns)
> >>> Time: pit clocksource has been installed.
> >>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> >>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data
4096
> > in
> >>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> >>> ata1.00: status: { DRDY }
> >>> ata1: soft resetting link
> >>> ata1.00: configured for MWDMA2
> >>> ata1: EH complete
> >>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> >>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data
4096
> > in
> >>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> >>> ata1.00: status: { DRDY }
> >>> ata1: soft resetting link
> >>> ata1.00: configured for MWDMA2
> >>> ata1: EH complete
> >>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> >>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data
4096
> > in
> >>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> >>> ata1.00: status: { DRDY }
> >>> ata1: soft resetting link
> >>> ata1.00: configured for MWDMA2
> >>> ata1: EH complete
> >>> ata1.00: limiting speed to MWDMA1:PIO4
> >>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> >>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data
4096
> > in
> >>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> >>> ata1.00: status: { DRDY }
> >>> ata1: soft resetting link
> >>> ata1.00: configured for MWDMA1
> >>> ata1: EH complete
> >>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> >>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data
4096
> > in
> >>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> >>> ata1.00: status: { DRDY }
> >>> ata1: soft resetting link
> >>> ata1.00: configured for MWDMA1
> >>> ata1: EH complete
> >>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> >>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data
4096
> > in
> >>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> >>> ata1.00: status: { DRDY }
> >>> ata1: soft resetting link
> >>> ata1.00: configured for MWDMA1
> >>> sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
> >>> sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
> >>> Descriptor sense data with sense descriptors (in hex):
> >>> 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
> >>> 00 00 00 00
> >>> sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
> >>> end_request: I/O error, dev sda, sector 0
> >>> Buffer I/O error on device sda, logical block 0
> >>> ata1: EH complete
> >>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> >>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data
4096
> > in
> >>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> >>> ata1.00: status: { DRDY }
> >>> ata1: soft resetting link
> >>> ata1.00: configured for MWDMA1
> >>> ata1: EH complete
> >>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> >>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data
4096
> > in
> >>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> >>> ata1.00: status: { DRDY }
> >>> ata1: soft resetting link
> >>> ata1.00: configured for MWDMA1
> >>> ata1: EH complete
> >>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> >>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data
4096
> > in
> >>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> >>> ata1.00: status: { DRDY }
> >>> ata1: soft resetting link
> >>> ata1.00: configured for MWDMA1
> >>> ata1: EH complete
> >>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> >>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data
4096
> > in
> >>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> >>> ata1.00: status: { DRDY }
> >>> ata1: soft resetting link
> >>> ata1.00: configured for MWDMA1
> >>> ata1: EH complete
> >>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> >>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data
4096
> > in
> >>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> >>> ata1.00: status: { DRDY }
> >>> ata1: soft resetting link
> >>> ata1.00: configured for MWDMA1
> >>> ata1: EH complete
> >>> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> >>> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data
4096
> > in
> >>> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> >>> ata1.00: status: { DRDY }
> >>> ata1: soft resetting link
> >>> ata1.00: configured for MWDMA1
> >>> sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
> >>> sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
> >>> Descriptor sense data with sense descriptors (in hex):
> >>> 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
> >>> 00 00 00 00
> >>> sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
> >>> end_request: I/O error, dev sda, sector 0
> >>> Buffer I/O error on device sda, logical block 0
> >>> ata1: EH complete
> >>> unable to read partition table
> >>> sd 0:0:0:0: [sda] Attached SCSI removable disk
> >>> scx200_wdt: timer margin 60 seconds
> >>> cpuidle: using governor ladder
> >>> enabling scx200 high-res timer (1 MHz +0 ppm)
> >>> TCP cubic registered
> >>> Time: scx200_hrt clocksource has been installed.
> >>> NET: Registered protocol family 1
> >>> NET: Registered protocol family 17
> >>> Using IPI Shortcut mode
> >>> VFS: Cannot open root device "<NULL>" or unknown-block(8,18)
> >>> Please append a correct "root=" boot option; here are the available
> >>> partitions:
> >>> 0800 1000944 sda driver: sd
> >>> Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-
> >>> block(8,18)
> >>>
> >> (+linux-ide)
> (+alan, tejun)
> >> So this has never worked on any known kernel?
> >
> > It is working now on old ide code (not libata).
>
> Can you post the dmesg and hdparm -I of a working boot using the old
> ide code? The thing that stuck out to me (as just a reader of linux-
> ide) was that the problem involves MWDMA and CF, which have had
> problems (although not the same as this one) in the past:
> http://thread.gmane.org/gmane.linux.ide/22058 and http://thread.gmane.org/
gmane.linux.ide/22400
>
> --
> James Andrewartha
WRAP ~ #./hdparm -I /dev/hda

/dev/hda:

ATAPI Write-once device, with non-removable media
Model Number: SanDisk SDP3B-16
Serial Number: 24313671615
Firmware Revision: vdd 1.00
Standards:
Likely used: 3
Configuration:
DRQ response: 50us.
Packet size: Unknown
Capabilities:
LBA, IORDY(may be)(cannot be disabled)
Buffer size: 1.0kB bytes avail on r/w long: 4
DMA: not supported
PIO: pio0 pio1


Now it is linux-2.6.24-rc1 from wireless-2.6 git (just testing)
All messages about IDE very similar to 2.6.24-rc2 (tested too)

0.000000] Linux version 2.6.24-rc1-embedded-g09bbb0a3-dirty
([email protected]) (gcc version 4.1.2 (Gentoo 4.1.2 p1.0.1)) #8 Wed
Nov 7 04:23:31 EET 2007
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e801: 0000000000000000 - 000000000009f000 (usable)
[ 0.000000] BIOS-e801: 0000000000100000 - 0000000004000000 (usable)
[ 0.000000] 64MB LOWMEM available.
[ 0.000000] Entering add_active_range(0, 0, 16384) 0 entries of 256 used
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0 -> 4096
[ 0.000000] Normal 4096 -> 16384
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] early_node_map[1] active PFN ranges
[ 0.000000] 0: 0 -> 16384
[ 0.000000] On node 0 totalpages: 16384
[ 0.000000] DMA zone: 32 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 4064 pages, LIFO batch:0
[ 0.000000] Normal zone: 96 pages used for memmap
[ 0.000000] Normal zone: 12192 pages, LIFO batch:1
[ 0.000000] Movable zone: 0 pages used for memmap
[ 0.000000] DMI not present or invalid.
[ 0.000000] Allocating PCI resources starting at 10000000 (gap:
04000000:fc000000)
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total
pages: 16256
[ 0.000000] Kernel command line: console=ttyS0,38400n8 panic=5 reboot=soft
clocksource=scx200_hrt notsc
[ 0.000000] notsc: Kernel compiled with CONFIG_X86_TSC, cannot disable TSC.
[ 0.000000] Initializing CPU#0
[ 0.000000] PID hash table entries: 256 (order: 8, 1024 bytes)
[ 0.000000] Detected 266.627 MHz processor.
[ 6.146789] Console: colour dummy device 80x25
[ 6.146884] console [ttyS0] enabled
[ 6.493841] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
[ 6.514729] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
[ 6.553125] Memory: 61992k/65536k available (1113k kernel code, 3136k
reserved, 454k data, 680k init, 0k highmem)
[ 6.584035] virtual kernel memory layout:
[ 6.584060] fixmap : 0xffffb000 - 0xfffff000 ( 16 kB)
[ 6.584092] vmalloc : 0xc4800000 - 0xffff9000 ( 951 MB)
[ 6.584124] lowmem : 0xc0000000 - 0xc4000000 ( 64 MB)
[ 6.584157] .init : 0xc028c000 - 0xc0336000 ( 680 kB)
[ 6.584190] .data : 0xc0216775 - 0xc028824c ( 454 kB)
[ 6.584222] .text : 0xc0100000 - 0xc0216775 (1113 kB)
[ 6.699565] Checking if this processor honours the WP bit even in
supervisor mode... Ok.
[ 6.724157] SLUB: Genslabs=11, HWalign=32, Order=0-1, MinObjects=4,
CPUs=1, Nodes=1
[ 6.827181] Calibrating delay using timer specific routine.. 534.53
BogoMIPS (lpj=1069066)
[ 6.852327] Mount-cache hash table entries: 512
[ 6.867065] CPU: After generic identify, caps: 00808131 01818131 00000000
00000000 00000000 00000000 00000000 00000000
[ 6.867249] CPU: After all inits, caps: 00808131 00818131 00000000
00000001 00000000 00000000 00000000 00000000
[ 6.867402] Compat vDSO mapped to ffffe000.
[ 6.880052] CPU: NSC Unknown stepping 01
[ 6.892089] Checking 'hlt' instruction... OK.
[ 6.905494] Freeing SMP alternatives: 0k freed
[ 6.921472] net_namespace: 64 bytes
[ 6.937068] NET: Registered protocol family 16
[ 6.955287] PCI: PCI BIOS revision 2.10 entry at 0xfc3ad, last bus=0
[ 6.974479] PCI: Using configuration type 1
[ 6.987082] Setting up standard PCI resources
[ 7.012835] SCSI subsystem initialized
[ 7.024946] PCI: Probing PCI hardware
[ 7.036044] PCI: Probing PCI hardware (bus 00)
[ 7.044386] PCI: Device 0000:00:12.5 not found by BIOS
[ 7.064960] Time: tsc clocksource has been installed.
[ 7.084872] NET: Registered protocol family 2
[ 7.099253] IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 7.122746] TCP established hash table entries: 2048 (order: 2, 16384
bytes)
[ 7.144139] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
[ 7.163374] TCP: Hash tables configured (established 2048 bind 2048)
[ 7.182494] TCP reno registered
[ 7.545264] apm: BIOS not found.
[ 7.555735] scx200: NatSemi SCx200 Driver
[ 7.568689] scx200: GPIO base 0xf400
[ 7.579604] scx200: Configuration Block base 0x9000
[ 7.598345] io scheduler noop registered (default)
[ 8.294143] nsc_gpio initializing
[ 8.294203] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ
sharing disabled
[ 8.318680] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a NS16550A
[ 8.338718] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a NS16550A
[ 8.368801] loop: module loaded
[ 8.378981] natsemi dp8381x driver, version 2.1, Sept 11, 2006
[ 8.379033] originally by Donald Becker <[email protected]>
[ 8.379074] 2.4.x kernel port by Jeff Garzik, Tjeerd Mulder
[ 8.435811] natsemi eth0: NatSemi DP8381[56] at 0x80000000 (0000:00:0e.0),
00:0d:b9:00:8a:30, IRQ 10, port TP.
[ 8.466288] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
[ 8.485458] ide: Assuming 33MHz system bus speed for PIO modes; override
with idebus=xx
[ 8.510324] SC1200: IDE controller (0x100b:0x0502 rev 0x01) at PCI slot
0000:00:12.2
[ 8.533951] SC1200: not 100% native mode: will probe irqs later
[ 8.551829] ide0: BM-DMA at 0xfc00-0xfc07, BIOS settings: hda:pio,
hdb:pio
[ 8.573784] ide1: BM-DMA at 0xfc08-0xfc0f, BIOS settings: hdc:pio,
hdd:pio
[ 8.595746] Probing IDE interface ide0...
[ 8.608917] Clocksource tsc unstable (delta = -497090346 ns)
[ 8.625999] Time: pit clocksource has been installed.
[ 8.644055] hda: SanDisk SDP3B-16, ATA DISK drive
[ 8.658485] hda: applying conservative PIO "downgrade"
[ 8.674009] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO0
[ 8.674264] hda: set_drive_speed_status: status=0x51 { DriveReady
SeekComplete Error }
[ 8.698231] hda: set_drive_speed_status: error=0x04 { DriveStatusError }
[ 8.718590] hda: applying conservative PIO "downgrade"
[ 8.734085] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO0
[ 8.734337] hda: set_drive_speed_status: status=0x51 { DriveReady
SeekComplete Error }
[ 8.758315] hda: set_drive_speed_status: error=0x04 { DriveStatusError }
[ 8.778838] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[ 8.793310] Probing IDE interface ide1...
[ 8.807926] hda: max request size: 128KiB
[ 8.820076] hda: 31360 sectors (16 MB) w/1KiB Cache, CHS=490/2/32
[ 8.838987] hda: hda1
[ 8.851358] scx200_wdt: NatSemi SCx200 Watchdog Driver
[ 8.851431] scx200_wdt: timer margin 60 seconds
[ 8.865139] scx200_wdt: disabling watchdog timer
[ 8.865921] cpuidle: using governor ladder
[ 8.878349] cpuidle: using governor menu
[ 8.890225] enabling scx200 high-res timer (1 MHz +0 ppm)
[ 8.906518] Netfilter messages via NETLINK v0.30.
[ 8.920683] Time: scx200_hrt clocksource has been installed.
[ 8.937755] Switched to high resolution mode on CPU 0
[ 8.952999] TCP cubic registered
[ 8.962739] NET: Registered protocol family 1
[ 8.975940] NET: Registered protocol family 17
[ 8.989342] Using IPI Shortcut mode
[ 9.006475] Freeing unused kernel memory: 680k freed
[ 10.189658] eth0: DSPCFG accepted after 0 usec.
[ 10.203289] eth0: link up.
[ 10.211460] eth0: Setting full-duplex based on negotiated link capability.





--
Denys Fedoryshchenko
Technical Manager
Virtual ISP S.A.L.

2007-11-07 19:13:14

by Mark Lord

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

> WRAP ~ #./hdparm -I /dev/hda
>
> /dev/hda:
>
> ATAPI Write-once device, with non-removable media
> Model Number: SanDisk SDP3B-16
> Serial Number: 24313671615
> Firmware Revision: vdd 1.00
> Standards:
> Likely used: 3
> Configuration:
> DRQ response: 50us.
> Packet size: Unknown
> Capabilities:
> LBA, IORDY(may be)(cannot be disabled)
> Buffer size: 1.0kB bytes avail on r/w long: 4
> DMA: not supported
> PIO: pio0 pio1

"ATAPI Write-once device" ???

That cannot be correct (??). Is this with hdparm-7.7 (latest sourceforge) ??
Can you show us the "hdparm --Istdout" output as well, please.

thanks.

2007-11-07 21:22:56

by Denys Fedoryschenko

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

On Wed, 07 Nov 2007 14:12:55 -0500, Mark Lord wrote
> > WRAP ~ #./hdparm -I /dev/hda
> >
> > /dev/hda:
> >
> > ATAPI Write-once device, with non-removable media
> > Model Number: SanDisk SDP3B-16
> > Serial Number: 24313671615
> > Firmware Revision: vdd 1.00
> > Standards:
> > Likely used: 3
> > Configuration:
> > DRQ response: 50us.
> > Packet size: Unknown
> > Capabilities:
> > LBA, IORDY(may be)(cannot be disabled)
> > Buffer size: 1.0kB bytes avail on r/w long: 4
> > DMA: not supported
> > PIO: pio0 pio1
>
> "ATAPI Write-once device" ???
>
> That cannot be correct (??). Is this with hdparm-7.7 (latest
> sourceforge) ?? Can you show us the "hdparm --Istdout" output as
> well, please.
>
> thanks.
Yes latest hdparm-7.7. But maybe it is related to git kernel? Cause lines
[ 8.658485] hda: applying conservative PIO "downgrade"
[ 8.674009] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO0
[ 8.674264] hda: set_drive_speed_status: status=0x51 { DriveReady SeekComplet
e Error }
[ 8.698231] hda: set_drive_speed_status: error=0x04 { DriveStatusError }
[ 8.718590] hda: applying conservative PIO "downgrade"
[ 8.734085] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO0
[ 8.734337] hda: set_drive_speed_status: status=0x51 { DriveReady SeekComplet
e Error }
[ 8.758315] hda: set_drive_speed_status: error=0x04 { DriveStatusError }

appeared only in those kernels (2.6.24-git/rc)


Anyways:

WRAP ~ #./hdparm --Istdout /dev/hda

/dev/hda:
844a 01ea 0000 0002 0000 0240 0020 0000
7a80 0000 2020 2020 2020 2020 2032 3433
3133 3637 3136 3135 0002 0002 0004 7664
6420 312e 3030 5361 6e44 6973 6b20 5344
5033 422d 3136 2020 2020 2020 2020 2020
2020 2020 2020 2020 2020 2020 2020 0001
0000 0200 0000 0100 0000 0001 01ea 0002
0020 7a80 0000 0100 7a80 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 2020
2020 2020 2020 2020 2020 2032 3433 3133
3637 3136 3135 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000




--
Denys Fedoryshchenko
Technical Manager
Virtual ISP S.A.L.

2007-11-07 21:37:25

by Denys Fedoryschenko

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

This is another flash (1GB probably original Sandisk), 16MB one is called
SHARP, and it is 2-4 years old. Maybe it is some noname with incorrect
identification data. But it works well in USB card reader.

[ 12.434864] ide: Assuming 33MHz system bus speed for PIO modes; override
with idebus=xx
[ 12.459739] SC1200: IDE controller (0x100b:0x0502 rev 0x01) at PCI slot
0000:00:12.2
[ 12.483360] SC1200: not 100% native mode: will probe irqs later
[ 12.501235] ide0: BM-DMA at 0xfc00-0xfc07, BIOS settings: hda:pio,
hdb:pio
[ 12.523191] ide1: BM-DMA at 0xfc08-0xfc0f, BIOS settings: hdc:pio,
hdd:pio
[ 12.545128] Probing IDE interface ide0...
[ 12.558292] Clocksource tsc unstable (delta = -497091188 ns)
[ 12.575348] Time: pit clocksource has been installed.
[ 12.593376] hda: SanDisk SDCFH-1024, CFA DISK drive
[ 12.608342] hda: applying conservative PIO "downgrade"
[ 12.623867] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO1
[ 12.624015] hda: selected mode 0x22
[ 12.624308] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[ 12.638802] Probing IDE interface ide1...
[ 12.653412] hda: max request size: 128KiB
[ 12.665560] hda: 2001888 sectors (1024 MB) w/1KiB Cache, CHS=1986/16/63,
DMA
[ 12.687374] hda:<4>hda: dma_timer_expiry: dma status == 0x21
[ 12.906900] hda: DMA timeout error
[ 12.917152] ide_dma_end dma_stat=21 err=1 newerr=0
[ 12.931812] hda: dma timeout error: status=0x58 { DriveReady SeekComplete
DataRequest }
[ 12.956039] ide: failed opcode was: unknown
[ 12.968807] hda: DMA disabled
[ 12.978108] ide0: reset: success
[ 12.989416] hda1
[ 12.998883] scx200_wdt: NatSemi SCx200 Watchdog Driver
[ 12.998957] scx200_wdt: timer margin 60 seconds
[ 13.012656] scx200_wdt: disabling watchdog timer
[ 13.013427] cpuidle: using governor ladder
[ 13.025906] cpuidle: using governor menu
[ 13.037808] enabling scx200 high-res timer (1 MHz +0 ppm)
[ 13.054084] Netfilter messages via NETLINK v0.30.
[ 13.068256] Time: scx200_hrt clocksource has been installed.
[ 13.085331] Switched to high resolution mode on CPU 0
[ 13.100573] TCP cubic registered
[ 13.110312] NET: Registered protocol family 1
[ 13.123516] NET: Registered protocol family 17
[ 13.136915] Using IPI Shortcut mode
[ 13.154057] Freeing unused kernel memory: 680k freed
[ 14.340096] eth0: DSPCFG accepted after 0 usec.
[ 14.353718] eth0: link up.
[ 14.361893] eth0: Setting full-duplex based on negotiated link capability.

here i am trying to mount partition (1Gb) /dev/hda1 to /mnt

[ 14.456362] hda: dma_timer_expiry: dma status == 0x21
[ 14.491812] hda: DMA timeout error
[ 14.502061] ide_dma_end dma_stat=21 err=1 newerr=0
[ 14.516718] hda: dma timeout error: status=0x58 { DriveReady SeekComplete
DataRequest }
[ 14.540944] ide: failed opcode was: unknown
[ 14.553712] hda: DMA disabled
[ 14.562712] ide0: reset: success
[ 14.789644] hda: dma_timer_expiry: dma status == 0x21
[ 14.862229] hda: DMA timeout error
[ 14.872491] ide_dma_end dma_stat=21 err=1 newerr=0
[ 14.887148] hda: dma timeout error: status=0x58 { DriveReady SeekComplete
DataRequest }
[ 14.911375] ide: failed opcode was: unknown
[ 14.924143] hda: DMA disabled
[ 14.933142] ide0: reset: success
[ 14.988000] hda: dma_timer_expiry: dma status == 0x21
[ 15.023352] hda: DMA timeout error
[ 15.033608] ide_dma_end dma_stat=21 err=1 newerr=0
[ 15.048267] hda: dma timeout error: status=0x58 { DriveReady SeekComplete
DataRequest }
[ 15.072494] ide: failed opcode was: unknown
[ 15.085261] hda: DMA disabled
[ 15.094269] ide0: reset: success

and it is failed.

WRAP ~ #./hdparm -I /dev/hda

/dev/hda:

CompactFlash ATA device, with removable media
Model Number: SanDisk SDCFH-1024
Serial Number: 111718F1705D0016
Firmware Revision: HDX 3.07
Standards:
Supported: 4
Likely used: 4
Configuration:
Logical max current
cylinders 1986 1986
heads 16 16
sectors/track 63 63
--
CHS current addressable sectors: 2001888
LBA user addressable sectors: 2001888
device size with M = 1024*1024: 977 MBytes
device size with M = 1000*1000: 1024 MBytes (1 GB)
Capabilities:
LBA, IORDY(may be)(cannot be disabled)
Standby timer values: spec'd by Vendor
R/W multiple sector transfer: Max = 4 Current = 0
DMA: mdma0 mdma1 mdma2 (?)
Cycle time: min=120ns recommended=120ns
PIO: pio0 pio1 pio2 pio3 pio4
Cycle time: no flow control=120ns IORDY flow control=120ns
Commands/features:
Enabled Supported:
* CFA feature set

WRAP ~ #./hdparm --Istdout /dev/hda

/dev/hda:
848a 07c2 0000 0010 0000 0240 003f 001e
8be0 0000 2020 2020 3131 3137 3138 4631
3730 3544 3030 3136 0002 0002 0004 4844
5820 332e 3037 5361 6e44 6973 6b20 5344
4346 482d 3130 3234 2020 2020 2020 2020
2020 2020 2020 2020 2020 2020 2020 0004
0000 0300 0000 0200 0000 0003 07c2 0010
003f 8be0 001e 0100 8be0 001e 0000 0007
0003 0078 0078 0078 0078 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0010 0000 0000 4004 4000 0020 0004 4000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000



On Wed, 07 Nov 2007 14:12:55 -0500, Mark Lord wrote
> > WRAP ~ #./hdparm -I /dev/hda
> >
> > /dev/hda:
> >
> > ATAPI Write-once device, with non-removable media
> > Model Number: SanDisk SDP3B-16
> > Serial Number: 24313671615
> > Firmware Revision: vdd 1.00
> > Standards:
> > Likely used: 3
> > Configuration:
> > DRQ response: 50us.
> > Packet size: Unknown
> > Capabilities:
> > LBA, IORDY(may be)(cannot be disabled)
> > Buffer size: 1.0kB bytes avail on r/w long: 4
> > DMA: not supported
> > PIO: pio0 pio1
>
> "ATAPI Write-once device" ???
>
> That cannot be correct (??). Is this with hdparm-7.7 (latest
> sourceforge) ?? Can you show us the "hdparm --Istdout" output as
> well, please.
>
> thanks.


--
Denys Fedoryshchenko
Technical Manager
Virtual ISP S.A.L.

2007-11-07 22:31:25

by Mark Lord

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

Denys Fedoryshchenko wrote:
> On Wed, 07 Nov 2007 14:12:55 -0500, Mark Lord wrote
>>> WRAP ~ #./hdparm -I /dev/hda
>>>
>>> /dev/hda:
>>>
>>> ATAPI Write-once device, with non-removable media
>>> Model Number: SanDisk SDP3B-16
>>> Serial Number: 24313671615
>>> Firmware Revision: vdd 1.00
>>> Standards:
>>> Likely used: 3
>>> Configuration:
>>> DRQ response: 50us.
>>> Packet size: Unknown
>>> Capabilities:
>>> LBA, IORDY(may be)(cannot be disabled)
>>> Buffer size: 1.0kB bytes avail on r/w long: 4
>>> DMA: not supported
>>> PIO: pio0 pio1
>> "ATAPI Write-once device" ???
>>
>> That cannot be correct (??). Is this with hdparm-7.7 (latest
>> sourceforge) ?? Can you show us the "hdparm --Istdout" output as
>> well, please.
>>
>> thanks.
> Yes latest hdparm-7.7.
...

> WRAP ~ #./hdparm --Istdout /dev/hda
>
> /dev/hda:
> 844a 01ea 0000 0002 0000 0240 0020 0000
..

CFA spec requires 848a in word[0], not 844a, which explains
why hdparm thought it might be ATAPI. I've updated my hdparm
source to detect this for hdparm-7.8 (unreleased).
That card (16MB) is definitely buggy.
But your other (1GB) card looks fine.

Thanks. Somebody else may help with the libata issue.

2007-11-07 22:55:13

by Alan

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

> CFA spec requires 848a in word[0], not 844a, which explains
> why hdparm thought it might be ATAPI. I've updated my hdparm
> source to detect this for hdparm-7.8 (unreleased).
> That card (16MB) is definitely buggy.
> But your other (1GB) card looks fine.
>
> Thanks. Somebody else may help with the libata issue.

848A is the preferred configuration value

The use of 044A is also allowed in the case of it being non-removable.
Guess someone got muddled up in the middle.

844A is a bit iffy and it should still be detectable as Word 83 bit 2
should be set ?


As to the old IDE (not libata ?) issue - I suspect a convertor without
DMA support as a first guess.

Alan

2007-11-07 23:20:59

by Jeff Garzik

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

On Wed, Nov 07, 2007 at 02:12:55PM -0500, Mark Lord wrote:
> That cannot be correct (??). Is this with hdparm-7.7 (latest sourceforge)
> ??
> Can you show us the "hdparm --Istdout" output as well, please.

If this is applicable... FWIW hdparm was only recently (in past <72
hours) updated from 6.9 to 7.7 in Fedora...

Jeff

2007-11-07 23:24:32

by Mark Lord

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

Jeff Garzik wrote:
> On Wed, Nov 07, 2007 at 02:12:55PM -0500, Mark Lord wrote:
>> That cannot be correct (??). Is this with hdparm-7.7 (latest sourceforge)
>> ??
>> Can you show us the "hdparm --Istdout" output as well, please.
>
> If this is applicable... FWIW hdparm was only recently (in past <72
> hours) updated from 6.9 to 7.7 in Fedora...
>
> Jeff

Woo-hoo!

2007-11-07 23:50:23

by Denys Fedoryschenko

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

I am using Gentoo (and it is custom build of linux, actually only busybox +
kernel + uclibc and few other tools), hdparm is vanilla 7.7

I will try to compile now -rc2 to see if there any changes.

With 16MB 2.6.24-rc1 works fine, 1GB working also with some errors in dmesg.

And IF that all is important, cause it is relatively old hardware and
probably if it is only this hardware-specific bug, it is enough to issue
workaround just to be able to use it. I dont think so someone using them now
much, but IMHO things must work in kernel if they are there.

On Wed, 7 Nov 2007 18:20:45 -0500, Jeff Garzik wrote
> On Wed, Nov 07, 2007 at 02:12:55PM -0500, Mark Lord wrote:
> > That cannot be correct (??). Is this with hdparm-7.7 (latest
sourceforge)
> > ??
> > Can you show us the "hdparm --Istdout" output as well, please.
>
> If this is applicable... FWIW hdparm was only recently (in past <72
> hours) updated from 6.9 to 7.7 in Fedora...
>
> Jeff


--
Denys Fedoryshchenko
Technical Manager
Virtual ISP S.A.L.

2007-11-08 00:02:13

by Denys Fedoryschenko

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

2.6.24-rc2 not working very well


dmesg
[ 12.386395] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
[ 12.405579] ide: Assuming 33MHz system bus speed for PIO modes; override
with idebus=xx
[ 12.430441] SC1200: IDE controller (0x100b:0x0502 rev 0x01) at PCI slot
0000:00:12.2
[ 12.454070] SC1200: not 100% native mode: will probe irqs later
[ 12.471947] ide0: BM-DMA at 0xfc00-0xfc07, BIOS settings: hda:pio,
hdb:pio
[ 12.493873] ide1: BM-DMA at 0xfc08-0xfc0f, BIOS settings: hdc:pio,
hdd:pio
[ 12.515810] Probing IDE interface ide0...
[ 12.528810] Clocksource tsc unstable (delta = -497423729 ns)
[ 12.545888] Time: pit clocksource has been installed.
[ 12.563379] hda: SanDisk SDCFH-1024, CFA DISK drive
[ 12.578340] hda: applying conservative PIO "downgrade"
[ 12.593869] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO1
[ 12.594006] hda: MW DMA 2 mode selected
[ 12.594297] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[ 12.608778] Probing IDE interface ide1...
[ 12.623192] hda: max request size: 128KiB
[ 12.635322] hda: 2001888 sectors (1024 MB) w/1KiB Cache, CHS=1986/16/63,
DMA
[ 12.657134] hda:<4>hda: dma_timer_expiry: dma status == 0x21
[ 12.865846] hda: DMA timeout error
[ 12.876092] ide_dma_end dma_stat=21 err=1 newerr=0
[ 12.890753] hda: dma timeout error: status=0x58 { DriveReady SeekComplete
DataRequest }
[ 12.914977] ide: failed opcode was: unknown
[ 12.927743] hda: DMA disabled
[ 12.937035] ide0: reset: success
[ 12.948324] hda1

Mounting taking long time on 1GB card cause of DMA issues. In dmesg i am not
sure about timestamp showing few seconds, in real life it took about 2
minutes.

after that in dmesg
[ 14.965070] hda: dma_timer_expiry: dma status == 0x21
[ 15.107909] hda: DMA timeout error
[ 15.118149] ide_dma_end dma_stat=21 err=1 newerr=0
[ 15.132809] hda: dma timeout error: status=0x58 { DriveReady SeekComplete
DataRequest }
[ 15.157035] ide: failed opcode was: unknown
[ 15.169799] hda: DMA disabled
[ 15.178797] ide0: reset: success
[ 15.312698] hda: dma_timer_expiry: dma status == 0x21
[ 15.650705] hda: DMA timeout error
[ 15.660952] ide_dma_end dma_stat=21 err=1 newerr=0
[ 15.675614] hda: dma timeout error: status=0x58 { DriveReady SeekComplete
DataRequest }
[ 15.699836] ide: failed opcode was: unknown
[ 15.712601] hda: DMA disabled
[ 15.721603] ide0: reset: success
[ 16.325999] hda: dma_timer_expiry: dma status == 0x21
[ 16.565756] hda: DMA timeout error
[ 16.576001] ide_dma_end dma_stat=21 err=1 newerr=0
[ 16.590661] hda: dma timeout error: status=0x58 { DriveReady SeekComplete
DataRequest }
[ 16.614886] ide: failed opcode was: unknown
[ 16.627651] hda: DMA disabled
[ 16.636659] ide0: reset: success
[ 16.650061] EXT2-fs warning: mounting unchecked fs, running e2fsck is
recommended


On Wed, 7 Nov 2007 18:20:45 -0500, Jeff Garzik wrote
> On Wed, Nov 07, 2007 at 02:12:55PM -0500, Mark Lord wrote:
> > That cannot be correct (??). Is this with hdparm-7.7 (latest
sourceforge)
> > ??
> > Can you show us the "hdparm --Istdout" output as well, please.
>
> If this is applicable... FWIW hdparm was only recently (in past <72
> hours) updated from 6.9 to 7.7 in Fedora...
>
> Jeff


--
Denys Fedoryshchenko
Technical Manager
Virtual ISP S.A.L.

2007-11-08 00:24:32

by Bartlomiej Zolnierkiewicz

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

On Thursday 08 November 2007, Denys Fedoryshchenko wrote:
> 2.6.24-rc2 not working very well
>
>
> dmesg
> [ 12.386395] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
> [ 12.405579] ide: Assuming 33MHz system bus speed for PIO modes; override
> with idebus=xx
> [ 12.430441] SC1200: IDE controller (0x100b:0x0502 rev 0x01) at PCI slot
> 0000:00:12.2
> [ 12.454070] SC1200: not 100% native mode: will probe irqs later
> [ 12.471947] ide0: BM-DMA at 0xfc00-0xfc07, BIOS settings: hda:pio,
> hdb:pio
> [ 12.493873] ide1: BM-DMA at 0xfc08-0xfc0f, BIOS settings: hdc:pio,
> hdd:pio
> [ 12.515810] Probing IDE interface ide0...
> [ 12.528810] Clocksource tsc unstable (delta = -497423729 ns)
> [ 12.545888] Time: pit clocksource has been installed.
> [ 12.563379] hda: SanDisk SDCFH-1024, CFA DISK drive
> [ 12.578340] hda: applying conservative PIO "downgrade"
> [ 12.593869] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO1
> [ 12.594006] hda: MW DMA 2 mode selected
> [ 12.594297] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
> [ 12.608778] Probing IDE interface ide1...
> [ 12.623192] hda: max request size: 128KiB
> [ 12.635322] hda: 2001888 sectors (1024 MB) w/1KiB Cache, CHS=1986/16/63,
> DMA
> [ 12.657134] hda:<4>hda: dma_timer_expiry: dma status == 0x21
> [ 12.865846] hda: DMA timeout error
> [ 12.876092] ide_dma_end dma_stat=21 err=1 newerr=0
> [ 12.890753] hda: dma timeout error: status=0x58 { DriveReady SeekComplete
> DataRequest }
> [ 12.914977] ide: failed opcode was: unknown
> [ 12.927743] hda: DMA disabled
> [ 12.937035] ide0: reset: success
> [ 12.948324] hda1
>
> Mounting taking long time on 1GB card cause of DMA issues. In dmesg i am not
> sure about timestamp showing few seconds, in real life it took about 2
> minutes.

Please try booting with "hda=nodma".

It could be a hardware problem (CF adapter without DMA lines).

Thanks,
Bart

2007-11-08 01:41:35

by Robert Hancock

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

Denys wrote:
> Finally i got full DMESG with 1GB card till end. Seems not readable too.
>

..


> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096 in
> res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> ata1.00: status: { DRDY }
> ata1: soft resetting link
> ata1.00: configured for MWDMA1
> sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
> sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
> Descriptor sense data with sense descriptors (in hex):
> 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
> 00 00 00 00
> sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
> end_request: I/O error, dev sda, sector 0
> Buffer I/O error on device sda, logical block 0
> ata1: EH complete

I'm guessing that your CF-to-IDE adapter doesn't have the correct lines
wired up for DMA to work properly, and the card indicates DMA support,
which libata tries to use but which doesn't work. It looks like it never
tried falling back to PIO after DMA failed. Seems like a deficiency in
the speed-down logic?

--
Robert Hancock Saskatoon, SK, Canada
To email, remove "nospam" from [email protected]
Home Page: http://www.roberthancock.com/

2007-11-08 07:01:59

by Denys Fedoryschenko

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

You are right, seems no dma lines in adapter. hda=nodma helped, no errors
anymore. I will try now also libata_dma_mask and will mail result. Btw there
is no notes in Documentation/kernel-parameters.txt about it.

In any case it is complete board, WRAP.2C made by PCEngines in 2003. Kind of
popular and mass produced, before was widely used by StarOS, probably known
GPL violator, who didn't bother himself to supply patches, but at same time
used it in his projects.

If it is valid for all board with this revision, maybe it is better to put it
in some kind of fixup/quirk/black list, or how it is called?

On Wed, 07 Nov 2007 19:41:15 -0600, Robert Hancock wrote
> Denys wrote:
> > Finally i got full DMESG with 1GB card till end. Seems not readable too.
> >
>
> ...
>
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> > ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0 data 4096
in
> > res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
> > ata1.00: status: { DRDY }
> > ata1: soft resetting link
> > ata1.00: configured for MWDMA1
> > sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
> > sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
> > Descriptor sense data with sense descriptors (in hex):
> > 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
> > 00 00 00 00
> > sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
> > end_request: I/O error, dev sda, sector 0
> > Buffer I/O error on device sda, logical block 0
> > ata1: EH complete
>
> I'm guessing that your CF-to-IDE adapter doesn't have the correct
> lines wired up for DMA to work properly, and the card indicates DMA
> support, which libata tries to use but which doesn't work. It looks
> like it never tried falling back to PIO after DMA failed. Seems like
> a deficiency in the speed-down logic?
>
> --
> Robert Hancock Saskatoon, SK, Canada
> To email, remove "nospam" from [email protected]
> Home Page: http://www.roberthancock.com/


--
Denys Fedoryshchenko
Technical Manager
Virtual ISP S.A.L.

2007-11-08 07:16:58

by Denys Fedoryschenko

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

Does it work as kernel parameter?

I tried libata_dma_mask=0x4 and to set 0xf or 0xff - doesn't help. How to
disable DMA in libata, if it is compiled in kernel?

On Thu, 8 Nov 2007 01:30:53 +0100, Bartlomiej Zolnierkiewicz wrote
> On Thursday 08 November 2007, Denys Fedoryshchenko wrote:
> > 2.6.24-rc2 not working very well
> >
> >
> > dmesg
> > [ 12.386395] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
> > [ 12.405579] ide: Assuming 33MHz system bus speed for PIO modes;
override
> > with idebus=xx
> > [ 12.430441] SC1200: IDE controller (0x100b:0x0502 rev 0x01) at PCI
slot
> > 0000:00:12.2
> > [ 12.454070] SC1200: not 100% native mode: will probe irqs later
> > [ 12.471947] ide0: BM-DMA at 0xfc00-0xfc07, BIOS settings: hda:pio,
> > hdb:pio
> > [ 12.493873] ide1: BM-DMA at 0xfc08-0xfc0f, BIOS settings: hdc:pio,
> > hdd:pio
> > [ 12.515810] Probing IDE interface ide0...
> > [ 12.528810] Clocksource tsc unstable (delta = -497423729 ns)
> > [ 12.545888] Time: pit clocksource has been installed.
> > [ 12.563379] hda: SanDisk SDCFH-1024, CFA DISK drive
> > [ 12.578340] hda: applying conservative PIO "downgrade"
> > [ 12.593869] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO1
> > [ 12.594006] hda: MW DMA 2 mode selected
> > [ 12.594297] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
> > [ 12.608778] Probing IDE interface ide1...
> > [ 12.623192] hda: max request size: 128KiB
> > [ 12.635322] hda: 2001888 sectors (1024 MB) w/1KiB Cache, CHS=1986/16/
63,
> > DMA
> > [ 12.657134] hda:<4>hda: dma_timer_expiry: dma status == 0x21
> > [ 12.865846] hda: DMA timeout error
> > [ 12.876092] ide_dma_end dma_stat=21 err=1 newerr=0
> > [ 12.890753] hda: dma timeout error: status=0x58 { DriveReady
SeekComplete
> > DataRequest }
> > [ 12.914977] ide: failed opcode was: unknown
> > [ 12.927743] hda: DMA disabled
> > [ 12.937035] ide0: reset: success
> > [ 12.948324] hda1
> >
> > Mounting taking long time on 1GB card cause of DMA issues. In dmesg i am
not
> > sure about timestamp showing few seconds, in real life it took about 2
> > minutes.
>
> Please try booting with "hda=nodma".
>
> It could be a hardware problem (CF adapter without DMA lines).
>
> Thanks,
> Bart


--
Denys Fedoryshchenko
Technical Manager
Virtual ISP S.A.L.

2007-11-08 10:48:38

by Alan

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

On Thu, 8 Nov 2007 09:16:35 +0200
"Denys Fedoryshchenko" <[email protected]> wrote:

> Does it work as kernel parameter?
>
> I tried libata_dma_mask=0x4 and to set 0xf or 0xff - doesn't help. How to
> disable DMA in libata, if it is compiled in kernel?

libata.dma_mask=3

will leave you with CD and disk DMA but not CF DMA

(Note libata[DOT] not underscore)

2007-11-08 16:44:57

by Denys Fedoryschenko

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

Doesn't help

WRAP ~ #cat /proc/cmdline
console=ttyS0,38400n8 libata.dma_mask=3

and in dmesg

[ 8.601592] scsi 0:0:0:0: Direct-Access ATA SanDisk SDCFH-10 HDX
PQ: 0 ANSI: 5
[ 8.627943] sd 0:0:0:0: [sda] 2001888 512-byte hardware sectors (1025 MB)
[ 8.648634] sd 0:0:0:0: [sda] Write Protect is off
[ 8.663129] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 8.663605] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 8.691824] sd 0:0:0:0: [sda] 2001888 512-byte hardware sectors (1025 MB)
[ 8.712512] sd 0:0:0:0: [sda] Write Protect is off
[ 8.726982] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 8.727462] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 8.754700] sda:<4>Clocksource tsc unstable (delta = 140275209 ns)
[ 8.774136] Time: pit clocksource has been installed.
[ 8.979224] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
[ 9.000527] ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0
data 4096 in
[ 9.000581] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4
(timeout)
[ 9.046810] ata1.00: status: { DRDY }
[ 9.057903] ata1: soft resetting link
[ 9.071700] ata1.00: configured for MWDMA2
[ 9.084159] ata1: EH complete
[ 9.283365] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
[ 9.304673] ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0
data 4096 in
[ 9.304728] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4
(timeout)
[ 9.350958] ata1.00: status: { DRDY }
[ 9.362046] ata1: soft resetting link
[ 9.375830] ata1.00: configured for MWDMA2
[ 9.388276] ata1: EH complete
[ 9.587493] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
[ 9.608806] ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0
data 4096 in
[ 9.608861] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4
(timeout)
[ 9.655090] ata1.00: status: { DRDY }
[ 9.666182] ata1: soft resetting link
[ 9.679975] ata1.00: configured for MWDMA2
[ 9.692423] ata1: EH complete
[ 9.891737] ata1.00: limiting speed to MWDMA1:PIO4
[ 9.906214] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
[ 9.927483] ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0
data 4096 in
[ 9.927526] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4
(timeout)
[ 9.973754] ata1.00: status: { DRDY }
[ 9.984844] ata1: soft resetting link
[ 9.998623] ata1.00: configured for MWDMA1
[ 10.011068] ata1: EH complete
[ 10.210291] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
[ 10.231591] ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0
data 4096 in
[ 10.231634] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4
(timeout)
[ 10.277850] ata1.00: status: { DRDY }
[ 10.288943] ata1: soft resetting link
[ 10.302724] ata1.00: configured for MWDMA1
[ 10.315174] ata1: EH complete
[ 10.514480] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
[ 10.535774] ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0
data 4096 in
[ 10.535818] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4
(timeout)
[ 10.582034] ata1.00: status: { DRDY }
[ 10.593124] ata1: soft resetting link
[ 10.606903] ata1.00: configured for MWDMA1
[ 10.619335] sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
[ 10.638312] sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
[ 10.657784] Descriptor sense data with sense descriptors (in hex):
[ 10.676411] 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
[ 10.696558] 00 00 00 00
[ 10.706601] sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
[ 10.720127] end_request: I/O error, dev sda, sector 0
[ 10.735360] Buffer I/O error on device sda, logical block 0
[ 10.752212] ata1: EH complete
[ 10.951561] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
[ 10.972864] ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0
data 4096 in
[ 10.972907] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4
(timeout)
[ 11.019124] ata1.00: status: { DRDY }
[ 11.030214] ata1: soft resetting link
[ 11.044001] ata1.00: configured for MWDMA1
[ 11.056439] ata1: EH complete
[ 11.255766] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
[ 11.277057] ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0
data 4096 in
[ 11.277100] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4
(timeout)
[ 11.323315] ata1.00: status: { DRDY }
[ 11.334406] ata1: soft resetting link
[ 11.348187] ata1.00: configured for MWDMA1
[ 11.360617] ata1: EH complete
[ 11.559852] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
[ 11.581152] ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0
data 4096 in
[ 11.581195] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4
(timeout)
[ 11.627411] ata1.00: status: { DRDY }
[ 11.638503] ata1: soft resetting link
[ 11.652281] ata1.00: configured for MWDMA1
[ 11.664726] ata1: EH complete
[ 11.864037] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
[ 11.885338] ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0
data 4096 in
[ 11.885382] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4
(timeout)
[ 11.931623] ata1.00: status: { DRDY }
[ 11.942712] ata1: soft resetting link
[ 11.956488] ata1.00: configured for MWDMA1
[ 11.968934] ata1: EH complete
[ 12.168151] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
[ 12.189442] ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0
data 4096 in
[ 12.189485] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4
(timeout)
[ 12.235701] ata1.00: status: { DRDY }
[ 12.246790] ata1: soft resetting link
[ 12.260575] ata1.00: configured for MWDMA1
[ 12.273015] ata1: EH complete
[ 12.472358] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
[ 12.493668] ata1.00: cmd c8/00:08:00:00:00/00:00:00:00:00/e0 tag 0 cdb 0x0
data 4096 in
[ 12.493712] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4
(timeout)
[ 12.539929] ata1.00: status: { DRDY }
[ 12.551019] ata1: soft resetting link
[ 12.564802] ata1.00: configured for MWDMA1
[ 12.577247] sd 0:0:0:0: [sda] Result: hostbyte=0x00 driverbyte=0x08
[ 12.596225] sd 0:0:0:0: [sda] Sense Key : 0xb [current] [descriptor]
[ 12.615694] Descriptor sense data with sense descriptors (in hex):
[ 12.634324] 72 0b 00 00 00 00 00 0c 00 0a 80 00 00 00 00 00
[ 12.654472] 00 00 00 00
[ 12.664516] sd 0:0:0:0: [sda] ASC=0x0 ASCQ=0x0
[ 12.678039] end_request: I/O error, dev sda, sector 0
[ 12.693273] Buffer I/O error on device sda, logical block 0
[ 12.710123] ata1: EH complete
[ 12.719187] unable to read partition table
[ 12.732602] sd 0:0:0:0: [sda] Attached SCSI removable disk



On Thu, 8 Nov 2007 10:48:13 +0000, Alan Cox wrote
> On Thu, 8 Nov 2007 09:16:35 +0200
> "Denys Fedoryshchenko" <[email protected]> wrote:
>
> > Does it work as kernel parameter?
> >
> > I tried libata_dma_mask=0x4 and to set 0xf or 0xff - doesn't help. How to
> > disable DMA in libata, if it is compiled in kernel?
>
> libata.dma_mask=3
>
> will leave you with CD and disk DMA but not CF DMA
>
> (Note libata[DOT] not underscore)


--
Denys Fedoryshchenko
Technical Manager
Virtual ISP S.A.L.

2007-11-08 17:31:50

by Jeff Garzik

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

On Thu, Nov 08, 2007 at 06:44:31PM +0200, Denys Fedoryshchenko wrote:
> Doesn't help
>
> WRAP ~ #cat /proc/cmdline
> console=ttyS0,38400n8 libata.dma_mask=3

It's "libata.dma" if its built into the kernel, or 'dma' module option
if built as a kernel module.

Jeff

2007-11-08 19:57:06

by Denys Fedoryschenko

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

Thanks, it works like that.

Seems in libata there is no fall-back to non-DMA mode, if DMA didn't work.

On Thu, 8 Nov 2007 12:31:39 -0500, Jeff Garzik wrote
> On Thu, Nov 08, 2007 at 06:44:31PM +0200, Denys Fedoryshchenko wrote:
> > Doesn't help
> >
> > WRAP ~ #cat /proc/cmdline
> > console=ttyS0,38400n8 libata.dma_mask=3
>
> It's "libata.dma" if its built into the kernel, or 'dma' module
> option if built as a kernel module.
>
> Jeff


--
Denys Fedoryshchenko
Technical Manager
Virtual ISP S.A.L.

2007-11-08 22:41:18

by Alan

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

On Thu, 8 Nov 2007 21:56:32 +0200
"Denys Fedoryshchenko" <[email protected]> wrote:

> Thanks, it works like that.
>
> Seems in libata there is no fall-back to non-DMA mode, if DMA didn't work.


It should be falling back from UDMA or MWDMA to PIO, if not please file a
bug

Alan

2007-11-09 01:44:54

by Tejun Heo

Subject: Re: SC1200 failure in 2.6.23 and 2.6.24-rc1-git10

Denys Fedoryshchenko wrote:
> Thanks, it works like that.
>
> Seems in libata there is no fall-back to non-DMA mode, if DMA didn't work.

There is, it's just too conservative about that. With improvements
pending for 2.6.24, it should be quite snappy at falling back to PIO if
configured transfer mode doesn't seem to work at all (consecutive IO
command failures after transfer mode configuration change).

--
tejun