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

Issue 863235 link

Starred by 1 user
Status: Duplicate
Merged: issue 867107
Owner:
grundler@chromium.org
Closed: Aug 4
Cc:
█ kirtika@chromium.org
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 3
Type: Bug



Sign in to add a comment

[nocturne] r8152 2-2.2:1.0 eth0: Tx status -71 (timeout)

Project Member Reported by grundler@chromium.org, Jul 12 
Using Unitech Y-3470B (RTL80153B; HWID 0x6010)

WARNING kernel: [55553.264000] r8152 2-2.2:1.0 eth0: Tx status -71

messages:2018-07-12T10:55:06.960198+08:00 INFO kernel: [  342.182107] r8152 2-1.4:1.0 eth0: Stop submitting intr, status -71

It's possible this is another symptom of USB host controller loosing communication with the USB device.

I'll attach logs in a follow up once I've reviewed it.

Comment 1 by grundler@chromium.org, Jul 13 

lsusb in the feedback logs doesn't show the NIC - seems like the NIC dropped off the USB bus completely.

/var/log/messages snippets:

2018-07-12T10:49:26.911463+08:00 NOTICE kernel: [    0.000000] Linux version 4.4.138-14463-ga3af6d2337eb .... #1 SMP PREEMPT Fri Jun 29 20:19:38 PDT 2018
...
2018-07-12T10:49:26.911502+08:00 DEBUG kernel: [    0.000000] DMI: Google Nocturne/Nocturne, BIOS Google_Nocturne.10819.1.0 07/11/2018
...
2018-07-12T10:53:03.406799+08:00 INFO kernel: [  218.627721] usb 2-2: new SuperSpeed USB device number 3 using xhci_hcd
2018-07-12T10:53:03.422196+08:00 INFO kernel: [  218.642898] usb 2-2: New USB device found, idVendor=050d, idProduct=090c, bcdDevice=51.01
2018-07-12T10:53:03.422233+08:00 INFO kernel: [  218.642914] usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2018-07-12T10:53:03.422238+08:00 INFO kernel: [  218.642922] usb 2-2: Product: USB-C HUB
2018-07-12T10:53:03.422241+08:00 INFO kernel: [  218.642929] usb 2-2: Manufacturer: CE-Link
2018-07-12T10:53:03.424988+08:00 INFO kernel: [  218.645770] hub 2-2:1.0: USB hub found
2018-07-12T10:53:03.425023+08:00 INFO kernel: [  218.646112] hub 2-2:1.0: 4 ports detected
2018-07-12T10:53:44.266858+08:00 INFO kernel: [  259.487798] usb 1-5: USB disconnect, device number 6
2018-07-12T10:53:44.363966+08:00 INFO kernel: [  259.584841] usb 2-2: USB disconnect, device number 3
2018-07-12T10:53:56.612872+08:00 INFO kernel: [  271.833716] usb 2-1: new SuperSpeed USB device number 4 using xhci_hcd
2018-07-12T10:53:56.726810+08:00 INFO kernel: [  271.946701] usb 2-1: New USB device found, idVendor=2109, idProduct=0813, bcdDevice=90.15
2018-07-12T10:53:56.726844+08:00 INFO kernel: [  271.946715] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2018-07-12T10:53:56.726848+08:00 INFO kernel: [  271.946722] usb 2-1: Product: USB3.0 Hub
2018-07-12T10:53:56.726851+08:00 INFO kernel: [  271.946728] usb 2-1: Manufacturer: VIA Labs, Inc.
2018-07-12T10:53:56.744881+08:00 INFO kernel: [  271.965618] hub 2-1:1.0: USB hub found
2018-07-12T10:53:56.744918+08:00 INFO kernel: [  271.966727] hub 2-1:1.0: 4 ports detected
2018-07-12T10:53:56.965859+08:00 ERR kernel: [  272.187390] hub 2-1:1.0: hub_ext_port_status failed (err = -71)
2018-07-12T10:53:57.430801+08:00 INFO kernel: [  272.651833] usb 2-1: USB disconnect, device number 4
2018-07-12T10:53:57.430844+08:00 ERR kernel: [  272.651863] usb 2-1: Failed to suspend device, error -19
2018-07-12T10:53:57.681875+08:00 INFO kernel: [  272.902805] usb 2-1: new SuperSpeed USB device number 5 using xhci_hcd
2018-07-12T10:53:57.796883+08:00 INFO kernel: [  273.017675] usb 2-1: New USB device found, idVendor=2109, idProduct=0813, bcdDevice=90.15
2018-07-12T10:53:57.796924+08:00 INFO kernel: [  273.017691] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2018-07-12T10:53:57.796929+08:00 INFO kernel: [  273.017700] usb 2-1: Product: USB3.0 Hub
2018-07-12T10:53:57.796932+08:00 INFO kernel: [  273.017706] usb 2-1: Manufacturer: VIA Labs, Inc.
2018-07-12T10:53:57.813812+08:00 INFO kernel: [  273.035027] hub 2-1:1.0: USB hub found
2018-07-12T10:53:57.815797+08:00 INFO kernel: [  273.037144] hub 2-1:1.0: 4 ports detected
...
2018-07-12T10:54:24.631804+08:00 INFO kernel: [  299.852932] usb 2-1: USB disconnect, device number 5
2018-07-12T10:54:25.170884+08:00 INFO kernel: [  300.392298] usb 2-1: new SuperSpeed USB device number 6 using xhci_hcd
2018-07-12T10:54:27.353851+08:00 INFO kernel: [  302.575291] PDLOG 2018/07/12 02:54:25.1531364064 P0 SRC 3000mA
2018-07-12T10:54:27.353906+08:00 INFO kernel: [  302.575777] PDLOG 2018/07/12 02:54:26.1531364065 P0 SRC 3000mA
2018-07-12T10:54:27.354784+08:00 INFO kernel: [  302.576509] PDLOG 2018/07/12 02:54:27.1531364066 P0 SNK Charger ??? 0mV max 5000mV / 1500mA
2018-07-12T10:54:27.355847+08:00 INFO kernel: [  302.577273] PDLOG 2018/07/12 02:54:27.1531364066 P0 SNK Charger ??? 0mV max 15000mV / 2670mA
2018-07-12T10:54:30.224853+08:00 ERR kernel: [  305.445872] usb 2-1: device descriptor read/all, error -110
...
2018-07-12T10:54:30.961895+08:00 INFO kernel: [  306.183415] usb 2-1: new SuperSpeed USB device number 7 using xhci_hcd
2018-07-12T10:54:31.075808+08:00 INFO kernel: [  306.295785] usb 2-1: New USB device found, idVendor=2109, idProduct=0813, bcdDevice=90.15
2018-07-12T10:54:31.075844+08:00 INFO kernel: [  306.295799] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2018-07-12T10:54:31.075848+08:00 INFO kernel: [  306.295806] usb 2-1: Product: USB3.0 Hub
2018-07-12T10:54:31.075850+08:00 INFO kernel: [  306.295812] usb 2-1: Manufacturer: VIA Labs, Inc.
2018-07-12T10:54:31.090839+08:00 INFO kernel: [  306.312034] hub 2-1:1.0: USB hub found
2018-07-12T10:54:31.093354+08:00 INFO kernel: [  306.314178] hub 2-1:1.0: 4 ports detected

...
2018-07-12T10:54:31.498897+08:00 INFO kernel: [  306.719905] usb 2-1.4: new SuperSpeed USB device number 8 using xhci_hcd
2018-07-12T10:54:31.510894+08:00 INFO kernel: [  306.732312] usb 2-1.4: New USB device found, idVendor=0bda, idProduct=8153, bcdDevice=31.00
2018-07-12T10:54:31.510938+08:00 INFO kernel: [  306.732328] usb 2-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=6
2018-07-12T10:54:31.510943+08:00 INFO kernel: [  306.732337] usb 2-1.4: Product: UNITEK Y-3470B
2018-07-12T10:54:31.510946+08:00 INFO kernel: [  306.732344] usb 2-1.4: Manufacturer: UNITEK
2018-07-12T10:54:31.510948+08:00 INFO kernel: [  306.732350] usb 2-1.4: SerialNumber: 001000001
2018-07-12T10:54:31.622940+08:00 INFO kernel: [  306.844249] usb 2-1.4: reset SuperSpeed USB device number 8 using xhci_hcd
2018-07-12T10:54:31.676135+08:00 INFO kernel: [  306.894246] r8152 2-1.4:1.0 eth0: v1.09.9
2018-07-12T10:54:31.799880+08:00 INFO kernel: [  307.021625] usbcore: registered new interface driver cdc_ether
2018-07-12T10:54:31.806821+08:00 INFO kernel: [  307.028651] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
2018-07-12T10:54:31.884315+08:00 INFO permission_broker[1252]: ProcessPath(/dev/bus/usb/002/008)
2018-07-12T10:54:31.983844+08:00 INFO permission_broker[1252]:   AllowUsbDeviceRule: ALLOW
2018-07-12T10:54:31.983863+08:00 INFO permission_broker[1252]:   AllowTtyDeviceRule: IGNORE
2018-07-12T10:54:32.069934+08:00 INFO permission_broker[1252]:   DenyClaimedUsbDeviceRule: DENY
2018-07-12T10:54:32.070079+08:00 INFO permission_broker[1252]: Verdict for /dev/bus/usb/002/008: DENY
2018-07-12T10:54:32.070194+08:00 ERR permission_broker[1252]: OpenPath(...): Domain=permission_broker, Code=permission_denied, Message=Permission to open '/dev/bus/usb/002/008' denied
2018-07-12T10:54:32.700504+08:00 ERR mtpd[1271]: LIBMTP_Detect_Raw_Devices failed with 5
2018-07-12T10:54:33.310427+08:00 INFO kernel: [  308.531562] r8152 2-1.4:1.0 eth0: carrier on
2018-07-12T10:54:33.310450+08:00 INFO kernel: [  308.531626] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
...

Other than the fact this takes place ~5 minutes after booting, it so far seems to be "working as intended".

...
2018-07-12T10:55:07.312075+08:00 INFO kernel: [  342.530894] usb 2-1: USB disconnect, device number 7
2018-07-12T10:55:07.312098+08:00 INFO kernel: [  342.530914] usb 2-1.4: USB disconnect, device number 8
2018-07-12T10:55:07.588813+08:00 INFO kernel: [  342.810485] usb 2-1: new SuperSpeed USB device number 9 using xhci_hcd
2018-07-12T10:55:07.702249+08:00 INFO kernel: [  342.923037] usb 2-1: New USB device found, idVendor=2109, idProduct=0813, bcdDevice=90.15
2018-07-12T10:55:07.702289+08:00 INFO kernel: [  342.923053] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2018-07-12T10:55:07.702293+08:00 INFO kernel: [  342.923062] usb 2-1: Product: USB3.0 Hub
2018-07-12T10:55:07.702296+08:00 INFO kernel: [  342.923069] usb 2-1: Manufacturer: VIA Labs, Inc.
2018-07-12T10:55:07.719803+08:00 INFO kernel: [  342.941533] hub 2-1:1.0: USB hub found
2018-07-12T10:55:07.721800+08:00 INFO kernel: [  342.943345] hub 2-1:1.0: 4 ports detected
...
2018-07-12T10:55:19.414175+08:00 INFO kernel: [  354.634890] usb 2-1: USB disconnect, device number 9

[suspend/resume of host]

2018-07-12T11:08:21.458935+08:00 INFO kernel: [ 1108.766020] usb 2-1: new SuperSpeed USB device number 10 using xhci_hcd
2018-07-12T11:08:21.570924+08:00 INFO kernel: [ 1108.877825] usb 2-1: New USB device found, idVendor=2109, idProduct=0813, bcdDevice=90.15
2018-07-12T11:08:21.570958+08:00 INFO kernel: [ 1108.877838] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2018-07-12T11:08:21.570961+08:00 INFO kernel: [ 1108.877845] usb 2-1: Product: USB3.0 Hub
2018-07-12T11:08:21.570964+08:00 INFO kernel: [ 1108.877850] usb 2-1: Manufacturer: VIA Labs, Inc.
2018-07-12T11:08:21.587985+08:00 INFO kernel: [ 1108.894647] hub 2-1:1.0: USB hub found
2018-07-12T11:08:21.589991+08:00 INFO kernel: [ 1108.896807] hub 2-1:1.0: 4 ports detected
2018-07-12T11:08:21.808968+08:00 ERR kernel: [ 1109.115754] hub 2-1:1.0: hub_ext_port_status failed (err = -71)
2018-07-12T11:08:22.259164+08:00 INFO kernel: [ 1109.566070] usb 2-1: USB disconnect, device number 10
2018-07-12T11:08:22.259200+08:00 ERR kernel: [ 1109.566095] usb 2-1: Failed to suspend device, error -19
2018-07-12T11:08:22.511995+08:00 INFO kernel: [ 1109.818271] usb 2-1: new SuperSpeed USB device number 11 using xhci_hcd
2018-07-12T11:08:22.625933+08:00 INFO kernel: [ 1109.932319] usb 2-1: New USB device found, idVendor=2109, idProduct=0813, bcdDevice=90.15
2018-07-12T11:08:22.625967+08:00 INFO kernel: [ 1109.932333] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2018-07-12T11:08:22.625971+08:00 INFO kernel: [ 1109.932340] usb 2-1: Product: USB3.0 Hub
2018-07-12T11:08:22.625973+08:00 INFO kernel: [ 1109.932345] usb 2-1: Manufacturer: VIA Labs, Inc.
2018-07-12T11:08:22.641915+08:00 INFO kernel: [ 1109.949118] hub 2-1:1.0: USB hub found
2018-07-12T11:08:22.644995+08:00 INFO kernel: [ 1109.951266] hub 2-1:1.0: 4 ports detected
...
2018-07-12T11:09:54.748948+08:00 INFO kernel: [ 1202.055618] usb 2-1: USB disconnect, device number 13
...
2018-07-12T11:10:04.074129+08:00 INFO kernel: [ 1211.380821] usb 2-1: new SuperSpeed USB device number 14 using xhci_hcd
2018-07-12T11:10:04.089148+08:00 INFO kernel: [ 1211.395827] usb 2-1: New USB device found, idVendor=050d, idProduct=090c, bcdDevice=51.01
2018-07-12T11:10:04.089183+08:00 INFO kernel: [ 1211.395840] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2018-07-12T11:10:04.089186+08:00 INFO kernel: [ 1211.395847] usb 2-1: Product: USB-C HUB
2018-07-12T11:10:04.089188+08:00 INFO kernel: [ 1211.395852] usb 2-1: Manufacturer: CE-Link
2018-07-12T11:10:04.091951+08:00 INFO kernel: [ 1211.398576] hub 2-1:1.0: USB hub found
2018-07-12T11:10:04.091982+08:00 INFO kernel: [ 1211.398896] hub 2-1:1.0: 4 ports detected
2018-07-12T11:10:54.736958+08:00 INFO kernel: [ 1262.043712] usb 1-1: USB disconnect, device number 20
2018-07-12T11:10:54.832953+08:00 INFO kernel: [ 1262.139619] usb 2-1: USB disconnect, device number 14

[what concerns me here is the disconnect/reconnect ~50 seconds later but no ethernet dongle is detected at 1109 nor at 1262 seconds after boot.]

Comment 2 by grundler@chromium.org, Jul 13 

Ah, and after the next reboot, nothing is discovered behind usb2. Seems like FW or HW got into a "bad" state.

Comment 3 by benhenry@chromium.org, Aug 3

Status: Assigned (was: Untriaged)
This bug has an owner, thus, it's been triaged. Changing status to "assigned".

Comment 4 by grundler@google.com, Aug 4

Mergedinto: 867107
Status: Duplicate (was: Assigned)
until I have more evidence, I suspect this is a dup of "bad Unitek" dongle issues and not specific to nocturne.
►

Sign in to add a comment