ESPRESSObin U-boot can't read Kingston SD card after reboot

This is for ARMv8 based devices

ESPRESSObin U-boot can't read Kingston SD card after reboot

Postby Excalibur » Wed May 30, 2018 4:32 pm

$this->bbcode_second_pass_quote('', '
')Update 1

Well this seems related to the armbian uboot. They provided an overclocked one yet didn't mention the board will be unstable on the download page, only in Github can you find this information. I'm gonna try again.

Update 2

Still can't reboot after using 1G/800MHz UBoot


I'm currently having the same issue as viewtopic.php?t=11920&p=56467. Since that thread is locked I'm gonna post here.

In fact, I was using a Sandisk Ultra C10 16GB card before and the system reboots just fine. I still have this card around as I need it to install ALARM on the new Kingston U1 32GB card, which behaves exactly like the old thread. I actually tried two same type of Kingston cards and they both have the same issue, so this can be a Kingston specific issue.

Currently the workaround is to eject and insert sd card when U-boot throws out error:
$this->bbcode_second_pass_code('', '
sdhci_transfer_data: Error detected in status(0x208000)!
** ext4fs_devread read error - block
')
and then press the physical reset button. Arch linux will boot again. Unplug and plug 12V DC for cold boot at this point also boots successfully.

I also tried eject insert and type "boot" in U-boot console, however the kernel will enter deadlock. At that point you have to press the reset button, and ALARM will boot. Here is the full log for this type of reboot:
$this->bbcode_second_pass_code('', '
Arch Linux 4.16.6-1-ARCH (ttyMV0)

alarm login: [ 13.825828] mv88e6085 d0032004.mdio-mii:01 wan: Link is Up - 1Gbps/Full - flow control rx/tx
[ 13.834263] IPv6: ADDRCONF(NETDEV_CHANGE): wan: link becomes ready
alarm
alarm
Password:
[ 135.634433] systemd-journald[169]: File /var/log/journal/98b4f0382aa74820a06ccf1747d6aedb/user-1000.journal corrupted or uncleanly shut down, renaming and replacing.
Last login: Tue Apr 24 00:32:28 on ttyMV0
[alarm@alarm ~]$ su
Password:
[root@alarm alarm]# reboot
Stopping Session c1 of user alarm..
[ OK ] Stopped Session c1 of user alarm.
[ OK ] Removed slice User Slice of alarm.
Stopping Permit User Sessions...
Stopping Login Service...
[ OK ] Stopped Login Service.
[ OK ] Stopped Permit User Sessions.
[ OK ] Stopped target Remote File Systems.
[ OK ] Stopped target Network.
Stopping Network Name Resolution...
[ OK ] Stopped target Basic System.
[ OK ] Stopped target Slices.
[ OK ] Removed slice User and Session Slice.
[ OK ] Stopped target Paths.
[ OK ] Stopped target Sockets.
[ OK ] Closed D-Bus System Message Bus Socket.
[ OK ] Stopped target System Initialization.
[ OK ] Stopped target Local Encrypted Volumes.
[ OK ] Stopped Dispatch Password Requests to Console Directory Watch.
[ OK ] Stopped Forward Password Requests to Wall Directory Watch.
Stopping Network Time Synchronization...
Stopping Load/Save Random Seed...
[ OK ] Stopped Update is Completed.
[ OK ] Stopped Rebuild Journal Catalog.
Stopping Update UTMP about System Boot/Shutdown...
[ OK ] Stopped Rebuild Dynamic Linker Cache.
[ OK ] Stopped Network Name Resolution.
[ OK ] Stopped Network Time Synchronization.
[ OK ] Started Generate shutdown-ramfs.
[ OK ] Stopped Load/Save Random Seed.
Stopping Network Service...
[ OK ] Stopped Update UTMP about System Boot/Shutdown.
[ OK ] Stopped Create Volatile Files and Directories.
[ OK ] Stopped target Local File Systems.
Unmounting Temporary Directory (/tmp)...
Unmounting /run/user/1000...
[ OK ] Stopped Network Service.
[ OK ] Unmounted Temporary Directory (/tmp).
[ OK ] Unmounted /run/user/1000.
[ OK ] Stopped target Local File Systems (Pre).
Stopping Monitoring of LVM2 mirrors…ng dmeventd or progress polling...
[ OK ] Stopped Create Static Device Nodes in /dev.
[ OK ] Reached target Unmount All Filesystems.
[ OK ] Stopped target Swap.
[ OK ] Stopped Create System Users.
[ OK ] Stopped Remount Root and Kernel File Systems.
[ OK ] Stopped Apply Kernel Variables.
[ OK ] Reached target Shutdown.
[ OK ] Reached target Final Step.
Starting Reboot...
[ OK ] Stopped Monitoring of LVM2 mirrors,…sing dmeventd or progress polling.
Stopping LVM2 metadata daemon...
[ OK ] Stopped LVM2 metadata daemon.
[ 146.152818] systemd-shutdow: 28 output lines suppressed due to ratelimiting
[ 146.185214] systemd-shutdown[1]: Syncing filesystems and block devices.
[ 146.869302] systemd-shutdown[1]: Sending SIGTERM to remaining processes...
[ 146.884467] systemd-journald[169]: Received SIGTERM from PID 1 (systemd-shutdow).
[ 146.987899] systemd-shutdown[1]: Sending SIGKILL to remaining processes...
[ 147.002287] systemd-shutdown[1]: Unmounting file systems.
[ 147.010530] [393]: Remounting '/' read-only in with options 'data=ordered'.
[ 147.052598] EXT4-fs (mmcblk0p1): re-mounted. Opts: data=ordered
[ 147.064237] systemd-shutdown[1]: All filesystems unmounted.
[ 147.071268] systemd-shutdown[1]: Deactivating swaps.
[ 147.076506] systemd-shutdown[1]: All swaps deactivated.
[ 147.081602] systemd-shutdown[1]: Detaching loop devices.
[ 147.087758] systemd-shutdown[1]: All loop devices detached.
[ 147.093287] systemd-shutdown[1]: Detaching DM devices.
[ 147.173849] shutdown: 3 output lines suppressed due to ratelimiting
[ 147.207593] shutdown[1]: Syncing filesystems and block devices.
[ 147.213996] shutdown[1]: Sending SIGTERM to remaining processes...
[ 147.226094] shutdown[1]: Sending SIGKILL to remaining processes...
[ 147.237952] shutdown[1]: Unmounting file systems.
[ 147.245025] sd-remou[398]: Remounting '/' read-only in with options 'mode=755'.
[ 147.255488] sd-remou[399]: Remounting '/oldroot/sys/kernel/config' read-only in with options ''.
[ 147.267203] sd-umoun[400]: Unmounting '/oldroot/sys/kernel/config'.
[ 147.312454] sd-remou[401]: Remounting '/oldroot/dev/mqueue' read-only in with options ''.
[ 147.323473] sd-umoun[402]: Unmounting '/oldroot/dev/mqueue'.
[ 147.372483] sd-remou[403]: Remounting '/oldroot/dev/hugepages' read-only in with options 'pagesize=2M'.
[ 147.385152] sd-umoun[404]: Unmounting '/oldroot/dev/hugepages'.
[ 147.442517] sd-remou[405]: Remounting '/oldroot/sys/kernel/debug' read-only in with options ''.
[ 147.454365] sd-umoun[406]: Unmounting '/oldroot/sys/kernel/debug'.
[ 147.512414] sd-remou[407]: Remounting '/oldroot/sys/fs/cgroup/freezer' read-only in with options 'freezer'.
[ 147.525186] sd-umoun[408]: Unmounting '/oldroot/sys/fs/cgroup/freezer'.
[ 147.582499] sd-remou[409]: Remounting '/oldroot/sys/fs/cgroup/devices' read-only in with options 'devices'.
[ 147.592876] sd-remou[409]: Failed to remount '/oldroot/sys/fs/cgroup/devices' read-only: Device or resource busy
[ 147.604575] shutdown[1]: Remounting '/oldroot/sys/fs/cgroup/devices' failed abnormally, child process 409 aborted or exited non-zero.
[ 147.618325] sd-remou[410]: Remounting '/oldroot/sys/fs/cgroup/hugetlb' read-only in with options 'hugetlb'.
[ 147.631134] sd-umoun[411]: Unmounting '/oldroot/sys/fs/cgroup/hugetlb'.
[ 147.672460] sd-remou[412]: Remounting '/oldroot/sys/fs/cgroup/rdma' read-only in with options 'rdma'.
[ 147.684747] sd-umoun[413]: Unmounting '/oldroot/sys/fs/cgroup/rdma'.
[ 147.732431] sd-remou[414]: Remounting '/oldroot/sys/fs/cgroup/memory' read-only in with options 'memory'.
[ 147.742655] sd-remou[414]: Failed to remount '/oldroot/sys/fs/cgroup/memory' read-only: Device or resource busy
[ 147.754447] shutdown[1]: Remounting '/oldroot/sys/fs/cgroup/memory' failed abnormally, child process 414 aborted or exited non-zero.
[ 147.768460] sd-remou[415]: Remounting '/oldroot/sys/fs/cgroup/blkio' read-only in with options 'blkio'.
[ 147.781212] sd-umoun[416]: Unmounting '/oldroot/sys/fs/cgroup/blkio'.
[ 147.832757] sd-remou[417]: Remounting '/oldroot/sys/fs/cgroup/cpu,cpuacct' read-only in with options 'cpu,cpuacct'.
[ 147.846506] sd-umoun[418]: Unmounting '/oldroot/sys/fs/cgroup/cpu,cpuacct'.
[ 147.892502] sd-remou[419]: Remounting '/oldroot/sys/fs/cgroup/net_cls,net_prio' read-only in with options 'net_cls,net_prio'.
[ 147.907205] sd-umoun[420]: Unmounting '/oldroot/sys/fs/cgroup/net_cls,net_prio'.
[ 147.952520] sd-remou[421]: Remounting '/oldroot/sys/fs/cgroup/pids' read-only in with options 'pids'.
[ 147.962359] sd-remou[421]: Failed to remount '/oldroot/sys/fs/cgroup/pids' read-only: Device or resource busy
[ 147.973557] shutdown[1]: Remounting '/oldroot/sys/fs/cgroup/pids' failed abnormally, child process 421 aborted or exited non-zero.
[ 147.987480] sd-remou[422]: Remounting '/oldroot/sys/fs/cgroup/perf_event' read-only in with options 'perf_event'.
[ 148.001080] sd-umoun[423]: Unmounting '/oldroot/sys/fs/cgroup/perf_event'.
[ 148.052509] sd-remou[424]: Remounting '/oldroot/sys/fs/cgroup/cpuset' read-only in with options 'cpuset'.
[ 148.065937] sd-umoun[425]: Unmounting '/oldroot/sys/fs/cgroup/cpuset'.
[ 148.122568] sd-remou[426]: Remounting '/oldroot/sys/fs/bpf' read-only in with options 'mode=700'.
[ 148.134426] sd-umoun[427]: Unmounting '/oldroot/sys/fs/bpf'.
[ 148.192521] sd-remou[428]: Remounting '/oldroot/sys/fs/pstore' read-only in with options ''.
[ 148.203857] sd-umoun[429]: Unmounting '/oldroot/sys/fs/pstore'.
[ 148.252644] sd-remou[430]: Remounting '/oldroot/sys/fs/cgroup/systemd' read-only in with options 'xattr,name=systemd'.
[ 148.263925] sd-remou[430]: Failed to remount '/oldroot/sys/fs/cgroup/systemd' read-only: Device or resource busy
[ 148.275637] shutdown[1]: Remounting '/oldroot/sys/fs/cgroup/systemd' failed abnormally, child process 430 aborted or exited non-zero.
[ 148.289800] sd-remou[431]: Remounting '/oldroot/sys/fs/cgroup/unified' read-only in with options 'nsdelegate'.
[ 148.303074] sd-umoun[432]: Unmounting '/oldroot/sys/fs/cgroup/unified'.
[ 148.362466] sd-umoun[433]: Unmounting '/oldroot/sys/fs/cgroup'.
[ 148.368307] sd-umoun[433]: Failed to unmount /oldroot/sys/fs/cgroup: Device or resource busy
[ 148.378384] shutdown[1]: Unmounting '/oldroot/sys/fs/cgroup' failed abnormally, child process 433 aborted or exited non-zero.
[ 148.391407] sd-remou[434]: Remounting '/oldroot/dev/pts' read-only in with options 'gid=5,mode=620,ptmxmode=000'.
[ 148.404766] sd-umoun[435]: Unmounting '/oldroot/dev/pts'.
[ 148.452532] sd-remou[436]: Remounting '/oldroot/dev/shm' read-only in with options ''.
[ 148.463365] sd-umoun[437]: Unmounting '/oldroot/dev/shm'.
[ 148.522479] sd-remou[438]: Remounting '/oldroot/sys/kernel/security' read-only in with options ''.
[ 148.534339] sd-umoun[439]: Unmounting '/oldroot/sys/kernel/security'.
[ 148.582458] sd-umoun[440]: Unmounting '/oldroot'.
[ 148.587196] sd-umoun[440]: Failed to unmount /oldroot: Device or resource busy
[ 148.596071] shutdown[1]: Unmounting '/oldroot' failed abnormally, child process 440 aborted or exited non-zero.
[ 148.607998] sd-remou[441]: Remounting '/oldroot/run' read-only in with options 'mode=755'.
[ 148.619393] sd-umoun[442]: Unmounting '/oldroot/run'.
[ 148.662392] sd-remou[443]: Remounting '/oldroot/dev' read-only in with options 'size=472236k,nr_inodes=118059,mode=755'.
[ 148.676230] sd-umoun[444]: Unmounting '/oldroot/dev'.
[ 148.732476] sd-remou[445]: Remounting '/oldroot/sys' read-only in with options ''.
[ 148.750623] sd-umoun[446]: Unmounting '/oldroot/sys'.
[ 148.755735] sd-umoun[446]: Failed to unmount /oldroot/sys: Device or resource busy
[ 148.766287] sd-remou[447]: Remounting '/oldroot/proc' read-only in with options ''.
[ 148.778396] sd-umoun[448]: Unmounting '/oldroot/proc'.
[ 148.833055] sd-remou[449]: Remounting '/oldroot/sys/fs/cgroup/devices' read-only in with options 'devices'.
[ 148.843162] sd-remou[449]: Failed to remount '/oldroot/sys/fs/cgroup/devices' read-only: Device or resource busy
[ 148.856452] sd-remou[450]: Remounting '/oldroot/sys/fs/cgroup/memory' read-only in with options 'memory'.
[ 148.866551] sd-remou[450]: Failed to remount '/oldroot/sys/fs/cgroup/memory' read-only: Device or resource busy
[ 148.879823] sd-remou[451]: Remounting '/oldroot/sys/fs/cgroup/pids' read-only in with options 'pids'.
[ 148.889401] sd-remou[451]: Failed to remount '/oldroot/sys/fs/cgroup/pids' read-only: Device or resource busy
[ 148.902308] sd-remou[452]: Remounting '/oldroot/sys/fs/cgroup/systemd' read-only in with options 'xattr,name=systemd'.
[ 148.913500] sd-remou[452]: Failed to remount '/oldroot/sys/fs/cgroup/systemd' read-only: Device or resource busy
[ 148.926726] sd-umoun[453]: Unmounting '/oldroot/sys/fs/cgroup'.
[ 148.932736] sd-umoun[453]: Failed to unmount /oldroot/sys/fs/cgroup: Device or resource busy
[ 148.944187] sd-umoun[454]: Unmounting '/oldroot'.
[ 148.949027] sd-umoun[454]: Failed to unmount /oldroot: Device or resource busy
[ 148.959507] sd-umoun[455]: Unmounting '/oldroot/sys'.
[ 148.964681] sd-umoun[455]: Failed to unmount /oldroot/sys: Device or resource busy
[ 148.976273] sd-remou[456]: Remounting '/oldroot/sys/fs/cgroup/devices' read-only in with options 'devices'.
[ 148.986462] sd-remou[456]: Failed to remount '/oldroot/sys/fs/cgroup/devices' read-only: Device or resource busy
[ 148.999769] sd-remou[457]: Remounting '/oldroot/sys/fs/cgroup/memory' read-only in with options 'memory'.
[ 149.009843] sd-remou[457]: Failed to remount '/oldroot/sys/fs/cgroup/memory' read-only: Device or resource busy
[ 149.023103] sd-remou[458]: Remounting '/oldroot/sys/fs/cgroup/pids' read-only in with options 'pids'.
[ 149.032649] sd-remou[458]: Failed to remount '/oldroot/sys/fs/cgroup/pids' read-only: Device or resource busy
[ 149.045410] sd-remou[459]: Remounting '/oldroot/sys/fs/cgroup/systemd' read-only in with options 'xattr,name=systemd'.
[ 149.056504] sd-remou[459]: Failed to remount '/oldroot/sys/fs/cgroup/systemd' read-only: Device or resource busy
[ 149.069917] sd-umoun[460]: Unmounting '/oldroot/sys/fs/cgroup'.
[ 149.076028] sd-umoun[460]: Failed to unmount /oldroot/sys/fs/cgroup: Device or resource busy
[ 149.087263] sd-umoun[461]: Unmounting '/oldroot'.
[ 149.091911] sd-umoun[461]: Failed to unmount /oldroot: Device or resource busy
[ 149.102988] sd-umoun[462]: Unmounting '/oldroot/sys'.
[ 149.107947] sd-umoun[462]: Failed to unmount /oldroot/sys: Device or resource busy
[ 149.122351] kvm: exiting hardware virtualization
[ 149.145894] reboot: Restarting system
TIM-1.0
WTMI-armada-17.10.1-b90dbf0
ENTER init_ddrgen
DDR_TOPOLOGY is 2 : DDR3, 2CS 512M + 512M
WTMI_CLOCK=2

Fill memory before self refresh...done

Fill memory before self refresh...done

Now in Self-refresh Mode
Restore CAS Read and Write Latency
Restore termination values to original values
Exited self-refresh ...

DLL TUNING
==============
DLL 0xc0001050[21:16]: [0,2d,16]
DLL 0xc0001050[29:24]: [6,2e,1a]
DLL 0xc0001054[21:16]: [2,2a,16]
DLL 0xc0001054[29:24]: [5,2e,19]
DLL 0xc0001074[21:16]: [0,3f,1f]
DLL 0xc0001074NOTICE: Booting Trusted Firmware
NOTICE: BL1: v1.3(release):armada-17.10.3:a3306ab
NOTICE: BL1: Built : 18:22:33, Jan 29 2NOTICE: BL2: v1.3(release):armada-17.10.3:a3306ab
NOTICE: BL2: Built : 18:22:35, Jan 29 201NOTICE: BL31: v1.3(release):armada-17.10.3:a3306ab
NOTICE: BL31:

U-Boot 2017.03-armada-17.10.1-gaee49fc (Jan 29 2018 - 18:21:49 +0800)

Model: Marvell Armada 3720 Community Board ESPRESSOBin
CPU @ 1000 [MHz]
L2 @ 800 [MHz]
TClock @ 200 [MHz]
DDR @ 800 [MHz]
DRAM: 1 GiB
U-Boot DT blob at : 000000003f7161b8
Comphy-0: USB3 5 Gbps
Comphy-1: PEX0 2.5 Gbps
Comphy-2: SATA0 6 Gbps
SATA link 0 timeout.
AHCI 0001.0300 32 slots 1 ports 6 Gbps 0x1 impl SATA mode
flags: ncq led only pmp fbss pio slum part sxs
PCIE-0: Link down
MMC: sdhci@d0000: 0, sdhci@d8000: 1
SF: Detected w25q32dw with page size 256 Bytes, erase size 4 KiB, total 4 MiB
Net: eth0: neta@30000 [PRIME]
Hit any key to stop autoboot: 0
switch to partitions #0, OK
mmc0 is current device
sdhci_transfer_data: Error detected in status(0x608000)!
** ext4fs_devread read error - block
sdhci_transfer_data: Error detected in status(0x208000)!
** ext4fs_devread read error - block
Marvell>> boot
switch to partitions #0, OK
mmc0 is current device
759 bytes read in 16 ms (45.9 KiB/s)
15129088 bytes read in 13963 ms (1 MiB/s)
8016 bytes read in 20 ms (390.6 KiB/s)
5102243 bytes read in 225 ms (21.6 MiB/s)
## Loading init Ramdisk from Legacy Image at 01100000 ...
Image Name: Ramdisk Image
Image Type: AArch64 Linux RAMDisk Image (uncompressed)
Data Size: 5102179 Bytes = 4.9 MiB
Load Address: 00000000
Entry Point: 00000000
Verifying Checksum ... OK
## Flattened Device Tree blob at 01000000
Booting using the fdt blob at 0x1000000
Loading Ramdisk to 3f236000, end 3f713a63 ... OK
Using Device Tree in place at 0000000001000000, end 0000000001004f4f

Starting kernel ...

[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[ 0.000000] Linux version 4.16.6-1-ARCH (builduser@leming) (gcc version 7.3.1 20180406 (GCC)) #1 SMP Sun Apr 29 18:10:10 MDT 2018
[ 0.000000] Machine model: Globalscale Marvell ESPRESSOBin Board
[ 0.000000] earlycon: ar3700_uart0 at MMIO 0x00000000d0012000 (options '')
[ 0.000000] bootconsole [ar3700_uart0] enabled
[ 0.000000] efi: Getting EFI parameters from FDT:
[ 0.000000] efi: UEFI not found.
[ 0.000000] cma: Reserved 64 MiB at 0x000000003b000000
[ 0.000000] psci: probing for conduit method from DT.
[ 0.000000] psci: PSCIv1.0 detected in firmware.
[ 0.000000] psci: Using standard PSCI v0.2 function IDs
[ 0.000000] psci: MIGRATE_INFO_TYPE not supported.
[ 0.000000] psci: SMC Calling Convention v1.0
[ 0.000000] percpu: Embedded 24 pages/cpu @ (ptrval) s61080 r8192 d29032 u98304
[ 0.000000] Detected VIPT I-cache on CPU0
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 258048
[ 0.000000] Kernel command line: console=ttyMV0,115200 earlycon=ar3700_uart,0xd0012000 root=/dev/mmcblk0p1 rw rootwait
[ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[ 0.000000] Memory: 944472K/1048576K available (9020K kernel code, 1334K rwdata, 3440K rodata, 960K init, 553K bss, 38568K reserved, 65536K cma-reserved)
[ 0.000000] random: get_random_u64 called from cache_random_seq_create+0x64/0x128 with crng_init=0
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[ 0.000000] ftrace: allocating 32270 entries in 127 pages
[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] Tasks RCU enabled.
[ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[ 0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[ 0.000000] GICv3: Distributor has no Range Selector support
[ 0.000000] GICv3: no VLPI support, no direct LPI support
[ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000d1d40000
[ 0.000000] arch_timer: cp15 timer(s) running at 12.50MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049cda, max_idle_ns: 440795202628 ns
[ 0.000004] sched_clock: 56 bits at 12MHz, resolution 80ns, wraps every 4398046511080ns
[ 0.008452] Console: colour dummy device 80x25
[ 0.012959] Calibrating delay loop (skipped), value calculated using timer frequency.. 25.00 BogoMIPS (lpj=125000)
[ 0.023611] pid_max: default: 32768 minimum: 301
[ 0.028538] Security Framework initialized
[ 0.032739] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[ 0.039585] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[ 0.048082] ASID allocator initialised with 32768 entries
[ 0.053671] Hierarchical SRCU implementation.
[ 0.059302] EFI services will not be available.
[ 0.064235] smp: Bringing up secondary CPUs ...
[ 0.069260] Detected VIPT I-cache on CPU1
[ 0.069293] GICv3: CPU1: found redistributor 1 region 0:0x00000000d1d60000
[ 0.069332] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[ 0.069437] smp: Brought up 1 node, 2 CPUs
[ 0.091499] SMP: Total of 2 processors activated.
[ 0.096330] CPU features: detected feature: GIC system register CPU interface
[ 0.103684] CPU features: detected feature: 32-bit EL0 Support
[ 0.109679] CPU features: detected feature: Kernel page table isolation (KPTI)
[ 0.120956] CPU: All CPU(s) started at EL2
[ 0.125094] alternatives: patching kernel code
[ 0.130713] devtmpfs: initialized
[ 0.136159] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.146257] futex hash table entries: 512 (order: 4, 65536 bytes)
[ 0.154726] pinctrl core: initialized pinctrl subsystem
[ 0.160555] DMI not present or invalid.
[ 0.164746] NET: Registered protocol family 16
[ 0.170164] cpuidle: using governor ladder
[ 0.174417] cpuidle: using governor menu
[ 0.178489] vdso: 2 pages (1 code @ (ptrval), 1 data @ (ptrval))
[ 0.186043] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[ 0.193980] DMA: preallocated 256 KiB pool for atomic allocations
[ 0.200379] Serial: AMBA PL011 UART driver
[ 0.205438] mvebu-uart d0012000.serial: could not find pctldev for node /soc/internal-regs@d0000000/pinctrl@13800/uart1-pins, deferring probe
[ 0.225663] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.232787] cryptd: max_cpu_qlen set to 1000
[ 0.238413] SCSI subsystem initialized
[ 0.242462] usbcore: registered new interface driver usbfs
[ 0.248055] usbcore: registered new interface driver hub
[ 0.253608] usbcore: registered new device driver usb
[ 0.259864] NetLabel: Initializing
[ 0.263283] NetLabel: domain hash size = 128
[ 0.267781] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
[ 0.273639] NetLabel: unlabeled traffic allowed by default
[ 0.279658] clocksource: Switched to clocksource arch_sys_counter
[ 0.358223] VFS: Disk quotas dquot_6.6.0
[ 0.362294] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.375138] NET: Registered protocol family 2
[ 0.380147] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes)
[ 0.387981] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.395327] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
[ 0.402107] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.408724] UDP hash table entries: 512 (order: 2, 16384 bytes)
[ 0.414773] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[ 0.421554] NET: Registered protocol family 1
[ 0.426496] RPC: Registered named UNIX socket transport module.
[ 0.432531] RPC: Registered udp transport module.
[ 0.437349] RPC: Registered tcp transport module.
[ 0.442195] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.448986] Unpacking initramfs...
[ 0.688534] Freeing initrd memory: 4980K
[ 0.693007] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available
[ 0.701117] kvm [1]: 8-bit VMID
[ 0.705124] kvm [1]: vgic-v2@d1da0000
[ 0.708824] kvm [1]: GIC system register CPU interface enabled
[ 0.714879] kvm [1]: vgic interrupt IRQ1
[ 0.718911] kvm [1]: Hyp mode initialized successfully
[ 0.725393] Initialise system trusted keyrings
[ 0.730160] workingset: timestamp_bits=46 max_order=18 bucket_order=0
[ 0.740057] zbud: loaded
[ 0.744735] NFS: Registering the id_resolver key type
[ 0.749922] Key type id_resolver registered
[ 0.754156] Key type id_legacy registered
[ 0.758285] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[ 2.072732] Key type asymmetric registered
[ 2.076872] Asymmetric key parser 'x509' registered
[ 2.082227] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[ 2.089956] io scheduler noop registered
[ 2.093908] io scheduler deadline registered
[ 2.098535] io scheduler cfq registered (default)
[ 2.103316] io scheduler mq-deadline registered
[ 2.107952] io scheduler kyber registered
[ 2.112280] io scheduler bfq registered
[ 2.116356] atomic64_test: passed
[ 2.122669] PCI: OF: host bridge /soc/pcie@d0070000 ranges:
[ 2.128346] PCI: OF: MEM 0xe8000000..0xe8ffffff -> 0xe8000000
[ 2.134521] PCI: OF: IO 0xe9000000..0xe900ffff -> 0xe9000000
[ 3.140691] advk-pcie d0070000.pcie: link never came up
[ 3.146146] advk-pcie d0070000.pcie: PCI host bridge to bus 0000:00
[ 3.152540] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 3.158163] pci_bus 0000:00: root bus resource [mem 0xe8000000-0xe8ffffff]
[ 3.165252] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] (bus address [0xe9000000-0xe900ffff])
[ 4.119649] advk-pcie d0070000.pcie: config read/write timed out
[ 4.126974] mv_xor d0060900.xor: Marvell shared XOR driver
[ 4.190660] mv_xor d0060900.xor: Marvell XOR (Descriptor Mode): ( xor cpy intr )
[ 4.260626] mv_xor d0060900.xor: Marvell XOR (Descriptor Mode): ( xor cpy intr )
[ 4.268697] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 4.276352] cacheinfo: Unable to detect cache hierarchy for CPU 0
[ 4.283233] ahci-mvebu d00e0000.sata: AHCI 0001.0300 32 slots 1 ports 6 Gbps 0x1 impl platform mode
[ 4.292516] ahci-mvebu d00e0000.sata: flags: ncq sntf led only pmp fbs pio slum part sxs
[ 4.301841] scsi host0: ahci-mvebu
[ 4.305578] ata1: SATA max UDMA/133 mmio [mem 0xd00e0000-0xd00e1fff] port 0x100 irq 20
[ 4.314322] libphy: Fixed MDIO Bus: probed
[ 4.318662] libphy: orion_mdio_bus: probed
[ 4.324037] mvneta d0030000.ethernet eth0: Using device tree mac address f0:ad:4e:05:33:da
[ 4.332587] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 4.339252] ehci-pci: EHCI PCI platform driver
[ 4.343864] ehci-platform: EHCI generic platform driver
[ 4.349254] ehci-orion: EHCI orion driver
[ 4.353459] orion-ehci d005e000.usb: EHCI Host Controller
[ 4.358949] orion-ehci d005e000.usb: new USB bus registered, assigned bus number 1
[ 4.367076] orion-ehci d005e000.usb: irq 12, io mem 0xd005e000
[ 4.399681] orion-ehci d005e000.usb: USB 2.0 started, EHCI 1.00
[ 4.405882] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 4.412804] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 4.420236] usb usb1: Product: EHCI Host Controller
[ 4.425245] usb usb1: Manufacturer: Linux 4.16.6-1-ARCH ehci_hcd
[ 4.431435] usb usb1: SerialNumber: d005e000.usb
[ 4.436677] hub 1-0:1.0: USB hub found
[ 4.440496] hub 1-0:1.0: 1 port detected
[ 4.445161] xhci-hcd d0058000.usb: xHCI Host Controller
[ 4.450503] xhci-hcd d0058000.usb: new USB bus registered, assigned bus number 2
[ 4.458512] xhci-hcd d0058000.usb: hcc params 0x0a000998 hci version 0x100 quirks 0x00010010
[ 4.467190] xhci-hcd d0058000.usb: irq 11, io mem 0xd0058000
[ 4.473220] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
[ 4.480153] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 4.487566] usb usb2: Product: xHCI Host Controller
[ 4.492594] usb usb2: Manufacturer: Linux 4.16.6-1-ARCH xhci-hcd
[ 4.498765] usb usb2: SerialNumber: d0058000.usb
[ 4.503994] hub 2-0:1.0: USB hub found
[ 4.507790] hub 2-0:1.0: 1 port detected
[ 4.512078] xhci-hcd d0058000.usb: xHCI Host Controller
[ 4.517383] xhci-hcd d0058000.usb: new USB bus registered, assigned bus number 3
[ 4.525075] usb usb3: We don't know the algorithms for LPM for this host, disabling LPM.
[ 4.533426] usb usb3: New USB device found, idVendor=1d6b, idProduct=0003
[ 4.540348] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 4.547771] usb usb3: Product: xHCI Host Controller
[ 4.552804] usb usb3: Manufacturer: Linux 4.16.6-1-ARCH xhci-hcd
[ 4.558971] usb usb3: SerialNumber: d0058000.usb
[ 4.564379] hub 3-0:1.0: USB hub found
[ 4.568175] hub 3-0:1.0: 1 port detected
[ 4.572605] usbcore: registered new interface driver uas
[ 4.578075] usbcore: registered new interface driver usb-storage
[ 4.584221] usbcore: registered new interface driver ums-cypress
[ 4.590391] usbcore: registered new interface driver ums_eneub6250
[ 4.596744] usbcore: registered new interface driver ums-freecom
[ 4.602932] usbcore: registered new interface driver ums-isd200
[ 4.609014] usbcore: registered new interface driver ums-usbat
[ 4.615631] device-mapper: uevent: version 1.0.3
[ 4.620786] device-mapper: ioctl: 4.37.0-ioctl (2017-09-20) initialised: dm-devel@redhat.com
[ 4.629746] sdhci: Secure Digital Host Controller Interface driver
[ 4.636027] sdhci: Copyright(c) Pierre Ossman
[ 4.640520] sdhci-pltfm: SDHCI platform and OF driver helper
[ 4.644598] ata1: SATA link down (SStatus 0 SControl 300)
[ 4.646574] xenon-sdhci d00d0000.sdhci: Got CD GPIO
[ 4.657239] ledtrig-cpu: registered to indicate activity on CPUs
[ 4.663541] hidraw: raw HID events driver (C) Jiri Kosina
[ 4.669113] usbcore: registered new interface driver usbhid
[ 4.674776] usbhid: USB HID core driver
[ 4.679075] drop_monitor: Initializing network drop monitor service
[ 4.685769] Initializing XFRM netlink socket
[ 4.690114] NET: Registered protocol family 17
[ 4.694908] Key type dns_resolver registered
[ 4.699823] registered taskstats version 1
[ 4.703954] Loading compiled-in X.509 certificates
[ 4.709016] zswap: loaded using pool lzo/zbud
[ 4.721050] Key type big_key registered
[ 4.731747] Key type encrypted registered
[ 4.737601] d0012000.serial: ttyMV0 at MMIO 0xd0012000 (irq = 0, base_baud = 1562500) is a mvebu-uart
[ 4.747079] console [ttyMV0] enabled
[ 4.747079] console [ttyMV0] enabled
[ 4.754414] bootconsole [ar3700_uart0] disabled
[ 4.754414] bootconsole [ar3700_uart0] disabled
[ 4.764784] xenon-sdhci d00d0000.sdhci: Got CD GPIO
[ 64.809653] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 64.815351] 0-...0: (0 ticks this GP) idle=75a/1/4611686018427387906 softirq=80/80 fqs=3000
[ 64.823816] (detected by 1, t=6002 jiffies, g=-265, c=-266, q=9)
[ 64.830029] Task dump for CPU 0:
[ 64.833809] kworker/0:1 R running task 0 22 2 0x00000022
[ 64.840766] Workqueue: events deferred_probe_work_func
[ 64.846420] Call trace:
[ 64.848774] __switch_to+0x74/0x98
[ 64.852372] 0xffff80003a1ede80
[ 243.749664] INFO: task swapper/0:1 blocked for more than 120 seconds.
[ 243.755975] Not tainted 4.16.6-1-ARCH #1
[ 243.760578] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 243.768756] swapper/0 D 0 1 0 0x00000020
[ 243.774346] Call trace:
[ 243.777220] __switch_to+0x74/0x98
[ 243.780300] __schedule+0x21c/0x860
[ 243.784069] schedule+0x2c/0x88
[ 243.787311] schedule_timeout+0x1dc/0x400
[ 243.791549] wait_for_common+0x114/0x1d8
[ 243.795681] wait_for_completion+0x28/0x38
[ 243.799832] flush_work+0x130/0x238
[ 243.803424] deferred_probe_initcall+0x38/0x50
[ 243.807926] do_one_initcall+0x44/0x138
[ 243.812076] kernel_init_freeable+0x198/0x238
[ 243.816656] kernel_init+0x18/0x110
[ 243.820093] ret_from_fork+0x10/0x1c
[ 243.823698] INFO: task irq/41-d00d0000:104 blocked for more than 120 seconds.
[ 243.831341] Not tainted 4.16.6-1-ARCH #1
[ 243.835757] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 243.843777] irq/41-d00d0000 D 0 104 2 0x00000020
[ 243.849528] Call trace:
[ 243.852061] __switch_to+0x74/0x98
[ 243.855564] __schedule+0x21c/0x860
[ 243.859343] schedule+0x2c/0x88
[ 243.862242] kthread+0xd8/0x138
[ 243.865473] ret_from_fork+0x10/0x1c
[ 244.859649] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 244.865152] 0-...0: (0 ticks this GP) idle=75a/1/4611686018427387906 softirq=80/80 fqs=11995
[ 244.874147] (detected by 1, t=24007 jiffies, g=-265, c=-266, q=9)
[ 244.880452] Task dump for CPU 0:
[ 244.883515] kworker/0:1 R running task 0 22 2 0x00000022
[ 244.891170] Workqueue: events deferred_probe_work_func
[ 244.896567] Call trace:
[ 244.898917] __switch_to+0x74/0x98
[ 244.902517] 0xffff80003a1ede80
[ 366.629662] INFO: task swapper/0:1 blocked for more than 120 seconds.
[ 366.635971] Not tainted 4.16.6-1-ARCH #1
[ 366.640665] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 366.648752] swapper/0 D 0 1 0 0x00000020
[ 366.654430] Call trace:
[ 366.657214] __switch_to+0x74/0x98
[ 366.660651] __schedule+0x21c/0x860
[ 366.664154] schedule+0x2c/0x88
[ 366.667306] schedule_timeout+0x1dc/0x400
[ 366.671455] wait_for_common+0x114/0x1d8
[ 366.675587] wait_for_completion+0x28/0x38
[ 366.679825] flush_work+0x130/0x238
[ 366.683330] deferred_probe_initcall+0x38/0x50
[ 366.688009] do_one_initcall+0x44/0x138
[ 366.692070] kernel_init_freeable+0x198/0x238
[ 366.696652] kernel_init+0x18/0x110
[ 366.699999] ret_from_fork+0x10/0x1c
[ 366.703693] INFO: task irq/41-d00d0000:104 blocked for more than 120 seconds.
[ 366.711161] Not tainted 4.16.6-1-ARCH #1
[ 366.715743] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 366.723673] irq/41-d00d0000 D 0 104 2 0x00000020
[ 366.729426] Call trace:
[ 366.732226] __switch_to+0x74/0x98
[ 366.735383] __schedule+0x21c/0x860
[ 366.739162] schedule+0x2c/0x88
[ 366.742322] kthread+0xd8/0x138
[ 366.745465] ret_from_fork+0x10/0x1c
[ 424.909649] INFO: rcu_sched detected stalls on CPUs/tasks:
[ 424.915152] 0-...0: (0 ticks this GP) idle=75a/1/4611686018427387906 softirq=80/80 fqs=20991
[ 424.924147] (detected by 1, t=42012 jiffies, g=-265, c=-266, q=9)
[ 424.930451] Task dump for CPU 0:
[ 424.933962] kworker/0:1 R running task 0 22 2 0x00000022
[ 424.940907] Workqueue: events deferred_probe_work_func
[ 424.946301] Call trace:
[ 424.949004] __switch_to+0x74/0x98
[ 424.952604] 0xffff80003a1ede80
')

Additionally I pressed reset when console was asking for login to skip the kernel shutdown procedure. Reboot also fails.

TL;DR:
Cold boot (boot by supplying 12V) always works.
Need both reinserting SD card and pressing reset button to reboot with 12V supply on.
$this->bbcode_second_pass_code('', '
RESET
→→[Cold boot]→→→→→→→→→→←←←←←←←←←←←←←
R↑ ↓sudo reboot ↓ ↑
E↑ [U-boot sdhci_transfer_data error]
S↑ ↓Reinsert SD card
E↑←[U-boot Console]
T↑ ↓"boot"
←←[Deadlock]
')

Still too long:
Don't buy Kingston.
Excalibur
 
Posts: 4
Joined: Sun Apr 22, 2018 11:22 pm

Re: ESPRESSObin U-boot can't read Kingston SD card after reb

Postby bleedair » Sun Jul 01, 2018 10:21 pm

Glad to see your post and that I'm not the only one with this problem. Received my Espressobin this morning. After following the instructions online I managed to get it booted up just fine. However I noticed that if I tried to reboot the system it failed with the exact same errors you gave. Not knowing what to do I repeated the U-Boot process from the instructions. Everything works until I type boot, then Im given a fs/block error. Seeing your post I tried ejecting/reinserting the SD card and hitting reset, it booted up just fine.

Just an extra data point: I'm not using a Kingston card, I grabbed a 'SanDisk Extreme Plus' card.

I hope to find a fix and post it, but I'm very new to the ALARM/ARM worlds, so I don't think I'll have much luck.
bleedair
 
Posts: 1
Joined: Sun Jul 01, 2018 10:12 pm

Re: ESPRESSObin U-boot can't read Kingston SD card after reb

Postby leothrix » Wed Aug 01, 2018 4:17 am

Same issue here, I came looking for some other reports (or solutions) but it's too bad there's not a definitive culprit.

The aforementioned Armbian link mentions that a 1000 MHz clock can cause instability, and I can confirm that mine is running at that frequency. Is it possible that scaling the CPU frequency to something like 800 Mhz could conceivably make a difference?
leothrix
 
Posts: 4
Joined: Wed Aug 01, 2018 4:09 am

Re: ESPRESSObin U-boot can't read Kingston SD card after reb

Postby danglin » Fri Aug 17, 2018 2:31 pm

Same issue here with Team Group SD card:

Device: sdhci@d0000
Manufacturer ID: 45
OEM: 2d42
Name: TeamTran Speed: 50000000
Rd Block Len: 512
SD version 3.0
High Capacity: Yes
Capacity: 29.1 GiB
Bus Width: 4-bit
Erase Group Size: 512 Bytes

Cold boot is almost always successful but reboot from Linux almost always fails.

The error only occurs in u-boot (currently U-Boot 2017.03-armada-17.10.3-g06ad760-armbian). I've never
seen the error in Linux.

The error is RD_DATA_CRC_ERR (Data CRC Error). Because the errors are random, I tend to think u-boot
may not be setting up the clock control register (RD00D002h) correctly.
danglin
 
Posts: 2
Joined: Fri Aug 17, 2018 2:01 pm

Re: ESPRESSObin U-boot can't read Kingston SD card after reb

Postby msmol » Wed Sep 19, 2018 1:52 am

I have the same issue with

SanDisk Extreme 32GB microSDHC UHS-I Card - SDSQXAF-032G-GN6MA

Does anybody know of a 32gb or larger micro SD card that is known to _not_ have this issue?
msmol
 
Posts: 1
Joined: Wed Sep 19, 2018 1:51 am

Re: ESPRESSObin U-boot can't read Kingston SD card after reb

Postby danglin » Wed Sep 19, 2018 2:24 pm

Since I wrote my previous post, I also tried a SanDisk Extreme 32GB microSDHC UHS-I Card - SDSQXAF-032G-GN6MA and it seems to be working.

However, I have slowed CPU to 600 MHz, disabled frequency scaling in kernel, and put a heat sink on the Armada 3720.
The Team SD card still fails with this configuration.

Testing with sbc-bench indicated that frequency scaling resulted in an effective CPU performance of 800 MHz at
1 and 1.2 GHz.

I have two boards. Both boards had problems running ntp. After a period of time, ntp would lose sync on one board and
the system time would start to drift (about one minute per day). The other board didn't lose lock but ntpq indicated that
the clock had a large amount of jitter (~30 ms). From startup, ntp would sync and run okay for a period of time.

I suspect this is a thermal issue but I don't really know.

I'm currently trying to figure out what to do about IGMP and multicast packets. The Marvell DSA configuration has
IGMP snooping turned on and multicast packets are not flooded between the wan, lan0 and lan1 ports.
danglin
 
Posts: 2
Joined: Fri Aug 17, 2018 2:01 pm


Return to ARMv8 Devices

Who is online

Users browsing this forum: No registered users and 6 guests