案例分析（三）linux-5.4 mmc无法挂载文件系统

项目背景

• 项目：”h618-linux-5.4-bsp
• 板型：H618-p2
• 介质：mmc

问题描述

• 在基础kernel跑起来后，init尝试挂载文件系统失败报如下错误：

[/init]: Wait /dev/mmcblk0p4 ready ...
[    3.484810]
[    3.484810] insmod_device_driver
[    3.484810]
[    3.491909] sunxi_usb_udc 5100000.udc-controller: 5100000.udc-controller supply udc not found, using dummy regulator
[    3.504499] device_chose finished 139!
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[    6.556734] random: fast init done
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: /dev/mmcblk0p4 is not available!
/ #

复现方法

• linux-5.4回退到：f9d81428e127bc7d3934805c11a68a8a721d04b0
• chip/config/h616回退到：bebb9255bf39dbcf9fe2169c5e6f4cc559bb9461
• build回退到：9256cf95301f0aaf880305486f4696745d4cf243
• spl回退到：ede46e3640b0e425460afc235ed101379fca73a3
• uboot2018回退到：8fcc23e44f40ac016c811dc17df69a4617273e55
• tools回退到：a9420002b52e285da833e672ab4daea4b474b983
• 之后编译固件即可复现。

实验调试

实验一：从log中查看mmc过程

• log如下：

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 5.4.61+ (weidonghui@AwExdroid99) (aarch64-linux-gnu-gcc (Linaro GCC 5.3-2016.05) 5.3.1 20160412, GNU ld (Linaro_Binutils-2016.05) 2.25.0 Linaro 2016_02) #9 SMP PREEMPT Thu Oct 21 17:24:32 CST 2021
[    0.000000] Machine model: sun50iw9
[    0.000000] printk: bootconsole [earlycon0] enabled
[    0.000000] cma: Reserved 64 MiB at 0x00000000bc000000
[    0.000000] On node 0 totalpages: 524288
[    0.000000]   DMA32 zone: 8192 pages used for memmap
[    0.000000]   DMA32 zone: 0 pages reserved
[    0.000000]   DMA32 zone: 524288 pages, LIFO batch:63
[    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: Trusted OS migration not required
[    0.000000] psci: SMC Calling Convention v1.0
[    0.000000] percpu: Embedded 21 pages/cpu s46272 r8192 d31552 u86016
[    0.000000] pcpu-alloc: s46272 r8192 d31552 u86016 alloc=21*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 516096
[    0.000000] Kernel command line: earlyprintk=sunxi-uart,0x05000000 clk_ignore_unused initcall_debug=0 console=ttyS0,115200 loglevel=8 root=/dev/mmcblk0p4 init=/init partitions=boot-resource@mmcblk0p1:env@mmcblk0p2:boot@mmcblk0p3:rootfs@mmcblk0p4:UDISK@mmcblk0p5 cma=64M snum=fc00141db04106b23ce mac_addr= wifi_mac= bt_mac= selinux=0 specialstr= gpt=1 androidboot.mode=normal androidboot.serialno=fc00141db04106b23ce androidboot.hardware=sun50iw9p1 boot_type=2 androidboot.boot_type=2 androidboot.secure_os_exist=0 androidboot.trustchain=false gpt=1 uboot_message=2018.05-00010-g8fcc23e44f(10/15/2021-11:24:41) mbr_offset=1032192 disp_reserve=-352321536,0xea000018 androidboot.dramsize=2048
[    0.000000] printk: log_buf_len individual max cpu contribution: 4096 bytes
[    0.000000] printk: log_buf_len total cpu_extra contributions: 12288 bytes
[    0.000000] printk: log_buf_len min size: 16384 bytes
[    0.000000] printk: log_buf_len: 32768 bytes
[    0.000000] printk: early log buf free: 14144(86%)
[    0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 1962936K/2097152K available (3838K kernel code, 520K rwdata, 1760K rodata, 2880K init, 147K bss, 68680K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000]  Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] random: get_random_bytes called from start_kernel+0x2d0/0x448 with crng_init=0
[    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (virt).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[    0.000005] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[    0.008141] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[    0.017600] Console: colour dummy device 80x25
[    0.021522] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
[    0.031730] pid_max: default: 32768 minimum: 301
[    0.036732] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.043705] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.055469] ASID allocator initialised with 32768 entries
[    0.057133] rcu: Hierarchical SRCU implementation.
[    0.064846] smp: Bringing up secondary CPUs ...
[    0.067254] Detected VIPT I-cache on CPU1
[    0.067317] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.068528] Detected VIPT I-cache on CPU2
[    0.068586] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
[    0.069653] Detected VIPT I-cache on CPU3
[    0.069703] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
[    0.069807] smp: Brought up 1 node, 4 CPUs
[    0.101686] SMP: Total of 4 processors activated.
[    0.106353] CPU features: detected: 32-bit EL0 Support
[    0.111484] CPU features: detected: CRC32 instructions
[    0.116638] CPU: All CPU(s) started at EL1
[    0.120686] alternatives: patching kernel code
[    0.126907] devtmpfs: initialized
[    0.174522] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.174618] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.177323] pinctrl core: initialized pinctrl subsystem
[    0.182517] DMA: preallocated 256 KiB pool for atomic allocations
[    0.184962] cpuidle: using governor menu
[    0.185866] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.246538] ccu: sunxi ccu init OK
[    0.247593] r_ccu: sunxi ccu init OK
[    0.247641] Sunxi ccu sun50iw9-r init OK
[    0.249288] sun50iw9-r-pinctrl 7022000.pinctrl: initialized sunXi PIO driver
[    0.308664] iommu: Default domain type: Translated
[    0.309060] sunxi iommu: irq = 27
[    0.312970] SCSI subsystem initialized
[    0.313466] usbcore: registered new interface driver usbfs
[    0.313702] usbcore: registered new interface driver hub
[    0.313955] usbcore: registered new device driver usb
[    0.316376] pwm module init!
[    0.319090] input: sunxi-keyboard as /devices/virtual/input/input0
[    0.324112] i2c i2c5: pinctrl_get failed
[    0.326976] i2c i2c5: I2C failed to get resource
[    0.332687] clocksource: Switched to clocksource arch_sys_counter
[    0.370661] thermal_sys: Registered thermal governor 'step_wise'
[    0.370666] thermal_sys: Registered thermal governor 'user_space'
[    0.370722] thermal_sys: Registered thermal governor 'power_allocator'
[    0.584300] Unpacking initramfs...
[    0.803150] Freeing initrd memory: 2560K
[    0.808185] workingset: timestamp_bits=62 max_order=19 bucket_order=0
[    0.840930] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.840998] io scheduler mq-deadline registered
[    0.841038] io scheduler kyber registered
[    0.841228] atomic64_test: passed
[    0.847564] rtc_ccu: sunxi ccu init OK
[    0.862260] misc dump reg init
[    0.863381] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    0.864796] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.864908] sunxi-ehci: EHCI SUNXI driver
[    0.865384] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.867329] sunxi-ohci: OHCI SUNXI driver
[    0.872230] usbcore: registered new interface driver uas
[    0.876836] usbcore: registered new interface driver usb-storage
[    0.882680] usbcore: registered new interface driver ums-alauda
[    0.888568] usbcore: registered new interface driver ums-cypress
[    0.894542] usbcore: registered new interface driver ums-datafab
[    0.900538] usbcore: registered new interface driver ums_eneub6250
[    0.906687] usbcore: registered new interface driver ums-freecom
[    0.912681] usbcore: registered new interface driver ums-isd200
[    0.918549] usbcore: registered new interface driver ums-jumpshot
[    0.924629] usbcore: registered new interface driver ums-karma
[    0.930432] usbcore: registered new interface driver ums-onetouch
[    0.936551] usbcore: registered new interface driver ums-realtek
[    0.942466] usbcore: registered new interface driver ums-sddr09
[    0.948379] usbcore: registered new interface driver ums-sddr55
[    0.954280] usbcore: registered new interface driver ums-usbat
[    0.961879] sunxi-rtc 7000000.rtc: reset_control_get() failed
[    0.965779] sunxi-rtc 7000000.rtc: Fail to get clock 'rtc-spi'
[    0.971520] sunxi-rtc 7000000.rtc: errata__fix_alarm_day_reg_default_value(): ALARM0_DAY_REG=0, set it to 1
[    0.983242] sunxi-rtc 7000000.rtc: registered as rtc0
[    0.986271] sunxi-rtc 7000000.rtc: setting system clock to 1970-01-01T00:00:04 UTC (4)
[    0.994133] sunxi-rtc 7000000.rtc: sunxi rtc probed
[    0.999411] i2c /dev entries driver
[    1.002614] IR NEC protocol handler initialized
[    1.006930] IR RC5(x/sz) protocol handler initialized
[    1.013841] sunxi-wdt 30090a0.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0)
[    1.024156] usbcore: registered new interface driver usbhid
[    1.025346] usbhid: USB HID core driver
[    1.110613] sun50iw9-pinctrl 300b?UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U??UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U???U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU?U?U?U?U?U?U?U?U?UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U??UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U?U???property
[    1.358434] sunxi-mmc 4020000.sdmmc: smc 0 p0 err, cmd 55, RTO !!
[    1.363396] uart uart0: dma_tx_buffer (____ptrval____)
[    1.374491] sunxi-mmc 4020000.sdmmc: sdc set ios:clk 0Hz bm PP pm OFF vdd 0 width 1 timing LEGACY(SDR12) dt B
{?#4t b1po[ 7] r
            99] printk: bootconsole [earlycon0] disabled
[    1.40-0s:0p2 A [    .c i2c5: pinctrl_get failed
[    1.447325] i2c i2c5: I2C failed to get resource
[    1.455069]
[    1.455069] ************dt_cpufreq_probe start*************
[    1.463080] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.474793] cpu cpu0: cpu0 regulator not ready, retry
[    1.480488]
[    1.480488] line:140 resources_available
[    1.486682] line:357 dt_cpufreq_probe
[    1.490826] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm OD pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.503136] get ehci0-controller wakeup-source is fail.
[    1.509276] sunxi ehci0-controller don't init wakeup source
[    1.515692] [sunxi-ehci0]: probe, pdev->name: 5101000.ehci0-controller, sunxi_ehci: 0xffffffc010970300, 0x:ffffffc0109b5000, irq_no:22
[    1.529319] [sunxi-ehci0]: Not init ehci0
[    1.534347] get ohci0-controller wakeup-source is fail.
[    1.540480] sunxi ohci0-controller don't init wakeup source
[    1.546819] [sunxi-ohci0]: probe, pdev->name: 5101400.ohci0-controller, sunxi_ohci: 0xffffffc0109706d8
[    1.557316] [sunxi-ohci0]: Not init ohci0
[    1.562346] get ehci1-controller wakeup-source is fail.
[    1.568482] sunxi ehci1-controller don't init wakeup source
[    1.574810] [sunxi-ehci1]: probe, pdev->name: 5200000.ehci1-controller, sunxi_ehci: 0xffffffc010970ab0, 0x:ffffffc0109cd000, irq_no:24
[    1.588443] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm OD pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.600203] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm OD pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.612514] sunxi-ehci 5200000.ehci1-controller: 5200000.ehci1-controller supply hci not found, using dummy regulator
[    1.626261] sunxi-ehci 5200000.ehci1-controller: EHCI Host Controller
[    1.633605] sunxi-ehci 5200000.ehci1-controller: new USB bus registered, assigned bus number 1
[    1.643321] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm OD pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.655496] sunxi-ehci 5200000.ehci1-controller: irq 36, io mem 0x05200000
[    1.676821] sunxi-ehci 5200000.ehci1-controller: USB 2.0 started, EHCI 1.00
[    1.685685] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.699091] hub 1-0:1.0: USB hub found
[    1.703497] hub 1-0:1.0: 1 port detected
[    1.709765] get ohci1-controller wakeup-source is fail.
[    1.715900] sunxi ohci1-controller don't init wakeup source
[    1.722238] [sunxi-ohci1]: probe, pdev->name: 5200400.ohci1-controller, sunxi_ohci: 0xffffffc010970e88
[    1.733077] sunxi-mmc 4022000.sdmmc: avoid to switch power_off_notification to POWERED_ON(0x01)
[    1.743342] sunxi-ohci 5200400.ohci1-controller: 5200400.ohci1-controller supply hci not found, using dummy regulator
[    1.755322] sunxi-mmc 4022000.sdmmc: avoid to switch power_off_notification to POWERED_ON(0x01)
[    1.765127] sunxi-mmc 4022000.sdmmc: avoid to switch power_off_notification to POWERED_ON(0x01)
[    1.775489] sunxi-ohci 5200400.ohci1-controller: OHCI Host Controller
[    1.782809] sunxi-ohci 5200400.ohci1-controller: new USB bus registered, assigned bus number 2
[    1.792526] sunxi-mmc 4022000.sdmmc: avoid to switch power_off_notification to POWERED_ON(0x01)
[    1.802671] sunxi-ohci 5200400.ohci1-controller: irq 37, io mem 0x05200400
[    1.811289] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing MMC-HS(SDR20) dt B
[    1.823516] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 50000000Hz bm PP pm ON vdd 21 width 1 timing MMC-HS(SDR20) dt B
[    1.836162] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 50000000Hz bm PP pm ON vdd 21 width 8 timing MMC-HS(SDR20) dt B
[    1.849558] mmc1: new high speed MMC card at address 0001
[    1.858128] mmcblk1: mmc1:0001 008G30 7.28 GiB
[    1.864587] mmcblk1boot0: mmc1:0001 008G30 partition 1 4.00 MiB
[    1.877711] hub 2-0:1.0: USB hub found
[    1.883054] mmcblk1boot1: mmc1:0001 008G30 partition 2 4.00 MiB
[    1.890110] hub 2-0:1.0: 1 port detected
[    1.896269] get ehci2-controller wakeup-source is fail.
[    1.902470] sunxi ehci2-controller don't init wakeup source
[    1.908870] mmcblk1rpmb: mmc1:0001 008G30 partition 3 4.00 MiB, chardev (248:0)
[    1.917150] [sunxi-ehci2]: probe, pdev->name: 5310000.ehci2-controller, sunxi_ehci: 0xffffffc010971260, 0x:ffffffc010a05000, irq_no:26
[    1.931365] sunxi-ehci 5310000.ehci2-controller: 5310000.ehci2-controller supply hci not found, using dummy regulator
[    1.943840] sunxi-ehci 5310000.ehci2-controller: EHCI Host Controller
[    1.951146] sunxi-ehci 5310000.ehci2-controller: new USB bus registered, assigned bus number 3
[    1.961389] sunxi-ehci 5310000.ehci2-controller: irq 38, io mem 0x05310000
[    1.975848]  mmcblk1: p1 p2 p3 p4 p5
[    1.985202] sunxi-ehci 5310000.ehci2-controller: USB 2.0 started, EHCI 1.00
[    1.993079] debugfs: Directory 'sunxi-ehci' with parent 'ehci' already present!
[    2.013439] hub 3-0:1.0: USB hub found
[    2.017806] hub 3-0:1.0: 1 port detected
[    2.023926] get ohci2-controller wakeup-source is fail.
[    2.030134] sunxi ohci2-controller don't init wakeup source
[    2.036425] [sunxi-ohci2]: probe, pdev->name: 5310400.ohci2-controller, sunxi_ohci: 0xffffffc010971638
[    2.047503] sunxi-ohci 5310400.ohci2-controller: 5310400.ohci2-controller supply hci not found, using dummy regulator
[    2.059959] sunxi-ohci 5310400.ohci2-controller: OHCI Host Controller
[    2.067273] sunxi-ohci 5310400.ohci2-controller: new USB bus registered, assigned bus number 4
[    2.077126] debugfs: Directory 'sunxi-ohci' with parent 'ohci' already present!
[    2.085553] sunxi-ohci 5310400.ohci2-controller: irq 39, io mem 0x05310400
[    2.159231] hub 4-0:1.0: USB hub found
[    2.163631] hub 4-0:1.0: 1 port detected
[    2.169855] get ehci3-controller wakeup-source is fail.
[    2.175990] sunxi ehci3-controller don't init wakeup source
[    2.182278] [sunxi-ehci3]: probe, pdev->name: 5311000.ehci3-controller, sunxi_ehci: 0xffffffc010971a10, 0x:ffffffc010a7d000, irq_no:28
[    2.196524] sunxi-ehci 5311000.ehci3-controller: 5311000.ehci3-controller supply hci not found, using dummy regulator
[    2.209070] sunxi-ehci 5311000.ehci3-controller: EHCI Host Controller
[    2.216395] sunxi-ehci 5311000.ehci3-controller: new USB bus registered, assigned bus number 5
[    2.226552] sunxi-ehci 5311000.ehci3-controller: irq 40, io mem 0x05311000
[    2.248822] sunxi-ehci 5311000.ehci3-controller: USB 2.0 started, EHCI 1.00
[    2.256690] debugfs: Directory 'sunxi-ehci' with parent 'ehci' already present!
[    2.267375] hub 5-0:1.0: USB hub found
[    2.271748] hub 5-0:1.0: 1 port detected
[    2.277948] get ohci3-controller wakeup-source is fail.
[    2.284130] sunxi ohci3-controller don't init wakeup source
[    2.290420] [sunxi-ohci3]: probe, pdev->name: 5311400.ohci3-controller, sunxi_ohci: 0xffffffc010971de8
[    2.301507] sunxi-ohci 5311400.ohci3-controller: 5311400.ohci3-controller supply hci not found, using dummy regulator
[    2.314019] sunxi-ohci 5311400.ohci3-controller: OHCI Host Controller
[    2.321371] sunxi-ohci 5311400.ohci3-controller: new USB bus registered, assigned bus number 6
[    2.331213] debugfs: Directory 'sunxi-ohci' with parent 'ohci' already present!
[    2.339659] sunxi-ohci 5311400.ohci3-controller: irq 41, io mem 0x05311400
[    2.411161] hub 6-0:1.0: USB hub found
[    2.415575] hub 6-0:1.0: 1 port detected
[    2.421567]
[    2.421567] ************dt_cpufreq_probe start*************
[    2.429584] cpu cpu0: cpu0 regulator not ready, retry
[    2.435274]
[    2.435274] line:140 resources_available
[    2.441351] line:357 dt_cpufreq_probe
[    2.445790]
[    2.445790] ************dt_cpufreq_probe start*************
[    2.453794] cpu cpu0: cpu0 regulator not ready, retry
[    2.459487]
[    2.459487] line:140 resources_available
[    2.465568] line:357 dt_cpufreq_probe
[    2.470355] get det_vbus is fail, -84
[    2.474526] get id is fail, -84
[    2.479172] clk: Not disabling unused clocks
[    2.485833] Freeing unused kernel memory: 2880K
[    2.491170] Run /init as init process
[    2.491186]
[    2.491186] ************dt_cpufreq_probe start*************
[    2.503346] cpu cpu0: cpu0 regulator not ready, retry
[    2.509063]
[    2.509063] line:140 resources_available
[    2.515189] line:357 dt_cpufreq_probe
[/init]: getty is ttyS0
[/init]: RootDevice is "/dev/mmcblk0p4" , GPT_SUPPORT=1
[/init]: Try to load EMMC ...
[/init]: /lib/modules/5.4.61+/sunxi_mmc_host.ko does not exist!
insmod: can't insert '/lib/modules/5.4.61+/sunxi_mmc_host.ko': No such file or directory
[/init]: /lib/modules/5.4.61+/sunxi_mmc_host.ko is invalid!
[/init]: Wait /dev/mmcblk0p4 ready ...
[    3.484810]
[    3.484810] insmod_device_driver
[    3.484810]
[    3.491909] sunxi_usb_udc 5100000.udc-controller: 5100000.udc-controller supply udc not found, using dummy regulator
[    3.504499] device_chose finished 139!
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[    6.556734] random: fast init done
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: Wait /dev/mmcblk0p4 ready ...
[/init]: /dev/mmcblk0p4 is not available!

/ # [  216.476767] random: crng init done
/ #
/ #
/ # ls
bin    etc    init   lib64  mnt    proc   run    sys    usr
dev    home   lib    media  opt    root   sbin   tmp    var
/ #

• log[ 1.975848] mmcblk1: p1 p2 p3 p4 p5可以看出mmc驱动已经识别了分区，但却是在mmcblk1设备上。mmcblk1: mmc1:0001 008G30 7.28 GiB也可以证实mmc被初始化为啦mmcblk1，但是init try的却是mmcblk0所以挂载不上文件系统。

解决方法

方法一

• 更改env.cfg将mmc_root=/dev/mmcblk0p4改成mmc_root=/dev/mmcblk1p4。（不推荐其他平台都是mmcblk0之后不好做兼容）

方法二

• 修改sun50iw9.dtsi中的sdc0，sdc1，sdc2节点的顺序。因为dts中的设备节点在没有特殊依赖关系时会形成一个链表从上往下加载此时只需要调整一下mmc相关设备节点的顺序从sdc0->sdc1->sdc2调整为sdc2->sdc0->sdc1即可达成目的。

sdc2: sdmmc@4022000 {
....
};
sdc0: sdmmc@4020000 {
....
};
sdc1: sdmmc@4021000 {
....
};

方法三

• 将sdc设备节点status全部设为“disable”，将开启status的权限交给uboot去控制，uboot相关处理代码路径：longan/brandy/brandy-2.0/u-boot-2018/board/sunxi/board_helper.c

int sunxi_update_fdt_para_for_kernel(void)
{
...
switch (storage_type) {
case STORAGE_EMMC:
        fdt_enable_node("mmc2", 1);
        break;
case STORAGE_EMMC0:
        fdt_enable_node("mmc0", 1);
        break;
case STORAGE_EMMC3:
        fdt_enable_node("mmc3", 1);
        break;
 ....
     };
 ....
}

细心的同学会发现sdc2最后初始化完的设备节点时mmcblk1而不是mmcblk2。不是说按从上往下的顺序加载吗？原因是sdc1节点的status="disable"所以不初始化相关设备。

sdc0: sdmmc@4020000 {
....
status="disable";
};
sdc1: sdmmc@4021000 {
....
status="disable";
};
sdc2: sdmmc@4022000 {
....
status="disable";
};

修复后log

• [ 1.682542] mmcblk0: mmc0:0001 008G30 7.28 GiB
• [ 1.800297] mmcblk0: p1 p2 p3 p4 p5

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 5.4.61+ (weidonghui@AwExdroid99) (aarch64-linux-gnu-gcc (Linaro GCC 5.3-2016.05) 5.3.1 20160412, GNU ld (Linaro_Binutils-2016.05) 2.25.0 Linaro 2016_02) #10 SMP PREEMPT Tue Oct 26 10:53:42 CST 2021
[    0.000000] Machine model: sun50iw9
[    0.000000] printk: bootconsole [earlycon0] enabled
[    0.000000] cma: Reserved 64 MiB at 0x00000000bc000000
[    0.000000] On node 0 totalpages: 524288
[    0.000000]   DMA32 zone: 8192 pages used for memmap
[    0.000000]   DMA32 zone: 0 pages reserved
[    0.000000]   DMA32 zone: 524288 pages, LIFO batch:63
[    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: Trusted OS migration not required
[    0.000000] psci: SMC Calling Convention v1.0
[    0.000000] percpu: Embedded 21 pages/cpu s46272 r8192 d31552 u86016
[    0.000000] pcpu-alloc: s46272 r8192 d31552 u86016 alloc=21*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 516096
[    0.000000] Kernel command line: earlyprintk=sunxi-uart,0x05000000 clk_ignore_unused initcall_debug=0 console=ttyS0,115200 loglevel=8 root=/dev/mmcblk0p4 init=/init partitions=boot-resource@mmcblk0p1:env@mmcblk0p2:boot@mmcblk0p3:rootfs@mmcblk0p4:UDISK@mmcblk0p5 cma=64M snum=fc00141db04106b23ce mac_addr= wifi_mac= bt_mac= selinux=0 specialstr= gpt=1 androidboot.mode=normal androidboot.serialno=fc00141db04106b23ce androidboot.hardware=sun50iw9p1 boot_type=2 androidboot.boot_type=2 androidboot.secure_os_exist=0 androidboot.trustchain=false gpt=1 uboot_message=2018.05-00010-g8fcc23e44f(10/15/2021-11:24:41) mbr_offset=1032192 disp_reserve=-352321536,0xea000018 androidboot.dramsize=2048
[    0.000000] printk: log_buf_len individual max cpu contribution: 4096 bytes
[    0.000000] printk: log_buf_len total cpu_extra contributions: 12288 bytes
[    0.000000] printk: log_buf_len min size: 16384 bytes
[    0.000000] printk: log_buf_len: 32768 bytes
[    0.000000] printk: early log buf free: 14144(86%)
[    0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 1962936K/2097152K available (3838K kernel code, 520K rwdata, 1760K rodata, 2880K init, 147K bss, 68680K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000]  Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] random: get_random_bytes called from start_kernel+0x2d0/0x448 with crng_init=0
[    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (virt).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[    0.000004] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[    0.008147] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[    0.017602] Console: colour dummy device 80x25
[    0.021523] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
[    0.031730] pid_max: default: 32768 minimum: 301
[    0.036734] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.043705] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.055488] ASID allocator initialised with 32768 entries
[    0.057088] rcu: Hierarchical SRCU implementation.
[    0.064874] smp: Bringing up secondary CPUs ...
[    0.067258] Detected VIPT I-cache on CPU1
[    0.067322] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.068525] Detected VIPT I-cache on CPU2
[    0.068583] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
[    0.069664] Detected VIPT I-cache on CPU3
[    0.069714] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
[    0.069819] smp: Brought up 1 node, 4 CPUs
[    0.101687] SMP: Total of 4 processors activated.
[    0.106353] CPU features: detected: 32-bit EL0 Support
[    0.111483] CPU features: detected: CRC32 instructions
[    0.116637] CPU: All CPU(s) started at EL1
[    0.120685] alternatives: patching kernel code
[    0.126861] devtmpfs: initialized
[    0.174584] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.174680] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.177375] pinctrl core: initialized pinctrl subsystem
[    0.182607] DMA: preallocated 256 KiB pool for atomic allocations
[    0.185059] cpuidle: using governor menu
[    0.185967] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.245824] ccu: sunxi ccu init OK
[    0.246820] r_ccu: sunxi ccu init OK
[    0.246878] Sunxi ccu sun50iw9-r init OK
[    0.248475] sun50iw9-r-pinctrl 7022000.pinctrl: initialized sunXi PIO driver
[    0.308846] iommu: Default domain type: Translated
[    0.309202] sunxi iommu: irq = 27
[    0.313494] SCSI subsystem initialized
[    0.314004] usbcore: registered new interface driver usbfs
[    0.314227] usbcore: registered new interface driver hub
[    0.314483] usbcore: registered new device driver usb
[    0.316877] pwm module init!
[    0.319247] input: sunxi-keyboard as /devices/virtual/input/input0
[    0.324321] i2c i2c5: pinctrl_get failed
[    0.327156] i2c i2c5: I2C failed to get resource
[    0.332826] clocksource: Switched to clocksource arch_sys_counter
[    0.371073] thermal_sys: Registered thermal governor 'step_wise'
[    0.371078] thermal_sys: Registered thermal governor 'user_space'
[    0.371136] thermal_sys: Registered thermal governor 'power_allocator'
[    0.584768] Unpacking initramfs...
[    0.803877] Freeing initrd memory: 2560K
[    0.808944] workingset: timestamp_bits=62 max_order=19 bucket_order=0
[    0.841981] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.842050] io scheduler mq-deadline registered
[    0.842090] io scheduler kyber registered
[    0.842287] atomic64_test: passed
[    0.848622] rtc_ccu: sunxi ccu init OK
[    0.863323] misc dump reg init
[    0.864468] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    0.865881] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.865988] sunxi-ehci: EHCI SUNXI driver
[    0.866442] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.868371] sunxi-ohci: OHCI SUNXI driver
[    0.873276] usbcore: registered new interface driver uas
[    0.877818] usbcore: registered new interface driver usb-storage
[    0.883728] usbcore: registered new interface driver ums-alauda
[    0.889630] usbcore: registered new interface driver ums-cypress
[    0.895590] usbcore: registered new interface driver ums-datafab
[    0.901575] usbcore: registered new interface driver ums_eneub6250
[    0.907730] usbcore: registered new interface driver ums-freecom
[    0.913710] usbcore: registered new interface driver ums-isd200
[    0.919597] usbcore: registered new interface driver ums-jumpshot
[    0.925660] usbcore: registered new interface driver ums-karma
[    0.931462] usbcore: registered new interface driver ums-onetouch
[    0.937604] usbcore: registered new interface driver ums-realtek
[    0.943513] usbcore: registered new interface driver ums-sddr09
[    0.949404] usbcore: registered new interface driver ums-sddr55
[    0.955310] usbcore: registered new interface driver ums-usbat
[    0.962864] sunxi-rtc 7000000.rtc: reset_control_get() failed
[    0.966815] sunxi-rtc 7000000.rtc: Fail to get clock 'rtc-spi'
[    0.972533] sunxi-rtc 7000000.rtc: errata__fix_alarm_day_reg_default_value(): ALARM0_DAY_REG=0, set it to 1
[    0.984213] sunxi-rtc 7000000.rtc: registered as rtc0
[    0.987322] sunxi-rtc 7000000.rtc: setting system clock to 1970-01-01T00:00:04 UTC (4)
[    0.995181] sunxi-rtc 7000000.rtc: sunxi rtc probed
[    1.000455] i2c /dev entries driver
[    1.003652] IR NEC protocol handler initialized
[    1.007976] IR RC5(x/sz) protocol handler initialized
[    1.014804] sunxi-wdt 30090a0.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0)
[    1.025257] usbcore: registered new interface driver usbhid
[    1.026336] usbhid: USB HID core driver
[    1.110468] sun50iw9-pinctrl 300b000.pinctrl: initialized sunXi PIO driver
[    1.112430] sun50iw9-pinctrl 300b000.pinctrl: 300b000.pinctrl supply vcc-pc not found, using dummy regulator
[    1.113279] sunxi-mmc 4022000.sdmmc: SD/MMC/SDIO Host Controller Driver(v4.20 2021-05-12 11:02)
[    1.113777] sunxi-mmc 4022000.sdmmc: No vmmc regulator found
[    1.119203] sunxi-mmc 4022000.sdmmc: No vqmmc regulator found
[    1.124892] sunxi-mmc 4022000.sdmmc: No vdmmc regulator found
[    1.130611] sunxi-mmc 4022000.sdmmc: No vd33sw regulator found
[    1.136418] sunxi-mmc 4022000.sdmmc: No vd18sw regulator found
[    1.142224] sunxi-mmc 4022000.sdmmc: No vq33sw regulator found
[    1.148031] sunxi-mmc 4022000.sdmmc: No vq18sw regulator found
[    1.153925] sunxi-mmc 4022000.sdmmc: Cann't get uart0 pinstate,check if needed
[    1.161034] sunxi-mmc 4022000.sdmmc: Cann't get pin bias hs pinstate,check if needed
[    1.171222] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 0Hz bm PP pm UP vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.178689] sunxi-mmc 4022000.sdmmc: no vqmmc,Check if there is regulator
[    1.198028] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.210846] sunxi-mmc 4022000.sdmmc: detmode:alway in(non removable)
[    1.211666] sun50iw9-pinctrl 300b000.pinctrl: 300b000.pinctrl supply vcc-ph not found, using dummy regulator
[    1.212206] uart uart0: get regulator failed
[    1.216126] uart uart0: uart0 supply uart not found, using dummy regulator
[    1.223526] uart uart0: uart0 error to get fifo size property
[    1.228741] uart uart0: dma_tx_buffer (____ptrval____)
Vss▒[▒6{s▒{c+▒▒▒˛▒▒ uart0: dm▒k▒&V▒VV▒▒
  c+#k
      [    1.258558] printk: console [ttyS0] enabled
[    1.263302] printk: bootconsole [earlycon0] disabled
[    1.263302] printk: bootconsole [earlycon0] disabled
[    1.273509] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.292075] i2c i2c5: pinctrl_get failed
[    1.296554] i2c i2c5: I2C failed to get resource
[    1.304975] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.316653] get ehci0-controller wakeup-source is fail.
[    1.322767] sunxi ehci0-controller don't init wakeup source
[    1.329165] [sunxi-ehci0]: probe, pdev->name: 5101000.ehci0-controller, sunxi_ehci: 0xffffffc010970300, 0x:ffffffc0109ad000, irq_no:21
[    1.342806] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm OD pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.354463] [sunxi-ehci0]: Not init ehci0
[    1.359487] get ohci0-controller wakeup-source is fail.
[    1.365800] sunxi ohci0-controller don't init wakeup source
[    1.372107] [sunxi-ohci0]: probe, pdev->name: 5101400.ohci0-controller, sunxi_ohci: 0xffffffc0109706d8
[    1.382600] [sunxi-ohci0]: Not init ohci0
[    1.387669] get ehci1-controller wakeup-source is fail.
[    1.393815] sunxi ehci1-controller don't init wakeup source
[    1.400139] [sunxi-ehci1]: probe, pdev->name: 5200000.ehci1-controller, sunxi_ehci: 0xffffffc010970ab0, 0x:ffffffc0109c5000, irq_no:23
[    1.413766] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm OD pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.425517] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm OD pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.437842] sunxi-ehci 5200000.ehci1-controller: 5200000.ehci1-controller supply hci not found, using dummy regulator
[    1.451615] sunxi-ehci 5200000.ehci1-controller: EHCI Host Controller
[    1.458984] sunxi-ehci 5200000.ehci1-controller: new USB bus registered, assigned bus number 1
[    1.468698] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm OD pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.480836] sunxi-ehci 5200000.ehci1-controller: irq 35, io mem 0x05200000
[    1.500961] sunxi-ehci 5200000.ehci1-controller: USB 2.0 started, EHCI 1.00
[    1.509827] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B
[    1.523234] hub 1-0:1.0: USB hub found
[    1.527617] hub 1-0:1.0: 1 port detected
[    1.533888] get ohci1-controller wakeup-source is fail.
[    1.540067] sunxi ohci1-controller don't init wakeup source
[    1.546407] [sunxi-ohci1]: probe, pdev->name: 5200400.ohci1-controller, sunxi_ohci: 0xffffffc010970e88
[    1.557305] sunxi-mmc 4022000.sdmmc: avoid to switch power_off_notification to POWERED_ON(0x01)
[    1.567435] sunxi-ohci 5200400.ohci1-controller: 5200400.ohci1-controller supply hci not found, using dummy regulator
[    1.579406] sunxi-mmc 4022000.sdmmc: avoid to switch power_off_notification to POWERED_ON(0x01)
[    1.589206] sunxi-mmc 4022000.sdmmc: avoid to switch power_off_notification to POWERED_ON(0x01)
[    1.599497] sunxi-ohci 5200400.ohci1-controller: OHCI Host Controller
[    1.606838] sunxi-ohci 5200400.ohci1-controller: new USB bus registered, assigned bus number 2
[    1.616553] sunxi-mmc 4022000.sdmmc: avoid to switch power_off_notification to POWERED_ON(0x01)
[    1.626763] sunxi-ohci 5200400.ohci1-controller: irq 36, io mem 0x05200400
[    1.635615] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing MMC-HS(SDR20) dt B
[    1.647827] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 50000000Hz bm PP pm ON vdd 21 width 1 timing MMC-HS(SDR20) dt B
[    1.660464] sunxi-mmc 4022000.sdmmc: sdc set ios:clk 50000000Hz bm PP pm ON vdd 21 width 8 timing MMC-HS(SDR20) dt B
[    1.673850] mmc0: new high speed MMC card at address 0001
[    1.682542] mmcblk0: mmc0:0001 008G30 7.28 GiB
[    1.688964] mmcblk0boot0: mmc0:0001 008G30 partition 1 4.00 MiB
[    1.702125] hub 2-0:1.0: USB hub found
[    1.707458] mmcblk0boot1: mmc0:0001 008G30 partition 2 4.00 MiB
[    1.714509] hub 2-0:1.0: 1 port detected
[    1.720701] get ehci2-controller wakeup-source is fail.
[    1.726856] sunxi ehci2-controller don't init wakeup source
[    1.733250] mmcblk0rpmb: mmc0:0001 008G30 partition 3 4.00 MiB, chardev (248:0)
[    1.741525] [sunxi-ehci2]: probe, pdev->name: 5310000.ehci2-controller, sunxi_ehci: 0xffffffc010971260, 0x:ffffffc0109fd000, irq_no:25
[    1.755802] sunxi-ehci 5310000.ehci2-controller: 5310000.ehci2-controller supply hci not found, using dummy regulator
[    1.768275] sunxi-ehci 5310000.ehci2-controller: EHCI Host Controller
[    1.775594] sunxi-ehci 5310000.ehci2-controller: new USB bus registered, assigned bus number 3
[    1.785751] sunxi-ehci 5310000.ehci2-controller: irq 37, io mem 0x05310000
[    1.800297]  mmcblk0: p1 p2 p3 p4 p5
[    1.809744] sunxi-ehci 5310000.ehci2-controller: USB 2.0 started, EHCI 1.00
[    1.817648] debugfs: Directory 'sunxi-ehci' with parent 'ehci' already present!
[    1.837723] hub 3-0:1.0: USB hub found
[    1.842121] hub 3-0:1.0: 1 port detected
[    1.848337] get ohci2-controller wakeup-source is fail.
[    1.854515] sunxi ohci2-controller don't init wakeup source
[    1.860804] [sunxi-ohci2]: probe, pdev->name: 5310400.ohci2-controller, sunxi_ohci: 0xffffffc010971638
[    1.871957] sunxi-ohci 5310400.ohci2-controller: 5310400.ohci2-controller supply hci not found, using dummy regulator
[    1.884473] sunxi-ohci 5310400.ohci2-controller: OHCI Host Controller
[    1.891793] sunxi-ohci 5310400.ohci2-controller: new USB bus registered, assigned bus number 4
[    1.901620] debugfs: Directory 'sunxi-ohci' with parent 'ohci' already present!
[    1.910061] sunxi-ohci 5310400.ohci2-controller: irq 38, io mem 0x05310400
[    1.983318] hub 4-0:1.0: USB hub found
[    1.987673] hub 4-0:1.0: 1 port detected
[    1.993854] get ehci3-controller wakeup-source is fail.
[    2.000040] sunxi ehci3-controller don't init wakeup source
[    2.006326] [sunxi-ehci3]: probe, pdev->name: 5311000.ehci3-controller, sunxi_ehci: 0xffffffc010971a10, 0x:ffffffc010a76000, irq_no:27
[    2.020558] sunxi-ehci 5311000.ehci3-controller: 5311000.ehci3-controller supply hci not found, using dummy regulator
[    2.033050] sunxi-ehci 5311000.ehci3-controller: EHCI Host Controller
[    2.040366] sunxi-ehci 5311000.ehci3-controller: new USB bus registered, assigned bus number 5
[    2.050589] sunxi-ehci 5311000.ehci3-controller: irq 39, io mem 0x05311000
[    2.072971] sunxi-ehci 5311000.ehci3-controller: USB 2.0 started, EHCI 1.00
[    2.080835] debugfs: Directory 'sunxi-ehci' with parent 'ehci' already present!
[    2.091365] hub 5-0:1.0: USB hub found
[    2.095750] hub 5-0:1.0: 1 port detected
[    2.101962] get ohci3-controller wakeup-source is fail.
[    2.108112] sunxi ohci3-controller don't init wakeup source
[    2.114394] [sunxi-ohci3]: probe, pdev->name: 5311400.ohci3-controller, sunxi_ohci: 0xffffffc010971de8
[    2.125546] sunxi-ohci 5311400.ohci3-controller: 5311400.ohci3-controller supply hci not found, using dummy regulator
[    2.138087] sunxi-ohci 5311400.ohci3-controller: OHCI Host Controller
[    2.145406] sunxi-ohci 5311400.ohci3-controller: new USB bus registered, assigned bus number 6
[    2.155254] debugfs: Directory 'sunxi-ohci' with parent 'ohci' already present!
[    2.163640] sunxi-ohci 5311400.ohci3-controller: irq 40, io mem 0x05311400
[    2.235374] hub 6-0:1.0: USB hub found
[    2.239730] hub 6-0:1.0: 1 port detected
[    2.246742] get det_vbus is fail, -84
[    2.250894] get id is fail, -84
[    2.255571] clk: Not disabling unused clocks
[    2.262282] Freeing unused kernel memory: 2880K
[    2.267621] Run /init as init process
[/init]: getty is ttyS0
[/init]: RootDevice is "/dev/mmcblk0p4" , GPT_SUPPORT=1
[/init]: Try to load EMMC ...
[/init]: /lib/modules/5.4.61+/sunxi_mmc_host.ko does not exist!
insmod: can't insert '/lib/modules/5.4.61+/sunxi_mmc_host.ko': No such file or directory
[/init]: /lib/modules/5.4.61+/sunxi_mmc_host.ko is invalid!
e2fsck 1.42.12 (29-Aug-2014)
/dev/mmcblk0p4: recovering journal
Setting free inodes count to 450 (was 452)
/dev/mmcblk0p4: clean, 3502/3952 files, 85966/101127 blocks
[    2.590818] EXT4-fs (mmcblk0p4): barriers disabled
[    2.600051] EXT4-fs (mmcblk0p4): mounted filesystem with ordered data mode. Opts: noauto_da_alloc,barrier=0,data=ordered
[    2.636843] random: fast init done
[    2.750642] EXT4-fs (mmcblk0p4): re-mounted. Opts: nobarrier,noauto_da_alloc,errors=remount-ro,data=ordered
Starting logging: OK
Initializing random number generator... [    2.849019] random: dd: uninitialized urandom read (512 bytes read)
done.
Starting system message bus: [    2.901685] random: dbus-uuidgen: uninitialized urandom read (12 bytes read)
[    2.909854] random: dbus-uuidgen: uninitialized urandom read (8 bytes read)
Failed to start message bus: Failed to open socket: Function not implemented
done
Starting network: ip: socket: Function not implemented
ip: socket: Function not implemented
FAIL
/ #