Bug 5215

Summary: USB OHCI pci card can not enable usb devices.
Product: Drivers Reporter: Pieter Smit (diepes)
Component: USBAssignee: David Brownell (dbrownell)
Status: REJECTED INSUFFICIENT_DATA    
Severity: normal CC: bunk, greg
Priority: P2    
Hardware: i386   
OS: Linux   
Kernel Version: 2.6.13-git9 SMP PREEMPT Subsystem:
Regression: --- Bisected commit-id:
Bug Depends on:    
Bug Blocks: 5089    

Description Pieter Smit 2005-09-10 03:03:09 UTC
Most recent kernel where this bug did not occur: N.A. Never worked
Distribution: Debian Stable
Hardware Environment: Pentium 2 dual cpu, with pci USB Controller: OPTi Inc.
82C861 (rev 10)
Software Environment: Std.
Problem Description: USB devices plugged into one of the 2 port on the pci card
seem to be detected, but they are not fully activated.
Steps to reproduce: Plug in usb device.  Tried with Microsof Mouse and Flash drive.

FROM SYSLOG
Sep 10 11:41:43 pumba kernel: usb 1-2: new full speed USB device using ohci_hcd
and address 8
Sep 10 11:41:43 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f220 path 2 ep0out
5ec20000 cc 5 --> status -110
Sep 10 11:41:43 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f220 path 2 ep0out
5ec20000 cc 5 --> status -110
Sep 10 11:41:43 pumba kernel: usb 1-2: device not accepting address 8, error -110
Sep 10 11:41:43 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [1] = 0x00100103 PRSC PPS PES CCS
Sep 10 11:41:43 pumba kernel: usb 1-2: new full speed USB device using ohci_hcd
and address 9
Sep 10 11:41:43 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f220 path 2 ep0out
5ec20000 cc 5 --> status -110
Sep 10 11:41:43 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f220 path 2 ep0out
5ec20000 cc 5 --> status -110
Sep 10 11:41:44 pumba kernel: usb 1-2: device not accepting address 9, error -110
Sep 10 11:41:44 pumba kernel: hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0004


LSPCI -vv for pci device

0000:01:07.0 USB Controller: OPTi Inc. 82C861 (rev 10) (prog-if 10 [OHCI])
        Subsystem: OPTi Inc. 82C861
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr+
Stepping- SERR+ FastB2B-
        Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort+ >SERR+ <PERR-
        Latency: 144, Cache Line Size: 0x08 (32 bytes)
        Interrupt: pin A routed to IRQ 9
        Region 0: Memory at 40000000 (32-bit, non-prefetchable) [size=4K]


cat /proc/interrupts 
           CPU0       CPU1       
  0:    2652539         79    IO-APIC-edge  timer
  1:        114          6    IO-APIC-edge  i8042
  2:          0          0          XT-PIC  cascade
  5:          0          0    IO-APIC-edge  snd-card-opti93x - WSS
  7:          0          0    IO-APIC-edge  MPU401 UART
  9:        239          1    IO-APIC-edge  ohci_hcd:usb1
 10:      17418          0    IO-APIC-edge  eth0
 11:      31933          0    IO-APIC-edge  aic7xxx
 12:       1548          2    IO-APIC-edge  i8042
 15:         90          0    IO-APIC-edge  aic7xxx
NMI:          0          0 
LOC:    2652728    2652727 
ERR:          0
MIS:          0
pumba:/home/pes# 


#modprobe -r ohci_hcd
#modprobe ohci_hcd
And then logging syslog.  Flash drive and USB mouse in the 2 usb ports.
Sep 10 12:00:08 pumba kernel: ohci_hcd 0000:01:07.0: ed_controlhead 1247a000
Sep 10 12:00:08 pumba kernel: ohci_hcd 0000:01:07.0: fminterval a7782edf
Sep 10 12:00:08 pumba kernel: ohci_hcd 0000:01:07.0: hcca frame #a640
Sep 10 12:00:08 pumba kernel: ohci_hcd 0000:01:07.0: roothub.a 01000202 POTPGT=1
NPS NDP=2
Sep 10 12:00:08 pumba kernel: ohci_hcd 0000:01:07.0: roothub.b 00000000
PPCM=0000 DR=0000
Sep 10 12:00:08 pumba kernel: ohci_hcd 0000:01:07.0: roothub.status 00008000 DRWE
Sep 10 12:00:08 pumba kernel: ohci_hcd 0000:01:07.0: roothub.portstatus [0]
0x00000301 LSDA PPS CCS
Sep 10 12:00:08 pumba kernel: ohci_hcd 0000:01:07.0: roothub.portstatus [1]
0x00000101 PPS CCS
Sep 10 12:00:08 pumba kernel: ohci_hcd 0000:01:07.0: USB bus 1 deregistered
Sep 10 12:00:08 pumba udev[4305]: removing device node '/dev/usbdev1.1'
Sep 10 12:00:34 pumba kernel: ohci_hcd: 2005 April 22 USB 1.1 'Open' Host
Controller (OHCI) Driver (PCI)
Sep 10 12:00:34 pumba kernel: ohci_hcd: block sizes: ed 64 td 64
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: OHCI Host Controller
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: new USB bus registered,
assigned bus number 1
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: irq 9, io mem 0x40000000
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: WARNING: OPTi workarounds
unavailable
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: resetting from state
'reset', control = 0x0
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: enabling initreset quirk
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: OHCI controller state
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: OHCI 1.0, NO legacy support
registers
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: control 0x083
HCFS=operational CBSR=3
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: cmdstatus 0x00000 SOC=0
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: intrstatus 0x00000044 RHSC SF
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: intrenable 0x8000000a MIE
RD WDH
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: fminterval a7782edf
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: hcca frame #0003
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: roothub.a 01000202 POTPGT=1
NPS NDP=2
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: roothub.b 00000000
PPCM=0000 DR=0000
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: roothub.status 00008000 DRWE
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: roothub.portstatus [0]
0x00010301 CSC LSDA PPS CCS
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: roothub.portstatus [1]
0x00010101 CSC PPS CCS
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: created debug files
Sep 10 12:00:34 pumba kernel: usb usb1: default language 0x0409
Sep 10 12:00:34 pumba kernel: usb usb1: new device strings: Mfr=3, Product=2,
SerialNumber=1
Sep 10 12:00:34 pumba kernel: usb usb1: Product: OHCI Host Controller
Sep 10 12:00:34 pumba kernel: usb usb1: Manufacturer: Linux 2.6.13-git9pesa ohci_hcd
Sep 10 12:00:34 pumba kernel: usb usb1: SerialNumber: 0000:01:07.0
Sep 10 12:00:34 pumba kernel: usb usb1: hotplug
Sep 10 12:00:34 pumba kernel: usb usb1: adding 1-0:1.0 (config #1, interface 0)
Sep 10 12:00:34 pumba kernel: usb 1-0:1.0: hotplug
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: usb_probe_interface
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: usb_probe_interface - got id
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: USB hub found
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: 2 ports detected
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: standalone hub
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: no power switching (usb 1.0)
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: global over-current protection
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: power on to power good time: 2ms
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: local power source is good
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: no over-current condition exists
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0000
Sep 10 12:00:34 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [0] = 0x00010301 CSC LSDA PPS CCS
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: port 1, status 0301, change 0001, 1.5
Mb/s
Sep 10 12:00:34 pumba kernel: hub 1-0:1.0: debounce: port 1: total 100ms stable
100ms status 0x301
Sep 10 12:00:35 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [0] = 0x00100303 PRSC LSDA PPS PES CCS
Sep 10 12:00:35 pumba kernel: usb 1-1: new low speed USB device using ohci_hcd
and address 2
Sep 10 12:00:35 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 1 ep0in
5ec20000 cc 5 --> status -110
Sep 10 12:00:35 pumba last message repeated 2 times
Sep 10 12:00:35 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [0] = 0x00100303 PRSC LSDA PPS PES CCS
Sep 10 12:00:35 pumba kernel: usb 1-1: device descriptor read/64, error -110
Sep 10 12:00:35 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 1 ep0in
5ec20000 cc 5 --> status -110
Sep 10 12:00:35 pumba last message repeated 2 times
Sep 10 12:00:35 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [0] = 0x00100303 PRSC LSDA PPS PES CCS
Sep 10 12:00:35 pumba kernel: usb 1-1: device descriptor read/64, error -110
Sep 10 12:00:36 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [0] = 0x00100303 PRSC LSDA PPS PES CCS
Sep 10 12:00:36 pumba kernel: usb 1-1: new low speed USB device using ohci_hcd
and address 3
Sep 10 12:00:36 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 1 ep0in
5ec20000 cc 5 --> status -110
Sep 10 12:00:36 pumba last message repeated 2 times
Sep 10 12:00:36 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [0] = 0x00100303 PRSC LSDA PPS PES CCS
Sep 10 12:00:36 pumba kernel: usb 1-1: device descriptor read/64, error -110
Sep 10 12:00:36 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 1 ep0in
5ec20000 cc 5 --> status -110
Sep 10 12:00:36 pumba last message repeated 2 times
Sep 10 12:00:36 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [0] = 0x00100303 PRSC LSDA PPS PES CCS
Sep 10 12:00:36 pumba kernel: usb 1-1: device descriptor read/64, error -110
Sep 10 12:00:37 pumba udev[4412]: creating device node '/dev/usbdev1.1'
Sep 10 12:00:37 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [0] = 0x00100303 PRSC LSDA PPS PES CCS
Sep 10 12:00:37 pumba kernel: usb 1-1: new low speed USB device using ohci_hcd
and address 4
Sep 10 12:00:37 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 1 ep0out
5ec20000 cc 5 --> status -110
Sep 10 12:00:37 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 1 ep0out
5ec20000 cc 5 --> status -110
Sep 10 12:00:37 pumba kernel: usb 1-1: device not accepting address 4, error -110
Sep 10 12:00:37 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [0] = 0x00100303 PRSC LSDA PPS PES CCS
Sep 10 12:00:37 pumba kernel: usb 1-1: new low speed USB device using ohci_hcd
and address 5
Sep 10 12:00:37 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 1 ep0out
5ec20000 cc 5 --> status -110
Sep 10 12:00:38 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 1 ep0out
5ec20000 cc 5 --> status -110
Sep 10 12:00:38 pumba kernel: usb 1-1: device not accepting address 5, error -110
Sep 10 12:00:38 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [1] = 0x00010101 CSC PPS CCS
Sep 10 12:00:38 pumba kernel: hub 1-0:1.0: port 2, status 0101, change 0001, 12 Mb/s
Sep 10 12:00:38 pumba kernel: hub 1-0:1.0: debounce: port 2: total 100ms stable
100ms status 0x101
Sep 10 12:00:38 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [1] = 0x00100103 PRSC PPS PES CCS
Sep 10 12:00:38 pumba kernel: usb 1-2: new full speed USB device using ohci_hcd
and address 6
Sep 10 12:00:38 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 2 ep0in
5ec20000 cc 5 --> status -110
Sep 10 12:00:38 pumba last message repeated 2 times
Sep 10 12:00:38 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [1] = 0x00100103 PRSC PPS PES CCS
Sep 10 12:00:38 pumba kernel: usb 1-2: device descriptor read/64, error -110
Sep 10 12:00:39 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 2 ep0in
5ec20000 cc 5 --> status -110
Sep 10 12:00:39 pumba last message repeated 2 times
Sep 10 12:00:39 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [1] = 0x00100103 PRSC PPS PES CCS
Sep 10 12:00:39 pumba kernel: usb 1-2: device descriptor read/64, error -110
Sep 10 12:00:39 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [1] = 0x00100103 PRSC PPS PES CCS
Sep 10 12:00:39 pumba kernel: usb 1-2: new full speed USB device using ohci_hcd
and address 7
Sep 10 12:00:39 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 2 ep0in
5ec20000 cc 5 --> status -110
Sep 10 12:00:39 pumba last message repeated 2 times
Sep 10 12:00:39 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [1] = 0x00100103 PRSC PPS PES CCS
Sep 10 12:00:39 pumba kernel: usb 1-2: device descriptor read/64, error -110
Sep 10 12:00:39 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 2 ep0in
5ec20000 cc 5 --> status -110
Sep 10 12:00:40 pumba last message repeated 2 times
Sep 10 12:00:40 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [1] = 0x00100103 PRSC PPS PES CCS
Sep 10 12:00:40 pumba kernel: usb 1-2: device descriptor read/64, error -110
Sep 10 12:00:40 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [1] = 0x00100103 PRSC PPS PES CCS
Sep 10 12:00:40 pumba kernel: usb 1-2: new full speed USB device using ohci_hcd
and address 8
Sep 10 12:00:40 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 2 ep0out
5ec20000 cc 5 --> status -110
Sep 10 12:00:40 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 2 ep0out
5ec20000 cc 5 --> status -110
Sep 10 12:00:41 pumba kernel: usb 1-2: device not accepting address 8, error -110
Sep 10 12:00:41 pumba kernel: ohci_hcd 0000:01:07.0: GetStatus
roothub.portstatus [1] = 0x00100103 PRSC PPS PES CCS
Sep 10 12:00:41 pumba kernel: usb 1-2: new full speed USB device using ohci_hcd
and address 9
Sep 10 12:00:41 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 2 ep0out
5ec20000 cc 5 --> status -110
Sep 10 12:00:41 pumba kernel: ohci_hcd 0000:01:07.0: urb d580f8a0 path 2 ep0out
5ec20000 cc 5 --> status -110
Sep 10 12:00:41 pumba kernel: usb 1-2: device not accepting address 9, error -110
Sep 10 12:00:41 pumba kernel: hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0004
Comment 1 David Brownell 2005-09-12 22:57:18 UTC
Please try these experiments:

- The latest GIT tree as of this afternoon, which merges a
  patch to relax some timeouts that happen right before
  the error messages you showed.  (Including that "2msec"
  which looks oddly low; that'll be treated as 100msec.)

- Toggle the "old_scheme_first" usbcore parameter; by default
  it's cleared.

- Swap in some other OHCI-supporting card, such as the ones
  available around the corner often for $US 5/board and with
  a NEC high speed usb host (EHCI).

- While it's getting those timeouts, and with CONFIG_USB_DEBUG
  still enabled, snapshot the /sys/class/usb_host/usb1/registers
  file with this OPTi card.  Maybe I'll notice something there.

Folk have problems from time to time with OPTi, but it's worked
in the past.  But a LOT of folk seem to be having problems with
early enumeration failures lately ... not just with OHCI.
Comment 2 Adrian Bunk 2006-02-13 14:13:55 UTC
Please reopen this bug if:
- it is still present in recent 2.6 kernels and
- you can provide the requested information.