[#5148] test usb isp1760 with bonnie++, error info shows up

Submitted By: Vivi Li

Open Date

2009-05-20 06:54:21 Close Date

2010-02-22 10:27:06

Priority:

Medium Assignee:

Michael Hennerich

Status:

Closed Fixed In Release:

N/A

Found In Release:

2009R1.1-RC4 Release:

Category:

N/A Board:

N/A

Processor:

BF537 Silicon Revision:

Is this bug repeatable?:

Yes Resolution:

Fixed

Uboot version or rev.:

Toolchain version or rev.:

gcc4.1-09r1_rc6

App binary format:

N/A

Summary: test usb isp1760 with bonnie++, error info shows up

Details:

test usb isp1760 with bonnie++, it always show error info "ep1out scatterlist error".

root:/> mount -t ext2 /dev/sda1 /mnt^M

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

Using uid:0, gid:0.^M

Writing a byte at a time...error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error -32/-32^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

done^M

Writing intelligently...error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

done^M

Rewriting...error bit is set in DW3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

done^M

Reading a byte at a time...done^M

Reading intelligently...done^M

start 'em...done...done...done...done...done...^M

Create files in sequential order...error bit is set in DW3^M

done.^M

Stat files in sequential order...done.^M

Delete files in sequential order...error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

usb 1-1.3: reset high speed USB device using isp1760-hcd and address 3^M

done.^M

Create files in random order...error bit is set in DW3^M

isp1760-hcd isp1760-hcd.0: dev 1.3 ep1out scatterlist error 0/-32^M

done.^M

Stat files in random order...done.^M

Delete files in random order...done.^M

Version 1.94 ------Sequential Output------ --Sequential Input- --Random-^M

Concurrency 1 -Per Chr- --Block-- -Rewrite- -Per Chr- --Block-- --Seeks--^M

Machine Size K/sec %CP K/sec %CP K/sec %CP K/sec %CP K/sec %CP /sec %CP^M

blackfin 300M 19 34 815 4 1446 17 181 99 5891 24 92.4 4^M

Latency 207ms 33483ms 31172ms 60000us 584ms 25547ms^M

Version 1.94 ------Sequential Create------ --------Random Create--------^M

blackfin -Create-- --Read--- -Delete-- -Create-- --Read--- -Delete--^M

files /sec %CP /sec %CP /sec %CP /sec %CP /sec %CP /sec %CP^M

16 266 96 8015 100 466 8 268 96 8677 99 913 90^M

Latency 172ms 4000us 4000us 31999us 4000us 4000us^M

1.93c,1.94,blackfin,1,15246972,300M,,19,34,815,4,1446,17,181,99,5891,24,92.4,4,16,,,,,266,96,8015,100,466,8,268,96,8677,99,913,90,207ms,33483ms,31172ms,60000us,584ms,25547ms,172ms,4000us,4000us,31999us,4000us,4000us^M

root:/>

Follow-ups

--- Mike Frysinger 2009-05-20 08:15:03

please retest after my new commit

--- Vivi Li 2009-05-22 02:22:15

This bug still exists.

--- Michael Hennerich 2009-05-25 04:53:04

Sorry can't replicate this issue -

see below and config attached.

-Michael

Linux version 2.6.28.10-ADI-2010R1-pre-svn6467 (michael@mhenneri-D02) (gcc

version 4.1.2 (ADI svn)) #10582 Mon

May 25 10:12:16 CEST 2009

Warning: limiting memory to 56MB due to hardware anomaly 05000263

Board Memory: 64MB

Kernel Managed Memory: 64MB

Memory map:

fixedcode = 0x00000400-0x00000490

text = 0x00001000-0x00142200

rodata = 0x00142200-0x001ad3ac

bss = 0x001ae000-0x001bfe6c

data = 0x001bfe6c-0x001d4000

stack = 0x001d2000-0x001d4000

init = 0x001d4000-0x00ea9000

available = 0x00ea9000-0x037ff000

DMA Zone = 0x03f00000-0x04000000

Hardware Trace Active and Enabled

Boot Mode: 0

Reset caused by Software reset

Compiled for ADSP-BF537 Rev 0.2

Warning: Compiled for Rev 2, but running on Rev 3

Blackfin Linux support by http://blackfin.uclinux.org/

Processor Speed: 500 MHz core clock and 125 MHz System Clock

NOMPU: setting up cplb tables

Instruction Cache Enabled for CPU0

Data Cache Enabled for CPU0 (write-back)

Built 1 zonelists in Zone order, mobility grouping off. Total pages: 14223

Kernel command line:

Configuring Blackfin Priority Driven Interrupts

PID hash table entries: 256 (order: 8, 1024 bytes)

console [ttyBF0] enabled

Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)

Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)

Memory available: 41760k/65536k RAM, (13140k init code, 1284k kernel code, 584k

data, 1024k dma, 7744k reserved

)

Calibrating delay loop... 997.37 BogoMIPS (lpj=1994752)

Security Framework initialized

Mount-cache hash table entries: 512

Blackfin Scratchpad data SRAM: 4 KB

Blackfin L1 Data A SRAM: 16 KB (15 KB free)

Blackfin L1 Data B SRAM: 16 KB (16 KB free)

Blackfin L1 Instruction SRAM: 48 KB (37 KB free)

net_namespace: 288 bytes

NET: Registered protocol family 16

Blackfin DMA Controller

stamp_init(): registering device resources

SCSI subsystem initialized

usbcore: registered new interface driver usbfs

usbcore: registered new interface driver hub

usbcore: registered new device driver usb

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

NET: Registered protocol family 1

msgmni has been set to 81

io scheduler noop registered

io scheduler anticipatory registered (default)

io scheduler cfq registered

Serial: Blackfin serial driver

bfin-uart.1: ttyBF0 at MMIO 0xffc00400 (irq = 18) is a BFIN-UART

brd: module loaded

bfin_mii_bus: probed

bfin_mac: attached PHY driver [SMSC LAN83C185] (mii_bus:phy_addr=0:01, irq=-1,

mdc_clk=2500000Hz(mdc_div=24)@sc

lk=125MHz)

bfin_mac bfin_mac.0: Blackfin on-chip Ethernet MAC driver, Version 1.1

Driver 'sd' needs updating - please use bus_type methods

bfin-spi bfin-spi.0: Blackfin on-chip SPI Controller Driver, Version 1.0,

regs_base@ffc00500, dma channel@7

isp1760-hcd isp1760-hcd.0: NXP ISP1760 USB Host Controller

isp1760-hcd isp1760-hcd.0: new USB bus registered, assigned bus number 1

isp1760-hcd isp1760-hcd.0: bus width: 16, oc: digital

isp1760-hcd isp1760-hcd.0: irq 57, io mem 0x203c0000

isp1760-hcd isp1760-hcd.0: USB ISP 1761 HW rev. 1 started

usb usb1: configuration #1 chosen from 1 choice

hub 1-0:1.0: USB hub found

hub 1-0:1.0: 1 port detected

Initializing USB Mass Storage driver...

usbcore: registered new interface driver usb-storage

USB Mass Storage support registered.

rtc-bfin rtc-bfin: rtc core: registered rtc-bfin as rtc0

bfin-wdt: initialized: timeout=20 sec (nowayout=0)

usbcore: registered new interface driver usbhid

usbhid: v2.6:USB HID core driver

TCP cubic registered

NET: Registered protocol family 17

rtc-bfin rtc-bfin: setting system clock to 1970-04-27 20:37:35 UTC (10096655)

Freeing unused kernel memory: 13140k freed

dma_alloc_init: dma_page @ 0x00ea6000 - 256 pages at 0x03f00000

port 1 high speed

usb 1-1: new high speed USB device using isp1760-hcd and address 2

port 1 high speed

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

hub 1-1:1.0: USB hub found

hub 1-1:1.0: 3 ports detected

_____________________________________

a8888b. / Welcome to the uClinux distribution \

d888888b. / _ _ \

8P"YP"Y88 / | | |_| __ __ (TM) |

8|o||o|88 _____/ | | _ ____ _ _ \ \/ / |

8' .88 \ | | | | _ \| | | | \ / |

8`._.' Y8. \ | |__ | | | | | |_| | / \ |

d/ `8b. \ \____||_|_| |_|\____|/_/\_\ |

dP . Y8b. \ For embedded processors including |

d8:' " `::88b \ the Analog Devices Blackfin /

d8" 'Y88b \___________________________________/

:8P ' :888

8a. : _a88P For further information, check out:

._/"Yaa_: .| 88P| - http://blackfin.uclinux.org/

\ YP" `| 8P `. - http://docs.blackfin.uclinux.org/

/ \.___.d| .' - http://www.uclinux.org/

`--..__)8888P`._.' jgs/a:f - http://www.analog.com/blackfin

Have a lot of fun...

BusyBox v1.13.4 (2009-05-25 09:43:41 CEST) built-in shell (msh)

Enter 'help' for a list of built-in commands.

root:/> usb 1-1.3: new high speed USB device using isp1760-hcd and address

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

scsi0 : SCSI emulation for USB Mass Storage devices

root:/> scsi 0:0:0:0: Direct-Access SanDisk U3 Cruzer Micro 2.21 PQ: 0

ANSI: 2

sd 0:0:0:0: [sda] 1994385 512-byte hardware sectors: (1.02 GB/973 MiB)

sd 0:0:0:0: [sda] Write Protect is off

sd 0:0:0:0: [sda] Assuming drive cache: write through

sd 0:0:0:0: [sda] 1994385 512-byte hardware sectors: (1.02 GB/973 MiB)

sd 0:0:0:0: [sda] Write Protect is off

sd 0:0:0:0: [sda] Assuming drive cache: write through

sda: sda1

sd 0:0:0:0: [sda] Attached SCSI removable disk

root:/> mount /dev/sda1 /mnt

root:/> 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 72 98 2743 15 2767 33 173 99 6216 32 93.2

Latency 160ms 2060ms 407ms 60000us 676ms 815ms

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 265 96 8241 100 3450 65 273 96 8808 99 915

Latency 39999us 4000us 4000us 39999us 4000us 4000us

1.93c,1.94,blackfin,1,10096841,300M,,72,98,2743,15,2767,33,173,99,6216,32,93.2,4,16,,,,,265,96,8241,100,3450,65

,273,96,8808,99,915,90,160ms,2060ms,407ms,60000us,676ms,815ms,39999us,4000us,4000us,39999us,4000us,4000us

root:/> version

kernel: Linux release 2.6.28.10-ADI-2010R1-pre-svn6467, build #10582 Mon May

25 10:12:16 CEST 2009

toolchain: bfin-linux-uclibc-gcc release gcc version 4.1.2 (ADI svn)

user-dist: release svn-8040, build #3617 Mon May 25 10:09:36 CEST 2009

root:/> mount

rootfs on / type rootfs (rw)

proc on /proc type proc (rw,nosuid,nodev,noexec)

sysfs on /sys type sysfs (rw,nosuid,nodev,noexec)

mdev on /dev type tmpfs (rw,nosuid)

devpts on /dev/pts type devpts (rw,nosuid,noexec,mode=600)

var on /var type ramfs (rw)

tmp on /tmp type tmpfs (rw,nosuid,nodev)

debugfs on /sys/kernel/debug type debugfs (rw)

/dev/sda1 on /mnt type ext2 (rw,errors=continue)

root:/> cd sys/kernel/debug/blackfin/EBIU/

root:/sys/kernel/debug/blackfin/EBIU> cat EBIU_AMBCTL1

0x99b27bb0

root:/sys/kernel/debug/blackfin/EBIU> cat EBIU_AMBCTL0

0x7bb07bb0

root:/sys/kernel/debug/blackfin/EBIU>

--- Vivi Li 2009-07-14 07:04:33

I use your config in latest branch, and bellow is the log.

For my original config, the result is the same as I described previously.

Linux version 2.6.28.10-ADI-2009R1-svn6990 (test@uboot34-bf537stamp) (gcc

version 4.1.2 (ADI svn)) #10 Tue Jul 14 18:53:29 CST 29

bootconsole [early_shadow0] enabled

bootconsole [early_BFuart0] enabled

early printk enabled on early_BFuart0

Warning: limiting memory to 56MB due to hardware anomaly 05000263

Board Memory: 64MB

Kernel Managed Memory: 64MB

Memory map:

fixedcode = 0x00000400-0x00000490

text = 0x00001000-0x00139a00

rodata = 0x00139a00-0x0019fc20

bss = 0x001a0000-0x001b1e6c

data = 0x001b1e6c-0x001c6000

stack = 0x001c4000-0x001c6000

init = 0x001c6000-0x007dc000

available = 0x007dc000-0x037ff000

DMA Zone = 0x03f00000-0x04000000

Hardware Trace Active and Enabled

Boot Mode: 0

Recovering from Watchdog event

Compiled for ADSP-BF537 Rev 0.2

Blackfin Linux support by http://blackfin.uclinux.org/

Processor Speed: 500 MHz core clock and 125 MHz System Clock

NOMPU: setting up cplb tables

Instruction Cache Enabled for CPU0

Data Cache Enabled for CPU0 (write-back)

Built 1 zonelists in Zone order, mobility grouping off. Total pages: 14223

Kernel command line: root=/dev/mtdblock0 rw clkin_hz=25000000

earlyprintk=serial,uart0,57600 console=ttyBF0,57600 ip=10.100.4.50f

Configuring Blackfin Priority Driven Interrupts

PID hash table entries: 256 (order: 8, 1024 bytes)

console handover:boot [early_BFuart0] boot [early_shadow0] -> real

[ttyBF0]

Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)

Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)

Memory available: 48724k/65536k RAM, (6232k init code, 1250k kernel code, 562k

data, 1024k dma, 7744k reserved)

Calibrating delay loop... 995.32 BogoMIPS (lpj=1990656)

Security Framework initialized

Mount-cache hash table entries: 512

Blackfin Scratchpad data SRAM: 4 KB

Blackfin L1 Data A SRAM: 16 KB (15 KB free)

Blackfin L1 Data B SRAM: 16 KB (16 KB free)

Blackfin L1 Instruction SRAM: 48 KB (37 KB free)

net_namespace: 288 bytes

NET: Registered protocol family 16

Blackfin DMA Controller

stamp_init(): registering device resources

SCSI subsystem initialized

usbcore: registered new interface driver usbfs

usbcore: registered new interface driver hub

usbcore: registered new device driver usb

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

NET: Registered protocol family 1

msgmni has been set to 95

io scheduler noop registered

io scheduler anticipatory registered (default)

io scheduler cfq registered

Serial: Blackfin serial driver

bfin-uart.1: ttyBF0 at MMIO 0xffc00400 (irq = 18) is a BFIN-UART

brd: module loaded

bfin_mii_bus: probed

bfin_mac: attached PHY driver [SMSC LAN83C185] (mii_bus:phy_addr=0:01, irq=-1,

mdc_clk=2500000Hz(mdc_div=24)@sclk=125MHz)

bfin_mac bfin_mac.0: Blackfin on-chip Ethernet MAC driver, Version 1.1

Driver 'sd' needs updating - please use bus_type methods

bfin-spi bfin-spi.0: Blackfin on-chip SPI Controller Driver, Version 1.0,

regs_base@ffc00500, dma channel@7

isp1760-hcd isp1760-hcd.0: NXP ISP1760 USB Host Controller

isp1760-hcd isp1760-hcd.0: new USB bus registered, assigned bus number 1

isp1760-hcd isp1760-hcd.0: bus width: 16, oc: digital

isp1760-hcd isp1760-hcd.0: irq 57, io mem 0x203c0000

isp1760-hcd isp1760-hcd.0: USB ISP 1761 HW rev. 1 started

usb usb1: configuration #1 chosen from 1 choice

hub 1-0:1.0: USB hub found

hub 1-0:1.0: 1 port detected

Initializing USB Mass Storage driver...

usbcore: registered new interface driver usb-storage

USB Mass Storage support registered.

rtc-bfin rtc-bfin: rtc core: registered rtc-bfin as rtc0

bfin-wdt: initialized: timeout=20 sec (nowayout=0)

usbcore: registered new interface driver usbhid

usbhid: v2.6:USB HID core driver

TCP cubic registered

NET: Registered protocol family 17

rtc-bfin rtc-bfin: setting system clock to 1970-01-01 02:08:32 UTC (7712)

port 1 high speed

usb 1-1: new high speed USB device using isp1760-hcd and address 2

port 1 high speed

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

hub 1-1:1.0: USB hub found

hub 1-1:1.0: 3 ports detected

usb 1-1.2: new high speed USB device using isp1760-hcd and address 3

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

scsi0 : SCSI emulation for USB Mass Storage devices

IP-Config: Complete:

device=eth0, addr=10.100.4.50, mask=255.255.255.0, gw=10.100.4.174,

host=bf537-stamp, domain=, nis-domain=(none),

bootserver=10.100.4.174, rootserver=10.100.4.174, rootpath=

Freeing unused kernel memory: 6232k freed

dma_alloc_init: dma_page @ 0x007d9000 - 256 pages at 0x03f00000

_____________________________________

a8888b. / Welcome to the uClinux distribution \

d888888b. / _ _ \

8P"YP"Y88 / | | |_| __ __ (TM) |

8|o||o|88 _____/ | | _ ____ _ _ \ \/ / |

8' .88 \ | | | | _ \| | | | \ / |

8`._.' Y8. \ | |__ | | | | | |_| | / \ |

d/ `8b. \ \____||_|_| |_|\____|/_/\_\ |

dP . Y8b. \ For embedded processors including |

d8:' " `::88b \ the Analog Devices Blackfin /

d8" 'Y88b \___________________________________/

:8P ' :888

8a. : _a88P For further information, check out:

._/"Yaa_: .| 88P| - http://blackfin.uclinux.org/

\ YP" `| 8P `. - http://docs.blackfin.uclinux.org/

/ \.___.d| .' - http://www.uclinux.org/

`--..__)8888P`._.' jgs/a:f - http://www.analog.com/blackfin

Have a lot of fun...

PHY: 0:01 - Link is Up - 100/Full

BusyBox v1.13.4 (2009-07-14 15:53:36 CST) built-in shell (msh)

Enter 'help' for a list of built-in commands.

root:/> scsi 0:0:0:0: Direct-Access SanDisk U3 Cruzer Micro 3.21 PQ: 0

ANSI: 2

sd 0:0:0:0: [sda] 2006673 512-byte hardware sectors: (1.02 GB/979 MiB)

sd 0:0:0:0: [sda] Write Protect is off

sd 0:0:0:0: [sda] Assuming drive cache: write through

sd 0:0:0:0: [sda] 2006673 512-byte hardware sectors: (1.02 GB/979 MiB)

sd 0:0:0:0: [sda] Write Protect is off

sd 0:0:0:0: [sda] Assuming drive cache: write through

sda: sda1

sd 0:0:0:0: [sda] Attached SCSI removable disk

root:/> scsi 0:0:0:0: Direct-Access SanDisk U3 Cruzer Micro 3.21 PQ: 0

ANSI: 2

sd 0:0:0:0: [sda] 2006673 512-byte hardware sectors: (1.02 GB/979 MiB)

sd 0:0:0:0: [sda] Write Protect is off

sd 0:0:0:0: [sda] Assuming drive cache: write through

sd 0:0:0:0: [sda] 2006673 512-byte hardware sectors: (1.02 GB/979 MiB)

sd 0:0:0:0: [sda] Write Protect is off

sd 0:0:0:0: [sda] Assuming drive cache: write through

sda: sda1

sd 0:0:0:0: [sda] Attached SCSI removable disk

root:/>

root:/> fdisk /dev/sda

Command (m for help): p

Disk /dev/sda: 32 heads, 62 sectors, 1011 cylinders

Units = cylinders of 1984 * 512 bytes

Device Boot Start End Blocks Id System

/dev/sda1 1 1011 1002881 83 Linux

Command (m for help): d

Partition number (1-4): 1

Command (m for help): n

Command action

e extended

p primary partition (1-4)

Partition number (1-4): 1

First cylinder (1-1011, default 1):

Using default value 1

Last cylinder or +size or +sizeM or +sizeK (1-1011, default 1011):

Using default value 1011

Command (m for help): w

The partition table has been altered!

Calling ioctl() to re-read partition table.

sd 0:0:0:0: [sda] 2006673 512-byte hardware sectors: (1.02 GB/979 MiB)

error bit is set in DW3

usb 1-1.2: reset high speed USB device using isp1760-hcd and address 3

sd 0:0:0:0: [sda] Write Protect is off

sd 0:0:0:0: [sda] Assuming drive cache: write through

sda: sda1

sd 0:0:0:0: [sda] 2006673 512-byte hardware sectors: (1.02 GB/979 MiB)

sd 0:0:0:0: [sda] Write Protect is off

sd 0:0:0:0: [sda] Assuming drive cache: write through

sda: sda1

WARNING: If you have created or modified any DOS 6.x

partitions, please see the fdisk manual page for additional

information.

Syncing disks.

root:/> mke2fs /dev/sda1

mke2fs 1.41.4 (27-Jan-2009)

ext2fs_check_if_mount: No such file or directory while determining whether

/dev/sda1 is mounted.

Filesystem label=

OS type: Linux

Block size=4096 (log=2)

Fragment size=4096 (log=2)

62720 inodes, 250720 blocks

12536 blocks (5.00%) reserved for the super user

First data block=0

Maximum filesystem blocks=260046848

8 block groups

32768 blocks per group, 32768 fragments per group

7840 inodes per group

Superblock backups stored on blocks:

32768, 98304, 163840, 229376

Writing inode tables: done

Writing superblocks and filesystem accounting information: done

This filesystem will be automatically checked every 34 mounts or

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

root:/> mount

root:/> mount -t ext2 /dev/sda1 /mnt

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

Using uid:0, gid:0.

Writing a byte at a time...done

Writing intelligently...error bit is set in DW3

usb 1-1.2: reset high speed USB device using isp1760-hcd and address 3

Hardware Trace:

0 Target : <0x0000482c> { _dump_bfin_trace_buffer + 0x0 }

Source : <0x000d28a8> { _enqueue_an_ATL_packet + 0x144 } CALL pcrel

1 Target : <0x000d28a8> { _enqueue_an_ATL_packet + 0x144 }

Source : <0x000d2788> { _enqueue_an_ATL_packet + 0x24 } IF !CC JUMP

2 Target : <0x000d2784> { _enqueue_an_ATL_packet + 0x20 }

Source : <0x000d1a3c> { _isp1760_readl + 0x14 } RTS

3 Target : <0x000d1a28> { _isp1760_readl + 0x0 }

Source : <0x000d2780> { _enqueue_an_ATL_packet + 0x1c } CALL pcrel

4 Target : <0x000d2764> { _enqueue_an_ATL_packet + 0x0 }

Source : <0x000d370a> { _isp1760_irq + 0x60e } CALL pcrel

5 Target : <0x000d3702> { _isp1760_irq + 0x606 }

Source : <0x000d37d2> { _isp1760_irq + 0x6d6 } IF CC JUMP

6 Target : <0x000d37ce> { _isp1760_irq + 0x6d2 }

Source : <0x000d2174> { _clean_this_qtd + 0x34 } RTS

7 Target : <0x000d216c> { _clean_this_qtd + 0x2c }

Source : <0x0003c320> { _kmem_cache_free + 0x3c } RTS

8 Target : <0x0003c31a> { _kmem_cache_free + 0x36 }

Source : <0x0003c30c> { _kmem_cache_free + 0x28 } IF !CC JUMP

9 Target : <0x0003c2e4> { _kmem_cache_free + 0x0 }

Source : <0x000d20ac> { _isp1760_qtd_free + 0xc } JUMP.L

10 Target : <0x000d20a0> { _isp1760_qtd_free + 0x0 }

Source : <0x000d2168> { _clean_this_qtd + 0x28 } CALL pcrel

11 Target : <0x000d2140> { _clean_this_qtd + 0x0 }

Source : <0x000d37ca> { _isp1760_irq + 0x6ce } CALL pcrel

12 Target : <0x000d37c8> { _isp1760_irq + 0x6cc }

Source : <0x000d36e2> { _isp1760_irq + 0x5e6 } IF !CC JUMP

13 Target : <0x000d36dc> { _isp1760_irq + 0x5e0 }

Source : <0x000d37a4> { _isp1760_irq + 0x6a8 } IF CC JUMP

14 Target : <0x000d37a0> { _isp1760_irq + 0x6a4 }

Source : <0x000d36d6> { _isp1760_irq + 0x5da } IF !CC JUMP

15 Target : <0x000d36c2> { _isp1760_irq + 0x5c6 }

Source : <0x000d1a48> { _isp1760_writel + 0x8 } RTS

BUG: failure at drivers/usb/host/isp1760-hcd.c:857/enqueue_an_ATL_packet()!

Kernel panic - not syncing: BUG!

--- Michael Hennerich 2009-07-14 09:20:21

I tried again - now doing exactly the same as in your testing.

Even using the same Flash Drive -

See below - I can't make this bug happen - and thus not debug.

U-Boot 2008.10-svn1961 (ADI-2009R1-rc2) (Jul 7 2009 - 10:38:43)

CPU: ADSP bf537-0.2 (Detected Rev: 0.3) (bypass boot)

Board: ADI BF537 stamp board

Support: http://blackfin.uclinux.org/

Clock: VCO: 500 MHz, Core: 500 MHz, System: 125 MHz

RAM: 64 MB

Flash: 4 MB

In: serial

Out: serial

Err: serial

Net: Blackfin EMAC

MAC: 4E:03:4E:03:4E:03

Hit any key to stop autoboot: 0

Using Blackfin EMAC device

TFTP from server 10.44.2.88; our IP address is 10.44.2.46

Filename 'uImage'.

Load address: 0x1000000

Loading: #################################################################

#################################################################

###########################################################

done

Bytes transferred = 4669744 (474130 hex)

## Booting kernel from Legacy Image at 01000000 ...

Image Name: Linux-2.6.28.10-ADI-2009R1-svn69

Created: 2009-07-14 12:31:26 UTC

Image Type: Blackfin Linux Kernel Image (gzip compressed)

Data Size: 4669680 Bytes = 4.5 MB

Load Address: 00001000

Entry Point: 001c689c

Verifying Checksum ... OK

Uncompressing Kernel Image ... OK

Starting Kernel at = 001c689c

Linux version 2.6.28.10-ADI-2009R1-svn6997 (michael@mhenneri-D02) (gcc version

4.1.2 (ADI svn)) #143 Tue Jul 14 14:31:18 CEST 2009

bootconsole [early_shadow0] enabled

bootconsole [early_BFuart0] enabled

early printk enabled on early_BFuart0

Warning: limiting memory to 56MB due to hardware anomaly 05000263

Board Memory: 64MB

Kernel Managed Memory: 64MB

Memory map:

fixedcode = 0x00000400-0x00000490

text = 0x00001000-0x00130a50

rodata = 0x00130a50-0x00191c7c

bss = 0x00192000-0x001a3e54

data = 0x001a3e54-0x001b8000

stack = 0x001b6000-0x001b8000

init = 0x001b8000-0x008b6000

available = 0x008b6000-0x037ff000

DMA Zone = 0x03f00000-0x04000000

Hardware Trace Active and Enabled

Boot Mode: 0

Compiled for ADSP-BF537 Rev 0.2

Warning: Compiled for Rev 2, but running on Rev 3

Blackfin Linux support by http://blackfin.uclinux.org/

Processor Speed: 500 MHz core clock and 125 MHz System Clock

NOMPU: setting up cplb tables

Instruction Cache Enabled for CPU0

Data Cache Enabled for CPU0 (write-back)

Built 1 zonelists in Zone order, mobility grouping off. Total pages: 14223

Kernel command line: root=/dev/mtdblock0 rw clkin_hz=25000000

earlyprintk=serial,uart0,57600 console=ttyBF0,57600

ip=10.44.2.46:10.44.2.88:19f

Configuring Blackfin Priority Driven Interrupts

PID hash table entries: 256 (order: 8, 1024 bytes)

console handover:boot [early_BFuart0] boot [early_shadow0] -> real

[ttyBF0]

Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)

Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)

Memory available: 47852k/65536k RAM, (7160k init code, 1214k kernel code, 542k

data, 1024k dma, 7744k reserved)

Calibrating delay loop... 995.32 BogoMIPS (lpj=1990656)

Security Framework initialized

Mount-cache hash table entries: 512

Blackfin Scratchpad data SRAM: 4 KB

Blackfin L1 Data A SRAM: 16 KB (15 KB free)

Blackfin L1 Data B SRAM: 16 KB (16 KB free)

Blackfin L1 Instruction SRAM: 48 KB (37 KB free)

net_namespace: 288 bytes

NET: Registered protocol family 16

Blackfin DMA Controller

stamp_init(): registering device resources

SCSI subsystem initialized

usbcore: registered new interface driver usbfs

usbcore: registered new interface driver hub

usbcore: registered new device driver usb

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

NET: Registered protocol family 1

msgmni has been set to 93

io scheduler noop registered

io scheduler anticipatory registered (default)

io scheduler cfq registered

Serial: Blackfin serial driver

bfin-uart.1: ttyBF0 at MMIO 0xffc00400 (irq = 18) is a BFIN-UART

brd: module loaded

bfin_mii_bus: probed

bfin_mac: attached PHY driver [SMSC LAN83C185] (mii_bus:phy_addr=0:01, irq=-1,

mdc_clk=2500000Hz(mdc_div=24)@sclk=125MHz)

bfin_mac bfin_mac.0: Blackfin on-chip Ethernet MAC driver, Version 1.1

Driver 'sd' needs updating - please use bus_type methods

isp1760-hcd isp1760-hcd.0: NXP ISP1760 USB Host Controller

isp1760-hcd isp1760-hcd.0: new USB bus registered, assigned bus number 1

isp1760-hcd isp1760-hcd.0: bus width: 16, oc: digital

isp1760-hcd isp1760-hcd.0: irq 57, io mem 0x203c0000

isp1760-hcd isp1760-hcd.0: USB ISP 1761 HW rev. 1 started

usb usb1: configuration #1 chosen from 1 choice

hub 1-0:1.0: USB hub found

hub 1-0:1.0: 1 port detected

Initializing USB Mass Storage driver...

usbcore: registered new interface driver usb-storage

USB Mass Storage support registered.

rtc-bfin rtc-bfin: rtc core: registered rtc-bfin as rtc0

i2c-bfin-twi i2c-bfin-twi.0: Blackfin BF5xx on-chip I2C TWI Contoller,

regs_base@ffc01400

bfin-wdt: initialized: timeout=20 sec (nowayout=0)

usbcore: registered new interface driver usbhid

usbhid: v2.6:USB HID core driver

TCP cubic registered

NET: Registered protocol family 17

rtc-bfin rtc-bfin: setting system clock to 1970-01-01 00:00:04 UTC (4)

port 1 high speed

usb 1-1: new high speed USB device using isp1760-hcd and address 2

port 1 high speed

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

hub 1-1:1.0: USB hub found

hub 1-1:1.0: 3 ports detected

IP-Config: Gateway not on directly connected network.

Freeing unused kernel memory: 7160k freed

dma_alloc_init: dma_page @ 0x008b3000 - 256 pages at 0x03f00000

_____________________________________

a8888b. / Welcome to the uClinux distribution \

d888888b. / _ _ \

8P"YP"Y88 / | | |_| __ __ (TM) |

8|o||o|88 _____/ | | _ ____ _ _ \ \/ / |

8' .88 \ | | | | _ \| | | | \ / |

8`._.' Y8. \ | |__ | | | | | |_| | / \ |

d/ `8b. \ \____||_|_| |_|\____|/_/\_\ |

dP . Y8b. \ For embedded processors including |

d8:' " `::88b \ the Analog Devices Blackfin /

d8" 'Y88b \___________________________________/

:8P ' :888

8a. : _a88P For further information, check out:

._/"Yaa_: .| 88P| - http://blackfin.uclinux.org/

\ YP" `| 8P `. - http://docs.blackfin.uclinux.org/

/ \.___.d| .' - http://www.uclinux.org/

`--..__)8888P`._.' jgs/a:f - http://www.analog.com/blackfin

Have a lot of fun...

PHY: 0:01 - Link is Up - 100/Full

BusyBox v1.13.4 (2009-07-13 10:47:27 CEST) built-in shell (msh)

Enter 'help' for a list of built-in commands.

root:/> usb 1-1.3: new high speed USB device using isp1760-hcd and address

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

scsi0 : SCSI emulation for USB Mass Storage devices

scsi 0:0:0:0: Direct-Access SanDisk U3 Cruzer Micro 2.21 PQ: 0 ANSI: 2

sd 0:0:0:0: [sda] 1994385 512-byte hardware sectors: (1.02 GB/973 MiB)

sd 0:0:0:0: [sda] Write Protect is off

sd 0:0:0:0: [sda] Assuming drive cache: write through

sd 0:0:0:0: [sda] 1994385 512-byte hardware sectors: (1.02 GB/973 MiB)

sd 0:0:0:0: [sda] Write Protect is off

sd 0:0:0:0: [sda] Assuming drive cache: write through

sda: sda1

sd 0:0:0:0: [sda] Attached SCSI removable disk

root:/> fdisk /dev/sda

Command (m for help): p

Disk /dev/sda: 1021 MB, 1021125120 bytes

32 heads, 61 sectors/track, 1021 cylinders

Units = cylinders of 1952 * 512 = 999424 bytes

Device Boot Start End Blocks Id System

/dev/sda1 1 1021 996465+ 83 Linux

Command (m for help): d

Selected partition 1

Command (m for help): n

Command action

e extended

p primary partition (1-4)

Invalid partition number for type '1'

Command action

e extended

p primary partition (1-4)

Partition number (1-4): 1

First cylinder (1-1021, default 1): Using default value 1

Last cylinder or +size or +sizeM or +sizeK (1-1021, default 1021): Using

default value 1021

Command (m for help): w

The partition table has been altered!

Calling ioctl() to re-read partition table

sd 0:0:0:0: [sda] 1994385 512-byte hardware sectors: (1.02 GB/973 MiB)

sd 0:0:0:0: [sda] Write Protect is off

sd 0:0:0:0: [sda] Assuming drive cache: write through

sda: sda1

root:/> mke2fs /dev/sda1

mke2fs 1.41.4 (27-Jan-2009)

ext2fs_check_if_mount: No such file or directory while determining whether

/dev/sda1 is mounted.

Filesystem label=

OS type: Linux

Block size=4096 (log=2)

Fragment size=4096 (log=2)

62336 inodes, 249116 blocks

12455 blocks (5.00%) reserved for the super user

First data block=0

Maximum filesystem blocks=255852544

8 block groups

32768 blocks per group, 32768 fragments per group

7792 inodes per group

Superblock backups stored on blocks:

32768, 98304, 163840, 229376

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:/> mount

rootfs on / type rootfs (rw)

proc on /proc type proc (rw,nosuid,nodev,noexec)

sysfs on /sys type sysfs (rw,nosuid,nodev,noexec)

mdev on /dev type tmpfs (rw,nosuid)

devpts on /dev/pts type devpts (rw,nosuid,noexec,mode=600)

var on /var type ramfs (rw)

tmp on /tmp type tmpfs (rw,nosuid,nodev)

debugfs on /sys/kernel/debug type debugfs (rw)

root:/> mount -t ext2 /dev/sda1 /mnt

root:/> 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 62 98 2802 16 2765 33 177 99 6494 40 91.3

Latency 171ms 2451ms 403ms 60000us 576ms 25911ms

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 268 96 8110 100 3430 65 269 96 8410 100 929

Latency 43999us 4000us 4000us 39999us 4000us 4000us

1.93c,1.94,blackfin,1,64,300M,,62,98,2802,16,2765,33,177,99,6494,40,91.3,3,16,,,,,268,96,8110,100,3430,65,269,96,8410,100,929,90,171ms,2451mss

root:/> 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 62 98 2811 15 2747 33 177 99 6408 35 94.7

Latency 192ms 2019ms 387ms 60000us 584ms 559ms

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 268 96 8110 100 3427 65 274 96 8427 100 926

Latency 39999us 4000us 4000us 39999us 4000us 4000us

1.93c,1.94,blackfin,1,1501,300M,,62,98,2811,15,2747,33,177,99,6408,35,94.7,4,16,,,,,268,96,8110,100,3427,65,274,96,8427,100,926,90,192ms,2019s

root:/>

--- Vivi Li 2009-07-14 21:59:07

Could you give me your image?

--- Vivi Li 2009-07-14 22:38:27

Bellow is my 537 board config:

sw2,sw3, sw5: all off

sw4: off/on/off/on

sw6: on/on/off/off

isp1761 board config:

JP1: 19-20 set

JP3: 5-6 set

JP4: 3-4 set

JP5: 2-3 set

--- Vivi Li 2009-07-14 22:48:15

I also attach my image built with your configuration.

--- Michael Hennerich 2009-07-15 09:01:35

Hi Vivi,

I could finally recreate this issue once one my side.

While debugging this issue - I found on the linux-usb mailing list that

someone

fixed this issue already

http://kerneltrap.org/mailarchive/linux-usb/2009/3/25/5238724/thread#mid-5238724

that fix went also into 2.6.30

Fixed on 2009R1 branch

[PATCH 96/97] USB: isp1760: Add a delay before reading the SKIPMAP registers in

isp1760-hcd.c

From: Catalin Marinas <catalin.marinas@arm.com>

The data read from the SKIPMAP registers is not immediately available

after writing and the driver panics when a packet is enqueued from the

interrupt handler. This patch adds an ndelay(195) before these registers

are read (delay value mentioned in section 15.1.1.3 of the ISP1760 data

sheet).

Signed-off-by: Catalin Marinas <catalin.marinas@arm.com>

Acked-by: Sebastian Andrzej Siewior <bigeasy@linutronix.de>

Signed-off-by: Greg Kroah-Hartman <gregkh@suse.de>

--- Vivi Li 2009-07-16 05:31:02

I can still see this bug.

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

Using uid:0, gid:0.

Writing a byte at a time...error bit is set in DW3

usb 1-1.1: reset high speed USB device using isp1760-hcd and address 4

error bit is set in DW3

isp1760-hcd isp1760-hcd.0: dev 1.1 ep1out scatterlist error 0/-32

usb 1-1.1: reset high speed USB device using isp1760-hcd and address 4

error bit is set in DW3

usb 1-1.1: reset high speed USB device using isp1760-hcd and address 4

error bit is set in DW3

isp1760-hcd isp1760-hcd.0: dev 1.1 ep1out scatterlist error 0/-32

error bit is set in DW3

isp1760-hcd isp1760-hcd.0: dev 1.1 ep1out scatterlist error -32/-32

Another issue is about bank3. I set CONFIG_BANK_3 to 0x88C0, and yours is

0x99B2. Does that matter?

--- Michael Hennerich 2009-07-16 05:47:44

It matters -

This is an error typically seen when the timings don't match or there are

electrical issues.

After my fix for the:

BUG: failure at drivers/usb/host/isp1760-hcd.c:857/enqueue_an_ATL_packet()!

issue I had bonnie running over night without any failures.

I used 0x66C4

My HW is a ADZs-BF537-STAMP board V2.1

-Michael

--- Michael Hennerich 2009-07-27 04:20:57

mark fixed

--- Vivi Li 2009-07-27 06:34:19

In the latest branch, I've set BANK_3 to 0x66C4. Now I can see this bug in one

of my usb memory and no this bug for another usb memory.

I'm not sure if it is the only problem of that particular usb memory. If you

happen to have some tool to check usb memory hardware problem, please let me

know.

--- Michael Hennerich 2010-02-22 10:24:37

close it

Files

Changes

Commits

Dependencies

Duplicates

Associations