Odd USB Mount Behavior

  • Hi Guys,

    Firstly let me say really appreciate all your work. Been a longterm OE and now LE user. Recently migrated over to 7.0.2 from OE 6.0.3. It was a very smooth process all in all.
    I was saddened to see the split in the community but hopefully everyone is happier this way!

    My issue is related to mounting of my USB hard drives which previously worked without problem under various flavours of OE.

    If I turn off the USB hub and boot the Rpi then turn on the USB hub everything mounts fine. However after a various period of time hard drives 'disappear' so that when I select an item to watch in the library to flags "Item no longer found, do you want to remove" warning. I haven't been able to catch the random dismounts on dmesg yet so cannot provide logs for those. My guess is it is due to power fluctuations which cause the hard drives to un-mount.

    However I do not believe this is the central issue which I believe is due to a failure to mount or 're-mount' the drive. If I turn of the USB hub once the pi is booted everything dismounts. However if I turn it back on, no hard drives are mounted. I also then find that when I attempt to reboot the pi it times out and the only way I can reboot it is to manually pull the power. This isn't something I've come across before.

    Ive attached the dmesg below. At 170 you can see where I turned off the USB hub and at 200 you can see where I turned it back on. Interestingly there is no mention of EXT-FS4 saying it has mounted the drives (as can be found earlier during boot up).

    Anyway your input is greatly appreciated.

    Regards,
    B

    dmesg: LibreELEC:~ # dmesg [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.00 - Pastebin.com

    Edited once, last by DaVu (September 17, 2016 at 7:39 PM).

  • Hey,

    Did some further digging and have definitely ruled the problem down to udev.

    Checking dmesg its clear that the system is picking up the hub and attached hard drives no problem.

    Currently if I run udevadm trigger --action=add the hard drives are succesfully mounted and seem to work.

    My guess is a bug of some sort has crept into the automount script either on the LE side or on the udev side. A complicating factor may be that I'm also running a slower/older RPi with a comparatively high number of HDD's (5).

    Anyway, let me know if I can provide logs or any further information to help track this down.

    Neil
    [hr]
    On Second thought I suspect this should be moved to Bug Reports?

    Also please see output of lsusb

    Bus 001 Device 009: ID 1058:10a2 Western Digital Technologies, Inc. Elements SE Portable (WDBPCK)
    Bus 001 Device 008: ID 0bc2:3000 Seagate RSS LLC FreeAgent Desktop
    Bus 001 Device 011: ID 0bc2:2300 Seagate RSS LLC Expansion Portable
    Bus 001 Device 010: ID 1058:1042 Western Digital Technologies, Inc. Elements SE Portable (WDBPCK)
    Bus 001 Device 007: ID 05e3:0608 Genesys Logic, Inc. Hub
    Bus 001 Device 006: ID 1058:1042 Western Digital Technologies, Inc. Elements SE Portable (WDBPCK)
    Bus 001 Device 005: ID 05e3:0608 Genesys Logic, Inc. Hub
    Bus 001 Device 004: ID 148f:5370 Ralink Technology, Corp. RT5370 Wireless Adapter
    Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp. SMSC9512/9514 Fast Ethernet Adapter
    Bus 001 Device 002: ID 0424:9512 Standard Microsystems Corp. SMC9512/9514 USB Hub
    Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub

    Edited once, last by BeneDicT (September 17, 2016 at 2:21 PM).

  • Further info.

    The system also refuses to reboot after issues arise with mounting.

    Output of dmesg is as follows:

    178.127551] usb 1-1.3: USB disconnect, device number 5
    178.127588] usb 1-1.3.1: USB disconnect, device number 6
    178.142273] usb 1-1.3.2: USB disconnect, device number 7
    178.142312] usb 1-1.3.2.1: USB disconnect, device number 10
    178.161232] usb 1-1.3.2.4: USB disconnect, device number 11
    178.167096] sd 4:0:0:0: [sde] Synchronizing SCSI cache
    178.177254] sd 4:0:0:0: [sde] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=0x00
    178.181471] usb 1-1.3.3: USB disconnect, device number 8
    178.192690] blk_update_request: I/O error, dev sdb, sector 0
    178.205814] sd 1:0:0:0: [sdb] Synchronizing SCSI cache
    178.208175] sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=0x00
    178.226434] usb 1-1.3.4: USB disconnect, device number 9
    179.565538] Buffer I/O error on dev sdc1, logical block 121667584, lost sync page write
    179.565572] JBD2: Error -5 detected when updating journal superblock for sdc1-8.
    179.565581] Aborting journal on device sdc1-8.
    179.565599] Buffer I/O error on dev sdc1, logical block 121667584, lost sync page write
    179.565613] JBD2: Error -5 detected when updating journal superblock for sdc1-8.
    179.611232] EXT4-fs error (device sdb1): ext4_find_entry:1451: inode #2: comm udevil: reading directory lblock 0
    179.653484] Buffer I/O error on dev sdb1, logical block 91258880, lost sync page write
    179.653517] JBD2: Error -5 detected when updating journal superblock for sdb1-8.
    179.653526] Aborting journal on device sdb1-8.
    179.653542] Buffer I/O error on dev sdb1, logical block 91258880, lost sync page write
    179.653554] JBD2: Error -5 detected when updating journal superblock for sdb1-8.
    198.976165] systemd-journald[191]: Failed to send WATCHDOG=1 notification message: Connection refused
    [hr]
    Further information:

    Cannot access via Samba and also cannot reboot, error messages in dmesg as follows:

    2621.208159] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Connection refused
    2715.141342] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    2801.208673] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    2921.208614] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    2981.207907] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    3092.179589] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    3161.217009] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    3281.217365] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    3341.216399] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    3447.818410] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    3521.216981] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    3641.217050] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    3701.216483] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    3821.216364] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    3881.216929] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    4001.216939] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    4061.216325] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    4181.225176] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    4241.225740] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    4338.606149] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    4421.225167] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    4541.225088] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    4601.225687] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    4692.034970] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    4781.225044] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    4882.550687] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    4956.378693] systemd-journald[175]: Failed to send WATCHDOG=1 notification message: Transport endpoint is not connected
    [hr]
    Debug.log output

    TWVY

    Edited once, last by BeneDicT (September 18, 2016 at 3:23 AM).