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.

AM3358SK with Multiple BT Dongles connection via USB Hub

Other Parts Discussed in Thread: AM3358, TMDSSK3358, CC2650
Hello Sitara team,
Our customer is checking the working of multiple USB BT dongles connected to the AM3358 Starter Kit. However, they are seeing the problem  that some dongles cannot boot up correctly. Thus, I would like to know whether anyone has a same experience about AM335x board or not. 
Please see the phenomenon below and if you have any comment or advice on this problem, it would be helpful.
[Phenomenon]
1. They connect the self-powered USB Hub (including AC power cable) to the USB port of AM3358SK and connect four USB BT dongles (BT4.0 supported) to each ports of USB Hub.
2. After Linux boots up, if they issue "hciconfig hci* up", some of dongles cannot be up and "timeout" is displayed on the terminal.
-EVM is TMDSSK3358 - AM335x Starter Kit
-Linux kernel is 3.12.10 and user land is their own distribution package.
 (They modified the source code related with Ethernet a little but they do not think this is related with this issue.
-BT USB Dongle is bt-micro4 made by Planex Communications.
Block Diagram
 AM3358SK --- USB Hub  ------ BT Dongle
                                         |           |-- BT Dongle
                              AC Power     |-- BT Dongle
                                                      -- BT Dongle
Display on the terminal
-------------------------------------------------------------------
-bash-3.2# hciconfig
hci3:   Type: BR/EDR  Bus: USB
        BD Address: 00:1B:DC:06:45:A1  ACL MTU: 310:10  SCO MTU: 64:8
        DOWN
        RX bytes:547 acl:0 sco:0 events:27 errors:0
        TX bytes:384 acl:0 sco:0 commands:27 errors:0
hci2:   Type: BR/EDR  Bus: USB
        BD Address: 00:1B:DC:06:45:A4  ACL MTU: 310:10  SCO MTU: 64:8
        DOWN
        RX bytes:547 acl:0 sco:0 events:27 errors:0
        TX bytes:384 acl:0 sco:0 commands:27 errors:0
hci1:   Type: BR/EDR  Bus: USB
        BD Address: 00:1B:DC:06:45:79  ACL MTU: 310:10  SCO MTU: 64:8
        UP RUNNING PSCAN
        RX bytes:1118 acl:0 sco:0 events:58 errors:0
        TX bytes:1032 acl:0 sco:0 commands:58 errors:0
hci0:   Type: BR/EDR  Bus: USB
        BD Address: 00:1B:DC:06:45:AD  ACL MTU: 310:10  SCO MTU: 64:8
        DOWN
        RX bytes:492 acl:0 sco:0 events:20 errors:0
        TX bytes:327 acl:0 sco:0 commands:25 errors:3
-bash-3.2# hciconfig hci0 up                         <--- Normally, after this command is issued and if there is no message, it is booted up correctly. So, this means hci0 is working correctly. 
-bash-3.2# hciconfig hci2 up
[  456.527359] Bluetooth: hci2 command 0x2007 tx timeout   *
[  458.527338] Bluetooth: hci2 command 0x200f tx timeout   *
[  460.527350] Bluetooth: hci2 command 0x2001 tx timeout   *
[  462.527343] Bluetooth: hci2 command 0x1002 tx timeout   *
Can't init device hci2: Connection timed out (110)         * Due to time out, hci2 cannot be up correctly.
[  464.527372] Bluetooth: hci2 command tx timeout          *
-bash-3.2#
-------------------------------------------------------------------
Additional information;
- If they connect one BT dongle to USB port of AM3358SK (without USB Hub), it is working correctly. (They succeeded by 10 of 10 times)
- If they connect two BT dongle to USB hub connected to AM3358SK,  these dongles are also working correctly in most cases.
 (They saw "Bluetooth: hci1 command 0x080f tx timeout" by 1 of 10 times but after that, they confirmed that these dongles were up correctly.) 
  • Hi,

    I will forward this to the USB experts.

  • It seems you are using AM335x AMSDK 7.0, please first apply the patch #7.1 ~ #7.7 in wiki http://processors.wiki.ti.com/index.php/Sitara_Linux_MUSB_Issues, and try the test again.

    If the issue still exists, please try to disable CPPI DMA in menuconfig and test again, and let me know the results.

  • Bin-san,

    Thank you for your comment.
    I will ask our customer to try to apply the patches and test again. I will let you know, if I can get the feedback from them.

    Best Regards,
    Nobuyoshi Arai
  • Bin-san,

    I got a feedback from our customer .
    They tried to apply all patches you suggested except for #7.1 and change the MUSB DMA mode under USB support of menuconfig (TI CPPI -> Disable DMA(always use PIO)).
    However, they could not see any improvement on this problem.

    If you have any other idea they should try, it would be helpful.


    Note : Regarding the reason why they did not apply the patch #7.1, USB gadget driver is failed to load while it is built into the kernel image., there is no specified node on their system and their kernel version.

    Best Regards,
    Nobu Arai
  • Arai-san,

    Please try to test with PIO mode. If PIO mode does not have the issue, then very likely this is a CPPI DMA issue, we can focus on CPPI debugging.

    It is okay to not test with patch #7.1, which should not be related.

  • Nobuyoshi Arai44 said:
    They tried to apply all patches you suggested except for #7.1 and change the MUSB DMA mode under USB support of menuconfig (TI CPPI -> Disable DMA(always use PIO)).

    Sorry, I not sure if you have tried PIO mode or not. If not yet, please test the PIO mode. Or if PIO mode has the same issue, please provide the log of the BT device descriptors, you can use the following command to get the log.

    lsusb -v -d <vid:pid>

  • Bin-san,

    Thank you for your comment.
    They tried to test the PIO mode but they could not see any improvement. So, we are asking them to provide the log of the BT device descriptors.
    If we get the information, I will let you know.

    Nobuyoshi Arai
  • Bin-san,

     

    I got an information about the device descriptors (result of lsusb command).

    Please see the attached file.

     

    If you have any comment or advice for this problem, please let us know.

     

    Thank you for your help.

     

    Nobu Arai

     

     

    Block Diagram of this result;

     

    ----------

    | AM3358SK |

    -----------

         |USB port

         |

    --------

    | USBHub |===(Power)

    --------

    ||||

    Bluetooth 4.0(BLE) dongle x4

    lsusb_result-UTF8.txt
    -bash-3.2# lsusb -v -d 0x0a12:0x0001                                                                   
    
    Bus 002 Device 003: ID 0a12:0001 Cambridge Silicon Radio, Ltd Bluetooth Dongle (HCI mode)
    Device Descriptor:
      bLength                18
      bDescriptorType         1
      bcdUSB               2.00
      bDeviceClass          224 Wireless
      bDeviceSubClass         1 Radio Frequency
      bDeviceProtocol         1 Bluetooth
      bMaxPacketSize0        64
      idVendor           0x0a12 Cambridge Silicon Radio, Ltd
      idProduct          0x0001 Bluetooth Dongle (HCI mode)
      bcdDevice           88.91
      iManufacturer           0 
      iProduct                2 
      iSerial                 0 
      bNumConfigurations      1
      Configuration Descriptor:
        bLength                 9
        bDescriptorType         2
        wTotalLength          177
        bNumInterfaces          2
        bConfigurationValue     1
        iConfiguration          0 
        bmAttributes         0xe0
          Self Powered
          Remote Wakeup
        MaxPower              100mA
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        0
          bAlternateSetting       0
          bNumEndpoints           3
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x81  EP 1 IN
            bmAttributes            3
              Transfer Type            Interrupt
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0010  1x 16 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x02  EP 2 OUT
            bmAttributes            2
              Transfer Type            Bulk
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0040  1x 64 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x82  EP 2 IN
            bmAttributes            2
              Transfer Type            Bulk
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0040  1x 64 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       0
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0000  1x 0 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0000  1x 0 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       1
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0009  1x 9 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0009  1x 9 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       2
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0011  1x 17 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0011  1x 17 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       3
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0019  1x 25 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0019  1x 25 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       4
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0021  1x 33 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0021  1x 33 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       5
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0031  1x 49 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0031  1x 49 bytes
            bInterval               1
    Device Status:     0xfef4
      (Bus Powered)
      Test Mode
      Debug Mode
    
    Bus 002 Device 004: ID 0a12:0001 Cambridge Silicon Radio, Ltd Bluetooth Dongle (HCI mode)
    Device Descriptor:
      bLength                18
      bDescriptorType         1
      bcdUSB               2.00
      bDeviceClass          224 Wireless
      bDeviceSubClass         1 Radio Frequency
      bDeviceProtocol         1 Bluetooth
      bMaxPacketSize0        64
      idVendor           0x0a12 Cambridge Silicon Radio, Ltd
      idProduct          0x0001 Bluetooth Dongle (HCI mode)
      bcdDevice           88.91
      iManufacturer           0 
      iProduct                2 
      iSerial                 0 
      bNumConfigurations      1
      Configuration Descriptor:
        bLength                 9
        bDescriptorType         2
        wTotalLength          177
        bNumInterfaces          2
        bConfigurationValue     1
        iConfiguration          0 
        bmAttributes         0xe0
          Self Powered
          Remote Wakeup
        MaxPower              100mA
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        0
          bAlternateSetting       0
          bNumEndpoints           3
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x81  EP 1 IN
            bmAttributes            3
              Transfer Type            Interrupt
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0010  1x 16 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x02  EP 2 OUT
            bmAttributes            2
              Transfer Type            Bulk
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0040  1x 64 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x82  EP 2 IN
            bmAttributes            2
              Transfer Type            Bulk
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0040  1x 64 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       0
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0000  1x 0 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0000  1x 0 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       1
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0009  1x 9 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0009  1x 9 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       2
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0011  1x 17 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0011  1x 17 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       3
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0019  1x 25 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0019  1x 25 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       4
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0021  1x 33 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0021  1x 33 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       5
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0031  1x 49 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0031  1x 49 bytes
            bInterval               1
    Device Status:     0x000a
      (Bus Powered)
      Remote Wakeup Enabled
    
    Bus 002 Device 005: ID 0a12:0001 Cambridge Silicon Radio, Ltd Bluetooth Dongle (HCI mode)
    Device Descriptor:
      bLength                18
      bDescriptorType         1
      bcdUSB               2.00
      bDeviceClass          224 Wireless
      bDeviceSubClass         1 Radio Frequency
      bDeviceProtocol         1 Bluetooth
      bMaxPacketSize0        64
      idVendor           0x0a12 Cambridge Silicon Radio, Ltd
      idProduct          0x0001 Bluetooth Dongle (HCI mode)
      bcdDevice           88.91
      iManufacturer           0 
      iProduct                2 
      iSerial                 0 
      bNumConfigurations      1
      Configuration Descriptor:
        bLength                 9
        bDescriptorType         2
        wTotalLength          177
        bNumInterfaces          2
        bConfigurationValue     1
        iConfiguration          0 
        bmAttributes         0xe0
          Self Powered
          Remote Wakeup
        MaxPower              100mA
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        0
          bAlternateSetting       0
          bNumEndpoints           3
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x81  EP 1 IN
            bmAttributes            3
              Transfer Type            Interrupt
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0010  1x 16 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x02  EP 2 OUT
            bmAttributes            2
              Transfer Type            Bulk
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0040  1x 64 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x82  EP 2 IN
            bmAttributes            2
              Transfer Type            Bulk
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0040  1x 64 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       0
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0000  1x 0 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0000  1x 0 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       1
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0009  1x 9 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0009  1x 9 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       2
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0011  1x 17 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0011  1x 17 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       3
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0019  1x 25 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0019  1x 25 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       4
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0021  1x 33 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0021  1x 33 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       5
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0031  1x 49 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0031  1x 49 bytes
            bInterval               1
    Device Status:     0x000a
      (Bus Powered)
      Remote Wakeup Enabled
    
    Bus 002 Device 006: ID 0a12:0001 Cambridge Silicon Radio, Ltd Bluetooth Dongle (HCI mode)
    Device Descriptor:
      bLength                18
      bDescriptorType         1
      bcdUSB               2.00
      bDeviceClass          224 Wireless
      bDeviceSubClass         1 Radio Frequency
      bDeviceProtocol         1 Bluetooth
      bMaxPacketSize0        64
      idVendor           0x0a12 Cambridge Silicon Radio, Ltd
      idProduct          0x0001 Bluetooth Dongle (HCI mode)
      bcdDevice           88.91
      iManufacturer           0 
      iProduct                2 
      iSerial                 0 
      bNumConfigurations      1
      Configuration Descriptor:
        bLength                 9
        bDescriptorType         2
        wTotalLength          177
        bNumInterfaces          2
        bConfigurationValue     1
        iConfiguration          0 
        bmAttributes         0xe0
          Self Powered
          Remote Wakeup
        MaxPower              100mA
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        0
          bAlternateSetting       0
          bNumEndpoints           3
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x81  EP 1 IN
            bmAttributes            3
              Transfer Type            Interrupt
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0010  1x 16 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x02  EP 2 OUT
            bmAttributes            2
              Transfer Type            Bulk
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0040  1x 64 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x82  EP 2 IN
            bmAttributes            2
              Transfer Type            Bulk
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0040  1x 64 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       0
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0000  1x 0 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0000  1x 0 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       1
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0009  1x 9 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0009  1x 9 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       2
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0011  1x 17 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0011  1x 17 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       3
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0019  1x 25 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0019  1x 25 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       4
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0021  1x 33 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0021  1x 33 bytes
            bInterval               1
        Interface Descriptor:
          bLength                 9
          bDescriptorType         4
          bInterfaceNumber        1
          bAlternateSetting       5
          bNumEndpoints           2
          bInterfaceClass       224 Wireless
          bInterfaceSubClass      1 Radio Frequency
          bInterfaceProtocol      1 Bluetooth
          iInterface              0 
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x03  EP 3 OUT
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0031  1x 49 bytes
            bInterval               1
          Endpoint Descriptor:
            bLength                 7
            bDescriptorType         5
            bEndpointAddress     0x83  EP 3 IN
            bmAttributes            1
              Transfer Type            Isochronous
              Synch Type               None
              Usage Type               Data
            wMaxPacketSize     0x0031  1x 49 bytes
            bInterval               1
    Device Status:     0x000a
      (Bus Powered)
      Remote Wakeup Enabled
    -bash-3.2# 
    -bash-3.2# 

  • I see the descriptors has many Isoch endpoints, and am wondering if it run out of resource. Can you please add the following patch to the kernel and re-run the test? and let me know if the debug message from this patch shows on the console or not when the test failed.

    diff --git a/drivers/usb/musb/musb_host.c b/drivers/usb/musb/musb_host.c
    index 85a6c1d..03b86bd 100644
    --- a/drivers/usb/musb/musb_host.c
    +++ b/drivers/usb/musb/musb_host.c
    @@ -2049,6 +2049,8 @@ static int musb_schedule(
                                    (USB_SPEED_HIGH == qh->dev->speed) ? 8 : 4;
                    goto success;
            } else if (best_end < 0) {
    +               dev_err(musb->controller, "hwep no slot for %d, in %d\n",
    +                               qh->type, is_in);
                    return -ENOSPC;
            }
    

    Do you by any chance have a protocol analyzer to capture a bus trace including the whole enumeration and test failure?

  • Bin-san,

    We got a feedback from the customer. Please see below.

      

    Bin Liu said:

    I see the descriptors has many Isoch endpoints, and am wondering if it run out of resource. Can you please add the following patch to the kernel and re-run the test? and let me know if the debug message from this patch shows on the console or not when the test failed.

    They tried to patch and checked if the dev_err statement is output or not.

    When they issue hciconfig hci* and the connection is failed, they checked the out of dmesg. As a result, they confirmed that the dev_err statement was not output.

    Bin Liu said:

    Do you by any chance have a protocol analyzer to capture a bus trace including the whole enumeration and test failure?

    They do not have a USB bus analyzer but they are checking if it can borrow it.

    In the past, they found the data that they enabled the USB monitor and checked the USB accessing condition by Wireshark.

    This is the data that was taken with their own AM3358 board (not AM3358SK). But according to this data, they confirmed that the interrupt transfer is done when the timeout happens. In the place that the timeout happens, it looks the timeout happens without any packet transfer communication.

    In addition to that, there is no trace of the Isochronous transferring in the place of hciconfig command.

    if you have any comment on this or any advice, it would be helpful for us. 

     

    Best Regards,

    Nobu Arai

  • Nobuyoshi Arai44 said:
    When they issue hciconfig hci* and the connection is failed, they checked the out of dmesg. As a result, they confirmed that the dev_err statement was not output.

    Thanks for confirming on this.

    Nobuyoshi Arai44 said:
    But according to this data, they confirmed that the interrupt transfer is done when the timeout happens.

    What is this timeout? in Wireshark trace, or kernel network layer drivers, or USB drivers?

    Nobuyoshi Arai44 said:
    if you have any comment on this or any advice, it would be helpful for us. 

    I cannot think of any specific pointer for this issue, but I would recommend to go aheard debugging the kernel to see where causes the tx timeout. It is better to debug with MUSB CPPI DMA disabled, which can make the debug much easier.

  • Hello Bin-san,

     

    I am sorry that it has been a long time. But we could reproduce the problem in our side.

    So, may I ask any questions to you regarding this problem?

     

    We tried to use AM3358SK + 1 USB HUB + 4 USB BT Dongles(BT-Micro4 by Planex) and we saw one of the BT was time-out.

    We also tried to use Beagle Bone Black and could see same problem.

    But if we use the Ubuntu PC (Lenovo T510), we could not see this problem.

     

    We checked the difference between Sitara and Leovo PC and found that the following messages are shown in the Sitara environment.

    *** device v0a12 p0001 is not supported ***

     

    We do not know this message is related with the problem. But I would like you to ask your opinion and the reason why this message is output.

     

    - First, do you think this message is related with this problem or is this no problem?

    - Do you have any idea to prevent this message from being shown?

      For example, any kernel configuration is needed or any additional driver is needed.

      If you have any advices for remove this message, it would be helpful.

     

    Best Regards,

    Nobuyoshi Arai

     

     

    Please see the dmesg output below when the USB BT dongles are input to the USB ports (via USB HUB).

     

    * In the case of using AM3358 starter kit:Processor SDK 01.00.00.03(linux-3.14.43) *

    <pre>

    [   34.948478] musb-hdrc musb-hdrc.1.auto: otg: usb_otg_kick_fsm: invalid host/gadget device

    [   35.068316] usb 2-1: new high-speed USB device number 2 using musb-hdrc

    [   35.208626] usb 2-1: New USB device found, idVendor=0409, idProduct=0059

    [   35.215412] usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0

    [   35.230383] hub 2-1:1.0: USB hub found

    [   35.234330] hub 2-1:1.0: 4 ports detected

    [   35.518325] usb 2-1.1: new full-speed USB device number 3 using musb-hdrc

    [   35.665562] usb 2-1.1: device v0a12 p0001 is not supported              <------

    [   35.671193] usb 2-1.1: New USB device found, idVendor=0a12, idProduct=0001

    [   35.678126] usb 2-1.1: New USB device strings: Mfr=0, Product=2, SerialNumber=0

    [   35.685516] usb 2-1.1: Product: CSR8510 A10

    [   35.808780] usb 2-1.2: new full-speed USB device number 4 using musb-hdrc

    [   35.955532] usb 2-1.2: device v0a12 p0001 is not supported              <------

    [   35.961110] usb 2-1.2: New USB device found, idVendor=0a12, idProduct=0001

    [   35.968023] usb 2-1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0

    [   35.975390] usb 2-1.2: Product: CSR8510 A10

    [   36.088298] usb 2-1.3: new full-speed USB device number 5 using musb-hdrc

    [   36.235756] usb 2-1.3: device v0a12 p0001 is not supported              <------

    [   36.241386] usb 2-1.3: New USB device found, idVendor=0a12, idProduct=0001

    [   36.248348] usb 2-1.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0

    [   36.255722] usb 2-1.3: Product: CSR8510 A10

    [   36.388343] usb 2-1.4: new full-speed USB device number 6 using musb-hdrc

    [   36.535628] usb 2-1.4: device v0a12 p0001 is not supported              <------

    [   36.541217] usb 2-1.4: New USB device found, idVendor=0a12, idProduct=0001

    [   36.548130] usb 2-1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0

    [   36.555493] usb 2-1.4: Product: CSR8510 A10

    </pre>

     

    * In the case of using Beagle Bone Black Ubuntu14.04 *

    <pre>

    [  642.628268] usb 2-1: new high-speed USB device number 12 using musb-hdrc

    [  642.768523] usb 2-1: New USB device found, idVendor=0409, idProduct=0059

    [  642.768572] usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0

    [  642.770595] hub 2-1:1.0: USB hub found

    [  642.774702] hub 2-1:1.0: 4 ports detected

    [  643.048386] usb 2-1.1: new full-speed USB device number 13 using musb-hdrc

    [  643.195222] usb 2-1.1: device v0a12 p0001 is not supported              <------

    [  643.201135] usb 2-1.1: New USB device found, idVendor=0a12, idProduct=0001

    [  643.201175] usb 2-1.1: New USB device strings: Mfr=0, Product=2, SerialNumber=0

    [  643.201206] usb 2-1.1: Product: CSR8510 A10

    [  643.308244] usb 2-1.2: new full-speed USB device number 14 using musb-hdrc

    [  643.435131] usb 2-1.2: device v0a12 p0001 is not supported              <------

    [  643.441036] usb 2-1.2: New USB device found, idVendor=0a12, idProduct=0001

    [  643.441056] usb 2-1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0

    [  643.441073] usb 2-1.2: Product: CSR8510 A10

    [  643.548245] usb 2-1.3: new full-speed USB device number 15 using musb-hdrc

    [  643.695072] usb 2-1.3: device v0a12 p0001 is not supported              <------

    [  643.700947] usb 2-1.3: New USB device found, idVendor=0a12, idProduct=0001

    [  643.700968] usb 2-1.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0

    [  643.700984] usb 2-1.3: Product: CSR8510 A10

    [  643.808290] usb 2-1.4: new full-speed USB device number 16 using musb-hdrc

    [  643.955179] usb 2-1.4: device v0a12 p0001 is not supported              <------

    [  643.961052] usb 2-1.4: New USB device found, idVendor=0a12, idProduct=0001

    [  643.961073] usb 2-1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0

    [  643.961089] usb 2-1.4: Product: CSR8510 A10 </pre>

     

     

     

     

     

    * There is no prpblem in the case of using UbuntuPC(lenovo T510)ubuntu14.04 LTS(3.13.0-32-generic)*

    <pre>

    [  375.399951] usb 1-1.1: new high-speed USB device number 11 using ehci-pci

    [  375.492206] usb 1-1.1: New USB device found, idVendor=0409, idProduct=0059

    [  375.492212] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0

    [  375.492637] hub 1-1.1:1.0: USB hub found

    [  375.492777] hub 1-1.1:1.0: 4 ports detected

    [  375.779875] usb 1-1.1.1: new full-speed USB device number 12 using ehci-pci

    [  375.915854] usb 1-1.1.1: New USB device found, idVendor=0a12, idProduct=0001

    [  375.915860] usb 1-1.1.1: New USB device strings: Mfr=0, Product=2, SerialNumber=0

    [  375.915864] usb 1-1.1.1: Product: CSR8510 A10

    [  376.003816] usb 1-1.1.2: new full-speed USB device number 13 using ehci-pci

    [  376.139749] usb 1-1.1.2: New USB device found, idVendor=0a12, idProduct=0001

    [  376.139756] usb 1-1.1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0

    [  376.139760] usb 1-1.1.2: Product: CSR8510 A10

    [  376.227655] usb 1-1.1.3: new full-speed USB device number 14 using ehci-pci

    [  376.363942] usb 1-1.1.3: New USB device found, idVendor=0a12, idProduct=0001

    [  376.363949] usb 1-1.1.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0

    [  376.363953] usb 1-1.1.3: Product: CSR8510 A10 [  376.451554] usb 1-1.1.4: new full-speed USB device number 15 using ehci-pci

    [  376.592094] usb 1-1.1.4: New USB device found, idVendor=0a12, idProduct=0001

    [  376.592100] usb 1-1.1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0

    [  376.592104] usb 1-1.1.4: Product: CSR8510 A10

    </pre>

  • Nobuyoshi Arai44 said:
    - First, do you think this message is related with this problem or is this no problem?

    I don't think this message is related.

    Nobuyoshi Arai44 said:
    - Do you have any idea to prevent this message from being shown?

    https://e2e.ti.com/support/arm/sitara_arm/f/791/t/352059

  • Hello Bin-san,

     

    Thank you for the comment.

     

    I understand this message (device v0a12 p0001 is not supported) is not related with this problem.

    We tried to test with disabling CONFIG_USB_OTG for confirmation and checked that the message is disappeared but the HCI timeout problem is still existing.

    (We also disabled CONFIG_USB_TI_CPPI41_DMA.)

     

     

    By the way, we also tried the same test with Raspberry Pi2 (Ubuntu 14.04 LTS, kernel3.18.0.20-rpl2) but we did not see the same problem.

    On the other hand, we are seeing the problem with Beagle Bone Black (Ubuntu 14.04 LTS, kernel3.14.4.1-bone-armhf.com).

     

    Though the kernel version and kernel config are different from BBB, it looks Raspberry Pi2 does not have this problem.

    So, it may be considered that this problem may not be related with software (or kernel, driver) but any hardware issue.

     

    In our side, we do not have a USB analyzer and cannot monitor the USB communication.

    But if it is possible for you, we would like you to give any opinion for this problem or any advices what we can do in our side to fix this problem.

    I am sorry for this type of question, but it would be helpful  if you can give any advice or comment for this problem.

     

    Best Regards,

    Nobuyoshi Arai 

  • (Deleted the incorrect post.)

  • Hello Bin-san,

    Thank you for your comment. But since we do not know your answer well, please let me add my comments below.

    >> We don't have much progress yet, the only thing we understood is that at the failure point the CP2108 just decides to stop responding to host's IN tokens, but not sure what causes CP2108 malfunction.

    We are not using the CP2108 in this experiment. Would you please explain more about this or do you confuse our issue with another issue?


    >> Meanwhile, can you please try to test with SDK7.0 or SDK6.0 kernel to see if the issue happens? If so, we know at least it is likely to be a sw issue.

    We have not tested with older SDKs but our customer had already tried with SDK7.0 and had seen this problem.


    Best Regards,
    Nobuyoshi Arai
  • Nobuyoshi-san,

    Nobuyoshi Arai44 said:
    We are not using the CP2108 in this experiment. Would you please explain more about this or do you confuse our issue with another issue?

    I am sorry for misleading. The CP2108 was for another usb-uart issue I have been investigating. I was trying to delete my previous response right after noticed the wrong information.

    Will you use other high-speed devices other than the hub and the BT dongles? If not, we can try to force AM335x USB host port to full-speed to see if the issue still exist.

  • Hello Bin-san,

     

    Thank you for the comment.

     

    >> Will you use other high-speed devices other than the hub and the BT dongles? If not, we can try to force AM335x USB host port to full-speed to see if the issue still exist.

     

     

    Would you please tell us how to configure to force the USB host port to Full-speed?

     

    We referred to the wiki [To force musb to full-speed] and tried to force it to Full-speed mode. But we could not set it well.

    http://processors.wiki.ti.com/index.php/UsbgeneralpageLinux-v3p1

    Thus, we would like to know how to force AM335x USB host port to full-speed. Please see our result and if you have any comment on this, it would be helpful.

     

    Best Regards,

    Nobuyoshi Arai

     

     

     

    * *kernel option*

    We did not change anything from tisdk_am335x-evm_defconfig.

    <pre>

    Kernel hacking  --->  Compile-time checks and compiler options  --->  [*] Debug Filesystem Kernel hacking  --->  -*- Kernel debugging </pre>

     

    * *.config*

    <pre>

    CONFIG_DEBUG_FS=y

    CONFIG_DEBUG_KERNEL=y

    </pre>

     

     

    * *board operation*

    To access to /sys/kernel/debug/testmode becomes“Permission denied”and we could not execute the procedure written in wiki.

     

    <pre>

    root@am335x-evm:~# mount

    rootfs on / type rootfs (rw)

    /dev/root on / type ext4 (rw,relatime,data=ordered) devtmpfs on /dev type devtmpfs (rw,relatime,size=112536k,nr_inodes=28134,mode=755)

    proc on /proc type proc (rw,relatime)

    sysfs on /sys type sysfs (rw,relatime)

    debugfs on /sys/kernel/debug type debugfs (rw,relatime) tmpfs on /run type tmpfs (rw,nosuid,nodev,mode=755) tmpfs on /var/volatile type tmpfs (rw,relatime,size=51200k)

    /dev/mmcblk0p1 on /run/media/mmcblk0p1 type vfat (rw,relatime,fmask=0022,dmask=0022,codepage=437,iocharset=iso8859-1,shortname=mixed,errors=remount-ro)

    devpts on /dev/pts type devpts (rw,relatime,gid=5,mode=620)

    root@am335x-evm:~#

    root@am335x-evm:~#

    root@am335x-evm:~# echo "force full-speed" > /sys/kernel/debug/testmode

    -sh: /sys/kernel/debug/testmode: Permission denied

    root@am335x-evm:~#

    root@am335x-evm:~#

    root@am335x-evm:~#

    root@am335x-evm:~# umount /sys/kernel/debug/

    root@am335x-evm:~# mount -t debugfs none /sys/kernel/debug/

    root@am335x-evm:~# mount rootfs on / type rootfs (rw) /dev/root on / type ext4 (rw,relatime,data=ordered) devtmpfs on /dev type devtmpfs (rw,relatime,size=112536k,nr_inodes=28134,mode=755)

    proc on /proc type proc (rw,relatime)

    sysfs on /sys type sysfs (rw,relatime)

    tmpfs on /run type tmpfs (rw,nosuid,nodev,mode=755) tmpfs on /var/volatile type tmpfs (rw,relatime,size=51200k)

    /dev/mmcblk0p1 on /run/media/mmcblk0p1 type vfat (rw,relatime,fmask=0022,dmask=0022,codepage=437,iocharset=iso8859-1,shortname=mixed,errors=remount-ro)

    devpts on /dev/pts type devpts (rw,relatime,gid=5,mode=620) none on /sys/kernel/debug type debugfs (rw,relatime)

    root@am335x-evm:~#

    root@am335x-evm:~# echo "force full-speed" > /sys/kernel/debug/testmode

    -sh: /sys/kernel/debug/testmode: Permission denied

    root@am335x-evm:~#

    </pre>

  • Nobuyoshi-san,

    The 'force full-speed' you tried was for USB test mode defined in the USB Specs, it is not for normal use.
    Please use the following commands to force MUSB to full-speed only mode, if it can workaround the issue, I will make a proper patch for the driver.

    <before plug in the hub>
    # echo on > /sys/bus/usb/devices/usb2/power/control
    # devmem2 0x47401c01 b 0xc0
    <then plug in the hub, it should be enumerated as a full-speed hub>

    The commands above assumes you use the 2nd MUSB port. Please use the following instead if you use the first MUSB port.

    # echo on > /sys/bus/usb/devices/usb1/power/control
    # devmem2 0x47401401 b 0xc0
  • Hello Bin-san,

    Thank you for the answer.

    We tried to test it with the forced Full-Speed setting. As a result, we do not see any problem and HCI time-out issue.
    So, we agree the USB Full-Speed is effective for this issue. However, the USB transfer rate becomes 12Mbps because USB Hub is linked with Full Speed. If the connected devices are BLE dongle, it should be okay but if it is not BLE device, the throughput issue should be left.
    Is it possible to provide any other solution to use with USB High Speed mode?
    It would be helpful if you have any idea.

    Best Regards,
    Nobuyoshi Arai

    Our test result is as follows;

    * Kernel Config:
    From tisdk_am335x-evm_defconfig, we changed to enable only BTUSB (CONFIG_BT=y、CONFIG_BT_HCIBTUSB=y).

    * Forced Full Speed setting
    <pre>
    echo on > /sys/bus/usb/devices/usb2/power/control
    devmem2 0x47401c01 b 0xc0
    </pre>

    * To check the speed when USB Hub is connected
    <pre>
    root@am335x-evm:~# lsusb -t
    /: Bus 02.Port 1: Dev 1, class="root_hub", Driver=musb-hdrc/1p, 480M
    |__ Port 1: Dev 2, If 0, class="Hub", Driver=hub/4p, 12M
    |__ Port 1: Dev 3, If 0, class="Wireless", Driver=btusb, 12M
    |__ Port 1: Dev 3, If 1, class="Wireless", Driver=btusb, 12M
    |__ Port 2: Dev 4, If 0, class="Wireless", Driver=btusb, 12M
    |__ Port 2: Dev 4, If 1, class="Wireless", Driver=btusb, 12M
    |__ Port 3: Dev 5, If 0, class="Wireless", Driver=btusb, 12M
    |__ Port 3: Dev 5, If 1, class="Wireless", Driver=btusb, 12M
    |__ Port 4: Dev 6, If 0, class="Wireless", Driver=btusb, 12M
    |__ Port 4: Dev 6, If 1, class="Wireless", Driver=btusb, 12M
    /: Bus 01.Port 1: Dev 1, class="root_hub", Driver=musb-hdrc/1p, 480M </pre>
    </pre>
    It looks that USB is forced to Full-speed correctly.

    * Test Result
    We tried to do up/down hci0-hci3 about 300 times periodically and did not see any hci timeout.
    We also could detect CC2650 SensorTag by issuing hcitool -i hciX lescan at hci0-hci3 like below.

    <pre>
    root@am335x-evm:~# hcitool -i hci0 lescan LE Scan ...
    68:C9:0B:05:F7:0D (unknown)
    68:C9:0B:05:F7:0D CC2650 SensorTag

    root@am335x-evm:~# hcitool -i hci1 lescan LE Scan ...
    68:C9:0B:05:F7:0D (unknown)
    68:C9:0B:05:F7:0D CC2650 SensorTag
    68:C9:0B:05:F7:0D (unknown)

    root@am335x-evm:~# hcitool -i hci2 lescan LE Scan ...
    68:C9:0B:05:F7:0D (unknown)
    68:C9:0B:05:F7:0D CC2650 SensorTag
    68:C9:0B:05:F7:0D (unknown)

    root@am335x-evm:~# hcitool -i hci3 lescan LE Scan ...
    68:C9:0B:05:F7:0D (unknown)
    68:C9:0B:05:F7:0D CC2650 SensorTag
    68:C9:0B:05:F7:0D (unknown)

    </pre>

  • Nobuyoshi-san,

    Yes, forcing musb to work in full-speed mode limits the usb throughput to 12Mbps. If you need high-speed working in your use case, I think you would have to debug the system to see why tx timeout happens, as I don't have enough data to give further explaination. I also think a protocol analyzer will be very helpful in the debug, as first you'd better check if the tx packet goes onto the bus or not, then to debug the host driver or dongle firmware, if accessable.

    What is the hub chipset you use? We recently has an issue report that in a case Genesys hub does not transmit data with am335x. Have you tried with different hubs?

  • Hello Bin-san,

     

    Thank you for your comment.

     

    Since we do not have the USB protocol analyzer, it is difficult to debug the USB communication. But we will try to ask our customer if this workaround is effective for them or not. If it is not acceptable for them, please let me consult with you again.

     

     

    Regarding the USB hub, we used two hubs below and we saw same phenomenon with both hubs.

    - USB-HUB2SW44 by SANWA SUPPLY (Self-Powered)

    - BSH4U06 by Buffalo (Bus-powered)

     

    We do not know what chip is used in these hubs. Though we are seeing the problem in AM3358SK and BBB with both USB hubs, we do not see any problem in Raspberry Pi with both USB hubs. So, we do not think this is related with USB hubs. Do you think there is any kind of compatibility or interoperability issue between AM335x and USB Hubs?

     

    Best Regards,

    Nobuyoshi Arai

  • Hello Bin-san,

     

    We do not have a protocol analyzer but we tried to use USB_MON to monitor the USB communication.

    I do not know what is the actual contents of the communication but there are some differences between good case and fail case.

    So, we would like to send the capture log and would like you to check these logs. Then, if you have any advice, it would be helpful for us.

     

    We got the information of USB communication with USB_MON in hci2 because we saw the problems many times at hci2 this time.

    We found that there are two types of difference in one set of logs.

     

    For example, if we compare usbmon_hci2_up_GOOD-01.log and usbmon_hci2_up_FAIL-01.log, we found following two differences.

     

    1. After the command [s 20 00 0000 0000 0003 3 = 140c00] is sent, the number of data of response from BLE dongle is smaller about 32bytes.

    2. After the command [s 20 00 0000 0000 0005 5 = 160c0200 7d] is sent, next line is [0 5 >] in Good case but [-2 5 >] in Fail case, then, there is no communication.

     

    Please see the attached image below. Left side is Fail and Right is Good.

    We prepared one more set of logs.

    If we compare usbmon_hci2_up_GOOD-02.log and usbmon_hci2_up_FAIL-02.log, we found following differences.

    1. After the command [s 20 00 0000 0000 0003 3 = 140c00] is sent, the number of data of response from BLE dongle is smaller about 32bytes.

    2. The output to the command [s 20 00 0000 0000 0005 5 = 160c0200 7d] is not sent.

     

    Please see the attached image below. Left side is Fail and Right is Good.

     

     

    From the results, it looks the output from AM3358 is not good or output incorrectly. For this reason, USB communication is stopping after that. Would you please check these logs  and give any advices?

     

    Thank you very much for your help.

     

    Best Regards,

     

    Nobuyoshi Arai

    usbmon_hci2_up_GOOD-01.log
    cf6fc080 3917846604 S Ii:2:034:1 -115:1 16 <
    cf32d580 3917848223 S Bi:2:034:2 -115 1028 <
    cc46dd80 3917849094 S Bi:2:034:2 -115 1028 <
    cc071300 3917850187 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 030c00
    cc071300 3917850762 C Co:2:034:0 0 3 >
    cf6fc080 3917985178 C Ii:2:034:1 0:1 6 = 0e040103 0c00
    cf6fc080 3917985226 S Ii:2:034:1 -115:1 16 <
    cc071300 3917985365 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 031000
    cc071300 3917985813 C Co:2:034:0 0 3 >
    cf6fc080 3917987162 C Ii:2:034:1 0:1 14 = 0e0c0103 1000ffff 8ffedbff 5b87
    cf6fc080 3917987205 S Ii:2:034:1 -115:1 16 <
    cc071300 3917987338 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 011000
    cc071300 3917987822 C Co:2:034:0 0 3 >
    cf6fc080 3917989164 C Ii:2:034:1 0:1 14 = 0e0c0101 100006bb 22060a00 bb22
    cf6fc080 3917989204 S Ii:2:034:1 -115:1 16 <
    cc071300 3917989322 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 091000
    cc071300 3917989774 C Co:2:034:0 0 3 >
    cf6fc080 3917990130 C Ii:2:034:1 0:1 12 = 0e0a0109 10001d49 05dc1b00
    cf6fc080 3917990165 S Ii:2:034:1 -115:1 16 <
    cc071300 3917991139 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 051000
    cc071300 3917991703 C Co:2:034:0 0 3 >
    cf6fc080 3917992133 C Ii:2:034:1 0:1 13 = 0e0b0105 10003601 400a0008 00
    cf6fc080 3917992168 S Ii:2:034:1 -115:1 16 <
    cc071300 3917992295 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 230c00
    cc071300 3917992758 C Co:2:034:0 0 3 >
    cf6fc080 3917993111 C Ii:2:034:1 0:1 9 = 0e070123 0c000000 00
    cf6fc080 3917993142 S Ii:2:034:1 -115:1 16 <
    cc071300 3917993227 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 140c00
    cc071300 3917995428 C Co:2:034:0 0 3 >
    cf6fc080 3917997176 C Ii:2:034:1 0:1 16 = 0efc0114 0c004353 52383531 30204131
    cf6fc080 3917997207 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3917998130 C Ii:2:034:1 0:1 16 = 30000000 00000000 00000000 00000000
    cf6fc080 3917998152 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3917999145 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3917999166 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918000126 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918000149 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918001117 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918001139 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918002122 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918002142 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918003257 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918003278 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918004134 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918004154 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918005246 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918005267 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918006116 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918006137 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918007119 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918007141 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918008124 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918008145 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918009119 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918009141 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918010133 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918010154 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918011156 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918011177 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918012124 C Ii:2:034:1 0:1 14 = 00000000 00000000 00000000 0000
    cf6fc080 3918012155 S Ii:2:034:1 -115:1 16 <
    cc071300 3918012662 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 250c00
    cc071300 3918013165 C Co:2:034:0 0 3 >
    cf6fc080 3918014251 C Ii:2:034:1 0:1 8 = 0e060125 0c006000
    cf6fc080 3918014289 S Ii:2:034:1 -115:1 16 <
    cc071300 3918014807 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 380c00
    cc071300 3918015305 C Co:2:034:0 0 3 >
    cf6fc080 3918016123 C Ii:2:034:1 0:1 7 = 0e050138 0c0002
    cf6fc080 3918016159 S Ii:2:034:1 -115:1 16 <
    cc071300 3918016628 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 390c00
    cc071300 3918017083 C Co:2:034:0 0 3 >
    cf6fc080 3918018145 C Ii:2:034:1 0:1 10 = 0e080139 0c000133 8b9e
    cf6fc080 3918018181 S Ii:2:034:1 -115:1 16 <
    cc071300 3918018690 S Co:2:034:0 s 20 00 0000 0000 0004 4 = 050c0100
    cc071300 3918019159 C Co:2:034:0 0 4 >
    cf6fc080 3918020125 C Ii:2:034:1 0:1 6 = 0e040105 0c00
    cf6fc080 3918020160 S Ii:2:034:1 -115:1 16 <
    cc071300 3918020632 S Co:2:034:0 s 20 00 0000 0000 0005 5 = 160c0200 7d
    cc071300 3918021151 C Co:2:034:0 0 5 >
    cf6fc080 3918022121 C Ii:2:034:1 0:1 6 = 0e040116 0c00
    cf6fc080 3918022158 S Ii:2:034:1 -115:1 16 <
    cc071300 3918022644 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 1b0c00
    cc071300 3918023139 C Co:2:034:0 0 3 >
    cf6fc080 3918024246 C Ii:2:034:1 0:1 10 = 0e08011b 0c000008 1200
    cf6fc080 3918024281 S Ii:2:034:1 -115:1 16 <
    cc071300 3918024784 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 460c00
    cc071300 3918025247 C Co:2:034:0 0 3 >
    cf6fc080 3918026129 C Ii:2:034:1 0:1 7 = 0e050146 0c0000
    cf6fc080 3918026162 S Ii:2:034:1 -115:1 16 <
    cc071300 3918026891 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 022000
    cc071300 3918027389 C Co:2:034:0 0 3 >
    cf6fc080 3918028128 C Ii:2:034:1 0:1 9 = 0e070102 20000000 00
    cf6fc080 3918028166 S Ii:2:034:1 -115:1 16 <
    cc071300 3918028445 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 032000
    cc071300 3918028913 C Co:2:034:0 0 3 >
    cf6fc080 3918030128 C Ii:2:034:1 0:1 14 = 0e0c0103 20000100 00000000 0000
    cf6fc080 3918030166 S Ii:2:034:1 -115:1 16 <
    cc071300 3918030710 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 072000
    cc071300 3918031172 C Co:2:034:0 0 3 >
    cf6fc080 3918032119 C Ii:2:034:1 0:1 7 = 0e050107 200008
    cf6fc080 3918032153 S Ii:2:034:1 -115:1 16 <
    cc071300 3918032662 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 0f2000
    cc071300 3918033142 C Co:2:034:0 0 3 >
    cf6fc080 3918034129 C Ii:2:034:1 0:1 7 = 0e05010f 200019
    cf6fc080 3918034167 S Ii:2:034:1 -115:1 16 <
    cc071300 3918034678 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 1c2000
    cc071300 3918035141 C Co:2:034:0 0 3 >
    cf6fc080 3918036124 C Ii:2:034:1 0:1 14 = 0e0c011c 2000ffff ff1f0000 0000
    cf6fc080 3918036162 S Ii:2:034:1 -115:1 16 <
    cc071300 3918036664 S Co:2:034:0 s 20 00 0000 0000 000b 11 = 010c08ff fffbff07 f8bf3d
    cc071300 3918037126 C Co:2:034:0 0 11 >
    cf6fc080 3918038200 C Ii:2:034:1 0:1 6 = 0e040101 0c00
    cf6fc080 3918038238 S Ii:2:034:1 -115:1 16 <
    cc071300 3918038753 S Co:2:034:0 s 20 00 0000 0000 000b 11 = 0120081f 00000000 000000
    cc071300 3918039305 C Co:2:034:0 0 11 >
    cf6fc080 3918040124 C Ii:2:034:1 0:1 6 = 0e040101 2000
    cf6fc080 3918040159 S Ii:2:034:1 -115:1 16 <
    cc071300 3918040848 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 021000
    cc071300 3918041431 C Co:2:034:0 0 3 >
    cf6fc080 3918042121 C Ii:2:034:1 0:1 16 = 0e440102 1000ffff ff03feff ffffffff
    cf6fc080 3918042146 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918043116 C Ii:2:034:1 0:1 16 = fffff30f e8fe3ff7 83ff1c00 000061f7
    cf6fc080 3918043137 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918044121 C Ii:2:034:1 0:1 16 = ffff7f00 00000000 00000000 00000000
    cf6fc080 3918044141 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918045118 C Ii:2:034:1 0:1 16 = 00000000 00000000 00000000 00000000
    cf6fc080 3918045138 S Ii:2:034:1 -115:1 16 <
    cf6fc080 3918046225 C Ii:2:034:1 0:1 6 = 00000000 0000
    cf6fc080 3918046254 S Ii:2:034:1 -115:1 16 <
    cc071300 3918046735 S Co:2:034:0 s 20 00 0000 0000 00f4 244 = 520cf100 00000000 00000000 00000000 00000000 00000000 00000000 00000000
    cc071300 3918049046 C Co:2:034:0 0 244 >
    cf6fc080 3918050125 C Ii:2:034:1 0:1 6 = 0e040152 0c00
    cf6fc080 3918050161 S Ii:2:034:1 -115:1 16 <
    cc071300 3918050666 S Co:2:034:0 s 20 00 0000 0000 0004 4 = 450c0102
    cc071300 3918051148 C Co:2:034:0 0 4 >
    cf6fc080 3918052135 C Ii:2:034:1 0:1 6 = 0e040145 0c00
    cf6fc080 3918052171 S Ii:2:034:1 -115:1 16 <
    cc071300 3918052650 S Co:2:034:0 s 20 00 0000 0000 0003 3 = 580c00
    cc071300 3918053136 C Co:2:034:0 0 3 >
    cf6fc080 3918054130 C Ii:2:034:1 0:1 7 = 0e050158 0c0004
    cf6fc080 3918054164 S Ii:2:034:1 -115:1 16 <
    cc071300 3918054663 S Co:2:034:0 s 20 00 0000 0000 0004 4 = 04100101
    cc071300 3918055131 C Co:2:034:0 0 4 >
    cf6fc080 3918056124 C Ii:2:034:1 0:1 16 = 0e0e0104 10000100 00000000 00000000
    cf6fc080 3918056159 S Ii:2:034:1 -115:1 16 <
    cc071300 3918056901 S Co:2:034:0 s 20 00 0000 0000 000a 10 = 120c0700 00000000 0001
    cf6fc080 3918057100 C Ii:2:034:1 0:1 0
    cf6fc080 3918057116 S Ii:2:034:1 -115:1 16 <
    cc071300 3918057478 C Co:2:034:0 0 10 >
    cf6fc080 3918058123 C Ii:2:034:1 0:1 8 = 0e060112 0c000000
    cf6fc080 3918058159 S Ii:2:034:1 -115:1 16 <
    cc071300 3918058282 S Co:2:034:0 s 20 00 0000 0000 0005 5 = 0f08020f 00
    cc071300 3918058762 C Co:2:034:0 0 5 >
    cf6fc080 3918059118 C Ii:2:034:1 0:1 6 = 0e04010f 0800
    cf6fc080 3918059153 S Ii:2:034:1 -115:1 16 <
    usbmon_hci2_up_FAIL-01.log
    cc071d00 3479585666 S Ii:2:005:1 -115:1 16 <
    cc071c00 3479587013 S Bi:2:005:2 -115 1028 <
    cc071b00 3479587684 S Bi:2:005:2 -115 1028 <
    cc071680 3479588567 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 030c00
    cc071680 3479589076 C Co:2:005:0 0 3 >
    cc071d00 3479724152 C Ii:2:005:1 0:1 6 = 0e040103 0c00
    cc071d00 3479724196 S Ii:2:005:1 -115:1 16 <
    cc071680 3479724317 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 031000
    cc071680 3479724741 C Co:2:005:0 0 3 >
    cc071d00 3479725104 C Ii:2:005:1 0:1 14 = 0e0c0103 1000ffff 8ffedbff 5b87
    cc071d00 3479725141 S Ii:2:005:1 -115:1 16 <
    cc071680 3479725226 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 011000
    cc071680 3479725723 C Co:2:005:0 0 3 >
    cc071d00 3479726177 C Ii:2:005:1 0:1 14 = 0e0c0101 100006bb 22060a00 bb22
    cc071d00 3479726212 S Ii:2:005:1 -115:1 16 <
    cc071680 3479726288 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 091000
    cc071680 3479726784 C Co:2:005:0 0 3 >
    cc071d00 3479727105 C Ii:2:005:1 0:1 12 = 0e0a0109 10001d49 05dc1b00
    cc071d00 3479727142 S Ii:2:005:1 -115:1 16 <
    cc071680 3479727690 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 051000
    cc071680 3479728186 C Co:2:005:0 0 3 >
    cc071d00 3479729142 C Ii:2:005:1 0:1 13 = 0e0b0105 10003601 400a0008 00
    cc071d00 3479729185 S Ii:2:005:1 -115:1 16 <
    cc071680 3479729297 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 230c00
    cc071680 3479729733 C Co:2:005:0 0 3 >
    cc071d00 3479730101 C Ii:2:005:1 0:1 9 = 0e070123 0c000000 00
    cc071d00 3479730135 S Ii:2:005:1 -115:1 16 <
    cc071680 3479730219 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 140c00
    cc071680 3479732391 C Co:2:005:0 0 3 >
    cc071d00 3479734135 C Ii:2:005:1 0:1 16 = 0efc0114 0c004353 52383531 30204131
    cc071d00 3479734161 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479735140 C Ii:2:005:1 0:1 16 = 30000000 00000000 00000000 00000000
    cc071d00 3479735161 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479736101 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cc071d00 3479736122 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479737135 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cc071d00 3479737156 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479738141 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cc071d00 3479738162 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479739134 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cc071d00 3479739153 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479740131 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cc071d00 3479740152 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479743134 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cc071d00 3479743154 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479744132 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cc071d00 3479744154 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479745134 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cc071d00 3479745155 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479746132 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cc071d00 3479746153 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479747135 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cc071d00 3479747155 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479748096 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cc071d00 3479748119 S Ii:2:005:1 -115:1 16 <
    cc071d00 3479749102 C Ii:2:005:1 0:1 14 = 00000000 00000000 00000000 0000
    cc071d00 3479749121 S Ii:2:005:1 -115:1 16 <
    cc071680 3481732049 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 250c00
    cc071680 3481732543 C Co:2:005:0 0 3 >
    cc071d00 3481733125 C Ii:2:005:1 0:1 8 = 0e060125 0c006000
    cc071d00 3481733161 S Ii:2:005:1 -115:1 16 <
    cc071680 3483732030 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 380c00
    cc071680 3483732604 C Co:2:005:0 0 3 >
    cc071d00 3483733120 C Ii:2:005:1 0:1 7 = 0e050138 0c0002
    cc071d00 3483733153 S Ii:2:005:1 -115:1 16 <
    cc071680 3485732073 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 390c00
    cc071680 3485732645 C Co:2:005:0 0 3 >
    cc071d00 3485733128 C Ii:2:005:1 0:1 10 = 0e080139 0c000133 8b9e
    cc071d00 3485733163 S Ii:2:005:1 -115:1 16 <
    cc071680 3487732044 S Co:2:005:0 s 20 00 0000 0000 0004 4 = 050c0100
    cc071680 3487732623 C Co:2:005:0 0 4 >
    cc071d00 3487733145 C Ii:2:005:1 0:1 6 = 0e040105 0c00
    cc071d00 3487733166 S Ii:2:005:1 -115:1 16 <
    cc071680 3489732101 S Co:2:005:0 s 20 00 0000 0000 0005 5 = 160c0200 7d
    cc071680 3489732509 C Co:2:005:0 -2 5 >
    cc071d00 3489732600 C Ii:2:005:1 -2:1 0
    cc071b00 3489732642 C Bi:2:005:2 -2 0
    	cc071c00 3489732846 C Bi:2:005:2 -2 0
    
    usbmon_hci2_up_GOOD-02.log
    cb959700 4050799005 S Ii:2:005:1 -115:1 16 <
    cb959800 4050800090 S Bi:2:005:2 -115 1028 <
    cb959700 4050800522 C Ii:2:005:1 0:1 6 = 0e040116 0c00
    cb959700 4050800575 S Ii:2:005:1 -115:1 16 <
    cb959900 4050802040 S Bi:2:005:2 -115 1028 <
    cb959980 4050802806 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 030c00
    cb959980 4050803378 C Co:2:005:0 0 3 >
    cb959700 4050938542 C Ii:2:005:1 0:1 6 = 0e040103 0c00
    cb959700 4050938590 S Ii:2:005:1 -115:1 16 <
    cb959980 4050938718 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 031000
    cb959980 4050939146 C Co:2:005:0 0 3 >
    cb959700 4050939490 C Ii:2:005:1 0:1 14 = 0e0c0103 1000ffff 8ffedbff 5b87
    cb959700 4050939525 S Ii:2:005:1 -115:1 16 <
    cb959980 4050939603 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 011000
    cb959980 4050940054 C Co:2:005:0 0 3 >
    cb959700 4050940487 C Ii:2:005:1 0:1 14 = 0e0c0101 100006bb 22060a00 bb22
    cb959700 4050940523 S Ii:2:005:1 -115:1 16 <
    cb959980 4050940598 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 091000
    cb959980 4050941018 C Co:2:005:0 0 3 >
    cb959700 4050941487 C Ii:2:005:1 0:1 12 = 0e0a0109 10001d49 05dc1b00
    cb959700 4050941522 S Ii:2:005:1 -115:1 16 <
    cb959980 4050942187 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 051000
    cb959980 4050942674 C Co:2:005:0 0 3 >
    cb959700 4050943524 C Ii:2:005:1 0:1 13 = 0e0b0105 10003601 400a0008 00
    cb959700 4050943564 S Ii:2:005:1 -115:1 16 <
    cb959980 4050943669 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 230c00
    cb959980 4050944124 C Co:2:005:0 0 3 >
    cb959700 4050944490 C Ii:2:005:1 0:1 9 = 0e070123 0c000000 00
    cb959700 4050944529 S Ii:2:005:1 -115:1 16 <
    cb959980 4050944610 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 140c00
    cb959980 4050946780 C Co:2:005:0 0 3 >
    cb959700 4050948527 C Ii:2:005:1 0:1 16 = 0efc0114 0c004353 52383531 30204131
    cb959700 4050948556 S Ii:2:005:1 -115:1 16 <
    cb959700 4050949509 C Ii:2:005:1 0:1 16 = 30000000 00000000 00000000 00000000
    cb959700 4050949531 S Ii:2:005:1 -115:1 16 <
    cb959700 4050950528 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050950551 S Ii:2:005:1 -115:1 16 <
    cb959700 4050951531 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050951554 S Ii:2:005:1 -115:1 16 <
    cb959700 4050952525 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050952548 S Ii:2:005:1 -115:1 16 <
    cb959700 4050953521 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050953544 S Ii:2:005:1 -115:1 16 <
    cb959700 4050954527 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050954549 S Ii:2:005:1 -115:1 16 <
    cb959700 4050955521 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050955543 S Ii:2:005:1 -115:1 16 <
    cb959700 4050956524 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050956544 S Ii:2:005:1 -115:1 16 <
    cb959700 4050957521 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050957541 S Ii:2:005:1 -115:1 16 <
    cb959700 4050958523 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050958543 S Ii:2:005:1 -115:1 16 <
    cb959700 4050959487 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050959508 S Ii:2:005:1 -115:1 16 <
    cb959700 4050960491 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050960511 S Ii:2:005:1 -115:1 16 <
    cb959700 4050961488 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050961508 S Ii:2:005:1 -115:1 16 <
    cb959700 4050962491 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050962510 S Ii:2:005:1 -115:1 16 <
    cb959700 4050963540 C Ii:2:005:1 0:1 14 = 00000000 00000000 00000000 0000
    cb959700 4050963573 S Ii:2:005:1 -115:1 16 <
    cb959980 4050963848 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 250c00
    cb959980 4050964271 C Co:2:005:0 0 3 >
    cb959700 4050965526 C Ii:2:005:1 0:1 8 = 0e060125 0c006000
    cb959700 4050965566 S Ii:2:005:1 -115:1 16 <
    cb959980 4050965819 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 380c00
    cb959980 4050966248 C Co:2:005:0 0 3 >
    cb959700 4050967525 C Ii:2:005:1 0:1 7 = 0e050138 0c0002
    cb959700 4050967561 S Ii:2:005:1 -115:1 16 <
    cb959980 4050967805 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 390c00
    cb959980 4050968248 C Co:2:005:0 0 3 >
    cb959700 4050969523 C Ii:2:005:1 0:1 10 = 0e080139 0c000133 8b9e
    cb959700 4050969561 S Ii:2:005:1 -115:1 16 <
    cb959980 4050969817 S Co:2:005:0 s 20 00 0000 0000 0004 4 = 050c0100
    cb959980 4050970248 C Co:2:005:0 0 4 >
    cb959700 4050971522 C Ii:2:005:1 0:1 6 = 0e040105 0c00
    cb959700 4050971557 S Ii:2:005:1 -115:1 16 <
    cb959980 4050971804 S Co:2:005:0 s 20 00 0000 0000 0005 5 = 160c0200 7d
    cb959980 4050972248 C Co:2:005:0 0 5 >
    cb959700 4050973532 C Ii:2:005:1 0:1 6 = 0e040116 0c00
    cb959700 4050973568 S Ii:2:005:1 -115:1 16 <
    cb959980 4050973820 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 1b0c00
    cb959980 4050974247 C Co:2:005:0 0 3 >
    cb959700 4050975525 C Ii:2:005:1 0:1 10 = 0e08011b 0c000008 1200
    cb959700 4050975560 S Ii:2:005:1 -115:1 16 <
    cb959980 4050975807 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 460c00
    cb959980 4050976247 C Co:2:005:0 0 3 >
    cb959700 4050977524 C Ii:2:005:1 0:1 7 = 0e050146 0c0000
    cb959700 4050977560 S Ii:2:005:1 -115:1 16 <
    cb959980 4050977808 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 022000
    cb959980 4050978247 C Co:2:005:0 0 3 >
    cb959700 4050979524 C Ii:2:005:1 0:1 9 = 0e070102 20000000 00
    cb959700 4050979559 S Ii:2:005:1 -115:1 16 <
    cb959980 4050979803 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 032000
    cb959980 4050980247 C Co:2:005:0 0 3 >
    cb959700 4050981522 C Ii:2:005:1 0:1 14 = 0e0c0103 20000100 00000000 0000
    cb959700 4050981558 S Ii:2:005:1 -115:1 16 <
    cb959980 4050981818 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 072000
    cb959980 4050982248 C Co:2:005:0 0 3 >
    cb959700 4050983534 C Ii:2:005:1 0:1 7 = 0e050107 200008
    cb959700 4050983569 S Ii:2:005:1 -115:1 16 <
    cb959980 4050983815 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 0f2000
    cb959980 4050984247 C Co:2:005:0 0 3 >
    cb959700 4050985526 C Ii:2:005:1 0:1 7 = 0e05010f 200019
    cb959700 4050985562 S Ii:2:005:1 -115:1 16 <
    cb959980 4050985807 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 1c2000
    cb959980 4050986247 C Co:2:005:0 0 3 >
    cb959700 4050987525 C Ii:2:005:1 0:1 14 = 0e0c011c 2000ffff ff1f0000 0000
    cb959700 4050987561 S Ii:2:005:1 -115:1 16 <
    cb959980 4050987826 S Co:2:005:0 s 20 00 0000 0000 000b 11 = 010c08ff fffbff07 f8bf3d
    cb959980 4050988262 C Co:2:005:0 0 11 >
    cb959700 4050989525 C Ii:2:005:1 0:1 6 = 0e040101 0c00
    cb959700 4050989561 S Ii:2:005:1 -115:1 16 <
    cb959980 4050989804 S Co:2:005:0 s 20 00 0000 0000 000b 11 = 0120081f 00000000 000000
    cb959980 4050990247 C Co:2:005:0 0 11 >
    cb959700 4050991524 C Ii:2:005:1 0:1 6 = 0e040101 2000
    cb959700 4050991559 S Ii:2:005:1 -115:1 16 <
    cb959980 4050991794 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 021000
    cb959980 4050992273 C Co:2:005:0 0 3 >
    cb959700 4050993532 C Ii:2:005:1 0:1 16 = 0e440102 1000ffff ff03feff ffffffff
    cb959700 4050993559 S Ii:2:005:1 -115:1 16 <
    cb959700 4050994526 C Ii:2:005:1 0:1 16 = fffff30f e8fe3ff7 83ff1c00 000061f7
    cb959700 4050994549 S Ii:2:005:1 -115:1 16 <
    cb959700 4050995523 C Ii:2:005:1 0:1 16 = ffff7f00 00000000 00000000 00000000
    cb959700 4050995545 S Ii:2:005:1 -115:1 16 <
    cb959700 4050996527 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959700 4050996548 S Ii:2:005:1 -115:1 16 <
    cb959700 4050997489 C Ii:2:005:1 0:1 6 = 00000000 0000
    cb959700 4050997520 S Ii:2:005:1 -115:1 16 <
    cb959980 4050997766 S Co:2:005:0 s 20 00 0000 0000 00f4 244 = 520cf100 00000000 00000000 00000000 00000000 00000000 00000000 00000000
    cb959980 4050999890 C Co:2:005:0 0 244 >
    cb959700 4051000601 C Ii:2:005:1 0:1 6 = 0e040152 0c00
    cb959700 4051000637 S Ii:2:005:1 -115:1 16 <
    cb959980 4051000868 S Co:2:005:0 s 20 00 0000 0000 0004 4 = 450c0102
    cb959980 4051001295 C Co:2:005:0 0 4 >
    cb959700 4051002524 C Ii:2:005:1 0:1 6 = 0e040145 0c00
    cb959700 4051002560 S Ii:2:005:1 -115:1 16 <
    cb959980 4051002809 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 580c00
    cb959980 4051003243 C Co:2:005:0 0 3 >
    cb959700 4051004488 C Ii:2:005:1 0:1 7 = 0e050158 0c0004
    cb959700 4051004525 S Ii:2:005:1 -115:1 16 <
    cb959980 4051004755 S Co:2:005:0 s 20 00 0000 0000 0004 4 = 04100101
    cb959980 4051005196 C Co:2:005:0 0 4 >
    cb959700 4051006488 C Ii:2:005:1 0:1 16 = 0e0e0104 10000100 00000000 00000000
    cb959700 4051006525 S Ii:2:005:1 -115:1 16 <
    cb959980 4051006840 S Co:2:005:0 s 20 00 0000 0000 000a 10 = 120c0700 00000000 0001
    cb959980 4051007402 C Co:2:005:0 0 10 >
    cb959700 4051007487 C Ii:2:005:1 0:1 0
    cb959700 4051007497 S Ii:2:005:1 -115:1 16 <
    cb959700 4051008488 C Ii:2:005:1 0:1 8 = 0e060112 0c000000
    cb959700 4051008523 S Ii:2:005:1 -115:1 16 <
    cb959980 4051008752 S Co:2:005:0 s 20 00 0000 0000 0005 5 = 0f08020f 00
    cb959980 4051009181 C Co:2:005:0 0 5 >
    cb959700 4051009495 C Ii:2:005:1 0:1 6 = 0e04010f 0800
    cb959700 4051009529 S Ii:2:005:1 -115:1 16 <
    usbmon_hci2_up_FAIL-02.log
    cb959200 177402440 S Ii:2:005:1 -115:1 16 <
    cb959500 177403783 S Bi:2:005:2 -115 1028 <
    cb959380 177404444 S Bi:2:005:2 -115 1028 <
    cbb57b80 177405368 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 030c00
    cbb57b80 177405848 C Co:2:005:0 0 3 >
    cb959200 177519546 C Ii:2:005:1 0:1 6 = 0e040103 0c00
    cb959200 177519591 S Ii:2:005:1 -115:1 16 <
    cbb57b80 177519708 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 031000
    cbb57b80 177520157 C Co:2:005:0 0 3 >
    cb959200 177520491 C Ii:2:005:1 0:1 14 = 0e0c0103 1000ffff 8ffedbff 5b87
    cb959200 177520529 S Ii:2:005:1 -115:1 16 <
    cbb57b80 177520628 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 011000
    cbb57b80 177521102 C Co:2:005:0 0 3 >
    cb959200 177521490 C Ii:2:005:1 0:1 14 = 0e0c0101 100006bb 22060a00 bb22
    cb959200 177521528 S Ii:2:005:1 -115:1 16 <
    cbb57b80 177521601 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 091000
    cbb57b80 177522025 C Co:2:005:0 0 3 >
    cb959200 177522491 C Ii:2:005:1 0:1 12 = 0e0a0109 10001d49 05dc1b00
    cb959200 177522523 S Ii:2:005:1 -115:1 16 <
    cbb57b80 177522994 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 051000
    cbb57b80 177523529 C Co:2:005:0 0 3 >
    cb959200 177524544 C Ii:2:005:1 0:1 13 = 0e0b0105 10003601 400a0008 00
    cb959200 177524600 S Ii:2:005:1 -115:1 16 <
    cbb57b80 177524711 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 230c00
    cbb57b80 177525163 C Co:2:005:0 0 3 >
    cb959200 177525492 C Ii:2:005:1 0:1 9 = 0e070123 0c000000 00
    cb959200 177525529 S Ii:2:005:1 -115:1 16 <
    cbb57b80 177525602 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 140c00
    cbb57b80 177527806 C Co:2:005:0 0 3 >
    cb959200 177529530 C Ii:2:005:1 0:1 16 = 0efc0114 0c004353 52383531 30204131
    cb959200 177529564 S Ii:2:005:1 -115:1 16 <
    cb959200 177530559 C Ii:2:005:1 0:1 16 = 30000000 00000000 00000000 00000000
    cb959200 177530584 S Ii:2:005:1 -115:1 16 <
    cb959200 177533537 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959200 177533559 S Ii:2:005:1 -115:1 16 <
    cb959200 177534523 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959200 177534549 S Ii:2:005:1 -115:1 16 <
    cb959200 177535528 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959200 177535551 S Ii:2:005:1 -115:1 16 <
    cb959200 177536523 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959200 177536543 S Ii:2:005:1 -115:1 16 <
    cb959200 177537526 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959200 177537548 S Ii:2:005:1 -115:1 16 <
    cb959200 177538523 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959200 177538547 S Ii:2:005:1 -115:1 16 <
    cb959200 177539529 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959200 177539549 S Ii:2:005:1 -115:1 16 <
    cb959200 177540525 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959200 177540549 S Ii:2:005:1 -115:1 16 <
    cb959200 177541526 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959200 177541547 S Ii:2:005:1 -115:1 16 <
    cb959200 177542523 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959200 177542543 S Ii:2:005:1 -115:1 16 <
    cb959200 177543494 C Ii:2:005:1 0:1 16 = 00000000 00000000 00000000 00000000
    cb959200 177543517 S Ii:2:005:1 -115:1 16 <
    cb959200 177544488 C Ii:2:005:1 0:1 14 = 00000000 00000000 00000000 0000
    cb959200 177544513 S Ii:2:005:1 -115:1 16 <
    cbb57b80 179528409 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 250c00
    cbb57b80 179528971 C Co:2:005:0 0 3 >
    cb959200 179529515 C Ii:2:005:1 0:1 8 = 0e060125 0c006000
    cb959200 179529551 S Ii:2:005:1 -115:1 16 <
    cbb57b80 181528422 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 380c00
    cbb57b80 181528996 C Co:2:005:0 0 3 >
    cb959200 181529541 C Ii:2:005:1 0:1 7 = 0e050138 0c0002
    cb959200 181529580 S Ii:2:005:1 -115:1 16 <
    cbb57b80 183528400 S Co:2:005:0 s 20 00 0000 0000 0003 3 = 390c00
    cbb57b80 183528971 C Co:2:005:0 0 3 >
    cb959200 183529507 C Ii:2:005:1 0:1 10 = 0e080139 0c000133 8b9e
    cb959200 183529532 S Ii:2:005:1 -115:1 16 <
    cbb57b80 185528425 S Co:2:005:0 s 20 00 0000 0000 0004 4 = 050c0100
    cbb57b80 185528996 C Co:2:005:0 0 4 >
    cb959200 185529509 C Ii:2:005:1 0:1 6 = 0e040105 0c00
    cb959200 185529534 S Ii:2:005:1 -115:1 16 <
    cb959200 187513384 C Ii:2:005:1 -2:1 0
    cb959380 187513597 C Bi:2:005:2 -2 0
    cb959500 187513721 C Bi:2:005:2 -2 0
    

  • Nobuyoshi-san, I am currently busy on another task, but will be checking the log in early next week and get you back.
  • Hello Bin-san,

     

    I sent the log of USB_MON last week. If you have any comment on this log, it would be helpful. I understand that you are busy for another issue.

     

     

    By the way, we have got another information about this problem. Please let me add some new information below and if you have any comment on this, we also would like you to have your opinion.

     

    First, we tried same test with AM437x Starter Kit and we do not see any problem with this environment.

    We are using the same processor SDK, 01.00.00.03 but it seems the different host driver is used, xhci-hcd(AM437x) and musb_hdrc(AM335x).

    Thus, this problem may be related with the device driver(musb_hdrc).

     

    Secondly, we tried to trace the working with dev_dbg.

    According to the log or dev_dbg, it looks some processing error does not occur but some expected interrupt is not be generated, then it becomes timeout.

     

    If it is good case, we can see that the following interrupt is happening.

    musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0040 

     

    But if it is fail caes, we cannot see this interrupt at all.

     

     

    Please see the attached log of Good case and Fail case.

     

    [Condition]

    We took a log that we enabled the dev_dbg in musb_host.c and musb_core.c.

     

    We also made the kernel by changing the config from tisdk_am335x-evm_defconfig

    CONFIG_BT=m -> y

    CONFIG_BT_HCIBTUSB=n -> y

    CONFIG_USB_OTG=y -> n

    CONFIG_USB_TI_CPPI41_DMA=y -> n

     

     

    Best Regards,

    Nobuyoshi Arai

    GOOD_dev_dbg.log

    FAIL_dev_dbg.log
    root@am335x-evm:~# hciconfig hci3 up
    [ 1127.517427] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1127.523813] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1127.533209] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1127.542668] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1127.549259] musb-hdrc musb-hdrc.1.auto: qh cf064380 periodic slot 5
    [ 1127.555620] musb-hdrc musb-hdrc.1.auto: qh cf064380 urb cc116200 dev6 ep2in-bulk, hw_ep 5, cc597000/1028
    [ 1127.565184] musb-hdrc musb-hdrc.1.auto: <-- hw5 urb cc116200 spd2 dev6 ep2in h_addr02 h_port04 bytes 1028
    [ 1127.574815] musb-hdrc musb-hdrc.1.auto: RXCSR5 := 0020
    [ 1127.580969] musb-hdrc musb-hdrc.1.auto: qh cc116500 urb cc116380 dev6 ep0out, hw_ep 0, cc5a0fc0/8
    [ 1127.589923] musb-hdrc musb-hdrc.1.auto: --> hw0 urb cc116380 spd2 dev6 ep0out h_addr02 h_port04 bytes 8
    [ 1127.599402] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 8 buf cc5a0fc0
    [ 1127.606941] musb-hdrc musb-hdrc.1.auto: Start TX0 pio
    [ 1127.612131] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1127.618984] musb-hdrc musb-hdrc.1.auto: <== csr0 0200, qh cc116500, count 0, urb cc116380, stage 1
    [ 1127.628000] musb-hdrc musb-hdrc.1.auto: start OUT-DATA
    [ 1127.633181] musb-hdrc musb-hdrc.1.auto: Sending 3 bytes to ep0 fifo cc4ebcc8
    [ 1127.640283] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 3 buf cc4ebcc8
    [ 1127.647873] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1127.654719] musb-hdrc musb-hdrc.1.auto: <== csr0 0000, qh cc116500, count 0, urb cc116380, stage 3
    [ 1127.663734] musb-hdrc musb-hdrc.1.auto: ep0 STATUS, csr 0860
    [ 1127.669481] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1127.676326] musb-hdrc musb-hdrc.1.auto: <== csr0 0841, qh cc116500, count 0, urb cc116380, stage 4
    [ 1127.685386] musb-hdrc musb-hdrc.1.auto: complete cc116380 btusb_tx_complete+0x0/0x74 (0), dev6 ep0out, 3/3
    [ 1127.783226] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1127.790068] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1003, urb actual 0 (+dma 0)
    [ 1127.797686] musb-hdrc musb-hdrc.1.auto: RX4 count 6, buffer cc5a0400 len 0/16
    [ 1127.804862] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 6 buf cc5a0400
    [ 1127.812383] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1127.817746] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 6/16
    [ 1127.827791] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1127.834099] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1127.843457] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1127.852898] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1020
    [ 1127.859041] musb-hdrc musb-hdrc.1.auto: qh cc116500 urb cc116380 dev6 ep0out, hw_ep 0, cc5a0fc0/8
    [ 1127.867971] musb-hdrc musb-hdrc.1.auto: --> hw0 urb cc116380 spd2 dev6 ep0out h_addr02 h_port04 bytes 8
    [ 1127.877427] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 8 buf cc5a0fc0
    [ 1127.884947] musb-hdrc musb-hdrc.1.auto: Start TX0 pio
    [ 1127.890120] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1127.896950] musb-hdrc musb-hdrc.1.auto: <== csr0 0200, qh cc116500, count 0, urb cc116380, stage 1
    [ 1127.905951] musb-hdrc musb-hdrc.1.auto: start OUT-DATA
    [ 1127.911116] musb-hdrc musb-hdrc.1.auto: Sending 3 bytes to ep0 fifo cc5b9588
    [ 1127.918200] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 3 buf cc5b9588
    [ 1127.925753] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1127.932580] musb-hdrc musb-hdrc.1.auto: <== csr0 0000, qh cc116500, count 0, urb cc116380, stage 3
    [ 1127.941581] musb-hdrc musb-hdrc.1.auto: ep0 STATUS, csr 0860
    [ 1127.947292] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1127.954119] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1203, urb actual 0 (+dma 0)
    [ 1127.961734] musb-hdrc musb-hdrc.1.auto: RX4 count 14, buffer cc5a0400 len 0/16
    [ 1127.968994] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 14 buf cc5a0400
    [ 1127.976603] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1127.981959] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 14/16
    [ 1127.992049] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1127.998876] musb-hdrc musb-hdrc.1.auto: <== csr0 0841, qh cc116500, count 0, urb cc116380, stage 4
    [ 1128.007888] musb-hdrc musb-hdrc.1.auto: complete cc116380 btusb_tx_complete+0x0/0x74 (0), dev6 ep0out, 3/3
    [ 1128.017678] musb-hdrc musb-hdrc.1.auto: qh cc116500 periodic slot 4
    [ 1128.023985] musb-hdrc musb-hdrc.1.auto: qh cc116500 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1128.033342] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1128.042782] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1128.049411] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116380 dev6 ep0out, hw_ep 0, cc5a0fc0/8
    [ 1128.058336] musb-hdrc musb-hdrc.1.auto: --> hw0 urb cc116380 spd2 dev6 ep0out h_addr02 h_port04 bytes 8
    [ 1128.067787] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 8 buf cc5a0fc0
    [ 1128.075302] musb-hdrc musb-hdrc.1.auto: Start TX0 pio
    [ 1128.080446] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.087271] musb-hdrc musb-hdrc.1.auto: <== csr0 0200, qh cc116c00, count 0, urb cc116380, stage 1
    [ 1128.096268] musb-hdrc musb-hdrc.1.auto: start OUT-DATA
    [ 1128.101429] musb-hdrc musb-hdrc.1.auto: Sending 3 bytes to ep0 fifo cc4eb6c8
    [ 1128.108509] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 3 buf cc4eb6c8
    [ 1128.116097] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.122922] musb-hdrc musb-hdrc.1.auto: <== csr0 0000, qh cc116c00, count 0, urb cc116380, stage 3
    [ 1128.131919] musb-hdrc musb-hdrc.1.auto: ep0 STATUS, csr 0860
    [ 1128.137627] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1128.144448] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1003, urb actual 0 (+dma 0)
    [ 1128.152060] musb-hdrc musb-hdrc.1.auto: RX4 count 14, buffer cc5a0400 len 0/16
    [ 1128.159317] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 14 buf cc5a0400
    [ 1128.166921] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1128.172276] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 14/16
    [ 1128.182346] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.189167] musb-hdrc musb-hdrc.1.auto: <== csr0 0841, qh cc116c00, count 0, urb cc116380, stage 4
    [ 1128.198173] musb-hdrc musb-hdrc.1.auto: complete cc116380 btusb_tx_complete+0x0/0x74 (0), dev6 ep0out, 3/3
    [ 1128.207931] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1128.214232] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1128.223584] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1128.233019] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1020
    [ 1128.238256] musb-hdrc musb-hdrc.1.auto: qh cc116500 urb cc116380 dev6 ep0out, hw_ep 0, cc5a0fc0/8
    [ 1128.247174] musb-hdrc musb-hdrc.1.auto: --> hw0 urb cc116380 spd2 dev6 ep0out h_addr02 h_port04 bytes 8
    [ 1128.256622] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 8 buf cc5a0fc0
    [ 1128.264138] musb-hdrc musb-hdrc.1.auto: Start TX0 pio
    [ 1128.269250] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.276073] musb-hdrc musb-hdrc.1.auto: <== csr0 0200, qh cc116500, count 0, urb cc116380, stage 1
    [ 1128.285070] musb-hdrc musb-hdrc.1.auto: start OUT-DATA
    [ 1128.290231] musb-hdrc musb-hdrc.1.auto: Sending 3 bytes to ep0 fifo cc40f448
    [ 1128.297311] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 3 buf cc40f448
    [ 1128.304867] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.311688] musb-hdrc musb-hdrc.1.auto: <== csr0 0000, qh cc116500, count 0, urb cc116380, stage 3
    [ 1128.320686] musb-hdrc musb-hdrc.1.auto: ep0 STATUS, csr 0860
    [ 1128.326390] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1128.333211] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1203, urb actual 0 (+dma 0)
    [ 1128.340816] musb-hdrc musb-hdrc.1.auto: RX4 count 12, buffer cc5a0400 len 0/16
    [ 1128.348072] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 12 buf cc5a0400
    [ 1128.355675] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1128.361019] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 12/16
    [ 1128.371084] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.377905] musb-hdrc musb-hdrc.1.auto: <== csr0 0841, qh cc116500, count 0, urb cc116380, stage 4
    [ 1128.386910] musb-hdrc musb-hdrc.1.auto: complete cc116380 btusb_tx_complete+0x0/0x74 (0), dev6 ep0out, 3/3
    [ 1128.397483] musb-hdrc musb-hdrc.1.auto: qh cc116500 periodic slot 4
    [ 1128.403786] musb-hdrc musb-hdrc.1.auto: qh cc116500 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1128.413138] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1128.422573] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1128.427897] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116380 dev6 ep0out, hw_ep 0, cc5a0fc0/8
    [ 1128.436816] musb-hdrc musb-hdrc.1.auto: --> hw0 urb cc116380 spd2 dev6 ep0out h_addr02 h_port04 bytes 8
    [ 1128.446265] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 8 buf cc5a0fc0
    [ 1128.453781] musb-hdrc musb-hdrc.1.auto: Start TX0 pio
    [ 1128.458889] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.465711] musb-hdrc musb-hdrc.1.auto: <== csr0 0200, qh cc116c00, count 0, urb cc116380, stage 1
    [ 1128.474708] musb-hdrc musb-hdrc.1.auto: start OUT-DATA
    [ 1128.479869] musb-hdrc musb-hdrc.1.auto: Sending 3 bytes to ep0 fifo cc4eb6c8
    [ 1128.486949] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 3 buf cc4eb6c8
    [ 1128.494490] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.501311] musb-hdrc musb-hdrc.1.auto: <== csr0 0000, qh cc116c00, count 0, urb cc116380, stage 3
    [ 1128.510308] musb-hdrc musb-hdrc.1.auto: ep0 STATUS, csr 0860
    [ 1128.516009] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1128.522830] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1003, urb actual 0 (+dma 0)
    [ 1128.530439] musb-hdrc musb-hdrc.1.auto: RX4 count 13, buffer cc5a0400 len 0/16
    [ 1128.537694] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 13 buf cc5a0400
    [ 1128.545299] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1128.550646] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 13/16
    [ 1128.560713] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.567534] musb-hdrc musb-hdrc.1.auto: <== csr0 0841, qh cc116c00, count 0, urb cc116380, stage 4
    [ 1128.576539] musb-hdrc musb-hdrc.1.auto: complete cc116380 btusb_tx_complete+0x0/0x74 (0), dev6 ep0out, 3/3
    [ 1128.586284] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1128.592584] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1128.601936] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1128.611371] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1020
    [ 1128.616690] musb-hdrc musb-hdrc.1.auto: qh cc116500 urb cc116380 dev6 ep0out, hw_ep 0, cc5a0fc0/8
    [ 1128.625605] musb-hdrc musb-hdrc.1.auto: --> hw0 urb cc116380 spd2 dev6 ep0out h_addr02 h_port04 bytes 8
    [ 1128.635048] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 8 buf cc5a0fc0
    [ 1128.642561] musb-hdrc musb-hdrc.1.auto: Start TX0 pio
    [ 1128.647672] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.654492] musb-hdrc musb-hdrc.1.auto: <== csr0 0200, qh cc116500, count 0, urb cc116380, stage 1
    [ 1128.663487] musb-hdrc musb-hdrc.1.auto: start OUT-DATA
    [ 1128.668645] musb-hdrc musb-hdrc.1.auto: Sending 3 bytes to ep0 fifo cc5b9588
    [ 1128.675723] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 3 buf cc5b9588
    [ 1128.683288] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.690110] musb-hdrc musb-hdrc.1.auto: <== csr0 0000, qh cc116500, count 0, urb cc116380, stage 3
    [ 1128.699105] musb-hdrc musb-hdrc.1.auto: ep0 STATUS, csr 0860
    [ 1128.704808] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1128.711627] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1203, urb actual 0 (+dma 0)
    [ 1128.719231] musb-hdrc musb-hdrc.1.auto: RX4 count 9, buffer cc5a0400 len 0/16
    [ 1128.726397] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 9 buf cc5a0400
    [ 1128.733911] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1128.739253] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 9/16
    [ 1128.749228] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.756047] musb-hdrc musb-hdrc.1.auto: <== csr0 0841, qh cc116500, count 0, urb cc116380, stage 4
    [ 1128.765047] musb-hdrc musb-hdrc.1.auto: complete cc116380 btusb_tx_complete+0x0/0x74 (0), dev6 ep0out, 3/3
    [ 1128.774788] musb-hdrc musb-hdrc.1.auto: qh cc116500 periodic slot 4
    [ 1128.781084] musb-hdrc musb-hdrc.1.auto: qh cc116500 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1128.790432] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1128.799865] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1128.805065] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116380 dev6 ep0out, hw_ep 0, cc5a0fc0/8
    [ 1128.813979] musb-hdrc musb-hdrc.1.auto: --> hw0 urb cc116380 spd2 dev6 ep0out h_addr02 h_port04 bytes 8
    [ 1128.823423] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 8 buf cc5a0fc0
    [ 1128.830936] musb-hdrc musb-hdrc.1.auto: Start TX0 pio
    [ 1128.836092] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.842912] musb-hdrc musb-hdrc.1.auto: <== csr0 0200, qh cc116c00, count 0, urb cc116380, stage 1
    [ 1128.851906] musb-hdrc musb-hdrc.1.auto: start OUT-DATA
    [ 1128.857064] musb-hdrc musb-hdrc.1.auto: Sending 3 bytes to ep0 fifo cc4ebcc8
    [ 1128.864142] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 3 buf cc4ebcc8
    [ 1128.871679] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.878496] musb-hdrc musb-hdrc.1.auto: <== csr0 0000, qh cc116c00, count 0, urb cc116380, stage 3
    [ 1128.887492] musb-hdrc musb-hdrc.1.auto: ep0 STATUS, csr 0860
    [ 1128.893188] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1128.900006] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1003, urb actual 0 (+dma 0)
    [ 1128.907609] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1128.914862] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1128.922463] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1128.927803] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1128.937862] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1128.944681] musb-hdrc musb-hdrc.1.auto: <== csr0 0841, qh cc116c00, count 0, urb cc116380, stage 4
    [ 1128.953681] musb-hdrc musb-hdrc.1.auto: complete cc116380 btusb_tx_complete+0x0/0x74 (0), dev6 ep0out, 3/3
    [ 1128.963429] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1128.969726] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1128.979073] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1128.988505] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1020
    [ 1128.994171] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.000993] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1203, urb actual 0 (+dma 0)
    [ 1129.008598] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1129.015851] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1129.023451] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.028793] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1129.038881] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.045177] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.054525] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.063958] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1129.072174] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.078999] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1003, urb actual 0 (+dma 0)
    [ 1129.086606] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1129.093860] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1129.101461] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.106805] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1129.116906] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.123203] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.132552] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.141984] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1020
    [ 1129.148169] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.154991] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1203, urb actual 0 (+dma 0)
    [ 1129.162597] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1129.169851] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1129.177451] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.182794] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1129.192866] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.199162] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.208510] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.217944] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1129.224166] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.230989] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1003, urb actual 0 (+dma 0)
    [ 1129.238592] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1129.245844] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1129.253445] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.258785] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1129.268858] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.275154] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.284502] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.293934] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1020
    [ 1129.300164] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.306986] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1203, urb actual 0 (+dma 0)
    [ 1129.314590] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1129.321843] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1129.329443] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.334785] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1129.344860] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.351157] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.360505] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.369937] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1129.376167] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.382989] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1003, urb actual 0 (+dma 0)
    [ 1129.390593] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1129.397846] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1129.405447] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.410788] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1129.420866] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.427161] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.436509] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.445942] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1020
    [ 1129.452165] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.458984] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1203, urb actual 0 (+dma 0)
    [ 1129.466588] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1129.473840] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1129.481441] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.486781] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1129.496863] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.503159] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.512507] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.521939] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1129.528166] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.534987] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1003, urb actual 0 (+dma 0)
    [ 1129.542590] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1129.549844] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1129.557444] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.562786] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1129.572857] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.579153] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.588501] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.597933] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1020
    [ 1129.604165] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.610986] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1203, urb actual 0 (+dma 0)
    [ 1129.618589] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1129.625841] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1129.633441] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.638782] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1129.648856] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.655152] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.664500] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.673933] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1129.680168] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.686991] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1003, urb actual 0 (+dma 0)
    [ 1129.694596] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1129.701851] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1129.709451] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.714795] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1129.724877] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.731175] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.740523] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.749956] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1020
    [ 1129.756164] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.762984] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1203, urb actual 0 (+dma 0)
    [ 1129.770588] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1129.777841] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1129.785442] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.790783] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1129.800864] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.807160] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.816507] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.825939] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1129.832194] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.839028] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1003, urb actual 0 (+dma 0)
    [ 1129.846639] musb-hdrc musb-hdrc.1.auto: RX4 count 16, buffer cc5a0400 len 0/16
    [ 1129.853894] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 16 buf cc5a0400
    [ 1129.861496] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.866849] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 16/16
    [ 1129.876987] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.883287] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.892636] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.902069] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1020
    [ 1129.908176] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1129.915003] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1203, urb actual 0 (+dma 0)
    [ 1129.922612] musb-hdrc musb-hdrc.1.auto: RX4 count 14, buffer cc5a0400 len 0/16
    [ 1129.929866] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 14 buf cc5a0400
    [ 1129.937467] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1129.942814] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 14/16
    [ 1129.952906] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1129.959204] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1129.968551] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1129.977983] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1130.828060] Bluetooth: hci3 command 0x0c14 tx timeout
    [ 1130.833320] musb-hdrc musb-hdrc.1.auto: qh cc116500 urb cc116380 dev6 ep0out, hw_ep 0, cc5a0fc0/8
    [ 1130.842270] musb-hdrc musb-hdrc.1.auto: --> hw0 urb cc116380 spd2 dev6 ep0out h_addr02 h_port04 bytes 8
    [ 1130.851749] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 8 buf cc5a0fc0
    [ 1130.859286] musb-hdrc musb-hdrc.1.auto: Start TX0 pio
    [ 1130.864462] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1130.871314] musb-hdrc musb-hdrc.1.auto: <== csr0 0200, qh cc116500, count 0, urb cc116380, stage 1
    [ 1130.880330] musb-hdrc musb-hdrc.1.auto: start OUT-DATA
    [ 1130.885511] musb-hdrc musb-hdrc.1.auto: Sending 3 bytes to ep0 fifo cc40f548
    [ 1130.892614] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 3 buf cc40f548
    [ 1130.900197] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1130.907043] musb-hdrc musb-hdrc.1.auto: <== csr0 0000, qh cc116500, count 0, urb cc116380, stage 3
    [ 1130.916061] musb-hdrc musb-hdrc.1.auto: ep0 STATUS, csr 0860
    [ 1130.921803] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0010
    [ 1130.928649] musb-hdrc musb-hdrc.1.auto: <== csr0 0841, qh cc116500, count 0, urb cc116380, stage 4
    [ 1130.937702] musb-hdrc musb-hdrc.1.auto: complete cc116380 btusb_tx_complete+0x0/0x74 (0), dev6 ep0out, 3/3
    [ 1130.947434] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1003, urb actual 0 (+dma 0)
    [ 1130.955070] musb-hdrc musb-hdrc.1.auto: RX4 count 8, buffer cc5a0400 len 0/16
    [ 1130.962262] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 8 buf cc5a0400
    [ 1130.969799] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1130.975173] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 8/16
    [ 1130.985271] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1130.991603] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1131.000982] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1131.010438] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1020
    [ 1132.978061] Bluetooth: hci3 command 0x0c25 tx timeout
    [ 1132.983291] musb-hdrc musb-hdrc.1.auto: qh cc116500 urb cc116380 dev6 ep0out, hw_ep 0, cc5a0fc0/8
    [ 1132.992240] musb-hdrc musb-hdrc.1.auto: --> hw0 urb cc116380 spd2 dev6 ep0out h_addr02 h_port04 bytes 8
    [ 1133.001718] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 8 buf cc5a0fc0
    [ 1133.009255] musb-hdrc musb-hdrc.1.auto: Start TX0 pio
    [ 1133.014429] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1133.021281] musb-hdrc musb-hdrc.1.auto: <== csr0 0200, qh cc116500, count 0, urb cc116380, stage 1
    [ 1133.030297] musb-hdrc musb-hdrc.1.auto: start OUT-DATA
    [ 1133.035479] musb-hdrc musb-hdrc.1.auto: Sending 3 bytes to ep0 fifo cc4eb3c8
    [ 1133.042580] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 3 buf cc4eb3c8
    [ 1133.050174] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1133.057021] musb-hdrc musb-hdrc.1.auto: <== csr0 0000, qh cc116500, count 0, urb cc116380, stage 3
    [ 1133.066036] musb-hdrc musb-hdrc.1.auto: ep0 STATUS, csr 0860
    [ 1133.071781] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0010
    [ 1133.078627] musb-hdrc musb-hdrc.1.auto: <== csr0 0841, qh cc116500, count 0, urb cc116380, stage 4
    [ 1133.087682] musb-hdrc musb-hdrc.1.auto: complete cc116380 btusb_tx_complete+0x0/0x74 (0), dev6 ep0out, 3/3
    [ 1133.097413] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1203, urb actual 0 (+dma 0)
    [ 1133.105051] musb-hdrc musb-hdrc.1.auto: RX4 count 7, buffer cc5a0400 len 0/16
    [ 1133.112242] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 7 buf cc5a0400
    [ 1133.119779] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1133.125154] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 7/16
    [ 1133.137121] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1133.143471] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1133.152851] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1133.162310] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1135.128071] Bluetooth: hci3 command 0x0c38 tx timeout
    [ 1135.133344] musb-hdrc musb-hdrc.1.auto: qh cc116500 urb cc116380 dev6 ep0out, hw_ep 0, cc5a0240/8
    [ 1135.142297] musb-hdrc musb-hdrc.1.auto: --> hw0 urb cc116380 spd2 dev6 ep0out h_addr02 h_port04 bytes 8
    [ 1135.151777] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 8 buf cc5a0240
    [ 1135.159315] musb-hdrc musb-hdrc.1.auto: Start TX0 pio
    [ 1135.164511] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1135.171362] musb-hdrc musb-hdrc.1.auto: <== csr0 0200, qh cc116500, count 0, urb cc116380, stage 1
    [ 1135.180379] musb-hdrc musb-hdrc.1.auto: start OUT-DATA
    [ 1135.185561] musb-hdrc musb-hdrc.1.auto: Sending 3 bytes to ep0 fifo cc4eb7c8
    [ 1135.192663] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 3 buf cc4eb7c8
    [ 1135.200253] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1135.207100] musb-hdrc musb-hdrc.1.auto: <== csr0 0000, qh cc116500, count 0, urb cc116380, stage 3
    [ 1135.216116] musb-hdrc musb-hdrc.1.auto: ep0 STATUS, csr 0860
    [ 1135.221859] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1135.228707] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1003, urb actual 0 (+dma 0)
    [ 1135.236348] musb-hdrc musb-hdrc.1.auto: RX4 count 10, buffer cc5a0400 len 0/16
    [ 1135.243627] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 10 buf cc5a0400
    [ 1135.251253] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1135.256653] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 10/16
    [ 1135.266771] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1135.273618] musb-hdrc musb-hdrc.1.auto: <== csr0 0841, qh cc116500, count 0, urb cc116380, stage 4
    [ 1135.282655] musb-hdrc musb-hdrc.1.auto: complete cc116380 btusb_tx_complete+0x0/0x74 (0), dev6 ep0out, 3/3
    [ 1135.292601] musb-hdrc musb-hdrc.1.auto: qh cc116500 periodic slot 4
    [ 1135.298937] musb-hdrc musb-hdrc.1.auto: qh cc116500 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1135.308316] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1135.317773] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1020
    [ 1137.288073] Bluetooth: hci3 command 0x0c39 tx timeout
    [ 1137.293328] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116380 dev6 ep0out, hw_ep 0, cc5a07c0/8
    [ 1137.302280] musb-hdrc musb-hdrc.1.auto: --> hw0 urb cc116380 spd2 dev6 ep0out h_addr02 h_port04 bytes 8
    [ 1137.311761] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 8 buf cc5a07c0
    [ 1137.319297] musb-hdrc musb-hdrc.1.auto: Start TX0 pio
    [ 1137.324488] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1137.331341] musb-hdrc musb-hdrc.1.auto: <== csr0 0200, qh cc116c00, count 0, urb cc116380, stage 1
    [ 1137.340357] musb-hdrc musb-hdrc.1.auto: start OUT-DATA
    [ 1137.345537] musb-hdrc musb-hdrc.1.auto: Sending 4 bytes to ep0 fifo cc4eb9c8
    [ 1137.352639] musb-hdrc musb-hdrc.1.auto: TX ep0 fifo d110ec20 count 4 buf cc4eb9c8
    [ 1137.360227] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1137.367074] musb-hdrc musb-hdrc.1.auto: <== csr0 0000, qh cc116c00, count 0, urb cc116380, stage 3
    [ 1137.376092] musb-hdrc musb-hdrc.1.auto: ep0 STATUS, csr 0860
    [ 1137.381840] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0000 rx0010
    [ 1137.388687] musb-hdrc musb-hdrc.1.auto: <== hw 4 rxcsr 1203, urb actual 0 (+dma 0)
    [ 1137.396328] musb-hdrc musb-hdrc.1.auto: RX4 count 6, buffer cc5a0400 len 0/16
    [ 1137.403521] musb-hdrc musb-hdrc.1.auto: RX ep4 fifo d110ec30 count 6 buf cc5a0400
    [ 1137.411058] musb-hdrc musb-hdrc.1.auto: read last packet
    [ 1137.416457] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 6/16
    [ 1137.426506] musb-hdrc musb-hdrc.1.auto: ** IRQ host usb0000 tx0001 rx0000
    [ 1137.433356] musb-hdrc musb-hdrc.1.auto: <== csr0 0841, qh cc116c00, count 0, urb cc116380, stage 4
    [ 1137.442392] musb-hdrc musb-hdrc.1.auto: complete cc116380 btusb_tx_complete+0x0/0x74 (0), dev6 ep0out, 4/4
    [ 1137.453364] musb-hdrc musb-hdrc.1.auto: qh cc116c00 periodic slot 4
    [ 1137.459707] musb-hdrc musb-hdrc.1.auto: qh cc116c00 urb cc116480 dev6 ep1in-intr, hw_ep 4, cc5a0400/16
    [ 1137.469087] musb-hdrc musb-hdrc.1.auto: <-- hw4 urb cc116480 spd2 dev6 ep1in h_addr02 h_port04 bytes 16
    [ 1137.478546] musb-hdrc musb-hdrc.1.auto: RXCSR4 := 1620
    [ 1138.418187] musb-hdrc musb-hdrc.1.auto: urb=cc116480, dev6 ep1in
    [ 1138.424318] musb-hdrc musb-hdrc.1.auto: complete cc116480 btusb_intr_complete+0x0/0x104 (0), dev6 ep1in, 0/16
    [ 1138.435344] musb-hdrc musb-hdrc.1.auto: urb=cc116780, dev6 ep2in
    [ 1138.441519] musb-hdrc musb-hdrc.1.auto: complete cc116780 btusb_bulk_complete+0x0/0x104 (0), dev6 ep2in, 0/1028
    [ 1138.452083] musb-hdrc musb-hdrc.1.auto: urb=cc116200, dev6 ep2in
    [ 1138.458221] musb-hdrc musb-hdrc.1.auto: complete cc116200 btusb_bulk_complete+0x0/0x104 (0), dev6 ep2in, 0/1028
    Can't init device hci3: Connection timed out (110)

  • Nobuyoshi-san,

    Nobuyoshi Arai44 said:
    I sent the log of USB_MON last week. If you have any comment on this log, it would be helpful.

    I have not got a chance to interpret the raw bytes in each packet yet, but I saw in the failure log the packet returned from the hub/device is shorter than that in the good log. We need bus trace to check if the the hub/device indeed returned short packet, or the packet gets corrupted in musb. I have ordered 4 of the Planex BT dongles to investigate the issue, and am expecting to receive them in the last week of October to the 2nd week of November. Yeah, it is slow shipping, it seems the package is shipped from Japan...

    Nobuyoshi Arai44 said:
    First, we tried same test with AM437x Starter Kit and we do not see any problem with this environment.

    This shows the issue is related to AM335x/MUSB.

    Nobuyoshi Arai44 said:
    According to the log or dev_dbg, it looks some processing error does not occur but some expected interrupt is not be generated, then it becomes timeout.

    Again, a bus trace would tell if the hub/device did not send the require packet, or musb supposed to receive it and generate the interrupt.

    Another two thoughts:

    - Do you use another usb port of am335x for something else? If not, can you please try to put two BT dongles on each usb port with a hub to see if the issue still happen? If not, maybe you can use both ports for the four dongles to workaround the issue.

    - Have you contacted Planex for any comments?

  • Bin-san,

    Thank you for the answer.

    I understand that the bus trace is needed to check what is happening.

    However, we do not have a USB analyzer. So, if we send you the USB BT dongle to you, is it possible to check the bus trace in your side?
    If possible, would you please give any advice how we should do?

    Regarding your questions, please let me add our comments below.

    >> Do you use another usb port of am335x for something else? If not, can you please try to put two BT dongles on each usb port with a hub to see if the issue still happen? If not, maybe you can use both ports for the four dongles to workaround the issue.

    In our customer environment, they cannot use another USB ports. Since both BBB and Starter kit does not have an additional USB host port, we would like to solve this problem with one USB host port. Please understand the requirement from the customer.

    >> Have you contacted Planex for any comments?

    No, we have not accessed Planex, yet because we do not know the contact information and this problem does not occur except for the AM335x.


    If you have any additional advice to progress this issue, it would be helpful for us.
    Thank you very much for your help.

    Best Regards,
    Nobuyoshi Arai
  • Nobuyoshi-san,

    Nobuyoshi Arai44 said:
    if we send you the USB BT dongle to you, is it possible to check the bus trace in your side?
    If possible, would you please give any advice how we should do?

    I have ordered the dongles, but if you think you can send me four faster than amazon.com, I will send you a friendship request through this forum to give my shipping address.

  • Bin-san,

    Thank you for the answer.
    I accepted your friend request and sent you my comment.

    Please let me contact you directly for the shipment from now.

    Best Regards,
    Nobuyoshi Arai
  • Nobuyoshi-san,

    I received the dongles. Thank you.

    I see different issue with SDK7.0. It seems all the 4 dongles are enumerated but only hci0 is listed when run hciconfig command. and 'hciconfig hci0' gives the following I/O error.

    Can't init device hci0: Input/output error (5)

    Then I switched to TI 4.1 kernel and don't see the issue you reported. All the 4 dongles are enumerated and can be brought up by hciconfig hci* command. I tested with 2 hubs - SMSC2514, and Cypress.

    Is it possible for you to test with TI 4.1 kernel?

    url = git://git.ti.com/ti-linux-kernel/ti-linux-kernel.git
    tag: ti2015.01

    (I tested ti-linux-4.1.y branch though.)
  • Hello Bin-san,

    Thank you for the information.

    We tried the test with the TI kernel 4.1.6. However, we still see same problem like below.
    (We are seeing the timeout in hci2.)


    So, would you please check the phenomenon in your side, again ?
    Or, is it possible to provide your environment (to send the kernel image by the file server)?
    I do not know why the problem cannot be duplicated in your side.


    *** Result of our experiment ***
    root@am335x-evm:~# uname -a
    Linux am335x-evm 4.1.6-dirty #1 Mon Oct 19 16:25:27 JST 2015 armv7l GNU/Linux
    root@am335x-evm:~#
    root@am335x-evm:~# hciconfig
    hci3: Type: BR/EDR Bus: USB
    BD Address: 00:1B:DC:05:49:1D ACL MTU: 310:10 SCO MTU: 64:8
    DOWN
    RX bytes:562 acl:0 sco:0 events:28 errors:0
    TX bytes:368 acl:0 sco:0 commands:30 errors:0

    hci2: Type: BR/EDR Bus: USB
    BD Address: 00:1B:DC:06:30:99 ACL MTU: 310:10 SCO MTU: 64:8
    DOWN
    RX bytes:574 acl:0 sco:0 events:30 errors:0
    TX bytes:368 acl:0 sco:0 commands:30 errors:0

    hci1: Type: BR/EDR Bus: USB
    BD Address: 00:1B:DC:06:30:A4 ACL MTU: 310:10 SCO MTU: 64:8
    DOWN
    RX bytes:574 acl:0 sco:0 events:30 errors:0
    TX bytes:368 acl:0 sco:0 commands:30 errors:0

    hci0: Type: BR/EDR Bus: USB
    BD Address: 00:1B:DC:06:17:0E ACL MTU: 310:10 SCO MTU: 64:8
    DOWN
    RX bytes:574 acl:0 sco:0 events:30 errors:0
    TX bytes:368 acl:0 sco:0 commands:30 errors:0

    root@am335x-evm:~# hciconfig hci0 up
    root@am335x-evm:~# hciconfig hci1 up
    root@am335x-evm:~# hciconfig hci2 up
    [ 52.972350] Bluetooth: hci2 command 0x0c14 tx timeout
    [ 54.972337] Bluetooth: hci2 command 0x0c25 tx timeout
    [ 56.972369] Bluetooth: hci2 command 0x0c38 tx timeout
    [ 58.972334] Bluetooth: hci2 command 0x0c39 tx timeout
    Can't init device hci2: Connection timed out (110)[ 60.973607] Bluetooth: hci2 command tx timeout

    root@am335x-evm:~# hciconfig hci3 up
    Can't init device hci3: Connection timed out (110)


    For your information, the following is our procedure to update the kernel.

    We made the zImage and dtb with the following procedure.
    Defconfig is copied from ti-processor-sdk 1.0.

    CONFIG_BT=m -> y
    CONFIG_BT_HCIBTUSB=n -> y
    CONFIG_USB_OTG=y -> n
    CONFIG_USB_TI_CPPI41_DMA=y -> n

    kernel build
    $ export PATH=/home/clv/ti-processor-sdk-linux-am335x-evm-01.00.00.03/linux-devkit/sysroots/i686-arago-linux/usr/bin:$PATH
    $ make ARCH=arm CROSS_COMPILE=arm-linux-gnueabihf- distclean
    $ cp /home/clv/ti-processor-sdk-linux-am335x-evm-01.00.00.03/board-support/linux-3.14.43+gitAUTOINC+875c69b2c3-g875c69b/arch/arm/configs/tisdk_am335x-evm_defconfig arch/arm/configs/
    $ make ARCH=arm CROSS_COMPILE=arm-linux-gnueabihf- tisdk_am335x-evm_defconfig
    $ make ARCH=arm CROSS_COMPILE=arm-linux-gnueabihf- zImage
    $ make ARCH=arm CROSS_COMPILE=arm-linux-gnueabihf- am335x-evmsk.dtb

    kernel module build
    We mount rootfs to /mnt.

    $ sudo -s
    # export PATH=/home/clv/ti-processor-sdk-linux-am335x-evm-01.00.00.03/linux-devkit/sysroots/i686-arago-linux/usr/bin:$PATH
    # make ARCH=arm CROSS_COMPILE=arm-linux-gnueabihf- modules
    # make ARCH=arm INSTALL_MOD_PATH=/mnt modules_install

    zImage and am335x-evmsk.dtb are placed to rootfs/boot.

    It would be helpful if you can give any advide.

    Best Regards,
    Nobuyoshi Arai

  • Please download the Processor SDK2.0 pre-built package for AM335x and test it out. I don't see issue with you BT dongles on it.

    am335x-evm-sdk-bin-02.00.00.00.tar.gz

    http://software-dl.ti.com/processor-sw/esd/PROCESSOR-SDK-LINUX-AM335X/latest/index_FDS.html

  • Hello Bin-san,

    I am sorry again and sorry for being late.

    We tried to test with the latest processor SDK2.0 pre-built image.
    However, we are still seeing the same timeout problem like below.

    We tried with several kernel version but the phenomenon is not changed. So, we think there may be other issue than kernel related.
    It seems you cannot duplicate this problem in your side. I am sorry but we would like you to try to duplicate the problem in your side.
    Is it possible to do that? In our side, we tried AM3358 SK PG2.1, PG1.0 and Beagle Bone Black. So, you may be able to duplicate the problem with other Sitara board.

    Anyway, it would be helpful if you can give any advice to solve this issue.

    Best Regards,
    Nobu Arai

    ***************************************************************

    _____ _____ _ _
    | _ |___ ___ ___ ___ | _ |___ ___ |_|___ ___| |_
    | | _| .'| . | . | | __| _| . | | | -_| _| _|
    |__|__|_| |__,|_ |___| |__| |_| |___|_| |___|___|_|
    |___| |___|

    Arago Project http://arago-project.org am335x-evm /dev/ttyO0

    Arago 2015.09 am335x-evm /dev/ttyO0

    am335x-evm login: root

    root@am335x-evm:~#
    root@am335x-evm:~#
    root@am335x-evm:~# uname -a
    Linux am335x-evm 4.1.6-g52c4aa7 #1 PREEMPT Wed Oct 7 14:38:18 EDT 2015 armv7l GNU/Linux
    root@am335x-evm:~#
    root@am335x-evm:~#
    root@am335x-evm:~#
    root@am335x-evm:~# hciconfig
    hci3: Type: BR/EDR Bus: USB
    BD Address: 00:1B:DC:05:49:1D ACL MTU: 310:10 SCO MTU: 64:8
    DOWN
    RX bytes:559 acl:0 sco:0 events:28 errors:0
    TX bytes:368 acl:0 sco:0 commands:30 errors:0

    hci2: Type: BR/EDR Bus: USB
    BD Address: 00:1B:DC:06:30:99 ACL MTU: 310:10 SCO MTU: 64:8
    DOWN
    RX bytes:574 acl:0 sco:0 events:30 errors:0
    TX bytes:368 acl:0 sco:0 commands:30 errors:0

    hci1: Type: BR/EDR Bus: USB
    BD Address: 00:1B:DC:06:30:A4 ACL MTU: 310:10 SCO MTU: 64:8
    DOWN
    RX bytes:574 acl:0 sco:0 events:30 errors:0
    TX bytes:368 acl:0 sco:0 commands:30 errors:0

    hci0: Type: BR/EDR Bus: USB
    BD Address: 00:1B:DC:06:17:0E ACL MTU: 310:10 SCO MTU: 64:8
    DOWN
    RX bytes:574 acl:0 sco:0 events:30 errors:0
    TX bytes:368 acl:0 sco:0 commands:30 errors:0

    root@am335x-evm:~# hciconfig hci0 up
    root@am335x-evm:~# hciconfig hci1 up
    root@am335x-evm:~# hciconfig hci2 up
    root@am335x-evm:~# hciconfig hci3 up
    [ 58.793265] Bluetooth: hci3 command 0x2007 tx timeout     <------ timeout
    Can't init device hci3: Connection timed out (110)           <------ timeout
    root@am335x-evm:~# hciconfig hci0 down
    root@am335x-evm:~# hciconfig hci1 down
    root@am335x-evm:~# hciconfig hci2 down
    root@am335x-evm:~# hciconfig hci3 down
    root@am335x-evm:~# hciconfig

    am335x-evm-sdk-bin-02.00.00.00.log
    
    Starting kernel ...
    
    [    0.000000] Booting Linux on physical CPU 0x0
    [    0.000000] Initializing cgroup subsys cpuset
    [    0.000000] Initializing cgroup subsys cpu
    [    0.000000] Initializing cgroup subsys cpuacct
    [    0.000000] Linux version 4.1.6-g52c4aa7 (gtbldadm@ubuntu-12) (gcc version 4.9.3 20150413 (prerelease) (Linaro GCC 4.9-2015.05) ) #1 PREEMPT Wed Oct 7 14:38:18 EDT 2015
    [    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
    [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
    [    0.000000] Machine model: TI AM335x EVM-SK
    [    0.000000] cma: Reserved 24 MiB at 0x8d000000
    [    0.000000] Memory policy: Data cache writeback
    [    0.000000] CPU: All CPU(s) started in SVC mode.
    [    0.000000] AM335X ES2.1 (sgx neon )
    [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 64960
    [    0.000000] Kernel command line: console=ttyO0,115200n8 root=PARTUUID=00000000-02 rw rootfstype=ext4 rootwait
    [    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
    [    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
    [    0.000000] Memory: 225616K/262144K available (6247K kernel code, 254K rwdata, 2160K rodata, 260K init, 229K bss, 11952K reserved, 24576K cma-reserved, 0K highmem)
    [    0.000000] Virtual kernel memory layout:
    [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
    [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
    [    0.000000]     vmalloc : 0xd0800000 - 0xff000000   ( 744 MB)
    [    0.000000]     lowmem  : 0xc0000000 - 0xd0000000   ( 256 MB)
    [    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
    [    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
    [    0.000000]       .text : 0xc0008000 - 0xc083e05c   (8409 kB)
    [    0.000000]       .init : 0xc083f000 - 0xc0880000   ( 260 kB)
    [    0.000000]       .data : 0xc0880000 - 0xc08bfb28   ( 255 kB)
    [    0.000000]        .bss : 0xc08c2000 - 0xc08fb7f8   ( 230 kB)
    [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
    [    0.000000] Preemptible hierarchical RCU implementation.
    [    0.000000]  Additional per-CPU info printed with stalls.
    [    0.000000] NR_IRQS:16 nr_irqs:16 16
    [    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
    [    0.000000] OMAP clockevent source: timer2 at 24000000 Hz
    [    0.000015] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
    [    0.000033] clocksource timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
    [    0.000043] OMAP clocksource: timer1 at 24000000 Hz
    [    0.000216] Console: colour dummy device 80x30
    [    0.000239] WARNING: Your 'console=ttyO0' has been replaced by 'ttyS0'
    [    0.000245] This ensures that you still see kernel messages. Please
    [    0.000250] update your kernel commandline.
    [    0.000268] Calibrating delay loop... 996.14 BogoMIPS (lpj=4980736)
    [    0.089261] pid_max: default: 32768 minimum: 301
    [    0.089355] Security Framework initialized
    [    0.089403] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.089412] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.090064] Initializing cgroup subsys blkio
    [    0.090086] Initializing cgroup subsys memory
    [    0.090125] Initializing cgroup subsys devices
    [    0.090139] Initializing cgroup subsys freezer
    [    0.090151] Initializing cgroup subsys perf_event
    [    0.090174] CPU: Testing write buffer coherency: ok
    [    0.090563] Setting up static identity map for 0x80008200 - 0x80008270
    [    0.092034] devtmpfs: initialized
    [    0.101654] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
    [    0.109696] omap_hwmod: tptc0 using broken dt data from edma
    [    0.109805] omap_hwmod: tptc1 using broken dt data from edma
    [    0.109889] omap_hwmod: tptc2 using broken dt data from edma
    [    0.114139] omap_hwmod: debugss: _wait_target_disable failed
    [    0.168771] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [    0.170924] pinctrl core: initialized pinctrl subsystem
    [    0.172143] NET: Registered protocol family 16
    [    0.174114] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [    0.199254] cpuidle: using governor ladder
    [    0.229240] cpuidle: using governor menu
    [    0.232336] OMAP GPIO hardware version 0.1
    [    0.241146] No ATAGs?
    [    0.241176] hw-breakpoint: debug architecture 0x4 unsupported.
    [    0.276624] edma-dma-engine edma-dma-engine.0: TI EDMA DMA engine driver
    [    0.277577] reg-fixed-voltage fixedregulator@2: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_wl12xx_gpio, deferring probe
    [    0.280249] vgaarb: loaded
    [    0.280713] SCSI subsystem initialized
    [    0.281173] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c0_pins, deferring probe
    [    0.281319] pps_core: LinuxPPS API ver. 1 registered
    [    0.281326] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
    [    0.281355] PTP clock support registered
    [    0.282108] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
    [    0.282323] Advanced Linux Sound Architecture Driver Initialized.
    [    0.283084] Switched to clocksource timer1
    [    0.292292] NET: Registered protocol family 2
    [    0.293054] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.293149] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.293175] TCP: Hash tables configured (established 2048 bind 2048)
    [    0.293265] UDP hash table entries: 256 (order: 0, 4096 bytes)
    [    0.293281] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
    [    0.293447] NET: Registered protocol family 1
    [    0.293829] RPC: Registered named UNIX socket transport module.
    [    0.293842] RPC: Registered udp transport module.
    [    0.293848] RPC: Registered tcp transport module.
    [    0.293853] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [    0.294702] CPU PMU: Failed to parse /pmu/interrupt-affinity[0]
    [    0.294744] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
    [    0.296498] futex hash table entries: 256 (order: -1, 3072 bytes)
    [    0.296579] audit: initializing netlink subsys (disabled)
    [    0.296652] audit: type=2000 audit(0.280:1): initialized
    [    0.303468] VFS: Disk quotas dquot_6.6.0
    [    0.303678] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
    [    0.305684] NFS: Registering the id_resolver key type
    [    0.305761] Key type id_resolver registered
    [    0.305768] Key type id_legacy registered
    [    0.305832] jffs2: version 2.2. (NAND) (SUMMARY)  c 2001-2006 Red Hat, Inc.
    [    0.308070] NET: Registered protocol family 38
    [    0.308138] io scheduler noop registered
    [    0.308151] io scheduler deadline registered
    [    0.308205] io scheduler cfq registered (default)
    [    0.309236] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
    [    0.311293] backlight supply power not found, using dummy regulator
    [    0.312501] wkup_m3_ipc 44e11324.wkup_m3_ipc: could not get rproc handle
    [    0.313647] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
    [    0.316882] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 155, base_baud = 3000000) is a 8250
    [    0.972360] console [ttyS0] enabled
    [    0.976812] [drm] Initialized drm 1.1.0 20060810
    [    0.983253] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
    [    0.989907] [drm] No driver support for vblank timestamp query.
    [    1.034989] Console: switching to colour frame buffer device 60x34
    [    1.042596] tilcdc 4830e000.lcdc: fb0:  frame buffer device
    [    1.048234] tilcdc 4830e000.lcdc: registered panic notifier
    [    1.083104] [drm] Initialized tilcdc 1.0.0 20121205 on minor 0
    [    1.098779] brd: module loaded
    [    1.106535] loop: module loaded
    [    1.110575] mtdoops: mtd device (mtddev=name/number) must be supplied
    [    1.119082] mousedev: PS/2 mouse device common for all mice
    [    1.124797] i2c /dev entries driver
    [    1.129213] omap_hsmmc 48060000.mmc: Got CD GPIO
    [    1.193492] ledtrig-cpu: registered to indicate activity on CPUs
    [    1.199819] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
    [    1.206980] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
    [    1.214307]  remoteproc0: wkup_m3 is available
    [    1.218782]  remoteproc0: Note: remoteproc is still under development and considered experimental.
    [    1.227944]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
    [    1.238615]  remoteproc0: Direct firmware load for am335x-pm-firmware.elf failed with error -2
    [    1.249788] oprofile: using arm/armv7
    [    1.253647]  remoteproc0: Falling back to user helper
    [    1.259195] Initializing XFRM netlink socket
    [    1.263633] NET: Registered protocol family 17
    [    1.268137] NET: Registered protocol family 15
    [    1.272714] Key type dns_resolver registered
    [    1.277230] omap_voltage_late_init: Voltage driver support not added
    [    1.284083] ThumbEE CPU extension supported.
    [    1.288395] Registering SWP/SWPB emulation handler
    [    1.313296] tps65910 0-002d: No interrupt support, no core IRQ
    [    1.340928] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
    [    1.347838] omap_hsmmc 48060000.mmc: Got CD GPIO
    [    1.352897] vmmc: supplied by vbat
    [    1.474170] mmc0: host does not support reading read-only switch, assuming write-enable
    [    1.486180] mmc0: new high speed SD card at address e624
    [    1.491983] mmcblk0: mmc0:e624 SU02G 1.84 GiB
    [    1.498990]  mmcblk0: p1 p2
    [    1.532297] omap_hsmmc 481d8000.mmc: card claims to support voltages below defined range
    [    1.550235] mmc1: queuing unknown CIS tuple 0x91 (3 bytes)
    [    1.556693] mmc1: new SDIO card at address 0001
    [    1.573115] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
    [    1.579239] davinci_mdio 4a101000.mdio: detected phy mask fffffffc
    [    1.587021] libphy: 4a101000.mdio: probed
    [    1.591053] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver Atheros 8031 ethernet
    [    1.600501] davinci_mdio 4a101000.mdio: phy[1]: device 4a101000.mdio:01, driver Atheros 8031 ethernet
    [    1.610580] cpsw 4a100000.ethernet: Detected MACID = c4:ed:ba:8c:b7:25
    [    1.618189] cpsw 4a100000.ethernet: cpsw: Detected MACID = c4:ed:ba:8c:b7:27
    [    1.626092] hctosys: unable to open rtc device (rtc0)
    [    1.634882] vwl1271: disabling
    [    1.637987] lis3_reg: disabling
    [    1.641242] ALSA device list:
    [    1.644309]   No soundcards found.
    [    1.681929] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
    [    1.690231] VFS: Mounted root (ext4 filesystem) on device 179:2.
    [    1.700532] devtmpfs: mounted
    [    1.703914] Freeing unused kernel memory: 260K (c083f000 - c0880000)
    INIT: version 2.88 booting
    Starting udev
    [    2.406718] udevd[94]: starting version 182
    [    2.709972] vdd_mpu: supplied by vbat
    [    2.754958] input: gpio_buttons@0 as /devices/platform/gpio_buttons@0/input/input0
    [    2.829089] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
    [    2.854628] TI-am335x-tsc TI-am335x-tsc: ti,charge-delay not specified
    [    2.861570] input: ti-tsc as /devices/platform/ocp/44e0d000.tscadc/TI-am335x-tsc/input/input1
    [    2.942815] omap_rtc 44e3e000.rtc: rtc core: registered 44e3e000.rtc as rtc0
    [    3.034995] 47401300.usb-phy supply vcc not found, using dummy regulator
    [    3.133006] 47401b00.usb-phy supply vcc not found, using dummy regulator
    [    3.231775] vaux2: supplied by vbat
    [    3.291773] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
    [    3.298901] lis3lv02d: 16 bits lis331dlh sensor found
    [    3.413593] input: ST LIS3LV02DL Accelerometer as /devices/platform/lis3lv02d/input/input2
    [    3.691384] PM: bootloader does not support rtc-only!
    [    3.781000]  remoteproc0: powering up wkup_m3
    [    3.814508]  remoteproc0: Booting fw image am335x-pm-firmware.elf, size 219803
    [    3.822055]  remoteproc0: remote processor wkup_m3 is now up
    [    3.827762] wkup_m3_ipc 44e11324.wkup_m3_ipc: CM3 Firmware Version = 0x191
    [    3.964357] davinci_evm sound: tlv320aic3x-hifi <-> 4803c000.mcasp mapping ok
    [    5.780729] usbcore: registered new interface driver usbfs
    [    5.881407] usbcore: registered new interface driver hub
    [    5.938659] usbcore: registered new device driver usb
    [    6.022968] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver
    [    6.115487] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 1
    [    6.195780] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
    [    6.253282] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [    6.260576] usb usb1: Product: MUSB HDRC host driver
    [    6.316242] cfg80211: Calling CRDA to update world regulatory domain
    [    6.364803] usb usb1: Manufacturer: Linux 4.1.6-g52c4aa7 musb-hcd
    [    6.446711] usb usb1: SerialNumber: musb-hdrc.1.auto
    [    6.496313] hub 1-0:1.0: USB hub found
    [    6.514949] hub 1-0:1.0: 1 port detected
    [    6.563048] wl12xx_driver wl12xx.0.auto: Direct firmware load for ti-connectivity/wl1271-nvs.bin failed with error -2
    [    6.579909] musb-hdrc musb-hdrc.2.auto: MUSB HDRC host driver
    [    6.628860] wl12xx_driver wl12xx.0.auto: Falling back to user helper
    [    6.636031] musb-hdrc musb-hdrc.2.auto: new USB bus registered, assigned bus number 2
    [    6.661075] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
    [    6.682830] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [    6.701923] usb usb2: Product: MUSB HDRC host driver
    [    6.710940] usb usb2: Manufacturer: Linux 4.1.6-g52c4aa7 musb-hcd
    [    6.727751] usb usb2: SerialNumber: musb-hdrc.2.auto
    [    6.736662] hub 2-0:1.0: USB hub found
    [    6.740514] hub 2-0:1.0: 1 port detected
    [    6.748573] cfg80211: World regulatory domain updated:
    [    6.754646] cfg80211:  DFS Master region: unset
    [    6.759494] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
    [    6.770589] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
    [    6.778725] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
    [    6.790534] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
    [    6.799921] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
    [    6.809670] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
    [    6.819381] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
    [    6.831419] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
    [    6.839613] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
    [    6.983255] cryptodev: driver 1.6 loaded.
    [    7.025981] EXT4-fs (mmcblk0p2): re-mounted. Opts: data=ordered
    bootlogd: cannot allocate pseudo tty: No such file or directory
    Starting random number generator daemon[    7.129863] wlcore: loaded
    .
    [    7.190722] random: nonblocking pool is initialized
    [    7.213382] usb 2-1: new high-speed USB device number 2 using musb-hdrc
    Populating dev cache
    [    7.363574] usb 2-1: New USB device found, idVendor=0409, idProduct=0059
    [    7.370331] usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    [    7.420718] hub 2-1:1.0: USB hub found
    [    7.433393] hub 2-1:1.0: 4 ports detected
    [    7.723248] usb 2-1.1: new full-speed USB device number 3 using musb-hdrc
    [    7.871780] usb 2-1.1: New USB device found, idVendor=0a12, idProduct=0001
    [    7.896024] usb 2-1.1: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    [    7.937466] usb 2-1.1: Product: CSR8510 A10
    [    8.073314] usb 2-1.2: new full-speed USB device number 4 using musb-hdrc
    [    8.220243] usb 2-1.2: New USB device found, idVendor=0a12, idProduct=0001
    [    8.253267] usb 2-1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    [    8.260648] usb 2-1.2: Product: CSR8510 A10
    [    8.373284] usb 2-1.3: new full-speed USB device number 5 using musb-hdrc
    [    8.521544] usb 2-1.3: New USB device found, idVendor=0a12, idProduct=0001
    [    8.545881] usb 2-1.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    [    8.574983] usb 2-1.3: Product: CSR8510 A10
    [    8.673272] usb 2-1.4: new full-speed USB device number 6 using musb-hdrc
    [    8.822495] usb 2-1.4: New USB device found, idVendor=0a12, idProduct=0001
    [    8.840200] usb 2-1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    [    8.871635] usb 2-1.4: Product: CSR8510 A10
    [    8.992747] Bluetooth: Core ver 2.20
    [    9.013291] NET: Registered protocol family 31
    [    9.017782] Bluetooth: HCI device and connection manager initialized
    [    9.052525] Bluetooth: HCI socket layer initialized
    [    9.073391] Bluetooth: L2CAP socket layer initialized
    [    9.078568] Bluetooth: SCO socket layer initialized
    [    9.140803] usbcore: registered new interface driver btusb
    [   11.293187] Bluetooth: hci3 command 0x0c16 tx timeout
    ALSA: Restoring mixer settings...
    No state is present for card AM335xEVMSK
    Found hardware: "AM335x-EVMSK" "" "" "" ""
    Hardware is initialized using a generic method
    No state is present for card AM335xEVMSK
    Wed Oct  7 19:31:53 UTC 2015
    INIT: Entering runlevel: 5
    Configuring network interfaces... [   12.509498] net eth0: initializing cpsw version 1.12 (0)
    [   12.593886] net eth0: phy found : id is : 0x4dd074
    udhcpc (v1.23.1) started
    Sending discover...
    [   13.293246] Bluetooth: hci3 command 0x2002 tx timeout
    [   15.594146] cpsw 4a100000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off
    Sending discover...
    Sending select for 10.13.100.24...
    Lease of 10.13.100.24 obtained, lease time 28800
    /etc/udhcpc.d/50default: Adding DNS 10.234.201.16
    /etc/udhcpc.d/50default: Adding DNS 192.168.131.111
    done.
    Starting system message bus: dbus.
    UIM SYSFS Node Not Found
    Initializing the graphics driver ...
    Starting Dropbear SSH server: Generating key, this may take a while...
    Public key portion is:
    ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCjwQvLlan/bouq3ccygYtg5pBb5k0rhEH9Rp4vSPmgAnfn1GnTw7HT+w2lPsRtTiTMB+f6tU44nZnRIKCEh0RCaR6R3aua/ly1VsheFHa8s97kpDYfDBoavwfXzNfj5c+Y6GRENk4gP+Avu+Y/vfSxxqg2v2PLCjr2EYljtehkKWXOwdMA9eUI9o/wNg0dgErHapR88Mgk65AqZtVrxFxp3RQ57lqjs/Dx6KR8vZyDl9F9vltb346GQQNn8Fc/0yspZDO+izupph+ICmlBpFZbaAgBbMAjyIUb+UFuPuz2t+6TNXFswZSK7QSyiuP3W0r2ZNQMjKAq4nscSn+8FWQN root@am335x-evm
    Fingerprint: md5 44:0e:3d:57:01:1f:38:17:d6:18:a9:0c:00:02:e8:58
    [   18.721877] NET: Registered protocol family 10
    dropbear.
    Starting telnet daemon.
    Starting rpcbind daemon...done.
    starting statd: done
    Starting syslogd/klogd: done
    Starting thttpd.
    Starting Lighttpd Web Server: lighttpd.
    2015-10-07 19:32:00: (/home/gtbldadm/processor-sdk-linux-fido-build/build-CORTEX_1/arago-tmp-external-linaro-toolchain/work/cortexa8hf-vfp-neon-linux-gnueabi/lighttpd/1.4.35-r1/lighttpd-1.4.35/src/log.c.164) server started
    Calibrating touchscreen (first time only)xres = 480, yres = 272
    Took 70 samples...
    Top left : X = 3518 Y = 3233
    Took 98 samples...
    Top right : X =  474 Y = 3310
    Took 123 samples...
    Bot right : X =  548 Y =  898
    Took 111 samples...
    Bot left : X = 3655 Y =  865
    Took 115 samples...
    Center : X = 1926 Y = 2074
    501.215698 -0.123482 -0.005425
    288.094574 -0.001279 -0.072016
    Calibration constants: 32847672 -8092 -355 18880566 -83 -4719 65536
    .
    /
    Starting Matrix GUI application.
    Enabling thermal zones...
    /etc/rc5.d/S98thermal-zone-init: line 7: /sys/class/thermal/thermal_zone*/mode: No such file or directory
    ***************************************************************
    ***************************************************************
    NOTICE: This file system contains the following GPLv3 packages:
            autoconf
            binutils
            cpp-symlinks
            cpp
            g++-symlinks
            g++
            gcc-symlinks
            gcc
            gdbserver
            gstreamer1.0-libav
            libgmp10
            libmpc3
            libmpfr4
            make
    
    If you do not wish to distribute GPLv3 components please remove
    the above packages prior to distribution.  This can be done using
    the opkg remove command.  i.e.:
        opkg remove <package>
    Where <package> is the name printed in the list above
    
    NOTE: If the package is a dependency of another package you
          will be notified of the dependent packages.  You should
          use the --force-removal-of-dependent-packages option to
          also remove the dependent packages as well
    ***************************************************************
    ***************************************************************
    
     _____                    _____           _         _
    |  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_
    |     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
    |__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|
                  |___|                    |___|
    
    Arago Project http://arago-project.org am335x-evm /dev/ttyO0
    
    Arago 2015.09 am335x-evm /dev/ttyO0
    
    am335x-evm login: root
    
    root@am335x-evm:~#
    root@am335x-evm:~#
    root@am335x-evm:~# uname -a
    Linux am335x-evm 4.1.6-g52c4aa7 #1 PREEMPT Wed Oct 7 14:38:18 EDT 2015 armv7l GNU/Linux
    root@am335x-evm:~#
    root@am335x-evm:~#
    root@am335x-evm:~#
    root@am335x-evm:~# hciconfig
    hci3:   Type: BR/EDR  Bus: USB
            BD Address: 00:1B:DC:05:49:1D  ACL MTU: 310:10  SCO MTU: 64:8
            DOWN
            RX bytes:559 acl:0 sco:0 events:28 errors:0
            TX bytes:368 acl:0 sco:0 commands:30 errors:0
    
    hci2:   Type: BR/EDR  Bus: USB
            BD Address: 00:1B:DC:06:30:99  ACL MTU: 310:10  SCO MTU: 64:8
            DOWN
            RX bytes:574 acl:0 sco:0 events:30 errors:0
            TX bytes:368 acl:0 sco:0 commands:30 errors:0
    
    hci1:   Type: BR/EDR  Bus: USB
            BD Address: 00:1B:DC:06:30:A4  ACL MTU: 310:10  SCO MTU: 64:8
            DOWN
            RX bytes:574 acl:0 sco:0 events:30 errors:0
            TX bytes:368 acl:0 sco:0 commands:30 errors:0
    
    hci0:   Type: BR/EDR  Bus: USB
            BD Address: 00:1B:DC:06:17:0E  ACL MTU: 310:10  SCO MTU: 64:8
            DOWN
            RX bytes:574 acl:0 sco:0 events:30 errors:0
            TX bytes:368 acl:0 sco:0 commands:30 errors:0
    
    root@am335x-evm:~# hciconfig hci0 up
    root@am335x-evm:~# hciconfig hci1 up
    root@am335x-evm:~# hciconfig hci2 up
    root@am335x-evm:~# hciconfig hci3 up
    [   58.793265] Bluetooth: hci3 command 0x2007 tx timeout
    Can't init device hci3: Connection timed out (110)
    root@am335x-evm:~# hciconfig hci0 down
    root@am335x-evm:~# hciconfig hci1 down
    root@am335x-evm:~# hciconfig hci2 down
    root@am335x-evm:~# hciconfig hci3 down
    root@am335x-evm:~# hciconfig
    hci3:   Type: BR/EDR  Bus: USB
            BD Address: 00:1B:DC:05:49:1D  ACL MTU: 310:10  SCO MTU: 64:8
            DOWN
            RX bytes:1118 acl:0 sco:0 events:56 errors:0
            TX bytes:726 acl:0 sco:0 commands:59 errors:0
    
    hci2:   Type: BR/EDR  Bus: USB
            BD Address: 00:1B:DC:06:30:99  ACL MTU: 310:10  SCO MTU: 64:8
            DOWN
            RX bytes:1148 acl:0 sco:0 events:60 errors:0
            TX bytes:736 acl:0 sco:0 commands:60 errors:0
    
    hci1:   Type: BR/EDR  Bus: USB
            BD Address: 00:1B:DC:06:30:A4  ACL MTU: 310:10  SCO MTU: 64:8
            DOWN
            RX bytes:1148 acl:0 sco:0 events:60 errors:0
            TX bytes:736 acl:0 sco:0 commands:60 errors:0
    
    hci0:   Type: BR/EDR  Bus: USB
            BD Address: 00:1B:DC:06:17:0E  ACL MTU: 310:10  SCO MTU: 64:8
            DOWN
            RX bytes:1148 acl:0 sco:0 events:60 errors:0
            TX bytes:736 acl:0 sco:0 commands:60 errors:0
    
    root@am335x-evm:~#
    root@am335x-evm:~# dmesg
    [    0.000000] Booting Linux on physical CPU 0x0
    [    0.000000] Initializing cgroup subsys cpuset
    [    0.000000] Initializing cgroup subsys cpu
    [    0.000000] Initializing cgroup subsys cpuacct
    [    0.000000] Linux version 4.1.6-g52c4aa7 (gtbldadm@ubuntu-12) (gcc version 4.9.3 20150413 (prerelease) (Linaro GCC 4.9-2015.05) ) #1 PREEMPT Wed Oct 7 14:38:18 EDT 2015
    [    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
    [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
    [    0.000000] Machine model: TI AM335x EVM-SK
    [    0.000000] cma: Reserved 24 MiB at 0x8d000000
    [    0.000000] Memory policy: Data cache writeback
    [    0.000000] On node 0 totalpages: 65536
    [    0.000000] free_area_init_node: node 0, pgdat c08bdaa0, node_mem_map cfdad000
    [    0.000000]   Normal zone: 576 pages used for memmap
    [    0.000000]   Normal zone: 0 pages reserved
    [    0.000000]   Normal zone: 65536 pages, LIFO batch:15
    [    0.000000] CPU: All CPU(s) started in SVC mode.
    [    0.000000] AM335X ES2.1 (sgx neon )
    [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
    [    0.000000] pcpu-alloc: [0] 0
    [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 64960
    [    0.000000] Kernel command line: console=ttyO0,115200n8 root=PARTUUID=00000000-02 rw rootfstype=ext4 rootwait
    [    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
    [    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
    [    0.000000] Memory: 225616K/262144K available (6247K kernel code, 254K rwdata, 2160K rodata, 260K init, 229K bss, 11952K reserved, 24576K cma-reserved, 0K highmem)
    [    0.000000] Virtual kernel memory layout:
    [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
    [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
    [    0.000000]     vmalloc : 0xd0800000 - 0xff000000   ( 744 MB)
    [    0.000000]     lowmem  : 0xc0000000 - 0xd0000000   ( 256 MB)
    [    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
    [    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
    [    0.000000]       .text : 0xc0008000 - 0xc083e05c   (8409 kB)
    [    0.000000]       .init : 0xc083f000 - 0xc0880000   ( 260 kB)
    [    0.000000]       .data : 0xc0880000 - 0xc08bfb28   ( 255 kB)
    [    0.000000]        .bss : 0xc08c2000 - 0xc08fb7f8   ( 230 kB)
    [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
    [    0.000000] Preemptible hierarchical RCU implementation.
    [    0.000000]  Additional per-CPU info printed with stalls.
    [    0.000000] NR_IRQS:16 nr_irqs:16 16
    [    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
    [    0.000000] OMAP clockevent source: timer2 at 24000000 Hz
    [    0.000015] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
    [    0.000033] clocksource timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
    [    0.000043] OMAP clocksource: timer1 at 24000000 Hz
    [    0.000216] Console: colour dummy device 80x30
    [    0.000239] WARNING: Your 'console=ttyO0' has been replaced by 'ttyS0'
    [    0.000245] This ensures that you still see kernel messages. Please
    [    0.000250] update your kernel commandline.
    [    0.000268] Calibrating delay loop... 996.14 BogoMIPS (lpj=4980736)
    [    0.089261] pid_max: default: 32768 minimum: 301
    [    0.089355] Security Framework initialized
    [    0.089403] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.089412] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.090064] Initializing cgroup subsys blkio
    [    0.090086] Initializing cgroup subsys memory
    [    0.090125] Initializing cgroup subsys devices
    [    0.090139] Initializing cgroup subsys freezer
    [    0.090151] Initializing cgroup subsys perf_event
    [    0.090174] CPU: Testing write buffer coherency: ok
    [    0.090563] Setting up static identity map for 0x80008200 - 0x80008270
    [    0.092034] devtmpfs: initialized
    [    0.101654] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
    [    0.109696] omap_hwmod: tptc0 using broken dt data from edma
    [    0.109805] omap_hwmod: tptc1 using broken dt data from edma
    [    0.109889] omap_hwmod: tptc2 using broken dt data from edma
    [    0.114139] omap_hwmod: debugss: _wait_target_disable failed
    [    0.168771] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [    0.170924] pinctrl core: initialized pinctrl subsystem
    [    0.172143] NET: Registered protocol family 16
    [    0.174114] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [    0.199254] cpuidle: using governor ladder
    [    0.229240] cpuidle: using governor menu
    [    0.232193] gpiochip_add: registered GPIOs 0 to 31 on device: gpio
    [    0.232336] OMAP GPIO hardware version 0.1
    [    0.233015] gpiochip_add: registered GPIOs 32 to 63 on device: gpio
    [    0.233565] gpiochip_add: registered GPIOs 64 to 95 on device: gpio
    [    0.234059] gpiochip_add: registered GPIOs 96 to 127 on device: gpio
    [    0.241146] No ATAGs?
    [    0.241176] hw-breakpoint: debug architecture 0x4 unsupported.
    [    0.276624] edma-dma-engine edma-dma-engine.0: TI EDMA DMA engine driver
    [    0.277079] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator@0[0]'
    [    0.277350] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator@1[0]'
    [    0.277577] reg-fixed-voltage fixedregulator@2: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_wl12xx_gpio, deferring probe
    [    0.277657] of_get_named_gpiod_flags: parsed 'gpio' property of node '/fixedregulator@3[0]' - status (0)
    [    0.280249] vgaarb: loaded
    [    0.280713] SCSI subsystem initialized
    [    0.281173] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c0_pins, deferring probe
    [    0.281319] pps_core: LinuxPPS API ver. 1 registered
    [    0.281326] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
    [    0.281355] PTP clock support registered
    [    0.282108] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
    [    0.282323] Advanced Linux Sound Architecture Driver Initialized.
    [    0.283084] Switched to clocksource timer1
    [    0.292292] NET: Registered protocol family 2
    [    0.293054] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.293149] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.293175] TCP: Hash tables configured (established 2048 bind 2048)
    [    0.293265] UDP hash table entries: 256 (order: 0, 4096 bytes)
    [    0.293281] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
    [    0.293447] NET: Registered protocol family 1
    [    0.293829] RPC: Registered named UNIX socket transport module.
    [    0.293842] RPC: Registered udp transport module.
    [    0.293848] RPC: Registered tcp transport module.
    [    0.293853] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [    0.293879] PCI: CLS 0 bytes, default 64
    [    0.294702] CPU PMU: Failed to parse /pmu/interrupt-affinity[0]
    [    0.294744] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
    [    0.296498] futex hash table entries: 256 (order: -1, 3072 bytes)
    [    0.296579] audit: initializing netlink subsys (disabled)
    [    0.296652] audit: type=2000 audit(0.280:1): initialized
    [    0.303468] VFS: Disk quotas dquot_6.6.0
    [    0.303678] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
    [    0.305684] NFS: Registering the id_resolver key type
    [    0.305761] Key type id_resolver registered
    [    0.305768] Key type id_legacy registered
    [    0.305832] jffs2: version 2.2. (NAND) (SUMMARY)  c 2001-2006 Red Hat, Inc.
    [    0.308070] NET: Registered protocol family 38
    [    0.308138] io scheduler noop registered
    [    0.308151] io scheduler deadline registered
    [    0.308205] io scheduler cfq registered (default)
    [    0.309236] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
    [    0.311212] pwm-backlight backlight: GPIO lookup for consumer enable
    [    0.311231] pwm-backlight backlight: using device tree for GPIO lookup
    [    0.311245] of_get_named_gpiod_flags: can't parse 'enable-gpios' property of node '/backlight[0]'
    [    0.311254] of_get_named_gpiod_flags: can't parse 'enable-gpio' property of node '/backlight[0]'
    [    0.311263] pwm-backlight backlight: using lookup tables for GPIO lookup
    [    0.311272] pwm-backlight backlight: lookup for GPIO enable failed
    [    0.311293] backlight supply power not found, using dummy regulator
    [    0.312501] wkup_m3_ipc 44e11324.wkup_m3_ipc: could not get rproc handle
    [    0.313647] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
    [    0.316396] omap8250 44e09000.serial: GPIO lookup for consumer enable
    [    0.316416] omap8250 44e09000.serial: using device tree for GPIO lookup
    [    0.316430] of_get_named_gpiod_flags: can't parse 'enable-gpios' property of node '/ocp/serial@44e09000[0]'
    [    0.316440] of_get_named_gpiod_flags: can't parse 'enable-gpio' property of node '/ocp/serial@44e09000[0]'
    [    0.316448] omap8250 44e09000.serial: using lookup tables for GPIO lookup
    [    0.316457] omap8250 44e09000.serial: lookup for GPIO enable failed
    [    0.316882] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 155, base_baud = 3000000) is a 8250
    [    0.972360] console [ttyS0] enabled
    [    0.976812] [drm] Initialized drm 1.1.0 20060810
    [    0.981862] panel panel: GPIO lookup for consumer enable
    [    0.981874] panel panel: using device tree for GPIO lookup
    [    0.981887] of_get_named_gpiod_flags: can't parse 'enable-gpios' property of node '/panel[0]'
    [    0.981895] of_get_named_gpiod_flags: can't parse 'enable-gpio' property of node '/panel[0]'
    [    0.981903] panel panel: using lookup tables for GPIO lookup
    [    0.981911] panel panel: lookup for GPIO enable failed
    [    0.983253] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
    [    0.989907] [drm] No driver support for vblank timestamp query.
    [    1.034989] Console: switching to colour frame buffer device 60x34
    [    1.042596] tilcdc 4830e000.lcdc: fb0:  frame buffer device
    [    1.048234] tilcdc 4830e000.lcdc: registered panic notifier
    [    1.083104] [drm] Initialized tilcdc 1.0.0 20121205 on minor 0
    [    1.098779] brd: module loaded
    [    1.106535] loop: module loaded
    [    1.110575] mtdoops: mtd device (mtddev=name/number) must be supplied
    [    1.119082] mousedev: PS/2 mouse device common for all mice
    [    1.124797] i2c /dev entries driver
    [    1.129145] omap_hsmmc 48060000.mmc: GPIO lookup for consumer cd
    [    1.129162] omap_hsmmc 48060000.mmc: using device tree for GPIO lookup
    [    1.129192] of_get_named_gpiod_flags: parsed 'cd-gpios' property of node '/ocp/mmc@48060000[0]' - status (0)
    [    1.129213] omap_hsmmc 48060000.mmc: Got CD GPIO
    [    1.133948] omap_hsmmc 48060000.mmc: GPIO lookup for consumer wp
    [    1.133960] omap_hsmmc 48060000.mmc: using device tree for GPIO lookup
    [    1.133969] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@48060000[0]'
    [    1.133978] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@48060000[0]'
    [    1.133986] omap_hsmmc 48060000.mmc: using lookup tables for GPIO lookup
    [    1.133995] omap_hsmmc 48060000.mmc: lookup for GPIO wp failed
    [    1.163413] omap_hsmmc 481d8000.mmc: GPIO lookup for consumer cd
    [    1.163430] omap_hsmmc 481d8000.mmc: using device tree for GPIO lookup
    [    1.163443] of_get_named_gpiod_flags: can't parse 'cd-gpios' property of node '/ocp/mmc@481d8000[0]'
    [    1.163452] of_get_named_gpiod_flags: can't parse 'cd-gpio' property of node '/ocp/mmc@481d8000[0]'
    [    1.163460] omap_hsmmc 481d8000.mmc: using lookup tables for GPIO lookup
    [    1.163469] omap_hsmmc 481d8000.mmc: lookup for GPIO cd failed
    [    1.163480] omap_hsmmc 481d8000.mmc: GPIO lookup for consumer wp
    [    1.163487] omap_hsmmc 481d8000.mmc: using device tree for GPIO lookup
    [    1.163495] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@481d8000[0]'
    [    1.163503] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@481d8000[0]'
    [    1.163511] omap_hsmmc 481d8000.mmc: using lookup tables for GPIO lookup
    [    1.163518] omap_hsmmc 481d8000.mmc: lookup for GPIO wp failed
    [    1.193492] ledtrig-cpu: registered to indicate activity on CPUs
    [    1.199819] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
    [    1.206980] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
    [    1.214307]  remoteproc0: wkup_m3 is available
    [    1.218782]  remoteproc0: Note: remoteproc is still under development and considered experimental.
    [    1.227944]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
    [    1.238615]  remoteproc0: Direct firmware load for am335x-pm-firmware.elf failed with error -2
    [    1.249788] oprofile: using arm/armv7
    [    1.253647]  remoteproc0: Falling back to user helper
    [    1.259195] Initializing XFRM netlink socket
    [    1.263633] NET: Registered protocol family 17
    [    1.268137] NET: Registered protocol family 15
    [    1.272714] Key type dns_resolver registered
    [    1.277230] omap_voltage_late_init: Voltage driver support not added
    [    1.284083] ThumbEE CPU extension supported.
    [    1.288395] Registering SWP/SWPB emulation handler
    [    1.295607] of_get_named_gpiod_flags: parsed 'gpio' property of node '/fixedregulator@2[0]' - status (0)
    [    1.313296] tps65910 0-002d: No interrupt support, no core IRQ
    [    1.340928] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
    [    1.347767] omap_hsmmc 48060000.mmc: GPIO lookup for consumer cd
    [    1.347786] omap_hsmmc 48060000.mmc: using device tree for GPIO lookup
    [    1.347815] of_get_named_gpiod_flags: parsed 'cd-gpios' property of node '/ocp/mmc@48060000[0]' - status (0)
    [    1.347838] omap_hsmmc 48060000.mmc: Got CD GPIO
    [    1.352503] omap_hsmmc 48060000.mmc: GPIO lookup for consumer wp
    [    1.352511] omap_hsmmc 48060000.mmc: using device tree for GPIO lookup
    [    1.352520] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@48060000[0]'
    [    1.352529] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@48060000[0]'
    [    1.352537] omap_hsmmc 48060000.mmc: using lookup tables for GPIO lookup
    [    1.352546] omap_hsmmc 48060000.mmc: lookup for GPIO wp failed
    [    1.352897] vmmc: supplied by vbat
    [    1.393980] omap_hsmmc 481d8000.mmc: GPIO lookup for consumer cd
    [    1.393998] omap_hsmmc 481d8000.mmc: using device tree for GPIO lookup
    [    1.394012] of_get_named_gpiod_flags: can't parse 'cd-gpios' property of node '/ocp/mmc@481d8000[0]'
    [    1.394020] of_get_named_gpiod_flags: can't parse 'cd-gpio' property of node '/ocp/mmc@481d8000[0]'
    [    1.394029] omap_hsmmc 481d8000.mmc: using lookup tables for GPIO lookup
    [    1.394038] omap_hsmmc 481d8000.mmc: lookup for GPIO cd failed
    [    1.394049] omap_hsmmc 481d8000.mmc: GPIO lookup for consumer wp
    [    1.394056] omap_hsmmc 481d8000.mmc: using device tree for GPIO lookup
    [    1.394064] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@481d8000[0]'
    [    1.394072] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@481d8000[0]'
    [    1.394080] omap_hsmmc 481d8000.mmc: using lookup tables for GPIO lookup
    [    1.394087] omap_hsmmc 481d8000.mmc: lookup for GPIO wp failed
    [    1.474170] mmc0: host does not support reading read-only switch, assuming write-enable
    [    1.486180] mmc0: new high speed SD card at address e624
    [    1.491983] mmcblk0: mmc0:e624 SU02G 1.84 GiB
    [    1.498990]  mmcblk0: p1 p2
    [    1.532297] omap_hsmmc 481d8000.mmc: card claims to support voltages below defined range
    [    1.550235] mmc1: queuing unknown CIS tuple 0x91 (3 bytes)
    [    1.556693] mmc1: new SDIO card at address 0001
    [    1.573115] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
    [    1.579239] davinci_mdio 4a101000.mdio: detected phy mask fffffffc
    [    1.586297] Atheros 8031 ethernet 4a101000.mdio:00: GPIO lookup for consumer reset
    [    1.586314] Atheros 8031 ethernet 4a101000.mdio:00: using lookup tables for GPIO lookup
    [    1.586324] Atheros 8031 ethernet 4a101000.mdio:00: lookup for GPIO reset failed
    [    1.586981] Atheros 8031 ethernet 4a101000.mdio:01: GPIO lookup for consumer reset
    [    1.586994] Atheros 8031 ethernet 4a101000.mdio:01: using lookup tables for GPIO lookup
    [    1.587002] Atheros 8031 ethernet 4a101000.mdio:01: lookup for GPIO reset failed
    [    1.587021] libphy: 4a101000.mdio: probed
    [    1.591053] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver Atheros 8031 ethernet
    [    1.600501] davinci_mdio 4a101000.mdio: phy[1]: device 4a101000.mdio:01, driver Atheros 8031 ethernet
    [    1.610580] cpsw 4a100000.ethernet: Detected MACID = c4:ed:ba:8c:b7:25
    [    1.618189] cpsw 4a100000.ethernet: cpsw: Detected MACID = c4:ed:ba:8c:b7:27
    [    1.626092] hctosys: unable to open rtc device (rtc0)
    [    1.634882] vwl1271: disabling
    [    1.637987] lis3_reg: disabling
    [    1.641242] ALSA device list:
    [    1.644309]   No soundcards found.
    [    1.681929] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
    [    1.690231] VFS: Mounted root (ext4 filesystem) on device 179:2.
    [    1.700532] devtmpfs: mounted
    [    1.703914] Freeing unused kernel memory: 260K (c083f000 - c0880000)
    [    2.406718] udevd[94]: starting version 182
    [    2.709972] vdd_mpu: supplied by vbat
    [    2.754253] of_get_named_gpiod_flags: parsed 'gpios' property of node '/gpio_buttons@0/switch@1[0]' - status (0)
    [    2.754297] of_get_named_gpiod_flags: parsed 'gpios' property of node '/gpio_buttons@0/switch@2[0]' - status (0)
    [    2.754317] of_get_named_gpiod_flags: parsed 'gpios' property of node '/gpio_buttons@0/switch@3[0]' - status (0)
    [    2.754335] of_get_named_gpiod_flags: parsed 'gpios' property of node '/gpio_buttons@0/switch@4[0]' - status (0)
    [    2.754958] input: gpio_buttons@0 as /devices/platform/gpio_buttons@0/input/input0
    [    2.815426] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@1[0]' - status (0)
    [    2.815681] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@2[0]' - status (0)
    [    2.815800] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@3[0]' - status (0)
    [    2.815932] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@4[0]' - status (0)
    [    2.829089] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
    [    2.854628] TI-am335x-tsc TI-am335x-tsc: ti,charge-delay not specified
    [    2.861570] input: ti-tsc as /devices/platform/ocp/44e0d000.tscadc/TI-am335x-tsc/input/input1
    [    2.942815] omap_rtc 44e3e000.rtc: rtc core: registered 44e3e000.rtc as rtc0
    [    3.034804] am335x-phy-driver 47401300.usb-phy: GPIO lookup for consumer reset
    [    3.034830] am335x-phy-driver 47401300.usb-phy: using device tree for GPIO lookup
    [    3.034845] of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/usb@47400000/usb-phy@47401300[0]'
    [    3.034854] of_get_named_gpiod_flags: can't parse 'reset-gpio' property of node '/ocp/usb@47400000/usb-phy@47401300[0]'
    [    3.034863] am335x-phy-driver 47401300.usb-phy: using lookup tables for GPIO lookup
    [    3.034873] am335x-phy-driver 47401300.usb-phy: lookup for GPIO reset failed
    [    3.034882] am335x-phy-driver 47401300.usb-phy: GPIO lookup for consumer vbus-detect
    [    3.034891] am335x-phy-driver 47401300.usb-phy: using device tree for GPIO lookup
    [    3.034899] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpios' property of node '/ocp/usb@47400000/usb-phy@47401300[0]'
    [    3.034908] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpio' property of node '/ocp/usb@47400000/usb-phy@47401300[0]'
    [    3.034916] am335x-phy-driver 47401300.usb-phy: using lookup tables for GPIO lookup
    [    3.034924] am335x-phy-driver 47401300.usb-phy: lookup for GPIO vbus-detect failed
    [    3.034995] 47401300.usb-phy supply vcc not found, using dummy regulator
    [    3.132815] am335x-phy-driver 47401b00.usb-phy: GPIO lookup for consumer reset
    [    3.132841] am335x-phy-driver 47401b00.usb-phy: using device tree for GPIO lookup
    [    3.132856] of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/usb@47400000/usb-phy@47401b00[0]'
    [    3.132866] of_get_named_gpiod_flags: can't parse 'reset-gpio' property of node '/ocp/usb@47400000/usb-phy@47401b00[0]'
    [    3.132875] am335x-phy-driver 47401b00.usb-phy: using lookup tables for GPIO lookup
    [    3.132884] am335x-phy-driver 47401b00.usb-phy: lookup for GPIO reset failed
    [    3.132894] am335x-phy-driver 47401b00.usb-phy: GPIO lookup for consumer vbus-detect
    [    3.132902] am335x-phy-driver 47401b00.usb-phy: using device tree for GPIO lookup
    [    3.132910] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpios' property of node '/ocp/usb@47400000/usb-phy@47401b00[0]'
    [    3.132919] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpio' property of node '/ocp/usb@47400000/usb-phy@47401b00[0]'
    [    3.132926] am335x-phy-driver 47401b00.usb-phy: using lookup tables for GPIO lookup
    [    3.132934] am335x-phy-driver 47401b00.usb-phy: lookup for GPIO vbus-detect failed
    [    3.133006] 47401b00.usb-phy supply vcc not found, using dummy regulator
    [    3.227593] of_get_named_gpiod_flags: can't parse 'gpio-reset' property of node '/ocp/i2c@44e0b000/tlv320aic3106@1b[0]'
    [    3.231775] vaux2: supplied by vbat
    [    3.291773] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
    [    3.298901] lis3lv02d: 16 bits lis331dlh sensor found
    [    3.413593] input: ST LIS3LV02DL Accelerometer as /devices/platform/lis3lv02d/input/input2
    [    3.691384] PM: bootloader does not support rtc-only!
    [    3.781000]  remoteproc0: powering up wkup_m3
    [    3.814508]  remoteproc0: Booting fw image am335x-pm-firmware.elf, size 219803
    [    3.822055]  remoteproc0: remote processor wkup_m3 is now up
    [    3.827762] wkup_m3_ipc 44e11324.wkup_m3_ipc: CM3 Firmware Version = 0x191
    [    3.964357] davinci_evm sound: tlv320aic3x-hifi <-> 4803c000.mcasp mapping ok
    [    5.780729] usbcore: registered new interface driver usbfs
    [    5.881407] usbcore: registered new interface driver hub
    [    5.938659] usbcore: registered new device driver usb
    [    6.022777] musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
    [    6.022803] musb-hdrc: MHDRC RTL version 2.0
    [    6.022811] musb-hdrc: setup fifo_mode 4
    [    6.022831] musb-hdrc: 28/31 max ep, 16384/16384 memory
    [    6.022968] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver
    [    6.115487] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 1
    [    6.195780] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
    [    6.253282] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [    6.260576] usb usb1: Product: MUSB HDRC host driver
    [    6.316242] cfg80211: Calling CRDA to update world regulatory domain
    [    6.364803] usb usb1: Manufacturer: Linux 4.1.6-g52c4aa7 musb-hcd
    [    6.446711] usb usb1: SerialNumber: musb-hdrc.1.auto
    [    6.496313] hub 1-0:1.0: USB hub found
    [    6.514949] hub 1-0:1.0: 1 port detected
    [    6.563048] wl12xx_driver wl12xx.0.auto: Direct firmware load for ti-connectivity/wl1271-nvs.bin failed with error -2
    [    6.579728] musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
    [    6.579748] musb-hdrc: MHDRC RTL version 2.0
    [    6.579755] musb-hdrc: setup fifo_mode 4
    [    6.579773] musb-hdrc: 28/31 max ep, 16384/16384 memory
    [    6.579909] musb-hdrc musb-hdrc.2.auto: MUSB HDRC host driver
    [    6.628860] wl12xx_driver wl12xx.0.auto: Falling back to user helper
    [    6.636031] musb-hdrc musb-hdrc.2.auto: new USB bus registered, assigned bus number 2
    [    6.661075] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
    [    6.682830] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [    6.701923] usb usb2: Product: MUSB HDRC host driver
    [    6.710940] usb usb2: Manufacturer: Linux 4.1.6-g52c4aa7 musb-hcd
    [    6.727751] usb usb2: SerialNumber: musb-hdrc.2.auto
    [    6.736662] hub 2-0:1.0: USB hub found
    [    6.740514] hub 2-0:1.0: 1 port detected
    [    6.748573] cfg80211: World regulatory domain updated:
    [    6.754646] cfg80211:  DFS Master region: unset
    [    6.759494] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
    [    6.770589] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
    [    6.778725] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
    [    6.790534] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
    [    6.799921] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
    [    6.809670] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
    [    6.819381] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
    [    6.831419] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
    [    6.839613] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
    [    6.983255] cryptodev: driver 1.6 loaded.
    [    7.025981] EXT4-fs (mmcblk0p2): re-mounted. Opts: data=ordered
    [    7.129863] wlcore: loaded
    [    7.190722] random: nonblocking pool is initialized
    [    7.213382] usb 2-1: new high-speed USB device number 2 using musb-hdrc
    [    7.363574] usb 2-1: New USB device found, idVendor=0409, idProduct=0059
    [    7.370331] usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    [    7.420718] hub 2-1:1.0: USB hub found
    [    7.433393] hub 2-1:1.0: 4 ports detected
    [    7.723248] usb 2-1.1: new full-speed USB device number 3 using musb-hdrc
    [    7.871780] usb 2-1.1: New USB device found, idVendor=0a12, idProduct=0001
    [    7.896024] usb 2-1.1: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    [    7.937466] usb 2-1.1: Product: CSR8510 A10
    [    8.073314] usb 2-1.2: new full-speed USB device number 4 using musb-hdrc
    [    8.220243] usb 2-1.2: New USB device found, idVendor=0a12, idProduct=0001
    [    8.253267] usb 2-1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    [    8.260648] usb 2-1.2: Product: CSR8510 A10
    [    8.373284] usb 2-1.3: new full-speed USB device number 5 using musb-hdrc
    [    8.521544] usb 2-1.3: New USB device found, idVendor=0a12, idProduct=0001
    [    8.545881] usb 2-1.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    [    8.574983] usb 2-1.3: Product: CSR8510 A10
    [    8.673272] usb 2-1.4: new full-speed USB device number 6 using musb-hdrc
    [    8.822495] usb 2-1.4: New USB device found, idVendor=0a12, idProduct=0001
    [    8.840200] usb 2-1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    [    8.871635] usb 2-1.4: Product: CSR8510 A10
    [    8.992747] Bluetooth: Core ver 2.20
    [    9.013291] NET: Registered protocol family 31
    [    9.017782] Bluetooth: HCI device and connection manager initialized
    [    9.052525] Bluetooth: HCI socket layer initialized
    [    9.073391] Bluetooth: L2CAP socket layer initialized
    [    9.078568] Bluetooth: SCO socket layer initialized
    [    9.140803] usbcore: registered new interface driver btusb
    [   11.293187] Bluetooth: hci3 command 0x0c16 tx timeout
    [   12.509498] net eth0: initializing cpsw version 1.12 (0)
    [   12.593886] net eth0: phy found : id is : 0x4dd074
    [   13.293246] Bluetooth: hci3 command 0x2002 tx timeout
    [   15.594146] cpsw 4a100000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off
    [   18.721877] NET: Registered protocol family 10
    [   58.793265] Bluetooth: hci3 command 0x2007 tx timeout
    root@am335x-evm:~#
    root@am335x-evm:~# lsusb
    Bus 002 Device 006: ID 0a12:0001 Cambridge Silicon Radio, Ltd Bluetooth Dongle (HCI mode)
    Bus 002 Device 005: ID 0a12:0001 Cambridge Silicon Radio, Ltd Bluetooth Dongle (HCI mode)
    Bus 002 Device 004: ID 0a12:0001 Cambridge Silicon Radio, Ltd Bluetooth Dongle (HCI mode)
    Bus 002 Device 003: ID 0a12:0001 Cambridge Silicon Radio, Ltd Bluetooth Dongle (HCI mode)
    Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
    Bus 002 Device 002: ID 0409:0059 NEC Corp. HighSpeed Hub
    Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
    root@am335x-evm:~#
    root@am335x-evm:~# lsusb -t
    /:  Bus 02.Port 1: Dev 1, Class=root_hub, Driver=musb-hdrc/1p, 480M
        |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/4p, 480M
            |__ Port 1: Dev 3, If 0, Class=Wireless, Driver=btusb, 12M
            |__ Port 1: Dev 3, If 1, Class=Wireless, Driver=btusb, 12M
            |__ Port 2: Dev 4, If 0, Class=Wireless, Driver=btusb, 12M
            |__ Port 2: Dev 4, If 1, Class=Wireless, Driver=btusb, 12M
            |__ Port 3: Dev 5, If 0, Class=Wireless, Driver=btusb, 12M
            |__ Port 3: Dev 5, If 1, Class=Wireless, Driver=btusb, 12M
            |__ Port 4: Dev 6, If 0, Class=Wireless, Driver=btusb, 12M
            |__ Port 4: Dev 6, If 1, Class=Wireless, Driver=btusb, 12M
    /:  Bus 01.Port 1: Dev 1, Class=root_hub, Driver=musb-hdrc/1p, 480M
    root@am335x-evm:~#
    

  • Nobuyoshi Arai44 said:
    Is it possible to do that? In our side, we tried AM3358 SK PG2.1, PG1.0 and Beagle Bone Black. So, you may be able to duplicate the problem with other Sitara board.

    I was using AM335x GP EVM. I will try it on other EVM boards.

  • Nobuyoshi-san,

    I re-created a sdcard from Processor SDK2.0 prebuilt with tisdk-rootfs-image-am335x-evm.tar.gz and ran it on my GP EVM, SK EVM(PG1.0), and BBB(PG2.0), but don't have issue with 'hciconfig hciX up'.

    Can you please confirm again what hubs you have tested with?
  • Hello Bin-san,

    Thank you for your checking.

    I understand that you cannot recreate the problem. But I do not know why you can see same issue.

    Please let me confirm a few things below.
    - Are you checking that all 4 dongles are inserted to USB Hub that is connected to USB port of Sitara board?
    - Did you try several times of this test? We sometimes do not see this problem but if we try several time we can see the timeout error after issuing hciconfig hciX up command.

    Regarding the USB hub we tested is as follows;
    1. USB-HUB2SW44 by SANWA SUPPLY (Self-Powered)
    www.sanwa.co.jp/.../syohin.asp
    2. BSH4U06 by Buffalo (Bus-powered hub)
    buffalo.jp/.../bsh4u06.html
    (I am sorry for Japanese page only)

    In our side, we will buy more 4 BT dongles and try the test again. I have not tested the dongles we sent to you (We bought it from shop and sent it to you without opening the package). Thus, we will try to buy new dongles and test with new sets.

    Best Regards,
    Nobuyoshi Arai
  • Nobuyoshi-san,

    Finally I am able to replicate the issue with a Genesys USB hub. The USB bus trace shows the hub stops talking to the host at the end.

    I understand this issue is related to MUSB controller, but it could take a while to find the root cause, if possible. I am wondering if you have control of the hub and BT devices on your final product? For example, will the hub chip be embedded on the board of your product and the BT dongles will be provided in the product as while? If so, can you pick the hub chip which works with the dongles to workaround the issue?
  • Hello Bin-san,

    Thank you for the answer.
    It is good to hear that you can reproduce the timeout problem in your side.

    In the stance of our customer, we would like you to find the root cause of this issue, even if it would take longer time.

    Our customer’s product is M2M (Machin to Machine) Gateway. The USB hub and BT dongles are connected externally to the GW.
    So, your workaround could be acceptable for them for a while, but our customer’s request is to find the cause of this problem.

    Thus, it would be appreciated if you can continue to analyze the root cause of this issue even if it takes a time.

    Thank you very much for your supporting.

    Best Regards,
    Nobu Arai
  • Nobu-san,

    We definitely work on investigating the root cause. I will keep you posted.
    For you particular project, another possible solution would be to directly use UART for BT instead of USB.
  • Hello Bin-san,

    I am new to this forum, but following reply of you is completely workable for us to reduce usb bus speed from 480 to 12M

    <before plug in the hub>
    # echo on > /sys/bus/usb/devices/usb2/power/control
    # devmem2 0x47401c01 b 0xc0
    <then plug in the hub, it should be enumerated as a full-speed hub>

    So, can you send me some tutorial or some document to understand 0x47401c01 memory location and its functioning.
  • Ghanshyam-san,

    0x47401c01 is the POWER register for USB1 controller on AM335x.

    The instruction you referred is only for a quick test to check with full-speed. I don't expect you to use it in your final product. I have kernel patch ready for controlling the bus speed, which will be available in mainline kernel v4.4.

    Can you please start a new forum post explaining your USB use case and the SDK/kernel version you use? I will point you to the patch to force MUSB to full-speed mode.