New issue
Advanced search Search tips
Note: Color blocks (like █ or █) mean that a user may not be available. Tooltip shows the reason.

Issue 756397 link

Starred by 1 user
Status: Assigned
Owner:
sadolfsson@chromium.org
Cc:
█ itspeter@chromium.org
smbar...@chromium.org
█ katierh@chromium.org
Components:
EstimatedDays: ----
NextAction: ----
OS: Linux
Pri: 2
Type: Bug



Sign in to add a comment

Mimo's SiS controller fails to be enumerated along with device setup address failure and usb disable failure

Project Member Reported by mzhuo@chromium.org, Aug 17 2017 
Chrome Version: (copy from chrome://version)
guado/R61-9765.21.0 dev image

What steps will reproduce the problem?
(1)Start meeting. 
(2)shut power to all 3 usb ports using gpio
(3) wait for 2 seconds.
(4) bring power back to all 3 usb ports
(5) wait for about 60 seconds, check all connected devices. 
(6) if ok, wait for about 60 seconds, repeat 1-5. if not stop

What is the expected result?
all devices should be neatly disconnected and re-connect, re-enumerated

What happens instead?
(1) Huddly report uvcvideo related error
(2) SiS controller fails
(3) usb disable fail
(4) cras_test_client fail
(5) even atrus is online, Hotrod fails to use  them. Cpanel reports no working speaker and mic. 
(6) Huddly is selected as preferred camera, however no video received. 

Please use labels and text to provide additional information.
============================================================
2017-08-16T23:48:05.124020-07:00 NOTICE ag[26776]: autotest server[stack::power_cycle_usb_gpio|run|wrapper] -> ssh_run(echo 1 > /sys/class/gpio/gpio219/value)
2017-08-16T23:48:05.181454-07:00 NOTICE ag[26778]: autotest server[stack::power_cycle_usb_gpio|run|wrapper] -> ssh_run(echo 219 > /sys/class/gpio/unexport)
2017-08-16T23:48:05.240203-07:00 NOTICE ag[26781]: autotest server[stack::power_cycle_usb_gpio|run|wrapper] -> ssh_run(echo 209 > /sys/class/gpio/export)
2017-08-16T23:48:05.296353-07:00 NOTICE ag[26784]: autotest server[stack::power_cycle_usb_gpio|run|wrapper] -> ssh_run(echo out > /sys/class/gpio/gpio209/direction)
2017-08-16T23:48:05.355531-07:00 NOTICE ag[26786]: autotest server[stack::power_cycle_usb_gpio|run|wrapper] -> ssh_run(echo 0 > /sys/class/gpio/gpio209/value)
2017-08-16T23:48:05.548161-07:00 INFO kernel: [29610.604470] usb 1-3: New USB device found, idVendor=2109, idProduct=2811
2017-08-16T23:48:05.548316-07:00 INFO kernel: [29610.604493] usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2017-08-16T23:48:05.548322-07:00 INFO kernel: [29610.604510] usb 1-3: Product: USB2.0 Hub             
2017-08-16T23:48:05.548326-07:00 INFO kernel: [29610.604523] usb 1-3: Manufacturer: VIA Labs, Inc.         
2017-08-16T23:48:05.678947-07:00 INFO kernel: [29610.736409] usb 2-2: New USB device found, idVendor=2109, idProduct=8110
2017-08-16T23:48:05.678986-07:00 INFO kernel: [29610.736441] usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2017-08-16T23:48:05.678993-07:00 INFO kernel: [29610.736465] usb 2-2: Product: USB3.0 Hub             
2017-08-16T23:48:05.678999-07:00 INFO kernel: [29610.736483] usb 2-2: Manufacturer: VIA Labs, Inc.         
2017-08-16T23:48:05.927939-07:00 INFO kernel: [29610.985441] usb 1-3.1: New USB device found, idVendor=2109, idProduct=2813
2017-08-16T23:48:05.927963-07:00 INFO kernel: [29610.985465] usb 1-3.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2017-08-16T23:48:05.927967-07:00 INFO kernel: [29610.985482] usb 1-3.1: Product: USB2.0 Hub
2017-08-16T23:48:05.927970-07:00 INFO kernel: [29610.985494] usb 1-3.1: Manufacturer: VIA Labs, Inc.
2017-08-16T23:48:06.204442-07:00 INFO kernel: [29611.261177] usb 2-2.1: New USB device found, idVendor=2109, idProduct=0813
2017-08-16T23:48:06.204469-07:00 INFO kernel: [29611.261203] usb 2-2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2017-08-16T23:48:06.204473-07:00 INFO kernel: [29611.261223] usb 2-2.1: Product: USB3.0 Hub
2017-08-16T23:48:06.204505-07:00 INFO kernel: [29611.261238] usb 2-2.1: Manufacturer: VIA Labs, Inc.
2017-08-16T23:48:06.416962-07:00 INFO kernel: [29611.474293] usb 1-3.1.1: New USB device found, idVendor=2109, idProduct=2813
2017-08-16T23:48:06.416988-07:00 INFO kernel: [29611.474321] usb 1-3.1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2017-08-16T23:48:06.416992-07:00 INFO kernel: [29611.474342] usb 1-3.1.1: Product: USB2.0 Hub
2017-08-16T23:48:06.416995-07:00 INFO kernel: [29611.474358] usb 1-3.1.1: Manufacturer: VIA Labs, Inc.
2017-08-16T23:48:06.589941-07:00 INFO kernel: [29611.647675] usb 1-3.1.2: New USB device found, idVendor=058f, idProduct=6254
2017-08-16T23:48:06.589971-07:00 INFO kernel: [29611.647698] usb 1-3.1.2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
2017-08-16T23:48:06.756055-07:00 INFO kernel: [29611.813883] usb 1-3.1.3: New USB device found, idVendor=18d1, idProduct=8001
2017-08-16T23:48:06.756074-07:00 INFO kernel: [29611.813905] usb 1-3.1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
2017-08-16T23:48:06.756078-07:00 INFO kernel: [29611.813924] usb 1-3.1.3: Product: Hangouts Meet speakermic
2017-08-16T23:48:06.756082-07:00 INFO kernel: [29611.813937] usb 1-3.1.3: Manufacturer: Google Inc.
2017-08-16T23:48:07.004934-07:00 INFO kernel: [29612.063363] usb 2-2.1.1: New USB device found, idVendor=2109, idProduct=0813
2017-08-16T23:48:07.004965-07:00 INFO kernel: [29612.063387] usb 2-2.1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2017-08-16T23:48:07.004969-07:00 INFO kernel: [29612.063406] usb 2-2.1.1: Product: USB3.0 Hub
2017-08-16T23:48:07.004972-07:00 INFO kernel: [29612.063418] usb 2-2.1.1: Manufacturer: VIA Labs, Inc.
2017-08-16T23:48:07.422405-07:00 NOTICE ag[26821]: autotest server[stack::power_cycle_usb_gpio|run|wrapper] -> ssh_run(echo 1 > /sys/class/gpio/gpio209/value)
2017-08-16T23:48:07.478068-07:00 NOTICE ag[26823]: autotest server[stack::power_cycle_usb_gpio|run|wrapper] -> ssh_run(echo 209 > /sys/class/gpio/unexport)
2017-08-16T23:48:12.276927-07:00 ERR kernel: [29617.338876] 72:1:1: usb_set_interface failed (-110)
2017-08-16T23:48:17.493506-07:00 ERR kernel: [29622.559102] 72:1:1: usb_set_interface failed (-110)
2017-08-16T23:48:27.700921-07:00 ERR kernel: [29632.775359] 72:2:1: usb_set_interface failed (-110)
2017-08-16T23:48:32.803900-07:00 ERR kernel: [29637.883379] 72:1:1: usb_set_interface failed (-110)
2017-08-16T23:48:37.809937-07:00 INFO kernel: [29642.893203] usb 1-3.1.2.3: New USB device found, idVendor=17e9, idProduct=016b
2017-08-16T23:48:37.809962-07:00 INFO kernel: [29642.893228] usb 1-3.1.2.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
2017-08-16T23:48:37.809968-07:00 INFO kernel: [29642.893247] usb 1-3.1.2.3: Product: MIMO VUE HD
2017-08-16T23:48:37.810217-07:00 INFO kernel: [29642.893259] usb 1-3.1.2.3: Manufacturer: DisplayLink
2017-08-16T23:48:37.810225-07:00 INFO kernel: [29642.893272] usb 1-3.1.2.3: SerialNumber: MXACP17040043
2017-08-16T23:48:37.908931-07:00 ERR kernel: [29642.991652] 72:2:1: usb_set_interface failed (-110)
2017-08-16T23:48:38.910946-07:00 ERR kernel: [29643.994525] [drm:udl_get_edid] *ERROR* Read EDID byte 0 failed err ffffff92
2017-08-16T23:48:43.015381-07:00 ERR kernel: [29648.099773] 72:2:1: usb_set_interface failed (-110)
2017-08-16T23:48:48.116933-07:00 ERR kernel: [29653.207870] 72:2:1: usb_set_interface failed (-110)
2017-08-16T23:49:08.595926-07:00 ERR kernel: [29673.704130] usb 1-3.1.2.1: device descriptor read/64, error -110
2017-08-16T23:49:08.696958-07:00 ERR kernel: [29673.804549] xhci_hcd 0000:00:14.0: Setup ERROR: setup context command for slot 6.
2017-08-16T23:49:09.696980-07:00 ERR kernel: [29674.805390] hub 1-3.1.2:1.0: cannot disable port 1 (err = -110)
2017-08-16T23:49:10.696983-07:00 ERR kernel: [29675.806169] hub 1-3.1.2:1.0: cannot reset port 1 (err = -110)
2017-08-16T23:49:11.696940-07:00 ERR kernel: [29676.806985] hub 1-3.1.2:1.0: cannot reset port 1 (err = -110)
2017-08-16T23:49:12.696939-07:00 ERR kernel: [29677.807792] hub 1-3.1.2:1.0: cannot reset port 1 (err = -110)
2017-08-16T23:49:34.195933-07:00 ERR kernel: [29699.324775] usb 1-3.1.2.1: device descriptor read/64, error -110
2017-08-16T23:49:34.296981-07:00 ERR kernel: [29699.425216] xhci_hcd 0000:00:14.0: Setup ERROR: setup context command for slot 28.
2017-08-16T23:49:35.296940-07:00 ERR kernel: [29700.426058] hub 1-3.1.2:1.0: cannot disable port 1 (err = -110)
2017-08-16T23:49:36.296979-07:00 ERR kernel: [29701.426853] hub 1-3.1.2:1.0: cannot reset port 1 (err = -110)
2017-08-16T23:49:37.297003-07:00 ERR kernel: [29702.427637] hub 1-3.1.2:1.0: cannot reset port 1 (err = -110)
2017-08-16T23:49:38.297967-07:00 ERR kernel: [29703.429463] hub 1-3.1.2:1.0: cannot reset port 1 (err = -110)
2017-08-16T23:49:54.857948-07:00 ERR kernel: [29720.002809] usb 1-3.1.2.1: device not accepting address 77, error -62
2017-08-16T23:49:55.857968-07:00 ERR kernel: [29721.003696] hub 1-3.1.2:1.0: cannot disable port 1 (err = -110)
2017-08-16T23:49:56.857969-07:00 ERR kernel: [29722.004537] hub 1-3.1.2:1.0: cannot reset port 1 (err = -110)
2017-08-16T23:49:57.857979-07:00 ERR kernel: [29723.005312] hub 1-3.1.2:1.0: cannot reset port 1 (err = -110)
2017-08-16T23:49:58.857967-07:00 ERR kernel: [29724.006097] hub 1-3.1.2:1.0: cannot reset port 1 (err = -110)
2017-08-16T23:50:15.417937-07:00 ERR kernel: [29740.579404] usb 1-3.1.2.1: device not accepting address 78, error -62
2017-08-16T23:50:16.417930-07:00 ERR kernel: [29741.580287] hub 1-3.1.2:1.0: cannot disable port 1 (err = -110)
2017-08-16T23:50:16.417959-07:00 ERR kernel: [29741.580325] hub 1-3.1.2:1.0: unable to enumerate USB device on port 1
2017-08-16T23:50:17.418522-07:00 ERR kernel: [29742.581067] hub 1-3.1.2:1.0: cannot disable port 1 (err = -110)
2017-08-16T23:50:20.438821-07:00 INFO mimo-updater[26841]: Firmware version: 0x1
2017-08-16T23:50:21.446035-07:00 ERR kernel: [29746.612383] [drm:udl_get_edid] *ERROR* Read EDID byte 5 failed err ffffff92
2017-08-16T23:50:22.765970-07:00 NOTICE ag[27471]: autotest server[stack::get_boot_id|run|wrapper] -> ssh_run(if [ -f '/proc/sys/kernel/random/boot_id' ]; then cat '/proc/sys/kernel/random/boot_id'; elseecho 'no boot_id available'; fi)
2017-08-16T23:50:23.529268-07:00 NOTICE ag[27536]: autotest server[stack::_process_line|run|wrapper] -> ssh_run(echo B > /tmp/sysinfo/autoserv-cNjC3h/tmp/_autotmp_Ty2quxharness-fifo/autoserv.fifo)
2017-08-16T23:50:23.530925-07:00 ERR kernel: [29748.699024] [drm:udl_get_edid] *ERROR* Read EDID byte 0 failed err ffffff92
2017-08-16T23:50:27.029922-07:00 NOTICE ag[27590]: autotest server[stack::get_boot_id|run|wrapper] -> ssh_run(if [ -f '/proc/sys/kernel/random/boot_id' ]; then cat '/proc/sys/kernel/random/boot_id'; elseecho 'no boot_id available'; fi)
2017-08-16T23:50:27.793969-07:00 NOTICE ag[27655]: autotest server[stack::_process_line|run|wrapper] -> ssh_run(echo B > /tmp/sysinfo/autoserv-cNjC3h/tmp/_autotmp_MtS9UUharness-fifo/autoserv.fifo)
2017-08-16T23:50:30.565974-07:00 INFO kernel: [29755.740556] usb 2-1.1: New USB device found, idVendor=2bd9, idProduct=0011
2017-08-16T23:50:30.566000-07:00 INFO kernel: [29755.740580] usb 2-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
2017-08-16T23:50:30.566005-07:00 INFO kernel: [29755.740600] usb 2-1.1: Product: Huddly GO
2017-08-16T23:50:30.566007-07:00 INFO kernel: [29755.740614] usb 2-1.1: Manufacturer: Huddly
2017-08-16T23:50:30.733514-07:00 INFO huddly-updater[27699]: Starting Huddly Package Updater ..
2017-08-16T23:50:30.737203-07:00 INFO huddly-updater[27699]: .. camera detected: 2bd9:0011
2017-08-16T23:50:30.746040-07:00 INFO huddly-updater[27699]: .. Upgrade plan: N [Bootloader] 1.0.0 -> 1.0.0 [App] 1.0.4 -> 1.0.4
2017-08-16T23:50:30.746069-07:00 INFO huddly-updater[27699]: .. No need to update. Exiting huddly-updater.
2017-08-16T23:50:33.721894-07:00 ERR kernel: [29758.898204] uvcvideo: Failed to query (GET_DEF) UVC control 11 on unit 2: -110 (exp. 2).
2017-08-16T23:50:34.021893-07:00 ERR kernel: [29759.198478] uvcvideo: Failed to query (GET_DEF) UVC control 11 on unit 2: -110 (exp. 2).
2017-08-16T23:50:34.321893-07:00 ERR kernel: [29759.498702] uvcvideo: Failed to query (GET_DEF) UVC control 11 on unit 2: -110 (exp. 2).
2017-08-16T23:50:34.621955-07:00 ERR kernel: [29759.799004] uvcvideo: Failed to query (GET_DEF) UVC control 11 on unit 2: -110 (exp. 2).
2017-08-16T23:50:34.921924-07:00 ERR kernel: [29760.099219] uvcvideo: Failed to query (GET_DEF) UVC control 11 on unit 2: -110 (exp. 2).
2017-08-16T23:50:35.221942-07:00 ERR kernel: [29760.399460] uvcvideo: Failed to query (GET_DEF) UVC control 11 on unit 2: -110 (exp. 2).
2017-08-16T23:50:35.521983-07:00 ERR kernel: [29760.699689] uvcvideo: Failed to query (GET_DEF) UVC control 2 on unit 2: -110 (exp. 1).
2017-08-16T23:50:37.524933-07:00 ERR kernel: [29762.704480] 72:1:1: usb_set_interface failed (-110)
2017-08-16T23:50:39.691991-07:00 NOTICE ag[27815]: autotest server[stack::get_network_stats|run|wrapper] -> ssh_run(route; echo SEPARATOR; cat /proc/net/dev)
2017-08-16T23:50:42.629094-07:00 ERR kernel: [29767.812531] 72:2:1: usb_set_interface failed (-110)
2017-08-16T23:50:50.644907-07:00 ERR kernel: [29775.834905] [drm:udl_get_edid] *ERROR* Read EDID byte 0 failed err ffffff92
2017-08-16T23:50:52.276920-07:00 ERR kernel: [29777.468421] 72:2:1: usb_set_interface failed (-110)
2017-08-16T23:50:58.101225-07:00 ERR kernel: [29783.297144] 72:2:1: usb_set_interface failed (-110)
2017-08-16T23:51:03.204972-07:00 ERR kernel: [29788.405081] 72:2:1: usb_set_interface failed (-110)
localhost ~ # lsusb -t                                                                                                                                                                                     
/:  Bus 02.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/4p, 5000M
    |__ Port 1: Dev 42, If 0, Class=Hub, Driver=hub/4p, 5000M
        |__ Port 1: Dev 47, If 0, Class=Video, Driver=uvcvideo, 5000M
        |__ Port 1: Dev 47, If 1, Class=Video, Driver=uvcvideo, 5000M
        |__ Port 1: Dev 47, If 2, Class=Video, Driver=uvcvideo, 5000M
        |__ Port 1: Dev 47, If 3, Class=Video, Driver=uvcvideo, 5000M
    |__ Port 2: Dev 43, If 0, Class=Hub, Driver=hub/4p, 5000M
        |__ Port 1: Dev 44, If 0, Class=Hub, Driver=hub/4p, 5000M
            |__ Port 1: Dev 45, If 0, Class=Hub, Driver=hub/4p, 5000M
/:  Bus 01.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/11p, 480M
    |__ Port 2: Dev 67, If 0, Class=Hub, Driver=hub/4p, 480M
    |__ Port 3: Dev 68, If 0, Class=Hub, Driver=hub/4p, 480M
        |__ Port 1: Dev 69, If 0, Class=Hub, Driver=hub/4p, 480M
            |__ Port 1: Dev 70, If 0, Class=Hub, Driver=hub/4p, 480M
            |__ Port 2: Dev 71, If 0, Class=Hub, Driver=hub/4p, 480M
                |__ Port 3: Dev 74, If 0, Class=Vendor Specific Class, Driver=udl, 480M
            |__ Port 3: Dev 72, If 0, Class=Audio, Driver=snd-usb-audio, 12M
            |__ Port 3: Dev 72, If 1, Class=Audio, Driver=snd-usb-audio, 12M
            |__ Port 3: Dev 72, If 2, Class=Audio, Driver=snd-usb-audio, 12M
            |__ Port 3: Dev 72, If 3, Class=Human Interface Device, Driver=usbhid, 12M
            |__ Port 3: Dev 72, If 4, Class=Application Specific Interface, Driver=, 12M
    |__ Port 4: Dev 4, If 0, Class=Wireless, Driver=btusb, 12M
    |__ Port 4: Dev 4, If 1, Class=Wireless, Driver=btusb, 12M
localhost ~ # lsusb -t                                                                                                                                                                                     
/:  Bus 02.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/4p, 5000M
    |__ Port 1: Dev 42, If 0, Class=Hub, Driver=hub/4p, 5000M
        |__ Port 1: Dev 47, If 0, Class=Video, Driver=uvcvideo, 5000M
        |__ Port 1: Dev 47, If 1, Class=Video, Driver=uvcvideo, 5000M
        |__ Port 1: Dev 47, If 2, Class=Video, Driver=uvcvideo, 5000M
        |__ Port 1: Dev 47, If 3, Class=Video, Driver=uvcvideo, 5000M
    |__ Port 2: Dev 43, If 0, Class=Hub, Driver=hub/4p, 5000M
        |__ Port 1: Dev 44, If 0, Class=Hub, Driver=hub/4p, 5000M
            |__ Port 1: Dev 45, If 0, Class=Hub, Driver=hub/4p, 5000M
/:  Bus 01.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/11p, 480M
    |__ Port 2: Dev 67, If 0, Class=Hub, Driver=hub/4p, 480M
    |__ Port 3: Dev 68, If 0, Class=Hub, Driver=hub/4p, 480M
        |__ Port 1: Dev 69, If 0, Class=Hub, Driver=hub/4p, 480M
            |__ Port 1: Dev 70, If 0, Class=Hub, Driver=hub/4p, 480M
            |__ Port 2: Dev 71, If 0, Class=Hub, Driver=hub/4p, 480M
                |__ Port 3: Dev 74, If 0, Class=Vendor Specific Class, Driver=udl, 480M
            |__ Port 3: Dev 72, If 0, Class=Audio, Driver=snd-usb-audio, 12M
            |__ Port 3: Dev 72, If 1, Class=Audio, Driver=snd-usb-audio, 12M
            |__ Port 3: Dev 72, If 2, Class=Audio, Driver=snd-usb-audio, 12M
            |__ Port 3: Dev 72, If 3, Class=Human Interface Device, Driver=usbhid, 12M
            |__ Port 3: Dev 72, If 4, Class=Application Specific Interface, Driver=, 12M
    |__ Port 4: Dev 4, If 0, Class=Wireless, Driver=btusb, 12M
    |__ Port 4: Dev 4, If 1, Class=Wireless, Driver=btusb, 12M



Complete log will be uploaded to google driver.

Comment 1 by mzhuo@chromium.org, Aug 17 2017

Cc: katierh@chromium.org itspeter@chromium.org smbar...@chromium.org
Components: Infra>Client>ChromeOS IO>USB
Labels: OS-Linux

Comment 2 by pprabhu@chromium.org, Aug 17 2017

Components: -Infra>Client>ChromeOS OS>Kernel
This is not an infra issue. Adding OS>Kernel as best guess.

Comment 3 by reillyg@chromium.org, Aug 17 2017

Components: -IO>USB
Removing IO>USB as this doesn't appear related to the browser process.

Comment 4 by katierh@chromium.org, Aug 22 2017

Labels: M-61
Owner: smbar...@chromium.org

Comment 5 by choonc@google.com, Aug 28 2017

Owner: sadolfsson@chromium.org

Comment 6 by sadolfsson@chromium.org, Aug 31 2017 

Min, is this somewhat easily reproducable so one could see if is exists on a newer kernel?

Comment 7 by benhenry@chromium.org, Aug 1

Status: Assigned (was: Untriaged)
►

Sign in to add a comment