OpenWrt/LEDE Project

  • Status Unconfirmed
  • Percent Complete
    0%
  • Task Type Bug Report
  • Category Packages
  • Assigned To No-one
  • Operating System All
  • Severity Low
  • Priority Very Low
  • Reported Version Trunk
  • Due in Version Undecided
  • Due Date Undecided
  • Private
Attached to Project: OpenWrt/LEDE Project
Opened by emvova - 28.01.2018

FS#1305 - Not working UAS driver for usb hdd case ORICO 2599US3-V1

- Xiaomi Mi Router 3G
- OpenWrt/LEDE trunk with kmod-usb-storage-uas package.
- After fresh install trunk version and kmod-usb-storage-uas drivers for USB storage have error:

[  457.174784] scsi host0: uas
[  457.178931] xhci-mtk 1e1c0000.xhci: ERROR Transfer event for disabled endpoint or incorrect stream ring
[  457.188303] xhci-mtk 1e1c0000.xhci: @000000000fded170 0ee20000 00000000 05000000 01058001
[  457.196514] xhci-mtk 1e1c0000.xhci: ERROR Transfer event for disabled endpoint or incorrect stream ring
[  457.205879] xhci-mtk 1e1c0000.xhci: @000000000fded180 0ee20100 00000000 05000000 01078001
[  464.441727] Data buffer not 16 bytes aligned: 8ee2c458
[  464.469820] Data buffer not 16 bytes aligned: 8ee2c0d8
[  464.477079] Data buffer not 16 bytes aligned: 8ee2c368
[  477.979944] scsi 0:0:0:0: tag#0 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD IN
[  477.987509] scsi 0:0:0:0: tag#0 CDB: opcode=0x12 12 00 00 00 24 00
[  479.029938] scsi host0: uas_eh_bus_reset_handler FAILED to get lock err -16
[  479.036896] scsi 0:0:0:0: Device offlined - not ready after error recovery
 

FULL log

[    0.000000] Linux version 4.9.77 (buildbot@builds) (gcc version 5.5.0 (OpenWrt GCC 5.5.0 r5972-74beb6f) ) #0 SMP Sun Jan 28 07:53:50 2018
[    0.000000] SoC Type: MediaTek MT7621 ver:1 eco:3
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 0001992f (MIPS 1004Kc)
[    0.000000] MIPS: machine is Xiaomi Mi Router 3G
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 10000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] VPE topology {2,2} total 4
[    0.000000] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
[    0.000000] MIPS secondary cache 256kB, 8-way, linesize 32 bytes.
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000]   HighMem  empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000] On node 0 totalpages: 65536
[    0.000000] free_area_init_node: node 0, pgdat 80519c20, node_mem_map 81003000
[    0.000000]   Normal zone: 512 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 65536 pages, LIFO batch:15
[    0.000000] percpu: Embedded 12 pages/cpu @8120f000 s17744 r8192 d23216 u49152
[    0.000000] pcpu-alloc: s17744 r8192 d23216 u49152 alloc=12*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 65024
[    0.000000] Kernel command line: console=ttyS0,115200n8 rootfstype=squashfs,jffs2
[    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Writing ErrCtl register=00022211
[    0.000000] Readback ErrCtl register=00022211
[    0.000000] Memory: 253888K/262144K available (4083K kernel code, 221K rwdata, 920K rodata, 204K init, 272K bss, 8256K reserved, 0K cma-reserved, 0K highmem)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS:256
[    0.000000] clocksource: GIC: mask: 0xffffffffffffffff max_cycles: 0xcaf478abb4, max_idle_ns: 440795247997 ns
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 4343773742 ns
[    0.000010] sched_clock: 32 bits at 440MHz, resolution 2ns, wraps every 4880645118ns
[    0.007796] Calibrating delay loop... 586.13 BogoMIPS (lpj=2930688)
[    0.070423] pid_max: default: 32768 minimum: 301
[    0.075160] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.081686] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.095326] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.095339] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
[    0.095351] MIPS secondary cache 256kB, 8-way, linesize 32 bytes.
[    0.095506] CPU1 revision is: 0001992f (MIPS 1004Kc)
[    0.177455] Synchronize counters for CPU 1:
[    0.177457] done.
[    0.190893] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.190901] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
[    0.190910] MIPS secondary cache 256kB, 8-way, linesize 32 bytes.
[    0.190995] CPU2 revision is: 0001992f (MIPS 1004Kc)
[    0.269476] Synchronize counters for CPU 2:
[    0.269477] done.
[    0.279363] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.279371] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
[    0.279379] MIPS secondary cache 256kB, 8-way, linesize 32 bytes.
[    0.279472] CPU3 revision is: 0001992f (MIPS 1004Kc)
[    0.354659] Synchronize counters for CPU 3:
[    0.354660] done.
[    0.360901] Brought up 4 CPUs
[    0.367821] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.377613] futex hash table entries: 1024 (order: 3, 32768 bytes)
[    0.383863] pinctrl core: initialized pinctrl subsystem
[    0.389755] NET: Registered protocol family 16
[    0.403515] FPU Affinity set after 11720 emulations
[    0.449650] pull PCIe RST: RALINK_RSTCTRL = 4000000
[    0.754956] release PCIe RST: RALINK_RSTCTRL = 7000000
[    0.760005] ***** Xtal 40MHz *****
[    0.763360] release PCIe RST: RALINK_RSTCTRL = 7000000
[    0.768459] Port 0 N_FTS = 1b105000
[    0.771934] Port 1 N_FTS = 1b105000
[    0.775368] Port 2 N_FTS = 1b102800
[    1.930662] PCIE2 no card, disable it(RST&CLK)
[    1.935027]  -> 21007f2
[    1.937422] PCIE0 enabled
[    1.940014] PCIE1 enabled
[    1.942610] PCI host bridge /pcie@1e140000 ranges:
[    1.947395]  MEM 0x0000000060000000..0x000000006fffffff
[    1.952552]   IO 0x000000001e160000..0x000000001e16ffff
[    1.957754] PCI coherence region base: 0x60000000, mask/settings: 0xf0000002
[    2.009324] mt7621_gpio 1e000600.gpio: registering 32 gpios
[    2.015156] mt7621_gpio 1e000600.gpio: registering 32 gpios
[    2.020966] mt7621_gpio 1e000600.gpio: registering 32 gpios
[    2.027884] PCI host bridge to bus 0000:00
[    2.031941] pci_bus 0000:00: root bus resource [mem 0x60000000-0x6fffffff]
[    2.038727] pci_bus 0000:00: root bus resource [io  0xffffffff]
[    2.044616] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[    2.051341] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    2.059269] pci 0000:00:00.0: [0e8d:0801] type 01 class 0x060400
[    2.059300] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x7fffffff]
[    2.059312] pci 0000:00:00.0: reg 0x14: [mem 0x60300000-0x6030ffff]
[    2.059374] pci 0000:00:00.0: supports D1
[    2.059383] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
[    2.059617] pci 0000:00:01.0: [0e8d:0801] type 01 class 0x060400
[    2.059641] pci 0000:00:01.0: reg 0x10: [mem 0x00000000-0x7fffffff]
[    2.059656] pci 0000:00:01.0: reg 0x14: [mem 0x60310000-0x6031ffff]
[    2.059705] pci 0000:00:01.0: supports D1
[    2.059713] pci 0000:00:01.0: PME# supported from D0 D1 D3hot
[    2.060115] pci 0000:01:00.0: [14c3:7603] type 00 class 0x028000
[    2.060145] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x000fffff]
[    2.060267] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold
[    2.060466] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    2.060648] pci 0000:02:00.0: [14c3:7662] type 00 class 0x028000
[    2.060683] pci 0000:02:00.0: reg 0x10: [mem 0x00000000-0x000fffff 64bit]
[    2.060728] pci 0000:02:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
[    2.060816] pci 0000:02:00.0: PME# supported from D0 D3hot D3cold
[    2.061016] pci_bus 0000:02: busn_res: [bus 02-ff] end is updated to 02
[    2.061034] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 02
[    2.061093] pci 0000:00:00.0: BAR 0: no space for [mem size 0x80000000]
[    2.067602] pci 0000:00:00.0: BAR 0: failed to assign [mem size 0x80000000]
[    2.074545] pci 0000:00:01.0: BAR 0: no space for [mem size 0x80000000]
[    2.081087] pci 0000:00:01.0: BAR 0: failed to assign [mem size 0x80000000]
[    2.088015] pci 0000:00:00.0: BAR 8: assigned [mem 0x60000000-0x600fffff]
[    2.094738] pci 0000:00:01.0: BAR 8: assigned [mem 0x60100000-0x601fffff]
[    2.101497] pci 0000:00:01.0: BAR 9: assigned [mem 0x60200000-0x602fffff pref]
[    2.108649] pci 0000:00:00.0: BAR 1: assigned [mem 0x60300000-0x6030ffff]
[    2.115404] pci 0000:00:01.0: BAR 1: assigned [mem 0x60310000-0x6031ffff]
[    2.122138] pci 0000:01:00.0: BAR 0: assigned [mem 0x60000000-0x600fffff]
[    2.128881] pci 0000:00:00.0: PCI bridge to [bus 01]
[    2.133788] pci 0000:00:00.0:   bridge window [mem 0x60000000-0x600fffff]
[    2.140555] pci 0000:02:00.0: BAR 0: assigned [mem 0x60100000-0x601fffff 64bit]
[    2.147793] pci 0000:02:00.0: BAR 6: assigned [mem 0x60200000-0x6020ffff pref]
[    2.154968] pci 0000:00:01.0: PCI bridge to [bus 02]
[    2.159885] pci 0000:00:01.0:   bridge window [mem 0x60100000-0x601fffff]
[    2.166635] pci 0000:00:01.0:   bridge window [mem 0x60200000-0x602fffff pref]
[    2.173807] BAR0 at slot 0 = 0
[    2.176821] bus=0x0, slot = 0x0
[    2.179929] BAR0 at slot 1 = 0
[    2.182941] bus=0x0, slot = 0x1
[    2.186059] bus=0x1, slot = 0x0, irq=0xff
[    2.190052] bus=0x2, slot = 0x1, irq=0xff
[    2.195374] clocksource: Switched to clocksource GIC
[    2.201886] NET: Registered protocol family 2
[    2.206991] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[    2.213879] TCP bind hash table entries: 2048 (order: 2, 16384 bytes)
[    2.220380] TCP: Hash tables configured (established 2048 bind 2048)
[    2.226740] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    2.232503] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    2.238991] NET: Registered protocol family 1
[    2.243310] PCI: CLS 80 bytes, default 32
[    2.475320] 4 CPUs re-calibrate udelay(lpj = 2924544)
[    2.481720] Crashlog allocated RAM at address 0x3f00000
[    2.487253] workingset: timestamp_bits=30 max_order=16 bucket_order=0
[    2.497205] random: fast init done
[    2.503153] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    2.508957] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    2.521334] io scheduler noop registered
[    2.525170] io scheduler deadline registered (default)
[    2.530800] gpio-export gpio_export: 1 gpio(s) exported
[    2.536631] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    2.546698] console [ttyS0] disabled
[    2.550247] 1e000c00.uartlite: ttyS0 at MMIO 0x1e000c00 (irq = 18, base_baud = 3125000) is a 16550A
[    2.559239] console [ttyS0] enabled
[    2.566106] bootconsole [early0] disabled
[    2.575604] MediaTek Nand driver init, version v2.1 Fix AHB virt2phys error
[    2.582660] Allocate 16 byte aligned buffer: 80588360
[    2.587709] Enable NFI Clock
[    2.590575] # MTK NAND # : Use HW ECC
[    2.594233] Device not found, ID: c8d1
[    2.597975] Not Support this Device!
[    2.601702] chip_mode=00000001
[    2.604750] Support this Device in MTK table! c8d1
[    2.609710] [NAND]select ecc bit:4, sparesize :64 spare_per_sector=16
[    2.616186] nand: device found, Manufacturer ID: 0xc8, Chip ID: 0xd1
[    2.622507] nand: ESMT NAND 128MiB 3,3V 8-bit
[    2.626855] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    2.634399] Scanning device for bad blocks
[    2.784501] 10 ofpart partitions found on MTD device MT7621-NAND
[    2.790493] Creating 10 MTD partitions on "MT7621-NAND":
[    2.795810] 0x000000000000-0x000000080000 : "Bootloader"
[    2.802545] 0x000000080000-0x0000000c0000 : "Config"
[    2.808805] 0x0000000c0000-0x000000100000 : "Bdata"
[    2.814961] 0x000000100000-0x000000140000 : "Factory"
[    2.821369] 0x000000140000-0x000000180000 : "crash"
[    2.827570] 0x000000180000-0x0000001c0000 : "crash_syslog"
[    2.834259] 0x0000001c0000-0x000000200000 : "reserved0"
[    2.840866] 0x000000200000-0x000000600000 : "kernel_stock"
[    2.847705] 0x000000600000-0x000000a00000 : "kernel"
[    2.853942] 0x000000a00000-0x000007f80000 : "ubi"
[    2.860898] [mtk_nand] probe successfully!
[    2.865771] Signature matched and data read!
[    2.870021] load_fact_bbt success 1023
[    2.874429] libphy: Fixed MDIO Bus: probed
[    2.947980] libphy: mdio: probed
[    4.350751] mtk_soc_eth 1e100000.ethernet: loaded mt7530 driver
[    4.357511] mtk_soc_eth 1e100000.ethernet eth0: mediatek frame engine at 0xbe100000, irq 20
[    4.368063] NET: Registered protocol family 10
[    4.374121] NET: Registered protocol family 17
[    4.378695] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    4.391609] 8021q: 802.1Q VLAN Support v1.8
[    4.398759] UBI: auto-attach mtd9
[    4.402087] ubi0: attaching mtd9
[    4.618462] mtk_soc_eth 1e100000.ethernet eth0: port 1 link up
[    5.521109] ubi0: scanning is finished
[    5.541359] ubi0: attached mtd9 (name "ubi", size 117 MiB)
[    5.546892] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
[    5.553734] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 2048
[    5.560504] ubi0: VID header offset: 2048 (aligned 2048), data offset: 4096
[    5.567456] ubi0: good PEBs: 940, bad PEBs: 0, corrupted PEBs: 0
[    5.573433] ubi0: user volume: 2, internal volumes: 1, max. volumes count: 128
[    5.580638] ubi0: max/mean erase counter: 13/8, WL threshold: 4096, image sequence number: 2038926528
[    5.589837] ubi0: available PEBs: 0, total reserved PEBs: 940, PEBs reserved for bad PEB handling: 20
[    5.599059] ubi0: background thread "ubi_bgt0d" started, PID 375
[    5.601341] block ubiblock0_0: created from ubi0:0(rootfs)
[    5.601351] ubiblock: device ubiblock0_0 (rootfs) set to be root filesystem
[    5.601368] hctosys: unable to open rtc device (rtc0)
[    5.628756] VFS: Mounted root (squashfs filesystem) readonly on device 254:0.
[    5.636357] Freeing unused kernel memory: 204K
[    5.640789] This architecture does not have kernel memory protection.
[    6.074383] init: Console is alive
[    6.078103] init: - watchdog -
[    6.598228] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    6.689821] usbcore: registered new interface driver usbfs
[    6.695512] usbcore: registered new interface driver hub
[    6.700979] usbcore: registered new device driver usb
[    6.715182] xhci-mtk 1e1c0000.xhci: xHCI Host Controller
[    6.720568] xhci-mtk 1e1c0000.xhci: new USB bus registered, assigned bus number 1
[    6.735589] xhci-mtk 1e1c0000.xhci: hcc params 0x01401198 hci version 0x96 quirks 0x00210010
[    6.744061] xhci-mtk 1e1c0000.xhci: irq 19, io mem 0x1e1c0000
[    6.750901] hub 1-0:1.0: USB hub found
[    6.754722] hub 1-0:1.0: 2 ports detected
[    6.759206] xhci-mtk 1e1c0000.xhci: xHCI Host Controller
[    6.764511] xhci-mtk 1e1c0000.xhci: new USB bus registered, assigned bus number 2
[    6.772203] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    6.781167] hub 2-0:1.0: USB hub found
[    6.784977] hub 2-0:1.0: 1 port detected
[    6.791578] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    6.815674] init: - preinit -
[    7.225713] usb 2-1: new SuperSpeed USB device number 2 using xhci-mtk
[    7.959769] mtk_soc_eth 1e100000.ethernet eth0: port 2 link up
[   10.669578] UBIFS (ubi0:1): background thread "ubifs_bgt0_1" started, PID 466
[   10.743280] UBIFS (ubi0:1): recovery needed
[   11.027687] UBIFS (ubi0:1): recovery completed
[   11.032307] UBIFS (ubi0:1): UBIFS: mounted UBI device 0, volume 1, name "rootfs_data"
[   11.040127] UBIFS (ubi0:1): LEB size: 126976 bytes (124 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[   11.050019] UBIFS (ubi0:1): FS size: 112754688 bytes (107 MiB, 888 LEBs), journal size 5586944 bytes (5 MiB, 44 LEBs)
[   11.060604] UBIFS (ubi0:1): reserved for root: 4952683 bytes (4836 KiB)
[   11.067215] UBIFS (ubi0:1): media format: w4/r0 (latest is w4/r0), UUID 20B5BE78-6BF9-4D9C-97D0-63EEEE29FCD2, small LPT model
[   11.089886] mount_root: overlay filesystem has not been fully initialized yet
[   11.099816] mount_root: switching to ubifs overlay
[   11.120963] urandom-seed: Seed file not found (/etc/urandom.seed)
[   11.206878] procd: - early -
[   11.209848] procd: - watchdog -
[   11.915518] procd: - watchdog -
[   11.918952] procd: - ubus -
[   11.977423] procd: - init -
[   12.220332] kmodloader: loading kernel modules from /etc/modules.d/*
[   12.230433] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   12.243720] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180
[   12.251747] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d
[   12.260419] ip_tables: (C) 2000-2006 Netfilter Core Team
[   12.272071] nf_conntrack version 0.5.0 (4096 buckets, 16384 max)
[   12.311093] xt_time: kernel timezone is -0000
[   12.364278] mt7603e 0000:01:00.0: ASIC revision: 76030010
[   12.395013] mt7603e 0000:01:00.0: Firmware Version: ap_pcie
[   12.400808] mt7603e 0000:01:00.0: Build Time: 20160107100755
[   12.445412] firmware init done
[   12.617059] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   12.624541] mt76x2e 0000:02:00.0: ASIC revision: 76120044
[   12.655444] mt76x2e 0000:02:00.0: ROM patch already applied
[   12.662251] mt76x2e 0000:02:00.0: Firmware Version: 0.0.00
[   12.667798] mt76x2e 0000:02:00.0: Build: 1
[   12.671877] mt76x2e 0000:02:00.0: Build Time: 201507311614____
[   12.705439] mt76x2e 0000:02:00.0: Firmware running!
[   12.711683] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[   12.719726] PPP generic driver version 2.4.2
[   12.725836] NET: Registered protocol family 24
[   12.733132] kmodloader: done loading kernel modules from /etc/modules.d/*
[   19.603555] device eth0 entered promiscuous mode
[   19.610919] br-lan: port 1(eth0.1) entered blocking state
[   19.616476] br-lan: port 1(eth0.1) entered disabled state
[   19.622586] device eth0.1 entered promiscuous mode
[   19.634503] br-lan: port 1(eth0.1) entered blocking state
[   19.639985] br-lan: port 1(eth0.1) entered forwarding state
[   19.646017] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[   20.605960] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[   22.159899] random: crng init done
[  191.009312] Data buffer not 16 bytes aligned: 8e40c028
[  191.017026] Data buffer not 16 bytes aligned: 8e40c508
[  191.025523] Data buffer not 16 bytes aligned: 8e40c238
[  191.034117] Data buffer not 16 bytes aligned: 8e40c198
[  191.052419] Data buffer not 16 bytes aligned: 8e40c4f8
[  191.066409] Data buffer not 16 bytes aligned: 8e40c278
[  191.081738] Data buffer not 16 bytes aligned: 8e40c1c8
[  191.096954] Data buffer not 16 bytes aligned: 8e40c088
[  191.111147] Data buffer not 16 bytes aligned: 8e40c028
[  191.139460] Data buffer not 16 bytes aligned: 8e40c018
[  191.161243] Data buffer not 16 bytes aligned: 8e40c028
[  191.176451] Data buffer not 16 bytes aligned: 8e40c128
[  191.193724] Data buffer not 16 bytes aligned: 8e40c018
[  191.213324] Data buffer not 16 bytes aligned: 8e40c078
[  191.225880] Data buffer not 16 bytes aligned: 8e40c2a8
[  191.265755] Data buffer not 16 bytes aligned: 8e40c158
[  191.278589] Data buffer not 16 bytes aligned: 8e40c138
[  191.289159] Data buffer not 16 bytes aligned: 8e40c248
[  191.317077] Data buffer not 16 bytes aligned: 8e40c278
[  191.334425] Data buffer not 16 bytes aligned: 8e40c0e8
[  191.350534] Data buffer not 16 bytes aligned: 8e40c0f8
[  283.715442] kmodloader: loading kernel modules from /etc/modules.d/*
[  283.733985] SCSI subsystem initialized
[  283.745511] usbcore: registered new interface driver usb-storage
[  283.751874] kmodloader: done loading kernel modules from /etc/modules.d/*
[  283.816960] kmodloader: loading kernel modules from /etc/modules.d/*
[  283.825496] kmodloader: done loading kernel modules from /etc/modules.d/*
[  313.727224] Data buffer not 16 bytes aligned: 8ee38068
[  313.741091] Data buffer not 16 bytes aligned: 8ee38128
[  313.749533] Data buffer not 16 bytes aligned: 8ee38078
[  313.756765] Data buffer not 16 bytes aligned: 8ee383d8
[  313.785037] Data buffer not 16 bytes aligned: 8ee38128
[  313.795667] Data buffer not 16 bytes aligned: 8ee38258
[  313.805290] Data buffer not 16 bytes aligned: 8ee38418
[  313.813705] Data buffer not 16 bytes aligned: 8ee381a8
[  313.822188] Data buffer not 16 bytes aligned: 8ee380d8
[  313.840545] Data buffer not 16 bytes aligned: 8ee38438
[  313.848969] Data buffer not 16 bytes aligned: 8ee382d8
[  434.295326] kmodloader: loading kernel modules from /etc/modules.d/*
[  434.305009] usbcore: registered new interface driver ums-alauda
[  434.312020] usbcore: registered new interface driver ums-cypress
[  434.319060] usbcore: registered new interface driver ums-datafab
[  434.326131] usbcore: registered new interface driver ums-freecom
[  434.333240] usbcore: registered new interface driver ums-isd200
[  434.340310] usbcore: registered new interface driver ums-jumpshot
[  434.347438] usbcore: registered new interface driver ums-karma
[  434.354646] usbcore: registered new interface driver ums-sddr09
[  434.361748] usbcore: registered new interface driver ums-sddr55
[  434.368806] usbcore: registered new interface driver ums-usbat
[  434.374965] kmodloader: done loading kernel modules from /etc/modules.d/*
[  457.162257] kmodloader: loading kernel modules from /etc/modules.d/*
[  457.174784] scsi host0: uas
[  457.178931] xhci-mtk 1e1c0000.xhci: ERROR Transfer event for disabled endpoint or incorrect stream ring
[  457.188303] xhci-mtk 1e1c0000.xhci: @000000000fded170 0ee20000 00000000 05000000 01058001
[  457.196514] xhci-mtk 1e1c0000.xhci: ERROR Transfer event for disabled endpoint or incorrect stream ring
[  457.205879] xhci-mtk 1e1c0000.xhci: @000000000fded180 0ee20100 00000000 05000000 01078001
[  464.441727] Data buffer not 16 bytes aligned: 8ee2c458
[  464.469820] Data buffer not 16 bytes aligned: 8ee2c0d8
[  464.477079] Data buffer not 16 bytes aligned: 8ee2c368
[  477.979944] scsi 0:0:0:0: tag#0 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD IN
[  477.987509] scsi 0:0:0:0: tag#0 CDB: opcode=0x12 12 00 00 00 24 00
[  479.029938] scsi host0: uas_eh_bus_reset_handler FAILED to get lock err -16
[  479.036896] scsi 0:0:0:0: Device offlined - not ready after error recovery
[  479.044393] usbcore: registered new interface driver uas
[  479.050016] kmodloader: done loading kernel modules from /etc/modules.d/*
emvova commented on 28.01.2018 15:58

usb hdd chipset

   pic1.jpg (220.3 KiB)
diizzyy commented on 13.05.2018 08:12

This should be fixed in trunk/master, still an issue?

Nikita commented on 07.07.2018 14:33

The same router, the same error for me. Mt external HDD drive is not recognized.
I am using OpenWrt 18.06.0-rc1. Here is the log, but it is pretty much the same as above.

[   11.526302] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   11.570858] SCSI subsystem initialized
[   11.600710] usbcore: registered new interface driver usb-storage
[   11.613179] scsi host0: uas
[   11.616785] xhci-mtk 1e1c0000.xhci: ERROR Transfer event for unknown stream ring slot 1 ep 4
[   11.625203] xhci-mtk 1e1c0000.xhci: @000000000f7bf1d0 0e950000 00000000 05000000 01058001
[   11.633342] xhci-mtk 1e1c0000.xhci: ERROR Transfer event for unknown stream ring slot 1 ep 6
[   11.641741] xhci-mtk 1e1c0000.xhci: @000000000f7bf1e0 0e950100 00000000 05000000 01078001
[   33.174936] scsi 0:0:0:0: tag#0 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD IN 
[   33.182488] scsi 0:0:0:0: tag#0 CDB: opcode=0x12 12 00 00 00 24 00
[   33.188740] xhci-mtk 1e1c0000.xhci: Mismatch between completed Set TR Deq Ptr command & xHCI internal state.
[   33.198527] xhci-mtk 1e1c0000.xhci: ep deq seg = 8e9d0680, deq ptr = af07e010
[   34.284898] scsi host0: uas_eh_device_reset_handler FAILED to get lock err -16
[   34.292099] scsi 0:0:0:0: Device offlined - not ready after error recovery
[   34.299395] usbcore: registered new interface driver uas
[   34.305087] kmodloader: done loading kernel modules from /tmp/overlay/upper/etc/modules-boot.d/*
Dmitry Gubin commented on 07.07.2018 14:50

Same problem USB3.0 HDD docking station Maiwo K308(JMS567 chipset).

[    0.000000] Linux version 4.14.50 (buildbot@builds-03.infra.lede-project.org) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r6984-fa0275b)) #0 SMP Fri Jun 22 10:22:57 2018
[    0.000000] SoC Type: MediaTek MT7621 ver:1 eco:3
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 0001992f (MIPS 1004Kc)
[    0.000000] MIPS: machine is Xiaomi Mi Router 3G
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 10000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] VPE topology {2,2} total 4
[    0.000000] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
[    0.000000] MIPS secondary cache 256kB, 8-way, linesize 32 bytes.
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000]   HighMem  empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000] On node 0 totalpages: 65536
[    0.000000] free_area_init_node: node 0, pgdat 80543e00, node_mem_map 81003000
[    0.000000]   Normal zone: 512 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 65536 pages, LIFO batch:15
[    0.000000] random: get_random_bytes called from 0x80547740 with crng_init=0
[    0.000000] percpu: Embedded 14 pages/cpu @81210000 s26256 r8192 d22896 u57344
[    0.000000] pcpu-alloc: s26256 r8192 d22896 u57344 alloc=14*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 65024
[    0.000000] Kernel command line: console=ttyS0,115200n8 rootfstype=squashfs,jffs2
[    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Writing ErrCtl register=00011020
[    0.000000] Readback ErrCtl register=00011020
[    0.000000] Memory: 253660K/262144K available (4589K kernel code, 238K rwdata, 564K rodata, 228K init, 275K bss, 8484K reserved, 0K cma-reserved, 0K highmem)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS: 256
[    0.000000] clocksource: GIC: mask: 0xffffffffffffffff max_cycles: 0xcaf478abb4, max_idle_ns: 440795247997 ns
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 4343773742 ns
[    0.000010] sched_clock: 32 bits at 440MHz, resolution 2ns, wraps every 4880645118ns
[    0.007810] Calibrating delay loop... 586.13 BogoMIPS (lpj=2930688)
[    0.073970] pid_max: default: 32768 minimum: 301
[    0.078735] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.085242] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.093942] Hierarchical SRCU implementation.
[    0.099007] smp: Bringing up secondary CPUs ...
[    0.104173] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.104182] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
[    0.104194] MIPS secondary cache 256kB, 8-way, linesize 32 bytes.
[    0.104334] CPU1 revision is: 0001992f (MIPS 1004Kc)
[    0.163753] Synchronize counters for CPU 1: done.
[    0.198312] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.198321] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
[    0.198330] MIPS secondary cache 256kB, 8-way, linesize 32 bytes.
[    0.198405] CPU2 revision is: 0001992f (MIPS 1004Kc)
[    0.254840] Synchronize counters for CPU 2: done.
[    0.285278] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.285285] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
[    0.285293] MIPS secondary cache 256kB, 8-way, linesize 32 bytes.
[    0.285371] CPU3 revision is: 0001992f (MIPS 1004Kc)
[    0.340021] Synchronize counters for CPU 3: done.
[    0.369881] smp: Brought up 1 node, 4 CPUs
[    0.377576] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.387361] futex hash table entries: 1024 (order: 3, 32768 bytes)
[    0.393653] pinctrl core: initialized pinctrl subsystem
[    0.399470] NET: Registered protocol family 16
[    0.409679] Can't analyze schedule() prologue at 80476f64
[    0.415043] FPU Affinity set after 11720 emulations
[    0.415750] pull PCIe RST: RALINK_RSTCTRL = 4000000
[    0.720900] release PCIe RST: RALINK_RSTCTRL = 7000000
[    0.725932] ***** Xtal 40MHz *****
[    0.729306] release PCIe RST: RALINK_RSTCTRL = 7000000
[    0.734412] Port 0 N_FTS = 1b105000
[    0.737854] Port 1 N_FTS = 1b105000
[    0.741314] Port 2 N_FTS = 1b102800
[    1.896374] PCIE2 no card, disable it(RST&CLK)
[    1.900722]  -> 21007f2
[    1.903135] PCIE0 enabled
[    1.905726] PCIE1 enabled
[    1.908347] PCI host bridge /pcie@1e140000 ranges:
[    1.913091]  MEM 0x0000000060000000..0x000000006fffffff
[    1.918259]   IO 0x000000001e160000..0x000000001e16ffff
[    1.923463] PCI coherence region base: 0x8fc35cf0, mask/settings: 0x60000000
[    1.938845] mt7621_gpio 1e000600.gpio: registering 32 gpios
[    1.944744] mt7621_gpio 1e000600.gpio: registering 32 gpios
[    1.950499] mt7621_gpio 1e000600.gpio: registering 32 gpios
[    1.957458] PCI host bridge to bus 0000:00
[    1.961489] pci_bus 0000:00: root bus resource [mem 0x60000000-0x6fffffff]
[    1.968345] pci_bus 0000:00: root bus resource [io  0xffffffff]
[    1.974177] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[    1.980935] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    1.988824] pci 0000:00:00.0: [0e8d:0801] type 01 class 0x060400
[    1.988865] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x7fffffff]
[    1.988878] pci 0000:00:00.0: reg 0x14: [mem 0x60300000-0x6030ffff]
[    1.988943] pci 0000:00:00.0: supports D1
[    1.988952] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
[    1.989205] pci 0000:00:01.0: [0e8d:0801] type 01 class 0x060400
[    1.989242] pci 0000:00:01.0: reg 0x10: [mem 0x00000000-0x7fffffff]
[    1.989261] pci 0000:00:01.0: reg 0x14: [mem 0x60310000-0x6031ffff]
[    1.989324] pci 0000:00:01.0: supports D1
[    1.989333] pci 0000:00:01.0: PME# supported from D0 D1 D3hot
[    1.989803] pci 0000:01:00.0: [14c3:7603] type 00 class 0x028000
[    1.989856] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x000fffff]
[    1.989984] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold
[    1.990180] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    1.990405] pci 0000:02:00.0: [14c3:7662] type 00 class 0x028000
[    1.990467] pci 0000:02:00.0: reg 0x10: [mem 0x00000000-0x000fffff 64bit]
[    1.990513] pci 0000:02:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
[    1.990609] pci 0000:02:00.0: PME# supported from D0 D3hot D3cold
[    1.990808] pci_bus 0000:02: busn_res: [bus 02-ff] end is updated to 02
[    1.990834] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 02
[    1.990909] pci 0000:00:00.0: BAR 0: no space for [mem size 0x80000000]
[    1.997459] pci 0000:00:00.0: BAR 0: failed to assign [mem size 0x80000000]
[    2.004333] pci 0000:00:01.0: BAR 0: no space for [mem size 0x80000000]
[    2.010916] pci 0000:00:01.0: BAR 0: failed to assign [mem size 0x80000000]
[    2.017817] pci 0000:00:00.0: BAR 8: assigned [mem 0x60000000-0x600fffff]
[    2.024579] pci 0000:00:01.0: BAR 8: assigned [mem 0x60100000-0x601fffff]
[    2.031289] pci 0000:00:01.0: BAR 9: assigned [mem 0x60200000-0x602fffff pref]
[    2.038482] pci 0000:00:00.0: BAR 1: assigned [mem 0x60300000-0x6030ffff]
[    2.045211] pci 0000:00:01.0: BAR 1: assigned [mem 0x60310000-0x6031ffff]
[    2.051972] pci 0000:01:00.0: BAR 0: assigned [mem 0x60000000-0x600fffff]
[    2.058684] pci 0000:00:00.0: PCI bridge to [bus 01]
[    2.063634] pci 0000:00:00.0:   bridge window [mem 0x60000000-0x600fffff]
[    2.070354] pci 0000:02:00.0: BAR 0: assigned [mem 0x60100000-0x601fffff 64bit]
[    2.077630] pci 0000:02:00.0: BAR 6: assigned [mem 0x60200000-0x6020ffff pref]
[    2.084769] pci 0000:00:01.0: PCI bridge to [bus 02]
[    2.089716] pci 0000:00:01.0:   bridge window [mem 0x60100000-0x601fffff]
[    2.096438] pci 0000:00:01.0:   bridge window [mem 0x60200000-0x602fffff pref]
[    2.104993] clocksource: Switched to clocksource GIC
[    2.111407] NET: Registered protocol family 2
[    2.116540] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[    2.123424] TCP bind hash table entries: 2048 (order: 2, 16384 bytes)
[    2.129870] TCP: Hash tables configured (established 2048 bind 2048)
[    2.136287] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    2.142049] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    2.148533] NET: Registered protocol family 1
[    2.152868] PCI: CLS 0 bytes, default 32
[    2.384942] 4 CPUs re-calibrate udelay(lpj = 2924544)
[    2.391343] Crashlog allocated RAM at address 0x3f00000
[    2.396687] workingset: timestamp_bits=30 max_order=16 bucket_order=0
[    2.404912] random: fast init done
[    2.410539] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    2.416318] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    2.429400] io scheduler noop registered
[    2.433238] io scheduler deadline registered (default)
[    2.439026] gpio-export gpio_export: 1 gpio(s) exported
[    2.444882] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    2.453852] console [ttyS0] disabled
[    2.457479] 1e000c00.uartlite: ttyS0 at MMIO 0x1e000c00 (irq = 18, base_baud = 3125000) is a 16550A
[    2.466496] console [ttyS0] enabled
[    2.473348] bootconsole [early0] disabled
[    2.482241] cacheinfo: Failed to find cpu0 device node
[    2.487404] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    2.494304] MediaTek Nand driver init, version v2.1 Fix AHB virt2phys error
[    2.501445] Allocate 16 byte aligned buffer: 805b7ed0
[    2.506518] Enable NFI Clock
[    2.509384] # MTK NAND # : Use HW ECC
[    2.513042] Device found in MTK table, ID: c8d1, EXT_ID: 809540
[    2.518959] Support this Device in MTK table! c8d1
[    2.523907] [NAND]select ecc bit:4, sparesize :64 spare_per_sector=16
[    2.530377] nand: device found, Manufacturer ID: 0xc8, Chip ID: 0xd1
[    2.536713] nand: ESMT NAND 128MiB 3,3V 8-bit
[    2.541049] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    2.548604] Scanning device for bad blocks
[    2.697019] 10 fixed-partitions partitions found on MTD device MT7621-NAND
[    2.703860] Creating 10 MTD partitions on "MT7621-NAND":
[    2.709168] 0x000000000000-0x000000080000 : "Bootloader"
[    2.715605] 0x000000080000-0x0000000c0000 : "Config"
[    2.721544] 0x0000000c0000-0x000000100000 : "Bdata"
[    2.727378] 0x000000100000-0x000000140000 : "Factory"
[    2.733371] 0x000000140000-0x000000180000 : "crash"
[    2.739233] 0x000000180000-0x0000001c0000 : "crash_syslog"
[    2.745747] 0x0000001c0000-0x000000200000 : "reserved0"
[    2.751941] 0x000000200000-0x000000600000 : "kernel_stock"
[    2.758465] 0x000000600000-0x000000a00000 : "kernel"
[    2.764398] 0x000000a00000-0x000007f80000 : "ubi"
[    2.771067] [mtk_nand] probe successfully!
[    2.775900] Signature matched and data read!
[    2.780148] load_fact_bbt success 1023
[    2.784635] libphy: Fixed MDIO Bus: probed
[    2.857534] libphy: mdio: probed
[    4.261146] mtk_soc_eth 1e100000.ethernet: loaded mt7530 driver
[    4.267827] mtk_soc_eth 1e100000.ethernet eth0: mediatek frame engine at 0xbe100000, irq 20
[    4.278580] NET: Registered protocol family 10
[    4.284643] Segment Routing with IPv6
[    4.288457] NET: Registered protocol family 17
[    4.292952] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    4.305864] 8021q: 802.1Q VLAN Support v1.8
[    4.312990] UBI: auto-attach mtd9
[    4.316370] ubi0: attaching mtd9
[    4.461474] mtk_soc_eth 1e100000.ethernet eth0: port 1 link up
[    5.429019] ubi0: scanning is finished
[    5.449109] ubi0: attached mtd9 (name "ubi", size 117 MiB)
[    5.454586] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
[    5.461470] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 2048
[    5.468245] ubi0: VID header offset: 2048 (aligned 2048), data offset: 4096
[    5.475187] ubi0: good PEBs: 940, bad PEBs: 0, corrupted PEBs: 0
[    5.481163] ubi0: user volume: 2, internal volumes: 1, max. volumes count: 128
[    5.488363] ubi0: max/mean erase counter: 2/1, WL threshold: 4096, image sequence number: 687133564
[    5.497380] ubi0: available PEBs: 0, total reserved PEBs: 940, PEBs reserved for bad PEB handling: 20
[    5.506590] ubi0: background thread "ubi_bgt0d" started, PID 387
[    5.508389] block ubiblock0_0: created from ubi0:0(rootfs)
[    5.508401] ubiblock: device ubiblock0_0 (rootfs) set to be root filesystem
[    5.508418] hctosys: unable to open rtc device (rtc0)
[    5.537185] VFS: Mounted root (squashfs filesystem) readonly on device 254:0.
[    5.544797] Freeing unused kernel memory: 228K
[    5.549260] This architecture does not have kernel memory protection.
[    6.066583] init: Console is alive
[    6.070218] init: - watchdog -
[    6.146572] mtk_soc_eth 1e100000.ethernet eth0: port 2 link up
[    6.632043] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    6.720511] usbcore: registered new interface driver usbfs
[    6.726121] usbcore: registered new interface driver hub
[    6.731594] usbcore: registered new device driver usb
[    6.745791] xhci-mtk 1e1c0000.xhci: xHCI Host Controller
[    6.751131] xhci-mtk 1e1c0000.xhci: new USB bus registered, assigned bus number 1
[    6.765180] xhci-mtk 1e1c0000.xhci: hcc params 0x01401198 hci version 0x96 quirks 0x00210010
[    6.773665] xhci-mtk 1e1c0000.xhci: irq 19, io mem 0x1e1c0000
[    6.780440] hub 1-0:1.0: USB hub found
[    6.784257] hub 1-0:1.0: 2 ports detected
[    6.788934] xhci-mtk 1e1c0000.xhci: xHCI Host Controller
[    6.794245] xhci-mtk 1e1c0000.xhci: new USB bus registered, assigned bus number 2
[    6.801735] xhci-mtk 1e1c0000.xhci: Host supports USB 3.0  SuperSpeed
[    6.808355] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    6.817321] hub 2-0:1.0: USB hub found
[    6.821132] hub 2-0:1.0: 1 port detected
[    6.828283] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    6.845358] init: - preinit -
[    6.901556] mtk_soc_eth 1e100000.ethernet eth0: port 2 link down
[    7.225172] usb 2-1: new SuperSpeed USB device number 2 using xhci-mtk
[    7.304253] random: procd: uninitialized urandom read (4 bytes read)
[    7.531222] mtk_soc_eth 1e100000.ethernet: PPE started
[   10.292596] mtk_soc_eth 1e100000.ethernet eth0: port 2 link up
[   10.751693] UBIFS (ubi0:1): background thread "ubifs_bgt0_1" started, PID 484
[   10.826593] UBIFS (ubi0:1): recovery needed
[   11.000433] UBIFS (ubi0:1): recovery completed
[   11.005062] UBIFS (ubi0:1): UBIFS: mounted UBI device 0, volume 1, name "rootfs_data"
[   11.012858] UBIFS (ubi0:1): LEB size: 126976 bytes (124 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[   11.022746] UBIFS (ubi0:1): FS size: 112373760 bytes (107 MiB, 885 LEBs), journal size 5586944 bytes (5 MiB, 44 LEBs)
[   11.033315] UBIFS (ubi0:1): reserved for root: 4952683 bytes (4836 KiB)
[   11.039915] UBIFS (ubi0:1): media format: w4/r0 (latest is w5/r0), UUID 81367A9F-39E5-4CEC-A7E5-22AF05B270C8, small LPT model
[   11.064431] mount_root: loading kmods from internal overlay
[   11.153260] kmodloader: loading kernel modules from /tmp/overlay/upper/etc/modules-boot.d/*
[   11.407918] SCSI subsystem initialized
[   11.442899] usbcore: registered new interface driver usb-storage
[   11.455482] scsi host0: uas
[   11.459086] xhci-mtk 1e1c0000.xhci: ERROR Transfer event for unknown stream ring slot 1 ep 4
[   11.467505] xhci-mtk 1e1c0000.xhci: @000000000f78b190 0e9ae000 00000000 05000000 01058001
[   11.475647] xhci-mtk 1e1c0000.xhci: ERROR Transfer event for unknown stream ring slot 1 ep 6
[   11.484046] xhci-mtk 1e1c0000.xhci: @000000000f78b1a0 0e9ae100 00000000 05000000 01078001
[   32.575006] scsi 0:0:0:0: tag#0 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD IN
[   32.582562] scsi 0:0:0:0: tag#0 CDB: opcode=0x12 12 00 00 00 24 00
[   32.588806] xhci-mtk 1e1c0000.xhci: Mismatch between completed Set TR Deq Ptr command & xHCI internal state.
[   32.598592] xhci-mtk 1e1c0000.xhci: ep deq seg = 8ea5ef00, deq ptr = aea6b010
[   33.684978] scsi host0: uas_eh_device_reset_handler FAILED to get lock err -16
[   33.692180] scsi 0:0:0:0: Device offlined - not ready after error recovery
[   33.699447] usbcore: registered new interface driver uas
[   33.705098] kmodloader: done loading kernel modules from /tmp/overlay/upper/etc/modules-boot.d/*
[   33.932535] block: attempting to load /tmp/ubifs_cfg/upper/etc/config/fstab
[   33.943699] block: extroot: not configured
[   33.988383] mount_root: switching to ubifs overlay
[   34.020262] urandom-seed: Seeding with /etc/urandom.seed
[   34.120351] mtk_soc_eth 1e100000.ethernet: 0x100 = 0x6060000c, 0x10c = 0x80818
[   34.138950] procd: - early -
[   34.141928] procd: - watchdog -
[   34.835159] procd: - watchdog -
[   34.838603] procd: - ubus -
[   34.904456] random: ubusd: uninitialized urandom read (4 bytes read)
[   34.915756] random: ubusd: uninitialized urandom read (4 bytes read)
[   34.922515] random: ubusd: uninitialized urandom read (4 bytes read)
[   34.929772] procd: - init -
[   35.294249] kmodloader: loading kernel modules from /etc/modules.d/*
[   35.304919] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   35.641767] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180
[   35.649783] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d
[   35.658312] ip_tables: (C) 2000-2006 Netfilter Core Team
[   36.010163] nf_conntrack version 0.5.0 (4096 buckets, 16384 max)
[   36.222225] xt_time: kernel timezone is -0000
[   36.469843] bus=0x1, slot = 0x0, irq=0xff
[   36.474058] mt7603e 0000:01:00.0: ASIC revision: 76030010
[   36.482777] mt7603e 0000:01:00.0: Firmware Version: ap_pcie
[   36.488374] mt7603e 0000:01:00.0: Build Time: 20160107100755
[   36.514986] firmware init done
[   36.701302] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   36.708452] bus=0x2, slot = 0x1, irq=0xff
[   36.712701] mt76x2e 0000:02:00.0: ASIC revision: 76120044
[   36.745757] mt76x2e 0000:02:00.0: ROM patch build: 20141115060606a
[   36.856607] mt76x2e 0000:02:00.0: Firmware Version: 0.0.00
[   36.862095] mt76x2e 0000:02:00.0: Build: 1
[   36.866202] mt76x2e 0000:02:00.0: Build Time: 201507311614____
[   36.895066] mt76x2e 0000:02:00.0: Firmware running!
[   36.901443] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[   36.909160] PPP generic driver version 2.4.2
[   36.914863] NET: Registered protocol family 24
[   36.921990] kmodloader: done loading kernel modules from /etc/modules.d/*
[   37.506856] urandom_read: 5 callbacks suppressed
[   37.506868] random: jshn: uninitialized urandom read (4 bytes read)
[   43.328603] mtk_soc_eth 1e100000.ethernet: PPE started
[   43.339035] device eth0 entered promiscuous mode
[   43.339679] random: crng init done
[   43.349998] br-lan: port 1(eth0.1) entered blocking state
[   43.355455] br-lan: port 1(eth0.1) entered disabled state
[   43.361540] device eth0.1 entered promiscuous mode
[   43.369853] br-lan: port 1(eth0.1) entered blocking state
[   43.375326] br-lan: port 1(eth0.1) entered forwarding state
[   43.381418] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[   44.095081] pppoe-wan: renamed from ppp0
[   44.395639] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[  161.234025] xhci-mtk 1e1c0000.xhci: Cannot set link state.
[  161.239536] usb usb2-port1: cannot disable (err = -32)
[  161.244695] usb 2-1: USB disconnect, device number 2
[  161.249997] xhci-mtk 1e1c0000.xhci: Mismatch between completed Set TR Deq Ptr command & xHCI internal state.
[  161.259783] xhci-mtk 1e1c0000.xhci: ep deq seg = 8ea5e680, deq ptr = af7fc010
[  173.723928] usb 2-1: new SuperSpeed USB device number 3 using xhci-mtk
[  173.758634] scsi host0: uas
[  173.762521] xhci-mtk 1e1c0000.xhci: ERROR Transfer event for unknown stream ring slot 1 ep 4
[  173.770987] xhci-mtk 1e1c0000.xhci: @000000000f78b400 0e9ae200 00000000 05000000 01058001
[  173.779174] xhci-mtk 1e1c0000.xhci: ERROR Transfer event for unknown stream ring slot 1 ep 6
[  173.787585] xhci-mtk 1e1c0000.xhci: @000000000f78b410 0e9ae100 00000000 05000000 01078001
[  195.773489] scsi 0:0:0:0: tag#0 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD IN
[  195.781053] scsi 0:0:0:0: tag#0 CDB: opcode=0x12 12 00 00 00 24 00
[  195.787337] xhci-mtk 1e1c0000.xhci: Mismatch between completed Set TR Deq Ptr command & xHCI internal state.
[  195.797126] xhci-mtk 1e1c0000.xhci: ep deq seg = 8f7fde80, deq ptr = ae9ca010
[  196.863444] scsi host0: uas_eh_device_reset_handler FAILED to get lock err -16
[  196.870659] scsi 0:0:0:0: Device offlined - not ready after error recovery
[ 7816.444318] mtk_soc_eth 1e100000.ethernet eth0: port 2 link down
[13923.944394] mtk_soc_eth 1e100000.ethernet eth0: port 2 link up
[13931.685420] mtk_soc_eth 1e100000.ethernet eth0: port 2 link down
[13934.647389] mtk_soc_eth 1e100000.ethernet eth0: port 2 link up
[13935.481016] mtk_soc_eth 1e100000.ethernet eth0: port 2 link down
[13938.603966] mtk_soc_eth 1e100000.ethernet eth0: port 2 link up
[27277.718233] xhci-mtk 1e1c0000.xhci: Cannot set link state.
[27277.723745] usb usb2-port1: cannot disable (err = -32)
[27277.728908] usb 2-1: USB disconnect, device number 3
[27277.734217] xhci-mtk 1e1c0000.xhci: Mismatch between completed Set TR Deq Ptr command & xHCI internal state.
[27277.744004] xhci-mtk 1e1c0000.xhci: ep deq seg = 8f7f8c80, deq ptr = ae92e010
[27289.778016] usb 2-1: new SuperSpeed USB device number 4 using xhci-mtk
[27289.812666] scsi host0: uas
[27289.816561] xhci-mtk 1e1c0000.xhci: ERROR Transfer event for unknown stream ring slot 1 ep 4
[27289.825022] xhci-mtk 1e1c0000.xhci: @000000000f78b670 0e9ae000 00000000 05000000 01058001
[27289.833207] xhci-mtk 1e1c0000.xhci: ERROR Transfer event for unknown stream ring slot 1 ep 6
[27289.841619] xhci-mtk 1e1c0000.xhci: @000000000f78b680 0e9ae100 00000000 05000000 01078001
[27311.067752] scsi 0:0:0:0: tag#0 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD IN
[27311.075319] scsi 0:0:0:0: tag#0 CDB: opcode=0x12 12 00 00 00 24 00
[27311.081591] xhci-mtk 1e1c0000.xhci: Mismatch between completed Set TR Deq Ptr command & xHCI internal state.
[27311.091378] xhci-mtk 1e1c0000.xhci: ep deq seg = 8f7a4680, deq ptr = ae9ca010
[27312.177719] scsi host0: uas_eh_device_reset_handler FAILED to get lock err -16
[27312.184941] scsi 0:0:0:0: Device offlined - not ready after error recovery
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 003: ID 152d:0567 JMicron Technology Corp. / JMicron USA Technology Corp. JMS567 SATA 6Gb/s bridge
Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
emvova commented on 14.10.2018 15:36

Hi guys, working usb2.0 (microusb cable) on OpenWrt 18.06.1 r7258-5eb055306f with:
opkg list-installed | grep usb
kmod-usb-core - 4.14.63-1
kmod-usb-ledtrig-usbport - 4.14.63-1
kmod-usb-storage - 4.14.63-1
kmod-usb-storage-uas - 4.14.63-1
kmod-usb-uhci - 4.14.63-1
kmod-usb3 - 4.14.63-1
libusb-1.0 - 1.0.22-1
usbutils - 007-7
LOG:

Sun Oct 14 18:22:57 2018 user.info kernel: [102926.344375] kmodloader: loading kernel modules from /etc/modules.d/*
Sun Oct 14 18:22:57 2018 kern.info kernel: [102926.357993] scsi host0: uas
Sun Oct 14 18:22:57 2018 kern.notice kernel: [102926.362189] scsi 0:0:0:0: Direct-Access     TO Exter nal USB 3.0      0204 PQ: 0 ANSI: 6
Sun Oct 14 18:22:57 2018 kern.info kernel: [102926.373655] usbcore: registered new interface driver uas
Sun Oct 14 18:22:57 2018 kern.notice kernel: [102926.379393] sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB)
Sun Oct 14 18:22:57 2018 kern.notice kernel: [102926.387042] sd 0:0:0:0: [sda] 4096-byte physical blocks
Sun Oct 14 18:22:57 2018 kern.notice kernel: [102926.392816] sd 0:0:0:0: [sda] Write Protect is off
Sun Oct 14 18:22:57 2018 kern.debug kernel: [102926.397807] sd 0:0:0:0: [sda] Mode Sense: 5f 00 00 08
Sun Oct 14 18:22:57 2018 kern.notice kernel: [102926.398498] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Sun Oct 14 18:23:01 2018 kern.err kernel: [102930.032306] usb 1-1: stat urb: status -71
Sun Oct 14 18:23:01 2018 kern.info kernel: [102930.036679] usb 1-1: USB disconnect, device number 2
Sun Oct 14 18:23:01 2018 kern.info kernel: [102930.042059] sd 0:0:0:0: [sda] tag#0 uas_zap_pending 0 uas-tag 1 inflight: CMD
Sun Oct 14 18:23:01 2018 kern.info kernel: [102930.049348] sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00
Sun Oct 14 18:23:01 2018 kern.info kernel: [102930.057610] sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=0x00
Sun Oct 14 18:23:01 2018 kern.info kernel: [102930.065886] sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00
Sun Oct 14 18:23:01 2018 kern.err kernel: [102930.073568] print_req_error: I/O error, dev sda, sector 0
Sun Oct 14 18:23:01 2018 kern.err kernel: [102930.079080] Buffer I/O error on dev sda, logical block 0, async page read
Sun Oct 14 18:23:01 2018 kern.info kernel: [102930.086118]  sda: unable to read partition table
Sun Oct 14 18:23:02 2018 kern.info kernel: [102930.886396] sd 0:0:0:0: [sda] Read Capacity(16) failed: Result: hostbyte=0x07 driverbyte=0x00
Sun Oct 14 18:23:02 2018 kern.notice kernel: [102930.894989] sd 0:0:0:0: [sda] Sense not available.
Sun Oct 14 18:23:02 2018 kern.info kernel: [102931.496361] sd 0:0:0:0: [sda] Read Capacity(10) failed: Result: hostbyte=0x07 driverbyte=0x00
Sun Oct 14 18:23:02 2018 kern.notice kernel: [102931.504951] sd 0:0:0:0: [sda] Sense not available.
Sun Oct 14 18:23:02 2018 kern.notice kernel: [102931.746383] sd 0:0:0:0: [sda] 0 512-byte logical blocks: (0 B/0 B)
Sun Oct 14 18:23:02 2018 kern.notice kernel: [102931.752633] sd 0:0:0:0: [sda] 4096-byte physical blocks
Sun Oct 14 18:23:03 2018 kern.notice kernel: [102932.276336] sd 0:0:0:0: [sda] Attached SCSI disk
Sun Oct 14 18:23:03 2018 user.info kernel: [102932.281752] kmodloader: done loading kernel modules from /etc/modules.d/*
Sun Oct 14 18:23:05 2018 kern.info kernel: [102934.316270] usb 1-1: new high-speed USB device number 3 using xhci-mtk
Sun Oct 14 18:23:05 2018 kern.info kernel: [102934.499911] scsi host0: uas
Sun Oct 14 18:23:05 2018 kern.notice kernel: [102934.504725] scsi 0:0:0:0: Direct-Access     TO Exter nal USB 3.0      0204 PQ: 0 ANSI: 6
Sun Oct 14 18:23:05 2018 kern.notice kernel: [102934.516983] sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB)
Sun Oct 14 18:23:05 2018 kern.notice kernel: [102934.524742] sd 0:0:0:0: [sda] 4096-byte physical blocks
Sun Oct 14 18:23:05 2018 kern.notice kernel: [102934.530734] sd 0:0:0:0: [sda] Write Protect is off
Sun Oct 14 18:23:05 2018 kern.debug kernel: [102934.535645] sd 0:0:0:0: [sda] Mode Sense: 5f 00 00 08
Sun Oct 14 18:23:05 2018 kern.notice kernel: [102934.536442] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Sun Oct 14 18:23:05 2018 kern.info kernel: [102934.614135]  sda: sda1
Sun Oct 14 18:23:05 2018 kern.notice kernel: [102934.619614] sd 0:0:0:0: [sda] Attached SCSI disk
rags22489664 commented on 25.12.2018 05:45

Same error on Mi router 3G on r8874-8f48414

Loading...

Available keyboard shortcuts

Tasklist

Task Details

Task Editing