Via VT6212L -- I'll try some patches for debug.

[email protected]
Newsgroups gmane.linux.usb.devel
Message-ID <[email protected]>
My PIII had only USB 1.1, so I picked up a USB 2.0 PCI card at Fry's
on sale.  If you have patches, I'll try them for debug on my (not
supported/not well supported) Via VT6212L chip based USB 2.0 PCI
board.  Let me know where to get any non-stock release kernels (I use
kernel.org (and mirrors) for my stock kernels) if the patch isn't
applied on stock releases.

For a few weeks I'll keep the following card in my computer
and try a few patches to see if we can get this thing to work:

Printed on the card:

VIA USB20
DH300-13 040719 REV:13

Printed on main (only?) chip:
  Via
  VT6212L
  0627CD Taiwan

Printed on the box:
  USB2.0 VIA 4+1
    Ports PCI Card

Printed on the UPC sticker:
  Product:USB 2.0 4+1 Ports
  Model: UP-400
  Macsense Connectivity

Image of this card from apparent "manufacturer" (reseller?):
  http://www.macsense.com/images/product/UP-400.jpg
Web site with picture of this card from same:
  http://www.macsense.com/product/usb/usb1394.htm  


Current computer host kernel and hardware from dmesg [my comments]:

Linux version 2.6.22.9 [stock from kernel.org]
  (gcc version 4.1.2 20061115 (prerelease) (Debian 4.1.1-21))
  [I'll switch from Debian the first chance I get due to
  declining distro quality]
Detected 448.887 MHz processor.
CPU: Intel Pentium III (Katmai) stepping 03
PCI: Bridge: 0000:00:01.0
  IO window: e000-efff
  MEM window: fc000000-feffffff
  PREFETCH window: f5000000-f5ffffff
PCI: Bridge: 0000:00:0f.0
  IO window: d000-dfff
  MEM window: fa000000-fbffffff
  PREFETCH window: ec000000-f3ffffff
[... and later ... syslog stock error messages with EHCI and USB debug:]

Oct  9 13:34:06 O kernel: usb 4-2: khubd timed out on ep0out len=0/0
Oct  9 13:34:09 O kernel: usb 4-2: khubd timed out on ep0out len=0/0
Oct  9 13:34:10 O kernel: usb 4-2: device not accepting address 2, error -110
Oct  9 13:34:10 O kernel: ehci_hcd 0000:02:09.2: port 2 high speed
Oct  9 13:34:10 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 2 status 001005 POWER sig=se0 PE CONNECT
Oct  9 13:34:10 O kernel: usb 4-2: new high speed USB device using ehci_hcd and address 3
Oct  9 13:34:15 O kernel: usb 4-2: khubd timed out on ep0in len=0/64
Oct  9 13:34:25 O last message repeated 2 times
Oct  9 13:34:25 O kernel: ehci_hcd 0000:02:09.2: port 2 high speed
Oct  9 13:34:25 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 2 status 001005 POWER sig=se0 PE CONNECT
Oct  9 13:34:25 O kernel: usb 4-2: device descriptor read/64, error -110
Oct  9 13:34:30 O kernel: usb 4-2: khubd timed out on ep0in len=0/64
Oct  9 13:34:35 O kernel: usb 4-2: khubd timed out on ep0in len=0/64
[see later for more syslog/dmesg examples]

Relevent lspci entries:

lspci -s 2:9    [should I focus instead only on -s 2:9.2 since that's the USB 2.0 portion?]
02:09.0 USB Controller: VIA Technologies, Inc. VT82xxxxx UHCI USB 1.1 Controller (rev 61)
02:09.1 USB Controller: VIA Technologies, Inc. VT82xxxxx UHCI USB 1.1 Controller (rev 61)
02:09.2 USB Controller: VIA Technologies, Inc. USB 2.0 (rev 63)

lspci -n -s 2:9
02:09.0 0c03: 1106:3038 (rev 61)
02:09.1 0c03: 1106:3038 (rev 61)
02:09.2 0c03: 1106:3104 (rev 63)

lspci -n -vv -s 2:9
02:09.0 0c03: 1106:3038 (rev 61) (prog-if 00 [UHCI])
        Subsystem: 1106:3038
        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-
        Interrupt: pin A routed to IRQ 9
        Region 4: I/O ports at dce0 [size=32]
        Capabilities: [80] Power Management version 2
                Flags: PMEClk+ DSI- D1+ D2+ AuxCurrent=0mA PME(D0+,D1+,D2+,D3hot+,D3cold-)
                Status: D0 PME-Enable- DSel=0 DScale=0 PME-

02:09.1 0c03: 1106:3038 (rev 61) (prog-if 00 [UHCI])
        Subsystem: 1106:3038
        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-
        Interrupt: pin B routed to IRQ 11
        Region 4: I/O ports at dcc0 [size=32]
        Capabilities: [80] Power Management version 2
                Flags: PMEClk+ DSI- D1+ D2+ AuxCurrent=0mA PME(D0+,D1+,D2+,D3hot+,D3cold-)
                Status: D0 PME-Enable- DSel=0 DScale=0 PME-

02:09.2 0c03: 1106:3104 (rev 63) (prog-if 20 [EHCI])
        Subsystem: 1106:3104
        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-
        Interrupt: pin C routed to IRQ 10
        Region 0: Memory at fafffc00 (32-bit, non-prefetchable) [size=256]
        Capabilities: [80] Power Management version 2
                Flags: PMEClk+ DSI- D1+ D2+ AuxCurrent=0mA PME(D0+,D1+,D2+,D3hot+,D3cold-)
                Status: D0 PME-Enable- DSel=0 DScale=0 PME-



Syslog entries of stock kernel 2.6.22.9:

modprobe usbcore:

Oct  9 14:31:40 O kernel: usbcore: registered new interface driver usbfs
Oct  9 14:31:40 O kernel: usbcore: registered new interface driver hub
Oct  9 14:31:40 O kernel: usbcore: registered new device driver usb

modprobe ehci-hcd:
Oct  9 14:31:47 O kernel: ehci_hcd: block sizes: qh 128 qtd 96 itd 192 sitd 96
Oct  9 14:31:47 O kernel: PCI: Found IRQ 10 for device 0000:02:09.2
Oct  9 14:31:47 O kernel: PCI: Sharing IRQ 10 with 0000:00:0e.0
Oct  9 14:31:47 O kernel: ehci_hcd 0000:02:09.2: EHCI Host Controller
Oct  9 14:31:47 O kernel: drivers/usb/core/inode.c: creating file 'devices'
Oct  9 14:31:47 O kernel: drivers/usb/core/inode.c: creating file '001'
Oct  9 14:31:47 O kernel: ehci_hcd 0000:02:09.2: new USB bus registered, assigned bus number 1
Oct  9 14:31:47 O kernel: ehci_hcd 0000:02:09.2: reset hcs_params 0x2204 dbg=0 cc=2 pcc=2 ordered !ppc ports=4
Oct  9 14:31:47 O kernel: ehci_hcd 0000:02:09.2: reset hcc_params 6872 thresh 7 uframes 256/512/1024
Oct  9 14:31:47 O kernel: ehci_hcd 0000:02:09.2: MWI active
Oct  9 14:31:47 O kernel: ehci_hcd 0000:02:09.2: supports USB remote wakeup
Oct  9 14:31:47 O kernel: ehci_hcd 0000:02:09.2: irq 10, io mem 0xfafffc00
Oct  9 14:31:47 O kernel: ehci_hcd 0000:02:09.2: reset command 080002 (park)=0 ithresh=8 period=1024 Reset HALT
Oct  9 14:31:47 O kernel: ehci_hcd 0000:02:09.2: init command 010009 (park)=0 ithresh=1 period=256 RUN
Oct  9 14:31:47 O kernel: ehci_hcd 0000:02:09.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
Oct  9 14:31:47 O kernel: usb usb1: default language 0x0409
Oct  9 14:31:47 O kernel: usb usb1: new device strings: Mfr=3, Product=2, SerialNumber=1
Oct  9 14:31:47 O kernel: usb usb1: Product: EHCI Host Controller
Oct  9 14:31:47 O kernel: usb usb1: Manufacturer: Linux 2.6.22.9 ehci_hcd
Oct  9 14:31:47 O kernel: usb usb1: SerialNumber: 0000:02:09.2
Oct  9 14:31:47 O kernel: usb usb1: uevent
Oct  9 14:31:47 O kernel: usb usb1: usb_probe_device
Oct  9 14:31:47 O kernel: usb usb1: configuration #1 chosen from 1 choice
Oct  9 14:31:47 O kernel: usb usb1: adding 1-0:1.0 (config #1, interface 0)
Oct  9 14:31:47 O kernel: usb 1-0:1.0: uevent
Oct  9 14:31:47 O kernel: usb 1-0:1.0: uevent
Oct  9 14:31:47 O kernel: hub 1-0:1.0: usb_probe_interface
Oct  9 14:31:47 O kernel: hub 1-0:1.0: usb_probe_interface - got id
Oct  9 14:31:47 O kernel: hub 1-0:1.0: USB hub found
Oct  9 14:31:47 O kernel: hub 1-0:1.0: 4 ports detected
Oct  9 14:31:47 O kernel: hub 1-0:1.0: standalone hub
Oct  9 14:31:47 O kernel: hub 1-0:1.0: no power switching (usb 1.0)
Oct  9 14:31:47 O kernel: hub 1-0:1.0: individual port over-current protection
Oct  9 14:31:47 O kernel: hub 1-0:1.0: Single TT
Oct  9 14:31:47 O kernel: hub 1-0:1.0: TT requires at most 8 FS bit times (666 ns)
Oct  9 14:31:47 O kernel: hub 1-0:1.0: power on to power good time: 20ms
Oct  9 14:31:47 O kernel: hub 1-0:1.0: local power source is good
Oct  9 14:31:47 O kernel: hub 1-0:1.0: trying to enable port power on non-switchable hub
Oct  9 14:31:47 O kernel: drivers/usb/core/inode.c: creating file '001'
Oct  9 14:31:47 O kernel: hub 1-0:1.0: state 7 ports 4 chg 0000 evt 0000
Oct  9 14:31:49 O kernel: hub 1-0:1.0: hub_suspend
Oct  9 14:31:49 O kernel: ehci_hcd 0000:02:09.2: suspend root hub
Oct  9 14:31:49 O kernel: usb usb1: usb auto-suspend

Plug in plain USB 2.0 hub:

Oct  9 14:35:14 O kernel: usb usb1: usb resume
Oct  9 14:35:14 O kernel: usb usb1: finish resume
Oct  9 14:35:14 O kernel: hub 1-0:1.0: hub_resume
Oct  9 14:35:14 O kernel: ehci_hcd 0000:02:09.2: resume root hub
Oct  9 14:35:14 O kernel: hub 1-0:1.0: state 7 ports 4 chg 0000 evt 0000
Oct  9 14:35:14 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001803 POWER sig=j CSC CONNECT
Oct  9 14:35:14 O kernel: hub 1-0:1.0: port 3, status 0501, change 0001, 480 Mb/s
Oct  9 14:35:14 O kernel: hub 1-0:1.0: debounce: port 3: total 100ms stable 100ms status 0x501
Oct  9 14:35:14 O kernel: ehci_hcd 0000:02:09.2: port 3 high speed
Oct  9 14:35:14 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Oct  9 14:35:14 O kernel: usb 1-3: new high speed USB device using ehci_hcd and address 2
Oct  9 14:35:15 O kernel: ehci_hcd 0000:02:09.2: port 3 high speed
Oct  9 14:35:15 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Oct  9 14:35:20 O kernel: usb 1-3: khubd timed out on ep0out len=0/0
Oct  9 14:35:25 O kernel: usb 1-3: khubd timed out on ep0out len=0/0
Oct  9 14:35:25 O kernel: usb 1-3: device not accepting address 2, error -110
Oct  9 14:35:25 O kernel: ehci_hcd 0000:02:09.2: port 3 full speed --> companion
Oct  9 14:35:25 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 003801 POWER OWNER sig=j CONNECT
Oct  9 14:35:25 O kernel: hub 1-0:1.0: port 3 not reset yet, waiting 50ms
Oct  9 14:35:25 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 003802 POWER OWNER sig=j CSC
Oct  9 14:35:25 O kernel: hub 1-0:1.0: state 7 ports 4 chg 0000 evt 0008
Oct  9 14:35:27 O kernel: hub 1-0:1.0: hub_suspend
Oct  9 14:35:27 O kernel: ehci_hcd 0000:02:09.2: suspend root hub
Oct  9 14:35:27 O kernel: usb usb1: usb auto-suspend

The error messages above, as you can see, start out at:
   14:35:20 O kernel: usb 1-3: khubd timed out on ep0out len=0/0
and then it continues with "device not accepting address 2", etc.

Plugging in a different object, a combination Hub and Flash Card
Reader/Writer, I get the following syslog entries and failures:

First, unplug above regular hub:

[no additional syslog entries.]

Second, plug in hub with four flashcard "drives":

Oct  9 14:40:01 O kernel: usb usb1: usb resume
Oct  9 14:40:01 O kernel: usb usb1: finish resume
Oct  9 14:40:01 O kernel: hub 1-0:1.0: hub_resume
Oct  9 14:40:01 O kernel: ehci_hcd 0000:02:09.2: resume root hub
Oct  9 14:40:01 O kernel: hub 1-0:1.0: state 7 ports 4 chg 0000 evt 0000
Oct  9 14:40:01 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001803 POWER sig=j CSC CONNECT
Oct  9 14:40:01 O kernel: hub 1-0:1.0: port 3, status 0501, change 0001, 480 Mb/s
Oct  9 14:40:01 O kernel: hub 1-0:1.0: debounce: port 3: total 100ms stable 100ms status 0x501
Oct  9 14:40:01 O kernel: ehci_hcd 0000:02:09.2: port 3 high speed
Oct  9 14:40:01 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Oct  9 14:40:01 O kernel: usb 1-3: new high speed USB device using ehci_hcd and address 4
Oct  9 14:40:06 O kernel: usb 1-3: khubd timed out on ep0in len=0/64
Oct  9 14:40:16 O last message repeated 2 times
Oct  9 14:40:16 O kernel: ehci_hcd 0000:02:09.2: port 3 high speed
Oct  9 14:40:16 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Oct  9 14:40:16 O kernel: usb 1-3: device descriptor read/64, error -110
Oct  9 14:40:21 O kernel: usb 1-3: khubd timed out on ep0in len=0/64
Oct  9 14:40:31 O last message repeated 2 times
Oct  9 14:40:31 O kernel: ehci_hcd 0000:02:09.2: port 3 high speed
Oct  9 14:40:31 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Oct  9 14:40:31 O kernel: usb 1-3: device descriptor read/64, error -110
Oct  9 14:40:31 O kernel: ehci_hcd 0000:02:09.2: port 3 high speed
Oct  9 14:40:31 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Oct  9 14:40:32 O kernel: usb 1-3: new high speed USB device using ehci_hcd and address 5
Oct  9 14:40:37 O kernel: usb 1-3: khubd timed out on ep0in len=0/64
Oct  9 14:40:42 O kernel: usb 1-3: khubd timed out on ep0in len=0/64
Oct  9 14:40:47 O kernel: usb 1-3: khubd timed out on ep0in len=0/64
Oct  9 14:40:47 O kernel: ehci_hcd 0000:02:09.2: port 3 high speed
Oct  9 14:40:47 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Oct  9 14:40:47 O kernel: usb 1-3: device descriptor read/64, error -110
Oct  9 14:40:52 O kernel: usb 1-3: khubd timed out on ep0in len=0/64
Oct  9 14:41:02 O last message repeated 2 times
Oct  9 14:41:02 O kernel: ehci_hcd 0000:02:09.2: port 3 high speed
Oct  9 14:41:02 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Oct  9 14:41:02 O kernel: usb 1-3: device descriptor read/64, error -110
Oct  9 14:41:02 O kernel: ehci_hcd 0000:02:09.2: port 3 high speed
Oct  9 14:41:02 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Oct  9 14:41:02 O kernel: usb 1-3: new high speed USB device using ehci_hcd and address 6
Oct  9 14:41:07 O kernel: usb 1-3: khubd timed out on ep0out len=0/0
Oct  9 14:41:12 O kernel: usb 1-3: khubd timed out on ep0out len=0/0
Oct  9 14:41:13 O kernel: usb 1-3: device not accepting address 6, error -110
Oct  9 14:41:13 O kernel: ehci_hcd 0000:02:09.2: port 3 high speed
Oct  9 14:41:13 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Oct  9 14:41:13 O kernel: usb 1-3: new high speed USB device using ehci_hcd and address 7
Oct  9 14:41:18 O kernel: usb 1-3: khubd timed out on ep0out len=0/0
Oct  9 14:41:23 O kernel: usb 1-3: khubd timed out on ep0out len=0/0
Oct  9 14:41:23 O kernel: usb 1-3: device not accepting address 7, error -110
Oct  9 14:41:23 O kernel: hub 1-0:1.0: hub_suspend
Oct  9 14:41:23 O kernel: ehci_hcd 0000:02:09.2: suspend root hub
Oct  9 14:41:23 O kernel: usb usb1: usb auto-suspend

Third, rmmod usb-ehci usbcore:

Oct  9 14:50:46 O kernel: ehci_hcd 0000:02:09.2: remove, state 4
Oct  9 14:50:46 O kernel: ehci_hcd 0000:02:09.2: roothub graceful disconnect
Oct  9 14:50:46 O kernel: usb usb1: USB disconnect, address 1
Oct  9 14:50:46 O kernel: usb usb1: unregistering device
Oct  9 14:50:46 O kernel: usb usb1: usb_disable_device nuking all URBs
Oct  9 14:50:46 O kernel: usb usb1: unregistering interface 1-0:1.0
Oct  9 14:50:46 O kernel: usb_endpoint usbdev1.1_ep81: ep_device_release called for usbdev1.1_ep81
Oct  9 14:50:46 O kernel: usb 1-0:1.0: uevent
Oct  9 14:50:46 O kernel: usb 1-0:1.0: uevent
Oct  9 14:50:46 O kernel: usb_endpoint usbdev1.1_ep00: ep_device_release called for usbdev1.1_ep00
Oct  9 14:50:46 O kernel: usb usb1: uevent
Oct  9 14:50:46 O kernel: ehci_hcd 0000:02:09.2: stop
Oct  9 14:50:46 O kernel: ehci_hcd 0000:02:09.2: reset command 01000a (park)=0 ithresh=1 period=256 Reset HALT
Oct  9 14:50:46 O kernel: ehci_hcd 0000:02:09.2: irq normal 101250 err 0 reclaim 19 (lost 0)
Oct  9 14:50:46 O kernel: ehci_hcd 0000:02:09.2: complete 1 unlink 18
Oct  9 14:50:46 O kernel: ehci_hcd 0000:02:09.2: ehci_stop completed status 1000 Halt
Oct  9 14:50:46 O kernel: ehci_hcd 0000:02:09.2: USB bus 1 deregistered
Oct  9 14:50:46 O kernel: usbcore: deregistering device driver usb
Oct  9 14:50:46 O kernel: usbcore: deregistering interface driver usbfs
Oct  9 14:50:46 O kernel: usbcore: khubd exiting
Oct  9 14:50:46 O kernel: usbcore: deregistering interface driver hub

Fourth:  with 4 flash card usb hub still in, insmod usbcore & ehci-hcd:

Oct  9 14:51:43 O kernel: usbcore: registered new interface driver usbfs
Oct  9 14:51:43 O kernel: usbcore: registered new interface driver hub
Oct  9 14:51:43 O kernel: usbcore: registered new device driver usb
Oct  9 14:51:45 O kernel: ehci_hcd: block sizes: qh 128 qtd 96 itd 192 sitd 96
Oct  9 14:51:45 O kernel: PCI: Found IRQ 10 for device 0000:02:09.2
Oct  9 14:51:45 O kernel: PCI: Sharing IRQ 10 with 0000:00:0e.0
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: EHCI Host Controller
Oct  9 14:51:45 O kernel: drivers/usb/core/inode.c: creating file 'devices'
Oct  9 14:51:45 O kernel: drivers/usb/core/inode.c: creating file '001'
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: new USB bus registered, assigned bus number 1
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: reset hcs_params 0x2204 dbg=0 cc=2 pcc=2 ordered !ppc ports=4
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: reset hcc_params 6872 thresh 7 uframes 256/512/1024
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: MWI active
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: supports USB remote wakeup
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: irq 10, io mem 0xfafffc00
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: reset command 080002 (park)=0 ithresh=8 period=1024 Reset HALT
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: init command 010009 (park)=0 ithresh=1 period=256 RUN
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
Oct  9 14:51:45 O kernel: usb usb1: default language 0x0409
Oct  9 14:51:45 O kernel: usb usb1: new device strings: Mfr=3, Product=2, SerialNumber=1
Oct  9 14:51:45 O kernel: usb usb1: Product: EHCI Host Controller
Oct  9 14:51:45 O kernel: usb usb1: Manufacturer: Linux 2.6.22.9 ehci_hcd
Oct  9 14:51:45 O kernel: usb usb1: SerialNumber: 0000:02:09.2
Oct  9 14:51:45 O kernel: usb usb1: uevent
Oct  9 14:51:45 O kernel: usb usb1: usb_probe_device
Oct  9 14:51:45 O kernel: usb usb1: configuration #1 chosen from 1 choice
Oct  9 14:51:45 O kernel: usb usb1: adding 1-0:1.0 (config #1, interface 0)
Oct  9 14:51:45 O kernel: usb 1-0:1.0: uevent
Oct  9 14:51:45 O kernel: usb 1-0:1.0: uevent
Oct  9 14:51:45 O kernel: hub 1-0:1.0: usb_probe_interface
Oct  9 14:51:45 O kernel: hub 1-0:1.0: usb_probe_interface - got id
Oct  9 14:51:45 O kernel: hub 1-0:1.0: USB hub found
Oct  9 14:51:45 O kernel: hub 1-0:1.0: 4 ports detected
Oct  9 14:51:45 O kernel: hub 1-0:1.0: standalone hub
Oct  9 14:51:45 O kernel: hub 1-0:1.0: no power switching (usb 1.0)
Oct  9 14:51:45 O kernel: hub 1-0:1.0: individual port over-current protection
Oct  9 14:51:45 O kernel: hub 1-0:1.0: Single TT
Oct  9 14:51:45 O kernel: hub 1-0:1.0: TT requires at most 8 FS bit times (666 ns)
Oct  9 14:51:45 O kernel: hub 1-0:1.0: power on to power good time: 20ms
Oct  9 14:51:45 O kernel: hub 1-0:1.0: local power source is good
Oct  9 14:51:45 O kernel: hub 1-0:1.0: trying to enable port power on non-switchable hub
Oct  9 14:51:45 O kernel: drivers/usb/core/inode.c: creating file '001'
Oct  9 14:51:45 O kernel: hub 1-0:1.0: state 7 ports 4 chg 0000 evt 0000
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001803 POWER sig=j CSC CONNECT
Oct  9 14:51:45 O kernel: hub 1-0:1.0: port 3, status 0501, change 0001, 480 Mb/s
Oct  9 14:51:45 O kernel: hub 1-0:1.0: debounce: port 3: total 100ms stable 100ms status 0x501
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: port 3 high speed
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Oct  9 14:51:45 O kernel: usb 1-3: new high speed USB device using ehci_hcd and address 2
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: port 3 high speed
Oct  9 14:51:45 O kernel: ehci_hcd 0000:02:09.2: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Oct  9 14:51:46 O kernel: usb 1-3: new device strings: Mfr=0, Product=0, SerialNumber=0
Oct  9 14:51:46 O kernel: usb 1-3: uevent
Oct  9 14:51:46 O kernel: usb 1-3: usb_probe_device
Oct  9 14:51:46 O kernel: usb 1-3: configuration #1 chosen from 1 choice
Oct  9 14:51:46 O kernel: usb 1-3: adding 1-3:1.0 (config #1, interface 0)
Oct  9 14:51:46 O kernel: usb 1-3:1.0: uevent
Oct  9 14:51:46 O kernel: usb 1-3:1.0: uevent
Oct  9 14:51:46 O kernel: hub 1-3:1.0: usb_probe_interface
Oct  9 14:51:46 O kernel: hub 1-3:1.0: usb_probe_interface - got id
Oct  9 14:51:46 O kernel: hub 1-3:1.0: USB hub found
Oct  9 14:51:46 O kernel: hub 1-3:1.0: 4 ports detected
Oct  9 14:51:46 O kernel: hub 1-3:1.0: standalone hub
Oct  9 14:51:46 O kernel: hub 1-3:1.0: individual port power switching
Oct  9 14:51:46 O kernel: hub 1-3:1.0: individual port over-current protection
Oct  9 14:51:46 O kernel: hub 1-3:1.0: Single TT
Oct  9 14:51:46 O kernel: hub 1-3:1.0: TT requires at most 16 FS bit times (1332 ns)
Oct  9 14:51:46 O kernel: hub 1-3:1.0: Port indicators are supported
Oct  9 14:51:46 O kernel: hub 1-3:1.0: power on to power good time: 100ms
Oct  9 14:51:46 O kernel: hub 1-3:1.0: local power source is good
Oct  9 14:51:46 O kernel: hub 1-3:1.0: enabling power on all ports
Oct  9 14:51:46 O kernel: usb 1-3: link qh256-0001/c79d5100 start 255 [1/0 us]
Oct  9 14:51:46 O kernel: drivers/usb/core/inode.c: creating file '002'
Oct  9 14:51:46 O kernel: hub 1-3:1.0: state 7 ports 4 chg 0000 evt 0000
Oct  9 14:51:46 O kernel: hub 1-3:1.0: port 4, status 0101, change 0001, 12 Mb/s
Oct  9 14:51:46 O kernel: hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status 0x101
Oct  9 14:51:46 O kernel: usb 1-3.4: new high speed USB device using ehci_hcd and address 3
Oct  9 14:51:51 O kernel: usb 1-3.4: khubd timed out on ep0in len=0/9
Oct  9 14:51:51 O kernel: usb 1-3.4: unable to read config index 0 descriptor/start
Oct  9 14:51:51 O kernel: usb 1-3.4: chopping to 0 config(s)
Oct  9 14:51:56 O kernel: usb 1-3.4: khubd timed out on ep0in len=0/255
Oct  9 14:52:01 O kernel: usb 1-3.4: khubd timed out on ep0in len=0/2
Oct  9 14:52:01 O kernel: usb 1-3.4: string descriptor 0 read error: -110
Oct  9 14:52:06 O kernel: usb 1-3.4: khubd timed out on ep0in len=0/255
Oct  9 14:52:11 O kernel: usb 1-3.4: khubd timed out on ep0in len=0/2
Oct  9 14:52:11 O kernel: usb 1-3.4: string descriptor 0 read error: -110
Oct  9 14:52:16 O kernel: usb 1-3.4: khubd timed out on ep0in len=0/255
Oct  9 14:52:21 O kernel: usb 1-3.4: khubd timed out on ep0in len=0/2
Oct  9 14:52:21 O kernel: usb 1-3.4: string descriptor 0 read error: -110
Oct  9 14:52:21 O kernel: usb 1-3.4: new device strings: Mfr=1, Product=2, SerialNumber=3
Oct  9 14:52:21 O kernel: usb 1-3.4: uevent
Oct  9 14:52:21 O kernel: usb 1-3.4: usb_probe_device
Oct  9 14:52:21 O kernel: usb 1-3.4: no configuration chosen from 0 choices
Oct  9 14:52:21 O kernel: drivers/usb/core/inode.c: creating file '003'

Oddly enough, some of the drives seemed to work?

Anyway, lsusb hangs for quite some time before resolving (respodning
to a kill -9, usually, or error messages/whatever).  I'll try one
right now:

Ok, it's going very slowly:

That wasn't so bad ... only about a minute this time (usually takes 5
minutes with my USB hard drive (shoulda gotten firewire)):

root# lsusb -vv

Bus 001 Device 003: ID 058f:6362 Alcor Micro Corp.
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            0 (Defined at Interface level)
  bDeviceSubClass         0
  bDeviceProtocol         0
  bMaxPacketSize0        64
  idVendor           0x058f Alcor Micro Corp.
  idProduct          0x6362
  bcdDevice            1.29
  iManufacturer           1
  iProduct                2
  iSerial                 3
  bNumConfigurations      0
can't get device qualifier: Connection timed out
can't get debug descriptor: Connection timed out
cannot read device status, Connection timed out (110)

Bus 001 Device 002: ID 0409:005a NEC Corp.
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0 Unused
  bDeviceProtocol         1 Single TT
  bMaxPacketSize0        64
  idVendor           0x0409 NEC Corp.
  idProduct          0x005a
  bcdDevice            1.00
  iManufacturer           0
  iProduct                0
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           25
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower              100mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0 Unused
      bInterfaceProtocol      0 Full speed hub
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0001  1x 1 bytes
        bInterval              12
can't get hub descriptor: Connection timed out
can't get device qualifier: Connection timed out
can't get debug descriptor: Connection timed out
cannot read device status, Connection timed out (110)

Bus 001 Device 001: ID 0000:0000
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0 Unused
  bDeviceProtocol         1 Single TT
  bMaxPacketSize0        64
  idVendor           0x0000
  idProduct          0x0000
  bcdDevice            2.06
  iManufacturer           3 Linux 2.6.22.9 ehci_hcd
  iProduct                2 EHCI Host Controller
  iSerial                 1 0000:02:09.2
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           25
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                0mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0 Unused
      bInterfaceProtocol      0 Full speed hub
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0004  1x 4 bytes
        bInterval              12
Hub Descriptor:
  bLength               9
  bDescriptorType      41
  nNbrPorts             4
  wHubCharacteristic 0x000a
    No power switching (usb 1.0)
    Per-port overcurrent protection
    TT think time 8 FS bits
  bPwrOn2PwrGood       10 * 2 milli seconds
  bHubContrCurrent      0 milli Ampere
  DeviceRemovable    0x00
  PortPwrCtrlMask    0xff
 Hub Port Status:
   Port 1: 0000.0100 power
   Port 2: 0000.0100 power
   Port 3: 0000.0503 highspeed power enable connect
   Port 4: 0000.0100 power
Device Status:     0x0003
  Self Powered
  Remote Wakeup Enabled
root# 


During this time, syslog showed:

Oct  9 14:57:08 O kernel: usb 1-3.4: lsusb timed out on ep0in len=0/255
Oct  9 14:57:08 O kernel: usb 1-3.4: usbfs: USBDEVFS_CONTROL failed cmd lsusb rqt 128 rq 6 len 255 ret -110
Oct  9 14:57:09 O kernel: usb 1-3.4: lsusb timed out on ep0in len=0/255
Oct  9 14:57:09 O kernel: usb 1-3.4: usbfs: USBDEVFS_CONTROL failed cmd lsusb rqt 128 rq 6 len 255 ret -110
Oct  9 14:57:10 O kernel: usb 1-3.4: lsusb timed out on ep0in len=0/255
Oct  9 14:57:10 O kernel: usb 1-3.4: usbfs: USBDEVFS_CONTROL failed cmd lsusb rqt 128 rq 6 len 255 ret -110
Oct  9 14:57:15 O kernel: usb 1-3.4: lsusb timed out on ep0in len=0/10
Oct  9 14:57:15 O kernel: usb 1-3.4: usbfs: USBDEVFS_CONTROL failed cmd lsusb rqt 128 rq 6 len 10 ret -110
Oct  9 14:57:20 O kernel: usb 1-3.4: lsusb timed out on ep0in len=0/4
Oct  9 14:57:20 O kernel: usb 1-3.4: usbfs: USBDEVFS_CONTROL failed cmd lsusb rqt 128 rq 6 len 4 ret -110
Oct  9 14:57:25 O kernel: usb 1-3.4: lsusb timed out on ep0in len=0/2
Oct  9 14:57:25 O kernel: usb 1-3.4: usbfs: USBDEVFS_CONTROL failed cmd lsusb rqt 128 rq 0 len 2 ret -110
Oct  9 14:57:28 O kernel: usb 1-3: ksuspend_usbd timed out on ep0out len=0/0
Oct  9 14:57:28 O kernel: hub 1-3:1.0: can't suspend port 4, status -110
Oct  9 14:57:30 O kernel: usb 1-3: lsusb timed out on ep0in len=0/13
Oct  9 14:57:30 O kernel: usb 1-3: usbfs: USBDEVFS_CONTROL failed cmd lsusb rqt 160 rq 6 len 13 ret -110
Oct  9 14:57:33 O kernel: usb 1-3.4: ksuspend_usbd timed out on ep0out len=0/0
Oct  9 14:57:35 O kernel: usb 1-3: lsusb timed out on ep0in len=0/10
Oct  9 14:57:35 O kernel: usb 1-3: usbfs: USBDEVFS_CONTROL failed cmd lsusb rqt 128 rq 6 len 10 ret -110
Oct  9 14:57:40 O kernel: usb 1-3: lsusb timed out on ep0in len=0/4
Oct  9 14:57:40 O kernel: usb 1-3: usbfs: USBDEVFS_CONTROL failed cmd lsusb rqt 128 rq 6 len 4 ret -110
Oct  9 14:57:45 O kernel: usb 1-3: lsusb timed out on ep0in len=0/2
Oct  9 14:57:45 O kernel: usb 1-3: usbfs: USBDEVFS_CONTROL failed cmd lsusb rqt 128 rq 0 len 2 ret -110


And finally I rmmod ehci-hcd usbcore again:

Oct  9 15:01:31 O kernel: ehci_hcd 0000:02:09.2: remove, state 1
Oct  9 15:01:31 O kernel: ehci_hcd 0000:02:09.2: roothub graceful disconnect
Oct  9 15:01:31 O kernel: usb usb1: USB disconnect, address 1
Oct  9 15:01:31 O kernel: usb 1-3: USB disconnect, address 2
Oct  9 15:01:31 O kernel: usb 1-3.4: USB disconnect, address 3
Oct  9 15:01:31 O kernel: usb 1-3.4: unregistering device
Oct  9 15:01:31 O kernel: usb 1-3.4: usb_disable_device nuking all URBs
Oct  9 15:01:31 O kernel: usb_endpoint usbdev1.3_ep00: ep_device_release called for usbdev1.3_ep00
Oct  9 15:01:31 O kernel: usb 1-3.4: uevent
Oct  9 15:01:31 O kernel: usb 1-3: unregistering device
Oct  9 15:01:31 O kernel: usb 1-3: usb_disable_device nuking all URBs
Oct  9 15:01:31 O kernel: usb 1-3: unlink qh256-0001/c79d5100 start 255 [1/0 us]
Oct  9 15:01:31 O kernel: ehci_hcd 0000:02:09.2: shutdown urb c36cea80 pipe 40408280 ep1in-intr
Oct  9 15:01:31 O kernel: usb 1-3: unregistering interface 1-3:1.0
Oct  9 15:01:31 O kernel: usb_endpoint usbdev1.2_ep81: ep_device_release called for usbdev1.2_ep81
Oct  9 15:01:31 O kernel: usb 1-3:1.0: uevent
Oct  9 15:01:31 O kernel: usb 1-3:1.0: uevent
Oct  9 15:01:31 O kernel: usb_endpoint usbdev1.2_ep00: ep_device_release called for usbdev1.2_ep00
Oct  9 15:01:31 O kernel: usb 1-3: uevent
Oct  9 15:01:31 O kernel: usb usb1: unregistering device
Oct  9 15:01:31 O kernel: usb usb1: usb_disable_device nuking all URBs
Oct  9 15:01:31 O kernel: ehci_hcd 0000:02:09.2: shutdown urb c36ceae0 pipe 40408180 ep1in-intr
Oct  9 15:01:31 O kernel: usb usb1: unregistering interface 1-0:1.0
Oct  9 15:01:31 O kernel: usb_endpoint usbdev1.1_ep81: ep_device_release called for usbdev1.1_ep81
Oct  9 15:01:31 O kernel: usb 1-0:1.0: uevent
Oct  9 15:01:31 O kernel: usb 1-0:1.0: uevent
Oct  9 15:01:31 O kernel: usb_endpoint usbdev1.1_ep00: ep_device_release called for usbdev1.1_ep00
Oct  9 15:01:31 O kernel: usb usb1: uevent
Oct  9 15:01:31 O kernel: ehci_hcd 0000:02:09.2: stop
Oct  9 15:01:31 O kernel: ehci_hcd 0000:02:09.2: reset command 01000b (park)=0 ithresh=1 period=256 Reset RUN
Oct  9 15:01:31 O kernel: ehci_hcd 0000:02:09.2: irq normal 343206 err 0 reclaim 35 (lost 0)
Oct  9 15:01:31 O kernel: ehci_hcd 0000:02:09.2: complete 34 unlink 19
Oct  9 15:01:31 O kernel: ehci_hcd 0000:02:09.2: ehci_stop completed status 1000 Halt
Oct  9 15:01:31 O kernel: ehci_hcd 0000:02:09.2: USB bus 1 deregistered
Oct  9 15:01:31 O kernel: usbcore: deregistering device driver usb
Oct  9 15:01:31 O kernel: usbcore: deregistering interface driver usbfs
Oct  9 15:01:31 O kernel: usbcore: khubd exiting
Oct  9 15:01:31 O kernel: usbcore: deregistering interface driver hub




I'm having trouble figuring out which patches from the list and on the
web to attempt to try so far.  When I tried to apply them (by hand),
they didn't work since obviously they're based on the wrong kernel
version (patching stuff not even in kernel 2.6.22.9), or they're for
the arch specific stuff for a non-i386 arch.

Brad Allen
<[email protected]>

-------------------------------------------------------------------------
This SF.net email is sponsored by: Splunk Inc.
Still grepping through log files to find problems?  Stop.
Now Search log events and configuration files using AJAX and a browser.
Download your FREE copy of Splunk now >> http://get.splunk.com/
_______________________________________________
[email protected]
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-devel
lmpx.com only provides a reader for public news (NNTP) servers. It is not affiliated with the servers or forums shown here and is not responsible for the content of articles, which is written by their respective authors.