We're hitting the "connect-debounce failed" message on a remote system,
in 2.6.12-rc3 (no changes).
There are two root hubs. One has a simple USB I/O board attached, which
we access directly with usbfs. (It shows up as an HID, but hid.o is not
loaded.) The other has a bus-powered hub attached. The problem only
happens when that hub is attached (even with nothing on it). When it
happens, access to the I/O board times out; when the hub is removed, I/O
wakes back up and everything is happy again.
kmsg output attached, with timestamps in seconds since starting the log.
The problem happens around 4162.279980.
Is this likely to be caused by a poorly-shielded USB cable? Is there
anything I can do to isolate the hub further from the I/O device? It's
very annoying if the hub fails, but it's fatal if the I/O stops; it seems
like a failure on one hub is breaking the other. How can I diagnose this
further?
(Trial and error is a bit slow, since this is occurring in a live beta
in a remote arcade; I'm trying to have a different USB hub and cable tried,
but ideally, that hub failing--not too strange, since it's exposed to
players--wouldn't knock out the I/O anyway.)
--
Glenn Maynard
Bus 002 Device 002: ID 07c0:1584
Device Descriptor:
bLength 18
bDescriptorType 1
bcdUSB 1.10
bDeviceClass 0 Interface
bDeviceSubClass 0
bDeviceProtocol 0
bMaxPacketSize0 8
idVendor 0x07c0
idProduct 0x1584
bcdDevice 10.21
iManufacturer 1 Code Mercenaries
iProduct 2 ITG-IO
iSerial 3 00000140
bNumConfigurations 1
Configuration Descriptor:
bLength 9
bDescriptorType 2
wTotalLength 59
bNumInterfaces 2
bConfigurationValue 1
iConfiguration 4
bmAttributes 0xa0
Remote Wakeup
MaxPower 100mA
Interface Descriptor:
bLength 9
bDescriptorType 4
bInterfaceNumber 0
bAlternateSetting 0
bNumEndpoints 1
bInterfaceClass 3 Human Interface Devices
bInterfaceSubClass 0 No Subclass
bInterfaceProtocol 0 None
iInterface 5 Plain I/O
HID Device Descriptor:
bLength 9
bDescriptorType 33
bcdHID 1.10
bCountryCode 0
bNumDescriptors 1
bDescriptorType 34 Report
wDescriptorLength 33
cannot get report descriptor
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x81 EP 1 IN
bmAttributes 3
Transfer Type Interrupt
Synch Type none
wMaxPacketSize 4
bInterval 10
Interface Descriptor:
bLength 9
bDescriptorType 4
bInterfaceNumber 1
bAlternateSetting 0
bNumEndpoints 1
bInterfaceClass 3 Human Interface Devices
bInterfaceSubClass 0 No Subclass
bInterfaceProtocol 0 None
iInterface 6 Complex Interfaces
HID Device Descriptor:
bLength 9
bDescriptorType 33
bcdHID 1.10
bCountryCode 0
bNumDescriptors 1
bDescriptorType 34 Report
wDescriptorLength 173
Report Descriptor: (length is 173)
Item(Global): Usage Page, data= [ 0x01 ] 1
Generic Desktop Controls
Item(Local ): Usage, data= [ 0x00 ] 0
Undefined
Item(Main ): Collection, data= [ 0x01 ] 1
Application
Item(Global): Logical Minimum, data= [ 0x00 ] 0
Item(Global): Logical Maximum, data= [ 0x01 ] 1
Item(Global): Report Size, data= [ 0x01 ] 1
Item(Global): Usage Page, data= [ 0x08 ] 8
LEDs
Item(Global): Report ID, data= [ 0x01 ] 1
Item(Local ): Usage, data= [ 0x4b ] 75
Generic Indicator
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Output, data= [ 0x20 ] 32
Data Array Absolute No_Wrap Linear
No_Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Report ID, data= [ 0x02 ] 2
Item(Local ): Usage, data= [ 0x4b ] 75
Generic Indicator
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Output, data= [ 0x20 ] 32
Data Array Absolute No_Wrap Linear
No_Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Usage Page, data= [ 0x09 ] 9
Buttons
Item(Local ): Usage Minimum, data= [ 0x01 ] 1
Button 1 (Primary)
Item(Local ): Usage Maximum, data= [ 0x38 ] 56
(null)
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Input, data= [ 0x02 ] 2
Data Variable Absolute No_Wrap Linear
Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Usage Page, data= [ 0x08 ] 8
LEDs
Item(Global): Report ID, data= [ 0x03 ] 3
Item(Local ): Usage, data= [ 0x4b ] 75
Generic Indicator
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Output, data= [ 0x20 ] 32
Data Array Absolute No_Wrap Linear
No_Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Usage Page, data= [ 0x09 ] 9
Buttons
Item(Local ): Usage Minimum, data= [ 0x01 ] 1
Button 1 (Primary)
Item(Local ): Usage Maximum, data= [ 0x38 ] 56
(null)
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Input, data= [ 0x02 ] 2
Data Variable Absolute No_Wrap Linear
Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Usage Page, data= [ 0x08 ] 8
LEDs
Item(Global): Report ID, data= [ 0x04 ] 4
Item(Local ): Usage, data= [ 0x4b ] 75
Generic Indicator
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Output, data= [ 0x20 ] 32
Data Array Absolute No_Wrap Linear
No_Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Report ID, data= [ 0x05 ] 5
Item(Local ): Usage, data= [ 0x4b ] 75
Generic Indicator
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Output, data= [ 0x20 ] 32
Data Array Absolute No_Wrap Linear
No_Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Report ID, data= [ 0x06 ] 6
Item(Local ): Usage, data= [ 0x4b ] 75
Generic Indicator
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Output, data= [ 0x20 ] 32
Data Array Absolute No_Wrap Linear
No_Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Usage Page, data= [ 0x09 ] 9
Buttons
Item(Local ): Usage Minimum, data= [ 0x01 ] 1
Button 1 (Primary)
Item(Local ): Usage Maximum, data= [ 0x38 ] 56
(null)
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Input, data= [ 0x02 ] 2
Data Variable Absolute No_Wrap Linear
Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Usage Page, data= [ 0x08 ] 8
LEDs
Item(Global): Report ID, data= [ 0x14 ] 20
Item(Local ): Usage, data= [ 0x4b ] 75
Generic Indicator
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Output, data= [ 0x20 ] 32
Data Array Absolute No_Wrap Linear
No_Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Report ID, data= [ 0x15 ] 21
Item(Local ): Usage, data= [ 0x4b ] 75
Generic Indicator
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Output, data= [ 0x20 ] 32
Data Array Absolute No_Wrap Linear
No_Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Usage Page, data= [ 0x08 ] 8
LEDs
Item(Global): Report ID, data= [ 0x18 ] 24
Item(Local ): Usage, data= [ 0x4b ] 75
Generic Indicator
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Output, data= [ 0x20 ] 32
Data Array Absolute No_Wrap Linear
No_Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Report ID, data= [ 0x19 ] 25
Item(Local ): Usage, data= [ 0x4b ] 75
Generic Indicator
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Output, data= [ 0x20 ] 32
Data Array Absolute No_Wrap Linear
No_Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Usage Page, data= [ 0x10 ] 16
Unicode
Item(Local ): Usage Minimum, data= [ 0x01 ] 1
(null)
Item(Local ): Usage Maximum, data= [ 0x38 ] 56
(null)
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Input, data= [ 0x02 ] 2
Data Variable Absolute No_Wrap Linear
Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Report ID, data= [ 0x1a ] 26
Item(Local ): Usage Minimum, data= [ 0x01 ] 1
(null)
Item(Local ): Usage Maximum, data= [ 0x38 ] 56
(null)
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Input, data= [ 0x02 ] 2
Data Variable Absolute No_Wrap Linear
Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Usage Page, data= [ 0x08 ] 8
LEDs
Item(Global): Report ID, data= [ 0xff ] 255
Item(Local ): Usage, data= [ 0x4b ] 75
Generic Indicator
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Output, data= [ 0x20 ] 32
Data Array Absolute No_Wrap Linear
No_Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Global): Usage Page, data= [ 0xff ] 255
(null)
Item(Local ): Usage Minimum, data= [ 0x01 ] 1
(null)
Item(Local ): Usage Maximum, data= [ 0x38 ] 56
(null)
Item(Global): Report Count, data= [ 0x38 ] 56
Item(Main ): Input, data= [ 0x02 ] 2
Data Variable Absolute No_Wrap Linear
Preferred_State No_Null_Position Non_Volatile
Bitfield
Item(Main ): End Collection, data=none
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x82 EP 2 IN
bmAttributes 3
Transfer Type Interrupt
Synch Type none
wMaxPacketSize 8
bInterval 10
Language IDs: (length=4)
0409 English(US)
Bus 002 Device 001: ID 0000:0000 Virtual Hub
Device Descriptor:
bLength 18
bDescriptorType 1
bcdUSB 1.10
bDeviceClass 9 Hub
bDeviceSubClass 0
bDeviceProtocol 0
bMaxPacketSize0 8
idVendor 0x0000 Virtual
idProduct 0x0000 Hub
bcdDevice 2.06
iManufacturer 3 Linux 2.6.12-rc3 ohci_hcd
iProduct 2 nVidia Corporation nForce2 USB Controller (#2)
iSerial 1 0000:00:02.1
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
bInterfaceProtocol 0
iInterface 0
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x81 EP 1 IN
bmAttributes 3
Transfer Type Interrupt
Synch Type none
wMaxPacketSize 2
bInterval 255
Language IDs: (length=4)
0409 English(US)
Bus 001 Device 004: ID 0409:0059 NEC Systems
Language IDs: none (cannot get min. string descriptor; got len=-1,
error=32:Broken pipe)
Device Descriptor:
bLength 18
bDescriptorType 1
bcdUSB 2.00
bDeviceClass 9 Hub
bDeviceSubClass 0
bDeviceProtocol 0
bMaxPacketSize0 64
idVendor 0x0409 NEC Systems
idProduct 0x0059
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
bInterfaceProtocol 0
iInterface 0
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x81 EP 1 IN
bmAttributes 3
Transfer Type Interrupt
Synch Type none
wMaxPacketSize 1
bInterval 255
Language IDs: none (cannot get min. string descriptor; got len=-1,
error=32:Broken pipe)
Bus 001 Device 001: ID 0000:0000 Virtual Hub
Device Descriptor:
bLength 18
bDescriptorType 1
bcdUSB 1.10
bDeviceClass 9 Hub
bDeviceSubClass 0
bDeviceProtocol 0
bMaxPacketSize0 8
idVendor 0x0000 Virtual
idProduct 0x0000 Hub
bcdDevice 2.06
iManufacturer 3 Linux 2.6.12-rc3 ohci_hcd
iProduct 2 nVidia Corporation nForce2 USB Controller
iSerial 1 0000:00:02.0
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
bInterfaceProtocol 0
iInterface 0
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x81 EP 1 IN
bmAttributes 3
Transfer Type Interrupt
Synch Type none
wMaxPacketSize 2
bInterval 255
Language IDs: (length=4)
0409 English(US)
3487.340510: <7>ohci_hcd: 2004 Nov 08 USB 1.1 'Open' Host Controller (OHCI)
Driver (PCI)
3487.340718: <7>ohci_hcd: block sizes: ed 64 td 64
3487.340842: <7>PCI: Setting latency timer of device 0000:00:02.0 to 64
3487.340919: <6>ohci_hcd 0000:00:02.0: nVidia Corporation nForce2 USB Controller
3487.341273: <6>ohci_hcd 0000:00:02.0: new USB bus registered, assigned bus
number 1
3487.341373: <6>ohci_hcd 0000:00:02.0: irq 11, io mem 0xee004000
3487.341440: <7>ohci_hcd 0000:00:02.0: resetting from state 'reset', control =
0x600
3487.394363: <7>ohci_hcd 0000:00:02.0: OHCI controller state
3487.394435: <7>ohci_hcd 0000:00:02.0: OHCI 1.0, NO legacy support registers
3487.394519: <7>ohci_hcd 0000:00:02.0: control 0x683 RWE RWC HCFS=operational
CBSR=3
3487.394603: <7>ohci_hcd 0000:00:02.0: cmdstatus 0x00000 SOC=0
3487.394666: <7>ohci_hcd 0000:00:02.0: intrstatus 0x00000004 SF
3487.394728: <7>ohci_hcd 0000:00:02.0: intrenable 0x8000000a MIE RD WDH
3487.394794: <7>ohci_hcd 0000:00:02.0: fminterval a7782edf
3487.394853: <7>ohci_hcd 0000:00:02.0: hcca frame #0003
3487.394915: <7>ohci_hcd 0000:00:02.0: roothub.a 01000203 POTPGT=1 NPS NDP=3
3487.394978: <7>ohci_hcd 0000:00:02.0: roothub.b 00000000 PPCM=0000 DR=0000
3487.395041: <7>ohci_hcd 0000:00:02.0: roothub.status 00008000 DRWE
3487.395117: <7>ohci_hcd 0000:00:02.0: roothub.portstatus [0] 0x00000100 PPS
3487.395180: <7>ohci_hcd 0000:00:02.0: roothub.portstatus [1] 0x00000100 PPS
3487.395243: <7>ohci_hcd 0000:00:02.0: roothub.portstatus [2] 0x00000100 PPS
3487.395307: <7>ohci_hcd 0000:00:02.0: supports USB remote wakeup
3487.395394: <7>usb usb1: default language 0x0409
3487.395462: <7>usb usb1: new device strings: Mfr=3, Product=2, SerialNumber=1
3487.395526: <6>usb usb1: Product: nVidia Corporation nForce2 USB Controller
3487.395597: <6>usb usb1: Manufacturer: Linux 2.6.12-rc3 ohci_hcd
3487.395657: <6>usb usb1: SerialNumber: 0000:00:02.0
3487.395730: <7>usb usb1: hotplug
3487.395823: <7>usb usb1: adding 1-0:1.0 (config #1, interface 0)
3487.395890: <7>hub 1-0:1.0: usb_probe_interface
3487.395981: <7>hub 1-0:1.0: usb_probe_interface - got id
3487.396040: <6>hub 1-0:1.0: USB hub found
3487.396122: <6>hub 1-0:1.0: 3 ports detected
3487.396182: <7>hub 1-0:1.0: standalone hub
3487.396239: <7>hub 1-0:1.0: no power switching (usb 1.0)
3487.396298: <7>hub 1-0:1.0: global over-current protection
3487.396370: <7>hub 1-0:1.0: power on to power good time: 2ms
3487.396434: <7>hub 1-0:1.0: local power source is good
3487.396493: <7>hub 1-0:1.0: no over-current condition exists
3487.399370: <7>hub 1-0:1.0: state 5 ports 3 chg 0000 evt 0000
3487.399434: <7>usb 1-0:1.0: hotplug
3487.399532: <7>ohci_hcd 0000:00:02.0: created debug files
3487.399624: <7>PCI: Setting latency timer of device 0000:00:02.1 to 64
3487.399689: <6>ohci_hcd 0000:00:02.1: nVidia Corporation nForce2 USB
Controller (#2)
3487.399795: <6>ohci_hcd 0000:00:02.1: new USB bus registered, assigned bus
number 2
3487.399863: <6>ohci_hcd 0000:00:02.1: irq 3, io mem 0xee005000
3487.399925: <7>ohci_hcd 0000:00:02.1: resetting from state 'reset', control =
0x600
3487.452353: <7>ohci_hcd 0000:00:02.1: OHCI controller state
3487.452423: <7>ohci_hcd 0000:00:02.1: OHCI 1.0, NO legacy support registers
3487.452495: <7>ohci_hcd 0000:00:02.1: control 0x683 RWE RWC HCFS=operational
CBSR=3
3487.452566: <7>ohci_hcd 0000:00:02.1: cmdstatus 0x00000 SOC=0
3487.452649: <7>ohci_hcd 0000:00:02.1: intrstatus 0x00000044 RHSC SF
3487.452714: <7>ohci_hcd 0000:00:02.1: intrenable 0x8000000a MIE RD WDH
3487.452779: <7>ohci_hcd 0000:00:02.1: fminterval a7782edf
3487.452838: <7>ohci_hcd 0000:00:02.1: hcca frame #0003
3487.452899: <7>ohci_hcd 0000:00:02.1: roothub.a 01000203 POTPGT=1 NPS NDP=3
3487.452961: <7>ohci_hcd 0000:00:02.1: roothub.b 00000000 PPCM=0000 DR=0000
3487.453024: <7>ohci_hcd 0000:00:02.1: roothub.status 00008000 DRWE
3487.453087: <7>ohci_hcd 0000:00:02.1: roothub.portstatus [0] 0x00010301 CSC
LSDA PPS CCS
3487.453161: <7>ohci_hcd 0000:00:02.1: roothub.portstatus [1] 0x00000100 PPS
3487.453224: <7>ohci_hcd 0000:00:02.1: roothub.portstatus [2] 0x00000100 PPS
3487.453286: <7>ohci_hcd 0000:00:02.1: supports USB remote wakeup
3487.453369: <7>usb usb2: default language 0x0409
3487.453435: <7>usb usb2: new device strings: Mfr=3, Product=2, SerialNumber=1
3487.453498: <6>usb usb2: Product: nVidia Corporation nForce2 USB Controller
(#2)
3487.453561: <6>usb usb2: Manufacturer: Linux 2.6.12-rc3 ohci_hcd
3487.453621: <6>usb usb2: SerialNumber: 0000:00:02.1
3487.453697: <7>usb usb2: hotplug
3487.453785: <7>usb usb2: adding 2-0:1.0 (config #1, interface 0)
3487.453850: <7>hub 2-0:1.0: usb_probe_interface
3487.453908: <7>hub 2-0:1.0: usb_probe_interface - got id
3487.453966: <6>hub 2-0:1.0: USB hub found
3487.454033: <6>hub 2-0:1.0: 3 ports detected
3487.454092: <7>hub 2-0:1.0: standalone hub
3487.454160: <7>hub 2-0:1.0: no power switching (usb 1.0)
3487.454219: <7>hub 2-0:1.0: global over-current protection
3487.454277: <7>hub 2-0:1.0: power on to power good time: 2ms
3487.454353: <7>hub 2-0:1.0: local power source is good
3487.454412: <7>hub 2-0:1.0: no over-current condition exists
3487.457359: <7>hub 2-0:1.0: state 5 ports 3 chg 0000 evt 0000
3487.457423: <7>usb 2-0:1.0: hotplug
3487.457555: <7>ohci_hcd 0000:00:02.1: GetStatus roothub.portstatus [0] =
0x00010301 CSC LSDA PPS CCS
3487.457578: <7>hub 2-0:1.0: port 1, status 0301, change 0001, 1.5 Mb/s
3487.457639: <7>ohci_hcd 0000:00:02.1: created debug files
3487.561353: <7>hub 2-0:1.0: debounce: port 1: total 100ms stable 100ms status
0x301
3487.623337: <7>ohci_hcd 0000:00:02.1: GetStatus roothub.portstatus [0] =
0x00100303 PRSC LSDA PPS PES CCS
3487.634348: <6>usb 2-1: new low speed USB device using ohci_hcd and address 2
3487.697326: <7>ohci_hcd 0000:00:02.1: GetStatus roothub.portstatus [0] =
0x00100303 PRSC LSDA PPS PES CCS
3487.730438: <7>usb 2-1: skipped 1 descriptor after interface
3487.730461: <7>usb 2-1: skipped 1 descriptor after interface
3487.733431: <7>usb 2-1: default language 0x0409
3487.743488: <7>usb 2-1: new device strings: Mfr=1, Product=2, SerialNumber=3
3487.743511: <6>usb 2-1: Product: ITG-IO
3487.743527: <6>usb 2-1: Manufacturer: Code Mercenaries
3487.743541: <6>usb 2-1: SerialNumber: 00000140
3487.743554: <7>usb 2-1: hotplug
3487.749468: <7>usb 2-1: adding 2-1:1.0 (config #1, interface 0)
3487.749490: <7>usb 2-1:1.0: hotplug
3487.752469: <7>usb 2-1: adding 2-1:1.1 (config #1, interface 1)
3487.752491: <7>usb 2-1:1.1: hotplug
3487.756449: <7>hub 2-0:1.0: state 5 ports 3 chg 0000 evt 0002
3498.331479: <4>ub: sizeof ub_scsi_cmd 64 ub_dev 2472
3498.331733: <6>usbcore: registered new driver ub
3561.638133: <7>hub 1-0:1.0: state 5 ports 3 chg 0000 evt 0008
3561.638209: <7>ohci_hcd 0000:00:02.0: GetStatus roothub.portstatus [2] =
0x00010101 CSC PPS CCS
3561.638226: <7>hub 1-0:1.0: port 3, status 0101, change 0001, 12 Mb/s
3561.743105: <7>hub 1-0:1.0: debounce: port 3: total 100ms stable 100ms status
0x101
3561.805090: <7>ohci_hcd 0000:00:02.0: GetStatus roothub.portstatus [2] =
0x00100103 PRSC PPS PES CCS
3561.816099: <6>usb 1-3: new full speed USB device using ohci_hcd and address 2
3561.879085: <7>ohci_hcd 0000:00:02.0: GetStatus roothub.portstatus [2] =
0x00100103 PRSC PPS PES CCS
3561.910601: <7>usb 1-3: new device strings: Mfr=0, Product=0, SerialNumber=0
3561.910677: <7>usb 1-3: hotplug
3561.914491: <7>usb 1-3: adding 1-3:1.0 (config #1, interface 0)
3561.914560: <7>hub 1-3:1.0: usb_probe_interface
3561.914576: <7>hub 1-3:1.0: usb_probe_interface - got id
3561.914589: <6>hub 1-3:1.0: USB hub found
3561.916505: <6>hub 1-3:1.0: 4 ports detected
3561.916528: <7>hub 1-3:1.0: standalone hub
3561.916542: <7>hub 1-3:1.0: individual port power switching
3561.916555: <7>hub 1-3:1.0: individual port over-current protection
3561.916569: <7>hub 1-3:1.0: TT requires at most 16 FS bit times
3561.916583: <7>hub 1-3:1.0: Port indicators are supported
3561.916596: <7>hub 1-3:1.0: power on to power good time: 100ms
3561.922489: <7>hub 1-3:1.0: local power source is good
3561.922512: <7>hub 1-3:1.0: enabling power on all ports
3562.036162: <7>usb 1-3:1.0: hotplug
3562.036237: <7>hub 1-0:1.0: state 5 ports 3 chg 0000 evt 0008
3562.036253: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3562.541625: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3562.546857: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3563.604875: <7>hub 1-3:1.0: debounce: port 1: total 875ms stable 100ms status
0x100
3563.610801: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3563.728834: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3563.728914: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3566.542342: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
3566.547349: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3567.611529: <7>hub 1-3:1.0: debounce: port 1: total 875ms stable 100ms status
0x100
3567.617515: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3567.732539: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3567.732608: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3570.511028: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3570.516034: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3571.601215: <7>hub 1-3:1.0: debounce: port 1: total 900ms stable 100ms status
0x100
3571.607191: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3571.722239: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3571.722308: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3574.512027: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
3574.516700: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3576.334066: <7>hub 1-3:1.0: debounce: port 1: total 1500ms stable 0ms status
0x100
3576.334134: <3>hub 1-3:1.0: connect-debounce failed, port 1 disabled
3576.343055: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3577.895335: <7>hub 1-3:1.0: debounce: port 4: total 1350ms stable 100ms status
0x100
3577.895415: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
3577.901310: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3578.190370: <7>hub 1-3:1.0: debounce: port 1: total 250ms stable 100ms status
0x100
3578.196626: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3578.313177: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3578.313246: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0010
3578.384410: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3578.389379: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3578.596284: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
3578.601418: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3578.718481: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3578.718551: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3578.768490: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3578.773450: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3578.953507: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3578.959481: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3579.074529: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3579.074597: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3579.120538: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
3579.125508: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3579.303565: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3579.309542: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3579.424591: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3579.424660: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3579.472600: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3579.480058: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3579.689634: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
3579.696160: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3579.812748: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3579.812822: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3579.856665: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
3579.863308: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3580.070703: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
3580.076677: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3580.191725: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3580.191794: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3580.240735: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
3580.246278: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3580.424762: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3580.430765: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3580.548788: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3580.548861: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3580.592796: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3580.597794: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3580.807829: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
3580.814830: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3580.930856: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3580.930953: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3580.976861: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3580.981858: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3581.162910: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3581.167870: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3581.284918: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3581.284986: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3581.363287: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3581.367916: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3581.546449: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3581.550939: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3581.666985: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3581.667065: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3581.713160: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3581.717963: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3581.931088: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
3581.937253: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3582.053052: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3582.053120: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3582.097092: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3582.102031: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3582.283092: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3582.289067: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3582.407116: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3582.407183: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3582.449121: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3582.454095: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3582.631153: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3582.637127: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3582.752176: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3582.752242: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3582.833190: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3582.838161: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3583.017228: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3583.023195: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3583.140244: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3583.140310: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3583.185252: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0010
3583.190223: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3583.397294: <7>hub 1-3:1.0: debounce: port 4: total 175ms stable 100ms status
0x100
3583.397362: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
3583.403263: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3583.518307: <7>hub 1-3:1.0: debounce: port 1: total 100ms stable 100ms status
0x100
3583.569320: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3583.574289: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3583.935381: <7>hub 1-3:1.0: debounce: port 1: total 300ms stable 100ms status
0x100
3583.941355: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3584.063854: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3584.063922: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3584.305454: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3584.316656: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3584.500093: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3584.505455: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3584.625506: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3584.625599: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3584.689519: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3584.701703: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3584.881568: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3584.887521: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3585.013107: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3585.013175: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3585.425650: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3585.432775: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3585.618677: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3585.624654: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3585.742703: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3585.742779: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3585.781431: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
3585.785680: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3585.974737: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3585.981745: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3586.096888: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3586.096956: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3586.165925: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3586.169749: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3586.540837: <7>hub 1-3:1.0: debounce: port 1: total 300ms stable 100ms status
0x100
3586.547839: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3586.665896: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3586.665967: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0010
3586.705874: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3586.713401: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3586.931915: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
3586.937881: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3587.058936: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3587.059018: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3587.281987: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3587.286942: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3587.466008: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3587.471975: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3587.598145: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3587.598221: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3587.634035: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3587.639007: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3587.831775: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3587.835041: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3587.957105: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3587.957187: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3588.370164: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3588.375136: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3588.598488: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
3588.602176: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3588.722228: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3588.722306: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3588.754230: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3588.764011: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3588.952262: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3588.958315: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3589.081297: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3589.081376: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
3589.106294: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3589.114146: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3589.518411: <7>hub 1-3:1.0: debounce: port 1: total 325ms stable 100ms status
0x100
3589.524339: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3589.640389: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3589.640466: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0010
3589.682403: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
3589.687364: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
3589.878822: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
3589.883402: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
3590.001449: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
3590.001522: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
...
4103.380659: <7>hub 1-3:1.0: debounce: port 1: total 850ms stable 100ms status
0x100
4103.386627: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4103.501675: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4103.501756: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4144.115812: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0010
4144.120805: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4145.875119: <7>hub 1-3:1.0: debounce: port 4: total 1500ms stable 0ms status
0x100
4145.875189: <3>hub 1-3:1.0: connect-debounce failed, port 4 disabled
4145.878109: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4145.884110: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4147.592435: <7>hub 1-3:1.0: debounce: port 1: total 1425ms stable 100ms status
0x100
4147.598410: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4147.713444: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4147.713513: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4149.364768: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4149.369814: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4149.547762: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4149.553770: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4149.669624: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4149.669712: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4149.812815: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4149.818003: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4149.995841: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4150.002938: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4150.116866: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4150.116935: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4150.260893: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4150.265864: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4150.444037: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4150.449894: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4150.564946: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4150.565016: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4150.676967: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4150.681936: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4150.858994: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4150.864968: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4150.980017: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4150.980087: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4151.125044: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4151.130018: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4151.308074: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4151.314047: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4151.430097: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4151.430167: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4151.541118: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4151.546087: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4151.755153: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
4151.761126: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4151.875175: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4151.875244: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4151.989197: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4151.994171: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4152.198229: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
4152.204230: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4152.319109: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4152.319287: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4152.437722: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4152.442256: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4152.624305: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4152.630720: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4152.744329: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4152.744396: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0010
4152.885776: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4152.890326: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4153.064382: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4153.070383: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4153.187407: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4153.187477: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4153.302369: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4153.306397: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4153.515460: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
4153.521459: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4153.637486: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4153.637554: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4153.749506: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4153.754499: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4153.931538: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4153.931606: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4154.197583: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4154.202603: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4154.380612: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4154.386622: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4154.596660: <7>hub 1-3:1.0: debounce: port 4: total 175ms stable 100ms status
0x100
4154.596745: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4154.607295: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4154.814688: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
4154.825016: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4155.002555: <7>hub 1-3:1.0: debounce: port 4: total 150ms stable 100ms status
0x100
4155.002782: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4155.007696: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4155.123744: <7>hub 1-3:1.0: debounce: port 1: total 100ms stable 100ms status
0x100
4155.290154: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4155.292747: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4155.688849: <7>hub 1-3:1.0: debounce: port 1: total 325ms stable 100ms status
0x100
4155.694824: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4155.907885: <7>hub 1-3:1.0: debounce: port 4: total 175ms stable 100ms status
0x100
4155.907952: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4155.913876: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4156.030903: <7>hub 1-3:1.0: debounce: port 1: total 100ms stable 100ms status
0x100
4156.149932: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4156.156922: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4156.782035: <7>hub 1-3:1.0: debounce: port 1: total 525ms stable 100ms status
0x100
4156.789209: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4156.904059: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4156.904127: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4157.014084: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0010
4157.021911: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4157.207115: <7>hub 1-3:1.0: debounce: port 4: total 150ms stable 100ms status
0x100
4157.207197: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4157.213082: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4157.331132: <7>hub 1-3:1.0: debounce: port 1: total 100ms stable 100ms status
0x100
4157.462161: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0010
4157.468958: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4157.674201: <7>hub 1-3:1.0: debounce: port 4: total 175ms stable 100ms status
0x100
4157.674282: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4157.680167: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4157.797212: <7>hub 1-3:1.0: debounce: port 1: total 100ms stable 100ms status
0x100
4157.910240: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4157.915217: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4158.103265: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4158.109243: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4158.314313: <7>hub 1-3:1.0: debounce: port 4: total 175ms stable 100ms status
0x100
4158.314405: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4158.320314: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4158.536887: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
4158.541331: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4158.659369: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4158.659441: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4158.806395: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4158.812793: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4159.409498: <7>hub 1-3:1.0: debounce: port 1: total 500ms stable 100ms status
0x100
4159.415471: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4159.531522: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4159.531602: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0010
4159.670555: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4159.675515: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4159.863574: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4159.869575: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4159.989758: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4159.989916: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4160.088818: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4160.093589: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4160.305658: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
4160.311628: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4160.430681: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4160.430759: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0010
4160.535130: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4160.539668: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4160.720647: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4160.724719: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4160.902763: <7>hub 1-3:1.0: debounce: port 4: total 150ms stable 100ms status
0x100
4160.902832: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4160.908732: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4161.180808: <7>hub 1-3:1.0: debounce: port 1: total 225ms stable 100ms status
0x100
4161.188012: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4161.304842: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4161.304934: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0000
4161.430854: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4161.436853: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4161.616168: <7>hub 1-3:1.0: debounce: port 1: total 150ms stable 100ms status
0x100
4161.624639: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4161.743913: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4161.743994: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0010
4161.846932: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4161.855927: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4162.069964: <7>hub 1-3:1.0: debounce: port 1: total 175ms stable 100ms status
0x100
4162.075958: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4162.191989: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4162.192064: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0010
4162.279980: <7>ohci_hcd 0000:00:02.1: urb dd312ae0 path 1 ep0in 5ec20000 cc 5
--> status -110
4162.280193: <7>usb 2-1: usbfs: USBDEVFS_CONTROL failed cmd itg rqt 161 rq 1
len 4 ret -110
4162.282975: <7>ohci_hcd 0000:00:02.1: urb dd312ae0 path 1 ep0out 5ec20000 cc 5
--> status -110
4162.295014: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4162.304904: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4162.498772: <7>ohci_hcd 0000:00:02.1: GetStatus roothub.portstatus [0] =
0x00120303 PRSC PESC LSDA PPS PES CCS
4162.510151: <6>usb 2-1: reset low speed USB device using ohci_hcd and address 2
4162.723761: <7>ohci_hcd 0000:00:02.1: GetStatus roothub.portstatus [0] =
0x00120303 PRSC PESC LSDA PPS PES CCS
4162.767943: <7>usb 2-1: manual set_interface for iface 0, alt 0
4162.772039: <7>usb 2-1: manual set_interface for iface 1, alt 0
4162.779161: <7>usb 2-1: manual set_interface for iface 0, alt 0
4164.220819: <7>hub 1-3:1.0: debounce: port 1: total 1500ms stable 0ms status
0x100
4164.220888: <3>hub 1-3:1.0: connect-debounce failed, port 1 disabled
4164.226321: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4166.030662: <7>hub 1-3:1.0: debounce: port 4: total 1500ms stable 0ms status
0x100
4166.030738: <3>hub 1-3:1.0: connect-debounce failed, port 4 disabled
4166.035887: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0012
4166.040638: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4167.937997: <7>hub 1-3:1.0: debounce: port 1: total 1500ms stable 0ms status
0x100
4167.938063: <3>hub 1-3:1.0: connect-debounce failed, port 1 disabled
4167.947226: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4168.469116: <7>hub 1-3:1.0: debounce: port 4: total 425ms stable 100ms status
0x100
4168.469184: <7>hub 2-0:1.0: state 5 ports 3 chg 0000 evt 0002
4168.469199: <7>ohci_hcd 0000:00:02.1: GetStatus roothub.portstatus [0] =
0x00020303 PESC LSDA PPS PES CCS
4168.469213: <7>hub 2-0:1.0: port 1 enable change, status 00000303
4168.469227: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4168.475258: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4170.358423: <7>hub 1-3:1.0: debounce: port 1: total 1500ms stable 0ms status
0x100
4170.358504: <3>hub 1-3:1.0: connect-debounce failed, port 1 disabled
4170.369599: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4172.186746: <7>hub 1-3:1.0: debounce: port 4: total 1500ms stable 25ms status
0x100
4172.186815: <3>hub 1-3:1.0: connect-debounce failed, port 4 disabled
4172.189721: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4172.195716: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4174.024109: <7>hub 1-3:1.0: debounce: port 1: total 1500ms stable 0ms status
0x100
4178.772071: <3>hub 1-3:1.0: connect-debounce failed, port 1 disabled
4178.772153: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4178.772170: <7>hub 1-3:1.0: debounce: port 4: total 100ms stable 100ms status
0x100
4178.772185: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4178.772199: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4178.772212: <7>hub 1-3:1.0: debounce: port 1: total 1500ms stable 0ms status
0x100
4178.772226: <3>hub 1-3:1.0: connect-debounce failed, port 1 disabled
4178.772252: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4178.772267: <7>hub 1-3:1.0: debounce: port 4: total 450ms stable 100ms status
0x100
4178.772281: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4178.772294: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4178.772308: <7>hub 1-3:1.0: debounce: port 1: total 1500ms stable 0ms status
0x100
4178.772321: <3>hub 1-3:1.0: connect-debounce failed, port 1 disabled
4178.772335: <7>hub 1-3:1.0: port 4, status 0100, change 0001, 12 Mb/s
4180.141147: <7>hub 1-3:1.0: debounce: port 4: total 1500ms stable 50ms status
0x100
4180.141216: <3>hub 1-3:1.0: connect-debounce failed, port 4 disabled
4180.144119: <7>hub 1-3:1.0: state 5 ports 4 chg 0000 evt 0002
4180.150142: <7>hub 1-3:1.0: port 1, status 0100, change 0001, 12 Mb/s
4181.962461: <7>hub 1-3:1.0: debounce: port 1: total 1500ms stable 0ms status
0x100
