Hello,
I am struggling with getting an usb gadget to enumerate and a couple of days ago, I received Greg's advice (by irc) of checking with the USBCV tests before trying out the enumeration on linux.
Since then:
1. I have tried and found that the USBCV tests cannot be used on non-enumerating usb devices.
2. USBTrace, USBlyzer and Snoopypro do not seem to be working on non-enumerating devices, even though they claim the contrary. Or, maybe they work after the device does something correctly, I guess.
So, using the windows software tests/sniffers has been a dead-end for me till now.
Of all the above, the linux hosts' usbmon has been the most promising.
The usbmon 0u tells me that the device is receiving the get_descriptor_from_device setup control packet and is also receiving some data back from the device, which it considers as corrupt (as evidenced by the below entry in the log, prefixed with '='):
dc4c7e20 211038484 S Ci:1:001:0 s a3 00 0000 0001 0004 4 <
dc4c7e20 211038523 C Ci:1:001:0 0 4 = 03030000
dc4c7e20 211094486 S Co:1:001:0 s 23 01 0014 0001 0000 0
dc4c7e20 211094508 C Co:1:001:0 0 0
=============dc4c7e20 211094564 S Ci:1:000:0 s 80 06 0100 0000 0040 64 <
=============dc4c7e20 211099733 C Ci:1:000:0 -84 8 = 12010002 00000008
dc4c7e20 211099791 S Co:1:001:0 s 23 03 0004 0001 0000 0
dc4c7e20 211099803 C Co:1:001:0 0 0
dc4c7e20 211154482 S Ci:1:001:0 s a3 00 0000 0001 0004 4 <
dc4c7e20 211154523 C Ci:1:001:0 0 4 = 03030000
The kernel ( CONFIG_USB_DEBUG) enabled identifies the data packet as corrupt due to "babble", as evidenced in the /var/log/debug. (I added the output line prefixed by>>, by updating the uhci 'c' file, to understand what the status code 50000 meant):
>>>>>>>>>>>>>>>>>Jul 12 12:38:52 backup-test kernel: [ 983.400706] usb 1-1: uhci_map_status_custom: failed with status 500000 -- TD_CTRL_BABBLE <<<<<<<<<<<<<<<<<<
Jul 12 12:38:52 backup-test kernel: [ 983.400729] usb 1-1: uhci_result_common: failed with status 500000
Jul 12 12:38:52 backup-test kernel: [ 983.400769] [dfc4d420] CTL QH link (00000001) element (1fe5b090)
I am attaching both files to this email.
I built my own logic analyzer to see the data passing on the USB D+ and D- lines and it tells me that my device is sending the correct data through. I believe that it has to be something very simple as sending an extra/defective bit after eop as the "12010002 00000008" is exactly what I wanted to send as the first 8 bytes of the device descriptor.
Do you have any thoughts/ideas on this, please?
I have tried everything and I believe that my redemption lies at poking/debugging further on the linux host to figure out why it considers the device as a babbling device....
Thanks and Very very sorry for the bother,
Siva
_________________________________________________________________
Insert movie times and more without leaving Hotmail®.
http://windowslive.com/Tutorial/Hotmail/QuickAdd?ocid=TXT_TAGLM_WL_HM_Tutorial_QuickAdd_062009
cat 0u
dc7b15c0 210854472 S Ci:1:001:0 s a3 00 0000 0001 0004 4 <
dc7b15c0 210854515 C Ci:1:001:0 0 4 = 01030100
dc7b15c0 210854525 S Ci:1:001:0 s a3 00 0000 0002 0004 4 <
dc7b15c0 210854531 C Ci:1:001:0 0 4 = 00010000
dfe8e360 210854536 S Ii:1:001:1 -115:128 2 <
dc7b15c0 210854577 S Ci:1:001:0 s a3 00 0000 0001 0004 4 <
dc7b15c0 210854593 C Ci:1:001:0 0 4 = 01030100
dc7b15c0 210854600 S Co:1:001:0 s 23 01 0010 0001 0000 0
dc7b15c0 210854605 C Co:1:001:0 0 0
dc7b15c0 210854622 S Ci:1:001:0 s a3 00 0000 0001 0004 4 <
dc7b15c0 210854628 C Ci:1:001:0 0 4 = 01030000
dc7b15c0 210886511 S Ci:1:001:0 s a3 00 0000 0001 0004 4 <
dc7b15c0 210886537 C Ci:1:001:0 0 4 = 01030000
dc7b15c0 210918490 S Ci:1:001:0 s a3 00 0000 0001 0004 4 <
dc7b15c0 210918516 C Ci:1:001:0 0 4 = 01030000
dc4c7e20 210950508 S Ci:1:001:0 s a3 00 0000 0001 0004 4 <
dc4c7e20 210950532 C Ci:1:001:0 0 4 = 01030000
dc4c7e20 210982494 S Ci:1:001:0 s a3 00 0000 0001 0004 4 <
dc4c7e20 210982519 C Ci:1:001:0 0 4 = 01030000
dc4c7e20 210982584 S Co:1:001:0 s 23 03 0004 0001 0000 0
dc4c7e20 210982593 C Co:1:001:0 0 0
dc4c7e20 211038484 S Ci:1:001:0 s a3 00 0000 0001 0004 4 <
dc4c7e20 211038523 C Ci:1:001:0 0 4 = 03030000
dc4c7e20 211094486 S Co:1:001:0 s 23 01 0014 0001 0000 0
dc4c7e20 211094508 C Co:1:001:0 0 0
dc4c7e20 211094564 S Ci:1:000:0 s 80 06 0100 0000 0040 64 <
dc4c7e20 211099733 C Ci:1:000:0 -84 8 = 12010002 00000008
dc4c7e20 211099791 S Co:1:001:0 s 23 03 0004 0001 0000 0
dc4c7e20 211099803 C Co:1:001:0 0 0
dc4c7e20 211154482 S Ci:1:001:0 s a3 00 0000 0001 0004 4 <
dc4c7e20 211154523 C Ci:1:001:0 0 4 = 03030000
dc4c7e20 211210451 S Co:1:001:0 s 23 01 0014 0001 0000 0
dc4c7e20 211210467 C Co:1:001:0 0 0
dc4c7e20 211210479 S Co:1:000:0 s 00 05 0002 0000 0000 0
dc4c7e20 211215545 C Co:1:000:0 -75 0
dc4c7e20 211418476 S Co:1:000:0 s 00 05 0002 0000 0000 0
dc4c7e20 211422551 C Co:1:000:0 -71 0
dc4c7e20 211626489 S Co:1:001:0 s 23 01 0001 0001 0000 0
dc4c7e20 211626505 C Co:1:001:0 0 0
Jul 12 12:20:21 backup-test kernel: [ 0.000000] Entering add_active_range(0, 0, 131072) 0 entries of 256 used
Jul 12 12:20:21 backup-test kernel: [ 0.000000] On node 0 totalpages: 131072
Jul 12 12:20:21 backup-test kernel: [ 0.000000] DMA zone: 32 pages used for memmap
Jul 12 12:20:21 backup-test kernel: [ 0.000000] DMA zone: 0 pages reserved
Jul 12 12:20:21 backup-test kernel: [ 0.000000] DMA zone: 4064 pages, LIFO batch:0
Jul 12 12:20:21 backup-test kernel: [ 0.000000] Normal zone: 992 pages used for memmap
Jul 12 12:20:21 backup-test kernel: [ 0.000000] Normal zone: 125984 pages, LIFO batch:31
Jul 12 12:20:21 backup-test kernel: [ 0.000000] Movable zone: 0 pages used for memmap
Jul 12 12:20:21 backup-test kernel: [ 0.000000] mapped APIC to ffffb000 (01403000)
Jul 12 12:20:21 backup-test kernel: [ 0.004000] CPA: page pool initialized 1 of 1 pages preallocated
Jul 12 12:20:21 backup-test kernel: [ 0.123096] ACPI: EC: Look up EC in DSDT
Jul 12 12:20:21 backup-test kernel: [ 0.139334] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
Jul 12 12:20:21 backup-test kernel: [ 3.007081] pci 0000:01:00.0: Boot video device
Jul 12 12:20:21 backup-test kernel: [ 4.653211] PCI: setting IRQ 11 as level-triggered
Jul 12 12:20:21 backup-test kernel: [ 4.686316] PCI: setting IRQ 10 as level-triggered
Jul 12 12:20:21 backup-test kernel: [ 4.712876] Probing IDE interface ide0...
Jul 12 12:20:21 backup-test kernel: [ 5.673236] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO4
Jul 12 12:20:21 backup-test kernel: [ 5.673554] Probing IDE interface ide1...
Jul 12 12:20:21 backup-test kernel: [ 6.408145] hdc: host max PIO4 wanted PIO255(auto-tune) selected PIO4
Jul 12 12:20:21 backup-test kernel: [ 6.408413] hdd: host max PIO4 wanted PIO255(auto-tune) selected PIO4
Jul 12 12:20:21 backup-test kernel: [ 6.420132] PCI: setting IRQ 9 as level-triggered
Jul 12 12:20:21 backup-test kernel: [ 6.515891] libata version 3.00 loaded.
Jul 12 12:20:28 backup-test kernel: [ 31.628050] eth2: no IPv6 routers present
Jul 12 12:20:28 backup-test kernel: [ 31.976041] eth3: no IPv6 routers present
Jul 12 12:22:48 backup-test kernel: [ 0.000000] Entering add_active_range(0, 0, 131072) 0 entries of 256 used
Jul 12 12:22:48 backup-test kernel: [ 0.000000] On node 0 totalpages: 131072
Jul 12 12:22:48 backup-test kernel: [ 0.000000] DMA zone: 32 pages used for memmap
Jul 12 12:22:48 backup-test kernel: [ 0.000000] DMA zone: 0 pages reserved
Jul 12 12:22:48 backup-test kernel: [ 0.000000] DMA zone: 4064 pages, LIFO batch:0
Jul 12 12:22:48 backup-test kernel: [ 0.000000] Normal zone: 992 pages used for memmap
Jul 12 12:22:48 backup-test kernel: [ 0.000000] Normal zone: 125984 pages, LIFO batch:31
Jul 12 12:22:48 backup-test kernel: [ 0.000000] Movable zone: 0 pages used for memmap
Jul 12 12:22:48 backup-test kernel: [ 0.000000] mapped APIC to ffffb000 (01403000)
Jul 12 12:22:48 backup-test kernel: [ 0.004000] CPA: page pool initialized 1 of 1 pages preallocated
Jul 12 12:22:48 backup-test kernel: [ 0.126767] ACPI: EC: Look up EC in DSDT
Jul 12 12:22:48 backup-test kernel: [ 0.144172] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
Jul 12 12:22:48 backup-test kernel: [ 0.159937] libata version 3.00 loaded.
Jul 12 12:22:48 backup-test kernel: [ 0.203678] pci 0000:00:07.2: uhci_check_and_reset_hc: legsup = 0x003b
Jul 12 12:22:48 backup-test kernel: [ 0.203686] pci 0000:00:07.2: Performing full reset
Jul 12 12:22:48 backup-test kernel: [ 0.203818] pci 0000:01:00.0: Boot video device
Jul 12 12:22:48 backup-test kernel: [ 0.693659] Probing IDE interface ide0...
Jul 12 12:22:48 backup-test kernel: [ 0.696050] Switched to high resolution mode on CPU 0
Jul 12 12:22:48 backup-test kernel: [ 1.652229] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO4
Jul 12 12:22:48 backup-test kernel: [ 1.652541] Probing IDE interface ide1...
Jul 12 12:22:48 backup-test kernel: [ 2.388182] hdc: host max PIO4 wanted PIO255(auto-tune) selected PIO4
Jul 12 12:22:48 backup-test kernel: [ 2.388443] hdd: host max PIO4 wanted PIO255(auto-tune) selected PIO4
Jul 12 12:22:48 backup-test kernel: [ 2.528813] PCI: setting IRQ 9 as level-triggered
Jul 12 12:22:48 backup-test kernel: [ 2.529225] drivers/usb/core/inode.c: creating file 'devices'
Jul 12 12:22:48 backup-test kernel: [ 2.529239] drivers/usb/core/inode.c: creating file '001'
Jul 12 12:22:48 backup-test kernel: [ 2.529528] uhci_hcd 0000:00:07.2: uhci_check_and_reset_hc: cmd = 0x0000
Jul 12 12:22:48 backup-test kernel: [ 2.529536] uhci_hcd 0000:00:07.2: Performing full reset
Jul 12 12:22:48 backup-test kernel: [ 2.529760] usb usb1: default language 0x0409
Jul 12 12:22:48 backup-test kernel: [ 2.529893] usb usb1: uevent
Jul 12 12:22:48 backup-test kernel: [ 2.530009] usb usb1: usb_probe_device
Jul 12 12:22:48 backup-test kernel: [ 2.530091] usb usb1: adding 1-0:1.0 (config #1, interface 0)
Jul 12 12:22:48 backup-test kernel: [ 2.530144] usb 1-0:1.0: uevent
Jul 12 12:22:48 backup-test kernel: [ 2.530252] hub 1-0:1.0: usb_probe_interface
Jul 12 12:22:48 backup-test kernel: [ 2.530261] hub 1-0:1.0: usb_probe_interface - got id
Jul 12 12:22:48 backup-test kernel: [ 2.530402] hub 1-0:1.0: standalone hub
Jul 12 12:22:48 backup-test kernel: [ 2.530409] hub 1-0:1.0: no power switching (usb 1.0)
Jul 12 12:22:48 backup-test kernel: [ 2.530416] hub 1-0:1.0: individual port over-current protection
Jul 12 12:22:48 backup-test kernel: [ 2.530425] hub 1-0:1.0: power on to power good time: 2ms
Jul 12 12:22:48 backup-test kernel: [ 2.530440] hub 1-0:1.0: local power source is good
Jul 12 12:22:48 backup-test kernel: [ 2.530449] hub 1-0:1.0: trying to enable port power on non-switchable hub
Jul 12 12:22:48 backup-test kernel: [ 2.632112] hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0000
Jul 12 12:22:48 backup-test kernel: [ 2.632458] drivers/usb/core/inode.c: creating file '001'
Jul 12 12:22:48 backup-test kernel: [ 3.872047] usb usb1: suspend_rh (auto-stop)
Jul 12 12:22:48 backup-test kernel: [ 4.816065] hub 1-0:1.0: hub_suspend
Jul 12 12:22:48 backup-test kernel: [ 4.816086] usb usb1: bus auto-suspend
Jul 12 12:22:48 backup-test kernel: [ 4.816095] usb usb1: suspend_rh
Jul 12 12:22:48 backup-test kernel: [ 5.604105] usb usb1: uevent
Jul 12 12:22:48 backup-test kernel: [ 5.604167] usb 1-0:1.0: uevent
Jul 12 12:22:48 backup-test kernel: [ 7.602048] PCI: setting IRQ 11 as level-triggered
Jul 12 12:22:48 backup-test kernel: [ 7.646591] PCI: setting IRQ 10 as level-triggered
Jul 12 12:22:55 backup-test kernel: [ 26.436071] eth2: no IPv6 routers present
Jul 12 12:22:55 backup-test kernel: [ 26.680058] eth3: no IPv6 routers present
Jul 12 12:23:00 backup-test kernel: [ 32.130956] usb usb1: uevent
Jul 12 12:23:00 backup-test kernel: [ 32.131593] usb 1-0:1.0: uevent
Jul 12 12:38:49 backup-test kernel: [ 981.093948] usb usb1: usb resume
Jul 12 12:38:49 backup-test kernel: [ 981.093948] usb usb1: wakeup_rh
Jul 12 12:38:49 backup-test kernel: [ 981.132061] hub 1-0:1.0: hub_resume
Jul 12 12:38:49 backup-test kernel: [ 981.132118] uhci_hcd 0000:00:07.2: port 1 portsc 01a3,00
Jul 12 12:38:49 backup-test kernel: [ 981.132178] hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0000
Jul 12 12:38:49 backup-test kernel: [ 981.132198] uhci_hcd 0000:00:07.2: port 1 portsc 01a3,00
Jul 12 12:38:49 backup-test kernel: [ 981.132225] hub 1-0:1.0: port 1, status 0301, change 0001, 1.5 Mb/s
Jul 12 12:38:49 backup-test kernel: [ 981.260172] hub 1-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x301
Jul 12 12:38:50 backup-test kernel: [ 981.377057] usb 1-1: uhci_map_status_custom: failed with status 440000-- TD_CTRL_CRCTIMEO
Jul 12 12:38:50 backup-test kernel: [ 981.377090] usb 1-1: uhci_result_common: failed with status 440000
Jul 12 12:38:50 backup-test kernel: [ 981.377201] [dfc4d420] CTL QH link (00000001) element (1fe5b060)
Jul 12 12:38:50 backup-test kernel: [ 981.377210] urb_priv [dfeb43bc] urb [dc4c7e20] qh [dfc4d420] Dev=0 EP=0(IN) CTL Actlen=8
Jul 12 12:38:50 backup-test kernel: [ 981.377220] 1: [dfe5b060] link (1fe5b090) e0 SPD LS Stalled CRC/Timeo Length=7 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fa0)
Jul 12 12:38:50 backup-test kernel: [ 981.377233] 2: [dfe5b090] link (1fe5b0c0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fa8)
Jul 12 12:38:50 backup-test kernel: [ 981.377244] 3: [dfe5b0c0] link (1fe5b0f0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fb0)
Jul 12 12:38:50 backup-test kernel: [ 981.377256] 4: [dfe5b0f0] link (1fe5b120) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fb8)
Jul 12 12:38:50 backup-test kernel: [ 981.377268] 5: [dfe5b120] link (1fe5b150) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fc0)
Jul 12 12:38:50 backup-test kernel: [ 981.377279] 6: [dfe5b150] link (1fe5b180) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fc8)
Jul 12 12:38:50 backup-test kernel: [ 981.377291] 7: [dfe5b180] link (1fe5b1b0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fd0)
Jul 12 12:38:50 backup-test kernel: [ 981.377302] 8: [dfe5b1b0] link (1fe5b1e0) e3 LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fd8)
Jul 12 12:38:50 backup-test kernel: [ 981.377314] 9: [dfe5b1e0] link (1fe5b210) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000)
Jul 12 12:38:50 backup-test kernel: [ 981.377324] Dummy TD
Jul 12 12:38:50 backup-test kernel: [ 981.377330] [dfe5b210] link (00000240) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000)
Jul 12 12:38:50 backup-test kernel: [ 981.377338]
Jul 12 12:38:50 backup-test kernel: [ 981.493029] usb 1-1: uhci_map_status_custom: failed with status 500000 -- TD_CTRL_BABBLE
Jul 12 12:38:50 backup-test kernel: [ 981.493057] usb 1-1: uhci_result_common: failed with status 500000
Jul 12 12:38:50 backup-test kernel: [ 981.493100] [dfc4d420] CTL QH link (00000001) element (1fe5b060)
Jul 12 12:38:50 backup-test kernel: [ 981.493109] urb_priv [dfeb43bc] urb [dc4c7e20] qh [dfc4d420] Dev=0 EP=0(OUT) CTL Actlen=0
Jul 12 12:38:50 backup-test kernel: [ 981.493119] 1: [dfe5b060] link (1fe5b1e0) e3 LS IOC Stalled Babble Length=7ff MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jul 12 12:38:50 backup-test kernel: [ 981.493130] Dummy TD
Jul 12 12:38:50 backup-test kernel: [ 981.493136] [dfe5b1e0] link (000001b0) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000)
Jul 12 12:38:50 backup-test kernel: [ 981.493144]
Jul 12 12:38:50 backup-test kernel: [ 981.700015] usb 1-1: uhci_map_status_custom: failed with status 440000-- TD_CTRL_CRCTIMEO
Jul 12 12:38:50 backup-test kernel: [ 981.700037] usb 1-1: uhci_result_common: failed with status 440000
Jul 12 12:38:50 backup-test kernel: [ 981.700037] [dfc4d420] CTL QH link (00000001) element (1fe5b1e0)
Jul 12 12:38:50 backup-test kernel: [ 981.700037] urb_priv [dfeb43bc] urb [dc4c7e20] qh [dfc4d420] Dev=0 EP=0(OUT) CTL Actlen=0
Jul 12 12:38:50 backup-test kernel: [ 981.700037] 1: [dfe5b1e0] link (1fe5b060) e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=1c6189e0)
Jul 12 12:38:50 backup-test kernel: [ 981.700037] 2: [dfe5b060] link (1fe5b210) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jul 12 12:38:50 backup-test kernel: [ 981.700037] Dummy TD
Jul 12 12:38:50 backup-test kernel: [ 981.700037] [dfe5b210] link (000001b0) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000)
Jul 12 12:38:50 backup-test kernel: [ 981.700037]
Jul 12 12:38:50 backup-test kernel: [ 982.020954] usb 1-1: uhci_map_status_custom: failed with status 440000-- TD_CTRL_CRCTIMEO
Jul 12 12:38:50 backup-test kernel: [ 982.020977] usb 1-1: uhci_result_common: failed with status 440000
Jul 12 12:38:50 backup-test kernel: [ 982.021087] [dfc4d420] CTL QH link (00000001) element (1fe5b1e0)
Jul 12 12:38:50 backup-test kernel: [ 982.021095] urb_priv [dfeb43bc] urb [dc4c7e20] qh [dfc4d420] Dev=0 EP=0(IN) CTL Actlen=8
Jul 12 12:38:50 backup-test kernel: [ 982.021105] 1: [dfe5b1e0] link (1fe5b060) e0 SPD LS Stalled CRC/Timeo Length=7 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fa0)
Jul 12 12:38:50 backup-test kernel: [ 982.021118] 2: [dfe5b060] link (1fe5b1b0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fa8)
Jul 12 12:38:50 backup-test kernel: [ 982.021129] 3: [dfe5b1b0] link (1fe5b180) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fb0)
Jul 12 12:38:50 backup-test kernel: [ 982.021141] 4: [dfe5b180] link (1fe5b150) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fb8)
Jul 12 12:38:50 backup-test kernel: [ 982.021152] 5: [dfe5b150] link (1fe5b120) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fc0)
Jul 12 12:38:50 backup-test kernel: [ 982.021164] 6: [dfe5b120] link (1fe5b0f0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fc8)
Jul 12 12:38:50 backup-test kernel: [ 982.021175] 7: [dfe5b0f0] link (1fe5b0c0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fd0)
Jul 12 12:38:50 backup-test kernel: [ 982.021187] 8: [dfe5b0c0] link (1fe5b090) e3 LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=1fec8fd8)
Jul 12 12:38:50 backup-test kernel: [ 982.021198] 9: [dfe5b090] link (1fe5b030) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000)
Jul 12 12:38:50 backup-test kernel: [ 982.021208] Dummy TD
Jul 12 12:38:50 backup-test kernel: [ 982.021214] [dfe5b030] link (00000240) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000)
Jul 12 12:38:50 backup-test kernel: [ 982.021222]
Jul 12 12:38:50 backup-test kernel: [ 982.135926] usb 1-1: uhci_map_status_custom: failed with status 440000-- TD_CTRL_CRCTIMEO
Jul 12 12:38:50 backup-test kernel: [ 982.135965] usb 1-1: uhci_result_common: failed with status 440000
Jul 12 12:38:50 backup-test kernel: [ 982.136018] [dfc4d420] CTL QH link (00000001) element (1fe5b030)
Jul 12 12:38:50 backup-test kernel: [ 982.136027] urb_priv [dfeb43bc] urb [dc4c7e20] qh [dfc4d420] Dev=0 EP=0(OUT) CTL Actlen=0
Jul 12 12:38:50 backup-test kernel: [ 982.136037] 1: [dfe5b030] link (1fe5b1e0) e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=1c6189e0)
Jul 12 12:38:50 backup-test kernel: [ 982.136050] 2: [dfe5b1e0] link (1fe5b090) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jul 12 12:38:50 backup-test kernel: [ 982.136057] Dummy TD
Jul 12 12:38:50 backup-test kernel: [ 982.136057] [dfe5b090] link (000000c0) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000)
Jul 12 12:38:50 backup-test kernel: [ 982.136057]
Jul 12 12:38:51 backup-test kernel: [ 982.345900] usb 1-1: uhci_map_status_custom: failed with status 500000 -- TD_CTRL_BABBLE
Jul 12 12:38:51 backup-test kernel: [ 982.345938] usb 1-1: uhci_result_common: failed with status 500000
Jul 12 12:38:51 backup-test kernel: [ 982.345982] [dfc4d420] CTL QH link (00000001) element (1fe5b030)
Jul 12 12:38:51 backup-test kernel: [ 982.345991] urb_priv [dfeb43bc] urb [dc4c7e20] qh [dfc4d420] Dev=0 EP=0(OUT) CTL Actlen=0
Jul 12 12:38:51 backup-test kernel: [ 982.346001] 1: [dfe5b030] link (1fe5b1e0) e3 LS IOC Stalled Babble Length=7ff MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jul 12 12:38:51 backup-test kernel: [ 982.346012] Dummy TD
Jul 12 12:38:51 backup-test kernel: [ 982.346018] [dfe5b1e0] link (000000c0) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000)
Jul 12 12:38:51 backup-test kernel: [ 982.346026]
Jul 12 12:38:51 backup-test kernel: [ 982.664826] usb 1-1: uhci_map_status_custom: failed with status 500000 -- TD_CTRL_BABBLE
Jul 12 12:38:51 backup-test kernel: [ 982.664850] usb 1-1: uhci_result_common: failed with status 500000
Jul 12 12:38:51 backup-test kernel: [ 982.664890] [dfc4d420] CTL QH link (00000001) element (1fe5b030)
Jul 12 12:38:51 backup-test kernel: [ 982.664899] urb_priv [dfeb43bc] urb [dc4c7e20] qh [dfc4d420] Dev=0 EP=0(OUT) CTL Actlen=0
Jul 12 12:38:51 backup-test kernel: [ 982.664910] 1: [dfe5b030] link (1fe5b090) e3 LS IOC Stalled Babble Length=7ff MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jul 12 12:38:51 backup-test kernel: [ 982.664920] Dummy TD
Jul 12 12:38:51 backup-test kernel: [ 982.664926] [dfe5b090] link (000000c0) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000)
Jul 12 12:38:51 backup-test kernel: [ 982.664935]
Jul 12 12:38:51 backup-test kernel: [ 982.871808] usb 1-1: uhci_map_status_custom: failed with status 440000-- TD_CTRL_CRCTIMEO
Jul 12 12:38:51 backup-test kernel: [ 982.871830] usb 1-1: uhci_result_common: failed with status 440000
Jul 12 12:38:51 backup-test kernel: [ 982.871879] [dfc4d420] CTL QH link (00000001) element (1fe5b090)
Jul 12 12:38:51 backup-test kernel: [ 982.871888] urb_priv [dfeb43bc] urb [dc4c7e20] qh [dfc4d420] Dev=0 EP=0(OUT) CTL Actlen=0
Jul 12 12:38:51 backup-test kernel: [ 982.871898] 1: [dfe5b090] link (1fe5b030) e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=1c618280)
Jul 12 12:38:51 backup-test kernel: [ 982.871910] 2: [dfe5b030] link (1fe5b1e0) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jul 12 12:38:51 backup-test kernel: [ 982.871920] Dummy TD
Jul 12 12:38:51 backup-test kernel: [ 982.871926] [dfe5b1e0] link (000000c0) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000)
Jul 12 12:38:51 backup-test kernel: [ 982.871935]
Jul 12 12:38:51 backup-test kernel: [ 983.192770] usb 1-1: uhci_map_status_custom: failed with status 500000 -- TD_CTRL_BABBLE
Jul 12 12:38:51 backup-test kernel: [ 983.192795] usb 1-1: uhci_result_common: failed with status 500000
Jul 12 12:38:51 backup-test kernel: [ 983.192838] [dfc4d420] CTL QH link (00000001) element (1fe5b090)
Jul 12 12:38:51 backup-test kernel: [ 983.192846] urb_priv [dfeb43bc] urb [dc4c7e20] qh [dfc4d420] Dev=0 EP=0(OUT) CTL Actlen=0
Jul 12 12:38:51 backup-test kernel: [ 983.192857] 1: [dfe5b090] link (1fe5b030) e3 LS IOC Stalled Babble Length=7ff MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jul 12 12:38:51 backup-test kernel: [ 983.192867] Dummy TD
Jul 12 12:38:51 backup-test kernel: [ 983.192874] [dfe5b030] link (000000c0) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000)
Jul 12 12:38:51 backup-test kernel: [ 983.192882]
Jul 12 12:38:52 backup-test kernel: [ 983.400706] usb 1-1: uhci_map_status_custom: failed with status 500000 -- TD_CTRL_BABBLE
Jul 12 12:38:52 backup-test kernel: [ 983.400729] usb 1-1: uhci_result_common: failed with status 500000
Jul 12 12:38:52 backup-test kernel: [ 983.400769] [dfc4d420] CTL QH link (00000001) element (1fe5b090)
Jul 12 12:38:52 backup-test kernel: [ 983.400778] urb_priv [dfeb43bc] urb [dc4c7e20] qh [dfc4d420] Dev=0 EP=0(OUT) CTL Actlen=0
Jul 12 12:38:52 backup-test kernel: [ 983.400788] 1: [dfe5b090] link (1fe5b1e0) e3 LS IOC Stalled Babble Length=7ff MaxLen=7ff DT1 EndPt=0 Dev=0, PID=69(IN) (buf=00000000)
Jul 12 12:38:52 backup-test kernel: [ 983.400799] Dummy TD
Jul 12 12:38:52 backup-test kernel: [ 983.400805] [dfe5b1e0] link (000000c0) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000)
Jul 12 12:38:52 backup-test kernel: [ 983.400814]
Jul 12 12:38:54 backup-test kernel: [ 986.000119] hub 1-0:1.0: hub_suspend
Jul 12 12:38:54 backup-test kernel: [ 986.000161] usb usb1: bus auto-suspend
Jul 12 12:38:54 backup-test kernel: [ 986.000171] usb usb1: suspend_rh
Jul 12 12:39:45 backup-test kernel: [ 1036.668837] usb usb1: usb resume
Jul 12 12:39:45 backup-test kernel: [ 1036.668837] usb usb1: wakeup_rh
Jul 12 12:39:45 backup-test kernel: [ 1036.708080] hub 1-0:1.0: hub_resume
Jul 12 12:39:45 backup-test kernel: [ 1036.708144] uhci_hcd 0000:00:07.2: port 1 portsc 0082,00
Jul 12 12:39:45 backup-test kernel: [ 1036.708206] hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0000
Jul 12 12:39:45 backup-test kernel: [ 1036.708226] uhci_hcd 0000:00:07.2: port 1 portsc 0082,00
Jul 12 12:39:45 backup-test kernel: [ 1036.708253] hub 1-0:1.0: port 1, status 0100, change 0001, 12 Mb/s
Jul 12 12:39:45 backup-test kernel: [ 1036.836158] hub 1-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x100
Jul 12 12:39:46 backup-test kernel: [ 1037.800104] usb usb1: suspend_rh (auto-stop)
Jul 12 12:39:47 backup-test kernel: [ 1039.000455] hub 1-0:1.0: hub_suspend
Jul 12 12:39:47 backup-test kernel: [ 1039.000493] usb usb1: bus auto-suspend
Jul 12 12:39:47 backup-test kernel: [ 1039.000501] usb usb1: suspend_rh
