New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

USB 3 hangup during file transfer #112

Open
klerai opened this Issue Feb 26, 2018 · 72 comments

Comments

Projects
None yet
@klerai
Copy link

klerai commented Feb 26, 2018

During file transfer (rsync) over a powered USB 3 hub from sda to sdb, the kernel disconnects the USB channel after a few minutes. The board needs be rebooted.

The failure occurs with the first message in the kernel log (more details further down):
Feb 26 14:26:30 r64omv kernel: core: dev_pm_opp_get_voltage: Invalid parameters

  • messages displayed on the terminal during rsync session:

    Message from syslogd@r64omv at Feb 26 14:27:37 ...
    kernel:[ 1440.377657] Kernel panic - not syncing: hung_task: blocked tasks

    Message from syslogd@r64omv at Feb 26 14:27:38 ...
    kernel:[ 1440.849084] Kernel Offset: disabled

    Message from syslogd@r64omv at Feb 26 14:27:38 ...
    kernel:[ 1440.851529] Memory Limit: none

  • journalctl -k
    Feb 26 14:21:13 r64omv kernel: sd 2:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
    Feb 26 14:21:13 r64omv kernel: sdb: sdb1
    Feb 26 14:21:13 r64omv kernel: sd 2:0:0:0: [sdb] Attached SCSI removable disk
    Feb 26 14:21:13 r64omv kernel: BTRFS: device label DATA devid 1 transid 23 /dev/sdb1
    Feb 26 14:21:13 r64omv kernel: BTRFS info (device sdb1): disk space caching is enabled
    Feb 26 14:21:13 r64omv kernel: BTRFS: has skinny extents
    Feb 26 14:26:30 r64omv kernel: core: dev_pm_opp_get_voltage: Invalid parameters
    Feb 26 14:26:30 r64omv kernel: mali-utgard ff300000.gpu: Failed to get voltage for frequency 163840000: -34
    Feb 26 14:26:30 r64omv kernel: devfreq ff300000.gpu: Couldn't update frequency transition information.
    Feb 26 14:28:05 r64omv kernel: xhci-hcd xhci-hcd.8.auto: xHCI host not responding to stop endpoint command.
    Feb 26 14:28:05 r64omv kernel: xhci-hcd xhci-hcd.8.auto: Assuming host is dying, halting host.
    Feb 26 14:28:05 r64omv kernel: xhci-hcd xhci-hcd.8.auto: xHCI host not responding to stop endpoint command.
    Feb 26 14:28:05 r64omv kernel: xhci-hcd xhci-hcd.8.auto: Assuming host is dying, halting host.
    Feb 26 14:28:05 r64omv kernel: xhci-hcd xhci-hcd.8.auto: Host not halted after 16000 microseconds.
    Feb 26 14:28:05 r64omv kernel: hub 5-1.1:1.0: hub_port_status failed (err = -22)
    Feb 26 14:28:05 r64omv kernel: xhci-hcd xhci-hcd.8.auto: HC died; cleaning up
    Feb 26 14:28:05 r64omv kernel: usb 5-1.1-port1: cannot reset (err = -22)
    Feb 26 14:28:05 r64omv kernel: usb 5-1.1-port1: cannot reset (err = -22)
    Feb 26 14:28:05 r64omv kernel: usb 5-1.1-port1: cannot reset (err = -22)
    Feb 26 14:28:05 r64omv kernel: usb 5-1.1-port1: cannot reset (err = -22)
    Feb 26 14:28:05 r64omv kernel: usb 5-1.1-port1: cannot reset (err = -22)
    Feb 26 14:28:05 r64omv kernel: usb 5-1.1-port1: Cannot enable. Maybe the USB cable is bad?

@Linux-cpp-lisp

This comment has been minimized.

Copy link

Linux-cpp-lisp commented Feb 27, 2018

Can reproduce, having the same problem. Seems to only happen during periods of heavy writing; when I only run my read-heavy task things seem to be fine. I have two USB 3.0 hard drives on an unpowered hub attached to the USB 3.0 port on the Rock64. These errors make both of them malfunction, even though I'm only writing to one.

I've attached a more complete log, but I'm seeing many of the same error messages as @klerai above:

core: dev_pm_opp_get_voltage: Invalid parameters
[106534.429998] mali-utgard ff300000.gpu: Failed to get voltage for frequency 163840000: -34

Followed by some:

[107314.389632] usb 5-1-port1: cannot reset (err = -108)
[107314.389634] usb 5-1-port1: Cannot enable. Maybe the USB cable is bad?

And finally a large pile of Ext4 errors.

@xalius

This comment has been minimized.

Copy link

xalius commented Mar 30, 2018

Have you eliminated power supply problems (also on the hub), the USB3 spec only allows up to 900mA per port...

@Linux-cpp-lisp

This comment has been minimized.

Copy link

Linux-cpp-lisp commented Mar 30, 2018

I'm running three wall powered (have their own individual power supply) desktop hard drives off of an unpowered hub. I was under the impression that there wouldn't be any power draw (except for maybe the hub's status light), and that power wouldn't be the issue, but I'm no electrical engineer, so that may not be true.

It may also be relevant that, upon rebooting, one or two of the drives will often not be recognized as connected (i.e. don't show up in the output of sudo blkid), and have to be physically reattached to be recognized by the system.

@klerai

This comment has been minimized.

Copy link

klerai commented Apr 15, 2018

The USB 3.0 power specs were taken in consideration. Beside the Rock board with its standard power adapter, my USB in use is powered by its own 4 A power supply.

All tests runs were duplicated on a Gigabyte BRX i5 desktop machine (flawlessly).

The following tests were executed

  • with a pair of 2.5" 4TB external Seagate disks (data volume around 1.6 TB).
  • one 2.5" 4TB external Seagate disk and 1 64GB USB flash drive
  • two 64 GB USB flash drive
    using rsync -av /srv/dev1/* /srv/dev2/

My initial work was done on the Rock64 with release 0.5.14: jenkins-linux-build-rock-64-133. - It ended with the errors reported in my first message.
After I recognizing that a newer version 0.5.15: jenkins-linux-build-rock-64-136, which initially seemed to have solved the USB dropouts. I was able to run an initial backup from the first to the second Seagate disk. But sub sequential runs on modified content failed.

My experiences with other systems showed that USB dropouts of external disks are usually handled by the kernel as soon the sub-system becomes back online, while the hub stays online. But with the Rock64, the whole USB channel disappears no unplug or re-plugin helps. The board has to go through a complete reboot.

Below are my relevant kernel messages.

====
-- from boot-up...
Apr 07 21:14:05 r64omv kernel: Linux version 4.4.77-rockchip-ayufan-136 (root@713b8312374e)
..
Apr 07 21:14:05 r64omv kernel: Kernel command line: earlycon=uart8250,mmio32,0xff130000 rw root=LABEL=linux-root rootwait rootfstype=ext4
..
Apr 07 21:14:05 r64omv kernel: sched-energy: CPU device node has no sched-energy-costs
Apr 07 21:14:05 r64omv kernel: Invalid sched_group_energy for CPU0
...
Apr 07 21:14:05 r64omv kernel: genirq: Setting trigger mode 8 for irq 176 failed (gic_set_type+0x0/0x68)
..
Apr 07 21:14:05 r64omv kernel: rockchip-thermal ff250000.tsadc: Missing tshut mode property, using default (cru)
Apr 07 21:14:05 r64omv kernel: rockchip-thermal ff250000.tsadc: Missing tshut-polarity property, using default (low)
..
Apr 07 21:14:05 r64omv systemd[1]: Failed to insert module 'autofs4'
..
Apr 07 21:14:15 r64omv kernel: usb 5-1.1.1: New USB device found, idVendor=0bc2, idProduct=61b7
Apr 07 21:14:15 r64omv kernel: usb 5-1.1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
..
Apr 07 21:14:15 r64omv kernel: usb 5-1.1.1: UAS is blacklisted for this device, using usb-storage instead
..
Apr 07 21:14:05 r64omv kernel: rockchip-thermal ff250000.tsadc: Missing tshut mode property, using default (cru)
Apr 07 21:14:05 r64omv kernel: rockchip-thermal ff250000.tsadc: Missing tshut-polarity property, using default (low)
..
Apr 07 21:14:05 r64omv systemd[1]: Failed to insert module 'autofs4'

==================================================
===> and this is the kernel log after the USB channel drops out
Apr 7 15:10:46 r64omv kernel: [ 31.872062] rk_gmac-dwmac ff540000.eth eth0: Link is Up - 1Gbps/Full - flow control rx/tx
Apr 7 16:28:08 r64omv kernel: [ 4655.943883] xhci-hcd xhci-hcd.8.auto: xHCI host not responding to stop endpoint command.
Apr 7 16:28:08 r64omv kernel: [ 4655.944763] xhci-hcd xhci-hcd.8.auto: Assuming host is dying, halting host.
Apr 7 16:28:08 r64omv kernel: [ 4655.945499] xhci-hcd xhci-hcd.8.auto: xHCI host not responding to stop endpoint command.
Apr 7 16:28:08 r64omv kernel: [ 4655.946354] xhci-hcd xhci-hcd.8.auto: Assuming host is dying, halting host.
Apr 7 16:28:08 r64omv kernel: [ 4655.968853] xhci-hcd xhci-hcd.8.auto: Host not halted after 16000 microseconds.
Apr 7 16:28:08 r64omv kernel: [ 4655.969628] xhci-hcd xhci-hcd.8.auto: Non-responsive xHCI host is not halting.
Apr 7 16:28:08 r64omv kernel: [ 4655.970388] xhci-hcd xhci-hcd.8.auto: Completing active URBs anyway.
Apr 7 16:28:08 r64omv kernel: [ 4655.970436] xhci-hcd xhci-hcd.8.auto: Host not halted after 16000 microseconds.
Apr 7 16:28:08 r64omv kernel: [ 4655.971836] xhci-hcd xhci-hcd.8.auto: Non-responsive xHCI host is not halting.
Apr 7 16:28:08 r64omv kernel: [ 4655.972597] xhci-hcd xhci-hcd.8.auto: Completing active URBs anyway.
Apr 7 16:28:08 r64omv kernel: [ 4655.973306] xhci-hcd xhci-hcd.8.auto: HC died; cleaning up
Apr 7 16:28:08 r64omv kernel: [ 4655.973311] xhci-hcd xhci-hcd.8.auto: HC died; cleaning up
Apr 7 16:28:08 r64omv kernel: [ 4655.973346] hub 5-1.1:1.0: hub_port_status failed (err = -108)
Apr 7 16:28:08 r64omv kernel: [ 4655.973401] usb 5-1.1.1: USB disconnect, device number 4
Apr 7 16:28:08 r64omv kernel: [ 4655.978022] usb 4-1: USB disconnect, device number 6
Apr 7 16:28:08 r64omv kernel: [ 4655.978579] usb 4-1.1: USB disconnect, device number 7
Apr 7 16:28:08 r64omv kernel: [ 4655.980772] usb 5-1: USB disconnect, device number 2
Apr 7 16:28:08 r64omv kernel: [ 4655.981408] usb 5-1.1: USB disconnect, device number 3
Apr 7 16:28:08 r64omv kernel: [ 4655.981929] sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=0x00
Apr 7 16:28:08 r64omv kernel: [ 4655.981937] sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x88 88 00 00 00 00 00 6e 7c 26 00 00 00 00 f0 00 00

@Linux-cpp-lisp

This comment has been minimized.

Copy link

Linux-cpp-lisp commented Apr 17, 2018

It's also quite clear that this issue is specifically ties to disk writes: I mounted the same disks as read-only and have been running a read-intensive workload for at least a week now without issue. When I run the same read-intensive workload but mount the disks RW, the issue occurs after a few days, I'm assuming as a result of the constant updating of atimes.

If I run a write-intensive load on the same disks, I get this issue within hours if not minutes.

@PeterPlatt

This comment has been minimized.

Copy link

PeterPlatt commented Apr 21, 2018

Same problem here. I'm trying to switch to the USB 2.0 port to see if it helps. System hangs when there are heavy writes to the external drive.

@marcosscriven

This comment has been minimized.

Copy link

marcosscriven commented Jun 16, 2018

Just came to report the same issue - this time by hitting two USB3 ethernet adapters at the same time (with connection bonding software).
I'll try another hub, but there's too many reports both on this issue, and this forum post https://forum.pine64.org/showthread.php?tid=5557 to suggest it's anything other than the Rock64 at fault here.

@Linux-cpp-lisp

This comment has been minimized.

Copy link

Linux-cpp-lisp commented Jun 17, 2018

@stevefan1999-personal

This comment has been minimized.

Copy link

stevefan1999-personal commented Jul 14, 2018

Can confirm. Just yesterday my Rock64 halted but I have no logs available because my system was booted from an external USB3 HDD, so presumably, USB3 is the problem of my random freezes. I used to reproduce this behavior by setting my APM and AAM to the best performant option possible (and hence power-hungry), maybe I triggered the polyfuse or some other protection mechanism that I'm unaware of? Or is it the temp sensor detected an anomaly so it just went off without leaving any logs for me...

Thankfully there are no bad blocks. The system has ran without trouble for a week before. If this keeps going on how am I going to keep this R64 for my NAS solution?

EDIT: It happened again 30 minutes ago. I was under both frequent reads and writes that I have BitTorrent and Plex running alongside each other. Too bad I hadn't got a spare and long enough USB cable to see what happened. That's why you shouldn't put a headless server around your router only.

EDIT 2: Just realized the absense of logs are pretty reasonable to be caused by folder2ram, so the logs are discarded in memory when I forcefully reboots. Gotta see something interesting in the dmesg after removing folder2ram if the USB hell ever happened again.

EDIT 3: Nope, nothing special beside the spamming of this line during the USB hang up:

Jul 15 14:46:07 nas kernel: [59526.130023] rockchip-sip: function[82000008]: args:[2,0,2] => res:[0,2ed96880,0,0]
Jul 15 14:46:07 nas kernel: [59526.131809] rockchip-sip: function[82000008]: args:[2,0,2] => res:[0,2ed96880,0,0]
Jul 15 14:46:07 nas kernel: [59526.152878] rockchip-sip: function[82000008]: args:[2,0,2] => res:[0,2ed96880,0,0]
Jul 15 14:46:07 nas kernel: [59526.154704] rockchip-sip: function[82000008]: args:[2,0,2] => res:[0,2ed96880,0,0]
Jul 15 14:46:07 nas kernel: [59526.215919] rockchip-sip: function[82000008]: args:[2,0,2] => res:[0,2ed96880,0,0]
...

So yeah I think I tripped the USB3 chipset, it has some flaws in it.

@Redwid

This comment has been minimized.

Copy link

Redwid commented Jul 16, 2018

The same for me.
Two 2.5" disks connected to usb 3.0 powered hub. On rsync data from one to another - disks disconnects. No way to return them back, only reboot helps.
I'm on 4.4.126-rockchip-ayufan-239.

@ayufan

This comment has been minimized.

Copy link
Member

ayufan commented Jul 16, 2018

@Redwid

This comment has been minimized.

Copy link

Redwid commented Jul 16, 2018

Thanks ayufan, I'll try in the evening.
Is that 0.5.15, right?
https://github.com/ayufan-rock64/linux-build/releases/tag/0.5.15

@ayufan

This comment has been minimized.

Copy link
Member

ayufan commented Jul 16, 2018

@Redwid

This comment has been minimized.

Copy link

Redwid commented Jul 16, 2018

The same result for jessie-openmediavault-rock64-0.5.15-136-armhf.img.xz
All usb disk are gone after copying about 7Gb of files.

[    1.962239] xhci-hcd xhci-hcd.8.auto: xHCI Host Controller
[    1.970443] xhci-hcd xhci-hcd.8.auto: new USB bus registered, assigned bus number 4
[    1.978738] xhci-hcd xhci-hcd.8.auto: hcc params 0x0220fe64 hci version 0x110 quirks 0x00210010
[    1.987008] xhci-hcd xhci-hcd.8.auto: irq 180, io mem 0xff600000
[    2.019606] usb usb4: Manufacturer: Linux 4.4.77-rockchip-ayufan-136 xhci-hcd
[    2.027901] usb usb4: SerialNumber: xhci-hcd.8.auto
[    2.053603] xhci-hcd xhci-hcd.8.auto: xHCI Host Controller
[    2.062301] xhci-hcd xhci-hcd.8.auto: new USB bus registered, assigned bus number 5
[    2.105026] usb usb5: Manufacturer: Linux 4.4.77-rockchip-ayufan-136 xhci-hcd
[    2.113525] usb usb5: SerialNumber: xhci-hcd.8.auto
[    2.367992] usb 4-1: new high-speed USB device number 2 using xhci-hcd
[    2.650176] usb 5-1: new SuperSpeed USB device number 2 using xhci-hcd
[    3.107050] usb 5-1.2: new SuperSpeed USB device number 3 using xhci-hcd
[    3.215063] usb 5-1.3: new SuperSpeed USB device number 4 using xhci-hcd
[  538.069919] usb 5-1.3: new SuperSpeed USB device number 5 using xhci-hcd
[  538.333933] usb 5-1.1: new SuperSpeed USB device number 6 using xhci-hcd
[  915.868847] xhci-hcd xhci-hcd.8.auto: xHCI host not responding to stop endpoint command.
[  915.873804] xhci-hcd xhci-hcd.8.auto: Assuming host is dying, halting host.
[  915.878549] xhci-hcd xhci-hcd.8.auto: xHCI host not responding to stop endpoint command.
[  915.883450] xhci-hcd xhci-hcd.8.auto: Assuming host is dying, halting host.
[  915.901904] xhci-hcd xhci-hcd.8.auto: Host not halted after 16000 microseconds.
[  915.905494] xhci-hcd xhci-hcd.8.auto: HC died; cleaning up
[  916.342611] xhci-hcd xhci-hcd.8.auto: Non-responsive xHCI host is not halting.
[  916.348589] xhci-hcd xhci-hcd.8.auto: Completing active URBs anyway.
[  916.354428] xhci-hcd xhci-hcd.8.auto: HC died; cleaning up```


```root@rock64:~# dmesg | grep "usb"
[    0.587825] usbcore: registered new interface driver usbfs
[    0.588430] usbcore: registered new interface driver hub
[    0.589038] usbcore: registered new device driver usb
[    1.106062] phy phy-ff450000.syscon:usb2-phy@100.0: Looking up phy-supply from device tree
[    1.106083] phy phy-ff450000.syscon:usb2-phy@100.0: Looking up phy-supply property in node /syscon@ff450000/usb2-phy@100/host-port failed
[    1.106614] phy phy-ff450000.syscon:usb2-phy@100.1: Looking up phy-supply from device tree
[    1.106634] phy phy-ff450000.syscon:usb2-phy@100.1: Looking up phy-supply property in node /syscon@ff450000/usb2-phy@100/otg-port failed
[    1.107150] rockchip-usb2phy ff450000.syscon:usb2-phy@100: GPIO lookup for consumer otg-vbus
[    1.107166] rockchip-usb2phy ff450000.syscon:usb2-phy@100: using device tree for GPIO lookup
[    1.107209] of_get_named_gpiod_flags: parsed 'otg-vbus-gpios' property of node '/syscon@ff450000/usb2-phy@100[0]' - status (0)
[    1.107958] rockchip-u3phy ff470000.usb3-phy: GPIO lookup for consumer vbus-drv
[    1.107975] rockchip-u3phy ff470000.usb3-phy: using device tree for GPIO lookup
[    1.108007] of_get_named_gpiod_flags: parsed 'vbus-drv-gpios' property of node '/usb3-phy@ff470000[0]' - status (0)
[    1.108402] phy phy-ff470000.usb3-phy.2: Looking up phy-supply from device tree
[    1.108421] phy phy-ff470000.usb3-phy.2: Looking up phy-supply property in node /usb3-phy@ff470000/utmi@ff470000 failed
[    1.108918] phy phy-ff470000.usb3-phy.3: Looking up phy-supply from device tree
[    1.108937] phy phy-ff470000.usb3-phy.3: Looking up phy-supply property in node /usb3-phy@ff470000/pipe@ff478000 failed
[    1.110685] rockchip-u3phy ff470000.usb3-phy: Rockchip u3phy initialized successfully
[    1.426222] usbcore: registered new interface driver cdc_ether
[    1.434287] usbcore: registered new interface driver rndis_host
[    1.443898] phy phy-ff470000.usb3-phy.2: u3phy u2 power on
[    1.451610] phy phy-ff470000.usb3-phy.3: u3phy u3 power on
[    1.460065] dwc2 ff580000.usb: Looking up vusb_d-supply from device tree
[    1.460086] dwc2 ff580000.usb: Looking up vusb_d-supply property in node /usb@ff580000 failed
[    1.460103] ff580000.usb supply vusb_d not found, using dummy regulator
[    1.467685] dwc2 ff580000.usb: Looking up vusb_a-supply from device tree
[    1.467704] dwc2 ff580000.usb: Looking up vusb_a-supply property in node /usb@ff580000 failed
[    1.467718] ff580000.usb supply vusb_a not found, using dummy regulator
[    1.631113] dwc2 ff580000.usb: DWC OTG Controller
[    1.638195] dwc2 ff580000.usb: new USB bus registered, assigned bus number 1
[    1.645471] dwc2 ff580000.usb: irq 40, io mem 0x00000000
[    1.652794] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.659977] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.667109] usb usb1: Product: DWC OTG Controller
[    1.673885] usb usb1: Manufacturer: Linux 4.4.77-rockchip-ayufan-136 dwc2_hsotg
[    1.680967] usb usb1: SerialNumber: ff580000.usb
[    1.726426] ehci-platform ff5c0000.usb: EHCI Host Controller
[    1.733479] ehci-platform ff5c0000.usb: new USB bus registered, assigned bus number 2
[    1.740833] ehci-platform ff5c0000.usb: irq 41, io mem 0xff5c0000
[    1.752897] ehci-platform ff5c0000.usb: USB 2.0 started, EHCI 1.00
[    1.760152] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
[    1.767153] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.774178] usb usb2: Product: EHCI Host Controller
[    1.781056] usb usb2: Manufacturer: Linux 4.4.77-rockchip-ayufan-136 ehci_hcd
[    1.788330] usb usb2: SerialNumber: ff5c0000.usb
[    1.826658] ohci-platform ff5d0000.usb: Generic Platform OHCI controller
[    1.834615] ohci-platform ff5d0000.usb: new USB bus registered, assigned bus number 3
[    1.842595] ohci-platform ff5d0000.usb: irq 42, io mem 0xff5d0000
[    1.905175] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001
[    1.913039] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.920937] usb usb3: Product: Generic Platform OHCI controller
[    1.928730] usb usb3: Manufacturer: Linux 4.4.77-rockchip-ayufan-136 ohci_hcd
[    1.936725] usb usb3: SerialNumber: ff5d0000.usb
[    1.995280] usb usb4: New USB device found, idVendor=1d6b, idProduct=0002
[    2.003420] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.011587] usb usb4: Product: xHCI Host Controller
[    2.019606] usb usb4: Manufacturer: Linux 4.4.77-rockchip-ayufan-136 xhci-hcd
[    2.027901] usb usb4: SerialNumber: xhci-hcd.8.auto
[    2.070960] usb usb5: We don't know the algorithms for LPM for this host, disabling LPM.
[    2.079784] usb usb5: New USB device found, idVendor=1d6b, idProduct=0003
[    2.088269] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.096794] usb usb5: Product: xHCI Host Controller
[    2.105026] usb usb5: Manufacturer: Linux 4.4.77-rockchip-ayufan-136 xhci-hcd
[    2.113525] usb usb5: SerialNumber: xhci-hcd.8.auto
[    2.141273] usbcore: registered new interface driver usbtouchscreen
[    2.321148] usbcore: registered new interface driver uvcvideo
[    2.367992] usb 4-1: new high-speed USB device number 2 using xhci-hcd
[    2.416998] usbcore: registered new interface driver bfusb
[    2.424928] usbcore: registered new interface driver btusb
[    2.552225] usb 4-1: New USB device found, idVendor=05e3, idProduct=0610
[    2.552230] usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.552233] usb 4-1: Product: USB2.0 Hub
[    2.552237] usb 4-1: Manufacturer: GenesysLogic
[    2.613522] of_get_named_gpiod_flags: parsed 'gpios' property of node '/switches/usb-switch[0]' - status (0)
[    2.625722] usbcore: registered new interface driver usbhid
[    2.631459] usbhid: USB HID core driver
[    2.637485] usbcore: registered new interface driver snd-usb-audio
[    2.650176] usb 5-1: new SuperSpeed USB device number 2 using xhci-hcd
[    2.813900] usb 5-1: New USB device found, idVendor=05e3, idProduct=0616
[    2.813905] usb 5-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.813909] usb 5-1: Product: USB3.0 Hub
[    2.813913] usb 5-1: Manufacturer: GenesysLogic
[    3.107050] usb 5-1.2: new SuperSpeed USB device number 3 using xhci-hcd
[    3.123152] usb 5-1.2: New USB device found, idVendor=0bc2, idProduct=ab26
[    3.128511] usb 5-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.133802] usb 5-1.2: Product: BUP Slim BK
[    3.138675] usb 5-1.2: Manufacturer: Seagate
[    3.143560] usb 5-1.2: SerialNumber: NA97MBXT
[    3.215063] usb 5-1.3: new SuperSpeed USB device number 4 using xhci-hcd
[    3.230707] usb 5-1.3: New USB device found, idVendor=2537, idProduct=1068
[    3.234948] usb 5-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.239215] usb 5-1.3: Product: NS1068
[    3.243131] usb 5-1.3: Manufacturer: Norelsys
[    3.247138] usb 5-1.3: SerialNumber: 0123456789ABCDE
[    3.256436] usb 5-1.2: UAS is blacklisted for this device, using usb-storage instead
[    3.260745] usb-storage 5-1.2:1.0: USB Mass Storage device detected
[    3.265693] usb-storage 5-1.2:1.0: Quirks match for vid 0bc2 pid ab26: 800000
[    3.270094] scsi host0: usb-storage 5-1.2:1.0
[    3.274550] usb 5-1.3: UAS is blacklisted for this device, using usb-storage instead
[    3.279016] usb-storage 5-1.3:1.0: USB Mass Storage device detected
[    3.283643] usb-storage 5-1.3:1.0: Quirks match for vid 2537 pid 1068: 800000
[    3.288222] scsi host1: usb-storage 5-1.3:1.0
[    3.292872] usbcore: registered new interface driver usb-storage
[    3.298157] usbcore: registered new interface driver uas
[    3.871933] usb 1-1: new high-speed USB device number 2 using dwc2
[    4.338793] usb 1-1: New USB device found, idVendor=0bc2, idProduct=ab26
[    4.338800] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    4.338805] usb 1-1: Product: BUP Slim RD
[    4.338810] usb 1-1: Manufacturer: Seagate
[    4.338814] usb 1-1: SerialNumber: NA9JK89E
[    4.339752] usb 1-1: UAS is blacklisted for this device, using usb-storage instead
[    4.339760] usb-storage 1-1:1.0: USB Mass Storage device detected
[    4.375079] usb-storage 1-1:1.0: Quirks match for vid 0bc2 pid ab26: 800000
[    4.380152] scsi host2: usb-storage 1-1:1.0
[  533.797163] usb 1-1: USB disconnect, device number 2
[  537.799553] usb 5-1.3: USB disconnect, device number 4
[  538.069919] usb 5-1.3: new SuperSpeed USB device number 5 using xhci-hcd
[  538.081470] usb 5-1.3: New USB device found, idVendor=2537, idProduct=1068
[  538.082230] usb 5-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[  538.083040] usb 5-1.3: Product: NS1068
[  538.083451] usb 5-1.3: Manufacturer: Norelsys
[  538.083942] usb 5-1.3: SerialNumber: 0123456789ABCDE
[  538.085325] usb 5-1.3: UAS is blacklisted for this device, using usb-storage instead
[  538.086179] usb-storage 5-1.3:1.0: USB Mass Storage device detected
[  538.087626] usb-storage 5-1.3:1.0: Quirks match for vid 2537 pid 1068: 800000
[  538.089073] scsi host3: usb-storage 5-1.3:1.0
[  538.333933] usb 5-1.1: new SuperSpeed USB device number 6 using xhci-hcd
[  538.346007] usb 5-1.1: New USB device found, idVendor=0bc2, idProduct=ab26
[  538.346752] usb 5-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[  538.347553] usb 5-1.1: Product: BUP Slim RD
[  538.348032] usb 5-1.1: Manufacturer: Seagate
[  538.348490] usb 5-1.1: SerialNumber: NA9JK89E
[  538.349801] usb 5-1.1: UAS is blacklisted for this device, using usb-storage instead
[  538.350682] usb-storage 5-1.1:1.0: USB Mass Storage device detected
[  538.351975] usb-storage 5-1.1:1.0: Quirks match for vid 0bc2 pid ab26: 800000
[  538.352791] scsi host4: usb-storage 5-1.1:1.0
[  915.905561] usb 5-1.1: USB disconnect, device number 6
[  915.905600] usb 4-1: USB disconnect, device number 2
[  915.906698] usb 5-1: USB disconnect, device number 2
[  918.129776] usb 5-1.2: USB disconnect, device number 3
[  919.322503] usb 5-1.3: USB disconnect, device number 5
@Redwid

This comment has been minimized.

Copy link

Redwid commented Jul 18, 2018

The same result on latest armbian firmware. But in general it allows me to copy more (>100Gb) that ayufan's ones :(

@ayufan

This comment has been minimized.

Copy link
Member

ayufan commented Jul 18, 2018

What do you see when USB3 drops?

@Redwid

This comment has been minimized.

Copy link

Redwid commented Jul 18, 2018

Just nothing, no disks at all. I'm doing rsync copy of urbackup data from one disk to another.

@ShapeShifter499

This comment has been minimized.

Copy link

ShapeShifter499 commented Jul 30, 2018

I'm seeing this issue here. I reported it to m01/rock64-arch-linux-build#2 and to https://archlinuxarm.org/forum/viewtopic.php?p=58934#p58934

I'm using a powered USB 3.0 hub from Amazon. All three hard drives connected stop working, it appears the controller runs into issues and stops working. I have two laptop external drives and one desktop external drive that has it's own separate power apart from the USB hub power.

EDIT: Running Arch Linux ARM with linux-aarch64-rc kernel package

[Jul29 18:07] usb 5-1.3: reset SuperSpeed Gen 1 USB device number 4 using xhci-hcd
[Jul29 18:17] usb 5-1.2: reset SuperSpeed Gen 1 USB device number 3 using xhci-hcd
[Jul29 18:32] usb 5-1.2: reset SuperSpeed Gen 1 USB device number 3 using xhci-hcd
[  +0.030627] sd 0:0:0:0: [sdb] tag#0 FAILED Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK
[  +0.000737] sd 0:0:0:0: [sdb] tag#0 CDB: Read(16) 88 00 00 00 00 00 c8 80 5e 10 00 00 00 f8 00 00
[  +0.000775] print_req_error: I/O error, dev sdb, sector 3363855888
[Jul29 18:37] usb 5-1.2: reset SuperSpeed Gen 1 USB device number 3 using xhci-hcd
[  +0.030621] sd 0:0:0:0: [sdb] tag#0 FAILED Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK
[  +0.000737] sd 0:0:0:0: [sdb] tag#0 CDB: Read(16) 88 00 00 00 00 00 fd 80 1e 18 00 00 00 e8 00 00
[  +0.000775] print_req_error: I/O error, dev sdb, sector 4253031960
[Jul29 18:45] usb 5-1.2: reset SuperSpeed Gen 1 USB device number 3 using xhci-hcd
[Jul29 18:51] usb 5-1.3: reset SuperSpeed Gen 1 USB device number 4 using xhci-hcd
[Jul29 19:50] usb 5-1.2: reset SuperSpeed Gen 1 USB device number 3 using xhci-hcd
[Jul29 19:51] xhci-hcd xhci-hcd.0.auto: xHCI host not responding to stop endpoint command.
[  +0.000722] xhci-hcd xhci-hcd.0.auto: xHCI host controller not responding, assume dead
[  +0.000768] hub 5-1:1.0: hub_ext_port_status failed (err = -22)
[  +0.000033] usb 5-1.4: cmd cmplt err -108
[  +0.000491] usb 5-1-port2: cannot reset (err = -22)
[  +0.000348] usb 5-1.4: cmd cmplt err -108
[  +0.000423] usb 5-1-port2: cannot reset (err = -22)
[  +0.000353] xhci-hcd xhci-hcd.0.auto: HC died; cleaning up
[  +0.000420] usb 5-1-port2: cannot reset (err = -22)
[  +0.000518] usb 4-1: USB disconnect, device number 2
[  +0.000410] usb 5-1.2: USB disconnect, device number 3
[  +0.001351] usb 5-1: USB disconnect, device number 2
[  +0.001152] sd 0:0:0:0: [sdb] Synchronizing SCSI cache
[  +0.003028] xhci-hcd xhci-hcd.0.auto: xHCI host not responding to stop endpoint command.
[  +0.020067] sd 0:0:0:0: [sdb] tag#0 FAILED Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
[  +0.000774] sd 0:0:0:0: [sdb] tag#0 CDB: Read(16) 88 00 00 00 00 01 72 5f 58 00 00 00 01 00 00 00
[  +0.000775] print_req_error: I/O error, dev sdb, sector 6213818368
[  +0.000652] sd 0:0:0:0: [sdb] tag#0 FAILED Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
[  +0.000775] sd 0:0:0:0: [sdb] tag#0 CDB: Write(16) 8a 00 00 00 00 01 71 e1 19 00 00 00 01 00 00 00
[  +0.000786] print_req_error: I/O error, dev sdb, sector 6205544704
[  +0.000568] EXT4-fs warning (device dm-1): ext4_end_bio:323: I/O error 10 writing to inode 21307152 (offset 536477696 size 131072 starting block 775692320)
[  +0.000491] EXT4-fs warning (device dm-1): ext4_end_bio:323: I/O error 10 writing to inode 21307152 (offset 144703488 size 131072 starting block 775593248)
[  +0.000728] Buffer I/O error on device dm-1, logical block 775692320
[  +0.000017] Buffer I/O error on device dm-1, logical block 775692321
[  +0.001208] Buffer I/O error on device dm-1, logical block 775593248
[  +0.000550] Buffer I/O error on device dm-1, logical block 775692322
[  +0.000556] Buffer I/O error on device dm-1, logical block 775593249
[  +0.000547] Buffer I/O error on device dm-1, logical block 775692323
[  +0.000551] Buffer I/O error on device dm-1, logical block 775593250
[  +0.000551] Buffer I/O error on device dm-1, logical block 775692324
[  +0.000594] Buffer I/O error on device dm-1, logical block 775593251
[  +0.000551] Buffer I/O error on device dm-1, logical block 775692325
@ShapeShifter499

This comment has been minimized.

Copy link

ShapeShifter499 commented Jul 30, 2018

I have since switched back to USB 2.0, it's slower but I have yet to run into any crashing.

EDIT: Since switching back to using the USB 2.0 ports I noticed some errors about my luks encrypted USB hard drives being misaligned has gone away.

@ShapeShifter499 ShapeShifter499 referenced this issue Jul 30, 2018

Closed

mergerfs: page allocation failure #488

5 of 5 tasks complete
@Redwid

This comment has been minimized.

Copy link

Redwid commented Jul 31, 2018

I do have this set up:
usb 3.0 -> powered usb 3.0 hub -> 2 usb disks
By running rsync from one disk to another, all disks gone after about 5-10 minutes.

If I do that connection:
usb 3.0 -> powered usb 3.0 hub -> usb disk #1
usb 2.0 -> usb disk #2
Than, doing rsync from disk #1 to disk #2 everything works fine. I do have a big set of urbackup data (more than 500Gb). Sync finished successfully after 24 hours. All disks still available.

I've used this hub and power supply before in my other single board NAS (banana pi pro). The board able to handle 4 disks simultaneously at that time.

@ShapeShifter499

This comment has been minimized.

Copy link

ShapeShifter499 commented Aug 1, 2018

I switched my USB hub out for a "Anker 10 Port 60W Data Hub with 7 USB 3.0 Ports and 3 PowerIQ Charging Ports"

Now I cannot even mount any drives or do anything USB with that hub attached. Below is the log from dmesg.

[root@kumo boot]# dmesg
[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 4.18.0-rc7-2-ARCH (builduser@leming) (gcc version 8.1.1 20180531 (GCC)) #1 SMP Mon Jul 30 18:05:51 MDT 2018
[    0.000000] Machine model: Pine64 Rock64
[    0.000000] earlycon: uart8250 at MMIO32 0x00000000ff130000 (options '')
[    0.000000] bootconsole [uart8250] enabled
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: UEFI not found.
[    0.000000] cma: Reserved 64 MiB at 0x00000000f7c00000
[    0.000000] On node 0 totalpages: 1043968
[    0.000000]   DMA32 zone: 16312 pages used for memmap
[    0.000000]   DMA32 zone: 0 pages reserved
[    0.000000]   DMA32 zone: 1043968 pages, LIFO batch:31
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: PSCIv1.0 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: MIGRATE_INFO_TYPE not supported.
[    0.000000] psci: SMC Calling Convention v1.0
[    0.000000] random: get_random_bytes called from start_kernel+0x9c/0x45c with crng_init=0
[    0.000000] percpu: Embedded 24 pages/cpu @(____ptrval____) s59672 r8192 d30440 u98304
[    0.000000] pcpu-alloc: s59672 r8192 d30440 u98304 alloc=24*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1027656
[    0.000000] Kernel command line: console=ttyS2,1500000 rw rootwait usbcore.autosuspend=-1 earlycon=uart8250,mmio32,0xff130000
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.000000] Memory: 3996084K/4175872K available (13116K kernel code, 2458K rwdata, 8440K rodata, 1600K init, 762K bss, 114252K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 47133 entries in 185 pages
[    0.000000] Hierarchical RCU implementation.
[    0.000000] 	RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4.
[    0.000000] 	Tasks RCU enabled.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] rockchip_mmc_get_phase: invalid clk rate
[    0.000000] rockchip_mmc_get_phase: invalid clk rate
[    0.000000] rockchip_mmc_get_phase: invalid clk rate
[    0.000000] rockchip_mmc_get_phase: invalid clk rate
[    0.000000] rockchip_mmc_get_phase: invalid clk rate
[    0.000000] rockchip_mmc_get_phase: invalid clk rate
[    0.000000] rockchip_mmc_get_phase: invalid clk rate
[    0.000000] rockchip_mmc_get_phase: invalid clk rate
[    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[    0.000006] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[    0.001737] Console: colour dummy device 80x25
[    0.002186] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=240000)
[    0.003171] pid_max: default: 32768 minimum: 301
[    0.003795] Security Framework initialized
[    0.004190] Yama: becoming mindful.
[    0.004622] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.005277] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.007783] ASID allocator initialised with 32768 entries
[    0.008468] Hierarchical SRCU implementation.
[    0.013256] EFI services will not be available.
[    0.014181] smp: Bringing up secondary CPUs ...
[    0.015316] Detected VIPT I-cache on CPU1
[    0.015380] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.016180] Detected VIPT I-cache on CPU2
[    0.016237] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
[    0.017021] Detected VIPT I-cache on CPU3
[    0.017074] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
[    0.017193] smp: Brought up 1 node, 4 CPUs
[    0.020622] SMP: Total of 4 processors activated.
[    0.021073] CPU features: detected: 32-bit EL0 Support
[    0.025871] CPU: All CPU(s) started at EL2
[    0.026283] alternatives: patching kernel code
[    0.029259] devtmpfs: initialized
[    0.038908] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.039853] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.043542] pinctrl core: initialized pinctrl subsystem
[    0.045205] DMI not present or invalid.
[    0.046114] NET: Registered protocol family 16
[    0.048479] cpuidle: using governor ladder
[    0.049056] cpuidle: using governor menu
[    0.049820] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____))
[    0.050527] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.052667] DMA: preallocated 256 KiB pool for atomic allocations
[    0.053831] Serial: AMBA PL011 UART driver
[    0.085164] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.086178] cryptd: max_cpu_qlen set to 1000
[    0.087404] ACPI: Interpreter disabled.
[    0.090075] vgaarb: loaded
[    0.091361] SCSI subsystem initialized
[    0.091999] libata version 3.00 loaded.
[    0.092303] usbcore: registered new interface driver usbfs
[    0.092882] usbcore: registered new interface driver hub
[    0.093489] usbcore: registered new device driver usb
[    0.094449] pps_core: LinuxPPS API ver. 1 registered
[    0.094922] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.095800] PTP clock support registered
[    0.096430] EDAC MC: Ver: 3.0.0
[    0.097766] Advanced Linux Sound Architecture Driver Initialized.
[    0.098936] NetLabel: Initializing
[    0.099267] NetLabel:  domain hash size = 128
[    0.099680] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.100291] NetLabel:  unlabeled traffic allowed by default
[    0.101275] clocksource: Switched to clocksource arch_sys_counter
[    0.240476] VFS: Disk quotas dquot_6.6.0
[    0.240931] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.242018] pnp: PnP ACPI: disabled
[    0.251792] NET: Registered protocol family 2
[    0.252901] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes)
[    0.253726] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[    0.254804] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[    0.256157] TCP: Hash tables configured (established 32768 bind 32768)
[    0.256933] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[    0.257647] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[    0.258710] NET: Registered protocol family 1
[    0.259821] RPC: Registered named UNIX socket transport module.
[    0.260389] RPC: Registered udp transport module.
[    0.260838] RPC: Registered tcp transport module.
[    0.261321] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.261938] PCI: CLS 0 bytes, default 64
[    0.262147] Unpacking initramfs...
[    1.542205] Freeing initrd memory: 15456K
[    1.543710] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
[    1.544709] kvm [1]: 8-bit VMID
[    1.547054] kvm [1]: vgic interrupt IRQ1
[    1.547591] kvm [1]: Hyp mode initialized successfully
[    1.680462] Initialise system trusted keyrings
[    1.681076] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    1.689233] zbud: loaded
[    1.692608] NFS: Registering the id_resolver key type
[    1.693115] Key type id_resolver registered
[    1.693515] Key type id_legacy registered
[    1.693908] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.694755] SGI XFS with ACLs, security attributes, no debug enabled
[    1.698244] pstore: using deflate compression
[    3.882290] NET: Registered protocol family 38
[    3.882730] Key type asymmetric registered
[    3.883123] Asymmetric key parser 'x509' registered
[    3.883688] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)
[    3.884565] io scheduler noop registered
[    3.884943] io scheduler deadline registered
[    3.885680] io scheduler cfq registered (default)
[    3.886130] io scheduler mq-deadline registered
[    3.886564] io scheduler kyber registered
[    3.887100] io scheduler bfq registered
[    3.895012] ipmi message handler version 39.2
[    3.900676] dma-pl330 ff1f0000.dmac: Loaded driver for PL330 DMAC-241330
[    3.901388] dma-pl330 ff1f0000.dmac: 	DBUFF-128x8bytes Num_Chans-8 Num_Peri-20 Num_Events-16
[    3.905948] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    3.909068] console [ttyS2] disabled
[    3.909473] ff130000.serial: ttyS2 at MMIO 0xff130000 (irq = 12, base_baud = 1500000) is a 16550A
[    3.910366] console [ttyS2] enabled
[    3.911021] bootconsole [uart8250] disabled
[    3.912924] msm_serial: driver initialized
[    3.919317] libphy: Fixed MDIO Bus: probed
[    3.921508] dwc3 ff600000.dwc3: Failed to get clk 'ref': -2
[    3.923388] dwc2 ff580000.usb: ff580000.usb supply vusb_d not found, using dummy regulator
[    3.924207] dwc2 ff580000.usb: ff580000.usb supply vusb_a not found, using dummy regulator
[    3.937649] dwc2 ff580000.usb: DWC OTG Controller
[    3.938098] dwc2 ff580000.usb: new USB bus registered, assigned bus number 1
[    3.938756] dwc2 ff580000.usb: irq 26, io mem 0xff580000
[    3.939500] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.18
[    3.940232] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.940870] usb usb1: Product: DWC OTG Controller
[    3.941356] usb usb1: Manufacturer: Linux 4.18.0-rc7-2-ARCH dwc2_hsotg
[    3.941935] usb usb1: SerialNumber: ff580000.usb
[    3.942937] hub 1-0:1.0: USB hub found
[    3.943311] hub 1-0:1.0: 1 port detected
[    3.945013] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    3.945605] ehci-pci: EHCI PCI platform driver
[    3.946057] ehci-platform: EHCI generic platform driver
[    3.948891] ehci-platform ff5c0000.usb: EHCI Host Controller
[    3.949621] ehci-platform ff5c0000.usb: new USB bus registered, assigned bus number 2
[    3.950657] ehci-platform ff5c0000.usb: irq 27, io mem 0xff5c0000
[    3.971300] ehci-platform ff5c0000.usb: USB 2.0 started, EHCI 1.00
[    3.972118] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.18
[    3.972849] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.973487] usb usb2: Product: EHCI Host Controller
[    3.973923] usb usb2: Manufacturer: Linux 4.18.0-rc7-2-ARCH ehci_hcd
[    3.974485] usb usb2: SerialNumber: ff5c0000.usb
[    3.975460] hub 2-0:1.0: USB hub found
[    3.975831] hub 2-0:1.0: 1 port detected
[    3.976686] ehci-orion: EHCI orion driver
[    3.977227] tegra-ehci: Tegra EHCI driver
[    3.977694] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    3.978261] ohci-pci: OHCI PCI platform driver
[    3.978735] ohci-platform: OHCI generic platform driver
[    3.979464] ohci-platform ff5d0000.usb: Generic Platform OHCI controller
[    3.980275] ohci-platform ff5d0000.usb: new USB bus registered, assigned bus number 3
[    3.981235] ohci-platform ff5d0000.usb: irq 28, io mem 0xff5d0000
[    4.055533] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.18
[    4.056267] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    4.056906] usb usb3: Product: Generic Platform OHCI controller
[    4.057432] usb usb3: Manufacturer: Linux 4.18.0-rc7-2-ARCH ohci_hcd
[    4.057995] usb usb3: SerialNumber: ff5d0000.usb
[    4.059024] hub 3-0:1.0: USB hub found
[    4.059398] hub 3-0:1.0: 1 port detected
[    4.060221] uhci_hcd: USB Universal Host Controller Interface driver
[    4.061409] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
[    4.062124] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 4
[    4.063382] xhci-hcd xhci-hcd.0.auto: hcc params 0x0220fe64 hci version 0x110 quirks 0x0000000002010010
[    4.064265] xhci-hcd xhci-hcd.0.auto: irq 162, io mem 0xff600000
[    4.065209] usb usb4: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.18
[    4.065941] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    4.066579] usb usb4: Product: xHCI Host Controller
[    4.067013] usb usb4: Manufacturer: Linux 4.18.0-rc7-2-ARCH xhci-hcd
[    4.067576] usb usb4: SerialNumber: xhci-hcd.0.auto
[    4.068619] hub 4-0:1.0: USB hub found
[    4.069000] hub 4-0:1.0: 1 port detected
[    4.069741] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
[    4.070437] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 5
[    4.071127] xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0  SuperSpeed
[    4.071837] usb usb5: We don't know the algorithms for LPM for this host, disabling LPM.
[    4.072699] usb usb5: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.18
[    4.073429] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    4.074066] usb usb5: Product: xHCI Host Controller
[    4.074501] usb usb5: Manufacturer: Linux 4.18.0-rc7-2-ARCH xhci-hcd
[    4.075063] usb usb5: SerialNumber: xhci-hcd.0.auto
[    4.076064] hub 5-0:1.0: USB hub found
[    4.076437] hub 5-0:1.0: 1 port detected
[    4.077654] usbcore: registered new interface driver uas
[    4.078310] usbcore: registered new interface driver usb-storage
[    4.078881] usbcore: registered new interface driver ums-alauda
[    4.079443] usbcore: registered new interface driver ums-cypress
[    4.080014] usbcore: registered new interface driver ums-datafab
[    4.080581] usbcore: registered new interface driver ums_eneub6250
[    4.081169] usbcore: registered new interface driver ums-freecom
[    4.081775] usbcore: registered new interface driver ums-isd200
[    4.082339] usbcore: registered new interface driver ums-jumpshot
[    4.082916] usbcore: registered new interface driver ums-karma
[    4.083468] usbcore: registered new interface driver ums-onetouch
[    4.084061] usbcore: registered new interface driver ums-realtek
[    4.084632] usbcore: registered new interface driver ums-sddr09
[    4.085201] usbcore: registered new interface driver ums-sddr55
[    4.085769] usbcore: registered new interface driver ums-usbat
[    4.086390] usbcore: registered new interface driver usbserial_generic
[    4.086998] usbserial: USB Serial support registered for generic
[    4.089112] mousedev: PS/2 mouse device common for all mice
[    4.093133] rk808 1-0018: chip id: 0x8050
[    4.101483] rk808-regulator rk808-regulator: there is no dvs0 gpio
[    4.102063] rk808-regulator rk808-regulator: there is no dvs1 gpio
[    4.102674] DCDC_REG1: supplied by vcc_sys
[    4.105515] vcc_host_5v: supplied by vcc_sys
[    4.105976] vcc_host1_5v: supplied by vcc_sys
[    4.106466] DCDC_REG2: supplied by vcc_sys
[    4.108350] DCDC_REG3: supplied by vcc_sys
[    4.108980] DCDC_REG4: supplied by vcc_sys
[    4.110534] LDO_REG1: supplied by vcc_io
[    4.114318] LDO_REG2: supplied by vcc_io
[    4.118109] LDO_REG3: supplied by vcc_sys
[    4.126814] device-mapper: uevent: version 1.0.3
[    4.127628] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com
[    4.131391] sdhci: Secure Digital Host Controller Interface driver
[    4.131939] sdhci: Copyright(c) Pierre Ossman
[    4.132663] Synopsys Designware Multimedia Card Interface Driver
[    4.133802] dwmmc_rockchip ff500000.dwmmc: IDMAC supports 32-bit address mode.
[    4.134571] dwmmc_rockchip ff500000.dwmmc: Using internal DMA controller.
[    4.135172] dwmmc_rockchip ff500000.dwmmc: Version ID is 270a
[    4.135718] dwmmc_rockchip ff500000.dwmmc: DW MMC controller at irq 23,32 bit host data width,256 deep fifo
[    4.136594] vcc_sd: supplied by vcc_io
[    4.149757] mmc_host mmc0: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0)
[    4.161818] dwmmc_rockchip ff520000.dwmmc: IDMAC supports 32-bit address mode.
[    4.162701] dwmmc_rockchip ff520000.dwmmc: Using internal DMA controller.
[    4.163304] dwmmc_rockchip ff520000.dwmmc: Version ID is 270a
[    4.163839] dwmmc_rockchip ff520000.dwmmc: DW MMC controller at irq 24,32 bit host data width,256 deep fifo
[    4.165293] mmc_host mmc1: card is non-removable.
[    4.178365] mmc_host mmc1: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0)
[    4.192248] sdhci-pltfm: SDHCI platform and OF driver helper
[    4.193371] ledtrig-cpu: registered to indicate activity on CPUs
[    4.194234] hidraw: raw HID events driver (C) Jiri Kosina
[    4.194818] usbcore: registered new interface driver usbhid
[    4.195322] usbhid: USB HID core driver
[    4.197820] mmc_host mmc0: Bus speed (slot 0) = 50000000Hz (slot req 50000000Hz, actual 50000000HZ div = 0)
[    4.198137] drop_monitor: Initializing network drop monitor service
[    4.198740] mmc0: new high speed SDXC card at address 59b4
[    4.199399] Initializing XFRM netlink socket
[    4.200484] mmcblk0: mmc0:59b4 UD128 119 GiB 
[    4.200667] NET: Registered protocol family 10
[    4.202572]  mmcblk0: p1 p2
[    4.207890] Segment Routing with IPv6
[    4.208275] mip6: Mobile IPv6
[    4.208541] NET: Registered protocol family 17
[    4.209073] Key type dns_resolver registered
[    4.209929] registered taskstats version 1
[    4.210293] Loading compiled-in X.509 certificates
[    4.210786] zswap: loaded using pool lzo/zbud
[    4.211346] Key type big_key registered
[    4.216930] Key type encrypted registered
[    4.221403] mmc_host mmc1: Bus speed (slot 0) = 300000Hz (slot req 300000Hz, actual 300000HZ div = 0)
[    4.225692] hctosys: unable to open rtc device (rtc0)
[    4.227430] ALSA device list:
[    4.227698]   No soundcards found.
[    4.228176] ttyS2 - failed to request DMA
[    4.233146] Freeing unused kernel memory: 1600K
[    4.250949] random: fast init done
[    4.266203] mmc_host mmc1: Bus speed (slot 0) = 200000Hz (slot req 200000Hz, actual 200000HZ div = 0)
[    4.294184] systemd[1]: systemd 239 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN +PCRE2 default-hierarchy=hybrid)
[    4.296364] systemd[1]: Detected architecture arm64.
[    4.296808] systemd[1]: Running in initial RAM disk.
[    4.318125] mmc_host mmc1: Bus speed (slot 0) = 100000Hz (slot req 100000Hz, actual 100000HZ div = 0)
[    4.321379] systemd[1]: No hostname configured.
[    4.321792] systemd[1]: Set hostname to <archlinux>.
[    4.322362] random: systemd: uninitialized urandom read (16 bytes read)
[    4.322957] systemd[1]: Initializing machine ID from random generator.
[    4.397771] random: systemd: uninitialized urandom read (16 bytes read)
[    4.398588] systemd[1]: Listening on udev Kernel Socket.
[    4.421494] random: systemd: uninitialized urandom read (16 bytes read)
[    4.422131] systemd[1]: Reached target Slices.
[    4.431310] usb 4-1: new high-speed USB device number 2 using xhci-hcd
[    4.454051] systemd[1]: Reached target Swap.
[    4.481421] systemd[1]: Reached target Timers.
[    4.601288] usb 3-1: new low-speed USB device number 2 using ohci-platform
[    4.624813] usb 4-1: New USB device found, idVendor=0bda, idProduct=5411, bcdDevice= 1.23
[    4.625532] usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    4.626156] usb 4-1: Product: 4-Port USB 2.0 Hub
[    4.626561] usb 4-1: Manufacturer: Generic
[    4.680578] hub 4-1:1.0: USB hub found
[    4.682005] hub 4-1:1.0: 4 ports detected
[    4.841882] usb 5-1: new SuperSpeed Gen 1 USB device number 2 using xhci-hcd
[    4.885916] usb 5-1: New USB device found, idVendor=0bda, idProduct=0411, bcdDevice= 1.23
[    4.886638] usb 5-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    4.887262] usb 5-1: Product: 4-Port USB 3.0 Hub
[    4.887668] usb 5-1: Manufacturer: Generic
[    4.899379] usb 3-1: New USB device found, idVendor=051d, idProduct=0002, bcdDevice= 1.06
[    4.900102] usb 3-1: New USB device strings: Mfr=3, Product=1, SerialNumber=2
[    4.900733] usb 3-1: Product: Back-UPS ES 550G FW:904.W1 .D USB FW:W1 
[    4.901335] usb 3-1: Manufacturer: APC
[    4.901668] usb 3-1: SerialNumber: 4B1544P16283  
[    4.923350] hub 5-1:1.0: USB hub found
[    4.924906] hub 5-1:1.0: 4 ports detected
[    5.042281] hid-generic 0003:051D:0002.0001: hiddev96,hidraw0: USB HID v1.10 Device [APC Back-UPS ES 550G FW:904.W1 .D USB FW:W1 ] on usb-ff5d0000.usb-1/input0
[    5.061340] usb 4-1.4: new high-speed USB device number 3 using xhci-hcd
[    5.205495] usb 4-1.4: New USB device found, idVendor=0bda, idProduct=5411, bcdDevice= 1.23
[    5.206238] usb 4-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    5.206878] usb 4-1.4: Product: 4-Port USB 2.0 Hub
[    5.207299] usb 4-1.4: Manufacturer: Generic
[    5.256701] hub 4-1.4:1.0: USB hub found
[    5.258218] hub 4-1.4:1.0: 4 ports detected
[    5.319928] usb 5-1.1: new SuperSpeed Gen 1 USB device number 3 using xhci-hcd
[    5.342584] usb 5-1.1: New USB device found, idVendor=1058, idProduct=25ee, bcdDevice=40.04
[    5.343328] usb 5-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    5.343972] usb 5-1.1: Product: My Book 25EE
[    5.344351] usb 5-1.1: Manufacturer: Western Digital
[    5.344789] usb 5-1.1: SerialNumber: 574343374B3141595A4C3059
[    5.356935] usb-storage 5-1.1:1.0: USB Mass Storage device detected
[    5.358382] scsi host0: usb-storage 5-1.1:1.0
[    5.391405] hub 5-1:1.0: hub_ext_port_status failed (err = -71)
[    5.391993] usb 5-1-port2: cannot reset (err = -71)
[    5.392470] usb 5-1-port2: cannot reset (err = -71)
[    5.392946] usb 5-1-port2: cannot reset (err = -71)
[    5.393417] usb 5-1-port2: cannot reset (err = -71)
[    5.393847] usb 5-1-port2: Cannot enable. Maybe the USB cable is bad?
[    5.394491] usb 5-1-port2: cannot disable (err = -71)
[    5.395251] hub 5-1:1.0: hub_ext_port_status failed (err = -71)
[    5.395860] usb 5-1-port2: cannot reset (err = -71)
[    5.396399] usb 5-1-port2: cannot reset (err = -71)
[    5.396960] usb 5-1-port2: cannot reset (err = -71)
[    5.397488] usb 5-1-port2: cannot reset (err = -71)
[    5.398018] usb 5-1-port2: cannot reset (err = -71)
[    5.398455] usb 5-1-port2: Cannot enable. Maybe the USB cable is bad?
[    5.399699] usb 5-1-port2: cannot disable (err = -71)
[    5.400260] usb 5-1-port2: attempt power cycle
[    5.421002] force_sf_dma_mode is ignored if force_thresh_dma_mode is set.
[    5.421039] rk_gmac-dwmac ff540000.ethernet: PTP uses main clock
[    5.422491] rk_gmac-dwmac ff540000.ethernet: clock input or output? (input).
[    5.423127] rk_gmac-dwmac ff540000.ethernet: TX delay(0x24).
[    5.423634] rk_gmac-dwmac ff540000.ethernet: RX delay(0x18).
[    5.424140] rk_gmac-dwmac ff540000.ethernet: integrated PHY? (no).
[    5.426468] rk_gmac-dwmac ff540000.ethernet: clock input from PHY
[    5.432091] rk_gmac-dwmac ff540000.ethernet: init for RGMII
[    5.432822] rk_gmac-dwmac ff540000.ethernet: User ID: 0x10, Synopsys ID: 0x35
[    5.433462] rk_gmac-dwmac ff540000.ethernet: 	DWMAC1000
[    5.433926] rk_gmac-dwmac ff540000.ethernet: DMA HW capability register supported
[    5.434582] rk_gmac-dwmac ff540000.ethernet: RX Checksum Offload Engine supported
[    5.435238] rk_gmac-dwmac ff540000.ethernet: COE Type 2
[    5.435697] rk_gmac-dwmac ff540000.ethernet: Wake-Up On Lan supported
[    5.436297] rk_gmac-dwmac ff540000.ethernet: Normal descriptors
[    5.436820] rk_gmac-dwmac ff540000.ethernet: Ring mode enabled
[    5.437334] rk_gmac-dwmac ff540000.ethernet: Enable RX Mitigation via HW Watchdog Timer
[    5.467578] libphy: stmmac: probed
[    5.467905] mdio_bus stmmac-0:00: attached PHY driver [unbound] (mii_bus:phy_addr=stmmac-0:00, irq=POLL)
[    5.468740] mdio_bus stmmac-0:03: attached PHY driver [unbound] (mii_bus:phy_addr=stmmac-0:03, irq=POLL)
[    5.524249] Generic PHY stmmac-0:00: attached PHY driver [Generic PHY] (mii_bus:phy_addr=stmmac-0:00, irq=POLL)
[    5.531422] rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found
[    5.532106] rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW
[    5.533165] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    5.731551] hub 5-1:1.0: hub_ext_port_status failed (err = -71)
[    5.732115] usb 5-1-port2: cannot reset (err = -71)
[    5.732587] usb 5-1-port2: cannot reset (err = -71)
[    5.733050] usb 5-1-port2: cannot reset (err = -71)
[    5.733532] usb 5-1-port2: cannot reset (err = -71)
[    5.733989] usb 5-1-port2: cannot reset (err = -71)
[    5.734417] usb 5-1-port2: Cannot enable. Maybe the USB cable is bad?
[    5.735242] hub 5-1:1.0: hub_ext_port_status failed (err = -71)
[    5.735818] usb 5-1-port2: cannot reset (err = -71)
[    5.736284] usb 5-1-port2: cannot reset (err = -71)
[    5.736810] usb 5-1-port2: cannot reset (err = -71)
[    5.737290] usb 5-1-port2: cannot reset (err = -71)
[    5.737804] usb 5-1-port2: cannot reset (err = -71)
[    5.738236] usb 5-1-port2: Cannot enable. Maybe the USB cable is bad?
[    5.738875] usb 5-1-port2: unable to enumerate USB device
[    5.739425] hub 5-1:1.0: hub_ext_port_status failed (err = -71)
[    5.739983] usb 5-1.1: USB disconnect, device number 3
[    6.040380] usb 5-1: reset SuperSpeed Gen 1 USB device number 2 using xhci-hcd
[    6.421327] usb 5-1.1: new SuperSpeed Gen 1 USB device number 8 using xhci-hcd
[    6.452200] usb 5-1.1: New USB device found, idVendor=1058, idProduct=25ee, bcdDevice=40.04
[    6.452936] usb 5-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    6.453575] usb 5-1.1: Product: My Book 25EE
[    6.453951] usb 5-1.1: Manufacturer: Western Digital
[    6.454386] usb 5-1.1: SerialNumber: 574343374B3141595A4C3059
[    6.455681] usb-storage 5-1.1:1.0: USB Mass Storage device detected
[    6.456470] scsi host0: usb-storage 5-1.1:1.0
[    6.551338] usb 5-1.2: new SuperSpeed Gen 1 USB device number 9 using xhci-hcd
[    6.552839] rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[    6.553612] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[    6.582239] usb 5-1.2: New USB device found, idVendor=0480, idProduct=0110, bcdDevice= 1.00
[    6.582976] usb 5-1.2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    6.583615] usb 5-1.2: Product: External USB 3.0
[    6.584020] usb 5-1.2: Manufacturer: TOSHIBA
[    6.584396] usb 5-1.2: SerialNumber: DK3P712205BB
[    6.586326] usb-storage 5-1.2:1.0: USB Mass Storage device detected
[    6.587145] scsi host1: usb-storage 5-1.2:1.0
[    6.703161] usb 5-1.4: Device not responding to setup address.
[    6.921318] usb 5-1.4: Device not responding to setup address.
[    7.141287] usb 5-1.4: device not accepting address 10, error -71
[    7.141885] usb 5-1-port4: cannot disable (err = -71)
[    7.142482] hub 5-1:1.0: hub_ext_port_status failed (err = -71)
[    7.143028] usb 5-1-port4: cannot reset (err = -71)
[    7.143480] usb 5-1-port4: cannot reset (err = -71)
[    7.143932] usb 5-1-port4: cannot reset (err = -71)
[    7.144398] usb 5-1-port4: cannot reset (err = -71)
[    7.144850] usb 5-1-port4: cannot reset (err = -71)
[    7.145276] usb 5-1-port4: Cannot enable. Maybe the USB cable is bad?
[    7.145864] usb 5-1-port4: cannot disable (err = -71)
[    7.146318] usb 5-1-port4: attempt power cycle
[    7.481417] hub 5-1:1.0: hub_ext_port_status failed (err = -71)
[    7.481970] usb 5-1-port4: cannot reset (err = -71)
[    7.482423] usb 5-1-port4: cannot reset (err = -71)
[    7.482874] usb 5-1-port4: cannot reset (err = -71)
[    7.483326] usb 5-1-port4: cannot reset (err = -71)
[    7.483777] usb 5-1-port4: cannot reset (err = -71)
[    7.484204] usb 5-1-port4: Cannot enable. Maybe the USB cable is bad?
[    7.484792] usb 5-1-port4: cannot disable (err = -71)
[    7.485358] hub 5-1:1.0: hub_ext_port_status failed (err = -71)
[    7.485902] usb 5-1-port4: cannot reset (err = -71)
[    7.486353] usb 5-1-port4: cannot reset (err = -71)
[    7.486804] usb 5-1-port4: cannot reset (err = -71)
[    7.487256] usb 5-1-port4: cannot reset (err = -71)
[    7.487707] usb 5-1-port4: cannot reset (err = -71)
[    7.488133] usb 5-1-port4: Cannot enable. Maybe the USB cable is bad?
[    7.488720] usb 5-1-port4: cannot disable (err = -71)
[    7.489173] usb 5-1-port4: unable to enumerate USB device
[    7.489671] usb 5-1-port4: cannot disable (err = -71)
[    7.490156] usb 5-1.1: USB disconnect, device number 8
[    7.521974] usb 5-1.2: USB disconnect, device number 9
[    7.799879] usb 5-1: Device not responding to setup address.
[    8.011339] usb 5-1: Device not responding to setup address.
[    8.231292] usb 5-1: device not accepting address 2, error -71
[    8.461825] usb 5-1: reset SuperSpeed Gen 1 USB device number 2 using xhci-hcd
[    8.861380] usb 5-1.1: new SuperSpeed Gen 1 USB device number 14 using xhci-hcd
[    8.892200] usb 5-1.1: New USB device found, idVendor=1058, idProduct=25ee, bcdDevice=40.04
[    8.892936] usb 5-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    8.893575] usb 5-1.1: Product: My Book 25EE
[    8.893951] usb 5-1.1: Manufacturer: Western Digital
[    8.894386] usb 5-1.1: SerialNumber: 574343374B3141595A4C3059
[    8.895695] usb-storage 5-1.1:1.0: USB Mass Storage device detected
[    8.896514] scsi host0: usb-storage 5-1.1:1.0
[    8.991337] usb 5-1.2: new SuperSpeed Gen 1 USB device number 15 using xhci-hcd
[    9.022136] usb 5-1.2: New USB device found, idVendor=0480, idProduct=0110, bcdDevice= 1.00
[    9.022872] usb 5-1.2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    9.023511] usb 5-1.2: Product: External USB 3.0
[    9.023918] usb 5-1.2: Manufacturer: TOSHIBA
[    9.024293] usb 5-1.2: SerialNumber: DK3P712205BB
[    9.025528] usb-storage 5-1.2:1.0: USB Mass Storage device detected
[    9.026315] scsi host1: usb-storage 5-1.2:1.0
[    9.121867] usb 5-1.4: new SuperSpeed Gen 1 USB device number 16 using xhci-hcd
[    9.165188] usb 5-1.4: New USB device found, idVendor=0bda, idProduct=0411, bcdDevice= 1.23
[    9.165924] usb 5-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    9.166563] usb 5-1.4: Product: 4-Port USB 3.0 Hub
[    9.166984] usb 5-1.4: Manufacturer: Generic
[    9.196054] hub 5-1.4:1.0: USB hub found
[    9.197772] hub 5-1.4:1.0: 4 ports detected
[    9.521337] usb 5-1.4.3: new SuperSpeed Gen 1 USB device number 17 using xhci-hcd
[    9.552258] usb 5-1.4.3: New USB device found, idVendor=1058, idProduct=25e2, bcdDevice=40.05
[    9.553011] usb 5-1.4.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    9.553665] usb 5-1.4.3: Product: My Passport 25E2
[    9.554087] usb 5-1.4.3: Manufacturer: Western Digital
[    9.554536] usb 5-1.4.3: SerialNumber: 5758353144393748364E434B
[    9.556175] usb-storage 5-1.4.3:1.0: USB Mass Storage device detected
[    9.557450] scsi host2: usb-storage 5-1.4.3:1.0
[    9.651339] usb 5-1.4.4: new SuperSpeed Gen 1 USB device number 18 using xhci-hcd
[    9.682200] usb 5-1.4.4: New USB device found, idVendor=0bc2, idProduct=2322, bcdDevice= 0.00
[    9.682952] usb 5-1.4.4: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    9.683607] usb 5-1.4.4: Product: Expansion
[    9.683974] usb 5-1.4.4: Manufacturer: Seagate
[    9.684365] usb 5-1.4.4: SerialNumber: NA823B8K
[    9.695392] scsi host3: uas
[    9.696711] scsi 3:0:0:0: Direct-Access     Seagate  Expansion        9300 PQ: 0 ANSI: 6
[    9.698705] sd 3:0:0:0: Attached scsi generic sg0 type 0
[    9.699211] sd 3:0:0:0: [sda] 1465149167 512-byte logical blocks: (750 GB/699 GiB)
[    9.699887] sd 3:0:0:0: [sda] 4096-byte physical blocks
[    9.911852] scsi 0:0:0:0: Direct-Access     WD       My Book 25EE     4004 PQ: 0 ANSI: 6
[    9.912926] scsi 0:0:0:1: Enclosure         WD       SES Device       4004 PQ: 0 ANSI: 6
[    9.915276] sd 0:0:0:0: Attached scsi generic sg1 type 0
[    9.915446] sd 0:0:0:0: [sdb] Very big device. Trying to use READ CAPACITY(16).
[    9.916403] scsi 0:0:0:1: Attached scsi generic sg2 type 13
[    9.916637] sd 0:0:0:0: [sdb] 7814035456 512-byte logical blocks: (4.00 TB/3.64 TiB)
[    9.917581] sd 0:0:0:0: [sdb] 4096-byte physical blocks
[    9.918378] sd 0:0:0:0: [sdb] Write Protect is off
[    9.918812] sd 0:0:0:0: [sdb] Mode Sense: 47 00 10 08
[    9.919137] sd 0:0:0:0: [sdb] No Caching mode page found
[    9.919619] sd 0:0:0:0: [sdb] Assuming drive cache: write through
[    9.938204] usb 5-1.1: USB disconnect, device number 14
[    9.971317] sd 0:0:0:0: [sdb] tag#0 FAILED Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
[    9.972090] sd 0:0:0:0: [sdb] tag#0 CDB: Read(16) 88 00 00 00 00 00 00 00 00 00 00 00 00 08 00 00
[    9.972866] print_req_error: I/O error, dev sdb, sector 0
[    9.973340] Buffer I/O error on dev sdb, logical block 0, async page read
[    9.974019] ldm_validate_partition_table(): Disk read failed.
[    9.974582] Dev sdb: unable to read RDB block 0
[    9.975009]  sdb: unable to read partition table
[    9.975898] sd 0:0:0:0: [sdb] Read Capacity(16) failed: Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
[    9.976770] sd 0:0:0:0: [sdb] Sense not available.
[    9.977349] sd 0:0:0:0: [sdb] Read Capacity(10) failed: Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
[    9.978217] sd 0:0:0:0: [sdb] Sense not available.
[    9.978645] sd 0:0:0:0: [sdb] 0 512-byte logical blocks: (0 B/0 B)
[    9.979187] sd 0:0:0:0: [sdb] 4096-byte physical blocks
[    9.979776] sd 0:0:0:0: [sdb] Attached SCSI disk
[   10.071670] hub 5-1:1.0: hub_ext_port_status failed (err = -71)
[   10.072224] usb 5-1-port2: cannot reset (err = -71)
[   10.072677] usb 5-1-port2: cannot reset (err = -71)
[   10.073129] usb 5-1-port2: cannot reset (err = -71)
[   10.073580] usb 5-1-port2: cannot reset (err = -71)
[   10.074031] usb 5-1-port2: cannot reset (err = -71)
[   10.074458] usb 5-1-port2: Cannot enable. Maybe the USB cable is bad?
[   10.075048] usb 5-1-port2: cannot disable (err = -71)
[   10.075524] hub 5-1:1.0: hub_ext_port_status failed (err = -71)
[   10.076067] usb 5-1-port2: cannot reset (err = -71)
[   10.076523] usb 5-1-port2: cannot reset (err = -71)
[   10.076974] usb 5-1-port2: cannot reset (err = -71)
[   10.077426] usb 5-1-port2: cannot reset (err = -71)
[   10.077877] usb 5-1-port2: cannot reset (err = -71)
[   10.078303] usb 5-1-port2: Cannot enable. Maybe the USB cable is bad?
[   10.078898] usb 5-1-port2: cannot disable (err = -71)
[   10.079367] hub 5-1:1.0: hub_ext_port_status failed (err = -71)
[   10.079909] usb 5-1-port2: cannot reset (err = -71)
[   10.080360] usb 5-1-port2: cannot reset (err = -71)
[   10.080811] usb 5-1-port2: cannot reset (err = -71)
[   10.082704] usb 5-1-port2: cannot reset (err = -71)
[   10.083203] usb 5-1-port2: cannot reset (err = -71)
[   10.083634] usb 5-1-port2: Cannot enable. Maybe the USB cable is bad?
[   10.084249] usb 5-1-port2: cannot disable (err = -71)
[   10.084750] hub 5-1:1.0: hub_ext_port_status failed (err = -71)
[   10.085310] usb 5-1-port2: cannot reset (err = -71)
[   10.085788] usb 5-1-port2: cannot reset (err = -71)
[   10.086249] usb 5-1-port2: cannot reset (err = -71)
[   10.086702] usb 5-1-port2: cannot reset (err = -71)
[   10.087155] usb 5-1-port2: cannot reset (err = -71)
[   10.087583] usb 5-1-port2: Cannot enable. Maybe the USB cable is bad?
[   10.088172] usb 5-1-port2: cannot disable (err = -71)
[   10.088648] usb 5-1-port2: cannot disable (err = -71)
[   10.631660] hub 5-1.4:1.0: hub_ext_port_status failed (err = -71)
[   10.632228] usb 5-1.4-port3: cannot reset (err = -71)
[   10.632696] usb 5-1.4-port3: cannot reset (err = -71)
[   10.633164] usb 5-1.4-port3: cannot reset (err = -71)
[   10.633630] usb 5-1.4-port3: cannot reset (err = -71)
[   10.634098] usb 5-1.4-port3: cannot reset (err = -71)
[   10.634539] usb 5-1.4-port3: Cannot enable. Maybe the USB cable is bad?
[   10.635148] usb 5-1.4-port3: cannot disable (err = -71)
[   10.635650] hub 5-1.4:1.0: hub_ext_port_status failed (err = -71)
[   10.636207] usb 5-1.4-port3: cannot reset (err = -71)
[   10.636674] usb 5-1.4-port3: cannot reset (err = -71)
[   10.637148] usb 5-1.4-port3: cannot reset (err = -71)
[   10.637616] usb 5-1.4-port3: cannot reset (err = -71)
[   10.638083] usb 5-1.4-port3: cannot reset (err = -71)
[   10.638525] usb 5-1.4-port3: Cannot enable. Maybe the USB cable is bad?
[   10.639126] usb 5-1.4-port3: cannot disable (err = -71)
[   10.639611] hub 5-1.4:1.0: hub_ext_port_status failed (err = -71)
[   10.640167] usb 5-1.4-port3: cannot reset (err = -71)
[   10.640648] usb 5-1.4-port3: cannot reset (err = -71)
[   10.641115] usb 5-1.4-port3: cannot reset (err = -71)
[   10.641614] usb 5-1.4-port3: cannot reset (err = -71)
[   10.642084] usb 5-1.4-port3: cannot reset (err = -71)
[   10.642528] usb 5-1.4-port3: Cannot enable. Maybe the USB cable is bad?
[   10.643167] usb 5-1.4-port3: cannot disable (err = -71)
[   10.643683] hub 5-1.4:1.0: hub_ext_port_status failed (err = -71)
[   10.644260] usb 5-1.4-port3: cannot reset (err = -71)
[   10.644743] usb 5-1.4-port3: cannot reset (err = -71)
[   10.645224] usb 5-1.4-port3: cannot reset (err = -71)
[   10.645702] usb 5-1.4-port3: cannot reset (err = -71)
[   10.646189] usb 5-1.4-port3: cannot reset (err = -71)
[   10.646634] usb 5-1.4-port3: Cannot enable. Maybe the USB cable is bad?
[   10.647244] usb 5-1.4-port3: cannot disable (err = -71)
[   10.647730] usb 5-1.4-port3: cannot disable (err = -71)
[   10.648266] hub 5-1.4:1.0: hub_ext_port_status failed (err = -71)
[   40.171293] sd 3:0:0:0: tag#0 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD IN 
[   40.171954] sd 3:0:0:0: tag#0 CDB: Mode Sense(6) 1a 00 3f 00 04 00
[   40.172527] xhci-hcd xhci-hcd.0.auto: ERROR Transfer event for unknown stream ring slot 8 ep 2
[   40.173281] xhci-hcd xhci-hcd.0.auto: @00000000f7c4a3d0 00000000 00000000 1b000000 08038001
[   40.211297] scsi host3: uas_eh_device_reset_handler start
[   40.211806] xhci-hcd xhci-hcd.0.auto: ERROR Transfer event for unknown stream ring slot 8 ep 6
[   40.212560] xhci-hcd xhci-hcd.0.auto: @00000000f7c4a400 00000000 00000000 1b000000 08078001
[   40.213810] hub 5-1.4:1.0: hub_ext_port_status failed (err = -71)
[   40.214373] usb 5-1.4-port4: cannot reset (err = -71)
[   40.214840] usb 5-1.4-port4: cannot reset (err = -71)
[   40.215308] usb 5-1.4-port4: cannot reset (err = -71)
[   40.215775] usb 5-1.4-port4: cannot reset (err = -71)
[   40.216241] usb 5-1.4-port4: cannot reset (err = -71)
[   40.216683] usb 5-1.4-port4: Cannot enable. Maybe the USB cable is bad?
[   40.217288] usb 5-1.4-port4: cannot disable (err = -71)
[   40.217774] hub 5-1.4:1.0: hub_ext_port_status failed (err = -71)
[   40.218331] usb 5-1.4-port4: cannot reset (err = -71)
[   40.218798] usb 5-1.4-port4: cannot reset (err = -71)
[   40.219273] usb 5-1.4-port4: cannot reset (err = -71)
[   40.219739] usb 5-1.4-port4: cannot reset (err = -71)
[   40.220205] usb 5-1.4-port4: cannot reset (err = -71)
[   40.220646] usb 5-1.4-port4: Cannot enable. Maybe the USB cable is bad?
[   40.221248] usb 5-1.4-port4: cannot disable (err = -71)
[   40.221791] hub 5-1.4:1.0: hub_ext_port_status failed (err = -71)
[   40.222376] usb 5-1.4-port4: cannot reset (err = -71)
[   40.222866] usb 5-1.4-port4: cannot reset (err = -71)
[   40.223355] usb 5-1.4-port4: cannot reset (err = -71)
[   40.223837] usb 5-1.4-port4: cannot reset (err = -71)
[   40.224325] usb 5-1.4-port4: cannot reset (err = -71)
[   40.224770] usb 5-1.4-port4: Cannot enable. Maybe the USB cable is bad?
[   40.225398] usb 5-1.4-port4: cannot disable (err = -71)
[   40.225914] hub 5-1.4:1.0: hub_ext_port_status failed (err = -71)
[   40.226497] usb 5-1.4-port4: cannot reset (err = -71)
[   40.226977] usb 5-1.4-port4: cannot reset (err = -71)
[   40.227458] usb 5-1.4-port4: cannot reset (err = -71)
[   40.227943] usb 5-1.4-port4: cannot reset (err = -71)
[   40.228417] usb 5-1.4-port4: cannot reset (err = -71)
[   40.228859] usb 5-1.4-port4: Cannot enable. Maybe the USB cable is bad?
[   40.229465] usb 5-1.4-port4: cannot disable (err = -71)
[   40.229948] usb 5-1.4-port4: cannot disable (err = -71)
[   40.230473] hub 5-1.4:1.0: hub_ext_port_status failed (err = -71)
[   40.231038] hub 5-1.4:1.0: hub_ext_port_status failed (err = -71)
[  243.671298] INFO: task kworker/1:0:19 blocked for more than 120 seconds.
[  243.671892]       Not tainted 4.18.0-rc7-2-ARCH #1
[  243.672311] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  243.672993] kworker/1:0     D    0    19      2 0x00000028
[  243.673487] Workqueue: usb_hub_wq hub_event
[  243.673857] Call trace:
[  243.674079]  __switch_to+0x74/0x98
[  243.674381]  __schedule+0x234/0x838
[  243.674689]  schedule+0x30/0x88
[  243.674967]  schedule_preempt_disabled+0x14/0x20
[  243.675373]  __mutex_lock.isra.0+0x260/0x4e8
[  243.675749]  __mutex_lock_slowpath+0x24/0x30
[  243.676126]  mutex_lock+0x54/0x58
[  243.676419]  hub_event+0x54/0x3d0
[  243.676714]  process_one_work+0x1b0/0x3d0
[  243.677067]  worker_thread+0x48/0x418
[  243.677391]  kthread+0x130/0x138
[  243.677677]  ret_from_fork+0x10/0x1c
[  243.677999] INFO: task kworker/0:1:36 blocked for more than 120 seconds.
[  243.678584]       Not tainted 4.18.0-rc7-2-ARCH #1
[  243.679003] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  243.679684] kworker/0:1     D    0    36      2 0x00000028
[  243.680170] Workqueue: usb_hub_wq hub_event
[  243.680538] Call trace:
[  243.680756]  __switch_to+0x74/0x98
[  243.681056]  __schedule+0x234/0x838
[  243.681395]  schedule+0x30/0x88
[  243.681678]  async_synchronize_cookie_domain+0xf0/0x180
[  243.682137]  async_synchronize_full_domain+0x24/0x30
[  243.682574]  sd_remove+0x50/0xd8
[  243.682862]  device_release_driver_internal+0x1b0/0x248
[  243.683320]  device_release_driver+0x28/0x38
[  243.683696]  bus_remove_device+0xd4/0x148
[  243.684050]  device_del+0x130/0x338
[  243.684359]  __scsi_remove_device+0x108/0x138
[  243.684744]  scsi_forget_host+0x74/0x78
[  243.685083]  scsi_remove_host+0x78/0x120
[  243.685429]  usb_stor_disconnect+0x5c/0xf8
[  243.685791]  usb_unbind_interface+0x74/0x298
[  243.686169]  device_release_driver_internal+0x1b0/0x248
[  243.686626]  device_release_driver+0x28/0x38
[  243.687002]  bus_remove_device+0xd4/0x148
[  243.687356]  device_del+0x130/0x338
[  243.687663]  usb_disable_device+0xb0/0x2a0
[  243.688026]  usb_disconnect+0xc0/0x268
[  243.688356]  hub_quiesce+0x64/0xb0
[  243.688657]  hub_pre_reset+0x28/0x80
[  243.688972]  usb_reset_device+0xc4/0x250
[  243.689318]  port_event+0x5e4/0x6e8
[  243.689626]  hub_event+0x144/0x3d0
[  243.689928]  process_one_work+0x1b0/0x3d0
[  243.690282]  worker_thread+0x48/0x418
[  243.690605]  kthread+0x130/0x138
[  243.690891]  ret_from_fork+0x10/0x1c
[  243.691241] INFO: task scsi_eh_3:342 blocked for more than 120 seconds.
[  243.691843]       Not tainted 4.18.0-rc7-2-ARCH #1
[  243.692264] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  243.692946] scsi_eh_3       D    0   342      2 0x00000028
[  243.693430] Call trace:
[  243.693652]  __switch_to+0x74/0x98
[  243.693955]  __schedule+0x234/0x838
[  243.694262]  schedule+0x30/0x88
[  243.694543]  async_synchronize_cookie_domain+0xf0/0x180
[  243.695001]  async_synchronize_full_domain+0x24/0x30
[  243.695438]  sd_remove+0x50/0xd8
[  243.695725]  device_release_driver_internal+0x1b0/0x248
[  243.696183]  device_release_driver+0x28/0x38
[  243.696561]  bus_remove_device+0xd4/0x148
[  243.696914]  device_del+0x130/0x338
[  243.697222]  __scsi_remove_device+0x108/0x138
[  243.697608]  scsi_forget_host+0x74/0x78
[  243.697946]  scsi_remove_host+0x78/0x120
[  243.698294]  uas_disconnect+0x7c/0xa0
[  243.698618]  usb_unbind_interface+0x74/0x298
[  243.698995]  device_release_driver_internal+0x1b0/0x248
[  243.699453]  device_release_driver+0x28/0x38
[  243.699828]  usb_driver_release_interface+0x6c/0x98
[  243.700256]  usb_forced_unbind_intf+0x30/0x68
[  243.700640]  unbind_marked_interfaces.isra.8+0x58/0x78
[  243.701091]  usb_unbind_and_rebind_marked_interfaces+0x20/0x38
[  243.701631]  usb_reset_device+0x154/0x250
[  243.701989]  uas_eh_device_reset_handler+0xb8/0x190
[  243.702417]  scsi_eh_ready_devs+0x318/0xb68
[  243.702784]  scsi_error_handler+0x448/0x588
[  243.703151]  kthread+0x130/0x138
[  243.703438]  ret_from_fork+0x10/0x1c
[  366.551290] INFO: task kworker/1:0:19 blocked for more than 120 seconds.
[  366.551884]       Not tainted 4.18.0-rc7-2-ARCH #1
[  366.552303] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  366.552985] kworker/1:0     D    0    19      2 0x00000028
[  366.553479] Workqueue: usb_hub_wq hub_event
[  366.553849] Call trace:
[  366.554070]  __switch_to+0x74/0x98
[  366.554372]  __schedule+0x234/0x838
[  366.554679]  schedule+0x30/0x88
[  366.554959]  schedule_preempt_disabled+0x14/0x20
[  366.555366]  __mutex_lock.isra.0+0x260/0x4e8
[  366.555741]  __mutex_lock_slowpath+0x24/0x30
[  366.556118]  mutex_lock+0x54/0x58
[  366.556412]  hub_event+0x54/0x3d0
[  366.556706]  process_one_work+0x1b0/0x3d0
[  366.557060]  worker_thread+0x48/0x418
[  366.557383]  kthread+0x130/0x138
[  366.557669]  ret_from_fork+0x10/0x1c
[  366.557991] INFO: task kworker/0:1:36 blocked for more than 120 seconds.
[  366.558576]       Not tainted 4.18.0-rc7-2-ARCH #1
[  366.558994] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  366.559676] kworker/0:1     D    0    36      2 0x00000028
[  366.560161] Workqueue: usb_hub_wq hub_event
[  366.560529] Call trace:
[  366.560746]  __switch_to+0x74/0x98
[  366.561046]  __schedule+0x234/0x838
[  366.561382]  schedule+0x30/0x88
[  366.561666]  async_synchronize_cookie_domain+0xf0/0x180
[  366.562126]  async_synchronize_full_domain+0x24/0x30
[  366.562562]  sd_remove+0x50/0xd8
[  366.562850]  device_release_driver_internal+0x1b0/0x248
[  366.563308]  device_release_driver+0x28/0x38
[  366.563685]  bus_remove_device+0xd4/0x148
[  366.564039]  device_del+0x130/0x338
[  366.564350]  __scsi_remove_device+0x108/0x138
[  366.564735]  scsi_forget_host+0x74/0x78
[  366.565073]  scsi_remove_host+0x78/0x120
[  366.565420]  usb_stor_disconnect+0x5c/0xf8
[  366.565782]  usb_unbind_interface+0x74/0x298
[  366.566159]  device_release_driver_internal+0x1b0/0x248
[  366.566617]  device_release_driver+0x28/0x38
[  366.566994]  bus_remove_device+0xd4/0x148
[  366.567348]  device_del+0x130/0x338
[  366.567656]  usb_disable_device+0xb0/0x2a0
[  366.568018]  usb_disconnect+0xc0/0x268
[  366.568349]  hub_quiesce+0x64/0xb0
[  366.568649]  hub_pre_reset+0x28/0x80
[  366.568965]  usb_reset_device+0xc4/0x250
[  366.569311]  port_event+0x5e4/0x6e8
[  366.569619]  hub_event+0x144/0x3d0
[  366.569920]  process_one_work+0x1b0/0x3d0
[  366.570274]  worker_thread+0x48/0x418
[  366.570598]  kthread+0x130/0x138
[  366.570884]  ret_from_fork+0x10/0x1c
[  366.571230] INFO: task scsi_eh_3:342 blocked for more than 120 seconds.
[  366.571831]       Not tainted 4.18.0-rc7-2-ARCH #1
[  366.572255] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  366.572936] scsi_eh_3       D    0   342      2 0x00000028
[  366.573420] Call trace:
[  366.573643]  __switch_to+0x74/0x98
[  366.573946]  __schedule+0x234/0x838
[  366.574253]  schedule+0x30/0x88
[  366.574535]  async_synchronize_cookie_domain+0xf0/0x180
[  366.574994]  async_synchronize_full_domain+0x24/0x30
[  366.575431]  sd_remove+0x50/0xd8
[  366.575719]  device_release_driver_internal+0x1b0/0x248
[  366.576176]  device_release_driver+0x28/0x38
[  366.576554]  bus_remove_device+0xd4/0x148
[  366.576908]  device_del+0x130/0x338
[  366.577217]  __scsi_remove_device+0x108/0x138
[  366.577602]  scsi_forget_host+0x74/0x78
[  366.577940]  scsi_remove_host+0x78/0x120
[  366.578288]  uas_disconnect+0x7c/0xa0
[  366.578611]  usb_unbind_interface+0x74/0x298
[  366.578989]  device_release_driver_internal+0x1b0/0x248
[  366.579446]  device_release_driver+0x28/0x38
[  366.579822]  usb_driver_release_interface+0x6c/0x98
[  366.580250]  usb_forced_unbind_intf+0x30/0x68
[  366.580634]  unbind_marked_interfaces.isra.8+0x58/0x78
[  366.581084]  usb_unbind_and_rebind_marked_interfaces+0x20/0x38
[  366.581621]  usb_reset_device+0x154/0x250
[  366.581979]  uas_eh_device_reset_handler+0xb8/0x190
[  366.582410]  scsi_eh_ready_devs+0x318/0xb68
[  366.582781]  scsi_error_handler+0x448/0x588
[  366.583150]  kthread+0x130/0x138
[  366.583435]  ret_from_fork+0x10/0x1c
[  432.312790] random: crng init done
[  432.313103] random: 7 urandom warning(s) missed due to ratelimiting
[  489.431312] INFO: task kworker/1:0:19 blocked for more than 120 seconds.
[  489.431906]       Not tainted 4.18.0-rc7-2-ARCH #1
[  489.432325] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  489.433009] kworker/1:0     D    0    19      2 0x00000028
[  489.433501] Workqueue: usb_hub_wq hub_event
[  489.433870] Call trace:
[  489.434093]  __switch_to+0x74/0x98
[  489.434396]  __schedule+0x234/0x838
[  489.434704]  schedule+0x30/0x88
[  489.434981]  schedule_preempt_disabled+0x14/0x20
[  489.435386]  __mutex_lock.isra.0+0x260/0x4e8
[  489.435762]  __mutex_lock_slowpath+0x24/0x30
[  489.436139]  mutex_lock+0x54/0x58
[  489.436433]  hub_event+0x54/0x3d0
[  489.436728]  process_one_work+0x1b0/0x3d0
[  489.437082]  worker_thread+0x48/0x418
[  489.437405]  kthread+0x130/0x138
[  489.437691]  ret_from_fork+0x10/0x1c
[  489.438014] INFO: task kworker/0:1:36 blocked for more than 120 seconds.
[  489.438599]       Not tainted 4.18.0-rc7-2-ARCH #1
[  489.439017] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  489.439699] kworker/0:1     D    0    36      2 0x00000028
[  489.440187] Workqueue: usb_hub_wq hub_event
[  489.440554] Call trace:
[  489.440773]  __switch_to+0x74/0x98
[  489.441075]  __schedule+0x234/0x838
[  489.441433]  schedule+0x30/0x88
[  489.441721]  async_synchronize_cookie_domain+0xf0/0x180
[  489.442181]  async_synchronize_full_domain+0x24/0x30
[  489.442618]  sd_remove+0x50/0xd8
[  489.442906]  device_release_driver_internal+0x1b0/0x248
[  489.443363]  device_release_driver+0x28/0x38
[  489.443740]  bus_remove_device+0xd4/0x148
[  489.444092]  device_del+0x130/0x338
[  489.444400]  __scsi_remove_device+0x108/0x138
[  489.444784]  scsi_forget_host+0x74/0x78
[  489.445122]  scsi_remove_host+0x78/0x120
[  489.445469]  usb_stor_disconnect+0x5c/0xf8
[  489.445830]  usb_unbind_interface+0x74/0x298
[  489.446207]  device_release_driver_internal+0x1b0/0x248
[  489.446665]  device_release_driver+0x28/0x38
[  489.447043]  bus_remove_device+0xd4/0x148
[  489.447396]  device_del+0x130/0x338
[  489.447705]  usb_disable_device+0xb0/0x2a0
[  489.448067]  usb_disconnect+0xc0/0x268
[  489.448398]  hub_quiesce+0x64/0xb0
[  489.448698]  hub_pre_reset+0x28/0x80
[  489.449015]  usb_reset_device+0xc4/0x250
[  489.449360]  port_event+0x5e4/0x6e8
[  489.449668]  hub_event+0x144/0x3d0
[  489.449970]  process_one_work+0x1b0/0x3d0
[  489.450324]  worker_thread+0x48/0x418
[  489.450646]  kthread+0x130/0x138
[  489.450933]  ret_from_fork+0x10/0x1c
[  489.451320] INFO: task scsi_eh_3:342 blocked for more than 120 seconds.
[  489.451905]       Not tainted 4.18.0-rc7-2-ARCH #1
[  489.452325] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  489.453007] scsi_eh_3       D    0   342      2 0x00000028
[  489.453490] Call trace:
[  489.453716]  __switch_to+0x74/0x98
[  489.454019]  __schedule+0x234/0x838
[  489.454326]  schedule+0x30/0x88
[  489.454607]  async_synchronize_cookie_domain+0xf0/0x180
[  489.455067]  async_synchronize_full_domain+0x24/0x30
[  489.455504]  sd_remove+0x50/0xd8
[  489.455791]  device_release_driver_internal+0x1b0/0x248
[  489.456250]  device_release_driver+0x28/0x38
[  489.456627]  bus_remove_device+0xd4/0x148
[  489.456981]  device_del+0x130/0x338
[  489.457290]  __scsi_remove_device+0x108/0x138
[  489.457675]  scsi_forget_host+0x74/0x78
[  489.458013]  scsi_remove_host+0x78/0x120
[  489.458361]  uas_disconnect+0x7c/0xa0
[  489.458684]  usb_unbind_interface+0x74/0x298
[  489.459062]  device_release_driver_internal+0x1b0/0x248
[  489.459521]  device_release_driver+0x28/0x38
[  489.459896]  usb_driver_release_interface+0x6c/0x98
[  489.460324]  usb_forced_unbind_intf+0x30/0x68
[  489.460707]  unbind_marked_interfaces.isra.8+0x58/0x78
[  489.461158]  usb_unbind_and_rebind_marked_interfaces+0x20/0x38
[  489.461709]  usb_reset_device+0x154/0x250
[  489.462071]  uas_eh_device_reset_handler+0xb8/0x190
[  489.462500]  scsi_eh_ready_devs+0x318/0xb68
[  489.462868]  scsi_error_handler+0x448/0x588
[  489.463238]  kthread+0x130/0x138
[  489.463525]  ret_from_fork+0x10/0x1c
[  552.635505] F2FS-fs (dm-0): Found nat_bits in checkpoint
[  553.361395] F2FS-fs (dm-0): Mounted with checkpoint version = 4de0e18d
[  554.200676] systemd-journald[249]: Received SIGTERM from PID 1 (systemd).
[  554.290798] systemd: 23 output lines suppressed due to ratelimiting
[  555.265168] systemd[1]: systemd 239 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN +PCRE2 default-hierarchy=hybrid)
[  555.267311] systemd[1]: Detected architecture arm64.
[  555.280871] systemd[1]: Set hostname to <kumo>.
[  556.443773] systemd[1]: Stopped Switch Root.
[  556.471799] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart.
[  556.472963] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
[  556.473786] systemd[1]: Stopped Journal Service.
[  556.501631] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[  556.542313] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[  556.581445] systemd[1]: Stopped target Switch Root.
[  558.156297] systemd-journald[571]: Received request to flush runtime journal from PID 1
[  558.794555] systemd-journald[571]: File /var/log/journal/4162d318469b4a059d26986104a86b35/system.journal corrupted or uncleanly shut down, renaming and replacing.
[  560.218518] dw_wdt: probe of ff1a0000.watchdog failed with error -2
[  560.225892] rk808-rtc rk808-rtc: registered as rtc0
[  560.858677] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[  612.311289] INFO: task kworker/1:0:19 blocked for more than 120 seconds.
[  612.311881]       Not tainted 4.18.0-rc7-2-ARCH #1
[  612.312300] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  612.312983] kworker/1:0     D    0    19      2 0x00000028
[  612.313477] Workqueue: usb_hub_wq hub_event
[  612.313846] Call trace:
[  612.314068]  __switch_to+0x74/0x98
[  612.314371]  __schedule+0x234/0x838
[  612.314678]  schedule+0x30/0x88
[  612.314956]  schedule_preempt_disabled+0x14/0x20
[  612.315361]  __mutex_lock.isra.0+0x260/0x4e8
[  612.315737]  __mutex_lock_slowpath+0x24/0x30
[  612.316114]  mutex_lock+0x54/0x58
[  612.316407]  hub_event+0x54/0x3d0
[  612.316701]  process_one_work+0x1b0/0x3d0
[  612.317053]  worker_thread+0x48/0x418
[  612.317377]  kthread+0x130/0x138
[  612.317663]  ret_from_fork+0x10/0x1c
[  649.440585] Generic PHY stmmac-0:00: attached PHY driver [Generic PHY] (mii_bus:phy_addr=stmmac-0:00, irq=POLL)
[  649.461309] rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found
[  649.461993] rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW
[  649.462766] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[  651.512872] rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[  651.513656] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 1103.280644] scsi 0:0:0:1: Failed to get diagnostic page 0x1
[ 1103.281151] scsi 0:0:0:1: Failed to bind enclosure -19
[ 1103.281673] ses 0:0:0:1: Attached Enclosure device
[root@kumo boot]#
@ShapeShifter499

This comment has been minimized.

Copy link

ShapeShifter499 commented Aug 1, 2018

Here is one more snip of dmesg log that might be useful, it dumped a trace

[    5.944846] usb 5-1: reset SuperSpeed Gen 1 USB device number 3 using xhci-hcd
[    6.253855] ------------[ cut here ]------------
[    6.254273] URB (____ptrval____) submitted while active
[    6.254780] WARNING: CPU: 3 PID: 222 at drivers/usb/core/urb.c:363 usb_submit_urb+0x45c/0x538
[    6.255522] Modules linked in: dwmac_rk stmmac_platform stmmac
[    6.256042] CPU: 3 PID: 222 Comm: kworker/3:3 Not tainted 4.18.0-rc7-2-ARCH #1
[    6.256670] Hardware name: Pine64 Rock64 (DT)
[    6.257057] Workqueue: events_power_efficient hub_init_func2
[    6.257555] pstate: 80000005 (Nzcv daif -PAN -UAO)
[    6.257975] pc : usb_submit_urb+0x45c/0x538
[    6.258343] lr : usb_submit_urb+0x45c/0x538
[    6.258710] sp : ffff00000a07bcc0
[    6.259001] x29: ffff00000a07bcc0 x28: 0000000000000000 
[    6.259467] x27: ffff000009206ed8 x26: ffff8000f11b3000 
[    6.259933] x25: ffff000009729688 x24: 0000000000000001 
[    6.260400] x23: 00000000fffffff0 x22: 0000000000600000 
[    6.260867] x21: 0000000000000000 x20: ffff8000f1cfc000 
[    6.261334] x19: ffff8000f1c50500 x18: 0000000000000010 
[    6.261801] x17: 0000aaaaba854030 x16: ffff0000080eb1b0 
[    6.262268] x15: ffffffffffffffff x14: ffff000009729688 
[    6.262735] x13: ffff000089991447 x12: ffff00000999144f 
[    6.263201] x11: ffff000009751000 x10: ffff00000a07b980 
[    6.263668] x9 : 00000000ffffffd0 x8 : ffff0000087b0808 
[    6.264135] x7 : 772064657474696d x6 : 000000000000016d 
[    6.264602] x5 : 0000000000000001 x4 : 0000000000000001 
[    6.265068] x3 : 0000000000000007 x2 : 0000000000000007 
[    6.265536] x1 : 90cf2eac175d3600 x0 : 0000000000000000 
[    6.266003] Call trace:
[    6.266222]  usb_submit_urb+0x45c/0x538
[    6.266561]  hub_activate+0x35c/0x608
[    6.266883]  hub_init_func2+0x24/0x30
[    6.267209]  process_one_work+0x1b0/0x3d0
[    6.267563]  worker_thread+0x48/0x418
[    6.267886]  kthread+0x130/0x138
[    6.268173]  ret_from_fork+0x10/0x1c
[    6.268487] ---[ end trace c3de9a9ac0647750 ]---
@h31

This comment has been minimized.

Copy link

h31 commented Aug 10, 2018

Still happens on 4.4.132-1075-rockchip-ayufan-ga83beded8524. I've posted an issue to the Rockchip's repository.

@ShapeShifter499

This comment has been minimized.

Copy link

ShapeShifter499 commented Aug 10, 2018

I no longer have a issue running 4.4.138-1094-rockchip-ayufan-gf13a8a9a4eee After changing my USB 3.0 hub to one with enough power, USB 3.0 appears to work so far.

The mainline kernel build from ayufan still has issues as well as the mainline kernel from Arch Linux ARM. I suspect there is a positive change made by ayufan or the maintainers over at https://github.com/rockchip-linux/kernel to the USB 3.0 drivers. Someone should try to get those changes merged into the mainline kernel source.

Hub used is a "Anker 10 Port 60W Data Hub with 7 USB 3.0 Ports and 3 PowerIQ Charging Ports" which does work fine despite earlier comments here.

@ayufan

This comment has been minimized.

Copy link
Member

ayufan commented Aug 10, 2018

@mikescandy

This comment has been minimized.

Copy link

mikescandy commented Aug 10, 2018

just tried 4.4.138 with usb3, hub anker https://www.anker.com/products/variant/USB-3.0-SuperSpeed-10-Port-Hub-/68ANHUB-B10A and powered external hard drives. no luck, after blacklisting uas and rebooting, I tried to transfer a 40gb file and eventually crashed. transfer speed was above 50MB/s, twice more than 3 times faster than usb2 :(
here is the output from dmesg
`

[ 135.890718] xhci-hcd xhci-hcd.9.auto: xHCI host not responding to stop endpoint command.
[ 135.891613] xhci-hcd xhci-hcd.9.auto: Assuming host is dying, halting host.
[ 135.892351] xhci-hcd xhci-hcd.9.auto: xHCI host not responding to stop endpoint command.
[ 135.893235] xhci-hcd xhci-hcd.9.auto: Assuming host is dying, halting host.
[ 135.915740] xhci-hcd xhci-hcd.9.auto: Host not halted after 16000 microseconds.
[ 135.916544] xhci-hcd xhci-hcd.9.auto: Non-responsive xHCI host is not halting.
[ 135.917326] xhci-hcd xhci-hcd.9.auto: Completing active URBs anyway.
[ 135.917379] xhci-hcd xhci-hcd.9.auto: Host not halted after 16000 microseconds.
[ 135.918796] xhci-hcd xhci-hcd.9.auto: Non-responsive xHCI host is not halting.
[ 135.919587] xhci-hcd xhci-hcd.9.auto: Completing active URBs anyway.
[ 135.920387] xhci-hcd xhci-hcd.9.auto: HC died; cleaning up
[ 135.920389] xhci-hcd xhci-hcd.9.auto: HC died; cleaning up
[ 135.920397] hub 5-1:1.0: hub_port_status failed (err = -108)
[ 135.920407] usb 5-1-port1: cannot reset (err = -108)
[ 135.920413] usb 5-1-port1: cannot reset (err = -108)
[ 135.920418] usb 5-1-port1: cannot reset (err = -108)
[ 135.920423] usb 5-1-port1: cannot reset (err = -108)
[ 135.920428] usb 5-1-port1: cannot reset (err = -108)
[ 135.920431] usb 5-1-port1: Cannot enable. Maybe the USB cable is bad?
[ 135.920548] usb 5-1.1: USB disconnect, device number 3
[ 135.920568] usb 4-1: USB disconnect, device number 2
[ 135.920575] usb 4-1.4: USB disconnect, device number 3
[ 135.920582] usb 4-1.4.4: USB disconnect, device number 4
[ 135.921552] usb 5-1: USB disconnect, device number 2
[ 135.927891] sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=0x00
[ 135.927905] sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x8a 8a 00 00 00 00 01 b9 fc 5c b0 00 00 00 f0 00 00
[ 135.927912] blk_update_request: I/O error, dev sda, sector 7415291056
[ 135.928103] EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 51644863 (offset 1635778560 size 8388608 starting block 926911744)
[ 135.928114] Buffer I/O error on device sda3, logical block 206539520
[ 135.928133] Buffer I/O error on device sda3, logical block 206539521
[ 135.928137] Buffer I/O error on device sda3, logical block 206539522
[ 135.928142] Buffer I/O error on device sda3, logical block 206539523
[ 135.928150] Buffer I/O error on device sda3, logical block 206539524
[ 135.928155] Buffer I/O error on device sda3, logical block 206539525
[ 135.928159] Buffer I/O error on device sda3, logical block 206539526
[ 135.928164] Buffer I/O error on device sda3, logical block 206539527
[ 135.928169] Buffer I/O error on device sda3, logical block 206539528
[ 135.928173] Buffer I/O error on device sda3, logical block 206539529
[ 135.928723] EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 51644863 (offset 1635778560 size 8388608 starting block 926912000)
[ 135.929293] EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 51644863 (offset 1635778560 size 8388608 starting block 926912256)
[ 135.929848] EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 51644863 (offset 1635778560 size 8388608 starting block 926912512)
[ 135.930392] EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 51644863 (offset 1635778560 size 8388608 starting block 926912768)
[ 135.930968] EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 51644863 (offset 1644167168 size 8388608 starting block 926913024)
[ 135.931544] EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 51644863 (offset 1644167168 size 8388608 starting block 926913280)
[ 135.932095] EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 51644863 (offset 1644167168 size 8388608 starting block 926913536)
[ 135.932651] EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 51644863 (offset 1644167168 size 8388608 starting block 926913792)
[ 135.933184] EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 51644863 (offset 1644167168 size 8388608 starting block 926914048)
[ 135.935395] EXT4-fs warning (device sda1): ext4_end_bio:330: I/O error -5 writing to inode 68552643 (offset 0 size 4096 starting block 225744158)
[ 135.935738] Aborting journal on device sda1-8.
[ 135.935747] sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=0x00
[ 135.935753] EXT4-fs error (device sda1) in ext4_free_blocks:4915: Journal has aborted
[ 135.935759] sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x8a 8a 00 00 00 00 01 b9 fc 5d a0 00 00 00 f0 00 00
[ 135.935765] blk_update_request: I/O error, dev sda, sector 7415291296
[ 135.935818] JBD2: Error -5 detected when updating journal superblock for sda1-8.
[ 135.935840] EXT4-fs error (device sda1) in ext4_do_update_inode:4755: Journal has aborted
[ 135.935845] EXT4-fs (sda1): previous I/O error to superblock detected
[ 135.935867] EXT4-fs error (device sda1) in ext4_do_update_inode:4755: Journal has aborted
[ 135.935870] EXT4-fs (sda1): previous I/O error to superblock detected
[ 135.935889] EXT4-fs error (device sda1) in ext4_ext_remove_space:3052: IO failure
[ 135.935892] EXT4-fs (sda1): previous I/O error to superblock detected
[ 135.935908] EXT4-fs error (device sda1) in ext4_ext_truncate:4669: Journal has aborted
[ 135.935911] EXT4-fs (sda1): previous I/O error to superblock detected
[ 135.935934] EXT4-fs error (device sda1) in ext4_orphan_del:2916: Journal has aborted
[ 135.935937] EXT4-fs (sda1): previous I/O error to superblock detected
[ 135.935957] EXT4-fs error (device sda1) in ext4_do_update_inode:4755: Journal has aborted
[ 135.935960] EXT4-fs (sda1): previous I/O error to superblock detected
[ 135.935978] EXT4-fs error (device sda1) in ext4_truncate:4027: Journal has aborted
[ 135.935981] EXT4-fs (sda1): previous I/O error to superblock detected
[ 135.936000] EXT4-fs (sda1): previous I/O error to superblock detected
[ 135.936020] EXT4-fs error (device sda1): ext4_journal_check_start:56: Detected aborted journal
[ 135.936023] EXT4-fs (sda1): Remounting filesystem read-only
[ 135.936026] EXT4-fs (sda1): previous I/O error to superblock detected
[ 135.981167] EXT4-fs error (device sda1) in ext4_reserve_inode_write:5226: Journal has aborted
[ 136.201686] EXT4-fs error (device sda3): ext4_wait_block_bitmap:515: comm kworker/u8:4: Cannot read block bitmap - block_group = 6304, block_bitmap = 206569472
[ 136.203393] JBD2: Detected IO errors while flushing file data on sda3-8
[ 136.203430] Aborting journal on device sda3-8.
[ 136.203525] JBD2: Error -5 detected when updating journal superblock for sda3-8.
[ 136.203568] EXT4-fs (sda3): Delayed block allocation failed for inode 51644863 at logical offset 430080 with max blocks 2048 with error 5
[ 136.203571] EXT4-fs (sda3): This should not happen!! Data will be lost

[ 136.203624] EXT4-fs error (device sda3) in ext4_writepages:2655: IO failure
[ 136.203631] EXT4-fs (sda3): previous I/O error to superblock detected
[ 136.208769] EXT4-fs (sda3): previous I/O error to superblock detected
[ 136.208950] EXT4-fs error (device sda3): ext4_journal_check_start:56: Detected aborted journal
[ 136.208953] EXT4-fs (sda3): Remounting filesystem read-only
[ 136.208958] EXT4-fs (sda3): previous I/O error to superblock detected
[ 136.209003] EXT4-fs (sda3): ext4_writepages: jbd2_start: 9223372036854775807 pages, ino 51644863; err -30
[ 136.258281] JBD2: Detected IO errors while flushing file data on sda3-8
[ 136.341519] blk_update_request: I/O error, dev sda, sector 0
[ 136.353446] blk_update_request: I/O error, dev sda, sector 0
[ 136.375002] sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=0x00
[ 136.381614] sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 16 5e ab 60 00 00 1e 00
[ 136.388225] blk_update_request: I/O error, dev sdb, sector 3002424064
[ 136.393271] EXT4-fs error (device sda1): ext4_find_entry:1454: inode #56363909: comm Plex Media Serv: reading directory lblock 0
[ 136.394922] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[ 136.395085] sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=0x00
[ 136.433284] usb 5-1.2: USB disconnect, device number 4
[ 136.444947] sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=0x00
[ 136.451774] sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 16 5e ab 7e 00 00 02 00
[ 136.458488] blk_update_request: I/O error, dev sdb, sector 3002424304
[ 136.465829] sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=0x00
[ 136.472553] sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 16 5e ab 80 00 00 1e 00
[ 136.479384] blk_update_request: I/O error, dev sdb, sector 3002424320
[ 136.730609] usb 5-1.4: USB disconnect, device number 5
[ 136.737233] usb 5-1.4.1: USB disconnect, device number 6
[ 136.755090] blk_update_request: I/O error, dev sdd, sector 0
[ 136.767248] sd 2:0:0:0: [sdd] Synchronizing SCSI cache
[ 136.773685] sd 2:0:0:0: [sdd] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=0x00
[ 136.790503] usb 5-1.4.3: USB disconnect, device number 7
[ 136.827165] usb 5-1.4.4: USB disconnect, device number 8
[ 157.716064] EXT4-fs error (device sda1): ext4_find_entry:1454: inode #56363915: comm Plex Script Hos: reading directory lblock 0

`

@mstattma

This comment has been minimized.

Copy link

mstattma commented Aug 29, 2018

FWIW, I'm experiencing random crashes on Bionic but also AndroidTV with a Wifi dongle (https://www.pine64.org/?product=usb-wifi-802-11bgn-rtl8188eu-for-rock64) connected to the USB 3 port. The system is either freezing or rebooting, I never got hold of any logs. But connecting the dongle to another port seems to make it stable. I'm still testing, but it looks promising so far.

@hermes73

This comment has been minimized.

Copy link

hermes73 commented Aug 29, 2018

Will try another approach. Read an interesting post from tkaiser in openmediavault forum (https://forum.openmediavault.org/index.php/Thread/19871-Which-energy-efficient-ARM-platform-to-choose/?postID=169303#post169303) concerning suitable hardware for deployment of two harddrives with one usb3 port.

I will test a setting with a powered HD622-PCBA Board USB 3.0 to Dual SATA Adapter Card
and report here afterwards whether rock64 still crashes during file transfer or not.

In the meanwhile, @ayufan and others: Are there any news concerning the problem, patches or workarounds?

@ShapeShifter499

This comment has been minimized.

Copy link

ShapeShifter499 commented Aug 30, 2018

After many days of up time I saw this error, all drives stopped working and USB 3.0 died.

xhci-hcd xhci-hcd.9.auto: Assuming host is dying, halting host.

The full log is here: https://gist.github.com/ShapeShifter499/a136d9a47359f6f3966b9f3484a7a93c

@hermes73

This comment has been minimized.

Copy link

hermes73 commented Aug 30, 2018

@ShapeShifter499 : Did this happen while you used Arch-Linux (cp. https://archlinuxarm.org/forum/viewtopic.php?f=67&t=12826&sid=6d006809bc4c6a4f3d41cfdc513a0f83&start=10#p59066)? If so, then currently there is no linux-distro available for rock64 with a stable usb3 support, isn't it?

@vitaliy-kuzmich

This comment has been minimized.

Copy link

vitaliy-kuzmich commented Sep 2, 2018

same, after heavy nextcloud indexing in docker, board stop responding(after ~1 hour), I've noticed that cache usage in ram fill whole 4gb of ram, only 1 gb took real data

@berturion

This comment has been minimized.

Copy link

berturion commented Sep 5, 2018

I have the same problem. My Rock64 is on Arch ARM mainline kernel. It hangs after browsing a big collection of photos on my nextcloud instance. My rootfs is on a Sandisk Extreme Pro 32Go SDCard, the Mysql database is stored on an external 3.5" drive self powered behind a powered USB 2.0 hub, itself connected to an USB 2.0 port of my Rock64.
I also have a 2.5" drive, not self powered, connected to the powered hub that I use to backup data from the big one.

Sometimes, the system hangs just few minutes later after browsing, sometimes immediately. System must be then rebooted.

The system can not run a day, even doing nothing on nextcloud. Some CRON jobs for system and data backups are running during the day but the system hangs at a quite random moment of the day.

I have found no log for that...

EDIT: I know the issue is on the USB 3.0 port and my setup doesn't use it but it might be related as the symptoms look the same.

@vitaliy-kuzmich

This comment has been minimized.

Copy link

vitaliy-kuzmich commented Sep 5, 2018

upd: I've tried usb2 port, and issue were the same. After I've disabled APM and HDD spindown, now it is 3 hours stable work, will test more.

upd: nope, it still failing.

@berturion

This comment has been minimized.

Copy link

berturion commented Sep 6, 2018

I can confirm that, removing my powered USB Hub and connecting my drives directly on the Rock 64 USB ports, resolved all my issues. For me, the problem is really related to the usage of a USB Hub, whatever port is used (USB 2.0 or USB 3.0).

EDIT: Removing the USB hub helps a little but I still have the hang issue after USB file transfers. USB 3 or USB 2 doesn't really matter.

@ShapeShifter499

This comment has been minimized.

Copy link

ShapeShifter499 commented Sep 6, 2018

@hermes73 The latest mainline has an instant crash with Arch Linux but the one from here lasts days for me but does eventually crash. So no at this point I'd say there isn't any USB 3.0 stable kernel. BUT I'm also beginning to think it could be related to using a USB hub. There's probably some bug with the kernel that causes issues with USB hubs, so until that's fixed either try using one USB 3.0 device or avoid USB 3.0 all together.

Because of this issue I've gone and brought an 'UP Board' instead.

@vitaliy-kuzmich

This comment has been minimized.

Copy link

vitaliy-kuzmich commented Sep 7, 2018

upd: issue remains, I do not use hubs, tested with usb3 and usb2 hung randomly, sometimes after 5 minutes, sometimes works whole day, it can hung without load.

@hermes73

This comment has been minimized.

Copy link

hermes73 commented Sep 12, 2018

Bad news. Obviously rock64' usb implementation is crap, at least for NAS oriented use cases.

As mentioned before, I tested a setting with a powered HD622-PCBA Board USB 3.0 to Dual SATA Adapter Card to deploy two harddrives via usb3 on rock64. It still crashed sooner or later during heavy file transfer, reproducably. I used latest stable release of armbian for the testbed since I got the impression armbian was more stable and less buggy. At least I got no "xhci-hcd xhci-hcd.9.auto: ERROR - Transfer event for disabled endpoint or incorrect stream ring" errors anymore - anyhow it crashed.

I also tried @ayufan 's latest bionic-minimal-rockpro64-0.7.9-1067-arm64.img and deployed the latest mainline kernel 4.18.0-rc8-1060-ayufan. Although transfer rates were quite impressive, I got the same errors as with legacy kernel and it crashed again: xhci-hcd xhci-hcd.9.auto: ERROR - Transfer event for disabled endpoint or incorrect stream ring. So mainline kernel is not adressing this issue either.

Moreover I tried to deploy a brandnew 500GB Samsung EVO 850 SSD with rock64 as single drive on usb3 for rootfs, with Pine64's enclosure (ROCK64 USB 3.0 to SATA III HARD DRIVE ADAPTER CABLE/ CONVERTER with UASP) and sufficient power supply. Therefore I used an Ubuntu Xenial image with latest legacy kernel from ayufan which I successfully used with a 250GB SSD from Verbatim before. This time the system didn't even start und threw an uncountable amount of incorrect stream ring error messages.

Thus, I'll give up wasting time with rock64 as NAS and will try to use Helios4 with armbian for that purpose. Perhaps rock64 is ok with Android as media center...

@berturion

This comment has been minimized.

Copy link

berturion commented Sep 12, 2018

@hermes73 I understand you frustration. The question is: is this a hardware problem or is it a question of time to have a good software (linux) support for rock64 USB ports ?

@vitaliy-kuzmich

This comment has been minimized.

Copy link

vitaliy-kuzmich commented Sep 12, 2018

upd: I found bad RAM sectors in my board, I've tested in 3 threads, because 1 thread too long, and showed all is good.

@Lt0

This comment has been minimized.

Copy link

Lt0 commented Sep 15, 2018

Is there anyone try to connect btrfs disk? I remember that I also meet this issue when I using ext4 disk and it disappear when I change the fs type to btrfs.

@hermes73

This comment has been minimized.

Copy link

hermes73 commented Sep 17, 2018

@Lt0 Yes, I did. I used btrfs as rootfs when I tested latest stable release of armbian. At least I got no "xhci-hcd xhci-hcd.9.auto: ERROR - Transfer event for disabled endpoint or incorrect stream ring" errors anymore - anyhow it crashed.

@shawwwn

This comment has been minimized.

Copy link

shawwwn commented Sep 26, 2018

My usb3 port also randomly disconnect under heavy IO usage.

My workaround for disconnection is to power cycle the usb3 hub; or worse, rebooting the board. In the worst case scenario, the board has to be rebooted twice followed by a power cycle on the usb3 hub in order to bring it back.

This issue happens frequently in summer, now(autumn) it's happening significantly less.
This leads me into thinking may be it was related to chip overheating.

@dkimmortal

This comment has been minimized.

Copy link

dkimmortal commented Oct 8, 2018

Same issue of not being able to copy between usb 3.0 hub connected drives after updating to latest pre-release 0.7.10 bionic minimal
workaround I'm using that has worked for the last few minutes and posted by an earlier user, is keeping the drive that I want to copy from, separately connected to a usb 2.0 port.
1 2.5" portable "download" drive to usb 2.0 port
3 usb hub (powered hub) drives into the 3.0 port (after download, I'll copy to the hub drives)
Coping from the usb 2 port drive to any 3.0 connected drive fine so far and vise versa but
no transfer between the usb 3.0 hub connected drives possible without crash. same crash errors in log "xHCI host not responding to stop " assume host is dying...etc.

thanks

@ayufan

This comment has been minimized.

Copy link
Member

ayufan commented Oct 8, 2018

Try to use this:

echo 1 > /sys/block/sda/device/queue_depth
echo 1 > /sys/block/sdb/device/queue_depth

It should disable NCQ and make everything more stable at the cost of small performance hit.

@solemgar

This comment has been minimized.

Copy link

solemgar commented Oct 10, 2018

Same issue here. Small file transfers are ok, but seems to consistently crash when copying over big files. When this happens, I lose ssh connectivity and I need to power cycle the board. I have a sftp task for backup and it seems to make the whole board crash when sending over files which are in the gigabyte scale. I am pretty sure I didn't have this on 0.5 xenial so it must have something introduced in newer kernels. Going to try the workaround suggested by @ayufan and report back. If that doesn't work , I will try again with 0.5 and see if it behaves better.

I am currently using latest armbian , but had the same with 0.7.10 bionic. Disk is a 2.5 seagate 5 TB, plugged directly into the USB3 port. No hub in used and no other devices connected. Board seems stable until the USB port gets saturated.

@solemgar

This comment has been minimized.

Copy link

solemgar commented Oct 10, 2018

With 0.5.15 i cannot even run sudo, maybe is because I am booting from a emmc now?

su: Authentication failure
rock64@rock64:$ sudo -i
Bus error (core dumped)
rock64@rock64:
$

With 0.7.10, I cannot set the queue_depth (i had to give write permissions to root since it was ready-only)

root@rock64:# echo 1 > /sys/block/sda/device/queue_depth
-bash: echo: write error: Invalid argument
root@rock64:
# ls -ltrh /sys/block/sda/device/queue_depth
-rw-r--r-- 1 root root 4.0K Oct 10 17:11 /sys/block/sda/device/queue_depth
root@rock64:~#

@ayufan

This comment has been minimized.

Copy link
Member

ayufan commented Oct 10, 2018

@solemgar

This comment has been minimized.

Copy link

solemgar commented Oct 10, 2018

Weird! Let's see if someone else can try

@ayufan

This comment has been minimized.

Copy link
Member

ayufan commented Oct 11, 2018

cat /sys/block/sda/device/queue_depth

Can it be that you don't uses USP compatible adapter?

@solemgar

This comment has been minimized.

Copy link

solemgar commented Oct 11, 2018

Could be . This is a external USB drive from Seagate. I was also reading in the pine64 forum that the crashes could also be due to be using the UAS driver:

dietpi@DietPi:~$ lsusb -t
/: Bus 05.Port 1: Dev 1, Class=root_hub, Driver=xhci-hcd/1p, 5000M
|__ Port 1: Dev 2, If 0, Class=Mass Storage, Driver=uas, 5000M

And they suggest to blacklust UAS to make the USB port more stable -> https://forum.pine64.org/showthread.php?tid=5137

Is this something that can be done on the current builds?

Strangely, I have been testing with the latest DietPi build for rock64, and even if I get from time to time these errors in the dmesg and transfer speeds fluctuates, the board hasn't crashed yet (running for 12 hours during heavy IO) :

Oct 11 07:39:04 DietPi kernel: xhci-hcd xhci-hcd.9.auto: ERROR Transfer event for disabled endpoint or incorrect stream ring

Oct 11 07:39:04 DietPi kernel: xhci-hcd xhci-hcd.9.auto: @00000000f3a3d600 00000000 00000000 1b000000 01058001

Oct 11 07:39:04 DietPi kernel: xhci-hcd xhci-hcd.9.auto: ERROR Transfer event for disabled endpoint or incorrect stream ring
Really strange!

EDIT:

I just disabled UAS. As a reference, this is how i disabled UAS on DietPi (will try on 0.7.10 later). Making tests now with the UAS driver disabled.

=== To find the devide ID, use lsbusb -t and lsusb commands ===

dietpi@DietPi:~$ echo options usb-storage quirks=0bc2:ab30:u | sudo tee /etc/modprobe.d
/blacklist_uas_357d.conf
dietpi@DietPi:~$ cat /etc/modprobe.d/blacklist_uas_357d.conf
options usb-storage quirks=0bc2:ab30:u
dietpi@DietPi:~$

@Lakewell

This comment has been minimized.

Copy link

Lakewell commented Oct 20, 2018

I have the same problem on the Rock64 while trying to transfer large files. It's happened on two different drives, one a Seagate Expansion 5TB drive, and a USB 3.0 Mediasonic HDD dock.

I was also able to replicate the problem in Transmission (Bittorent plugin). I'm only able to download one torrent at a time. If I do more OMV locks up and will eventually reboot itself after a few minutes. Once it's rebooted the USB 3.0 hard drive isn't recognized. I'm able to remedy this by power cycling the HDD dock or unplugging the power to the Seagate external.

Another odd problem is that with the Seagate I'm only able to write to it at 20 MB/s and with the Mediasonic dock I'm able to write to it at 50 MB/s. Also with the Mediasonic dock I'm able to FTP to it at just about 100 MB/s. I know this probably comes down to chipsets and even my Windows PC, but just thought I'd mention it.

@solemgar

This comment has been minimized.

Copy link

solemgar commented Nov 15, 2018

I still get disconnects with the UAS driver blacklisted. I replaced the power supply of the rock64 as I had a faulty one but still the same. Something is very wrong either with the usb3 drivers or with some units in the field :(

@h31

This comment has been minimized.

Copy link

h31 commented Nov 15, 2018

Good news about the issue.
I've detected that the problem was caused by the faulty USB-SATA enclosure. When I disconnect it from the hub, everything starts to work nicely. This USB-SATA enclosure uses the ASMedia chip. I don't remember the precise model number of the chip, something ancient like ASM1051 or ASM1053. I can look again if you need this information. I tested USB-SATA adapters equipped with chips from JMicron (official adapter from Pine64), Innostor, Norelsys, and they all work more or less correctly, at least without such bad errors. As far as I know, Seagate's external HDDs use ASMedia chips and that may be a cause of the problem. Nevertheless I think that this is a bug since Intel systems works OK with ASMedia chips.
My board is currently running the kernel version 0.7.10.
P.S. If you have problems with the official adapter from Pine64, try to update it's firmware to version v173.01.00.01 from this site. I don't have any information about the compatibility of this firmware with the adapter and I'm not able to guarantee that it will not destroy your adapter. However, it works for me and solves some nasty issues related to hdparm compatibility. Do a backup of the current firmware. Also take a note that the newer firmware is not compatible with SATA optical drives.
P.S. 2. Do not treat this comment as a purchase guide. My board is running for 6 days, but It didn't had any heavy load except for artificial one.

@solemgar

This comment has been minimized.

Copy link

solemgar commented Nov 16, 2018

@h31 , that's a great find! I am using a external usb disk from seagate so that might be it. I will see if I can try a different enclosure.

@ShapeShifter499

This comment has been minimized.

Copy link

ShapeShifter499 commented Dec 22, 2018

@h31 Do you happen to know if I could run that utility on a x86_64 system? Or is that ARM only? I tried looking around and maybe I missed but that seems to be the only utility for Linux that updates the firmware of JMicron JMS578 chips.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment