Thread Rating:

fzacca · 05-29-2016, 03:45 PM

Hello everyone.

I'm having a really hard time trying to figure out what's wrong with my configuration.

I'm on OpenELEC 6.95.3 on 8Gb SandDisk SDHC, Raspberry Pi 3 with it's official 7" touchscreen, Rii wireless keyboard, hifiberry DIGI+ and a Crucial 9600Gb SSD through a USB to SATA cable everything powered by official 2.5A power supply with max_usb_current set to 1.

Everything worked so far, pretty flawlessly actually.

The other day I tried the SAMBA share feature with the intent to add some music from another PC and worked for a minute...after that I got a read-only filesystem problem (it's set on rw for everyone) and the SSD got removed from OpenELEC.

Since then, whenever I plug in the SSD, I get the message "Mounted Removable Storage Device" immediately followed by "Successfully Removed Storage Device"!

I tried many things: removed all the add-ons, even reflashed the SD Card with a fresh OpenELEC, reformatted the SSD (from ntfs to ext4 to btrfs), run a fsck that returned 0 errors, removed SAMBA sharing, but nothing. The curious thing is that the SSD works just fine with my other PCs.

Now I really have no more ideas and I'm pretty concerned because this is a very important project for me.
I'm attaching a dmesg and the fsck output just for more info to you, hoping for a help.

Thank you
Fabio

[attachment=2721]dmesg.txt[/attachment]

.txt

fsck-result.txt (Size: 870 bytes / Downloads: 0) fsck-result.txt[/attachment]

Code:
[ 0.000000] Booting Linux on physical CPU 0x0

[ 0.000000] Initializing cgroup subsys cpuset

[ 0.000000] Initializing cgroup subsys cpu

[ 0.000000] Initializing cgroup subsys cpuacct

[ 0.000000] Linux version 4.4.8 (stephan@buildserver.openelec.tv) (gcc version 5.3.0 (GCC) ) #1 SMP Sun May 1 13:29:11 CEST 2016

[ 0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d

[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache

[ 0.000000] Machine model: Raspberry Pi 3 Model B Rev 1.2

[ 0.000000] cma: Reserved 8 MiB at 0x2e400000

[ 0.000000] Memory policy: Data cache writealloc

[ 0.000000] On node 0 totalpages: 192512

[ 0.000000] free_area_init_node: node 0, pgdat 8094b800, node_mem_map add64000

[ 0.000000] Normal zone: 1692 pages used for memmap

[ 0.000000] Normal zone: 0 pages reserved

[ 0.000000] Normal zone: 192512 pages, LIFO batch:31

[ 0.000000] [bcm2709_smp_init_cpus] enter (94c0->f3003010)

[ 0.000000] [bcm2709_smp_init_cpus] ncores=4

[ 0.000000] PERCPU: Embedded 12 pages/cpu @aefaf000 s17292 r8192 d23668 u49152

[ 0.000000] pcpu-alloc: s17292 r8192 d23668 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: 190820

[ 0.000000] Kernel command line: root=/dev/ram0 rdinit=/init BOOT_IMAGE=/kernel.img usbcore.autosuspend=-1 8250.nr_uarts=1 dma.dmachans=0x7f35 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2709.boardrev=0xa02082 bcm2709.serial=0xb1d5cc19 smsc95xx.macaddr=B8:27:EB:D5:CC:19 bcm2708_fb.fbswap=1 bcm2709.uart_clock=48000000 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000 boot=/dev/mmcblk0p1 disk=/dev/mmcblk0p2 quiet

[ 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: 744072K/770048K available (5459K kernel code, 202K rwdata, 1428K rodata, 2400K init, 396K bss, 17784K reserved, 8192K cma-reserved)

[ 0.000000] Virtual kernel memory layout:

[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)

[ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)

[ 0.000000] vmalloc : 0xaf800000 - 0xff800000 (1280 MB)

[ 0.000000] lowmem : 0x80000000 - 0xaf000000 ( 752 MB)

[ 0.000000] modules : 0x7f000000 - 0x80000000 ( 16 MB)

[ 0.000000] .text : 0x80008000 - 0x806c1ffc (6888 kB)

[ 0.000000] .init : 0x806c2000 - 0x8091a000 (2400 kB)

[ 0.000000] .data : 0x8091a000 - 0x8094c8d8 ( 203 kB)

[ 0.000000] .bss : 0x8094f000 - 0x809b2054 ( 397 kB)

[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1

[ 0.000000] Hierarchical RCU implementation.

[ 0.000000] Build-time adjustment of leaf fanout to 32.

[ 0.000000] NR_IRQS:16 nr_irqs:16 16

[ 0.000000] Architected 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.000008] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns

[ 0.000023] Switching to timer-based delay loop, resolution 52ns

[ 0.000195] Console: colour dummy device 80x30

[ 0.000371] console [tty0] enabled

[ 0.000397] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.00 BogoMIPS (lpj=64000)

[ 0.000413] pid_max: default: 32768 minimum: 301

[ 0.000538] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)

[ 0.000550] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)

[ 0.001368] Disabling cpuset control group subsystem

[ 0.001394] Initializing cgroup subsys io

[ 0.001420] Initializing cgroup subsys memory

[ 0.001452] Initializing cgroup subsys devices

[ 0.001468] Initializing cgroup subsys freezer

[ 0.001493] CPU: Testing write buffer coherency: ok

[ 0.001923] CPU0: update cpu_capacity 1024

[ 0.001935] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000

[ 0.001944] [bcm2709_smp_prepare_cpus] enter

[ 0.002027] Setting up static identity map for 0x8280 - 0x82b4

[ 0.003519] [bcm2709_boot_secondary] cpu:1 started (0) 18

[ 0.003717] [bcm2709_secondary_init] enter cpu:1

[ 0.003753] CPU1: update cpu_capacity 1024

[ 0.003759] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001

[ 0.004113] [bcm2709_boot_secondary] cpu:2 started (0) 17

[ 0.004264] [bcm2709_secondary_init] enter cpu:2

[ 0.004283] CPU2: update cpu_capacity 1024

[ 0.004288] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002

[ 0.004631] [bcm2709_boot_secondary] cpu:3 started (0) 16

[ 0.004769] [bcm2709_secondary_init] enter cpu:3

[ 0.004788] CPU3: update cpu_capacity 1024

[ 0.004793] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003

[ 0.004851] Brought up 4 CPUs

[ 0.004872] SMP: Total of 4 processors activated (153.00 BogoMIPS).

[ 0.004879] CPU: All CPU(s) started in HYP mode.

[ 0.004886] CPU: Virtualization extensions available.

[ 0.005311] devtmpfs: initialized

[ 0.014179] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4

[ 0.014356] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns

[ 0.014850] pinctrl core: initialized pinctrl subsystem

[ 0.015362] NET: Registered protocol family 16

[ 0.020483] DMA: preallocated 4096 KiB pool for atomic coherent allocations

[ 0.030011] cpuidle: using governor ladder

[ 0.040029] cpuidle: using governor menu

[ 0.044799] bcm2709: Mini UART enabled

[ 0.044948] Serial: AMBA PL011 UART driver

[ 0.045075] uart-pl011 3f201000.uart: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe

[ 0.045221] bcm2835-mbox 3f00b880.mailbox: mailbox enabled

[ 0.087645] bcm2835-dma 3f007000.dma: DMA legacy API manager at f3007000, dmachans=0x1

[ 0.088775] SCSI subsystem initialized

[ 0.089079] usbcore: registered new interface driver usbfs

[ 0.089165] usbcore: registered new interface driver hub

[ 0.089250] usbcore: registered new device driver usb

[ 0.090236] raspberrypi-firmware soc:firmware: Attached to firmware from 2016-04-27 11:10

[ 0.117267] clocksource: Switched to clocksource arch_sys_counter

[ 0.125318] NET: Registered protocol family 2

[ 0.125988] TCP established hash table entries: 8192 (order: 3, 32768 bytes)

[ 0.126100] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)

[ 0.126288] TCP: Hash tables configured (established 8192 bind 8192)

[ 0.126379] UDP hash table entries: 512 (order: 2, 16384 bytes)

[ 0.126424] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)

[ 0.126645] NET: Registered protocol family 1

[ 0.126918] RPC: Registered named UNIX socket transport module.

[ 0.126928] RPC: Registered udp transport module.

[ 0.126935] RPC: Registered tcp transport module.

[ 0.126943] RPC: Registered tcp NFSv4.1 backchannel transport module.

[ 0.277028] futex hash table entries: 1024 (order: 4, 65536 bytes)

[ 0.290177] squashfs: version 4.0 (2009/01/31) Phillip Lougher

[ 0.291844] NFS: Registering the id_resolver key type

[ 0.291889] Key type id_resolver registered

[ 0.291897] Key type id_legacy registered

[ 0.291921] nfs4filelayout_init: NFSv4 File Layout Driver Registering...

[ 0.295141] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)

[ 0.295162] io scheduler noop registered (default)

[ 0.295178] io scheduler deadline registered

[ 0.295216] io scheduler cfq registered

[ 0.297767] BCM2708FB: allocated DMA memory ee800000

[ 0.297791] BCM2708FB: allocated DMA channel 0 @ f3007000

[ 0.304371] Console: switching to colour frame buffer device 100x30

[ 0.308429] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled

[ 0.309246] 3f215040.uart: ttyS0 at MMIO 0x3f215040 (irq = 59, base_baud = 50000000) is a 16550

[ 1.193617] bcm2835-rng 3f104000.rng: hwrng registered

[ 1.193765] vc-cma: Videocore CMA driver

[ 1.193775] vc-cma: vc_cma_base = 0x00000000

[ 1.193783] vc-cma: vc_cma_size = 0x00000000 (0 MiB)

[ 1.193791] vc-cma: vc_cma_initial = 0x00000000 (0 MiB)

[ 1.193976] vc-mem: phys_addr:0x00000000 mem_base=0x3dc00000 mem_size:0x3f000000(1008 MiB)

[ 1.205635] brd: module loaded

[ 1.205768] loop: module loaded

[ 1.207807] nbd: registered device at major 43

[ 1.215508] vchiq: vchiq_init_state: slot_zero = 0xae880000, is_master = 0

[ 1.216986] usbcore: registered new interface driver rtsx_usb

[ 1.217008] Loading iSCSI transport class v2.0-870.

[ 1.217397] iscsi: registered transport (tcp)

[ 1.217674] tun: Universal TUN/TAP device driver, 1.6

[ 1.217683] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>

[ 1.217929] usbcore: registered new interface driver dm9601

[ 1.218021] usbcore: registered new interface driver smsc95xx

[ 1.218085] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)

[ 1.418340] Core Release: 2.80a

[ 1.418352] Setting default values for core params

[ 1.418380] Finished setting default values for core params

[ 1.618714] Using Buffer DMA mode

[ 1.618724] Periodic Transfer Interrupt Enhancement - disabled

[ 1.618731] Multiprocessor Interrupt Enhancement - disabled

[ 1.618740] OTG VER PARAM: 0, OTG VER FLAG: 0

[ 1.618757] Dedicated Tx FIFOs mode

[ 1.619027] WARN::dwc_otg_hcd_init:1047: FIQ DMA bounce buffers: virt = 0xae814000 dma = 0xee814000 len=9024

[ 1.619056] FIQ FSM acceleration enabled for :

[ 1.619056] Non-periodic Split Transactions

[ 1.619056] Periodic Split Transactions

[ 1.619056] High-Speed Isochronous Endpoints

[ 1.619056] Interrupt/Control Split Transaction hack enabled

[ 1.619077] dwc_otg: Microframe scheduler enabled

[ 1.619118] WARN::hcd_init_fiq:413: FIQ on core 1 at 0x803d46f0

[ 1.619130] WARN::hcd_init_fiq:414: FIQ ASM at 0x803d4a54 length 36

[ 1.619143] WARN::hcd_init_fiq:439: MPHI regs_base at 0xaf964000

[ 1.619196] dwc_otg 3f980000.usb: DWC OTG Controller

[ 1.619239] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1

[ 1.619271] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000

[ 1.619318] Init: Port Power? op_state=1

[ 1.619325] Init: Power Port (0)

[ 1.619486] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002

[ 1.619500] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1

[ 1.619512] usb usb1: Product: DWC OTG Controller

[ 1.619523] usb usb1: Manufacturer: Linux 4.4.8 dwc_otg_hcd

[ 1.619534] usb usb1: SerialNumber: 3f980000.usb

[ 1.620124] hub 1-0:1.0: USB hub found

[ 1.620163] hub 1-0:1.0: 1 port detected

[ 1.620530] dwc_otg: FIQ enabled

[ 1.620538] dwc_otg: NAK holdoff enabled

[ 1.620545] dwc_otg: FIQ split-transaction FSM enabled

[ 1.620600] Module dwc_common_port init

[ 1.620768] usbcore: registered new interface driver uas

[ 1.620892] usbcore: registered new interface driver usb-storage

[ 1.621064] mousedev: PS/2 mouse device common for all mice

[ 1.621190] i2c /dev entries driver

[ 1.621717] bcm2708_i2c 3f804000.i2c: BSC1 Controller at 0x3f804000 (irq 83) (baudrate 100000)

[ 1.622630] bcm2835-cpufreq: min=600000 max=1200000

[ 1.624343] sdhci: Secure Digital Host Controller Interface driver

[ 1.624347] sdhci: Copyright(c) Pierre Ossman

[ 1.624496] sdhost: log_buf @ ae813000 (ee813000)

[ 1.690591] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)

[ 1.692605] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0

[ 1.692611] mmc-bcm2835 3f300000.mmc: DMA channel allocated

[ 1.737300] sdhci-pltfm: SDHCI platform and OF driver helper

[ 1.737469] ledtrig-cpu: registered to indicate activity on CPUs

[ 1.737545] hidraw: raw HID events driver (C) Jiri Kosina

[ 1.738596] usbcore: registered new interface driver usbhid

[ 1.738600] usbhid: USB HID core driver

[ 1.739492] NET: Registered protocol family 10

[ 1.739888] NET: Registered protocol family 17

[ 1.739946] Key type dns_resolver registered

[ 1.740191] Registering SWP/SWPB emulation handler

[ 1.740520] vc-sm: Videocore shared memory driver

[ 1.740527] [vc_sm_connected_init]: start

[ 1.740763] [vc_sm_connected_init]: end - returning 0

[ 1.741897] 3f201000.uart: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2

[ 1.742040] console [netcon0] enabled

[ 1.742044] netconsole: network logging started

[ 1.742052] of_cfs_init

[ 1.742092] of_cfs_init: OK

[ 1.744154] Freeing unused kernel memory: 2400K (806c2000 - 8091a000)

[ 1.772651] mmc0: host does not support reading read-only switch, assuming write-enable

[ 1.777330] mmc0: new high speed SDHC card at address aaaa

[ 1.777661] mmcblk0: mmc0:aaaa SL08G 7.40 GiB 

[ 1.778655] mmcblk0: p1 p2

[ 1.795585] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)

[ 1.797077] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)

[ 1.798567] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)

[ 1.801245] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)

[ 1.817315] Indeed it is in host mode hprt0 = 00021501

[ 1.888340] mmc1: new high speed SDIO card at address 0001

[ 1.983980] usb 1-1: new high-speed USB device number 2 using dwc_otg

[ 1.984133] Indeed it is in host mode hprt0 = 00001101

[ 2.151132] EXT4-fs (mmcblk0p2): couldn't mount as ext3 due to feature incompatibilities

[ 2.151517] EXT4-fs (mmcblk0p2): couldn't mount as ext2 due to feature incompatibilities

[ 2.164061] usb 1-1: New USB device found, idVendor=0424, idProduct=9514

[ 2.164069] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0

[ 2.164428] hub 1-1:1.0: USB hub found

[ 2.164477] hub 1-1:1.0: 5 ports detected

[ 2.175930] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)

[ 2.444021] usb 1-1.1: new high-speed USB device number 3 using dwc_otg

[ 2.510334] systemd[1]: System time before build time, advancing clock.

[ 2.514484] random: systemd urandom read with 66 bits of entropy available

[ 2.523436] systemd[1]: systemd 229 running in system mode. (-PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK -SYSVINIT -UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS -ACL -XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN)

[ 2.524062] systemd[1]: Detected architecture arm.

[ 2.524268] systemd[1]: Running with unpopulated /etc.

[ 2.524503] systemd[1]: Set hostname to <OpenELEC>.

[ 2.524690] systemd[1]: Initializing machine ID from random generator.

[ 2.537611] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00

[ 2.537632] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0

[ 2.540341] smsc95xx v1.0.4

[ 2.574683] systemd[1]: Populated /etc with preset unit settings.

[ 2.582291] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:d5:cc:19

[ 2.649653] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.

[ 2.649889] systemd[1]: Started Forward Password Requests to Wall Directory Watch.

[ 2.650028] systemd[1]: Listening on Journal Socket.

[ 2.650141] systemd[1]: Listening on udev Kernel Socket.

[ 2.688735] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.

[ 2.688798] systemd[1]: Reached target Paths.

[ 2.689502] systemd[1]: Created slice System Slice.

[ 2.707405] systemd[1]: Mounting POSIX Message Queue File System...

[ 2.709065] systemd[1]: Starting Create list of required static device nodes for the current kernel...

[ 2.710530] systemd[1]: Mounting Temporary Directory...

[ 2.712545] systemd[1]: Starting Mounting swapfile...

[ 2.714353] systemd[1]: Starting Remount Root and Kernel File Systems...

[ 2.716186] systemd[1]: Mounting Debug File System...

[ 2.718127] systemd[1]: Mounting Variable Directory...

[ 2.719741] systemd[1]: Starting Setup machine-id...

[ 2.721624] systemd[1]: Starting Show Version...

[ 2.721825] systemd[1]: Listening on Journal Socket (/dev/log).

[ 2.722250] systemd[1]: Created slice User and Session Slice.

[ 2.722311] systemd[1]: Reached target Slices.

[ 2.728736] systemd[1]: Starting Load Kernel Modules...

[ 2.728927] systemd[1]: Listening on udev Control Socket.

[ 2.731676] systemd[1]: Started Create list of required static device nodes for the current kernel.

[ 2.733366] systemd[1]: Starting Create Static Device Nodes in /dev...

[ 2.753544] systemd[1]: Started Load Kernel Modules.

[ 2.773390] systemd[1]: Mounting Configuration File System...

[ 2.774995] systemd[1]: Starting Apply Kernel Variables...

[ 2.777206] systemd[1]: Started Remount Root and Kernel File Systems.

[ 2.797168] systemd[1]: Mounted Configuration File System.

[ 2.797574] systemd[1]: Mounted POSIX Message Queue File System.

[ 2.797838] systemd[1]: Mounted Temporary Directory.

[ 2.801828] systemd[1]: Mounted Debug File System.

[ 2.801975] systemd[1]: Mounted Variable Directory.

[ 2.803574] systemd[1]: Started Apply Kernel Variables.

[ 2.815479] systemd[1]: Started Show Version.

[ 2.816728] systemd[1]: Started Create Static Device Nodes in /dev.

[ 2.820658] usb 1-1.5: new full-speed USB device number 4 using dwc_otg

[ 2.833164] systemd[1]: Started Setup machine-id.

[ 2.918929] systemd[1]: Started Mounting swapfile.

[ 2.923746] usb 1-1.5: New USB device found, idVendor=1997, idProduct=2433

[ 2.923757] usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=0

[ 2.923764] usb 1-1.5: Product: Mini Keyboard

[ 2.923769] usb 1-1.5: Manufacturer: 

[ 2.928798] input: Mini Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/0003:1997:2433.0001/input/input0

[ 2.981109] hid-generic 0003:1997:2433.0001: input,hidraw0: USB HID v1.01 Keyboard [ Mini Keyboard] on usb-3f980000.usb-1.5/input0

[ 2.987125] input: Mini Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.1/0003:1997:2433.0002/input/input1

[ 3.000124] systemd[1]: Reached target Swap.

[ 3.014251] systemd[1]: Starting Journal Service...

[ 3.016583] systemd[1]: Starting Setup Timezone data...

[ 3.016963] systemd[1]: Reached target Local File Systems (Pre).

[ 3.017023] systemd[1]: Reached target Local File Systems.

[ 3.020357] systemd[1]: Starting udev Coldplug all Devices...

[ 3.033094] systemd[1]: Started Setup Timezone data.

[ 3.037609] hid-generic 0003:1997:2433.0002: input,hidraw1: USB HID v1.01 Mouse [ Mini Keyboard] on usb-3f980000.usb-1.5/input1

[ 3.081974] systemd[1]: Started Journal Service.

[ 3.125639] systemd-journald[205]: Received request to flush runtime journal from PID 1

[ 3.448617] random: nonblocking pool is initialized

[ 4.012887] rpi-ft5406 rpi_ft5406: Probing device

[ 4.013080] input: FT5406 memory based driver as /devices/virtual/input/input2

[ 4.106675] Console: switching to colour dummy device 80x30

[ 4.309941] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer

[ 4.323843] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000

[ 4.338148] cfg80211: World regulatory domain updated:

[ 4.338164] cfg80211: DFS Master region: unset

[ 4.338169] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)

[ 4.338176] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)

[ 4.338183] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A)

[ 4.338189] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)

[ 4.338195] cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)

[ 4.338202] cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)

[ 4.338208] cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)

[ 4.338213] cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)

[ 4.338219] cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)

[ 4.472590] brcmfmac: brcmf_sdio_drivestrengthinit: No SDIO Drive strength init done for chip 43430 rev 1 pmurev 24

[ 4.474476] usbcore: registered new interface driver brcmfmac

[ 4.477938] snd-hifiberry-digi soc:sound: ASoC: CODEC DAI wm8804-spdif not registered

[ 4.477953] snd-hifiberry-digi soc:sound: snd_soc_register_card() failed: -517

[ 4.500023] wm8804 1-003b: revision E

[ 4.503436] snd-hifiberry-digi soc:sound: wm8804-spdif <-> 3f203000.i2s mapping ok

[ 4.679008] uart-pl011 3f201000.uart: no DMA platform data

[ 4.700977] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Dec 15 2015 18:10:45 version 7.45.41.23 (r606571) FWID 01-cc4eda9c

[ 4.718246] brcmfmac: brcmf_cfg80211_reg_notifier: not a ISO3166 code

[ 4.821985] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup

[ 4.822103] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready

[ 5.017577] brcmfmac: brcmf_add_if: ERROR: netdev:wlan0 already exists

[ 5.017594] brcmfmac: brcmf_add_if: ignore IF event

[ 5.021835] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready

[ 5.021865] brcmfmac: power management disabled

[ 5.662642] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready

[ 9.111561] Bluetooth: Core ver 2.21

[ 9.111660] NET: Registered protocol family 31

[ 9.111675] Bluetooth: HCI device and connection manager initialized

[ 9.112632] Bluetooth: HCI socket layer initialized

[ 9.112826] Bluetooth: L2CAP socket layer initialized

[ 9.112905] Bluetooth: SCO socket layer initialized

[ 9.119535] Bluetooth: HCI UART driver ver 2.3

[ 9.119551] Bluetooth: HCI UART protocol H4 registered

[ 9.119556] Bluetooth: HCI UART protocol Three-wire (H5) registered

[ 9.119800] Bluetooth: HCI UART protocol BCM registered

[ 43.273939] usb 1-1.4: new high-speed USB device number 5 using dwc_otg

[ 43.412002] usb 1-1.4: New USB device found, idVendor=174c, idProduct=55aa

[ 43.412015] usb 1-1.4: New USB device strings: Mfr=2, Product=3, SerialNumber=1

[ 43.412021] usb 1-1.4: Product: ASMT1051

[ 43.412027] usb 1-1.4: Manufacturer: asmedia

[ 43.412034] usb 1-1.4: SerialNumber: 12345678BFCC

[ 43.412664] usb 1-1.4: The driver for the USB controller dwc_otg_hcd does not support scatter-gather which is

[ 43.412679] usb 1-1.4: required by the UAS driver. Please try an other USB controller if you wish to use UAS.

[ 43.412731] usb 1-1.4: The driver for the USB controller dwc_otg_hcd does not support scatter-gather which is

[ 43.412738] usb 1-1.4: required by the UAS driver. Please try an other USB controller if you wish to use UAS.

[ 43.412746] usb-storage 1-1.4:1.0: USB Mass Storage device detected

[ 43.417816] usb-storage 1-1.4:1.0: Quirks match for vid 174c pid 55aa: 400000

[ 43.417858] scsi host0: usb-storage 1-1.4:1.0

[ 44.421195] scsi 0:0:0:0: Direct-Access ASMT 2115 0 PQ: 0 ANSI: 6

[ 44.428919] sd 0:0:0:0: [sda] 1875385008 512-byte logical blocks: (960 GB/894 GiB)

[ 44.428933] sd 0:0:0:0: [sda] 4096-byte physical blocks

[ 44.429363] sd 0:0:0:0: [sda] Write Protect is off

[ 44.429376] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00

[ 44.429803] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA

[ 44.450378] sda: sda1

[ 44.452898] sd 0:0:0:0: [sda] Attached SCSI disk

[ 44.710759] raid6: int32x1 gen() 195 MB/s

[ 44.767369] raid6: int32x1 xor() 167 MB/s

[ 44.823998] raid6: int32x2 gen() 269 MB/s

[ 44.880731] raid6: int32x2 xor() 198 MB/s

[ 44.937418] raid6: int32x4 gen() 301 MB/s

[ 44.993975] raid6: int32x4 xor() 220 MB/s

[ 45.050735] raid6: int32x8 gen() 307 MB/s

[ 45.107404] raid6: int32x8 xor() 207 MB/s

[ 45.107416] raid6: using algorithm int32x8 gen() 307 MB/s

[ 45.107421] raid6: .... xor() 207 MB/s, rmw enabled

[ 45.107425] raid6: using intx1 recovery algorithm

[ 45.109104] xor: measuring software checksum speed

[ 45.140583] arm4regs : 1842.000 MB/sec

[ 45.173919] 8regs : 1371.600 MB/sec

[ 45.207257] 32regs : 1275.600 MB/sec

[ 45.207275] xor: using function: arm4regs (1842.000 MB/sec)

[ 45.253166] Btrfs loaded

[ 45.255032] BTRFS: device label SSD devid 1 transid 867 /dev/sda1

[ 45.359640] BTRFS info (device sda1): disk space caching is enabled

[ 45.359654] BTRFS: has skinny extents

[ 47.171909] usb 1-1.4: USB disconnect, device number 5

[ 47.176478] sd 0:0:0:0: [sda] Synchronizing SCSI cache

[ 47.176605] sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=0x00

[ 47.270931] BTRFS error (device sda1): bdev /dev/sda1 errs: wr 0, rd 1, flush 0, corrupt 0, gen 0

[ 47.271008] BTRFS error (device sda1): bdev /dev/sda1 errs: wr 0, rd 2, flush 0, corrupt 0, gen 0

[ 47.271063] BTRFS error (device sda1): error reading free space cache

[ 47.271072] BTRFS warning (device sda1): failed to load free space cache for block group 29360128, rebuilding it now

[ 47.271288] BTRFS error (device sda1): bdev /dev/sda1 errs: wr 0, rd 3, flush 0, corrupt 0, gen 0

[ 47.271334] BTRFS error (device sda1): bdev /dev/sda1 errs: wr 0, rd 4, flush 0, corrupt 0, gen 0

[ 47.271375] BTRFS error (device sda1): bdev /dev/sda1 errs: wr 0, rd 5, flush 0, corrupt 0, gen 0

[ 47.271406] BTRFS error (device sda1): bdev /dev/sda1 errs: wr 0, rd 6, flush 0, corrupt 0, gen 0

[ 47.271602] BTRFS error (device sda1): bdev /dev/sda1 errs: wr 0, rd 7, flush 0, corrupt 0, gen 0

[ 47.271639] BTRFS error (device sda1): bdev /dev/sda1 errs: wr 0, rd 8, flush 0, corrupt 0, gen 0

[ 47.271726] BTRFS error (device sda1): bdev /dev/sda1 errs: wr 0, rd 9, flush 0, corrupt 0, gen 0

[ 47.271760] BTRFS error (device sda1): bdev /dev/sda1 errs: wr 0, rd 10, flush 0, corrupt 0, gen 0

[ 47.271784] ------------[ cut here ]------------

[ 47.271931] WARNING: CPU: 2 PID: 766 at fs/btrfs/extent-tree.c:7454 find_free_extent+0xf78/0xfdc [btrfs]()

[ 47.271936] BTRFS: Transaction aborted (error -5)

[ 47.271941] Modules linked in: btrfs xor raid6_pq zlib_deflate hci_uart btbcm bluetooth snd_soc_wm8804_i2c snd_soc_wm8804 regmap_i2c snd_soc_hifiberry_digi brcmfmac snd_soc_bcm2835_i2s snd_soc_core brcmutil snd_pcm_dmaengine snd_pcm cfg80211 snd_timer snd bcm2835_gpiomem bcm2835_wdt rpi_ft5406

[ 47.271999] CPU: 2 PID: 766 Comm: umount Not tainted 4.4.8 #1

[ 47.272004] Hardware name: BCM2709

[ 47.272008] Backtrace: 

[ 47.272030] [<80012838>] (dump_backtrace) from [<80012a30>] (show_stack+0x18/0x1c)

[ 47.272034] r7:7f2ef214 r6:80932e20 r5:00000000 r4:60000013

[ 47.272055] [<80012a18>] (show_stack) from [<802bad58>] (dump_stack+0xa4/0xc4)

[ 47.272066] [<802bacb4>] (dump_stack) from [<8001d858>] (warn_slowpath_common+0x88/0xb8)

[ 47.272070] r7:7f2ef214 r6:00001d1e r5:00000009 r4:9f203a18

[ 47.272084] [<8001d7d0>] (warn_slowpath_common) from [<8001d8c0>] (warn_slowpath_fmt+0x38/0x40)

[ 47.272088] r8:fffffffb r7:acc03720 r6:00000002 r5:ad766068 r4:7f38ba40

[ 47.272188] [<8001d88c>] (warn_slowpath_fmt) from [<7f2ef214>] (find_free_extent+0xf78/0xfdc [btrfs])

[ 47.272192] r3:fffffffb r2:7f38ba40

[ 47.272198] r4:00000001

[ 47.272342] [<7f2ee29c>] (find_free_extent [btrfs]) from [<7f2ef330>] (btrfs_reserve_extent+0xb8/0x22c [btrfs])

[ 47.272346] r10:9f203b9f r9:00000000 r8:00004000 r7:00000000 r6:acd27800 r5:00000000

[ 47.272357] r4:00000000

[ 47.272499] [<7f2ef278>] (btrfs_reserve_extent [btrfs]) from [<7f2ef614>] (btrfs_alloc_tree_block+0x170/0x574 [btrfs])

[ 47.272504] r10:acc03000 r9:acc03148 r8:ad766068 r7:00000000 r6:00004000 r5:acc03148

[ 47.272515] r4:acd27800

[ 47.272656] [<7f2ef4a4>] (btrfs_alloc_tree_block [btrfs]) from [<7f2d4f50>] (__btrfs_cow_block+0x180/0x710 [btrfs])

[ 47.272660] r10:8091ebc4 r9:a86f37e0 r8:ad766068 r7:00000000 r6:00000005 r5:9f203c1f

[ 47.272671] r4:acd27800

[ 47.272811] [<7f2d4dd0>] (__btrfs_cow_block [btrfs]) from [<7f2d56c0>] (btrfs_cow_block+0x148/0x1b0 [btrfs])

[ 47.272815] r10:9f203d04 r9:00000000 r8:acd27800 r7:00000000 r6:00000000 r5:00000000

[ 47.272826] r4:00000000

[ 47.272966] [<7f2d5578>] (btrfs_cow_block [btrfs]) from [<7f2d954c>] (btrfs_search_slot+0x274/0xb08 [btrfs])

[ 47.272971] r10:acd27800 r9:00000002 r8:a8504000 r7:00000000 r6:00000364 r5:80976304

[ 47.272982] r4:00000002

[ 47.273126] [<7f2d92d8>] (btrfs_search_slot [btrfs]) from [<7f2f701c>] (btrfs_lookup_inode+0x3c/0xe4 [btrfs])

[ 47.273130] r10:adaa9000 r9:ad766068 r8:acd27800 r7:00000001 r6:a8504000 r5:9f203d8e

[ 47.273142] r4:a8689000

[ 47.273288] [<7f2f6fe0>] (btrfs_lookup_inode [btrfs]) from [<7f35f110>] (__btrfs_update_delayed_inode+0x78/0x270 [btrfs])

[ 47.273293] r6:a8504000 r5:00000100 r4:a8689000

[ 47.273445] [<7f35f098>] (__btrfs_update_delayed_inode [btrfs]) from [<7f35f4a4>] (__btrfs_run_delayed_items+0x19c/0x1e8 [btrfs])

[ 47.273450] r10:adaa9000 r9:ffffffff r8:a868902c r7:a8504000 r6:ad766068 r5:ffffffff

[ 47.273461] r4:a8689000

[ 47.273608] [<7f35f308>] (__btrfs_run_delayed_items [btrfs]) from [<7f35f8dc>] (btrfs_run_delayed_items+0x14/0x18 [btrfs])

[ 47.273612] r10:00000000 r9:9f202000 r8:a8724000 r7:a8704140 r6:acc0358c r5:adaa9000

[ 47.273623] r4:ad766068

[ 47.273768] [<7f35f8c8>] (btrfs_run_delayed_items [btrfs]) from [<7f301988>] (btrfs_commit_transaction+0x274/0xc58 [btrfs])

[ 47.273912] [<7f301714>] (btrfs_commit_transaction [btrfs]) from [<7f2ce0bc>] (btrfs_sync_fs+0x44/0xb4 [btrfs])

[ 47.273916] r10:00000000 r9:9f202000 r8:8000f944 r7:a7c4a640 r6:adaa8c00 r5:acc03000

[ 47.273928] r4:adaa9000

[ 47.274003] [<7f2ce078>] (btrfs_sync_fs [btrfs]) from [<8011372c>] (sync_filesystem+0x90/0xb4)

[ 47.274007] r7:a7c4a640 r6:00000000 r5:00000000 r4:adaa8c00

[ 47.274024] [<8011369c>] (sync_filesystem) from [<800e82dc>] (generic_shutdown_super+0x2c/0xfc)

[ 47.274028] r5:7f3877d4 r4:adaa8c00

[ 47.274045] [<800e82b0>] (generic_shutdown_super) from [<800e861c>] (kill_anon_super+0x14/0x20)

[ 47.274049] r5:7f391358 r4:0000001a

[ 47.274125] [<800e8608>] (kill_anon_super) from [<7f2ce17c>] (btrfs_kill_super+0x14/0x78 [btrfs])

[ 47.274129] r5:7f391358 r4:acc03000

[ 47.274207] [<7f2ce168>] (btrfs_kill_super [btrfs]) from [<800e87e0>] (deactivate_locked_super+0x64/0x88)

[ 47.274211] r5:7f391358 r4:adaa8c00

[ 47.274225] [<800e877c>] (deactivate_locked_super) from [<800e933c>] (deactivate_super+0x60/0x64)

[ 47.274229] r5:8094fea4 r4:adaa8c00

[ 47.274242] [<800e92dc>] (deactivate_super) from [<80103c1c>] (cleanup_mnt+0x40/0x84)

[ 47.274246] r5:8094fea4 r4:ad3e4300

[ 47.274257] [<80103bdc>] (cleanup_mnt) from [<80103cc4>] (__cleanup_mnt+0x14/0x18)

[ 47.274261] r5:8094fea4 r4:a7c4a994

[ 47.274273] [<80103cb0>] (__cleanup_mnt) from [<80036674>] (task_work_run+0x98/0xcc)

[ 47.274283] [<800365dc>] (task_work_run) from [<8001243c>] (do_work_pending+0xb4/0xb8)

[ 47.274289] r7:9f202000 r6:9f203fb0 r5:8000f944 r4:9f202010

[ 47.274303] [<80012388>] (do_work_pending) from [<8000f7ec>] (slow_work_pending+0xc/0x20)

[ 47.274307] r7:00000034 r6:00000004 r5:000f19f0 r4:000f19f0

[ 47.274335] ---[ end trace ceeb273c60a4ce6d ]---

[ 47.274345] BTRFS warning (device sda1): find_free_extent:7454: Aborting unused transaction(IO failure).

[ 47.274358] BTRFS warning (device sda1): __btrfs_run_delayed_items:1192: Aborting unused transaction(IO failure).

[ 47.274367] BTRFS warning (device sda1): Skipping commit of aborted transaction.

[ 47.274373] BTRFS warning (device sda1): cleanup_transaction:1746: Aborting unused transaction(IO failure).

[ 47.274379] BTRFS info (device sda1): delayed_refs has NO entry

[ 47.277374] BTRFS warning (device sda1): find_free_extent:7454: Aborting unused transaction(IO failure).

[ 47.277393] BTRFS warning (device sda1): btrfs_update_root:153: Aborting unused transaction(IO failure).

[ 47.277403] BTRFS warning (device sda1): Skipping commit of aborted transaction.

[ 47.277409] BTRFS warning (device sda1): cleanup_transaction:1746: Aborting unused transaction(IO failure).

[ 47.277415] BTRFS info (device sda1): delayed_refs has NO entry

[ 47.277427] BTRFS error (device sda1): commit super ret -5

gda · 05-29-2016, 06:59 PM

You have even set up a RAID 6, why? Why are you using an experimental file system like btrfs? What was the idea behind all this experiments?

Gerald

fzacca · 05-29-2016, 08:54 PM

Thanks for replying.

Actually the original file system was intended to be ext4.
Trying to fix this issue I tried other file systems, like btrfs, hoping for some positive response.
I have no idea about the RAID 6. I just created a partition from Gparted and plug the ssd in, which worked just fine for weeks. This leads the big question. Why suddenly did it stop working, being immediately unmounted?

The whole thing will be the source in my car audio system, nearly in the future (ext4 ssd in openelec with digital audio output via optical cable), but this issue really scares me, since I have no idea about the origin and the solution.

BlockABoots · 06-04-2016, 02:50 PM

Same thing has happened to my USB external HDD, when i hook it up to my Raspberry Pi3...

http://openelec.tv/forum/65-storage/8136...y-openelec

i used to get a message that the HDD was connected and mounted now i get no message and i cant see the drive in file explorer BUT when i remove the USB device i get a message that the usb hdd has been removed!

fzacca · (This post was last modified: 06-04-2016, 04:51 PM by fzacca.)

Well, I'm sorry to hear that, man. I just read your situation, pretty similar to mine. Here's some details of my updates from past days work and swearing, maybe something can help you.

First, yours an HDD, mine's an SSD..apparently that makes no difference though.
My brother suggested me trying formatting by force the first 100Mb, using the "dd" command under linux and remake the partition but the problem stayed. I noticed that if formatted in ntfs from windows made it "resist" a bunch of seconds more though...some satisfaction. By the way I usually get ready a disk via GParted, creating a GPT table then ext4 partition then setting permission rw for root, "users" group and others, pretty simple.

In these days I even tried another cable+controller taken from a working WD MyPassport just to understand if it was my SATA to USB3 cable. Same thing.
Then I tried an old Toshiba 2.5" 16GB mechanical HDD, with an IDE enclosure and that worked...that, I suppose, solves the doubt about enough power. That HDD wants double power via USB otherwise It won't start spinning.

Yesterday I contacted Crucial (my SSD is a Crucial BX200 which is still under warranty, firmware updated by the way) I explained the situation and this morning I shipped that demonic thing back to them.
I'll get a substitution. This will give me the answer to the question "Is it SSD's fault?" and my fingers are crossed very tight as you can immagine.

The dmesg I have saved here (many of them) all have that "dwc_otg_hcd" and "UAS" in them, just like in yours. Plus the say the filesystem is read-only which is wrong.

Mine started not working, during a file copy while trying the SAMBA share feature, over wifi...from that moment on...darkness.
Even Raspbian via NOOBS says nasty things on dmesg with same SSD. A real mistery.
I wonder if some bug in OpenELEC code can phisically damage a drive...sounds harsh but I really have no more ideas. That would make no sense then because of Raspbian behaving the same way, I think.
If the new SSD behaves the same I guess that's the only possible reason.

That, or a very nasty problem with Raspberry Pi 3s...

Possibly Related Threads...
Thread		Author	Replies	Views	Last Post
	Mounting NTFS HDD's problems	Halfwind	18	1,532	07-10-2020, 06:48 AM Last Post: FrankJScott
	No mounting of external ntfs USB drives on Zotac HD-ND22	JuniX	4	1,030	11-07-2018, 05:56 PM Last Post: jimmy1
	Storage device unmounts right after mounting	tattersail	0	301	05-08-2017, 12:16 PM Last Post: tattersail
	External - Converted sata -> usb hd not mounting	linuxmad	2	443	03-29-2015, 11:42 AM Last Post: linuxmad
	Wiki about mounting network shares is wrong...	advocate99	5	721	01-28-2015, 04:10 AM Last Post: lrusak
	Internal HDD not mounting	gtpoliveira	7	761	10-10-2014, 07:36 PM Last Post: gtpoliveira
	Complete openelec newbie- mounting local hdd	vulgartrendkill	6	654	06-02-2014, 03:50 AM Last Post: michael7oliver
	Mounting and accessing other SD Card partitions	SteBa	0	253	06-01-2014, 08:48 PM Last Post: SteBa
	Inefficient mounting of partitions on boot	doesntcount	4	597	10-18-2013, 08:15 AM Last Post: sovaby
	Local Hdd not mounting	DeathCon 4	5	654	10-17-2013, 05:58 PM Last Post: lwiddif