PINE64: aw_mmc0: controller timeout aw_mmc0: timeout updating clock

Sat Jun 9 17:31:21 UTC 2018

 Hi,

On Sat, 9 Jun 2018 18:07:11 +0200
"O. Hartmann" <ohartmann at walstatt.org> wrote:

> -----BEGIN PGP SIGNED MESSAGE-----
> Hash: SHA512
> 
> Running a PINE64 with a recent CURRENT ( FreeBSD 12.0-CURRENT #27 r334874: Sat Jun  9
> 09:31:07 CEST 2018 aarch64) and trying to update the system via "pkg Base" (self compiled
> on a amd64 system), it turns out, that updating the Pine64 on such a way is a ever
> lasting job. 
> 
> Downloading is a task I need a lot of patience even with the 1 GBit NIC of the Pine64,
> but extracting turns out to be a slow as the movement of a glacier and most of the time
> my Pine64 crashes due to the error shown below.
> 
> The SD card is not the fastest one, dmesg reports
> 
> mmcsd0: 16GB <SDHC SD16G 3.0 SN 01125EA3 MFG 06/2015 by 39 PH> at mmc0
> 50.0MHz/4bit/32768-block
> 
> but on other systems the performance is sufficient - but not on the Pine64. I haven't
> checked with other SD cards due to the lack of other types, so if the reason for the
> timing problems is the SD card and not the driver, I'll check with another one. 
> 
> But could someone check?

  I know there is still a bug somewhere in the driver that cause this,
I use to be able to reproduce it with pkg install llvm50 but not
anymore after my latest commits. I guess I have a new test case.
 What's the size of the FreeBSD-runtime-debug-12.0.s20180609073121
package ?

> What is about MMCCAM? Is it worth giving that a try or is its development state to risky
> regarding the usage on a Pine64?

 No support for MMCCAM in this driver, kibab@ is working on it.

> Thanks in advance,
> 
> oh
> 
> [...]
> Proceed with this action? [y/N]: y
> [1/379] Upgrading FreeBSD-runtime-debug from 12.0.s20180602132042 to
> 12.0.s20180609073121... [1/379] Extracting FreeBSD-runtime-debug-12.0.s20180609073121:
> 89%aw_mmc0: controller timeout aw_mmc0: timeout updating clock
> mmcsd0: Error indicated: 1 Timeout
> g_vfs_done():ufs/rootfs[WRITE(offset=131072, length=32768)]error = 5
> softdep_update_inodeblock: bwrite: got error 5 while accessing filesystem
> aw_mmc0: controller timeout
> aw_mmc0: timeout updating clock
> mmcsd0: Error indicated: 1 Timeout
> g_vfs_done():ufs/rootfs[WRITE(offset=196608, length=32768)]error = 5
> aw_mmc0: controller timeout
> aw_mmc0: timeout updating clock
> mmcsd0: Error indicated: 1 Timeout
> g_vfs_done():ufs/rootfs[WRITE(offset=425984, length=32768)]error = 5
> aw_mmc0: controller timeout
> aw_mmc0: timeout updating clock
> mmcsd0: Error indicated: 1 Timeout
> g_vfs_done():ufs/rootfs[WRITE(offset=2195456, length=32768)]error = 5
> aw_mmc0: controller timeout
> aw_mmc0: timeout updating clock
> mmcsd0: Error indicated: 1 Timeout
> g_vfs_done():ufs/rootfs[WRITE(offset=2228224, length=32768)]error = 5
> 
> pkg: Fail to truncate
> file: /usr/lib/debug/usr/sbin/.praliases.debug.RVoslDv5e7DD:Input/output error aw_mmc0:
> controller timeout aw_mmc0: timeout updating clock
> mmcsd0: Error indicated: 1 Timeout
> g_vfs_done():ufs/rootfs[WRITE(offset=22646784, length=4096)]error = 5
> aw_mmc0: controller timeout
> aw_mmc0: timeout updating clock
> mmcsd0: Error indicated: 1 Timeout
> g_vfs_done():ufs/rootfs[WRITE(offset=654704640, length=32768)]error = 5
> 
> aw_mmc0: controller timeout
> aw_mmc0: timeout updating clock
> mmcsd0: Error indicated: 1 Timeout
> g_vfs_done():ufs/rootfs[WRITE(offset=1303248896, length=32768)]error = 5
> aw_mmc0: controller timeout
> 
> [...]
> 
> aw_mmc0: controller timeout
> aw_mmc0: timeout updating clock
> mmcsd0: Error indicated: 1 Timeout
> g_vfs_done():panic: vm_fault_hold: fault on nofault entry, addr: 0xffff000043330000
> cpuid = 1
> time = 1262342837
> KDB: stack backtrace:
> #0 0xffff00000038b34c at kdb_backtrace+0x60
> #1 0xffff00000034384c at vpanic+0x1a8
> #2 0xffff0000003436a0 at panic+0x44
> #3 0xffff00000060f650 at vm_fault_hold+0x20b4
> #4 0xffff00000060d544 at vm_fault+0x60
> #5 0xffff000000666154 at data_abort+0xa0
> #6 0xffff000000665fb0 at do_el1h_sync+0x11c
> #7 0xffff000000650874 at handle_el1h_sync+0x74
> #8 0xffff00000064e8f8 at bounce_bus_dmamap_sync+0x258
> #9 0xffff00000063c7a0 at aw_mmc_request+0x32c
> #10 0xffff00000015a358 at mmc_wait_for_request+0x120
> #11 0xffff00000016319c at mmcsd_rw+0x198
> #12 0xffff000000161d98 at mmcsd_task+0x220
> #13 0xffff000000306490 at fork_exit+0x90
> Uptime: 20m2s
> 
> U-Boot SPL 2018.03 (May 29 2018 - 17:22:25 +0000)
> DRAM: 2048 MiB
> Trying to boot from MMC1
> 
> 
> U-Boot 2018.03 (May 29 2018 - 17:22:25 +0000) Allwinner Technology
> 
> CPU:   Allwinner A64 (SUN50I)
> Model: Pine64+
> DRAM:  2 GiB
> MMC:   SUNXI SD/MMC: 0
> Loading Environment from FAT... *** Warning - bad CRC, using default environment
> 
> Failed (-5)
> Loading Environment from MMC... *** Warning - bad CRC, using default environment
> 
> Failed (-5)
> In:    serial
> Out:   serial
> Err:   serial
> Net:   phy interface7
> eth0: ethernet at 01c30000
> starting USB...
> USB0:   USB EHCI 1.00
> USB1:   USB OHCI 1.0
> scanning bus 0 for devices... 1 USB Device(s) found
>        scanning usb for storage devices... 0 Storage Device(s) found
> Hit any key to stop autoboot:  2 
> 
> - -- 
> O. Hartmann
> 
> Ich widerspreche der Nutzung oder Übermittlung meiner Daten für
> Werbezwecke oder für die Markt- oder Meinungsforschung (§ 28 Abs. 4 BDSG).
> -----BEGIN PGP SIGNATURE-----
> 
> iLUEARMKAB0WIQQZVZMzAtwC2T/86TrS528fyFhYlAUCWxv7SgAKCRDS528fyFhY
> lNncAf9ElDc9ZC9cSVJbq/LrFgC6xjLczcIDUBHNGgLTyVeYWiQvo3TN8SgCiTga
> Fizga6dIHGrQ/RwmGtxeR8rfiUP2Af0Q8yVEI9vA7kX0niLn1G/IxaQXy8IFw7Wc
> Fn94C0cJr/2iqEsgSLsrqfMfaVFHlwNCBzS5Wr+Di/9y8R8Z4Qbs
> =3YP2
> -----END PGP SIGNATURE-----
> _______________________________________________
> freebsd-arm at freebsd.org mailing list
> https://lists.freebsd.org/mailman/listinfo/freebsd-arm
> To unsubscribe, send any mail to "freebsd-arm-unsubscribe at freebsd.org"

-- 
Emmanuel Vadot <manu at bidouilliste.com> <manu at freebsd.org>