Is there a problem with the Dockstar and USB 3.0?
March 27, 2018 07:28PM
Seagate Barracuda ST4000DM004 (4 TB), ASMedia bridge, USB 3.0, directly connected to the Dockstar, no hubs, power provided by the brick included with the enclosure/drive.

I launched a long cp operation from the system drive (ext4, 1.1 TiB) to an NTFS partition on the Seagate, mounted with ntfs-3g, which completed without error. After a break, I launched another cp for roughly 650 GiB, which encountered repeated failures, with I/O errors reported by cp.

Windows chkdsk predictably found and corrected errors. SMART is OK, the unit is not reporting any problem, and passed an Extended Offline Test which took over 8 hours. There is no indication of over-heating in the temperature log, or otherwise high temperature when the errors occurred.

That leaves me wondering whether the problem lies with the software, the USB bridge itself, or the Dockstar not liking USB 3.0. The suspicion comes from the Dockstar refusing to boot from its system drive while plugged in a JMS56x two-bay USB3.0 enclosure:

U-Boot 2017.07-tld-1 (Oct 24 2017 - 22:32:36 -0700)
Seagate FreeAgent DockStar
gcc (Debian 6.3.0-18) 6.3.0 20170516
GNU ld (GNU Binutils for Debian) 2.28
Hit any key to stop autoboot:  0
starting USB...
USB0:   USB EHCI 1.00
scanning bus 0 for devices... 2 USB Device(s) found
       scanning usb for storage devices... 0 Storage Device(s) found
Unknown command 'ide' - try 'help'
Unknown command 'mmc' - try 'help'
## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0
loading envs from usb 0 ...
** Bad device usb 0 **
Unknown command 'ide' - try 'help'
Unknown command 'mmc' - try 'help'
running scan_disk ...
Scan device usb
device usb 0:1
** Bad device usb 0 **
device usb 1:1
** Bad device usb 1 **
device usb 2:1
** Bad device usb 2 **
device usb 3:1
** Bad device usb 3 **
Scan device ide
Unknown command 'ide' - try 'help'
device ide 0:1
** Bad device ide 0 **
device ide 1:1
** Bad device ide 1 **
device ide 2:1
** Bad device ide 2 **
device ide 3:1
** Bad device ide 3 **
Scan device mmc
Unknown command 'mmc' - try 'help'
device mmc 0:1
** Bad device mmc 0 **
device mmc 1:1
** Bad device mmc 1 **
device mmc 2:1
** Bad device mmc 2 **
device mmc 3:1
** Bad device mmc 3 **
loading uImage ...
** Bad device usb 0 **
loading uInitrd ...
** Bad device usb 0 **
loading DTB ...
** Bad device usb 0 **
Wrong Image Format for bootm command
ERROR: can't get kernel image!
DockStar>

/var/log/messages, the disconnect happens at 5:28, which is the timestamp on the stdout/stderr files from the cp operation that failed:

Jan  1 00:00:44 <hostname> kernel: [    0.000000] Booting Linux on physical CPU 0x0
Jan  1 00:00:44 <hostname> kernel: [    0.000000] Linux version 4.9.0-6-marvell (debian-kernel@lists.debian.org) (gcc version 6.3.0 20170516 (Debian 6.3.0-18) ) #1 Debian 4.9.82-1+deb9u3 (2018-03-02)
Jan  1 00:00:44 <hostname> kernel: [    0.000000] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=0005397f
Jan  1 00:00:44 <hostname> kernel: [    0.000000] CPU: VIVT data cache, VIVT instruction cache
Jan  1 00:00:44 <hostname> kernel: [    0.000000] OF: fdt:Machine model: Seagate FreeAgent Dockstar
Jan  1 00:00:44 <hostname> kernel: [    0.000000] Memory policy: Data cache writeback
Jan  1 00:00:44 <hostname> kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
Jan  1 00:00:44 <hostname> kernel: [    0.000000] Kernel command line: console=ttyS0,115200 root=LABEL=rootfs rootdelay=10 mtdparts=orion_nand:1M(u-boot),4M(uImage),32M(rootfs),-(data)
Jan  1 00:00:44 <hostname> kernel: [    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
Jan  1 00:00:44 <hostname> kernel: [    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
Jan  1 00:00:44 <hostname> kernel: [    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
Jan  1 00:00:44 <hostname> kernel: [    0.000000] Memory: 111552K/131072K available (3782K kernel code, 396K rwdata, 1128K rodata, 292K init, 249K bss, 19520K reserved, 0K cma-reserved, 0K highmem)
Jan  1 00:00:44 <hostname> kernel: [    0.000000] Virtual kernel memory layout:
Jan  1 00:00:44 <hostname> kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Jan  1 00:00:44 <hostname> kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
Jan  1 00:00:44 <hostname> kernel: [    0.000000]     vmalloc : 0xc8800000 - 0xff800000   ( 880 MB)
Jan  1 00:00:44 <hostname> kernel: [    0.000000]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)
Jan  1 00:00:44 <hostname> kernel: [    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
Jan  1 00:00:44 <hostname> kernel: [    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
Jan  1 00:00:44 <hostname> kernel: [    0.000000]       .text : 0xc0008000 - 0xc03b9b18   (3783 kB)
Jan  1 00:00:44 <hostname> kernel: [    0.000000]       .init : 0xc04f5000 - 0xc053e000   ( 292 kB)
Jan  1 00:00:44 <hostname> kernel: [    0.000000]       .data : 0xc053e000 - 0xc05a1278   ( 397 kB)
Jan  1 00:00:44 <hostname> kernel: [    0.000000]        .bss : 0xc05a1278 - 0xc05df85c   ( 250 kB)
Jan  1 00:00:44 <hostname> kernel: [    0.000000] NR_IRQS:16 nr_irqs:16 16
Jan  1 00:00:44 <hostname> kernel: [    0.000000] clocksource: orion_clocksource: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 9556302233 ns
Jan  1 00:00:44 <hostname> kernel: [    0.000008] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps every 10737418237ns
Jan  1 00:00:44 <hostname> kernel: [    0.000498] Console: colour dummy device 80x30
Jan  1 00:00:44 <hostname> kernel: [    0.000529] Calibrating delay loop... 1185.79 BogoMIPS (lpj=2371584)
Jan  1 00:00:44 <hostname> kernel: [    0.024032] pid_max: default: 32768 minimum: 301
Jan  1 00:00:44 <hostname> kernel: [    0.024225] Security Framework initialized
Jan  1 00:00:44 <hostname> kernel: [    0.024243] Yama: disabled by default; enable with sysctl kernel.yama.*
Jan  1 00:00:44 <hostname> kernel: [    0.024401] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Jan  1 00:00:44 <hostname> kernel: [    0.024420] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
Jan  1 00:00:44 <hostname> kernel: [    0.025441] CPU: Testing write buffer coherency: ok
Jan  1 00:00:44 <hostname> kernel: [    0.025539] ftrace: allocating 16973 entries in 34 pages
Jan  1 00:00:44 <hostname> kernel: [    0.059868] Setting up static identity map for 0x8200 - 0x823c
Jan  1 00:00:44 <hostname> kernel: [    0.060151] mvebu-soc-id: MVEBU SoC ID=0x6281, Rev=0x2
Jan  1 00:00:44 <hostname> kernel: [    0.063294] devtmpfs: initialized
Jan  1 00:00:44 <hostname> kernel: [    0.067517] VFP support v0.3: not present
Jan  1 00:00:44 <hostname> kernel: [    0.067709] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
Jan  1 00:00:44 <hostname> kernel: [    0.067735] futex hash table entries: 256 (order: -1, 3072 bytes)
Jan  1 00:00:44 <hostname> kernel: [    0.067984] pinctrl core: initialized pinctrl subsystem
Jan  1 00:00:44 <hostname> kernel: [    0.069138] NET: Registered protocol family 16
Jan  1 00:00:44 <hostname> kernel: [    0.069536] DMA: preallocated 256 KiB pool for atomic coherent allocations
Jan  1 00:00:44 <hostname> kernel: [    0.070954] cpuidle: using governor ladder
Jan  1 00:00:44 <hostname> kernel: [    0.070973] cpuidle: using governor menu
Jan  1 00:00:44 <hostname> kernel: [    0.071307] Feroceon L2: Enabling L2
Jan  1 00:00:44 <hostname> kernel: [    0.071351] Feroceon L2: Cache support initialised.
Jan  1 00:00:44 <hostname> kernel: [    0.075368] No ATAGs?
Jan  1 00:00:44 <hostname> kernel: [    0.080507] clocksource: Switched to clocksource orion_clocksource
Jan  1 00:00:44 <hostname> kernel: [    0.093881] VFS: Disk quotas dquot_6.6.0
Jan  1 00:00:44 <hostname> kernel: [    0.093966] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Jan  1 00:00:44 <hostname> kernel: [    0.102008] NET: Registered protocol family 2
Jan  1 00:00:44 <hostname> kernel: [    0.102841] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
Jan  1 00:00:44 <hostname> kernel: [    0.102867] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
Jan  1 00:00:44 <hostname> kernel: [    0.102887] TCP: Hash tables configured (established 1024 bind 1024)
Jan  1 00:00:44 <hostname> kernel: [    0.102948] UDP hash table entries: 256 (order: 0, 4096 bytes)
Jan  1 00:00:44 <hostname> kernel: [    0.102971] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Jan  1 00:00:44 <hostname> kernel: [    0.103162] NET: Registered protocol family 1
Jan  1 00:00:44 <hostname> kernel: [    0.103447] Unpacking initramfs...
Jan  1 00:00:44 <hostname> kernel: [    1.032532] random: fast init done
Jan  1 00:00:44 <hostname> kernel: [    1.197859] Freeing initrd memory: 12284K
Jan  1 00:00:44 <hostname> kernel: [    1.198603] audit: initializing netlink subsys (disabled)
Jan  1 00:00:44 <hostname> kernel: [    1.198683] audit: type=2000 audit(1.184:1): initialized
Jan  1 00:00:44 <hostname> kernel: [    1.199090] workingset: timestamp_bits=30 max_order=15 bucket_order=0
Jan  1 00:00:44 <hostname> kernel: [    1.199185] zbud: loaded
Jan  1 00:00:44 <hostname> kernel: [    1.200869] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Jan  1 00:00:44 <hostname> kernel: [    1.201019] io scheduler noop registered
Jan  1 00:00:44 <hostname> kernel: [    1.201099] io scheduler cfq registered (default)
Jan  1 00:00:44 <hostname> kernel: [    1.202265] kirkwood-pinctrl f1010000.pin-controller: registered pinctrl driver
Jan  1 00:00:44 <hostname> kernel: [    1.203998] mv_xor f1060800.xor: Marvell shared XOR driver
Jan  1 00:00:44 <hostname> kernel: [    1.233752] mv_xor f1060800.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
Jan  1 00:00:44 <hostname> kernel: [    1.233943] mv_xor f1060900.xor: Marvell shared XOR driver
Jan  1 00:00:44 <hostname> kernel: [    1.261745] mv_xor f1060900.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
Jan  1 00:00:44 <hostname> kernel: [    1.262132] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
Jan  1 00:00:44 <hostname> kernel: [    1.263143] console [ttyS0] disabled
Jan  1 00:00:44 <hostname> kernel: [    1.263203] f1012000.serial: ttyS0 at MMIO 0xf1012000 (irq = 25, base_baud = 12500000) is a 16550A
Jan  1 00:00:44 <hostname> kernel: [    1.730356] console [ttyS0] enabled
Jan  1 00:00:44 <hostname> kernel: [    1.735034] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xda
Jan  1 00:00:44 <hostname> kernel: [    1.741448] nand: Micron MT29F2G08AAD
Jan  1 00:00:44 <hostname> kernel: [    1.745141] nand: 256 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
Jan  1 00:00:44 <hostname> kernel: [    1.752777] Scanning device for bad blocks
Jan  1 00:00:44 <hostname> kernel: [    1.912513] 4 cmdlinepart partitions found on MTD device orion_nand
Jan  1 00:00:44 <hostname> kernel: [    1.918823] Creating 4 MTD partitions on "orion_nand":
Jan  1 00:00:44 <hostname> kernel: [    1.923999] 0x000000000000-0x000000100000 : "u-boot"
Jan  1 00:00:44 <hostname> kernel: [    1.930161] 0x000000100000-0x000000500000 : "uImage"
Jan  1 00:00:44 <hostname> kernel: [    1.936272] 0x000000500000-0x000002500000 : "rootfs"
Jan  1 00:00:44 <hostname> kernel: [    1.942488] 0x000002500000-0x000010000000 : "data"
Jan  1 00:00:44 <hostname> kernel: [    1.949847] mousedev: PS/2 mouse device common for all mice
Jan  1 00:00:44 <hostname> kernel: [    2.994226] i2c /dev entries driver
Jan  1 00:00:44 <hostname> kernel: [    2.998634] ledtrig-cpu: registered to indicate activity on CPUs
Jan  1 00:00:44 <hostname> kernel: [    3.005074] NET: Registered protocol family 17
Jan  1 00:00:44 <hostname> kernel: [    3.010101] registered taskstats version 1
Jan  1 00:00:44 <hostname> kernel: [    3.014282] zswap: loaded using pool lzo/zbud
Jan  1 00:00:44 <hostname> kernel: [    3.019921] hctosys: unable to open rtc device (rtc0)
Jan  1 00:00:44 <hostname> kernel: [    3.026197] Freeing unused kernel memory: 292K
Jan  1 00:00:44 <hostname> kernel: [    3.030688] This architecture does not have kernel memory protection.
Jan  1 00:00:44 <hostname> kernel: [    3.375972] libphy: Fixed MDIO Bus: probed
Jan  1 00:00:44 <hostname> kernel: [    3.391769] libphy: orion_mdio_bus: probed
Jan  1 00:00:44 <hostname> kernel: [    3.395940] [Firmware Warn]: /ocp@f1000000/mdio-bus@72004/ethernet-phy@0: Whitelisted compatible string. Please remove
Jan  1 00:00:44 <hostname> kernel: [    3.413463] usbcore: registered new interface driver usbfs
Jan  1 00:00:44 <hostname> kernel: [    3.433399] usbcore: registered new interface driver hub
Jan  1 00:00:44 <hostname> kernel: [    3.456907] usbcore: registered new device driver usb
Jan  1 00:00:44 <hostname> kernel: [    3.482938] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Jan  1 00:00:44 <hostname> kernel: [    3.503298] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
Jan  1 00:00:44 <hostname> kernel: [    3.527311] ehci-orion: EHCI orion driver
Jan  1 00:00:44 <hostname> kernel: [    3.547365] orion-ehci f1050000.ehci: EHCI Host Controller
Jan  1 00:00:44 <hostname> kernel: [    3.568572] orion-ehci f1050000.ehci: new USB bus registered, assigned bus number 1
Jan  1 00:00:44 <hostname> kernel: [    4.095438] orion-ehci f1050000.ehci: irq 29, io mem 0xf1050000
Jan  1 00:00:44 <hostname> liblogging-stdlog:  [origin software="rsyslogd" swVersion="8.24.0" x-pid="1263" x-info="http://www.rsyslog.com";] start
Jan  1 00:00:44 <hostname> kernel: [    4.132567] orion-ehci f1050000.ehci: USB 2.0 started, EHCI 1.00
Jan  1 00:00:44 <hostname> kernel: [    4.149126] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Jan  1 00:00:44 <hostname> kernel: [    4.155986] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jan  1 00:00:44 <hostname> kernel: [    4.163261] usb usb1: Product: EHCI Host Controller
Jan  1 00:00:44 <hostname> kernel: [    4.168177] usb usb1: Manufacturer: Linux 4.9.0-6-marvell ehci_hcd
Jan  1 00:00:44 <hostname> kernel: [    4.174402] usb usb1: SerialNumber: f1050000.ehci
Jan  1 00:00:44 <hostname> kernel: [    4.234195] hub 1-0:1.0: USB hub found
Jan  1 00:00:44 <hostname> kernel: [    4.238034] hub 1-0:1.0: 1 port detected
Jan  1 00:00:44 <hostname> kernel: [    4.752025] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address <macaddr>
Jan  1 00:00:44 <hostname> kernel: [    4.798323] netpoll: netconsole: local port 6666
Jan  1 00:00:44 <hostname> kernel: [    4.803009] netpoll: netconsole: local IPv4 address <IP>
Jan  1 00:00:44 <hostname> kernel: [    4.809146] netpoll: netconsole: interface 'eth0'
Jan  1 00:00:44 <hostname> kernel: [    4.813880] netpoll: netconsole: remote port 6666
Jan  1 00:00:44 <hostname> kernel: [    4.818615] netpoll: netconsole: remote IPv4 address <IP>
Jan  1 00:00:44 <hostname> kernel: [    4.824834] netpoll: netconsole: remote ethernet address ff:ff:ff:ff:ff:ff
Jan  1 00:00:44 <hostname> kernel: [    4.837896] netpoll: netconsole: device eth0 not up yet, forcing it
Jan  1 00:00:44 <hostname> kernel: [    5.414474] usb 1-1: new high-speed USB device number 2 using orion-ehci
Jan  1 00:00:44 <hostname> kernel: [    6.006311] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608
Jan  1 00:00:44 <hostname> kernel: [    6.013073] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Jan  1 00:00:44 <hostname> kernel: [    6.020265] usb 1-1: Product: USB2.0 Hub
Jan  1 00:00:44 <hostname> kernel: [    6.027906] hub 1-1:1.0: USB hub found
Jan  1 00:00:44 <hostname> kernel: [    6.034327] hub 1-1:1.0: 4 ports detected
Jan  1 00:00:44 <hostname> kernel: [    6.324530] usb 1-1.4: new high-speed USB device number 3 using orion-ehci
Jan  1 00:00:44 <hostname> kernel: [    6.437806] usb 1-1.4: New USB device found, idVendor=1058, idProduct=1021
Jan  1 00:00:44 <hostname> kernel: [    6.444737] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan  1 00:00:44 <hostname> kernel: [    6.452107] usb 1-1.4: Product: Ext HDD 1021
Jan  1 00:00:44 <hostname> kernel: [    6.456410] usb 1-1.4: Manufacturer: Western Digital
Jan  1 00:00:44 <hostname> kernel: [    6.461414] usb 1-1.4: SerialNumber: <serial>
Jan  1 00:00:44 <hostname> kernel: [    6.488188] SCSI subsystem initialized
Jan  1 00:00:44 <hostname> kernel: [    6.509622] usb-storage 1-1.4:1.0: USB Mass Storage device detected
Jan  1 00:00:44 <hostname> kernel: [    6.516316] scsi host0: usb-storage 1-1.4:1.0
Jan  1 00:00:44 <hostname> kernel: [    6.523191] usbcore: registered new interface driver usb-storage
Jan  1 00:00:44 <hostname> kernel: [    6.533500] usbcore: registered new interface driver uas
Jan  1 00:00:44 <hostname> kernel: [    7.535282] scsi 0:0:0:0: Direct-Access     WD       Ext HDD 1021     2021 PQ: 0 ANSI: 4
Jan  1 00:00:44 <hostname> kernel: [    7.557617] sd 0:0:0:0: [sda] 3907024896 512-byte logical blocks: (2.00 TB/1.82 TiB)
Jan  1 00:00:44 <hostname> kernel: [    7.575607] sd 0:0:0:0: [sda] Write Protect is off
Jan  1 00:00:44 <hostname> kernel: [    8.255619] mv643xx_eth_port mv643xx_eth_port.0 eth0: link up, 1000 Mb/s, full duplex, flow control disabled
Jan  1 00:00:44 <hostname> kernel: [    8.267540] console [netcon0] enabled
Jan  1 00:00:44 <hostname> kernel: [    8.271261] netconsole: network logging started
Jan  1 00:00:44 <hostname> kernel: [   21.601465]  sda: sda1 sda2 sda3
Jan  1 00:00:44 <hostname> kernel: [   21.613221] sd 0:0:0:0: [sda] Attached SCSI disk
Jan  1 00:00:44 <hostname> kernel: [   27.222773] EXT4-fs (sda1): mounting ext3 file system using the ext4 subsystem
Jan  1 00:00:44 <hostname> kernel: [   27.231524] EXT4-fs (sda1): INFO: recovery required on readonly filesystem
Jan  1 00:00:44 <hostname> kernel: [   27.238487] EXT4-fs (sda1): write access will be enabled during recovery
Jan  1 00:00:44 <hostname> kernel: [   27.287453] EXT4-fs (sda1): recovery complete
Jan  1 00:00:44 <hostname> kernel: [   27.293230] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
Jan  1 00:00:44 <hostname> kernel: [   29.297011] random: crng init done
Jan  1 00:00:44 <hostname> kernel: [   30.619541] orion_wdt: Initial timeout 21 sec
Jan  1 00:00:44 <hostname> kernel: [   30.846024] marvell-cesa f1030000.crypto: CESA device successfully registered
Jan  1 00:00:44 <hostname> kernel: [   30.905455] sd 0:0:0:0: Attached scsi generic sg0 type 0
Jan  1 00:00:44 <hostname> kernel: [   32.212130] Adding 2097148k swap on /dev/sda2.  Priority:-1 extents:1 across:2097148k FS
Jan  1 00:00:44 <hostname> kernel: [   32.371520] EXT4-fs (sda1): re-mounted. Opts: (null)
Jan  1 00:00:44 <hostname> kernel: [   32.735911] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro
Jan  1 00:00:44 <hostname> kernel: [   34.833918] EXT4-fs (sda3): mounted filesystem with ordered data mode. Opts: (null)
Jan  1 00:00:44 <hostname> kernel: [   39.793814] mv643xx_eth_port mv643xx_eth_port.0 eth0: link up, 1000 Mb/s, full duplex, flow control disabled
Jan  1 00:00:44 <hostname> kernel: [   43.591437] NET: Registered protocol family 10
Mar 25 19:52:08 <hostname> kernel: [   53.769689] io scheduler deadline registered
Mar 25 20:12:00 <hostname> kernel: [ 1246.184540] usb 1-1.3: new high-speed USB device number 4 using orion-ehci
Mar 25 20:12:00 <hostname> kernel: [ 1246.338564] usb 1-1.3: New USB device found, idVendor=18a5, idProduct=0415
Mar 25 20:12:00 <hostname> kernel: [ 1246.345544] usb 1-1.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Mar 25 20:12:00 <hostname> kernel: [ 1246.352937] usb 1-1.3: Product: Store N Save Desktop HDD
Mar 25 20:12:00 <hostname> kernel: [ 1246.358294] usb 1-1.3: Manufacturer:  
Mar 25 20:12:00 <hostname> kernel: [ 1246.362097] usb 1-1.3: SerialNumber: <serial>
Mar 25 20:12:00 <hostname> kernel: [ 1246.368944] usb-storage 1-1.3:1.0: USB Mass Storage device detected
Mar 25 20:12:00 <hostname> kernel: [ 1246.375626] scsi host1: usb-storage 1-1.3:1.0
Mar 25 20:12:01 <hostname> kernel: [ 1247.405654] scsi 1:0:0:0: Direct-Access     Verbatim Desktop HDD 3.0  0    PQ: 0 ANSI: 6
Mar 25 20:12:01 <hostname> kernel: [ 1247.418305] sd 1:0:0:0: Attached scsi generic sg1 type 0
Mar 25 20:12:01 <hostname> kernel: [ 1247.428106] sd 1:0:0:0: [sdb] Spinning up disk...
Mar 25 20:12:03 <hostname> kernel: [ 1248.460531] .
Mar 25 20:12:03 <hostname> kernel: [ 1249.484528] .
Mar 25 20:12:05 <hostname> kernel: [ 1250.508529] .
Mar 25 20:12:05 <hostname> kernel: [ 1251.532536] .
Mar 25 20:12:06 <hostname> kernel: [ 1252.556532] .
Mar 25 20:12:06 <hostname> kernel: [ 1252.557085] ready
Mar 25 20:12:07 <hostname> kernel: [ 1252.561731] sd 1:0:0:0: [sdb] Very big device. Trying to use READ CAPACITY(16).
Mar 25 20:12:07 <hostname> kernel: [ 1252.569849] sd 1:0:0:0: [sdb] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
Mar 25 20:12:07 <hostname> kernel: [ 1252.577695] sd 1:0:0:0: [sdb] 4096-byte physical blocks
Mar 25 20:12:07 <hostname> kernel: [ 1252.584219] sd 1:0:0:0: [sdb] Write Protect is off
Mar 25 20:12:07 <hostname> kernel: [ 1252.589837] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 25 20:12:07 <hostname> kernel: [ 1252.602883] sd 1:0:0:0: [sdb] Very big device. Trying to use READ CAPACITY(16).
Mar 25 20:12:07 <hostname> kernel: [ 1252.698368]  sdb: sdb1
Mar 25 20:12:07 <hostname> kernel: [ 1252.705975] sd 1:0:0:0: [sdb] Very big device. Trying to use READ CAPACITY(16).
Mar 25 20:12:07 <hostname> kernel: [ 1252.722361] sd 1:0:0:0: [sdb] Attached SCSI disk
Mar 25 20:12:09 <hostname> kernel: [ 1255.279666] fuse init (API version 7.26)
Mar 26 13:19:24 <hostname> kernel: [62889.600598] man: page allocation stalls for 10096ms, order:2, mode:0x24000c0(GFP_KERNEL)
Mar 26 13:19:24 <hostname> kernel: [62889.608878] CPU: 0 PID: 2736 Comm: man Not tainted 4.9.0-6-marvell #1 Debian 4.9.82-1+deb9u3
Mar 26 13:19:24 <hostname> kernel: [62889.617383] Hardware name: Marvell Kirkwood (Flattened Device Tree)
Mar 26 13:19:24 <hostname> kernel: [62889.623730] [<c0015fe8>] (unwind_backtrace) from [<c00130a0>] (show_stack+0x18/0x1c)
Mar 26 13:19:24 <hostname> kernel: [62889.631551] [<c00130a0>] (show_stack) from [<c00db878>] (warn_alloc+0xe4/0x118)
Mar 26 13:19:24 <hostname> kernel: [62889.638942] [<c00db878>] (warn_alloc) from [<c00dbdfc>] (__alloc_pages_nodemask+0x4c8/0xa5c)
Mar 26 13:19:24 <hostname> kernel: [62889.647447] [<c00dbdfc>] (__alloc_pages_nodemask) from [<c00dc3a8>] (__get_free_pages+0x18/0x30)
Mar 26 13:19:24 <hostname> kernel: [62889.656309] [<c00dc3a8>] (__get_free_pages) from [<c001946c>] (pgd_alloc+0x1c/0x134)
Mar 26 13:19:24 <hostname> kernel: [62889.664127] [<c001946c>] (pgd_alloc) from [<c001ed78>] (mm_init.constprop.8+0xd0/0x138)
Mar 26 13:19:24 <hostname> kernel: [62889.672197] [<c001ed78>] (mm_init.constprop.8) from [<c0020378>] (copy_process.part.5+0xec0/0x14cc)
Mar 26 13:19:24 <hostname> kernel: [62889.681323] [<c0020378>] (copy_process.part.5) from [<c0020b0c>] (_do_fork+0xd8/0x3b0)
Mar 26 13:19:24 <hostname> kernel: [62889.689304] [<c0020b0c>] (_do_fork) from [<c0020ecc>] (SyS_clone+0x24/0x2c)
Mar 26 13:19:24 <hostname> kernel: [62889.696332] [<c0020ecc>] (SyS_clone) from [<c000f560>] (ret_fast_syscall+0x0/0x44)
Mar 26 13:19:24 <hostname> kernel: [62889.703968] Mem-Info:
Mar 26 13:19:24 <hostname> kernel: [62889.706279] active_anon:1638 inactive_anon:2200 isolated_anon:0
Mar 26 13:19:24 <hostname> kernel: [62889.706279]  active_file:9891 inactive_file:10017 isolated_file:0
Mar 26 13:19:24 <hostname> kernel: [62889.706279]  unevictable:0 dirty:132 writeback:3961 unstable:0
Mar 26 13:19:24 <hostname> kernel: [62889.706279]  slab_reclaimable:2054 slab_unreclaimable:1344
Mar 26 13:19:24 <hostname> kernel: [62889.706279]  mapped:2520 shmem:89 pagetables:231 bounce:0
Mar 26 13:19:24 <hostname> kernel: [62889.706279]  free:2429 free_pcp:5 free_cma:0
Mar 26 13:19:24 <hostname> kernel: [62889.739392] Node 0 active_anon:6552kB inactive_anon:8800kB active_file:39564kB inactive_file:40068kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:10080kB dirty:528kB writeback:15844kB shmem:356kB writeback_tmp:0kB unstable:0kB pages_scanned:0 all_unreclaimable? no
Mar 26 13:19:24 <hostname> kernel: [62889.764305] Normal free:9716kB min:1332kB low:1664kB high:1996kB active_anon:6552kB inactive_anon:8800kB active_file:39564kB inactive_file:40068kB unevictable:0kB writepending:16372kB present:131072kB managed:124128kB mlocked:0kB slab_reclaimable:8216kB slab_unreclaimable:5376kB kernel_stack:624kB pagetables:924kB bounce:0kB free_pcp:20kB local_pcp:20kB free_cma:0kB
Mar 26 13:19:24 <hostname> kernel: lowmem_reserve[]: 0 0 0
Mar 26 13:19:24 <hostname> kernel: [62889.800419] Normal: 1857*4kB (UME) 286*8kB (UM) 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 9716kB
Mar 26 13:19:24 <hostname> kernel: 20338 total pagecache pages
Mar 26 13:19:24 <hostname> kernel: [62889.814841] 341 pages in swap cache
Mar 26 13:19:24 <hostname> kernel: [62889.818362] Swap cache stats: add 1392, delete 1051, find 3263/3433
Mar 26 13:19:24 <hostname> kernel: [62889.824675] Free swap  = 2093708kB
Mar 26 13:19:24 <hostname> kernel: [62889.828097] Total swap = 2097148kB
Mar 26 13:19:24 <hostname> kernel: [62889.831533] 32768 pages RAM
Mar 26 13:19:24 <hostname> kernel: [62889.834351] 0 pages HighMem/MovableOnly
Mar 26 13:19:24 <hostname> kernel: [62889.838231] 1736 pages reserved
Mar 27 05:28:55 <hostname> kernel: [121060.422690] usb 1-1.3: USB disconnect, device number 4
Mar 27 05:28:55 <hostname> kernel: [121060.436581] sd 1:0:0:0: [sdb] tag#0 FAILED Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Mar 27 05:28:55 <hostname> kernel: [121060.445491] sd 1:0:0:0: [sdb] tag#0 CDB: Write(16) 8a 00 00 00 00 00 00 00 20 00 00 00 00 10 00 00
Mar 27 05:28:55 <hostname> kernel: [121060.491603] sd 1:0:0:0: [sdb] tag#0 FAILED Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Mar 27 05:28:55 <hostname> kernel: [121060.500523] sd 1:0:0:0: [sdb] tag#0 CDB: Write(16) 8a 00 00 00 00 00 00 03 83 48 00 00 00 10 00 00
Mar 27 05:28:55 <hostname> kernel: [121060.621591] sd 1:0:0:0: [sdb] Synchronizing SCSI cache
Mar 27 05:28:55 <hostname> kernel: [121060.627302] sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Mar 27 06:37:05 <hostname> liblogging-stdlog:  [origin software="rsyslogd" swVersion="8.24.0" x-pid="1263" x-info="http://www.rsyslog.com";] rsyslogd was HUPed
Mar 27 14:21:19 <hostname> kernel: [153005.544538] usb 1-1.3: new high-speed USB device number 5 using orion-ehci
Mar 27 14:21:20 <hostname> kernel: [153005.698564] usb 1-1.3: New USB device found, idVendor=18a5, idProduct=0415
Mar 27 14:21:20 <hostname> kernel: [153005.705629] usb 1-1.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Mar 27 14:21:20 <hostname> kernel: [153005.713111] usb 1-1.3: Product: Store N Save Desktop HDD
Mar 27 14:21:20 <hostname> kernel: [153005.718562] usb 1-1.3: Manufacturer:  
Mar 27 14:21:20 <hostname> kernel: [153005.722440] usb 1-1.3: SerialNumber: <serial>
Mar 27 14:21:20 <hostname> kernel: [153005.732141] usb-storage 1-1.3:1.0: USB Mass Storage device detected
Mar 27 14:21:20 <hostname> kernel: [153005.742254] scsi host2: usb-storage 1-1.3:1.0
Mar 27 14:21:21 <hostname> kernel: [153006.765654] scsi 2:0:0:0: Direct-Access     Verbatim Desktop HDD 3.0  0    PQ: 0 ANSI: 6
Mar 27 14:21:21 <hostname> kernel: [153006.778471] sd 2:0:0:0: Attached scsi generic sg1 type 0
Mar 27 14:21:21 <hostname> kernel: [153006.788612] sd 2:0:0:0: [sdc] Spinning up disk...
Mar 27 14:21:23 <hostname> kernel: [153007.820523] .
Mar 27 14:21:23 <hostname> kernel: [153008.844532] .
Mar 27 14:21:25 <hostname> kernel: [153009.868526] .
Mar 27 14:21:25 <hostname> kernel: [153010.892530] .
Mar 27 14:21:27 <hostname> kernel: [153011.916530] .
Mar 27 14:21:27 <hostname> kernel: [153012.940534] .
Mar 27 14:21:27 <hostname> kernel: [153012.942698] ready
Mar 27 14:21:27 <hostname> kernel: [153013.300609] sd 2:0:0:0: [sdc] Very big device. Trying to use READ CAPACITY(16).
Mar 27 14:21:27 <hostname> kernel: [153013.308904] sd 2:0:0:0: [sdc] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
Mar 27 14:21:27 <hostname> kernel: [153013.316841] sd 2:0:0:0: [sdc] 4096-byte physical blocks
Mar 27 14:21:27 <hostname> kernel: [153013.323345] sd 2:0:0:0: [sdc] Write Protect is off
Mar 27 14:21:27 <hostname> kernel: [153013.329606] sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 27 14:21:27 <hostname> kernel: [153013.342003] sd 2:0:0:0: [sdc] Very big device. Trying to use READ CAPACITY(16).
Mar 27 14:21:28 <hostname> kernel: [153013.775877]  sdc: sdc1
Mar 27 14:21:28 <hostname> kernel: [153013.783628] sd 2:0:0:0: [sdc] Very big device. Trying to use READ CAPACITY(16).
Mar 27 14:21:28 <hostname> kernel: [153013.800873] sd 2:0:0:0: [sdc] Attached SCSI disk
Mar 27 15:09:23 <hostname> kernel: [155889.272078] buffer_io_error: 724 callbacks suppressed
Mar 27 15:11:31 <hostname> kernel: [156016.853935] VFS: Dirty inode writeback failed for block device sdb1 (err=-5).
Mar 27 15:22:25 <hostname> kernel: [156671.241565] usb 1-1.3: USB disconnect, device number 5
Mar 27 15:22:25 <hostname> kernel: [156671.252472] sd 2:0:0:0: [sdc] Synchronizing SCSI cache
Mar 27 15:22:25 <hostname> kernel: [156671.275786] sd 2:0:0:0: [sdc] Synchronize Cache(10) failed: Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Mar 27 15:22:29 <hostname> kernel: [156674.792547] usb 1-1.3: new high-speed USB device number 6 using orion-ehci
Mar 27 15:22:29 <hostname> kernel: [156674.946687] usb 1-1.3: New USB device found, idVendor=18a5, idProduct=0415
Mar 27 15:22:29 <hostname> kernel: [156674.953754] usb 1-1.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Mar 27 15:22:29 <hostname> kernel: [156674.961230] usb 1-1.3: Product: Store N Save Desktop HDD
Mar 27 15:22:29 <hostname> kernel: [156674.966676] usb 1-1.3: Manufacturer:  
Mar 27 15:22:29 <hostname> kernel: [156674.970554] usb 1-1.3: SerialNumber: <serial>
Mar 27 15:22:29 <hostname> kernel: [156674.980281] usb-storage 1-1.3:1.0: USB Mass Storage device detected
Mar 27 15:22:29 <hostname> kernel: [156674.990779] scsi host1: usb-storage 1-1.3:1.0
Mar 27 15:22:30 <hostname> kernel: [156676.013657] scsi 1:0:0:0: Direct-Access     Verbatim Desktop HDD 3.0  0    PQ: 0 ANSI: 6
Mar 27 15:22:30 <hostname> kernel: [156676.026314] sd 1:0:0:0: Attached scsi generic sg1 type 0
Mar 27 15:22:30 <hostname> kernel: [156676.036250] sd 1:0:0:0: [sdb] Spinning up disk...
Mar 27 15:22:32 <hostname> kernel: [156677.068526] .
Mar 27 15:22:32 <hostname> kernel: [156678.092531] .
Mar 27 15:22:34 <hostname> kernel: [156679.116540] .
Mar 27 15:22:34 <hostname> kernel: [156680.140524] .
Mar 27 15:22:35 <hostname> kernel: [156681.164525] .
Mar 27 15:22:35 <hostname> kernel: [156681.165087] ready
Mar 27 15:22:36 <hostname> kernel: [156682.093621] sd 1:0:0:0: [sdb] Very big device. Trying to use READ CAPACITY(16).
Mar 27 15:22:36 <hostname> kernel: [156682.101598] sd 1:0:0:0: [sdb] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
Mar 27 15:22:36 <hostname> kernel: [156682.109523] sd 1:0:0:0: [sdb] 4096-byte physical blocks
Mar 27 15:22:36 <hostname> kernel: [156682.116095] sd 1:0:0:0: [sdb] Write Protect is off
Mar 27 15:22:36 <hostname> kernel: [156682.121838] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 27 15:22:36 <hostname> kernel: [156682.134476] sd 1:0:0:0: [sdb] Very big device. Trying to use READ CAPACITY(16).
Mar 27 15:22:36 <hostname> kernel: [156682.213877]  sdb: sdb1
Mar 27 15:22:36 <hostname> kernel: [156682.221495] sd 1:0:0:0: [sdb] Very big device. Trying to use READ CAPACITY(16).
Mar 27 15:22:36 <hostname> kernel: [156682.238646] sd 1:0:0:0: [sdb] Attached SCSI disk
Mar 27 15:32:18 <hostname> kernel: [157263.625565] usb 1-1.3: USB disconnect, device number 6
Mar 27 15:32:18 <hostname> kernel: [157263.632910] sd 1:0:0:0: [sdb] Synchronizing SCSI cache
Mar 27 15:32:18 <hostname> kernel: [157263.638394] sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Mar 27 16:08:49 <hostname> shutdown[8098]: shutting down for system reboot

/var/log/syslog

Mar 27 14:21:27 <hostname> kernel: [153013.300609] sd 2:0:0:0: [sdc] Very big device. Trying to use READ CAPACITY(16).
Mar 27 14:21:27 <hostname> kernel: [153013.308904] sd 2:0:0:0: [sdc] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
Mar 27 14:21:27 <hostname> kernel: [153013.316841] sd 2:0:0:0: [sdc] 4096-byte physical blocks
Mar 27 14:21:27 <hostname> kernel: [153013.323345] sd 2:0:0:0: [sdc] Write Protect is off
Mar 27 14:21:27 <hostname> kernel: [153013.328308] sd 2:0:0:0: [sdc] Mode Sense: 43 00 00 00
Mar 27 14:21:27 <hostname> kernel: [153013.329606] sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 27 14:21:27 <hostname> kernel: [153013.342003] sd 2:0:0:0: [sdc] Very big device. Trying to use READ CAPACITY(16).
Mar 27 14:21:28 <hostname> kernel: [153013.775877]  sdc: sdc1
Mar 27 14:21:28 <hostname> kernel: [153013.783628] sd 2:0:0:0: [sdc] Very big device. Trying to use READ CAPACITY(16).
Mar 27 14:21:28 <hostname> kernel: [153013.800873] sd 2:0:0:0: [sdc] Attached SCSI disk
Mar 27 15:09:23 <hostname> ntfs-3g[1899]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error
Mar 27 15:09:23 <hostname> ntfs-3g[1899]: Failed to read index block: Input/output error
Mar 27 15:09:23 <hostname> kernel: [155889.272078] buffer_io_error: 724 callbacks suppressed
Mar 27 15:09:23 <hostname> kernel: [155889.272087] Buffer I/O error on dev sdb1, logical block 46984, async page read
Mar 27 15:09:24 <hostname> ntfs-3g[1899]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error
Mar 27 15:09:24 <hostname> ntfs-3g[1899]: Failed to read index block: Input/output error
Mar 27 15:09:24 <hostname> kernel: [155890.157624] Buffer I/O error on dev sdb1, logical block 46984, async page read
Mar 27 15:09:26 <hostname> ntfs-3g[1899]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error
Mar 27 15:09:26 <hostname> ntfs-3g[1899]: Failed to read index block: Input/output error
Mar 27 15:09:26 <hostname> kernel: [155892.132217] Buffer I/O error on dev sdb1, logical block 46984, async page read
Mar 27 15:09:27 <hostname> ntfs-3g[1899]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error
Mar 27 15:09:27 <hostname> ntfs-3g[1899]: Failed to read index block: Input/output error
Mar 27 15:09:27 <hostname> kernel: [155893.298423] Buffer I/O error on dev sdb1, logical block 46984, async page read
Mar 27 15:09:56 <hostname> ntfs-3g[1899]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error
Mar 27 15:09:56 <hostname> ntfs-3g[1899]: Failed to read vcn 0x0: Input/output error
Mar 27 15:09:56 <hostname> kernel: [155922.005187] Buffer I/O error on dev sdb1, logical block 46984, async page read
Mar 27 15:10:03 <hostname> kernel: [155929.452911] Buffer I/O error on dev sdb1, logical block 46984, async page read
Mar 27 15:10:03 <hostname> ntfs-3g[1899]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error
Mar 27 15:10:03 <hostname> ntfs-3g[1899]: Failed to read vcn 0x0: Input/output error
Mar 27 15:11:31 <hostname> kernel: [156016.646487] Buffer I/O error on dev sdb1, logical block 71869, lost async page write
Mar 27 15:11:31 <hostname> kernel: [156016.654489] Buffer I/O error on dev sdb1, logical block 71870, lost async page write
Mar 27 15:11:31 <hostname> kernel: [156016.662872] Buffer I/O error on dev sdb1, logical block 918611355, lost async page write
Mar 27 15:11:31 <hostname> kernel: [156016.671181] Buffer I/O error on dev sdb1, logical block 918611356, lost async page write
Mar 27 15:11:31 <hostname> kernel: [156016.679834] Buffer I/O error on dev sdb1, logical block 918611357, lost async page write
Mar 27 15:11:31 <hostname> kernel: [156016.688217] Buffer I/O error on dev sdb1, logical block 918611358, lost async page write
Mar 27 15:11:31 <hostname> kernel: [156016.696815] Buffer I/O error on dev sdb1, logical block 918611359, lost async page write
Mar 27 15:11:31 <hostname> kernel: [156016.705110] Buffer I/O error on dev sdb1, logical block 918611360, lost async page write
Mar 27 15:11:31 <hostname> kernel: [156016.713647] Buffer I/O error on dev sdb1, logical block 918611361, lost async page write
Mar 27 15:11:31 <hostname> kernel: [156016.721942] Buffer I/O error on dev sdb1, logical block 918611362, lost async page write
Mar 27 15:11:31 <hostname> ntfs-3g[1899]: Unmounting /dev/sdb1 ()
Mar 27 15:11:31 <hostname> ntfs-3g[1899]: Failed to sync device /dev/sdb1: Input/output error
Mar 27 15:11:31 <hostname> ntfs-3g[1899]: Failed to fsync device /dev/sdb1: Input/output error
Mar 27 15:11:31 <hostname> ntfs-3g[1899]: Failed to close volume /dev/sdb1: Device or resource busy
Mar 27 15:11:31 <hostname> kernel: [156016.853935] VFS: Dirty inode writeback failed for block device sdb1 (err=-5).
Mar 27 15:17:01 <hostname> CRON[4926]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar 27 15:22:25 <hostname> kernel: [156671.241565] usb 1-1.3: USB disconnect, device number 5
Mar 27 15:22:25 <hostname> kernel: [156671.252472] sd 2:0:0:0: [sdc] Synchronizing SCSI cache
Mar 27 15:22:25 <hostname> kernel: [156671.275786] sd 2:0:0:0: [sdc] Synchronize Cache(10) failed: Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Mar 27 15:22:29 <hostname> kernel: [156674.792547] usb 1-1.3: new high-speed USB device number 6 using orion-ehci
Mar 27 15:22:29 <hostname> kernel: [156674.946687] usb 1-1.3: New USB device found, idVendor=18a5, idProduct=0415
Mar 27 15:22:29 <hostname> kernel: [156674.953754] usb 1-1.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Mar 27 15:22:29 <hostname> kernel: [156674.961230] usb 1-1.3: Product: Store N Save Desktop HDD
Mar 27 15:22:29 <hostname> kernel: [156674.966676] usb 1-1.3: Manufacturer:
Mar 27 15:22:29 <hostname> kernel: [156674.970554] usb 1-1.3: SerialNumber: <serial>
Mar 27 15:22:29 <hostname> kernel: [156674.980281] usb-storage 1-1.3:1.0: USB Mass Storage device detected
Mar 27 15:22:29 <hostname> kernel: [156674.990779] scsi host1: usb-storage 1-1.3:1.0
Mar 27 15:22:30 <hostname> kernel: [156676.013657] scsi 1:0:0:0: Direct-Access     Verbatim Desktop HDD 3.0  0    PQ: 0 ANSI: 6
Mar 27 15:22:30 <hostname> kernel: [156676.026314] sd 1:0:0:0: Attached scsi generic sg1 type 0
Mar 27 15:22:30 <hostname> kernel: [156676.036250] sd 1:0:0:0: [sdb] Spinning up disk...
Mar 27 15:22:32 <hostname> kernel: [156677.068526] .
Mar 27 15:22:32 <hostname> kernel: [156678.092531] .
Mar 27 15:22:34 <hostname> kernel: [156679.116540] .
Mar 27 15:22:34 <hostname> kernel: [156680.140524] .
Mar 27 15:22:35 <hostname> kernel: [156681.164525] .
Mar 27 15:22:35 <hostname> kernel: [156681.165087] ready
Mar 27 15:22:36 <hostname> kernel: [156682.093621] sd 1:0:0:0: [sdb] Very big device. Trying to use READ CAPACITY(16).
Mar 27 15:22:36 <hostname> kernel: [156682.101598] sd 1:0:0:0: [sdb] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
Mar 27 15:22:36 <hostname> kernel: [156682.109523] sd 1:0:0:0: [sdb] 4096-byte physical blocks
Mar 27 15:22:36 <hostname> kernel: [156682.116095] sd 1:0:0:0: [sdb] Write Protect is off
Mar 27 15:22:36 <hostname> kernel: [156682.121067] sd 1:0:0:0: [sdb] Mode Sense: 43 00 00 00
Mar 27 15:22:36 <hostname> kernel: [156682.121838] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 27 15:22:36 <hostname> kernel: [156682.134476] sd 1:0:0:0: [sdb] Very big device. Trying to use READ CAPACITY(16).
Mar 27 15:22:36 <hostname> kernel: [156682.213877]  sdb: sdb1
Mar 27 15:22:36 <hostname> kernel: [156682.221495] sd 1:0:0:0: [sdb] Very big device. Trying to use READ CAPACITY(16).
Mar 27 15:22:36 <hostname> kernel: [156682.238646] sd 1:0:0:0: [sdb] Attached SCSI disk
Mar 27 15:32:18 <hostname> kernel: [157263.625565] usb 1-1.3: USB disconnect, device number 6
Mar 27 15:32:18 <hostname> kernel: [157263.632910] sd 1:0:0:0: [sdb] Synchronizing SCSI cache
Re: Is there a problem with the Dockstar and USB 3.0?
March 27, 2018 11:41PM
The USB bridge itself. The Dockstar might not like this USB 3.0 bridge.

Quote

U-Boot 2017.07-tld-1 (Oct 24 2017 - 22:32:36 -0700)
Seagate FreeAgent DockStar
gcc (Debian 6.3.0-18) 6.3.0 20170516
GNU ld (GNU Binutils for Debian) 2.28
Hit any key to stop autoboot: 0
starting USB...
USB0: USB EHCI 1.00
scanning bus 0 for devices... 2 USB Device(s) found
scanning usb for storage devices... 0 Storage Device(s) found

With your system drive plugged in, the Dockstar did not spin up either USB drives.

I'm running my Dockstar with a Sabrent USB 3.0 4-port hub. And the system drive is plugged in directly to the USB port next to the Ethernet port. This has not cause any problem for as long as I can remember (when other device are plugged in during booting).

For experiment in the past, I've used a Belkin USB 2.0 hub with a Sandisk USB 3.0 drive plugged in to the hub, and this caused many problem with booting the Dockstar.

Since you have netconsole, try powering up with both system drive and and the USB 3.0 enclosure attached, and repeat a few

usb start
See if the Dockstar ever spin up either drives.

Also try plugging the USB enclosure to the port next to the Ethernet port, and the system drive plugged in to one of the other USB ports.

-bodhi
===========================
Forum Wiki
bodhi's corner
Re: Is there a problem with the Dockstar and USB 3.0?
March 28, 2018 04:39PM
Hey bodhi! The JMS56x dock is not working no matter what. I tried a few usb starts, only the first one appeared to be processed by the Dockstar. Issuing a usb reset only detects the system drive, regardless of what ports they're plugged in. Dunno, this dock works fine on my Windows PC on both USB 2.0 and 3.0, does not get detected as anything weird, despite being a cloning dock.

It's not important, though, I discovered it wasn't working by pure accident and I mentioned because it got me thinking about USB 3.0.

What IS important, is the disconnect. That's my most pressing concern: I need to remove the WD from the Dockstar, and the Seagate was meant to take its place as a storage drive (the rootfs would be moved to a pen drive).

If the ASMedia bridge cannot be trusted to work reliably with the Dockstar, then I am in a bind. As remediation, I tried upgrading the kernel to the latest available on backports (4.14 from 4.9), but that was just a shot in the dark.

Anything else comes to mind?



Edited 1 time(s). Last edit at 03/28/2018 04:40PM by leitmotiv.
Re: Is there a problem with the Dockstar and USB 3.0?
March 28, 2018 05:02PM
leitmotiv,

> Dunno, this dock works fine on my
> Windows PC on both USB 2.0 and 3.0, does not get
> detected as anything weird, despite being a
> cloning dock.

This situation is normal. U-boot don't use the same driver as Windows, or even Linux laptop/dekstop.

> If the ASMedia bridge cannot be trusted to work
> reliably with the Dockstar, then I am in a bind.
> As remediation, I tried upgrading the kernel to
> the latest available on backports (4.14 from 4.9),
> but that was just a shot in the dark.
>

Sounds like you only need to make sure it works in Linux, your rootfs drive is or will be on a pen drive. That's I think is feasible.

You are running mainline kernel
Jan  1 00:00:44 <hostname> kernel: [    0.000000] Linux version 4.9.0-6-marvell (debian-kernel@lists.debian.org) (gcc version 6.3.0 20170516 (Debian 6.3.0-18) ) #1 Debian 4.9.82-1+deb9u3 (2018-03-02)

Try install my released kernel 4.15.2:
https://forum.doozan.com/read.php?2,12096

Quote

Latest released kernel: linux-4.15.2-kirkwood-tld-1-bodhi.tar.bz2 (11 Feb 2018)

1. You can test with linux-4.15.2-kirkwood-tld-1 to see if it handling the Asmedia enclosure better than the mainline kernel. And then go from there. Going back to mainline kernel is easy by just restoring 2 files: uImage and uInitrd.

2. You can also try the basic rootfs Debian-4.12.1-kirkwood-tld-1-rootfs-bodhi.tar.bz2:
https://forum.doozan.com/read.php?2,12096

-bodhi
===========================
Forum Wiki
bodhi's corner



Edited 1 time(s). Last edit at 03/28/2018 05:03PM by bodhi.
Re: Is there a problem with the Dockstar and USB 3.0?
March 28, 2018 06:32PM
Luckily the Dockstar has no problem recognizing the ASMedia bridge, and it seemed to be working just fine for many hours until the disconnect reared its ugly head. The idea of moving rootfs to a pen drive stems from my plan of sharing the storage drive between Windows (where I'd make use of its superior speed to take image backups quickly) and Linux (where it would instead serve my media library to clients). While I could boot the Dockstar from the Seagate itself, it being a large drive requiring GPT makes things more difficult, and it should be re-partitioned using parted to have a chance to work.

Two questions:

What are the differences between your kernel and mainline? A link to an existing post would be great.
Any most clever idea how to test the bridge without constantly copying terabytes of data, for hours on end?
Re: Is there a problem with the Dockstar and USB 3.0?
March 28, 2018 08:04PM
You can download the tarball from the released thread
https://forum.doozan.com/read.php?2,12096

And look at the diff patch in there. Also the config file (diff the mainline config file with it). There are too many additional modules that l configured in, listing the diferences in plain text is not easy! Most notable would be Sound and Network.

-bodhi
===========================
Forum Wiki
bodhi's corner
Author:

Your Email:


Subject:


Spam prevention:
Please, enter the code that you see below in the input field. This is for blocking bots that try to post this form automatically. If the code is hard to read, then just try to guess it right. If you enter the wrong code, a new image is created and you get another chance to enter it right.
Message: