kern/46696: uhub disables port where USB keyboard attached

[mishka%NetBSD.org@localhost]

>Number:         46696
>Category:       kern
>Synopsis:       uhub disables port where USB keyboard attached
>Confidential:   no
>Severity:       critical
>Priority:       medium
>Responsible:    kern-bug-people
>State:          open
>Class:          sw-bug
>Submitter-Id:   net
>Arrival-Date:   Thu Jul 12 18:35:00 +0000 2012
>Originator:     Mike M. Volokhov
>Release:        NetBSD 6.0_BETA2, CVS 20120712
>Organization:
>Environment:
NetBSD nostromo.od5.lohika.com 6.0_BETA2 NetBSD 6.0_BETA2 (GENERIC) #0: Thu Jul 
12 05:39:46 EEST 2012  
mishka%nostromo.od5.lohika.com@localhost:/home/build/ab/obj-amd64-6.0_BETA2-20120712/sys/arch/amd64/compile/GENERIC
 amd64

>Description:
After reboot it may often happen that kernel cannot attach USB keyboard with 
"uhubX: device problem, disabling port Y" message. I tried different USB 
keyboards - effect the same. Sometimes keyboard attaches successfully. NetBSD 
5.1_STABLE just works.

The *working* dmesg output:

Copyright (c) 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005,
    2006, 2007, 2008, 2009, 2010, 2011, 2012
    The NetBSD Foundation, Inc.  All rights reserved.
Copyright (c) 1982, 1986, 1989, 1991, 1993
    The Regents of the University of California.  All rights reserved.

NetBSD 6.0_BETA2 (GENERIC) #0: Thu Jul 12 05:39:46 EEST 2012
        
mishka%nostromo.od5.lohika.com@localhost:/home/build/ab/obj-amd64-6.0_BETA2-20120712/sys/arch/amd64/compile/GENERIC
total memory = 8172 MB
avail memory = 7919 MB
timecounter: Timecounters tick every 10.000 msec
timecounter: Timecounter "i8254" frequency 1193182 Hz quality 100
Hewlett-Packard HP Elite 7300 Series MT (1.01)
mainbus0 (root)
cpu0 at mainbus0 apid 0: Intel(R) Core(TM) i7-2600 CPU @ 3.40GHz, id 0x206a7
cpu1 at mainbus0 apid 2: Intel(R) Core(TM) i7-2600 CPU @ 3.40GHz, id 0x206a7
cpu2 at mainbus0 apid 4: Intel(R) Core(TM) i7-2600 CPU @ 3.40GHz, id 0x206a7
cpu3 at mainbus0 apid 6: Intel(R) Core(TM) i7-2600 CPU @ 3.40GHz, id 0x206a7
ioapic0 at mainbus0 apid 0: pa 0xfec00000, version 20, 24 pins
acpi0 at mainbus0: Intel ACPICA 20110623
acpi0: X/RSDT: OemId <HPQOEM,SLIC-CPC,01072009>, AslId <AMI ,00010013>
acpi0: SCI interrupting at int 9
timecounter: Timecounter "ACPI-Fast" frequency 3579545 Hz quality 1000
hpet0 at acpi0: high precision event timer (mem 0xfed00000-0xfed00400)
timecounter: Timecounter "hpet0" frequency 14318180 Hz quality 2000
MCH (PNP0C01) at acpi0 not configured
SIO1 (PNP0C02) at acpi0 not configured
attimer1 at acpi0 (TMR, PNP0100): io 0x40-0x43 irq 0
pcppi1 at acpi0 (SPKR, PNP0800): io 0x61
midi0 at pcppi1: PC speaker
sysbeep0 at pcppi1
RMSC (PNP0C02) at acpi0 not configured
PCH (PNP0C01) at acpi0 not configured
CWDT (INT3F0D) at acpi0 not configured
acpibut0 at acpi0 (PWRB, PNP0C0C-170): ACPI Power Button
RMEM (PNP0C01) at acpi0 not configured
acpiwmi0 at acpi0 (WMID, PNP0C14-0): ACPI WMI Interface
wmihp0 at acpiwmi0: HP WMI mappings
OMSC (PNP0C02) at acpi0 not configured
attimer1: attached to pcppi1
pci0 at mainbus0 bus 0: configuration mode 1
pci0: i/o space, memory space enabled, rd/line, rd/mult, wr/inv ok
pchb0 at pci0 dev 0 function 0: vendor 0x8086 product 0x0100 (rev. 0x09)
ppb0 at pci0 dev 1 function 0: vendor 0x8086 product 0x0101 (rev. 0x09)
ppb0: PCI Express 2.0 <Root Port of PCI-E Root Complex>
pci1 at ppb0 bus 1
pci1: i/o space, memory space enabled, rd/line, wr/inv ok
vga0 at pci1 dev 0 function 0: vendor 0x1002 product 0x95c5 (rev. 0x00)
wsdisplay0 at vga0 kbdmux 1: console (80x25, vt100 emulation)
wsmux1: connecting to wsdisplay0
radeondrm0 at vga0: ATI Radeon HD 3450
radeondrm0: Initialized radeon 1.29.0 20080613
hdaudio0 at pci1 dev 0 function 1: HD Audio Controller
hdaudio0: interrupting at ioapic0 pin 17
hdafg0 at hdaudio0: ATI R6xx HDMI
hdafg0: HDMI00 2ch: Digital Out [Jack]
hdafg0: 2ch/0ch 48000Hz PCM16 AC3
vendor 0x8086 product 0x1c3a (miscellaneous communications, revision 0x04) at 
pci0 dev 22 function 0 not configured
ehci0 at pci0 dev 26 function 0: vendor 0x8086 product 0x1c2d (rev. 0x05)
ehci0: interrupting at ioapic0 pin 16
ehci0: BIOS has given up ownership
ehci0: EHCI version 1.0
usb0 at ehci0: USB revision 2.0
hdaudio1 at pci0 dev 27 function 0: HD Audio Controller
hdaudio1: interrupting at ioapic0 pin 22
hdafg1 at hdaudio1: Sigmatel product 76c7
hdafg1: DAC00 8ch: Speaker [Jack], HP Out [Jack]
hdafg1: ADC01 2ch: Mic In [Jack]
hdafg1: ADC02 2ch: Line In [Built-In]
hdafg1: DIG03 2ch: SPDIF Out [Jack]
hdafg1: 8ch/2ch 44100Hz 48000Hz 88200Hz 96000Hz 192000Hz PCM16 PCM20 PCM24 AC3
audio0 at hdafg1: full duplex, playback, capture, independent
ppb1 at pci0 dev 28 function 0: vendor 0x8086 product 0x1c10 (rev. 0xb5)
ppb1: PCI Express 2.0 <Root Port of PCI-E Root Complex>
pci2 at ppb1 bus 2
pci2: i/o space, memory space enabled, rd/line, wr/inv ok
ppb2 at pci0 dev 28 function 1: vendor 0x8086 product 0x1c12 (rev. 0xb5)
ppb2: PCI Express 2.0 <Root Port of PCI-E Root Complex>
pci3 at ppb2 bus 3
pci3: i/o space, memory space enabled, rd/line, wr/inv ok
ppb3 at pci0 dev 28 function 2: vendor 0x8086 product 0x1c14 (rev. 0xb5)
ppb3: PCI Express 2.0 <Root Port of PCI-E Root Complex>
pci4 at ppb3 bus 4
pci4: i/o space, memory space enabled, rd/line, wr/inv ok
ppb4 at pci0 dev 28 function 3: vendor 0x8086 product 0x1c16 (rev. 0xb5)
ppb4: PCI Express 2.0 <Root Port of PCI-E Root Complex>
pci5 at ppb4 bus 5
pci5: i/o space, memory space enabled, rd/line, wr/inv ok
ppb5 at pci0 dev 28 function 4: vendor 0x8086 product 0x1c18 (rev. 0xb5)
ppb5: PCI Express 2.0 <Root Port of PCI-E Root Complex>
pci6 at ppb5 bus 6
pci6: i/o space, memory space enabled, rd/line, wr/inv ok
re0 at pci6 dev 0 function 0: RealTek 8168/8111 PCIe Gigabit Ethernet (rev. 
0x06)
re0: interrupting at ioapic0 pin 16
re0: Ethernet address e0:69:95:f9:a9:5e
re0: using 256 tx descriptors
rgephy0 at re0 phy 7: RTL8169S/8110S/8211 1000BASE-T media interface, rev. 4
rgephy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 
1000baseT-FDX, auto
ppb6 at pci0 dev 28 function 5: vendor 0x8086 product 0x1c1a (rev. 0xb5)
ppb6: PCI Express 2.0 <Root Port of PCI-E Root Complex>
pci7 at ppb6 bus 7
pci7: i/o space, memory space enabled, rd/line, wr/inv ok
vendor 0x104c product 0x8241 (USB serial bus, interface 0x30, revision 0x02) at 
pci7 dev 0 function 0 not configured
ppb7 at pci0 dev 28 function 6: vendor 0x8086 product 0x1c1c (rev. 0xb5)
ppb7: PCI Express 2.0 <Root Port of PCI-E Root Complex>
pci8 at ppb7 bus 8
pci8: i/o space, memory space enabled, rd/line, wr/inv ok
ppb8 at pci0 dev 28 function 7: vendor 0x8086 product 0x1c1e (rev. 0xb5)
ppb8: PCI Express 2.0 <Root Port of PCI-E Root Complex>
pci9 at ppb8 bus 9
pci9: i/o space, memory space enabled, rd/line, wr/inv ok
ehci1 at pci0 dev 29 function 0: vendor 0x8086 product 0x1c26 (rev. 0x05)
ehci1: interrupting at ioapic0 pin 23
ehci1: BIOS has given up ownership
ehci1: EHCI version 1.0
usb1 at ehci1: USB revision 2.0
ichlpcib0 at pci0 dev 31 function 0: vendor 0x8086 product 0x1c4a (rev. 0x05)
timecounter: Timecounter "ichlpcib0" frequency 3579545 Hz quality 1000
ichlpcib0: 24-bit timer
ichlpcib0: TCO (watchdog) timer configured.
ahcisata0 at pci0 dev 31 function 2: vendor 0x8086 product 0x1c02 (rev. 0x05)
ahcisata0: interrupting at ioapic0 pin 19
ahcisata0: 64-bit DMA
ahcisata0: AHCI revision 1.30, 6 ports, 32 slots, CAP 
0xe730ff45<EMS,PSC,SSC,PMD,ISS=0x3=Gen3,SCLO,SAL,SALP,SSNTF,SNCQ,S64A>
atabus0 at ahcisata0 channel 0
atabus1 at ahcisata0 channel 1
atabus2 at ahcisata0 channel 2
atabus3 at ahcisata0 channel 3
atabus4 at ahcisata0 channel 4
atabus5 at ahcisata0 channel 5
ichsmb0 at pci0 dev 31 function 3: vendor 0x8086 product 0x1c22 (rev. 0x05)
ichsmb0: interrupting at ioapic0 pin 18
iic0 at ichsmb0: I2C bus
isa0 at ichlpcib0
pckbc0 at isa0 port 0x60-0x64
acpicpu0 at cpu0: ACPI CPU
acpicpu0: C1: FFH, lat   1 us, pow  1000 mW
acpicpu0: C3: FFH, lat 104 us, pow   350 mW, bus master check
acpicpu0: P0: FFH, lat  10 us, pow 95000 mW, 3401 MHz, turbo boost
acpicpu0: P1: FFH, lat  10 us, pow 95000 mW, 3400 MHz
acpicpu0: P2: FFH, lat  10 us, pow 87000 mW, 3200 MHz
acpicpu0: P3: FFH, lat  10 us, pow 80000 mW, 3000 MHz
acpicpu0: P4: FFH, lat  10 us, pow 73000 mW, 2800 MHz
acpicpu0: P5: FFH, lat  10 us, pow 66000 mW, 2600 MHz
acpicpu0: P6: FFH, lat  10 us, pow 60000 mW, 2400 MHz
acpicpu0: P7: FFH, lat  10 us, pow 54000 mW, 2200 MHz
acpicpu0: P8: FFH, lat  10 us, pow 48000 mW, 2000 MHz
acpicpu0: P9: FFH, lat  10 us, pow 42000 mW, 1800 MHz
acpicpu0: P10: FFH, lat  10 us, pow 36000 mW, 1600 MHz
acpicpu0: T0: FFH, lat   1 us, pow  1000 mW, 100 %
acpicpu0: T1: FFH, lat   1 us, pow   875 mW,  88 %
acpicpu0: T2: FFH, lat   1 us, pow   750 mW,  75 %
acpicpu0: T3: FFH, lat   1 us, pow   625 mW,  63 %
acpicpu0: T4: FFH, lat   1 us, pow   500 mW,  50 %
acpicpu0: T5: FFH, lat   1 us, pow   375 mW,  38 %
acpicpu0: T6: FFH, lat   1 us, pow   250 mW,  25 %
acpicpu0: T7: FFH, lat   1 us, pow   125 mW,  13 %
coretemp0 at cpu0: thermal sensor, 1 C resolution
acpicpu1 at cpu1: ACPI CPU
coretemp1 at cpu1: thermal sensor, 1 C resolution
acpicpu2 at cpu2: ACPI CPU
coretemp2 at cpu2: thermal sensor, 1 C resolution
acpicpu3 at cpu3: ACPI CPU
coretemp3 at cpu3: thermal sensor, 1 C resolution
timecounter: Timecounter "clockinterrupt" frequency 100 Hz quality 0
timecounter: Timecounter "TSC" frequency 3392412040 Hz quality 3000
uhub0 at usb0: vendor 0x8086 EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
uhub1 at usb1: vendor 0x8086 EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub1: 2 ports with 2 removable, self powered
ahcisata0 port 0: device present, speed: 3.0Gb/s
ahcisata0 port 1: device present, speed: 3.0Gb/s
ahcisata0 port 4: device present, speed: 1.5Gb/s
ahcisata0 port 2: PHY offline
ahcisata0 port 3: PHY offline
ahcisata0 port 5: PHY offline
wd0 at atabus0 drive 0
wd0: <ST31500341AS>
wd0: drive supports 16-sector PIO transfers, LBA48 addressing
wd0: 1397 GB, 2907021 cyl, 16 head, 63 sec, 512 bytes/sect x 2930277168 sectors
wd0: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100)
wd0(ahcisata0:0:0): using PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100) 
(using DMA)
wd1 at atabus1 drive 0
wd1: <ST31500341AS>
wd1: drive supports 16-sector PIO transfers, LBA48 addressing
wd1: 1397 GB, 2907021 cyl, 16 head, 63 sec, 512 bytes/sect x 2930277168 sectors
wd1: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100)
wd1(ahcisata0:1:0): using PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100) 
(using DMA)
atapibus0 at atabus4: 1 targets
cd0 at atapibus0 drive 0: <hp      DVD RW AD-7251H5, 2966433L21, 1H8D> cdrom 
removable
cd0: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100)
cd0(ahcisata0:4:0): using PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100) 
(using DMA)
uhub2 at uhub1 port 1: vendor 0x8087 product 0x0024, class 9/0, rev 2.00/0.00, 
addr 2
uhub2: single transaction translator
uhub3 at uhub0 port 1: vendor 0x8087 product 0x0024, class 9/0, rev 2.00/0.00, 
addr 2
uhub3: single transaction translator
uhub3: 6 ports with 6 removable, self powered
uhub2: 8 ports with 8 removable, self powered
umass0 at uhub3 port 6 configuration 1 interface 0
umass0: Generic Mass Storage Device, rev 2.00/1.00, addr 3
umass0: using SCSI over Bulk-Only
uhidev0 at uhub2 port 1 configuration 1 interface 0
scsibus0 at umass0: 2 targets, 4 luns per target
sd0 at scsibus0 target 0 lun 0: <Generic-, SD/MMC, 1.00> disk removable
uhidev0: vendor 0x0461 USB Optical Mouse, rev 2.00/2.00, addr 3, iclass 3/1
sd0: drive offline
ums0 at uhidev0: 3 buttons, W and Z dirs
wsmouse0 at ums0 mux 0
sd0: unable to open device, error = 19
sd1 at scsibus0 target 0 lun 1: <Generic-, Compact Flash, 1.01> disk removable
sd1: drive offline
sd1: unable to open device, error = 19
sd2 at scsibus0 target 0 lun 2: <Generic-, SM/xD-Picture, 1.02> disk removable
sd2: drive offline
sd2: unable to open device, error = 19
sd3 at scsibus0 target 0 lun 3: <Generic-, MS/MS-Pro, 1.03> disk removable
sd3: drive offline
sd3: unable to open device, error = 19
uhidev1 at uhub2 port 2 configuration 1 interface 0
uhidev1: LITEON Technology USB Multimedia Keyboard, rev 1.10/1.01, addr 4, 
iclass 3/1
ukbd0 at uhidev1: 8 modifier keys, 6 key codes
wskbd0 at ukbd0: console keyboard, using wsdisplay0
Kernelized RAIDframe activated
pad0: outputs: 44100Hz, 16-bit, stereo
audio1 at pad0: half duplex, playback, capture
raid0: RAID Level 1
raid0: Components: /dev/wd0e /dev/wd1e
raid0: Total Sectors: 2930276992 (1430799 MB)
boot device: raid0
root on raid0a dumps on raid0b
root on raid0a dumps on raid0b
root file system type: ffs
wsdisplay0: screen 1 added (80x25, vt100 emulation)
wsdisplay0: screen 2 added (80x25, vt100 emulation)
wsdisplay0: screen 3 added (80x25, vt100 emulation)
wsdisplay0: screen 4 added (80x25, vt100 emulation)
info: [drm] Setting GART location based on new memory map
info: [drm] Loading RV620 Microcode
info: [drm] Resetting GPU
info: [drm] writeback test succeeded in 1 usecs
radeondrm0: interrupting at ioapic0 pin 16

After reboot (including remote shutdown, when nobody touches the keyboard), the 
following difference may happen which leads to *defunct* keyboard:

--- dmesg+kbd   2012-07-12 21:08:23.000000000 +0300
+++ dmesg-kbd   2012-07-12 21:14:21.000000000 +0300
@@ -163,7 +163,7 @@
 acpicpu3 at cpu3: ACPI CPU
 coretemp3 at cpu3: thermal sensor, 1 C resolution
 timecounter: Timecounter "clockinterrupt" frequency 100 Hz quality 0
-timecounter: Timecounter "TSC" frequency 3392412040 Hz quality 3000
+timecounter: Timecounter "TSC" frequency 3392513720 Hz quality 3000
 uhub0 at usb0: vendor 0x8086 EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
 uhub0: 2 ports with 2 removable, self powered
 uhub1 at usb1: vendor 0x8086 EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
@@ -199,8 +199,8 @@
 umass0 at uhub3 port 6 configuration 1 interface 0
 umass0: Generic Mass Storage Device, rev 2.00/1.00, addr 3
 umass0: using SCSI over Bulk-Only
-uhidev0 at uhub2 port 1 configuration 1 interface 0
 scsibus0 at umass0: 2 targets, 4 luns per target
+uhidev0 at uhub2 port 1 configuration 1 interface 0
 sd0 at scsibus0 target 0 lun 0: <Generic-, SD/MMC, 1.00> disk removable
 uhidev0: vendor 0x0461 USB Optical Mouse, rev 2.00/2.00, addr 3, iclass 3/1
 sd0: drive offline
@@ -216,10 +216,7 @@
 sd3 at scsibus0 target 0 lun 3: <Generic-, MS/MS-Pro, 1.03> disk removable
 sd3: drive offline
 sd3: unable to open device, error = 19
-uhidev1 at uhub2 port 2 configuration 1 interface 0
-uhidev1: LITEON Technology USB Multimedia Keyboard, rev 1.10/1.01, addr 4, 
iclass 3/1
-ukbd0 at uhidev1: 8 modifier keys, 6 key codes
-wskbd0 at ukbd0: console keyboard, using wsdisplay0
+uhub2: device problem, disabling port 2
 Kernelized RAIDframe activated
 pad0: outputs: 44100Hz, 16-bit, stereo
 audio1 at pad0: half duplex, playback, capture

>How-To-Repeat:
Use HP Elite 7300, install stock NetBSD 6.0_BETA, reboot.
>Fix: