• State Resolved
  • Locked Locked
  • Replies 31 replies
  • Answers 3 answers
  • Subscribers 98 subscribers
  • Views 1889 views
  • Users 0 members are here
Support feedback
Options
Options
Related

This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

PROCESSOR-SDK-AM65X: USB problem in U-boot

user6115282
Intellectual 320 points
Part Number: PROCESSOR-SDK-AM65X

Tool/software: TI C/C++ Compiler

Hi:

We found the following U-disk recognition problem in using EVM boards under u-boot.


Test environment:

   Hardware:    AM654X EVM PROCESSOR BOARD (REV 1.0), 8G miscro SD card (Panasonic)
                    
                       USB-HUB (Standard Microsystems, Realtek Semiconductor, Ithink)

                       U-disk (Sandisk  , Kingston , HP ,Sansumg ,BANQ )

   Software:     Processor SDK Linux 06_00_00_07

Detailed description of the problem： 

In U-boot, the EVM board connects a certain type of USB HUB and inserts the U disk, some of which can not be recognized.
The test topology is as follows. The EVM board has tested three different manufacturers'USB-HUB. 
The first and second types of USB-HUB can be recognized normally,   while the third type of USB-HUB(Standard microsystems  chip:  USB2514) has two types of USB-HUB that can not be recognized.But these two U disks can be correctly identified under Linux.   The two U disks are BANQ (F30/64G)  and Sandisk (SDCZ33/32G).

  • 0
    TI__Guru**** 167581 points

    Hi,

    Is this the same issue as Q1 reported in https://e2e.ti.com/support/processors/f/791/t/825391? I thought the issue was resolved with the 2 U-Boot patches I provided, is it not the case?

  • 0 in reply to Bin Liu
    Intellectual 320 points

    Hi,

    Yes, it's the same problem as Q1.I describe Q1 and Q2 separately. I use the patches in u-boot as you said, but it still hasn't been solved for Q1.

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    Can you please provide the uboot log of the following command in the failure case?

    => usb reset
    => usb info

  • 0 in reply to Bin Liu
    Intellectual 320 points

    Hi,

    In your opinion, this is my insertion of unrecognizable U-disk print information.

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    In the log above, where is the Human Interface device connected to? It is not shown in the diagram in your first post.

    Is this as indicated in your first post that you tested 20 thumb drives with this USB-HUB-3 and only two of the thumb drives are failed to numerate? If so, what are the brands of the working thumb drives? I'd like to collect more information to see if I can reproduce the problem on my evm.

    Is the USB-HUB-3 self-powered or bus powered? If bus-powered, is it possible to use an external power supply to the hub?

  • 0 in reply to Bin Liu
    Intellectual 320 points

    Hi，

    Human Interface Device is another device that you can ignore.

    1. I give some examples of U disks device that can be normally recognized: DTSE9, HP (x5000m), Patriot U330, Sandisk (CZ73), Kingston Data Traveler 100, aigo(NEX3.1), Sandisk (CZ880).

    2. my hubs used powered externally also not ok.

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    Thanks for the information. I will try to locate a same hub to reproduce the issue and debug it.

  • 0 in reply to Bin Liu
    Intellectual 320 points

    Hi,

    How about debugging now , Is there any progress?

    Thanks

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    Today I found one Sandisk high-speed thumb drive which fails in enumeration behind a SMSC2514 hub, after I run 'usb reset' 3 times.

    In your case, do you see the failure in the very first time running 'usb reset' or 'usb start' after booted into U-Boot? Or you have to run 'usb reset' multiple times to see the enumeration failure? I'd like to understand if the issue I saw is the same as that you saw.

  • 0 in reply to Bin Liu
    Intellectual 320 points

    Hi,

    If I run it only once ('usb reset 'or 'usb start'), it will fail.

    Thanks 

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hmm, it seems that I am seeing a different issue on my setup.

    Can you please trigger the issue twice and use a USB protocol analyzer to capture a bus trace in each failure? One to capture between the thumb drive and the hub, another is to capture between the hub and AM654x EVM. You could capture the two traces in one test by chaining two analyzers, but it is not necessary. Preferred to use Lecroy USB protocol analyzer.

  • 0 in reply to Bin Liu
    TI__Guru**** 167581 points

    By the way, you can ask the TI local FAE to send the USB traces to me.

  • 0 in reply to Bin Liu
    TI__Guru**** 167581 points

    Hi, I haven’t heard back from you, I’m assuming you were able to resolve your issue. If not, just post a reply below (or create a new thread if the thread has locked due to time-out).  thanks.

  • 0 in reply to Bin Liu
    Intellectual 320 points

    Hi,

    I feel strange. I think this bug is present. You should be able to catch it there.

    Thanks

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    Yes, I have captured the bus traces on my setup, which shows the problem is on the thumb drive.

    But seeing the same symptom in U-Boot on both your setup and mine does mean the issue root cause would be the same. That is why I asked for your bus trace to confirm whether the findings on my setup is applicable to the issue on your setup. Make sense?

  • 0 in reply to Bin Liu
    Intellectual 320 points

    Hi,

    I have sent the analysis data to the local FAE, please check it carefully.

    Thanks

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    Yes, I received the data, but .csv format has some information lost. Can you please send the raw file instead? I should be able to download whatever the software to read the raw capture file. I have asked the local FAE for the same yesterday.

  • 0 in reply to Bin Liu
    Intellectual 320 points

    Hi，

    Okay, I'll provide you later. The raw files needs our USB logic analysis software to open. I'll send it to you together.

     

    Thanks for your support!

  • 0 in reply to Bin Liu
    Intellectual 320 points

    Hi,

    Here are some information

    1. I modify the ‘<u-boot>/driver/usb/host/xhci-ring.c>’ to add the delay(See attached picture ), It   can solve the U-boot part of the U disk can not identify the problem, has been successfully verified on Evm board and our own board, but the root cause has not been found. It's just a solution.

             

    2.  I upload the USB logical analysis data again, see the attachment; the original data needs specific software to open. Software download links are described in the README.md file  I have sent the analysis data to the local FAE, please check it carefully

    3. This is my reference. ： https://lists.denx.de/pipermail/u-boot/2010-September/077489.html

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    In the last few days I have been trying to reproduce the issue on my setup again so that I can experiment with the delay solution you discovered. But for whatever reason I am no longer to see the issue any more even with the same U-Boot binary I used weeks ago which showed the issue with the hub.

    As you tested and the person's experience in the U-Boot mailing list you referred, adding the delay seems to be a valid solution. But adding 100ms in xhci_ctrl_tx() for every USB control packet has some performance impact and 100ms seems too long, so have you tried to reduce the time?

    Also as in the debug message from the printf() you added above mdelay(), the delay is added for *every* USB control packet, have to tried to add 'if' condition check for mdelay() to see if the delay can be only added for certain type of the control packet? this would reduce the performance impact even more.

  • 0 in reply to Bin Liu
    Intellectual 320 points

    Hi，

    1. Yes, according to the references I sent, add a judgment of  '2514 hub',  Try various delays . I tried to delay at least 80ms. There are two phenomena:

    a. When the delay time is set to 80ms, it works normally.

    b. When the delay is set to more than 90 ms, insert two U disks at the same time, one Hub port, It  can identify all U disks normally, but the probability of the occurrence of cpu's native port can not identify a U-disk (only a U-disk ' Kingston Data Traveler 32G' will appear this phenomenon, if no delay is normal).

    2. We still have some doubts about delay reliability, so we still hope to find root cause.

    Thank  you for you support !

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    For debugging purpose, please remove the delay you added in the U-Boot code, but apply the following debug patch, and let me know if the issue still happens. Please also capture the bus traces (hub-to-evm and hub-to-thumbdrive) no matter the issue happens or not. I'd like to see how enumeration timing changes with this patch.

    diff --git a/common/usb_hub.c b/common/usb_hub.c
index 33aaeb8e4452..054f17f82054 100644
--- a/common/usb_hub.c
+++ b/common/usb_hub.c
@@ -40,7 +40,7 @@

 #define USB_BUFSIZ     512

-#define HUB_SHORT_RESET_TIME   20
+#define HUB_SHORT_RESET_TIME   800
 #define HUB_LONG_RESET_TIME    200

 #define PORT_OVERCURRENT_MAX_SCAN_COUNT                3
  • 0 in reply to Bin Liu
    Intellectual 320 points

    Hi,

    I tried your way, but I still can't.  After the modification, I compared and tested two U-disk, one recognizable and the other unrecognizable, and captured and recorded the waveform.

    Data I will send to FAE

    Tank you for you support!

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    I received the bus traces, thanks. It seems the USB host doesn't initiate the enumeration of the thumb drive in the failure case. I would have do some experiment on my EVM to provide next debug instruction. But our office is closed since last week due to severe weather damage. I will update once I make progress.

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    Please apply the following U-Boot debug patch, it enables the debug messages in USB drives. Please run with the same SMSC hub but with the failing U-Disk and a working U-Disk, and capture both U-Boot console logs. Then attach the log files here.

    Fullscreen u-boot-2019.01-enable-usb-debug.diff Download 
    diff --git a/common/usb.c b/common/usb.c
index b70f614d244f..4139abaeebfc 100644
--- a/common/usb.c
+++ b/common/usb.c
@@ -25,6 +25,7 @@
  *
  * For each transfer (except "Interrupt") we wait for completion.
  */
+#define DEBUG
 #include <common.h>
 #include <command.h>
 #include <dm.h>
diff --git a/common/usb_hub.c b/common/usb_hub.c
index 33aaeb8e4452..72866d4b4252 100644
--- a/common/usb_hub.c
+++ b/common/usb_hub.c
@@ -21,6 +21,7 @@
  * Probes device for being a hub and configurate it
  */
 
+#define DEBUG
 #include <common.h>
 #include <command.h>
 #include <dm.h>
diff --git a/drivers/usb/host/xhci-mem.c b/drivers/usb/host/xhci-mem.c
index 530e979bb78e..bd3a26a56ab7 100644
--- a/drivers/usb/host/xhci-mem.c
+++ b/drivers/usb/host/xhci-mem.c
@@ -13,6 +13,7 @@
  *	    Vikas Sajjan <vikas.sajjan@samsung.com>
  */
 
+#define DEBUG
 #include <common.h>
 #include <dm.h>
 #include <asm/byteorder.h>
diff --git a/drivers/usb/host/xhci-ring.c b/drivers/usb/host/xhci-ring.c
index 119b41848715..195596b66626 100644
--- a/drivers/usb/host/xhci-ring.c
+++ b/drivers/usb/host/xhci-ring.c
@@ -13,6 +13,7 @@
  *	    Vikas Sajjan <vikas.sajjan@samsung.com>
  */
 
+#define DEBUG
 #include <common.h>
 #include <asm/byteorder.h>
 #include <usb.h>
diff --git a/drivers/usb/host/xhci.c b/drivers/usb/host/xhci.c
index 848721a49d5b..ed048e00b944 100644
--- a/drivers/usb/host/xhci.c
+++ b/drivers/usb/host/xhci.c
@@ -19,6 +19,7 @@
  * The quirk devices support hasn't been given yet.
  */
 
+#define DEBUG
 #include <common.h>
 #include <dm.h>
 #include <asm/byteorder.h>

  • 0 in reply to Bin Liu
    Intellectual 320 points

    Hi 

    Debugging messages  will affect the test results.

    After add debugg essages , the previously unrecognized U-disk can be identified normally. I've done debugging and messages before. Just like adding delay, the unidentified U disk can be identified.

    Normal-udisk.log : the normal U-disk.

    Error udisk.log:    the unidentified U-disk, but it can be seen from the log that it can be identified with printing.

    Fullscreen normal-u-disk.log Download 
    => usb reset
resetting USB...
// Halt the HC: 0000000004010020
// Reset the HC
// Disabling event ring interrupts
// Halt the HC: 0000000004030020
// Reset the HC
// Disabling event ring interrupts
USB0:   xhci_register: dev='usb@10000', ctrl=00000000bdefb100, hccr=0000000004010000, hcor=0000000004010020
// Halt the HC: 0000000004010020
// Reset the HC
Register 2001040 NbrPorts 2
Starting the controller
USB XHCI 1.10
USB1:   xhci_register: dev='usb@10000', ctrl=00000000bdf01040, hccr=0000000004030000, hcor=0000000004030020
// Halt the HC: 0000000004030020
// Reset the HC
Register 2001040 NbrPorts 2
Starting the controller
USB XHCI 1.10
scanning bus 0 for devices... xhci_alloc_device: dev='usb@10000', udev=00000000bdecd600
set address 1
usb_control_msg: request: 0x5, requesttype: 0x0, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_REQ_SET_ADDRESS
scrlen = 0
 req->length = 0
Len is 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x100 index 0x0 length 0x12
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_DEVICE request
scrlen = 18
 req->length = 18
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x9
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_CONFIG config
scrlen = 25
 req->length = 9
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x1F
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_CONFIG config
scrlen = 25
 req->length = 31
get_conf_no 0 Result 25, wLength 31
if 0, ep 0
##EP epmaxpacketin[1] = 8
set configuration 1
usb_control_msg: request: 0x9, requesttype: 0x0, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
scrlen = 0
 req->length = 0
Len is 0
new device strings: Mfr=1, Product=2, SerialNumber=0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x300 index 0x0 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_STRING config
scrlen = 4
 req->length = 255
USB device number 1 default language ID 0x409
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x301 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_STRING config
scrlen = 14
 req->length = 255
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x302 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_STRING config
scrlen = 42
 req->length = 255
Manufacturer U-Boot
Product      XHCI Host Controller
SerialNumber
usb_hub_post_probe
usb_control_msg: request: 0x6, requesttype: 0xA0, value 0x2A00 index 0x0 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
USB_DT_HUB config
scrlen = 8
 req->length = 4
usb_control_msg: request: 0x6, requesttype: 0xA0, value 0x2A00 index 0x0 length 0xC
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
USB_DT_HUB config
scrlen = 8
 req->length = 12
2 ports detected
individual port power switching
standalone hub
individual port over-current protection
TT requires at most 8 FS bit times (666 ns)
power on to power good time: 20ms
hub controller current requirement: 0mA
port 1 is removable
port 2 is removable
usb_control_msg: request: 0x0, requesttype: 0xA0, value 0x0 index 0x0 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
scrlen = 2
 req->length = 4
get_hub_status returned status 1, change 902
local power source is lost (inactive)
no over-current condition exists
xhci_update_hub_device: dev='usb@10000', udev=00000000bdef4080
enabling power on all ports
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
scrlen = 0
 req->length = 0
Len is 0
port 1 returns 0
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x2 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
scrlen = 0
 req->length = 0
Len is 0
port 2 returns 0
pgood_delay=20ms
devnum=1 poweron: query_delay=100 connect_timeout=1100
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
SPEED = FULLSPEED
scrlen = 4
 req->length = 4
Port 1 Status 101 Change 1
devnum=1 port=1: USB dev found
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
SPEED = FULLSPEED
scrlen = 4
 req->length = 4
portstatus 101, change 1, 12 Mb/s
usb_control_msg: request: 0x1, requesttype: 0x23, value 0x10 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
clear port connect change, actual port 1 status  = 0x6e1
scrlen = 0
 req->length = 0
Len is 0
usb_hub_port_reset: resetting 'usb_hub' port 1...
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x4 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
scrlen = 0
 req->length = 0
Len is 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
SPEED = FULLSPEED
scrlen = 4
 req->length = 4
portstatus 111, change 0, 12 Mb/s
STAT_C_CONNECTION = 0 STAT_CONNECTION = 1  USB_PORT_STAT_ENABLE 0
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x4 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
scrlen = 0
 req->length = 0
Len is 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
SPEED = HIGHSPEED
scrlen = 4
 req->length = 4
portstatus 503, change 10, 480 Mb/s
STAT_C_CONNECTION = 0 STAT_CONNECTION = 1  USB_PORT_STAT_ENABLE 1
usb_control_msg: request: 0x1, requesttype: 0x23, value 0x14 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
clear port reset change, actual port 1 status  = 0xe03
scrlen = 0
 req->length = 0
Len is 0
xhci_alloc_device: dev='usb@10000', udev=00000000bdecc680
EP STATE RUNNING.
set address 2
usb_control_msg: request: 0x5, requesttype: 0x0, value 0x2 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
Setting up addressable devices 00000000bdefdb00
route string 0
port_num = 1
SPEED = 3
Setting Packet size = 64bytes
EP STATE RUNNING.
Successful Address Device command
xHC internal address is: 1
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x100 index 0x0 length 0x12
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=256 (0x100), index=0
EP STATE RUNNING.
start_trb 00000000bdef91c0, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 256,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 18
length_field = 18, length = 18,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x9
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=512 (0x200), index=0
EP STATE RUNNING.
start_trb 00000000bdef91f0, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 512,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 9
length_field = 9, length = 9,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x29
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=512 (0x200), index=0
EP STATE RUNNING.
start_trb 00000000bdef9220, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 512,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 41
length_field = 41, length = 41,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
get_conf_no 0 Result 41, wLength 41
if 0, ep 0
if 0, ep 1
##EP epmaxpacketin[1] = 1
set configuration 1
usb_control_msg: request: 0x9, requesttype: 0x0, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
EP STATE RUNNING.
Successful Configure Endpoint command
req=9 (0x9), type=0 (0x0), value=1 (0x1), index=0
EP STATE RUNNING.
start_trb 00000000bdef9250, start_cycle 1
req->requesttype = 0, req->request = 9,le16_to_cpu(req->value) = 1,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
new device strings: Mfr=0, Product=0, SerialNumber=0
Manufacturer
Product
SerialNumber
usb_hub_post_probe
usb_control_msg: request: 0x6, requesttype: 0xA0, value 0x2900 index 0x0 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=160 (0xa0), value=10496 (0x2900), index=0
EP STATE RUNNING.
start_trb 00000000bdef9270, start_cycle 1
req->requesttype = 160, req->request = 6,le16_to_cpu(req->value) = 10496,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0xA0, value 0x2900 index 0x0 length 0x9
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=160 (0xa0), value=10496 (0x2900), index=0
EP STATE RUNNING.
start_trb 00000000bdef92a0, start_cycle 1
req->requesttype = 160, req->request = 6,le16_to_cpu(req->value) = 10496,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 9
length_field = 9, length = 9,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
4 ports detected
individual port power switching
standalone hub
individual port over-current protection
usb_control_msg: request: 0xB, requesttype: 0x1, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=11 (0xb), type=1 (0x1), value=1 (0x1), index=0
EP STATE RUNNING.
start_trb 00000000bdef92d0, start_cycle 1
req->requesttype = 1, req->request = 11,le16_to_cpu(req->value) = 1,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
TT per port
TT requires at most 8 FS bit times (666 ns)
power on to power good time: 100ms
hub controller current requirement: 1mA
port 1 is removable
port 2 is removable
port 3 is removable
port 4 is removable
usb_control_msg: request: 0x0, requesttype: 0xA0, value 0x0 index 0x0 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=160 (0xa0), value=0 (0x0), index=0
EP STATE RUNNING.
start_trb 00000000bdef92f0, start_cycle 1
req->requesttype = 160, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
get_hub_status returned status 0, change 0
local power source is good
no over-current condition exists
xhci_update_hub_device: dev='usb@10000', udev=00000000bdee7e80
EP STATE RUNNING.
Successful Configure Endpoint command
enabling power on all ports
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=3 (0x3), type=35 (0x23), value=8 (0x8), index=1
EP STATE RUNNING.
start_trb 00000000bdef9320, start_cycle 1
req->requesttype = 35, req->request = 3,le16_to_cpu(req->value) = 8,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
port 1 returns 0
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x2 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=3 (0x3), type=35 (0x23), value=8 (0x8), index=2
EP STATE RUNNING.
start_trb 00000000bdef9340, start_cycle 1
req->requesttype = 35, req->request = 3,le16_to_cpu(req->value) = 8,le16_to_cpu(req->index) = 2,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
port 2 returns 0
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x3 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=3 (0x3), type=35 (0x23), value=8 (0x8), index=3
EP STATE RUNNING.
start_trb 00000000bdef9360, start_cycle 1
req->requesttype = 35, req->request = 3,le16_to_cpu(req->value) = 8,le16_to_cpu(req->index) = 3,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
port 3 returns 0
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x4 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=3 (0x3), type=35 (0x23), value=8 (0x8), index=4
EP STATE RUNNING.
start_trb 00000000bdef9380, start_cycle 1
req->requesttype = 35, req->request = 3,le16_to_cpu(req->value) = 8,le16_to_cpu(req->index) = 4,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
port 4 returns 0
pgood_delay=100ms
devnum=2 poweron: query_delay=100 connect_timeout=1100
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef4080, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
devnum=1 port=2: timeout
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x4 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=4
EP STATE RUNNING.
start_trb 00000000bdef93a0, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 4,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 4 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=1
EP STATE RUNNING.
start_trb 00000000bdef93d0, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 1 Status 101 Change 1
devnum=2 port=1: USB dev found
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=1
EP STATE RUNNING.
start_trb 00000000bdef9400, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
portstatus 101, change 1, 12 Mb/s
usb_control_msg: request: 0x1, requesttype: 0x23, value 0x10 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=1 (0x1), type=35 (0x23), value=16 (0x10), index=1
EP STATE RUNNING.
start_trb 00000000bdef9430, start_cycle 1
req->requesttype = 35, req->request = 1,le16_to_cpu(req->value) = 16,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_hub_port_reset: resetting 'usb_hub' port 1...
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x4 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=3 (0x3), type=35 (0x23), value=4 (0x4), index=1
EP STATE RUNNING.
start_trb 00000000bdef9450, start_cycle 1
req->requesttype = 35, req->request = 3,le16_to_cpu(req->value) = 4,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=1
EP STATE RUNNING.
start_trb 00000000bdef9470, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
portstatus 503, change 10, 480 Mb/s
STAT_C_CONNECTION = 0 STAT_CONNECTION = 1  USB_PORT_STAT_ENABLE 1
usb_control_msg: request: 0x1, requesttype: 0x23, value 0x14 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=1 (0x1), type=35 (0x23), value=20 (0x14), index=1
EP STATE RUNNING.
start_trb 00000000bdef94a0, start_cycle 1
req->requesttype = 35, req->request = 1,le16_to_cpu(req->value) = 20,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
xhci_alloc_device: dev='usb@10000', udev=00000000bdecc680
EP STATE RUNNING.
set address 3
usb_control_msg: request: 0x5, requesttype: 0x0, value 0x3 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
Setting up addressable devices 00000000bdefdb00
route string 1
port_num = 1
SPEED = 3
Setting Packet size = 64bytes
EP STATE RUNNING.
Successful Address Device command
xHC internal address is: 2
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x100 index 0x0 length 0x12
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=256 (0x100), index=0
EP STATE RUNNING.
start_trb 00000000bdefa480, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 256,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 18
length_field = 18, length = 18,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x9
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=512 (0x200), index=0
EP STATE RUNNING.
start_trb 00000000bdefa4b0, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 512,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 9
length_field = 9, length = 9,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x20
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=512 (0x200), index=0
EP STATE RUNNING.
start_trb 00000000bdefa4e0, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 512,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 32
length_field = 32, length = 32,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
get_conf_no 0 Result 32, wLength 32
if 0, ep 0
if 0, ep 1
##EP epmaxpacketout[1] = 512
##EP epmaxpacketin[2] = 512
set configuration 1
usb_control_msg: request: 0x9, requesttype: 0x0, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
EP STATE RUNNING.
Successful Configure Endpoint command
req=9 (0x9), type=0 (0x0), value=1 (0x1), index=0
EP STATE RUNNING.
start_trb 00000000bdefa510, start_cycle 1
req->requesttype = 0, req->request = 9,le16_to_cpu(req->value) = 1,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
new device strings: Mfr=1, Product=2, SerialNumber=3
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x300 index 0x0 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=768 (0x300), index=0
EP STATE RUNNING.
start_trb 00000000bdefa530, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 768,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 255
length_field = 255, length = 255,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
USB device number 3 default language ID 0x409
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x301 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=769 (0x301), index=1033
EP STATE RUNNING.
start_trb 00000000bdefa560, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 769,le16_to_cpu(req->index) = 1033,le16_to_cpu(req->length) = 255
length_field = 255, length = 255,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x302 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=770 (0x302), index=1033
EP STATE RUNNING.
start_trb 00000000bdefa590, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 770,le16_to_cpu(req->index) = 1033,le16_to_cpu(req->length) = 255
length_field = 255, length = 255,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x303 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=771 (0x303), index=1033
EP STATE RUNNING.
start_trb 00000000bdefa5c0, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 771,le16_to_cpu(req->index) = 1033,le16_to_cpu(req->length) = 255
length_field = 255, length = 255,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Manufacturer Samsung
Product      Flash Drive FIT
SerialNumber 0340118020001324
usb_control_msg: request: 0xFE, requesttype: 0xA1, value 0x0 index 0x0 length 0x1
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef5620, udev->dev='usb_mass_storage', portnr=1
req=254 (0xfe), type=161 (0xa1), value=0 (0x0), index=0
EP STATE RUNNING.
start_trb 00000000bdefa5f0, start_cycle 1
req->requesttype = 161, req->request = 254,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 1
length_field = 1, length = 1,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef5620
dev=00000000bdef5620, pipe=c0008303, buffer=00000000bdecc300, length=31
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef5620
dev=00000000bdef5620, pipe=c0010383, buffer=00000000bdecc440, length=36
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef5620
dev=00000000bdef5620, pipe=c0010383, buffer=00000000bdecc280, length=13
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef5620
dev=00000000bdef5620, pipe=c0008303, buffer=00000000bdecc300, length=31
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef5620
dev=00000000bdef5620, pipe=c0010383, buffer=00000000bdecc280, length=13
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef5620
dev=00000000bdef5620, pipe=c0008303, buffer=00000000bdecc300, length=31
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef5620
dev=00000000bdef5620, pipe=c0010383, buffer=00000000bdecc3c0, length=8
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef5620
dev=00000000bdef5620, pipe=c0010383, buffer=00000000bdecc280, length=13
EP STATE RUNNING.
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=2
EP STATE RUNNING.
start_trb 00000000bdef94c0, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 2,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 2 Status 100 Change 0
devnum=2 port=2: timeout
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x3 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=3
EP STATE RUNNING.
start_trb 00000000bdef94f0, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 3,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 3 Status 100 Change 0
devnum=2 port=3: timeout
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x4 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdee7e80, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=4
EP STATE RUNNING.
start_trb 00000000bdef9520, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 4,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 4 Status 100 Change 0
devnum=2 port=4: timeout
3 USB Device(s) found
scanning bus 1 for devices... xhci_alloc_device: dev='usb@10000', udev=00000000bdecd600
set address 1
usb_control_msg: request: 0x5, requesttype: 0x0, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_REQ_SET_ADDRESS
scrlen = 0
 req->length = 0
Len is 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x100 index 0x0 length 0x12
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_DEVICE request
scrlen = 18
 req->length = 18
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x9
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_CONFIG config
scrlen = 25
 req->length = 9
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x1F
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_CONFIG config
scrlen = 25
 req->length = 31
get_conf_no 0 Result 25, wLength 31
if 0, ep 0
##EP epmaxpacketin[1] = 8
set configuration 1
usb_control_msg: request: 0x9, requesttype: 0x0, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
scrlen = 0
 req->length = 0
Len is 0
new device strings: Mfr=1, Product=2, SerialNumber=0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x300 index 0x0 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_STRING config
scrlen = 4
 req->length = 255
USB device number 1 default language ID 0x409
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x301 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_STRING config
scrlen = 14
 req->length = 255
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x302 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_STRING config
scrlen = 42
 req->length = 255
Manufacturer U-Boot
Product      XHCI Host Controller
SerialNumber
usb_hub_post_probe
usb_control_msg: request: 0x6, requesttype: 0xA0, value 0x2A00 index 0x0 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
USB_DT_HUB config
scrlen = 8
 req->length = 4
usb_control_msg: request: 0x6, requesttype: 0xA0, value 0x2A00 index 0x0 length 0xC
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
USB_DT_HUB config
scrlen = 8
 req->length = 12
2 ports detected
individual port power switching
standalone hub
individual port over-current protection
TT requires at most 8 FS bit times (666 ns)
power on to power good time: 20ms
hub controller current requirement: 0mA
port 1 is removable
port 2 is removable
usb_control_msg: request: 0x0, requesttype: 0xA0, value 0x0 index 0x0 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 2
 req->length = 4
get_hub_status returned status 1, change 902
local power source is lost (inactive)
no over-current condition exists
xhci_update_hub_device: dev='usb@10000', udev=00000000bdef6280
enabling power on all ports
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 0
 req->length = 0
Len is 0
port 1 returns 0
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x2 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 0
 req->length = 0
Len is 0
port 2 returns 0
pgood_delay=20ms
devnum=1 poweron: query_delay=100 connect_timeout=1100
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
devnum=1 port=2: timeout
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef6280, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
devnum=1 port=1: timeout
1 USB Device(s) found
       scanning usb for storage devices... 1 Storage Device(s) found
=>

    Fullscreen error-u-disk.log Download 
    =>
=> usb reset
resetting USB...
// Halt the HC: 0000000004010020
// Reset the HC
// Disabling event ring interrupts
// Halt the HC: 0000000004030020
// Reset the HC
// Disabling event ring interrupts
USB0:   xhci_register: dev='usb@10000', ctrl=00000000bdef88c0, hccr=0000000004010000, hcor=0000000004010020
// Halt the HC: 0000000004010020
// Reset the HC
Register 2001040 NbrPorts 2
Starting the controller
USB XHCI 1.10
USB1:   xhci_register: dev='usb@10000', ctrl=00000000bdf1f040, hccr=0000000004030000, hcor=0000000004030020
// Halt the HC: 0000000004030020
// Reset the HC
Register 2001040 NbrPorts 2
Starting the controller
USB XHCI 1.10
scanning bus 0 for devices... xhci_alloc_device: dev='usb@10000', udev=00000000bdecd600
set address 1
usb_control_msg: request: 0x5, requesttype: 0x0, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_REQ_SET_ADDRESS
scrlen = 0
 req->length = 0
Len is 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x100 index 0x0 length 0x12
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_DEVICE request
scrlen = 18
 req->length = 18
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x9
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_CONFIG config
scrlen = 25
 req->length = 9
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x1F
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_CONFIG config
scrlen = 25
 req->length = 31
get_conf_no 0 Result 25, wLength 31
if 0, ep 0
##EP epmaxpacketin[1] = 8
set configuration 1
usb_control_msg: request: 0x9, requesttype: 0x0, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
scrlen = 0
 req->length = 0
Len is 0
new device strings: Mfr=1, Product=2, SerialNumber=0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x300 index 0x0 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_STRING config
scrlen = 4
 req->length = 255
USB device number 1 default language ID 0x409
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x301 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_STRING config
scrlen = 14
 req->length = 255
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x302 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_STRING config
scrlen = 42
 req->length = 255
Manufacturer U-Boot
Product      XHCI Host Controller
SerialNumber
usb_hub_post_probe
usb_control_msg: request: 0x6, requesttype: 0xA0, value 0x2A00 index 0x0 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
USB_DT_HUB config
scrlen = 8
 req->length = 4
usb_control_msg: request: 0x6, requesttype: 0xA0, value 0x2A00 index 0x0 length 0xC
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
USB_DT_HUB config
scrlen = 8
 req->length = 12
2 ports detected
individual port power switching
standalone hub
individual port over-current protection
TT requires at most 8 FS bit times (666 ns)
power on to power good time: 20ms
hub controller current requirement: 0mA
port 1 is removable
port 2 is removable
usb_control_msg: request: 0x0, requesttype: 0xA0, value 0x0 index 0x0 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
scrlen = 2
 req->length = 4
get_hub_status returned status 1, change 902
local power source is lost (inactive)
no over-current condition exists
xhci_update_hub_device: dev='usb@10000', udev=00000000bdf06080
enabling power on all ports
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
scrlen = 0
 req->length = 0
Len is 0
port 1 returns 0
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x2 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
scrlen = 0
 req->length = 0
Len is 0
port 2 returns 0
pgood_delay=20ms
devnum=1 poweron: query_delay=100 connect_timeout=1100
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
SPEED = FULLSPEED
scrlen = 4
 req->length = 4
Port 1 Status 101 Change 1
devnum=1 port=1: USB dev found
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
SPEED = FULLSPEED
scrlen = 4
 req->length = 4
portstatus 101, change 1, 12 Mb/s
usb_control_msg: request: 0x1, requesttype: 0x23, value 0x10 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
clear port connect change, actual port 1 status  = 0x6e1
scrlen = 0
 req->length = 0
Len is 0
usb_hub_port_reset: resetting 'usb_hub' port 1...
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x4 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
scrlen = 0
 req->length = 0
Len is 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
SPEED = FULLSPEED
scrlen = 4
 req->length = 4
portstatus 111, change 0, 12 Mb/s
STAT_C_CONNECTION = 0 STAT_CONNECTION = 1  USB_PORT_STAT_ENABLE 0
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x4 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
scrlen = 0
 req->length = 0
Len is 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
SPEED = HIGHSPEED
scrlen = 4
 req->length = 4
portstatus 503, change 10, 480 Mb/s
STAT_C_CONNECTION = 0 STAT_CONNECTION = 1  USB_PORT_STAT_ENABLE 1
usb_control_msg: request: 0x1, requesttype: 0x23, value 0x14 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
clear port reset change, actual port 1 status  = 0xe03
scrlen = 0
 req->length = 0
Len is 0
xhci_alloc_device: dev='usb@10000', udev=00000000bdecc680
EP STATE RUNNING.
set address 2
usb_control_msg: request: 0x5, requesttype: 0x0, value 0x2 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
Setting up addressable devices 00000000bdf1ae40
route string 0
port_num = 1
SPEED = 3
Setting Packet size = 64bytes
EP STATE RUNNING.
Successful Address Device command
xHC internal address is: 1
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x100 index 0x0 length 0x12
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=256 (0x100), index=0
EP STATE RUNNING.
start_trb 00000000bdf074c0, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 256,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 18
length_field = 18, length = 18,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x9
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=512 (0x200), index=0
EP STATE RUNNING.
start_trb 00000000bdf074f0, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 512,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 9
length_field = 9, length = 9,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x29
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=512 (0x200), index=0
EP STATE RUNNING.
start_trb 00000000bdf07520, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 512,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 41
length_field = 41, length = 41,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
get_conf_no 0 Result 41, wLength 41
if 0, ep 0
if 0, ep 1
##EP epmaxpacketin[1] = 1
set configuration 1
usb_control_msg: request: 0x9, requesttype: 0x0, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
EP STATE RUNNING.
Successful Configure Endpoint command
req=9 (0x9), type=0 (0x0), value=1 (0x1), index=0
EP STATE RUNNING.
start_trb 00000000bdf07550, start_cycle 1
req->requesttype = 0, req->request = 9,le16_to_cpu(req->value) = 1,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
new device strings: Mfr=0, Product=0, SerialNumber=0
Manufacturer
Product
SerialNumber
usb_hub_post_probe
usb_control_msg: request: 0x6, requesttype: 0xA0, value 0x2900 index 0x0 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=160 (0xa0), value=10496 (0x2900), index=0
EP STATE RUNNING.
start_trb 00000000bdf07570, start_cycle 1
req->requesttype = 160, req->request = 6,le16_to_cpu(req->value) = 10496,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0xA0, value 0x2900 index 0x0 length 0x9
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=160 (0xa0), value=10496 (0x2900), index=0
EP STATE RUNNING.
start_trb 00000000bdf075a0, start_cycle 1
req->requesttype = 160, req->request = 6,le16_to_cpu(req->value) = 10496,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 9
length_field = 9, length = 9,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
4 ports detected
individual port power switching
standalone hub
individual port over-current protection
usb_control_msg: request: 0xB, requesttype: 0x1, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=11 (0xb), type=1 (0x1), value=1 (0x1), index=0
EP STATE RUNNING.
start_trb 00000000bdf075d0, start_cycle 1
req->requesttype = 1, req->request = 11,le16_to_cpu(req->value) = 1,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
TT per port
TT requires at most 8 FS bit times (666 ns)
power on to power good time: 100ms
hub controller current requirement: 1mA
port 1 is removable
port 2 is removable
port 3 is removable
port 4 is removable
usb_control_msg: request: 0x0, requesttype: 0xA0, value 0x0 index 0x0 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=160 (0xa0), value=0 (0x0), index=0
EP STATE RUNNING.
start_trb 00000000bdf075f0, start_cycle 1
req->requesttype = 160, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
get_hub_status returned status 0, change 0
local power source is good
no over-current condition exists
xhci_update_hub_device: dev='usb@10000', udev=00000000bdefd9c0
EP STATE RUNNING.
Successful Configure Endpoint command
enabling power on all ports
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=3 (0x3), type=35 (0x23), value=8 (0x8), index=1
EP STATE RUNNING.
start_trb 00000000bdf07620, start_cycle 1
req->requesttype = 35, req->request = 3,le16_to_cpu(req->value) = 8,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
port 1 returns 0
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x2 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=3 (0x3), type=35 (0x23), value=8 (0x8), index=2
EP STATE RUNNING.
start_trb 00000000bdf07640, start_cycle 1
req->requesttype = 35, req->request = 3,le16_to_cpu(req->value) = 8,le16_to_cpu(req->index) = 2,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
port 2 returns 0
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x3 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=3 (0x3), type=35 (0x23), value=8 (0x8), index=3
EP STATE RUNNING.
start_trb 00000000bdf07660, start_cycle 1
req->requesttype = 35, req->request = 3,le16_to_cpu(req->value) = 8,le16_to_cpu(req->index) = 3,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
port 3 returns 0
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x4 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=3 (0x3), type=35 (0x23), value=8 (0x8), index=4
EP STATE RUNNING.
start_trb 00000000bdf07680, start_cycle 1
req->requesttype = 35, req->request = 3,le16_to_cpu(req->value) = 8,le16_to_cpu(req->index) = 4,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
port 4 returns 0
pgood_delay=100ms
devnum=2 poweron: query_delay=100 connect_timeout=1100
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf06080, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
devnum=1 port=2: timeout
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x4 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=4
EP STATE RUNNING.
start_trb 00000000bdf076a0, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 4,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 4 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=1
EP STATE RUNNING.
start_trb 00000000bdf076d0, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=2
EP STATE RUNNING.
start_trb 00000000bdf07700, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 2,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x3 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=3
EP STATE RUNNING.
start_trb 00000000bdf07730, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 3,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 3 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x4 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=4
EP STATE RUNNING.
start_trb 00000000bdf07760, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 4,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 4 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=1
EP STATE RUNNING.
start_trb 00000000bdf07790, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=2
EP STATE RUNNING.
start_trb 00000000bdf077c0, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 2,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x3 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=3
EP STATE RUNNING.
start_trb 00000000bdf077f0, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 3,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 3 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x4 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=4
EP STATE RUNNING.
start_trb 00000000bdf07820, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 4,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 4 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=1
EP STATE RUNNING.
start_trb 00000000bdf07850, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=2
EP STATE RUNNING.
start_trb 00000000bdf07880, start_cycle 1
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 2,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x3 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=3
EP STATE RUNNING.
start_trb 00000000bdf074c0, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 3,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 3 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x4 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=4
EP STATE RUNNING.
start_trb 00000000bdf074f0, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 4,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 4 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=1
EP STATE RUNNING.
start_trb 00000000bdf07520, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=2
EP STATE RUNNING.
start_trb 00000000bdf07550, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 2,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x3 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=3
EP STATE RUNNING.
start_trb 00000000bdf07580, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 3,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 3 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x4 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=4
EP STATE RUNNING.
start_trb 00000000bdf075b0, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 4,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 4 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=1
EP STATE RUNNING.
start_trb 00000000bdf075e0, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=2
EP STATE RUNNING.
start_trb 00000000bdf07610, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 2,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x3 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=3
EP STATE RUNNING.
start_trb 00000000bdf07640, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 3,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 3 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x4 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=4
EP STATE RUNNING.
start_trb 00000000bdf07670, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 4,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 4 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=1
EP STATE RUNNING.
start_trb 00000000bdf076a0, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 1 Status 101 Change 1
devnum=2 port=1: USB dev found
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=1
EP STATE RUNNING.
start_trb 00000000bdf076d0, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
portstatus 101, change 1, 12 Mb/s
usb_control_msg: request: 0x1, requesttype: 0x23, value 0x10 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=1 (0x1), type=35 (0x23), value=16 (0x10), index=1
EP STATE RUNNING.
start_trb 00000000bdf07700, start_cycle 0
req->requesttype = 35, req->request = 1,le16_to_cpu(req->value) = 16,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_hub_port_reset: resetting 'usb_hub' port 1...
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x4 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=3 (0x3), type=35 (0x23), value=4 (0x4), index=1
EP STATE RUNNING.
start_trb 00000000bdf07720, start_cycle 0
req->requesttype = 35, req->request = 3,le16_to_cpu(req->value) = 4,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=1
EP STATE RUNNING.
start_trb 00000000bdf07740, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
portstatus 503, change 10, 480 Mb/s
STAT_C_CONNECTION = 0 STAT_CONNECTION = 1  USB_PORT_STAT_ENABLE 1
usb_control_msg: request: 0x1, requesttype: 0x23, value 0x14 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=1 (0x1), type=35 (0x23), value=20 (0x14), index=1
EP STATE RUNNING.
start_trb 00000000bdf07770, start_cycle 0
req->requesttype = 35, req->request = 1,le16_to_cpu(req->value) = 20,le16_to_cpu(req->index) = 1,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
xhci_alloc_device: dev='usb@10000', udev=00000000bdecc680
EP STATE RUNNING.
set address 3
usb_control_msg: request: 0x5, requesttype: 0x0, value 0x3 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
Setting up addressable devices 00000000bdf1ae40
route string 1
port_num = 1
SPEED = 3
Setting Packet size = 64bytes
EP STATE RUNNING.
Successful Address Device command
xHC internal address is: 2
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x100 index 0x0 length 0x12
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=256 (0x100), index=0
EP STATE RUNNING.
start_trb 00000000bdee8e80, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 256,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 18
length_field = 18, length = 18,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x9
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=512 (0x200), index=0
EP STATE RUNNING.
start_trb 00000000bdee8eb0, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 512,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 9
length_field = 9, length = 9,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x20
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=512 (0x200), index=0
EP STATE RUNNING.
start_trb 00000000bdee8ee0, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 512,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 32
length_field = 32, length = 32,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
get_conf_no 0 Result 32, wLength 32
if 0, ep 0
if 0, ep 1
##EP epmaxpacketin[1] = 512
##EP epmaxpacketout[2] = 512
set configuration 1
usb_control_msg: request: 0x9, requesttype: 0x0, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
EP STATE RUNNING.
Successful Configure Endpoint command
req=9 (0x9), type=0 (0x0), value=1 (0x1), index=0
EP STATE RUNNING.
start_trb 00000000bdee8f10, start_cycle 1
req->requesttype = 0, req->request = 9,le16_to_cpu(req->value) = 1,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 0
length_field = 0, length = 0,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
new device strings: Mfr=1, Product=2, SerialNumber=3
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x300 index 0x0 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=768 (0x300), index=0
EP STATE RUNNING.
start_trb 00000000bdee8f30, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 768,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 255
length_field = 255, length = 255,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
USB device number 3 default language ID 0x409
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x301 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=769 (0x301), index=1033
EP STATE RUNNING.
start_trb 00000000bdee8f60, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 769,le16_to_cpu(req->index) = 1033,le16_to_cpu(req->length) = 255
length_field = 255, length = 255,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x302 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=770 (0x302), index=1033
EP STATE RUNNING.
start_trb 00000000bdee8f90, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 770,le16_to_cpu(req->index) = 1033,le16_to_cpu(req->length) = 255
length_field = 255, length = 255,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x303 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecc680, udev->dev='usb_hub', portnr=1
req=6 (0x6), type=128 (0x80), value=771 (0x303), index=1033
EP STATE RUNNING.
start_trb 00000000bdee8fc0, start_cycle 1
req->requesttype = 128, req->request = 6,le16_to_cpu(req->value) = 771,le16_to_cpu(req->index) = 1033,le16_to_cpu(req->length) = 255
length_field = 255, length = 255,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Manufacturer SanDisk
Product      Cruzer Fit
SerialNumber 4C530001280425117554
usb_control_msg: request: 0xFE, requesttype: 0xA1, value 0x0 index 0x0 length 0x1
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdef59b0, udev->dev='usb_mass_storage', portnr=1
req=254 (0xfe), type=161 (0xa1), value=0 (0x0), index=0
EP STATE RUNNING.
start_trb 00000000bdee8ff0, start_cycle 1
req->requesttype = 161, req->request = 254,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 0,le16_to_cpu(req->length) = 1
length_field = 1, length = 1,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef59b0
dev=00000000bdef59b0, pipe=c0010303, buffer=00000000bdecc300, length=31
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef59b0
dev=00000000bdef59b0, pipe=c0008383, buffer=00000000bdecc440, length=36
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef59b0
dev=00000000bdef59b0, pipe=c0008383, buffer=00000000bdecc280, length=13
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef59b0
dev=00000000bdef59b0, pipe=c0010303, buffer=00000000bdecc300, length=31
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef59b0
dev=00000000bdef59b0, pipe=c0008383, buffer=00000000bdecc280, length=13
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef59b0
dev=00000000bdef59b0, pipe=c0010303, buffer=00000000bdecc300, length=31
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef59b0
dev=00000000bdef59b0, pipe=c0008383, buffer=00000000bdecc3c0, length=8
EP STATE RUNNING.
xhci_submit_bulk_msg: dev='usb@10000', udev=00000000bdef59b0
dev=00000000bdef59b0, pipe=c0008383, buffer=00000000bdecc280, length=13
EP STATE RUNNING.
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=2
EP STATE RUNNING.
start_trb 00000000bdf07790, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 2,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 2 Status 100 Change 0
devnum=2 port=2: timeout
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x3 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=3
EP STATE RUNNING.
start_trb 00000000bdf077c0, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 3,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 3 Status 100 Change 0
devnum=2 port=3: timeout
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x4 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdefd9c0, udev->dev='usb_hub', portnr=1
req=0 (0x0), type=163 (0xa3), value=0 (0x0), index=4
EP STATE RUNNING.
start_trb 00000000bdf077f0, start_cycle 0
req->requesttype = 163, req->request = 0,le16_to_cpu(req->value) = 0,le16_to_cpu(req->index) = 4,le16_to_cpu(req->length) = 4
length_field = 4, length = 4,xhci_td_remainder(length) = 0 , TRB_INTR_TARGET(0) = 0
Port 4 Status 100 Change 0
devnum=2 port=4: timeout
3 USB Device(s) found
scanning bus 1 for devices... xhci_alloc_device: dev='usb@10000', udev=00000000bdecd600
set address 1
usb_control_msg: request: 0x5, requesttype: 0x0, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_REQ_SET_ADDRESS
scrlen = 0
 req->length = 0
Len is 0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x100 index 0x0 length 0x12
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_DEVICE request
scrlen = 18
 req->length = 18
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x9
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_CONFIG config
scrlen = 25
 req->length = 9
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x200 index 0x0 length 0x1F
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_CONFIG config
scrlen = 25
 req->length = 31
get_conf_no 0 Result 25, wLength 31
if 0, ep 0
##EP epmaxpacketin[1] = 8
set configuration 1
usb_control_msg: request: 0x9, requesttype: 0x0, value 0x1 index 0x0 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
scrlen = 0
 req->length = 0
Len is 0
new device strings: Mfr=1, Product=2, SerialNumber=0
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x300 index 0x0 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_STRING config
scrlen = 4
 req->length = 255
USB device number 1 default language ID 0x409
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x301 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_STRING config
scrlen = 14
 req->length = 255
usb_control_msg: request: 0x6, requesttype: 0x80, value 0x302 index 0x409 length 0xFF
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdecd600, udev->dev='usb@10000', portnr=0
USB_DT_STRING config
scrlen = 42
 req->length = 255
Manufacturer U-Boot
Product      XHCI Host Controller
SerialNumber
usb_hub_post_probe
usb_control_msg: request: 0x6, requesttype: 0xA0, value 0x2A00 index 0x0 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
USB_DT_HUB config
scrlen = 8
 req->length = 4
usb_control_msg: request: 0x6, requesttype: 0xA0, value 0x2A00 index 0x0 length 0xC
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
USB_DT_HUB config
scrlen = 8
 req->length = 12
2 ports detected
individual port power switching
standalone hub
individual port over-current protection
TT requires at most 8 FS bit times (666 ns)
power on to power good time: 20ms
hub controller current requirement: 0mA
port 1 is removable
port 2 is removable
usb_control_msg: request: 0x0, requesttype: 0xA0, value 0x0 index 0x0 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 2
 req->length = 4
get_hub_status returned status 1, change 902
local power source is lost (inactive)
no over-current condition exists
xhci_update_hub_device: dev='usb@10000', udev=00000000bdf030c0
enabling power on all ports
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x1 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 0
 req->length = 0
Len is 0
port 1 returns 0
usb_control_msg: request: 0x3, requesttype: 0x23, value 0x8 index 0x2 length 0x0
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 0
 req->length = 0
Len is 0
port 2 returns 0
pgood_delay=20ms
devnum=1 poweron: query_delay=100 connect_timeout=1100
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x2 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 2 Status 100 Change 0
devnum=1 port=2: timeout
usb_control_msg: request: 0x0, requesttype: 0xA3, value 0x0 index 0x1 length 0x4
xhci_submit_control_msg: dev='usb@10000', udev=00000000bdf030c0, udev->dev='usb_hub', portnr=0
scrlen = 4
 req->length = 4
Port 1 Status 100 Change 0
devnum=1 port=1: timeout
1 USB Device(s) found
       scanning usb for storage devices... 1 Storage Device(s) found
=>

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    Due to the nature of this issue, it is difficult to debug it remotely. As discussed offline, please send me the SMSC2514 hub and the thumb drive, so that I can debug the issue locally. The TI local FAE can help for the shipment.

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    Since this issue is at low priority. I will close this thread for now. We will re-visit it once I received the USB devices for debugging.

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    Thanks for providing me the USB hub and thumb drives. It helped me quickly locating the root cause of the issue.

    In your U-Boot env, before 'usb start' or 'usb reset' command, please set 'usb_pgood_delay' which is a delay (in ms) waiting for the hub port power to be stable. I have to set it to 600ms in my setup to get all three thumb drives enumerated properly.

    => setenv usb_pgood_delay 600

  • 0 in reply to Bin Liu
    Intellectual 320 points

    First of all, thank you very much for your support,

    There is also a question about how to determine the delay time? For the USB hub port, we test more U-disks. We found that some U-disks have a delay time of 600ms which can be recognized successfully, but some of them need a longer time.(example 900ms or 1200ms)，So, How to determine the set delay time? Is there any explanation?

    Thank you

  • 0 in reply to user6115282
    TI__Guru**** 167581 points

    Hi,

    184         /*                                                                      
185          * Wait for power to become stable,                                     
186          * plus spec-defined max time for device to connect                     
187          * but allow this time to be increased via env variable as some         
188          * devices break the spec and require longer warm-up times              
189          */                                                                     
190         env = env_get("usb_pgood_delay");

    As mentioned in the comments in the U-Boot code above in common/usb_hub.c, adding the delay is due to some USB devices are out of the Specs. So I am not sure if there is a way to determine the delay if you want to support all the USB devices on the market.

    But if you search for 'usb_pgood_delay' in the U-Boot source code, you can find there are a few platforms which set the delay default value to 2000ms.