raspberrypi / linux

Kernel source tree for Raspberry Pi-provided kernel builds. Issues unrelated to the linux kernel should be posted on the community forum at https://forums.raspberrypi.com/
Other
10.87k stars 4.89k forks source link

RPi3B+ USB flash devices I/O errors #2714

Closed vmkernel closed 5 years ago

vmkernel commented 5 years ago

Hi, there!

First of all, let me apologize if I've done something wrong while I was opening the issue.

Problem

I've got the Raspberry Pi 3 B+ on this Monday and since then I'm fighting the following issue without any success:

[  312.921458] usb 1-1.1.2: reset high-speed USB device number 5 using dwc_otg
[  313.320517] sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08
[  313.320532] sd 0:0:0:0: [sda] tag#0 Sense Key : 0x6 [current]
[  313.320544] sd 0:0:0:0: [sda] tag#0 ASC=0x28 ASCQ=0x0
[  313.320559] sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 09 e6 00 00 00 f0 00
[  313.320570] print_req_error: I/O error, dev sda, sector 648704
[  313.320593] Buffer I/O error on dev sda1, logical block 152190, async page read

This issue happens every time I try to read/write to a USB flash drive and therefore it corrupts data and stops a copy process with input/output error on the USB flash drive.

The issue reproduces on all of my USB flash drives:

So, there's six (6) different USB flash drives with the same problem and, of course, all of these devices works perfectly fine on a RPi3B device that I borrowed from a friend and on desktop PCs running Windows 8.1 and 10.

The most confusing part for me is that my external USB 3.0 HDD works fine with just this little harmless messages in /var/log/kern.log that appear every 2 - 10 seconds:

Jun 27 04:48:25 prometheus kernel: [ 1227.961494] usb 1-1.3: reset high-speed USB device number 7 using dwc_otg
Jun 27 04:48:30 prometheus kernel: [ 1233.071463] usb 1-1.3: reset high-speed USB device number 7 using dwc_otg

My wired USB keyboard works fine too.

I suppose, if this is all about the hardware issue then the USB HDD and the keyboard should suffer from the same issue, but they're not.

Investigation

During my small research I've discovered the following.

Everything works fine on the following devices (for the same OS (firmware) on the same microsSD card):

The issue doesn't relates to:

Workarounds I've already tried, yet not successfully:

The question

The main thing I want to understand: is the issue related to software or to the hardware part of the RPi3B+? If it's a software issue/bug, therefore it can be fixed by some adjustments, downgrades, workarounds or in future releases (of kernel?). But if it's a hardware bug, then I should try to return the device to the local distributor.

So for now I'm in a little despair and obviously failed in fixing this issue by myself, so I'm looking forward for your help!

Thanks in advance!

Technical details

Kernel

pi@prometheus:~ $ uname -a
Linux prometheus 4.14.71-v7+ #1145 SMP Fri Sep 21 15:38:35 BST 2018 armv7l GNU/Linux

lsusb -t USB flash drive is the last one (Port 2: Dev 5, If 0)

pi@prometheus:~ $ lsusb -t
/:  Bus 01.Port 1: Dev 1, Class=root_hub, Driver=dwc_otg/1p, 480M
    |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/4p, 480M
        |__ Port 1: Dev 3, If 0, Class=Hub, Driver=hub/3p, 480M
            |__ Port 1: Dev 4, If 0, Class=Vendor Specific Class, Driver=lan78xx, 480M
            |__ Port 2: Dev 5, If 0, Class=Mass Storage, Driver=usb-storage, 480M

lsusb -v

pi@prometheus:~ $ lsusb -v

Bus 001 Device 005: ID 05e3:0736 Genesys Logic, Inc. microSD Reader/Writer
Couldn't open device, some information will be missing
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            0 (Defined at Interface level)
  bDeviceSubClass         0
  bDeviceProtocol         0
  bMaxPacketSize0        64
  idVendor           0x05e3 Genesys Logic, Inc.
  idProduct          0x0736 microSD Reader/Writer
  bcdDevice            2.72
  iManufacturer           3
  iProduct                4
  iSerial                 2
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           32
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0x80
      (Bus Powered)
    MaxPower               98mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           2
      bInterfaceClass         8 Mass Storage
      bInterfaceSubClass      6 SCSI
      bInterfaceProtocol     80 Bulk-Only
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x02  EP 2 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0

Bus 001 Device 004: ID 0424:7800 Standard Microsystems Corp.
Couldn't open device, some information will be missing
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.10
  bDeviceClass          255 Vendor Specific Class
  bDeviceSubClass         0
  bDeviceProtocol       255
  bMaxPacketSize0        64
  idVendor           0x0424 Standard Microsystems Corp.
  idProduct          0x7800
  bcdDevice            3.00
  iManufacturer           0
  iProduct                0
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           39
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                2mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           3
      bInterfaceClass       255 Vendor Specific Class
      bInterfaceSubClass      0
      bInterfaceProtocol    255
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x02  EP 2 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0010  1x 16 bytes
        bInterval               4

Bus 001 Device 003: ID 0424:2514 Standard Microsystems Corp. USB 2.0 Hub
Couldn't open device, some information will be missing
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0 Unused
  bDeviceProtocol         2 TT per port
  bMaxPacketSize0        64
  idVendor           0x0424 Standard Microsystems Corp.
  idProduct          0x2514 USB 2.0 Hub
  bcdDevice            b.b3
  iManufacturer           0
  iProduct                0
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           41
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                2mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0 Unused
      bInterfaceProtocol      1 Single TT
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0001  1x 1 bytes
        bInterval              12
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       1
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0 Unused
      bInterfaceProtocol      2 TT per port
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0001  1x 1 bytes
        bInterval              12

Bus 001 Device 002: ID 0424:2514 Standard Microsystems Corp. USB 2.0 Hub
Couldn't open device, some information will be missing
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0 Unused
  bDeviceProtocol         2 TT per port
  bMaxPacketSize0        64
  idVendor           0x0424 Standard Microsystems Corp.
  idProduct          0x2514 USB 2.0 Hub
  bcdDevice            b.b3
  iManufacturer           0
  iProduct                0
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           41
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                2mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0 Unused
      bInterfaceProtocol      1 Single TT
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0001  1x 1 bytes
        bInterval              12
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       1
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0 Unused
      bInterfaceProtocol      2 TT per port
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0001  1x 1 bytes
        bInterval              12

Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Couldn't open device, some information will be missing
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0 Unused
  bDeviceProtocol         1 Single TT
  bMaxPacketSize0        64
  idVendor           0x1d6b Linux Foundation
  idProduct          0x0002 2.0 root hub
  bcdDevice            4.14
  iManufacturer           3
  iProduct                2
  iSerial                 1
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           25
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                0mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0 Unused
      bInterfaceProtocol      0 Full speed (or root) hub
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0004  1x 4 bytes
        bInterval              12

dmesg

pi@prometheus:~ $ dmesg
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.14.71-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1145 SMP Fri Sep 21 15:38:35 BST 2018
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] cma: Reserved 8 MiB at 0x3ac00000
[    0.000000] On node 0 totalpages: 242688
[    0.000000] free_area_init_node: node 0, pgdat 80c85280, node_mem_map ba39f000
[    0.000000]   Normal zone: 2133 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 242688 pages, LIFO batch:31
[    0.000000] percpu: Embedded 17 pages/cpu @ba348000 s38720 r8192 d22720 u69632
[    0.000000] pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 240555
[    0.000000] Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=PARTUUID=bc7986ce-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 940232K/970752K available (7168K kernel code, 576K rwdata, 2076K rodata, 1024K init, 698K bss, 22328K reserved, 8192K cma-reserved)
[    0.000000] Virtual kernel memory layout:
                   vector  : 0xffff0000 - 0xffff1000   (   4 kB)
                   fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
                   vmalloc : 0xbb800000 - 0xff800000   (1088 MB)
                   lowmem  : 0x80000000 - 0xbb400000   ( 948 MB)
                   modules : 0x7f000000 - 0x80000000   (  16 MB)
                     .text : 0x80008000 - 0x80800000   (8160 kB)
                     .init : 0x80b00000 - 0x80c00000   (1024 kB)
                     .data : 0x80c00000 - 0x80c9017c   ( 577 kB)
                      .bss : 0x80c97f10 - 0x80d468b0   ( 699 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 25273 entries in 75 pages
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000023] Switching to timer-based delay loop, resolution 52ns
[    0.000275] Console: colour dummy device 80x30
[    0.000809] console [tty1] enabled
[    0.000847] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.000887] pid_max: default: 32768 minimum: 301
[    0.001215] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001249] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.002204] Disabling memory control group subsystem
[    0.002296] CPU: Testing write buffer coherency: ok
[    0.002724] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.003145] Setting up static identity map for 0x100000 - 0x10003c
[    0.003287] Hierarchical SRCU implementation.
[    0.003983] smp: Bringing up secondary CPUs ...
[    0.004783] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.005631] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.006462] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.006568] smp: Brought up 1 node, 4 CPUs
[    0.006641] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.006662] CPU: All CPU(s) started in HYP mode.
[    0.006680] CPU: Virtualization extensions available.
[    0.007576] devtmpfs: initialized
[    0.017880] random: get_random_u32 called from bucket_table_alloc+0xfc/0x24c with crng_init=0
[    0.018556] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.018801] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.018846] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.019423] pinctrl core: initialized pinctrl subsystem
[    0.020227] NET: Registered protocol family 16
[    0.022835] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.027702] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.027734] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.027947] Serial: AMBA PL011 UART driver
[    0.029634] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.030152] uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
[    0.061895] bcm2835-dma 3f007000.dma: DMA legacy API manager at bb813000, dmachans=0x1
[    0.063344] SCSI subsystem initialized
[    0.063589] usbcore: registered new interface driver usbfs
[    0.063660] usbcore: registered new interface driver hub
[    0.063760] usbcore: registered new device driver usb
[    0.070079] raspberrypi-firmware soc:firmware: Attached to firmware from 2018-09-21 15:43
[    0.071416] clocksource: Switched to clocksource arch_sys_counter
[    0.148615] VFS: Disk quotas dquot_6.6.0
[    0.148728] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.148930] FS-Cache: Loaded
[    0.149143] CacheFiles: Loaded
[    0.158119] NET: Registered protocol family 2
[    0.158855] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.158984] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.159186] TCP: Hash tables configured (established 8192 bind 8192)
[    0.159331] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.159392] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.159632] NET: Registered protocol family 1
[    0.160104] RPC: Registered named UNIX socket transport module.
[    0.160129] RPC: Registered udp transport module.
[    0.160148] RPC: Registered tcp transport module.
[    0.160167] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.161438] NOHZ: local_softirq_pending 80
[    0.161713] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    0.164479] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    0.172715] FS-Cache: Netfs 'nfs' registered for caching
[    0.173341] NFS: Registering the id_resolver key type
[    0.173387] Key type id_resolver registered
[    0.173406] Key type id_legacy registered
[    0.173435] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.175399] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.175559] io scheduler noop registered
[    0.175581] io scheduler deadline registered (default)
[    0.175878] io scheduler cfq registered
[    0.175900] io scheduler mq-deadline registered
[    0.175920] io scheduler kyber registered
[    0.178218] BCM2708FB: allocated DMA memory fad00000
[    0.178262] BCM2708FB: allocated DMA channel 0 @ bb813000
[    0.186853] Console: switching to colour frame buffer device 82x26
[    0.195219] bcm2835-rng 3f104000.rng: hwrng registered
[    0.197678] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    0.202838] vc-sm: Videocore shared memory driver
[    0.205428] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    0.219744] brd: module loaded
[    0.230740] loop: module loaded
[    0.233059] Loading iSCSI transport class v2.0-870.
[    0.236029] libphy: Fixed MDIO Bus: probed
[    0.238388] usbcore: registered new interface driver lan78xx
[    0.240706] usbcore: registered new interface driver smsc95xx
[    0.242889] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    0.272938] dwc_otg 3f980000.usb: base=0xf0980000
[    0.401413] NOHZ: local_softirq_pending 80
[    0.421413] NOHZ: local_softirq_pending 80
[    0.441413] NOHZ: local_softirq_pending 80
[    0.461413] NOHZ: local_softirq_pending 80
[    0.475329] Core Release: 2.80a
[    0.477331] Setting default values for core params
[    0.479353] Finished setting default values for core params
[    0.681640] Using Buffer DMA mode
[    0.683555] Periodic Transfer Interrupt Enhancement - disabled
[    0.685468] Multiprocessor Interrupt Enhancement - disabled
[    0.687383] OTG VER PARAM: 0, OTG VER FLAG: 0
[    0.689235] Dedicated Tx FIFOs mode
[    0.691311] WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0xbad14000 dma = 0xfad14000 len=9024
[    0.695172] FIQ FSM acceleration enabled for :
               Non-periodic Split Transactions
               Periodic Split Transactions
               High-Speed Isochronous Endpoints
               Interrupt/Control Split Transaction hack enabled
[    0.704853] dwc_otg: Microframe scheduler enabled
[    0.704910] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x805e9c5c
[    0.706923] WARN::hcd_init_fiq:460: FIQ ASM at 0x805e9fc4 length 36
[    0.708915] WARN::hcd_init_fiq:486: MPHI regs_base at 0xf0006000
[    0.711015] dwc_otg 3f980000.usb: DWC OTG Controller
[    0.713201] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    0.715522] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
[    0.717840] Init: Port Power? op_state=1
[    0.720091] Init: Power Port (0)
[    0.722488] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    0.724798] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.727094] usb usb1: Product: DWC OTG Controller
[    0.729335] usb usb1: Manufacturer: Linux 4.14.71-v7+ dwc_otg_hcd
[    0.731631] usb usb1: SerialNumber: 3f980000.usb
[    0.734490] hub 1-0:1.0: USB hub found
[    0.736693] hub 1-0:1.0: 1 port detected
[    0.739272] dwc_otg: FIQ enabled
[    0.739277] dwc_otg: NAK holdoff enabled
[    0.739282] dwc_otg: FIQ split-transaction FSM enabled
[    0.739292] Module dwc_common_port init
[    0.739539] usbcore: registered new interface driver usb-storage
[    0.741844] mousedev: PS/2 mouse device common for all mice
[    0.744058] IR NEC protocol handler initialized
[    0.746221] IR RC5(x/sz) protocol handler initialized
[    0.748419] IR RC6 protocol handler initialized
[    0.750588] IR JVC protocol handler initialized
[    0.752680] IR Sony protocol handler initialized
[    0.754765] IR SANYO protocol handler initialized
[    0.756850] IR Sharp protocol handler initialized
[    0.758839] IR MCE Keyboard/mouse protocol handler initialized
[    0.760862] IR XMP protocol handler initialized
[    0.763535] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
[    0.765878] bcm2835-cpufreq: min=600000 max=1400000
[    0.768327] sdhci: Secure Digital Host Controller Interface driver
[    0.770453] sdhci: Copyright(c) Pierre Ossman
[    0.772910] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
[    0.775385] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
[    0.777677] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.781293] ledtrig-cpu: registered to indicate activity on CPUs
[    0.783714] hidraw: raw HID events driver (C) Jiri Kosina
[    0.786183] usbcore: registered new interface driver usbhid
[    0.788492] usbhid: USB HID core driver
[    0.791325] vchiq: vchiq_init_state: slot_zero = bad80000, is_master = 0
[    0.795090] [vc_sm_connected_init]: start
[    0.803831] [vc_sm_connected_init]: end - returning 0
[    0.806762] Initializing XFRM netlink socket
[    0.809145] NET: Registered protocol family 17
[    0.811629] Key type dns_resolver registered
[    0.814401] Registering SWP/SWPB emulation handler
[    0.817442] registered taskstats version 1
[    0.825882] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    0.828441] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
[    0.835208] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
[    0.837744] mmc-bcm2835 3f300000.mmc: DMA channel allocated
[    0.892049] sdhost: log_buf @ bad13000 (fad13000)
[    0.932069] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    0.936053] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    0.939915] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    0.944897] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    0.951544] Indeed it is in host mode hprt0 = 00021501
[    1.027451] random: fast init done
[    1.041461] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    1.044581] of_cfs_init
[    1.046657] of_cfs_init: OK
[    1.049085] Waiting for root device PARTUUID=bc7986ce-02...
[    1.097207] mmc1: new high speed SDIO card at address 0001
[    1.109960] mmc0: host does not support reading read-only switch, assuming write-enable
[    1.117783] mmc0: new high speed SD card at address 1234
[    1.120477] mmcblk0: mmc0:1234 SA02G 1.84 GiB
[    1.126161]  mmcblk0: p1 p2
[    1.139076] NOHZ: local_softirq_pending 40
[    1.141092] NOHZ: local_softirq_pending 40
[    1.142973] NOHZ: local_softirq_pending 2c2
[    1.144808] NOHZ: local_softirq_pending 2c2
[    1.146543] NOHZ: local_softirq_pending 2c2
[    1.162389] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    1.166116] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    1.171462] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    1.173759] Indeed it is in host mode hprt0 = 00001101
[    1.244302] devtmpfs: mounted
[    1.249364] Freeing unused kernel memory: 1024K
[    1.411717] usb 1-1: New USB device found, idVendor=0424, idProduct=2514
[    1.414049] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    1.417016] hub 1-1:1.0: USB hub found
[    1.419346] hub 1-1:1.0: 4 ports detected
[    1.751467] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    1.881759] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514
[    1.883942] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    1.886728] hub 1-1.1:1.0: USB hub found
[    1.888920] hub 1-1.1:1.0: 3 ports detected
[    1.891472] systemd[1]: System time before build time, advancing clock.
[    2.074724] NET: Registered protocol family 10
[    2.078087] Segment Routing with IPv6
[    2.095431] ip_tables: (C) 2000-2006 Netfilter Core Team
[    2.123633] random: systemd: uninitialized urandom read (16 bytes read)
[    2.133168] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[    2.141278] systemd[1]: Detected architecture arm.
[    2.156929] systemd[1]: Set hostname to <prometheus>.
[    2.202561] random: systemd: uninitialized urandom read (16 bytes read)
[    2.222335] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read)
[    2.244053] dwc_otg_handle_wakeup_detected_intr lxstate = 2
[    2.841461] usb 1-1.1.1: new high-speed USB device number 4 using dwc_otg
[    2.870631] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    2.879865] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    2.889185] systemd[1]: Listening on udev Kernel Socket.
[    2.896258] systemd[1]: Created slice System Slice.
[    2.905150] systemd[1]: Mounting RPC Pipe File System...
[    2.912691] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    2.921033] systemd[1]: Listening on Journal Socket (/dev/log).
[    2.971910] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800
[    2.974793] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.269512] libphy: lan78xx-mdiobus: probed
[    3.972003] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    4.064634] systemd-journald[83]: Received request to flush runtime journal from PID 1
[    4.730738] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    4.734209] bcm2835_alsa bcm2835_alsa: card created with 8 channels
[    4.967295] brcmfmac: F1 signature read @0x18000000=0x15264345
[    4.974430] brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43455-sdio.bin for chip 0x004345(17221) rev 0x000006
[    4.974814] usbcore: registered new interface driver brcmfmac
[    5.176202] random: crng init done
[    5.176216] random: 7 urandom warning(s) missed due to ratelimiting
[    5.303409] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04
[    5.304006] brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 9.10.105 Compiler: 1.29.4 ClmImport: 1.36.3 Creation: 2018-03-09 18:56:28
[    6.282273] uart-pl011 3f201000.serial: no DMA platform data
[    6.796264] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
[    6.987917] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[    6.987934] brcmfmac: power management disabled
[    7.216237] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    7.216352] lan78xx 1-1.1.1:1.0 eth0: kevent 4 may have been dropped
[    7.217329] lan78xx 1-1.1.1:1.0 eth0: kevent 4 may have been dropped
[    7.225648] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   12.836699] Bluetooth: Core ver 2.22
[   12.836778] NET: Registered protocol family 31
[   12.836785] Bluetooth: HCI device and connection manager initialized
[   12.836808] Bluetooth: HCI socket layer initialized
[   12.836820] Bluetooth: L2CAP socket layer initialized
[   12.836874] Bluetooth: SCO socket layer initialized
[   12.854104] Bluetooth: HCI UART driver ver 2.3
[   12.854117] Bluetooth: HCI UART protocol H4 registered
[   12.854124] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   12.854348] Bluetooth: HCI UART protocol Broadcom registered
[   13.077629] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   13.077636] Bluetooth: BNEP filters: protocol multicast
[   13.077648] Bluetooth: BNEP socket layer initialized
[  284.631458] usb 1-1.1.2: new high-speed USB device number 5 using dwc_otg
[  284.765856] usb 1-1.1.2: New USB device found, idVendor=05e3, idProduct=0736
[  284.765869] usb 1-1.1.2: New USB device strings: Mfr=3, Product=4, SerialNumber=2
[  284.765877] usb 1-1.1.2: Product: USB Storage
[  284.765885] usb 1-1.1.2: Manufacturer: Generic
[  284.765894] usb 1-1.1.2: SerialNumber: 000000000272
[  284.769681] usb-storage 1-1.1.2:1.0: USB Mass Storage device detected
[  284.778025] scsi host0: usb-storage 1-1.1.2:1.0
[  285.832766] scsi 0:0:0:0: Direct-Access     Generic  STORAGE DEVICE   0272 PQ: 0 ANSI: 0
[  285.854830] sd 0:0:0:0: Attached scsi generic sg0 type 0
[  286.052324] sd 0:0:0:0: [sda] 125042688 512-byte logical blocks: (64.0 GB/59.6 GiB)
[  286.053273] sd 0:0:0:0: [sda] Write Protect is off
[  286.053285] sd 0:0:0:0: [sda] Mode Sense: 0b 00 00 08
[  286.054229] sd 0:0:0:0: [sda] No Caching mode page found
[  286.054240] sd 0:0:0:0: [sda] Assuming drive cache: write through
[  286.059670]  sda: sda1
[  286.063353] sd 0:0:0:0: [sda] Attached SCSI removable disk
[  297.551462] usb 1-1.1.2: reset high-speed USB device number 5 using dwc_otg
[  297.950579] sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08
[  297.950595] sd 0:0:0:0: [sda] tag#0 Sense Key : 0x6 [current]
[  297.950606] sd 0:0:0:0: [sda] tag#0 ASC=0x28 ASCQ=0x0
[  297.950621] sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 01 7f 00 00 00 f0 00
[  297.950632] print_req_error: I/O error, dev sda, sector 98048
[  312.921458] usb 1-1.1.2: reset high-speed USB device number 5 using dwc_otg
[  313.320517] sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08
[  313.320532] sd 0:0:0:0: [sda] tag#0 Sense Key : 0x6 [current]
[  313.320544] sd 0:0:0:0: [sda] tag#0 ASC=0x28 ASCQ=0x0
[  313.320559] sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 09 e6 00 00 00 f0 00
[  313.320570] print_req_error: I/O error, dev sda, sector 648704
vmkernel commented 5 years ago

Anyway it was a hardware issue with the specific board. I've got another one and now it works just fine. Thanks to no one for help!