[#4773] BF527 usb-ide hard disk error

Submitted By: Yi Li

Open Date

2009-01-05 04:37:00 Close Date

2010-06-11 04:34:04

Priority:

Medium High Assignee:

Nobody

Status:

Closed Fixed In Release:

N/A

Found In Release:

2008R1.5-RC3 Release:

svn trunk

Category:

N/A Board:

N/A

Processor:

BF527 Silicon Revision:

0.2

Is this bug repeatable?:

Yes Resolution:

Duplicate

Uboot version or rev.:

Toolchain version or rev.:

svn trunk

App binary format:

N/A

Summary: BF527 usb-ide hard disk error

Details:

I tried to run bonnie++ on BF527-ezkit, with usb-ide hard disk. There is error "usb 1-1: device descriptor read/64, error -71". The usb-ide bridge is "JMicron JM20337". I use the save device on BF548, it works well.

Details bellow:

root:/> mkfs.ext2 /dev/sda2

mke2fs 1.41.3 (12-Oct-2008)

ext2fs_check_if_mount: No such file or directory while determining whether /dev.

Filesystem label=

OS type: Linux

Block size=4096 (log=2)

Fragment size=4096 (log=2)

301328 inodes, 1204875 blocks

60243 blocks (5.00%) reserved for the super user

First data block=0

Maximum filesystem blocks=1237319680

37 block groups

32768 blocks per group, 32768 fragments per group

8144 inodes per group

Superblock backups stored on blocks:

32768, 98304, 163840, 229376, 294912, 819200, 884736

Writing inode tables: done

Writing superblocks and filesystem accounting information: done

This filesystem will be automatically checked every 28 mounts or

180 days, whichever comes first. Use tune2fs -c or -i to override.

root:/> mkfs.vfat /dev/sda1

mkfs.vfat 2.11 (12 Mar 2005)

root:/> mount /dev/sda2 /mnt/

root:/> bonnie++ -u root -d /mnt

Using uid:0, gid:0.

Writing a byte at a time...usb 1-1: USB disconnect, address 3

Buffer I/O error on device sda2, logical block 0

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 1

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 296

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 298

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 20492

lost page write due to I/O error on sda2

------------[ cut here ]------------

WARNING: at fs/buffer.c:1185 _mark_buffer_dirty+0x7e/0x88()

Modules linked in:

Hardware Trace:

0 Target : <0x0000489c> { _dump_stack + 0x0 }

Source : <0x0000e93c> { _warn_on_slowpath + 0x44 } CALL pcrel

1 Target : <0x0000e93c> { _warn_on_slowpath + 0x44 }

Source : <0x00027ed6> { _print_modules + 0x7a } RTS

2 Target : <0x00027ed0> { _print_modules + 0x74 }

Source : <0x0000f04e> { _printk + 0x16 } RTS

3 Target : <0x0000f04a> { _printk + 0x12 }

Source : <0x0000f860> { _vprintk + 0x12c } RTS

4 Target : <0x0000f840> { _vprintk + 0x10c }

Source : <0x0000f984> { _vprintk + 0x250 } JUMP.S

5 Target : <0x0000f984> { _vprintk + 0x250 }

Source : <0x0000eebe> { _wake_up_klogd + 0x1a } RTS

6 Target : <0x0000eebe> { _wake_up_klogd + 0x1a }

Source : <0x0000eeb0> { _wake_up_klogd + 0xc } IF !CC JUMP

7 Target : <0x0000eea4> { _wake_up_klogd + 0x0 }

Source : <0x0000f2d0> { _release_console_sem + 0x1a8 } JUMP.L

8 Target : <0x0000f2c8> { _release_console_sem + 0x1a0 }

Source : <0x0000f2bc> { _release_console_sem + 0x194 } IF !CC JUMP

9 Target : <0x0000f2b8> { _release_console_sem + 0x190 }

Source : <0x0000f2aa> { _release_console_sem + 0x182 } IF !CC JUMP

10 Target : <0x0000f2a4> { _release_console_sem + 0x17c }

Source : <0x00021908> { _up + 0x38 } RTS

11 Target : <0x00021902> { _up + 0x32 }

Source : <0x000218f4> { _up + 0x24 } IF !CC JUMP

12 Target : <0x000218d0> { _up + 0x0 }

Source : <0x0000f2a0> { _release_console_sem + 0x178 } CALL pcrel

13 Target : <0x0000f28c> { _release_console_sem + 0x164 }

Source : <0x0000f17a> { _release_console_sem + 0x52 } IF !CC JUMP

14 Target : <0x0000f14a> { _release_console_sem + 0x22 }

Source : <0x0000f26e> { _release_console_sem + 0x146 } IF !CC JUMP

15 Target : <0x0000f268> { _release_console_sem + 0x140 }

Source : <0x0000ee12> { __call_console_drivers + 0x7a } RTS

Stack info:

SP: [0x014cfa30] <0x014cfa30> /* kernel dynamic memory */

FP: (0x014cfa2c)

Memory from 0x014cfa30 to 014d0000

014cfa30:[0000e93c]<0000e940> 014cfa50 <0017fe94> 014cfa64 <0017fe94> 000004a1

014cfa50: 72616d5f 75625f6b 72656666 7269645f 302b7974 2f653778 38387830

014cfa70: 03ed9080 03ed9080 00000002 00000000 014cfab0 <0013e7d2> 03ed91d8

014cfa90: 001a9e30 00000001 014ce000 03a5c49c <000bc61c> 00831800 014ce000

014cfab0: 014cfae0 <0013ec38> 001aa208 008cb020 00000002 00000000 014cfb44

014cfad0: 0000001f 014cfb44 00000001 014cfb4c 000561bc <000561ec> 014cfb3c >

014cfaf0:<0008bb20> 008f9240 <0013edb4> 00000000 0000500c 014cfc2c 005820ac >

014cfb10: 02f7e650 005820ac 00001000 02f7e650 00000002 000561bc 00000000

014cfb30:<00055bae> 00516ac8 <00182160> 00182174 00000002 00000000 <00076c3e>>

014cfb50: 014d4400 03bb3200 <0005612c> 00000002 0000500c <000770c0> 03bb3200

014cfb70: 02f7e650 00000002 <00057da8> 00000002 <000752c4> 014cfc28 00000000

014cfb90:<000750ba> 014cfbe4 ffffffff 0000000c 0000500c <000754ea> 014cfbe4

014cfbb0: 014cfbe4 00000002 ffffffec 00000000 00001000 <0005544e><00055434>

014cfbd0: 00000001 014cfc24 00001000 005820a0 00582040 00582070 0000500c

014cfbf0: fffffffb 02f7e54c <00056bd8> 00000000 fffffffb 00001000 00001000

014cfc10: 00000000 0000000c 00000215 005820ac 014cfd34 fffffffb 00000000

014cfc30:<0003951e> 005820ac 005820ac 014cfd34 fffffffb 00000000 00221000

014cfc50: 005820ac 014cfd34 00000001 <00056d38> 00000000 008b7140 005820ac

014cfc70: 00000000 00000001 00075840 <000757e4> 014ce000 00582144 00000000

014cfc90: 00000000 00221000 00000000 00000000 00000001 00221000 00000000

014cfcb0: 00000000 014cfd34 014cfd30 00075840 <0002e5ba> 00001000 cc397cea

014cfcd0: 00221000 00000000 00000001 00000000 014cfd34 014cfd30 03812840

014cfcf0: 00147b90 005820ac 00000000 00147b90 00000000 00000001 <0002d722>

014cfd10: 028b6904 00582144 00000000 <00012628> 014cfe9c 00000001 00000000

014cfd30: 00000000 00000000 00582144 <0002edb8> 005820ac 00000001 03812840

014cfd50: 014cfe9c 00000000 00221000 014cfe18 <00012e56> 001aeba8 00221000

014cfd70: 014cfe60 00000001 00000000 <0002c1b8> 001bacd0 00000006 02f78004

014cfd90: 028b6910 <ffa002da> 001b31c4 00000004 00000001 00582144 <0002efd2>

014cfdb0: 005820ac 03812840 00582118 014cfe9c 00000000 00221000 014cfe18

014cfdd0: 00000001 014cfe60 <ffa00a8a> 0005d698 028b6910 <0003d7f8> 03812840

014cfdf0: 02f78004 014cfe18 014cfe9c 00000001 028b69f0 02888b97 00099cb4

014cfe10: 00221000 00000000 00000000 ffffb910 00000000 00000001 ffffffff

014cfe30: 00000000 00000000 00000000 00000000 03ed9080 00000000 00000000

014cfe50: 03ed9080 0001ea28 014cfe58 014cfe58 00221000 00000000 00000002

014cfe70: 028b6910 005820ac 00000001 02f78004 001d462c 014cfea8 00000000

014cfe90: 001a9e60 028b6910 <0003df0c> 028b6953 00000001 <0003decc> 03812840

014cfeb0: 014cfef0 028b6953 00000040 001a9e30 03edf5e0 014cfef0 00000000 >

014cfed0: 03812840 028b6953 00000001 00000001 028b69f0 028b6910 <00013216>

014cfef0: 00221000 00000000 00000000 <ffa00774> 0003e200 00000000 ffffe000

014cff10: 02f78004 00000003 00000000 028b69f0 00000001 0289173e 00008000

014cff30: 00000000 014d0000 0289173e 0289173e <0288e4c4><ffa00ca8> 02003025

014cff50: 0288847d 02888b62 02888470 00000000 00000000 ffffb910 ffffffff

014cff70: ffffffff 00000000 00000000 0000001e 028b68cc 00000000 00000000

014cff90: 00000000 00000000 00000000 00000000 00000000 00000000 00000001

014cffb0: 00000000 028b6904 028b6910 028b698c 028b6a68 02f78004 028aabe4

014cffd0: 00000004 00000003 028b6953 00000001 028b69f0 00000000 00000001

014cfff0: 00000003 00000003 00000004 00000006 03020100

Return addresses in stack:

address : <0x0000e93c> { _warn_on_slowpath + 0x44 }

address : <0x0000e940> { _warn_on_slowpath + 0x48 }

address : <0x0017fe94> /* kernel dynamic memory */

address : <0x0013e7d2> { _schedule + 0xb6 }

address : <0x000bc61c> { _scsi_request_fn + 0x250 }

address : <0x0013ec38> { _io_schedule + 0x2c }

address : <0x000561ec> { _sync_buffer + 0x30 }

address : <0x00059716> { _bvec_alloc_bs + 0x3a }

address : <0x0008bb20> { _submit_bio + 0x44 }

address : <0x0013edb4> { ___wait_on_bit + 0x60 }

address : <0x0013ef1e> { _out_of_line_wait_on_bit + 0x52 }

address : <0x00055bae> { _mark_buffer_dirty + 0x7e }

address : <0x00182160> /* kernel dynamic memory */

address : <0x00076c3e> { _ext2_sync_super + 0x16 }

address : <0x00076c50> { _ext2_sync_super + 0x28 }

address : <0x0005612c> { ___wait_on_buffer + 0x20 }

address : <0x000770c0> { _ext2_error + 0x8c }

address : <0x00057da8> { ___bread + 0xac }

address : <0x000752c4> { _ext2_free_branches + 0xb4 }

address : <0x000750ba> { _ext2_get_branch + 0x5a }

address : <0x000754ea> { _ext2_truncate + 0x1b6 }

address : <0x0005544e> { _alloc_page_buffers + 0x62 }

address : <0x00055434> { _alloc_page_buffers + 0x48 }

address : <0x00056bd8> { ___block_prepare_write + 0x440 }

address : <0x0003951e> { _vmtruncate + 0x3a }

address : <0x00056d38> { _block_write_begin + 0xe0 }

address : <0x000757e4> { ___ext2_write_begin + 0x38 }

address : <0x0002e5ba> { _generic_file_buffered_write + 0xe6 }

address : <0x0002d722> { _file_remove_suid + 0x12 }

address : <0x00012628> { _current_fs_time + 0x14 }

address : <0x0002edb8> { ___generic_file_aio_write_nolock + 0x1f0 }

address : <0x00012e56> { ___do_softirq + 0x5a }

address : <0x0002c1b8> { _handle_simple_irq + 0x74 }

address : <0xffa002da> { _asm_do_IRQ + 0x5a }

address : <0x0002efd2> { _generic_file_aio_write + 0x56 }

address : <0xffa00a8a> { __common_int_entry + 0x62 }

address : <0x0003d7f8> { _do_sync_write + 0xac }

address : <0x0003df0c> { _vfs_write + 0xac }

address : <0x0003decc> { _vfs_write + 0x6c }

address : <0x0003e230> { _sys_write + 0x30 }

address : <0x00013216> { _irq_enter + 0xa }

address : <0xffa00774> { _system_call + 0x68 }

usb 1-1: new high speed USB device using musb_hdrc and address 4

address : <0x0288e4c4> [ bonnie++ + 0xe484 ]

address : <0xffa00ca8> { _evt_system_call + 0x64 }

---[ end trace 4f177fe446d99d4b ]---

Buffer I/O error on device sda2, logical block 0

lost page write due to I/O error on sda2

EXT2-fs error (device sda2): ext2_free_branches: Read failure, inode=12, block=2

Can't Bruff() er Isk /O error on device sda2, logical block 0

lost page write due to I/O error on sda2

EXT2-fs error (device sda2): ext2_get_inode: unable to read inode block - inode8

Can't sync file.

Buffer I/O error on device sda2, logical block 0

lost page write due to I/O error on sda2

EXT2-fs error (device sda2): ext2_get_inode: unable to read inode block - inode8

Buffer I/O error on device sda2, logical block 0

lost page write due to I/O error on sda2

EXT2-fs error (device sda2): read_block_bitmap: Cannot read block bitmap - bloc6

Buffer I/O error on device sda2, logical block 0

lost page write due to I/O error on sda2

EXT2-fs error (device sda2): read_inode_bitmap: Cannot read inode bitmap - bloc7

root:/> usb 1-1: configuration #1 chosen from 1 choice

scsi2 : SCSI emulation for USB Mass Storage devices

usb 1-1: reset high speed USB device using musb_hdrc and address 4

usb 1-1: device descriptor read/64, error -71

usb 1-1: reset high speed USB device using musb_hdrc and address 4

usb 1-1: device descriptor read/64, error -71

scsi 2:0:0:0: Direct-Access IBM-DTLA -307015 A50C PQ: 0 ANSI: 2 CCS

sd 2:0:0:0: [sdb] 30003120 512-byte hardware sectors: (15.3 GB/14.3 GiB)

sd 2:0:0:0: [sdb] Write Protect is off

sd 2:0:0:0: [sdb] Assuming drive cache: write through

sd 2:0:0:0: [sdb] 30003120 512-byte hardware sectors: (15.3 GB/14.3 GiB)

sd 2:0:0:0: [sdb] Write Protect is off

sd 2:0:0:0: [sdb] Assuming drive cache: write through

sdb:<6>usb 1-1: reset high speed USB device using musb_hdrc and address 4

usb 1-1: reset high speed USB device using musb_hdrc and address 4

sd 2:0:0:0: [sdb] Result: hostbyte=0x07 driverbyte=0x00

end_request: I/O error, dev sdb, sector 0

Buffer I/O error on device sdb, logical block 0

usb 1-1: reset high speed USB device using musb_hdrc and address 4

sd 2:0:0:0: [sdb] Result: hostbyte=0x07 driverbyte=0x00

end_request: I/O error, dev sdb, sector 0

Buffer I/O error on device sdb, logical block 0

unable to read partition table

sd 2:0:0:0: [sdb] Attached SCSI disk

Buffer I/O error on device sda2, logical block 0

lost page write due to I/O error on sda2

EXT2-fs error (device sda2): ext2_get_inode: unable to read inode block - inode8

root:/> usb 1-1: USB disconnect, address 4

usb 1-1: new high speed USB device using musb_hdrc and address 5

usb 1-1: configuration #1 chosen from 1 choice

scsi3 : SCSI emulation for USB Mass Storage devices

usb 1-1: reset high speed USB device using musb_hdrc and address 5

usb 1-1: device descriptor read/64, error -71

usb 1-1: reset high speed USB device using musb_hdrc and address 5

usb 1-1: device descriptor read/64, error -71

usb 1-1: reset high speed USB device using musb_hdrc and address 5

Follow-ups

--- Yi Li 2009-01-05 05:42:51

This is not a bug. I am using bf527-0.2 chip, while I configured kernel as 0.0.

If set kernel as bf527-0.2, this bug seems to disappear.

--- Yi Li 2009-01-05 05:55:58

After plug-play for a while, this bug appears again - reopen - sorry.

--------------------

root:/> mkfs.ext2 /dev/sda2

mke2fs 1.41.3 (12-Oct-2008)

ext2fs_check_if_mount: No such file or directory while determining whether

/dev.

Filesystem label=

OS type: Linux

Block size=4096 (log=2)

Fragment size=4096 (log=2)

301328 inodes, 1204875 blocks

60243 blocks (5.00%) reserved for the super user

First data block=0

Maximum filesystem blocks=1237319680

37 block groups

32768 blocks per group, 32768 fragments per group

8144 inodes per group

Superblock backups stored on blocks:

32768, 98304, 163840, 229376, 294912, 819200, 884736

Writing inode tables: usb 1-1: reset high speed USB device using musb_hdrc and

usb 1-1: device descriptor read/64, error -71

usb 1-1: reset high speed USB device using musb_hdrc and address 2

usb 1-1: device descriptor read/64, error -71

usb 1-1: reset high speed USB device using musb_hdrc and address 2

usb 1-1: device not accepting address 2, error -71

usb 1-1: reset high speed USB device using musb_hdrc and address 2

usb 1-1: device not accepting address 2, error -71

usb 1-1: USB disconnect, address 2

sd 0:0:0:0: [sda] Result: hostbyte=0x01 driverbyte=0x00

end_request: I/O error, dev sda, sector 13047128

Buffer I/O error on device sda2, logical block 426016

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 426017

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 426018

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 426019

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 426020

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 426021

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 426022

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 426023

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 426024

lost page write due to I/O error on sda2

Buffer I/O error on device sda2, logical block 426025

lost page write due to I/O error on sda2

sd 0:0:0:0: [sda] Result: hostbyte=0x01 driverbyte=0x00

end_request: I/O error, dev sda, sector 13047368

20/usb 1-1: new high speed USB device using musb_hdrc and address 3

usb 1-1: device descriptor read/64, error -71

usb 1-1: new high speed USB device using musb_hdrc and address 4

usb 1-1: device descriptor read/64, error -71

usb 1-1: new high speed USB device using musb_hdrc and address 5

done

ext2fs_mkdir: Attempt to read block from filesystem resulted in short read

whilr

root:/> usb 1-1: device not accepting address 5, error -71

usb 1-1: new high speed USB device using musb_hdrc and address 6

usb 1-1: device not accepting address 6, error -71

hub 1-0:1.0: unable to enumerate USB device on port 1

--- Bryan Wu 2009-02-13 01:12:53

Maybe this issue has the same root cause in Bug 4669. We will retest it when we

got the final hardware fixing method.

-Bryan

--- Sonic Zhang 2009-04-07 23:53:55

Can't replicate on svn trunk with bf527 v0.2

root:/> usr/bin/bonnie\+\+ -u root -d /mnt/

Using uid:0, gid:0.

Writing a byte at a time...done

Writing intelligently...done

Rewriting...done

Reading a byte at a time...done

Reading intelligently...done

start 'em...done...done...done...done...done...

Create files in sequential order...done.

Stat files in sequential order...done.

Delete files in sequential order...done.

Create files in random order...done.

Stat files in random order...done.

Delete files in random order...done.

Version 1.94 ------Sequential Output------ --Sequential Input-

--Random-

Concurrency 1 -Per Chr- --Block-- -Rewrite- -Per Chr- --Block--

--Seeks--

Machine Size K/sec %CP K/sec %CP K/sec %CP K/sec %CP K/sec %CP /sec

%CP

blackfin 300M 48 99 13837 89 6925 69 199 99 13157 59 128.7

Latency 187ms 148ms 240ms 84000us 660ms 395ms

Version 1.94 ------Sequential Create------ --------Random

Create--------

blackfin -Create-- --Read--- -Delete-- -Create-- --Read---

-Delete--

files /sec %CP /sec %CP /sec %CP /sec %CP /sec %CP /sec

%CP

16 223 99 8605 99 4858 94 220 99 9246 99 774

Latency 44000us 4000us 4000us 44000us 4000us 4000us

1.93c,1.94,blackfin,1,1623230000,300M,,48,99,13837,89,6925,69,199,99,13157,59,128.7,5,16,,,,,s

--- Sonic Zhang 2009-04-08 02:23:12

OK. replicate ramdomly.

root:/> /usr/bin/bonnie\+\+ -u root -d /mnt

Using uid:0, gid:0.

Writing a byte at a time...done

Writing intelligently...done

Rewriting...done NReading a byte at a time...done

Reading intelligently...done

start 'em...done...done...done...done...done...

Create files in sequential order...done.

Stat files in sequential order...done.

Delete files in sequential order...done.

Create files in random order...done.

Stat files in random order...done.

Delete files in random order...usb 1-1: reset high speed USB device using

musb_hdrc and addre2

usb 1-1: device descriptor read/64, error -71

usb 1-1: reset high speed USB device using musb_hdrc and address 2

usb 1-1: device descriptor read/64, error -71

usb 1-1: reset high speed USB device using musb_hdrc and address 2

usb 1-1: device not accepting address 2, error -71

usb 1-1: reset high speed USB device using musb_hdrc and address 2

usb 1-1: device not accepting address 2, error -71

usb 1-1: USB disconnect, address 2

sd 0:0:0:0: Device offlined - not ready after error recovery

sd 0:0:0:0: [sda] Result: hostbyte=0x05 driverbyte=0x00

end_request: I/O error, dev sda, sector 20107948

Buffer I/O error on device sda3, logical block 829948

lost page write due to I/O error on sda3

Buffer I/O error on device sda3, logical block 829949

lost page write due to I/O error on sda3

Buffer I/O error on device sda3, logical block 829950

lost page write due to I/O error on sda3

Buffer I/O error on device sda3, logical block 829951

lost page write due to I/O error on sda3

Buffer I/O error on device sda3, logical block 829952

lost page write due to I/O error on sda3

sd 0:0:0:0: rejecting I/O to offline device

Buffer I/O error on device sda3, logical block 2038

lost page write due to I/O error on sda3

sd 0:0:0:0: rejecting I/O to offline device

Buffer I/O error on device sda3, logical block 1843

lost page write due to I/O error on sda3

Buffer I/O error on device sda3, logical block 1844

lost page write due to I/O error on sda3

Buffer I/O error on device sda3, logical block 1845

lost page write due to I/O error on sda3

sd 0:0:0:0: rejecting I/O to offline device

Buffer I/O error on device sda3, logical block 840

lost page write due to I/O error on sda3

sd 0:0:0:0: rejecting I/O to offline device

Files

Changes

Commits

Dependencies

Duplicates

Associations