Re: kern/51126: USB device problem after nick-nhusb merge

[Nick Hudson <skrll%netbsd.org@localhost>]

The following reply was made to PR kern/51126; it has been noted by GNATS.

From: Nick Hudson <skrll%netbsd.org@localhost>
To: gnats-bugs%NetBSD.org@localhost, kern-bug-people%netbsd.org@localhost,
 gnats-admin%netbsd.org@localhost, netbsd-bugs%netbsd.org@localhost
Cc: 
Subject: Re: kern/51126: USB device problem after nick-nhusb merge
Date: Mon, 9 May 2016 09:46:14 +0100

 From http://mail-index.netbsd.org/port-amd64/2016/04/26/msg002417.html
 
 
 NetBSD 7.99.28 (GENERIC) #1: Tue Apr 26 09:10:57 EDT 2016
      xxx@tiamat:/opt/src/sys/arch/amd64/compile/GENERIC
 total memory = 4079 MB
 avail memory = 3935 MB
 timecounter: Timecounters tick every 10.000 msec
 Kernelized RAIDframe activated
 timecounter: Timecounter "i8254" frequency 1193182 Hz quality 100
 Gigabyte Technology Co., Ltd. H61M-S2-B3 ( )
 mainbus0 (root)
 ACPI: RSDP 0x00000000000F6EA0 000014 (v00 GBT   )
 ACPI: RSDT 0x00000000DF7D3040 00004C (v01 GBT    GBTUACPI 42302E31 GBTU 
 01010101)
 ACPI: FACP 0x00000000DF7D3100 000074 (v01 GBT    GBTUACPI 42302E31 GBTU 
 01010101)
 ACPI: DSDT 0x00000000DF7D31C0 0049F2 (v01 GBT    GBTUACPI 00001000 MSFT 
 04000000)
 ACPI: FACS 0x00000000DF7D0000 000040
 ACPI: MSDM 0x00000000DF7D7D00 000055 (v03 GBT    GBTUACPI 42302E31 GBTU 
 01010101)
 ACPI: HPET 0x00000000DF7D7DC0 000038 (v01 GBT    GBTUACPI 42302E31 GBTU 
 00000098)
 ACPI: MCFG 0x00000000DF7D7E40 00003C (v01 GBT    GBTUACPI 42302E31 GBTU 
 01010101)
 ACPI: ASPT 0x00000000DF7D7F00 000034 (v07 GBT    PerfTune 312E3042 UTBG 
 01010101)
 ACPI: SSPT 0x00000000DF7D7F40 002270 (v01 GBT    SsptHead 312E3042 UTBG 
 01010101)
 ACPI: EUDS 0x00000000DF7DA1B0 0000C0 (v01 GBT 00000000      00000000)
 ACPI: TAMG 0x00000000DF7DA270 000382 (v01 GBT    GBT   B0 5455312E BG?? 
 45240101)
 ACPI: APIC 0x00000000DF7D7C00 0000BC (v01 GBT    GBTUACPI 42302E31 GBTU 
 01010101)
 ACPI: SSDT 0x00000000DF7DA600 001EC8 (v01 INTEL  PPM RCM  80000001 INTL 
 20061109)
 ACPI: 2 ACPI AML tables successfully acquired and loaded
 ioapic0 at mainbus0 apid 2: pa 0xfec00000, version 0x20, 24 pins
 cpu0 at mainbus0 apid 0
 cpu0: Intel(R) Core(TM) i3-2120 CPU @ 3.30GHz, id 0x206a7
 cpu1 at mainbus0 apid 2
 cpu1: Intel(R) Core(TM) i3-2120 CPU @ 3.30GHz, id 0x206a7
 cpu2 at mainbus0 apid 1
 cpu2: Intel(R) Core(TM) i3-2120 CPU @ 3.30GHz, id 0x206a7
 cpu3 at mainbus0 apid 3
 cpu3: Intel(R) Core(TM) i3-2120 CPU @ 3.30GHz, id 0x206a7
 acpi0 at mainbus0: Intel ACPICA 20160108
 acpi0: X/RSDT: OemId <GBT   ,GBTUACPI,42302e31>, AslId <GBTU,01010101>
 acpi0: MCFG: segment 0, bus 0-63, address 0x00000000f4000000
 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
 acpibut0 at acpi0 (PWRB, PNP0C0C): ACPI Power Button
 SYSR (PNP0C02) at acpi0 not configured
 attimer1 at acpi0 (TMR, PNP0100): io 0x40-0x43
 pcppi1 at acpi0 (SPKR, PNP0800): io 0x61
 midi0 at pcppi1: PC speaker
 sysbeep0 at pcppi1
 UAR1 (PNP0501) at acpi0 not configured
 pckbc1 at acpi0 (PS2M, PNP0F13) (aux port): irq 12
 pckbc2 at acpi0 (PS2K, PNP0303) (kbd port): io 0x60,0x64 irq 1
 PMIO (PNP0C02) at acpi0 not configured
 PTMD (ICD0001) at acpi0 not configured
 CWDT (INT3F0D) at acpi0 not configured
 EXPL (PNP0C02) at acpi0 not configured
 MEM (PNP0C01) at acpi0 not configured
 FWH (INT0800) at acpi0 not configured
 ACPI: Enabled 1 GPEs in block 00 to 3F
 attimer1: attached to pcppi1
 pckbd0 at pckbc2 (kbd slot)
 pckbc2: using irq 1 for kbd slot
 wskbd0 at pckbd0: console keyboard
 pms0 at pckbc2 (aux slot)
 pckbc2: using irq 12 for aux slot
 wsmouse0 at pms0 mux 0
 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 8086 product 0100 (rev. 0x09)
 ppb0 at pci0 dev 1 function 0: vendor 8086 product 0101 (rev. 0x09)
 ppb0: PCI Express capability version 2 <Root Port of PCI-E Root Complex> 
 x16 @ 5.0GT/s
 pci1 at ppb0 bus 1
 pci1: i/o space, memory space enabled, rd/line, wr/inv ok
 radeon0 at pci1 dev 0 function 0: vendor 1002 product 6779 (rev. 0x00)
 hdaudio0 at pci1 dev 0 function 1: HD Audio Controller
 hdaudio0: interrupting at ioapic0 pin 17
 hdafg0 at hdaudio0: vendor 1002 product aa01
 hdafg0: HDMI00 2ch: Digital Out [Jack]
 hdafg0: 2ch/0ch 32000Hz 44100Hz 48000Hz PCM16 AC3
 audio0 at hdafg0: half duplex, playback, mmap
 vendor 8086 product 1c3a (miscellaneous communications, revision 0x04) 
 at pci0 dev 22 function 0 not configured
 ehci0 at pci0 dev 26 function 0: vendor 8086 product 1c2d (rev. 0x05)
 ehci0: interrupting at ioapic0 pin 18
 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: vendor 1106 product 0397
 hdafg1: DAC00 2ch: Speaker [Jack], HP Out [Jack]
 hdafg1: ADC01 2ch: Line In [Jack], Mic In [Jack]
 hdafg1: 2ch/2ch 48000Hz PCM16*
 audio1 at hdafg1: full duplex, playback, capture, mmap, independent
 ppb1 at pci0 dev 28 function 0: vendor 8086 product 1c10 (rev. 0xb5)
 ppb1: PCI Express capability version 2 <Root Port of PCI-E Root Complex> 
 x1 @ 5.0GT/s
 pci2 at ppb1 bus 2
 pci2: i/o space, memory space enabled, rd/line, wr/inv ok
 ppb2 at pci0 dev 28 function 4: vendor 8086 product 1c18 (rev. 0xb5)
 ppb2: PCI Express capability version 2 <Root Port of PCI-E Root Complex> 
 x1 @ 5.0GT/s
 ppb2: link is x1 @ 2.5GT/s
 pci3 at ppb2 bus 3
 pci3: i/o space, memory space enabled, rd/line, wr/inv ok
 alc0 at pci3 dev 0 function 0: Atheros AR8151 v2.0 PCIe Gigabit Ethernet
 alc0: interrupting at ioapic0 pin 16
 alc0: Ethernet address 50:e5:49:84:e3:f0
 atphy0 at alc0 phy 0: Atheros AR8035 10/100/1000 PHY, rev. 0
 atphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT-FDX, auto
 ppb3 at pci0 dev 28 function 5: vendor 8086 product 1c1a (rev. 0xb5)
 ppb3: PCI Express capability version 2 <Root Port of PCI-E Root Complex> 
 x1 @ 5.0GT/s
 ppb3: link is x1 @ 2.5GT/s
 pci4 at ppb3 bus 4
 pci4: i/o space, memory space enabled, rd/line, wr/inv ok
 jmide0 at pci4 dev 0 function 0: vendor 197b product 2363 (rev. 0x10)
 jmide0: 1 PATA port, 2 SATA ports
 jmide0: interrupting at ioapic0 pin 17
 ahcisata0 at jmide0
 ahcisata0: AHCI revision 1.10, 2 ports, 32 slots, CAP 
 0xc722ff01<PSC,SSC,PMD,SPM,ISS=0x2=Gen2,SCLO,SAL,SALP,SNCQ,S64A>
 atabus0 at ahcisata0 channel 0
 atabus1 at ahcisata0 channel 1
 jmide0: PCI IDE interface used
 jmide0: bus-master DMA support present
 jmide0: primary channel wired to native-PCI mode
 jmide0: primary channel is unused
 jmide0: secondary channel wired to native-PCI mode
 jmide0: secondary channel is PATA
 atabus2 at jmide0 channel 1
 ehci1 at pci0 dev 29 function 0: vendor 8086 product 1c26 (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
 ppb4 at pci0 dev 30 function 0: vendor 8086 product 244e (rev. 0xa5)
 pci5 at ppb4 bus 5
 pci5: i/o space, memory space enabled
 ichlpcib0 at pci0 dev 31 function 0: vendor 8086 product 1c5c (rev. 0x05)
 timecounter: Timecounter "ichlpcib0" frequency 3579545 Hz quality 1000
 ichlpcib0: 24-bit timer
 tco0 at ichlpcib0: TCO (watchdog) timer configured.
 tco0: TCO timer reboot disabled by hardware; hope SMBIOS properly 
 handles it.
 tco0: Min/Max interval 1/367 seconds
 gpio0 at ichlpcib0: 64 pins
 ahcisata1 at pci0 dev 31 function 2: vendor 8086 product 1c02 (rev. 0x05)
 ahcisata1: interrupting at ioapic0 pin 19
 ahcisata1: 64-bit DMA
 ahcisata1: AHCI revision 1.30, 4 ports, 32 slots, CAP 
 0xef22ff43<EMS,PSC,SSC,PMD,SPM,ISS=0x2=Gen2,SCLO,SAL,SALP,SSS,SSNTF,SNCQ,S64A>
 atabus3 at ahcisata1 channel 0
 atabus4 at ahcisata1 channel 1
 atabus5 at ahcisata1 channel 4
 atabus6 at ahcisata1 channel 5
 ichsmb0 at pci0 dev 31 function 3: vendor 8086 product 1c22 (rev. 0x05)
 ichsmb0: interrupting at ioapic0 pin 18
 iic0 at ichsmb0: I2C bus
 isa0 at ichlpcib0
 com0 at isa0 port 0x3f8-0x3ff irq 4: ns16550a, working fifo
 acpicpu0 at cpu0: ACPI CPU
 acpicpu0: C1: FFH, lat   1 us, pow  1000 mW
 acpicpu0: C2: FFH, lat  64 us, pow   500 mW
 acpicpu0: C3: FFH, lat  96 us, pow   350 mW
 acpicpu0: P0: FFH, lat  10 us, pow 84000 mW, 3300 MHz
 acpicpu0: P1: FFH, lat  10 us, pow 84000 mW, 3200 MHz
 acpicpu0: P2: FFH, lat  10 us, pow 84000 mW, 3100 MHz
 acpicpu0: P3: FFH, lat  10 us, pow 84000 mW, 3000 MHz
 acpicpu0: P4: FFH, lat  10 us, pow 84000 mW, 2900 MHz
 acpicpu0: P5: FFH, lat  10 us, pow 84000 mW, 2800 MHz
 acpicpu0: P6: FFH, lat  10 us, pow 84000 mW, 2700 MHz
 acpicpu0: P7: FFH, lat  10 us, pow 84000 mW, 2600 MHz
 acpicpu0: P8: FFH, lat  10 us, pow 84000 mW, 2500 MHz
 acpicpu0: P9: FFH, lat  10 us, pow 76000 mW, 2400 MHz
 acpicpu0: P10: FFH, lat  10 us, pow 72000 mW, 2300 MHz
 acpicpu0: P11: FFH, lat  10 us, pow 69000 mW, 2200 MHz
 acpicpu0: P12: FFH, lat  10 us, pow 66000 mW, 2100 MHz
 acpicpu0: P13: FFH, lat  10 us, pow 63000 mW, 2000 MHz
 acpicpu0: P14: FFH, lat  10 us, pow 58000 mW, 1900 MHz
 acpicpu0: P15: FFH, lat  10 us, pow 56000 mW, 1800 MHz
 acpicpu0: P16: FFH, lat  10 us, pow 54000 mW, 1700 MHz
 acpicpu0: P17: FFH, lat  10 us, pow 52000 mW, 1600 MHz
 acpicpu0: T0: FFH, lat   1 us, pow   672 mW, 100 %
 acpicpu0: T1: FFH, lat   1 us, pow   588 mW,  88 %
 acpicpu0: T2: FFH, lat   1 us, pow   504 mW,  75 %
 acpicpu0: T3: FFH, lat   1 us, pow   420 mW,  63 %
 acpicpu0: T4: FFH, lat   1 us, pow   336 mW,  50 %
 acpicpu0: T5: FFH, lat   1 us, pow   252 mW,  38 %
 acpicpu0: T6: FFH, lat   1 us, pow   168 mW,  25 %
 acpicpu0: T7: FFH, lat   1 us, pow    84 mW,  13 %
 coretemp0 at cpu0: thermal sensor, 1 C resolution, Tjmax=102
 acpicpu1 at cpu1: ACPI CPU
 coretemp1 at cpu1: thermal sensor, 1 C resolution, Tjmax=102
 acpicpu2 at cpu2: ACPI CPU
 acpicpu3 at cpu3: ACPI CPU
 timecounter: Timecounter "clockinterrupt" frequency 100 Hz quality 0
 ERROR: 982 cycle TSC drift observed
 IPsec: Initialized Security Association Processing.
 uhub0 at usb0: vendor 8086 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 8086 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: 1.5Gb/s
 ahcisata0 port 1: device present, speed: 1.5Gb/s
 ahcisata1 port 0: device present, speed: 3.0Gb/s
 ahcisata1 port 1: device present, speed: 3.0Gb/s
 atapibus0 at atabus0: 1 targets
 cd0 at atapibus0 drive 0: <Memorex MRX-550L v4, CSAJ5121200003, AM62> 
 cdrom removable
 cd0: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100)
 cd0(ahcisata0:0:0): using PIO mode 4, DMA mode 2, Ultra-DMA mode 5 
 (Ultra/100) (using DMA)
 atapibus1 at atabus1: 1 targets
 cd1 at atapibus1 drive 0: <TSSTcorp CDDVDW SH-224DB, R9636YAFB00DAZ, 
 SB01> cdrom removable
 cd1: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100)
 cd1(ahcisata0:1:0): using PIO mode 4, DMA mode 2, Ultra-DMA mode 5 
 (Ultra/100) (using DMA)
 wd0 at atabus3 drive 0
 wd0: <ST1000NM0033-9ZM173>
 wd0: drive supports 16-sector PIO transfers, LBA48 addressing
 wd0: 931 GB, 1938021 cyl, 16 head, 63 sec, 512 bytes/sect x 1953525168 
 sectors
 wd0: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 6 (Ultra/133)
 wd0(ahcisata1:0:0): using PIO mode 4, DMA mode 2, Ultra-DMA mode 6 
 (Ultra/133) (using DMA)
 wd1 at atabus4 drive 0
 wd1: <ST500DM002-1BC142>
 wd1: drive supports 16-sector PIO transfers, LBA48 addressing
 wd1: 465 GB, 969021 cyl, 16 head, 63 sec, 512 bytes/sect x 976773168 sectors
 wd1: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 6 (Ultra/133)
 wd1(ahcisata1:1:0): using PIO mode 4, DMA mode 2, Ultra-DMA mode 6 
 (Ultra/133) (using DMA)
 uhub2 at uhub0 port 1: vendor 8087 product 0024, class 9/0, rev 
 2.00/0.00, addr 2
 uhub2: single transaction translator
 uhub3 at uhub1 port 1: vendor 8087 product 0024, class 9/0, rev 
 2.00/0.00, addr 2
 uhub3: single transaction translator
 uhub2: 4 ports with 4 removable, self powered
 uhub3: 6 ports with 6 removable, self powered
 uplcom0 at uhub3 port 2
 uplcom0: Prolific Technology Inc. USB-Serial Controller D, rev 
 1.10/4.00, addr 3
 ucom0 at uplcom0
 uhub4 at uhub3 port 3: vendor 0a05 USB2.0 HUB, class 9/0, rev 2.00/1.00, 
 addr 4
 uhub4: 4 ports with 4 removable, self powered
 ugen0 at uhub3 port 4
 ugen0: USB Hub 2.0 USB Hub 2.0, rev 1.10/7.02, addr 5
 pad0: outputs: 44100Hz, 16-bit, stereo
 audio2 at pad0: half duplex, playback, capture
 boot device: wd0
 root on wd0a dumps on wd0b
 root file system type: ffs
 kern.module.path=/stand/amd64/7.99.28/modules
 drm: initializing kernel modesetting (CAICOS 0x1002:0x6779 0x1682:0x3200).
 drm: register mmio base: 0xfbec0000
 drm: register mmio size: 131072
 drm kern info: ATOM BIOS: C28506
 radeon0: info: VRAM: 2048M 0x0000000000000000 - 0x000000007FFFFFFF 
 (2048M used)
 radeon0: info: GTT: 1024M 0x0000000080000000 - 0x00000000BFFFFFFF
 drm: Detected VRAM RAM=800M, BAR=256M
 drm: RAM width 64bits DDR
 Zone  kernel: Available graphics memory: 1411020 kiB
 drm: radeon: 2048M of VRAM memory ready
 drm: radeon: 1024M of GTT memory ready.
 drm: Loading CAICOS Microcode
 drm: Internal thermal controller without fan control
 drm: GART: num cpu pages 262144, num gpu pages 262144
 drm: PCIE GART of 1024M enabled (table at 0x0000000000273000).
 radeon0: info: WB enabled
 radeon0: info: fence driver on ring 0 use gpu addr 0x0000000080000c00 
 and cpu addr 0x0xffff80004a1f4c00
 radeon0: info: fence driver on ring 3 use gpu addr 0x0000000080000c0c 
 and cpu addr 0x0xffff80004a1f4c0c
 radeon0: info: fence driver on ring 5 use gpu addr 0x0000000000072118 
 and cpu addr 0x0xffff800049df2118
 drm: Supports vblank timestamp caching Rev 2 (21.10.2013).
 drm: Driver supports precise vblank timestamp query.
 radeon0: interrupting at ioapic0 pin 16 (radeon)
 drm: radeon: irq initialized.
 drm: ring test on 0 succeeded in 1 usecs
 drm: ring test on 3 succeeded in 1 usecs
 drm: ring test on 5 succeeded in 1 usecs
 drm: UVD initialized successfully.
 drm: ib test on ring 0 succeeded in 0 usecs
 drm: ib test on ring 3 succeeded in 0 usecs
 drm: ib test on ring 5 succeeded
 drm: Radeon Display Connectors
 drm: Connector 0:
 drm:   HDMI-A-1
 drm:   HPD4
 drm:   DDC: 0x6460 0x6460 0x6464 0x6464 0x6468 0x6468 0x646c 0x646c
 drm:   Encoders:
 drm:     DFP1: INTERNAL_UNIPHY1
 drm: Connector 1:
 drm:   DVI-I-1
 drm:   HPD1
 drm:   DDC: 0x6430 0x6430 0x6434 0x6434 0x6438 0x6438 0x643c 0x643c
 drm:   Encoders:
 drm:     DFP2: INTERNAL_UNIPHY
 drm: Connector 2:
 drm:   VGA-1
 drm:   DDC: 0x6440 0x6440 0x6444 0x6444 0x6448 0x6448 0x644c 0x644c
 drm:   Encoders:
 drm:     CRT1: INTERNAL_KLDSCP_DAC1
 radeondrmkmsfb0 at radeon0
 radeon0: info: registered panic notifier
 radeondrmkmsfb0: framebuffer at 0xffff80004a416000, size 1920x1200, 
 depth 32, stride 7680
 wsdisplay0 at radeondrmkmsfb0 kbdmux 1: console (default, vt100 
 emulation), using wskbd0
 wsmux1: connecting to wsdisplay0
 ahcisata0 port 0: SERR 0x180000
 ahcisata0 port 0: SERR 0x180000
 ahcisata0 port 1: SERR 0x0
 ahcisata0 port 1: SERR 0x0
 wsdisplay0: screen 1 added (default, vt100 emulation)
 wsdisplay0: screen 2 added (default, vt100 emulation)
 wsdisplay0: screen 3 added (default, vt100 emulation)
 wsdisplay0: screen 4 added (default, vt100 emulation)
 ahcisata0 port 1: SERR 0x0
 ahcisata0 port 1: SERR 0x0
 hdafg0: unsol: tag=1
 hdafg0: unsol: display device hotplug
 hdafg0: bad ELD size (0/256)
 hdafg_dd_parse_info: datalen=256
   datalen 4294967280 sadcount 15 sizeof sad 3
 hdafg0: failed to parse ELD data
 
 detach USB serial:
 
 ucom0: detached
 uplcom0: detached
 uplcom0: at uhub3 port 2 (addr 3) disconnected
 usb_disconnect_port: no device
 
 -------------------
 (both usb devices plug in downstream of an external usb hub in this
 example)
 
 $ vmstat -u usbhist
 
 USB serial connect:
 
 1461677442.741826 uhub_intr#4@0: called!
 1461677442.741827 uhub_intr#4@0: uhub3
 1461677442.741828 uhub_intr#4@0: uhub3: explore pending 0
 1461677442.741829 uhub_intr#4@0: uhub3: pending/new ports [0] 0x4/0x4
 1461677442.741829 uhub_intr#4@0: uhub3: exploring ports [0] 0x4
 1461677442.741837 uhub_explore#10@0: called!
 1461677442.741838 uhub_explore#10@0: uhub1 dev=0xfffffe81076d5e10 addr=1 
 speed=3
 1461677442.741843 uhub_explore#10@0: uhub1 s/c=0/0
 1461677442.741846 uhub_explore#10@0: uhub1 port 1: s/c=503/0
 1461677442.741847 uhub_explore#11@0: called!
 1461677442.741848 uhub_explore#11@0: uhub3 dev=0xfffffe81076d58d0 addr=2 
 speed=3
 1461677442.742076 uhub_explore#11@0: uhub3 port 2: s/c=101/1
 1461677442.742077 uhub_explore#11@0: uhub3 status change port 2
 1461677442.742324 uhub_explore#11@0: unit 3 dev->speed=3 dev->depth=1
 1461677443.370645 uhub_explore#11@0: uhub3 port 2 after reset: s/c=103/10
 1461677443.370645 uhub_explore#11@0: uhub3 speed 2
 1461677445.429881 uhub_intr#5@0: called!
 1461677445.429882 uhub_intr#5@0: uhub3
 1461677445.429883 uhub_intr#5@0: uhub3: explore pending 1
 1461677445.429884 uhub_intr#5@0: uhub3: pending/new ports [0] 0x4/0x4
 1461677446.111898 uhub_explore#11@0: usbd_new_device failed, error 17
 1461677446.112149 uhub_explore#12@0: called!
 1461677446.112150 uhub_explore#12@0: uhub4 dev=0xfffffe81076d5710 addr=4 
 speed=2
 1461677446.112400 uhub_explore#12@0: uhub4 port 3: s/c=100/0
 1461677446.112405 uhub_explore#10@0: uhub1 port 2: s/c=500/0
 1461677446.112406 uhub_explore#13@0: called!
 1461677446.112407 uhub_explore#13@0: uhub1 dev=0xfffffe81076d5e10 addr=1 
 speed=3
 1461677446.112409 uhub_explore#13@0: uhub1 s/c=0/0
 1461677446.112412 uhub_explore#13@0: uhub1 port 1: s/c=503/0
 1461677446.112413 uhub_explore#14@0: called!
 1461677446.112414 uhub_explore#14@0: uhub3 dev=0xfffffe81076d58d0 addr=2 
 speed=3
 1461677446.112649 uhub_explore#14@0: uhub3 port 2: s/c=101/0
 1461677446.112650 uhub_explore#15@0: called!
 1461677446.112652 uhub_explore#15@0: uhub4 dev=0xfffffe81076d5710 addr=4 
 speed=2
 1461677446.112900 uhub_explore#15@0: uhub4 port 3: s/c=100/0
 1461677446.112903 uhub_explore#13@0: uhub1 port 2: s/c=500/0
 
 USB serial disconnect:
 
 1461677482.678697 uhub_intr#6@0: called!
 1461677482.678698 uhub_intr#6@0: uhub3
 1461677482.678699 uhub_intr#6@0: uhub3: explore pending 0
 1461677482.678699 uhub_intr#6@0: uhub3: pending/new ports [0] 0x4/0x4
 1461677482.678700 uhub_intr#6@0: uhub3: exploring ports [0] 0x4
 1461677482.678708 uhub_explore#16@0: called!
 1461677482.678709 uhub_explore#16@0: uhub1 dev=0xfffffe81076d5e10 addr=1 
 speed=3
 1461677482.678715 uhub_explore#16@0: uhub1 s/c=0/0
 1461677482.678717 uhub_explore#16@0: uhub1 port 1: s/c=503/0
 1461677482.678718 uhub_explore#17@0: called!
 1461677482.678719 uhub_explore#17@0: uhub3 dev=0xfffffe81076d58d0 addr=2 
 speed=3
 1461677482.678947 uhub_explore#17@0: uhub3 port 2: s/c=100/1
 1461677482.678947 uhub_explore#17@0: uhub3 status change port 2
 1461677482.679195 uhub_explore#17@0: uhub3 port 2 !CURRENT_CONNECT_STATUS
 1461677482.679447 uhub_explore#18@0: called!
 1461677482.679447 uhub_explore#18@0: uhub4 dev=0xfffffe81076d5710 addr=4 
 speed=2
 1461677482.679696 uhub_explore#18@0: uhub4 port 3: s/c=100/0
 1461677482.679699 uhub_explore#16@0: uhub1 port 2: s/c=500/0
 
 ----------------------
 
 Garmin connect (has two memory devices) :
 
 1461677509.880428 uhub_intr#7@0: called!
 1461677509.880429 uhub_intr#7@0: uhub4
 1461677509.880430 uhub_intr#7@0: uhub4: explore pending 0
 1461677509.880430 uhub_intr#7@0: uhub4: pending/new ports [0] 0x8/0x8
 1461677509.880431 uhub_intr#7@0: uhub4: exploring ports [0] 0x8
 1461677509.880440 uhub_explore#19@0: called!
 1461677509.880440 uhub_explore#19@0: uhub1 dev=0xfffffe81076d5e10 addr=1 
 speed=3
 1461677509.880446 uhub_explore#19@0: uhub1 s/c=0/0
 1461677509.880449 uhub_explore#19@0: uhub1 port 1: s/c=503/0
 1461677509.880450 uhub_explore#20@0: called!
 1461677509.880451 uhub_explore#20@0: uhub3 dev=0xfffffe81076d58d0 addr=2 
 speed=3
 1461677509.880678 uhub_explore#20@0: uhub3 port 2: s/c=100/0
 1461677509.880678 uhub_explore#21@0: called!
 1461677509.880679 uhub_explore#21@0: uhub4 dev=0xfffffe81076d5710 addr=4 
 speed=2
 1461677509.880927 uhub_explore#21@0: uhub4 port 3: s/c=101/1
 1461677509.880927 uhub_explore#21@0: uhub4 status change port 3
 1461677509.881176 uhub_explore#21@0: unit 4 dev->speed=2 dev->depth=2
 1461677510.510877 uhub_explore#21@0: uhub4 port 3 after reset: s/c=103/10
 1461677510.510878 uhub_explore#21@0: uhub4 speed 2
 1461677512.568463 uhub_intr#8@0: called!
 1461677512.568463 uhub_intr#8@0: uhub4
 1461677512.568464 uhub_intr#8@0: uhub4: explore pending 1
 1461677512.568465 uhub_intr#8@0: uhub4: pending/new ports [0] 0x8/0x8
 1461677513.252225 uhub_explore#21@0: usbd_new_device failed, error 17
 1461677513.252484 uhub_explore#19@0: uhub1 port 2: s/c=500/0
 1461677513.252485 uhub_explore#22@0: called!
 1461677513.252486 uhub_explore#22@0: uhub1 dev=0xfffffe81076d5e10 addr=1 
 speed=3
 1461677513.252488 uhub_explore#22@0: uhub1 s/c=0/0
 1461677513.252490 uhub_explore#22@0: uhub1 port 1: s/c=503/0
 1461677513.252492 uhub_explore#23@0: called!
 1461677513.252493 uhub_explore#23@0: uhub3 dev=0xfffffe81076d58d0 addr=2 
 speed=3
 1461677513.252729 uhub_explore#23@0: uhub3 port 2: s/c=100/0
 1461677513.252730 uhub_explore#24@0: called!
 1461677513.252731 uhub_explore#24@0: uhub4 dev=0xfffffe81076d5710 addr=4 
 speed=2
 1461677513.252976 uhub_explore#24@0: uhub4 port 3: s/c=101/0
 1461677513.252979 uhub_explore#22@0: uhub1 port 2: s/c=500/0
 
 Garmin disconnect:
 
 1461677555.705092 uhub_intr#9@0: called!
 1461677555.705092 uhub_intr#9@0: uhub4
 1461677555.705093 uhub_intr#9@0: uhub4: explore pending 0
 1461677555.705094 uhub_intr#9@0: uhub4: pending/new ports [0] 0x8/0x8
 1461677555.705094 uhub_intr#9@0: uhub4: exploring ports [0] 0x8
 1461677555.705101 uhub_explore#25@0: called!
 1461677555.705101 uhub_explore#25@0: uhub1 dev=0xfffffe81076d5e10 addr=1 
 speed=3
 1461677555.705106 uhub_explore#25@0: uhub1 s/c=0/0
 1461677555.705108 uhub_explore#25@0: uhub1 port 1: s/c=503/0
 1461677555.705109 uhub_explore#26@0: called!
 1461677555.705109 uhub_explore#26@0: uhub3 dev=0xfffffe81076d58d0 addr=2 
 speed=3
 1461677555.705343 uhub_explore#26@0: uhub3 port 2: s/c=100/0
 1461677555.705344 uhub_explore#27@0: called!
 1461677555.705344 uhub_explore#27@0: uhub4 dev=0xfffffe81076d5710 addr=4 
 speed=2
 1461677555.705593 uhub_explore#27@0: uhub4 port 3: s/c=100/1
 1461677555.705594 uhub_explore#27@0: uhub4 status change port 3
 1461677555.705841 uhub_explore#27@0: uhub4 port 3 !CURRENT_CONNECT_STATUS
 1461677555.706096 uhub_explore#25@0: uhub1 port 2: s/c=500/0