Failed to bring up with a verified image

[Micky]

Which Khadas SBC do you use?

VIM3

Which system do you use? Android, Ubuntu, OOWOW or others?

Andriod

Which version of system do you use? Khadas official images, self built images, or others?

self built images (this image is verified can bring up on couple of VIM3 boards)

Please describe your issue below:

I built an Android image, it’s verified on couple of VIM3 boards.
My VIM3 device can bring up with this image before as well.
But recently, it’s failed to bring up with this identical image (no change on this image).
Not sure if any module (eMMC or other module) is broken.

Post a console log of your issue below:

U-Boot 2021.01-00035-gde44e287b6 (Feb 02 2021 - 18:02:13 +0100) khadas-vim3


Model: Khadas VIM3
SoC:   Amlogic Meson G12B (A311D) Revision 29:b (10:2)
DRAM:  3.8 GiB

MMC:   
sd@ffe03000: 0, sd@ffe05000: 1, mmc@ffe07000: 2
Loading Environment from MMC... 
*** Warning - bad CRC, using default environment


In:    serial@3000
Out:   serial@3000
Err:   serial@3000
Net:   eth0: ethernet@ff3f0000
Verify GPT: success!
switch to partitions #0, OK
mmc2(part 0) is current device

MMC read: dev # 2, block # 1024, count 4096 ... 
4096 blocks read: OK
There is no valid bmp file at the given address
Hit any key to stop autoboot:  2 
 1 
 0 
Verify GPT: success!
dev: pinctrl@14
dev: pinctrl@40
gpio: pin 88 (gpio 88) value is 1
Loading Android boot partition...
switch to partitions #0, OK
mmc2(part 0) is current device
Running without AVB...

MMC read: dev # 2, block # 23552, count 65536 ... 
65536 blocks read: OK
Preparing FDT...
  Reading DTB for vim3...
  Reading DTBO for vim3...

MMC read: dev # 2, block # 6144, count 16384 ... 
16384 blocks read: OK
  Applying DTBOs...
0x48f (1167)
Running Android...
## Booting Android Image at 0x01080000 ...
Kernel load addr 0x11080000 size 20875 KiB
Kernel command line: no_console_suspend console=ttyAML0,115200 earlycon printk.devkmsg=on androidboot.boot_devices=soc/ffe07000.mmc init=/init firmware_class.path=/vendor/firmware androidboot.hardware=yukawa androidboot.selinux=permissive buildvariant=userdebug
RAM disk load addr 0x11000000 size 1333 KiB
## Flattened Device Tree blob at 01000000
   Booting using the fdt blob at 0x1000000
   Uncompressing Kernel Image

   Loading Ramdisk to 7feb2000, end 7ffff158 ... OK
   Loading Device Tree to 000000007fe2f000, end 000000007feb1fff ... OK

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 5.10.74-ge7ee32103b2f-dirty (andy_kuo@bobee) (Android (6875598, based on r399163b) clang version 11.0.5 (https://android.googlesource.com/toolchain/llvm-project 87f1315dfbea7c137aa2e6d362dbb457e388158d), LLD 11.0.5 (/buildbot/tmp/tmpx1DlI_ 87f1315dfbea7c137aa2e6d362dbb457e388158d)) #16 SMP PREEMPT Wed Aug 10 17:26:06 CST 2022
[    0.000000] Machine model: Khadas VIM3
[    0.000000] earlycon: meson0 at MMIO 0x00000000ff803000 (options '115200n8')
[    0.000000] printk: bootconsole [meson0] enabled
[    0.000000] efi: UEFI not found.
[    0.000000] [Firmware Bug]: Kernel image misaligned at boot, please fix your bootloader!
[    0.000000] OF: fdt: Reserved memory: failed to reserve memory for node 'secmon@5000000': base 0x0000000005000000, size 3 MiB
[    0.000000] Reserved memory: created CMA memory pool at 0x00000000e4c00000, size 256 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-poo
l

[    0.000000] NUMA: No NUMA configuration found
[    0.000000] NUMA: Faking a node at [mem 0x0000000000000000-0x00000000f4e5afff]
[    0.000000] NUMA: NODE_DATA [mem 0xe464a300-0xe464bfff]
[    0.000000] Zone ranges:
[    0.000000]   DMA32    [mem 0x0000000000000000-0x00000000f4e5afff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x00000000f4e5afff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000f4e5afff]
[    0.000000] On node 0, zone DMA32: 421 pages in unavailable ranges
[    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.1
[    0.000000] percpu: Embedded 33 pages/cpu s96152 r8192 d30824 u135168
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] CPU features: kernel page table isolation disabled by kernel configuration
[    0.000000]
 Built 1 zonelists, mobility grouping on.  Total pages: 987425
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line:  androidboot.verifiedbootstate=orange androidboot.dtbo_idx=3    androidboot.serialno=7EC4DB2A3131  no_console_suspend console=ttyAML0,115200 earlycon printk.devkmsg=on androidboot.boot_devices=soc/ffe07000.mmc init=/init firmware_class.path=/vendor/firmware androidboot.hardware=yukawa androidboot.selinux=permissive buildvariant=userdebug
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 3631756K/4012396K available (18304K kernel code, 3804K rwdata, 9800K rodata, 1280K init, 903K bss, 118496K reserved, 262144K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu: 	RCU event tracing is enabled.
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=6.
[    0.000000] 	Trampoline variant of Tas
ks RCU enabled.
[    0.000000] 	Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] random: get_random_bytes called from start_kernel+0x1f0/0x3c0 with crng_init=0
[    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
[    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.008285] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
[    0.018388] pid_max: default: 32768 minimum: 301
[    0.023089] LSM: Security Framework initializing
[    0.027684] SELinux:  Initializing.
[    0.031228] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.038610] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.048674] rcu: Hierarch
ical SRCU implementation.
[    0.052404] EFI services will not be available.
[    0.056293] smp: Bringing up secondary CPUs ...
[    0.061225] Detected VIPT I-cache on CPU1
[    0.061280] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.062663] CPU features: detected: ARM erratum 858921
[    0.062672] CPU features: detected: Spectre-v2
[    0.062675] CPU features: detected: Spectre-v4
[    0.062682] Detected VIPT I-cache on CPU2
[    0.062704] arch_timer: Enabling local workaround for ARM erratum 858921
[    0.062718] arch_timer: CPU2: Trapping CNTVCT access
[    0.062730] CPU2: Booted secondary processor 0x0000000100 [0x410fd092]
[    0.063542] Detected VIPT I-cache on CPU3
[    0.063558] arch_timer: Enabling local workaround for ARM erratum 858921
[    0.063564] arch_timer: CPU3: Trapping CNTVCT access
[    0.063572] CPU3: Booted secondary processor 0x0000000101 [0x410fd092]
[    0.064376] Detected VIPT I-cache on CPU4
[    0.064391] arch_timer: Enabling local workaround for ARM erratum 858921
[    0.064398] arch_timer: CPU4: Trapping CNTVCT access
[    0.064406] CPU4: Booted secondary processor 0x0000000102 [0x410fd092]
[    0.065235] Detecte
d VIPT I-cache on CPU5
[    0.065250] arch_timer: Enabling local workaround for ARM erratum 858921
[    0.065257] arch_timer: CPU5: Trapping CNTVCT access
[    0.065266] CPU5: Booted secondary processor 0x0000000103 [0x410fd092]
[    0.065377] smp: Brought up 1 node, 6 CPUs
[    0.178443] SMP: Total of 6 processors activated.
[    0.183154] CPU features: detected: 32-bit EL0 Support
[    0.188329] CPU features: detected: CRC32 instructions
[    0.193473] CPU features: detected: 32-bit EL1 Support
[    0.198720] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching
[    0.207037] CPU: All CPU(s) started at EL2
[    0.211179] alternatives: patching kernel code
[    0.218236] devtmpfs: initialized
[    0.227235] Registered cp15_barrier emulation handler
[    0.227270] Registered setend emulation handler
[    0.231215] KASLR disabled due to lack of seed
[    0.235791] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.245468] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)
[    0.257548] pinctrl core: initialized pinctrl subsystem
[    0.258157] DMI not present or invalid.

[    0.261853] NET: Registered protocol family 16
[    0.267942] DMA: preallocated 2048 KiB GFP_KERNEL pool for atomic allocations
[    0.274019] DMA: preallocated 2048 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.281311] audit: initializing netlink subsys (disabled)
[    0.286792] audit: type=2000 audit(0.220:1): state=initialized audit_enabled=0 res=1
[    0.287137] thermal_sys: Registered thermal governor 'step_wise'
[    0.294464] thermal_sys: Registered thermal governor 'user_space'
[    0.300897] cpuidle: using governor ladder
[    0.310747] cpuidle: using governor menu
[    0.314811] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.321622] ASID allocator initialised with 65536 entries
[    0.327018] Serial: AMBA PL011 UART driver
[    0.332103] printk: console [ramoops-1] enabled
[    0.335723] pstore: Registered ramoops as persistent store backend
[    0.341801] ramoops: using 0x100000@0xd000000, ecc: 0

... ...

[   40.245165] logd: logdr: UID=1002 GID=1002 PID=2262 n tail=0 logMask=8 pid=2213 start=0ns deadline=0ns
[   40.251015] logd: logdr: UID=1002 GID=1002 PID=2262 n tail=0 logMask=1 pid=2213 start=0ns deadline=0ns

[   40.314607] init: Untracked pid 2262 exited with status 0
[   40.323194] init: Untracked pid 2264 exited with status 0
[   40.330536] binder: release 2213:2253 transaction 38416 in, still active
[   40.331617] binder: release 2213:2253 transaction 38411 out, still active
[   40.338478] binder: send failed reply for transaction 38416 to 2258:2258
[   40.345121] binder: 2258:2258 transaction failed 29189/0, size 4-0 line 2654
[   40.345749] binder: send failed reply for transaction 38484 to 598:653
[   40.358574] binder: send failed reply for transaction 38411, target dead
[   40.399993] logd: logdr: UID=1002 GID=1002 PID=2271 n tail=0 logMask=8 pid=2258 start=0ns deadline=0ns
[   40.405991] logd: logdr: UID=1002 GID=1002 PID=2271 n tail=0 logMask=1 pid=2258 start=0ns deadline=0ns
[   40.425792] binder: undelivered death notification, b400e081bd6be090
[   40.426720] init: Service 'vendor.bluetooth-1-1' (pid 2258) received signal 11
[   40.433849] init: Sending signal 9 to service 'vendor.bluetooth-1-1' (pid 2258) process group...
[   40.443594] libprocessgroup: Successfully killed process cgroup uid 1002 pid 2258 in 0ms
[   40.451660] init: process with updatable com
ponents 'vendor.bluetooth-1-1' exited 4 times in 4 minutes
[   40.460291] init: Untracked pid 2273 exited with status 0
[   40.465030] init: Untracked pid 2271 exited with status 0
[   40.470475] init: processing action (sys.init.updatable_crashing=1) from (/system/etc/init/flags_health_check.rc:10)
[   40.482026] init: starting service 'exec 20 (/system/bin/flags_health_check UPDATABLE_CRASHING)'...
[   40.491857] init: SVC_EXEC service 'exec 20 (/system/bin/flags_health_check UPDATABLE_CRASHING)' pid 2276 (uid 1000 gid 1000+0 context default) started; waiting...
[   40.507059] init: Service 'exec 20 (/system/bin/flags_health_check UPDATABLE_CRASHING)' (pid 2276) exited with status 0 waiting took 0.016000 seconds
[   40.517813] init: Sending signal 9 to service 'exec 20 (/system/bin/flags_health_check UPDATABLE_CRASHING)' (pid 2276) process group...
[   40.529866] libprocessgroup: Successfully killed process cgroup uid 1000 pid 2276 in 0ms

[   40.741773] ueventd: LoadWithAliases was unable to load platform:regulatory

[   44.065476] ueventd: LoadWithAliases was unable to load platform:regulatory

[   44.758742] init: starting service 'vendor.bluetooth-1-1'...
[   44.761294] libprocessgroup: Failed to open /dev/stune/foreground/tasks: No such file or directory: No such file or directory
[   44.769999] libprocessgroup: Failed to apply HighPerformance task profile: No such file or directory

[   47.393533] ueventd: LoadWithAliases was unable to load platform:regulatory

[xiong.zhang]

Hello, can you use our latest official image of VIM3 to make your board light up?

VIM3/VIM3L-Android-9-64bit-V231122 Rom Release

Please check VIM3 USB Burn Firmware Page or VIM3L USB Burn Firmware Page to download the ROM for USB burning. ROM Infos: Version(Latest): vim3/vim3l-android-9-64bit-v231122.img.xz Update List: LED: Fix the problem that the white LED light does not turn on until 6s after the VIM3L is turned on GPIO: fix gpio control on bl2 RSDB: add wifi rsdb support on VIM3L WIFI: open wifi sta and AP coexistence mode M2X: Add M2X extended version detection Upgrade: update system with OTAUpgrade2 applicat…

[Micky]

Hi Xiong,
Thanks for your quick reply. Here is the logs with official image vim3-android-9-64bit-v230719.img, please help to take a look, thanks

G12B:BL:6e7c85:2a3b91;FEAT:E0F83180:402000;POC:F;RCY:0;EMMC:0;READ:0;0.\0
bl2_stage_init 0x01
bl2_stage_init 0x81
hw id: 0x0000 - pwm id 0x01
bl2_stage_init 0xc1
bl2_stage_init 0x02

L0:00000000
L1:20000703
L2:00008067
L3:14000000
B2:00402000
B1:e0f83180

TE: 174916
BL2 Built : 19:23:21, Sep 18 2020. g12b g9fde858 - gongwei.chen@droid11-sz

Board ID = 7
Set A53 clk to 24M
Set A73 clk to 24M
Set clk81 to 24M
A53 clk: 1200 MHz
A73 clk: 1200 MHz
CLK81: 166.6M
smccc: 0002f428
eMMC boot @ 0
sw8 s
board id: 7
Load FIP HDR DDR from eMMC, src: 0x00010200, des: 0xfffd0000, size: 0x00004000, part: 0
Get wrong ddr fw magic! Error!!
fw parse done
PIEI prepare done
00000000
emmc switch 1 ok
ddr saved addr:00016000
Load ddr parameter from eMMC, src: 0x02c00000, des: 0xfffd0000, size: 0x00001000, part: 0
00000000
emmc switch 0 ok
fastboot data verify
result: 255
Cfg max: 12, cur: 1. Board id: 255. Force loop cfg
LPDDR4 probe

LPDDR4_PHY_V_0_1_22-Built : 14:57:26, Sep  1 2020. g12b g3c95fed - gongwei.chen@droid11-sz
ddr clk to 1608MHz

dmc_version 0001
Check phy result
INFO : End of CA training
INFO : End of initialization
INFO : Training has run successfully!
Check phy result
INFO : End of initialization
INFO : End of read enable training
INFO : End of fine write leveling
INFO : End of read dq deskew training
INFO : End of MPR read delay center optimization
INFO : End of Write leveling coarse delay
INFO : End of read delay center optimization
INFO : Training has run successfully!
Check phy result
INFO : End of initialization
INFO : End of MPR read delay center optimization
INFO : End of write delay center optimization
INFO : End of read delay center optimization
INFO : End of max read latency training
INFO : Training has run successfully!
1D training succeed
Check phy result
INFO : End of initialization
INFO : End of 2D read delay Voltage center optimization
INFO : End of 2D read delay Voltage center optimization
INFO : End of 2D write delay Voltage center optimization
INFO : End of 2D write delay Voltage center optimization
INFO : Training has run successfully!

soc_vref_reg_value 0x 00000029 00000026 00000028 00000029 00000028 00000026 00000028 00000028 00000027 00000026 00000026 00000027 00000028 00000029 00000028 00000027 00000027 00000028 00000029 00000027 00000028 00000028 00000028 00000026 00000028 00000028 00000029 00000028 0000002a 00000029 00000026 00000028 dwc_ddrphy_apb_wr((0<<20)|(2<<16)|(0<<12)|(0xb0):0004 
 dram_vref_reg_value 0x 00000012
2D training succeed
auto size-- 65535DDR cs0 size: 2048MB
DDR cs1 size: 2048MB
DMC_DDR_CTRL: 00e00024DDR size: 3928MB
cs0 DataBus test pass
cs1 DataBus test pass
cs0 AddrBus test pass
cs1 AddrBus test pass

non-sec scramble use zero key
ddr scramble enabled

100bdlr_step_size ps== 420
result report
boot times 0Enable ddr reg access
00000000
emmc switch 3 ok
Authentication key not yet programmed
get rpmb counter error 0x00000007
00000000
emmc switch 0 ok
Load FIP HDR from eMMC, src: 0x00010200, des: 0x01700000, size: 0x00004000, part: 0
Load BL3X from eMMC, src: 0x00078200, des: 0x01768000, size: 0x000e4800, part: 0
0.0;M3 CHK:0;cm4_sp_mode 0
[Image: g12b_v1.1.3396-c893a7153 2020-11-11 13:54:36 gongwei.chen@droid11-sz]
OPS=0x10
ring efuse init
chipver efuse init
29 0b 10 00 01 25 14 00 00 11 39 32 34 54 31 50 
[0.016082 Inits done]
secure task start!
high task start!
low task start!
run into bl31
NOTICE:  BL31: v1.3(release):5c909a099
NOTICE:  BL31: Built : 17:18:21, Nov 20 2020
NOTICE:  BL31: G12A normal boot!
NOTICE:  BL31: BL33 decompress pass
ERROR:   Error initializing runtime service opteed_fast


U-Boot 2015.01-gb6634a96e4-dirty (Jul 19 2023 - 20:54:20)

DRAM:  3.8 GiB
Relocation Offset is: d6e1d000
mmu cfg end: 0xe0000000
mmu cfg end: 0xe0000000
spi_post_bind(spifc): req_seq = 0
board init
register usb cfg[0][1] = 00000000d7f30708
aml_i2c_init_port init regs for 0
MCU version: 0x00 0x03
MCU version is to low! Doesn't support froce boot from SD card.
NAND:  MMC:   aml_priv->desc_buf = 0x00000000d3e0da70
aml_priv->desc_buf = 0x00000000d3e0fdb0
SDIO Port B: 0, SDIO Port C: 1
co-phase 0x3, tx-dly 0, clock 400000
co-phase 0x3, tx-dly 0, clock 400000
co-phase 0x3, tx-dly 0, clock 400000
emmc/sd response timeout, cmd8, cmd->cmdarg=0x1aa, status=0x3ff2800
emmc/sd response timeout, cmd55, cmd->cmdarg=0x0, status=0x3ff2800
co-phase 0x3, tx-dly 0, clock 400000
co-phase 0x1, tx-dly 0, clock 40000000
[set_emmc_calc_fixed_adj][875]find fixed adj_delay=20
init_part() 297: PART_TYPE_AML
[mmc_init] mmc init success
start dts,buffer=00000000d3e12620,dt_addr=00000000d3e12620
get_partition_from_dts() 92: ret 0
parts: 17
00:      logo	0000000000800000 1
01:  recovery	0000000001800000 1
02:      misc	0000000000800000 1
03:      dtbo	0000000000800000 1
04:  cri_data	0000000000800000 2
05:     param	0000000001000000 2
06:      boot	0000000001000000 1
set has_boot_slot = 0
07:       rsv	0000000001000000 1
08:  metadata	0000000001000000 1
09:    vbmeta	0000000000200000 1
10:       tee	0000000002000000 1
11:    vendor	000000001f000000 1
12:       odm	0000000008000000 1
13:    system	0000000078800000 1
14:   product	0000000008000000 1
15:     cache	0000000046000000 2
16:      data	ffffffffffffffff 4
init_part() 297: PART_TYPE_AML
eMMC/TSD partition table have been checked OK!
crc32_s:0x1577dad == storage crc_pattern:0x1577dad!!!
crc32_s:0xee152b83 == storage crc_pattern:0xee152b83!!!
crc32_s:0x79f50f07 == storage crc_pattern:0x79f50f07!!!
mmc env offset: 0x4d400000 
In:    serial
Out:   serial
Err:   serial
board late init
aml log : internal sys error!
reboot_mode=cold_boot
TP050 id=0xff
TP101 id=0xff
panel_type=lcd_1   khadas_mipi_id=0   id=0---default old_TS050   id=1,lcd_1---old_TS050   id=2,lcd_2---TS101   id=3,lcd_3---new_TS050
[aml_i2c_xfer] error ret = -5 	i2c master b current slave addr is 0xc
i2c_read: i2c transfer failed
Error reading the chip: -5
khadas camera id=0xfffffffb
khadas_camera_id=1   id=1---is OS08A10   id=2---is IMX415
[store]To run cmd[emmc dtb_read 0x1000000 0x40000]
_verify_dtb_checksum()-3354: calc 9c9d7730, store 9c9d7730
_verify_dtb_checksum()-3354: calc 9c9d7730, store 9c9d7730
dtb_read()-3569: total valid 2
update_old_dtb()-3550: do nothing
aml_i2c_init_port init regs for 0
fusb302_init: Device ID: 0x91
CC connected in 1 as UFP
fusb302 detect chip.port_num = 0
amlkey_init() enter!
[EFUSE_MSG]keynum is 1
vpu: driver version: v20190313
vpu: detect chip type: 9
vpu: clk_level default: 7(666667000Hz), max: 7(666667000Hz)
vpu: clk_level in dts: 7
vpu: vpu_power_on
vpu: set_vpu_clk
vpu: set clk: 666667000Hz, readback: 666666667Hz(0x100)
vpu: set_vpu_clk finish
vpu: vpu_module_init_config
vpp: vpp_init
vpp: vpp osd2 matrix rgb2yuv..............
hdr_func 4, hdr_process_select 0x1
LCD_RESET PIN: 0
lcd: lcd_debug_print flag: 0
lcd: detect mode: tablet, key_valid: 0
lcd: detect lcd_clk_path: 1
lcd: failed to get lcd_cpu_gpio_names
lcd: load config from dts
lcd: pinctrl_version: 2
lcd: use panel_type=lcd_1
lcd: bl: pinctrl_version: 2
lcd: bl: name: backlight_pwm, method: 1
lcd: error: gpio: wrong name invalid
lcd: bl: pwm_reg=0x00800002
lcd: bl: aml_bl_power_ctrl: 0
Net:   use internal ethernet
--------ext_ethernet=0
+++++++++ext_ethernet=0
dwmac.ff3f0000 Waiting for PHY auto negotiation to complete.... done
The Best Window is index 40
[KM]Msg:isEncrypt=
[KM]Msg:keyAttr is 0x00000000
dwmac.ff3f0000amlkey_init() enter!
amlkey_init() 71: already init!
[EFUSE_MSG]keynum is 1

ramdump_init, add:0, size:0
CONFIG_AVB2: null 
Start read misc partition datas!
info->magic = 
info->version_major = 1
info->version_minor = 0
info->slots[0].priority = 15
info->slots[0].tries_remaining = 7
info->slots[0].successful_boot = 0
info->slots[1].priority = 14
info->slots[1].tries_remaining = 7
info->slots[1].successful_boot = 0
info->crc32 = -1075449479
active slot = 0

wipe_data=successful
wipe_cache=successful
upgrade_step=2
aml log : internal sys error!
reboot_mode:::: cold_boot
lcd: error: outputmode[576cvbs] is not support
hpd_state=0
do_hpd_detect: hdmimode=1080p60hz
do_hpd_detect: colorattribute=444,8bit
Saving Environment to aml-storage...
mmc env offset: 0x4d400000 
Writing to MMC(1)... done
no sink, fallback to 720p60hz[4]
hdr mode is 0
dv  mode is ver:0  len: 0
hdr10+ mode is 0
read hdmichecksum 0x00000000, colorattribute 444,8bit
read hdmimode 1080p60hz, colorspace <NULL>, colordepth <NULL>
do_get_parse_edid: autoMode = false, manualMode=16
isYuv4kSink: false, maxTMDSRate=150
do_get_parse_edid: non-yuv4k sink: inColorSpace: HDMI_COLOR_FORMAT_RGB
do_get_parse_edid: default inColorDepth: HDMI_COLOR_DEPTH_24B
TV is same, initial mode is: 576cvbs attr: 444,8bit
HDMI cable is NOT connected
Unknown command 'dovi' - try 'help'
[OSD]load fb addr from dts:/meson-fb
[OSD]set initrd_high: 0x7f800000
[OSD]fb_addr for logo: 0x7f800000
[OSD]load fb addr from dts:/meson-fb
[OSD]fb_addr for logo: 0x7f800000
[OSD]VPP_OFIFO_SIZE:0xfff01fff
[CANVAS]canvas init
[CANVAS]addr=0x7f800000 width=3840, height=2160
[OSD]osd_hw.free_dst_data: 0,719,0,575
[OSD]osd1_update_disp_freescale_enable
hdmitx: outputmode[576cvbs] is invalid
lcd: error: outputmode[576cvbs] is not support
hdr_packet
vpp: hdr_policy = 1
vpp: Rx hdr_info.hdr_sup_eotf_smpte_st_2084 = 0
s_version: U-Boot 2015.01-gb6634a96e4-dirty


normal power on
boot wol: disable
[reg_31] 0xd8a
[reg_16] 0x0
[reg_17] 0x7fff
[reg_19] 0x0
[reg_31] 0x0
[reg_31] 0xd40
[reg_22] 0x0
[reg_31] 0x0
saradc: 0x285, hw_ver: 0x32 (VIM3.V12)
gpio: pin GPIOAO_7 (gpio 7) value is 1
port mode is usb3.0
Command: bcb uboot-command 
Start read misc partition datas!
BCB hasn't any datas,exit!
s_version: U-Boot 2015.01-gb6634a96e4-dirty


Hit Enter or space or Ctrl+C key to stop autoboot -- :  1  0 
cfgload: start ...
cfgload: reading /boot.ini from mmc 0:1 ...
card out
** Bad device mmc 0 **
cfgload: no /boot.ini or empty file on mmc 0:1
cfgload: reading /boot.ini from mmc 1:1 ...
Failed to mount ext2 filesystem...
** Unrecognized filesystem type **
cfgload: no /boot.ini or empty file on mmc 1:1
cfgload: reading /boot/boot.ini from mmc 1:5 ...
Failed to mount ext2 filesystem...
** Unrecognized filesystem type **
cfgload: no /boot/boot.ini or empty file on mmc 1:5
cfgload: failed to read boot.ini on all partitions!
CONFIG_SYSTEM_AS_ROOT: systemroot 
system_mode: 1
active_slot: normal
avb2: 0
ee_gate_off ...
avb2: 0
## Booting Android Image at 0x03080000 ...
Kernel command line: androidboot.dtbo_idx=0 --cmdline root=/dev/mmcblk0p18 buildvariant=userdebug
[store]Is good fdt check header, no need decrypt!
active_slot is normal
load dtb from 0x1000000 ......
find 1 dtbos
dtbos to be applied: 0
Apply dtbo 0
   Uncompressing Kernel Image ... OK
   kernel loaded at 0x01080000, end = 0x0265f808
   Loading Device Tree to 000000001ffe5000, end 000000001ffffdb8 ... OK
fdt_fixup_memory_banks, reg:0000000000000000

Starting kernel ...

uboot time: 5618321 us
[    0.000000@0] Booting Linux on physical CPU 0x0
[    0.000000@0] Linux version 4.9.113-g7f3f7f3ef13b-dirty (xiong@server) (gcc version 6.3.1 20170109 (Linaro GCC 6.3-2017.02) ) #1 SMP PREEMPT Wed Jul 19 19:50:41 CST 2023
[    0.000000@0] Boot CPU: AArch64 Processor [410fd034]
[    0.000000@0] earlycon: aml-uart0 at MMIO 0x00000000ff803000 (options '')
[    0.000000@0] bootconsole [aml-uart0] enabled
[    0.000000@0] 	07400000 - 07500000,     1024 KB, ramoops@0x07400000
[    0.000000@0] fdt_init_reserved_mem, start:0x0000000005000000, end:0x0000000005400000, len:4 MiB
[    0.000000@0] 	05000000 - 05400000,     4096 KB, linux,secmon
[    0.000000@0] fdt_init_reserved_mem, start:0x000000007f800000, end:0x0000000080000000, len:8 MiB
[    0.000000@0] 	7f800000 - 80000000,     8192 KB, linux,meson-fb
[    0.000000@0] 	d0000000 - d8000000,   131072 KB, linux,ion-dev
[    0.000000@0] 	cd800000 - d0000000,    40960 KB, linux,di_cma
[    0.000000@0] Reserved memory: created DMA memory pool at 0x00000000cd800000, size 0 MiB
[    0.000000@0] 	cd800000 - cd800000,        0 KB, linux,ppmgr
[    0.000000@0] 	ba400000 - cd800000,   315392 KB, linux,codec_mm_cma
[    0.000000@0] 	ba400000 - ba400000,        0 KB, linux,codec_mm_reserved
[    0.000000@0] 	b6400000 - ba400000,    65536 KB, linux,vdin0_cma
[    0.000000@0] 	b2400000 - b6400000,    65536 KB, linux,vdin1_cma
[    0.000000@0] 	b1400000 - b2400000,    16384 KB, linux,galcore
[    0.000000@0] 	a9400000 - b1400000,   131072 KB, linux,isp_cma
[    0.000000@0] 	a7c00000 - a9400000,    24576 KB, linux,adapt_cma
[    0.000000@0] cma: Reserved 8 MiB at 0x00000000a7400000
[    0.000000@0] psci: probing for conduit method from DT.
[    0.000000@0] psci: PSCIv1.0 detected in firmware.
[    0.000000@0] psci: Using standard PSCI v0.2 function IDs
[    0.000000@0] psci: MIGRATE_INFO_TYPE not supported.
[    0.000000@0] psci: SMC Calling Convention v1.1
[    0.000000@0] percpu: Embedded 26 pages/cpu @ffffffc0a72e5000 s68952 r8192 d29352 u106496
[    0.000000@0] Detected VIPT I-cache on CPU0
[    0.000000@0] CPU features: enabling workaround for ARM erratum 845719
[    0.000000@0] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 870912
[    0.000000@0] Kernel command line: init=/init console=ttyS0,115200 no_console_suspend earlycon=aml-uart,0xff803000 ramoops.pstore_en=1 ramoops.record_size=0x8000 ramoops.console_size=0x4000 hdr_policy=1 hdr_priority= otg_device=1 reboot_mode_android=normal logo=osd0,loaded,0x3d800000 fb_width=1920 fb_height=1080 vout2=,enable vout=576cvbs,enable panel_type=lcd_1 lcd_ctrl=0x00000083 hdmitx=,444,8bit hdmimode=1080p60hz nativeui=disable hdmichecksum=0x00000000 dolby_vision_on=0 frac_rate_policy=1 hdmi_read_edid=1 cvbsmode=576cvbs osd_reverse=0 video_reverse=0 irq_check_en=0 androidboot.selinux=permissive androidboot.firstboot=1 jtag=disable wol_enable=0 spi_state=0 fusb302_state=1 hwver=VIM3.V12 factory_mac=c8:63:14:72:61:bc lcd_exist=0 ext_board_exist=0 androidboot.hardware=amlogic androidboot.bootloader=U-Boot 2015.01-gb6634a96e4-dirty androidboot.build.expect.baseband=N/A androidboot.serialno=c863147261bc mac=c8:63:14:72:61:bc androidboot.mac=c8:63:14:72:61:bc khadas_mipi_id=0 khadas_came
[    0.000000@0] am_vecm: boot hdr_policy: 1
[    0.000000@0] fb: osd0
[    0.000000@0] fb: loaded
[    0.000000@0] fb: 0x3d800000
[    0.000000@0] fb: logo_info.fb_width=1920
[    0.000000@0] fb: logo_info.fb_height=1080
[    0.000000@0] vout: error: get_vout2_init_mode: 
[    0.000000@0] vout: vout2: enable: 1
[    0.000000@0] vout: 576cvbs
[    0.000000@0] vout: enable: 1
[    0.000000@0] lcd: panel_type: lcd_1
[    0.000000@0] lcd: lcd_ctrl: 0x00000083
[    0.000000@0] vout: get hdmimode: 1080p60hz
[    0.000000@0] jason hdmichecksum: 0x00000000
[    0.000000@0] vout: get hdmi checksum: 0x00000000
[    0.000000@0] vout: get cvbsmode: 576cvbs
[    0.000000@0] vpp_axis_reverse: bootargs is 0
[    0.000000@0] DI: di_read_canvas_reverse: bootargs is 0.
[    0.000000@0] vdin_get_video_reverse: bootargs is 0.
[    0.000000@0] phlock_phase_config: bootargs is 0.
[    0.000000@0] init_wol_state, wol_enable=0
[    0.000000@0] jason lcd_exist: 0
[    0.000000@0] ext_board_exist: 0
[    0.000000@0] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000@0] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.000000@0] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.000000@0] Memory: 2640296K/3538944K available (12350K kernel code, 1726K rwdata, 3576K rodata, 4672K init, 1499K bss, 87640K reserved, 811008K cma-reserved)
[    0.000000@0] Virtual kernel memory layout:
[    0.000000@0]     modules : 0xffffff8000000000 - 0xffffff8008000000   (   128 MB)
[    0.000000@0]     vmalloc : 0xffffff8008000000 - 0xffffffbebfff0000   (   250 GB)
[    0.000000@0]       .text : 0xffffff8009080000 - 0xffffff8009c90000   ( 12352 KB)
[    0.000000@0]     .rodata : 0xffffff8009c90000 - 0xffffff800a020000   (  3648 KB)
[    0.000000@0]       .init : 0xffffff800a020000 - 0xffffff800a4b0000   (  4672 KB)
[    0.000000@0]       .data : 0xffffff800a4b0000 - 0xffffff800a65f808   (  1727 KB)
[    0.000000@0]        .bss : 0xffffff800a65f808 - 0xffffff800a7d67d4   (  1500 KB)
[    0.000000@0]     fixed   : 0xffffffbefe7fd000 - 0xffffffbefec00000   (  4108 KB)
[    0.000000@0]     PCI I/O : 0xffffffbefee00000 - 0xffffffbeffe00000   (    16 MB)
[    0.000000@0]     vmemmap : 0xffffffbf00000000 - 0xffffffc000000000   (     4 GB maximum)
[    0.000000@0]               0xffffffbf00000000 - 0xffffffbf03600000   (    54 MB actual)
[    0.000000@0]     memory  : 0xffffffc000000000 - 0xffffffc0d8000000   (  3456 MB)
[    0.000000@0] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1
[    0.000000@0] Preemptible hierarchical RCU implementation.
[    0.000000@0] 	Build-time adjustment of leaf fanout to 64.
[    0.000000@0] 	RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=6.
[    0.000000@0] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=6
[    0.000000@0] NR_IRQS:64 nr_irqs:64 0
[    0.000000@0] irq_meson_gpio: 100 to 8 gpio interrupt mux initialized
[    0.000000@0] g12a_aoclkc_init: register ao clk ok!
[    0.000000@0] Meson chip version = RevB (29:B - 10:0)
[    0.000000@0] meson_g12a_sdemmc_init: register amlogic sdemmc clk
[    0.000000@0] meson_g12a_sdemmc_init: register amlogic sdemmc clk
[    0.000000@0] meson_g12a_gpu_init: register meson gpu clk
[    0.000000@0] meson_g12a_media_init: register meson media clk
[    0.000000@0] meson_g12a_misc_init: register amlogic g12a misc clks
[    0.000000@0] meson_g12a_misc_init: done.
[    0.000000@0] g12a_clkc_init initialization complete
[    0.000000@0] arm_arch_timer: Architected cp15 timer(s) running at 24.00MHz (phys).
[    0.000000@0] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[    0.000003@0] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[    0.008257@0] meson_bc_timer: mclk->mux_reg =ffffff800800c190,mclk->reg =ffffff800800e194
[    0.017162@0] Console: colour dummy device 80x25
[    0.021108@0] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
[    0.031587@0] pid_max: default: 32768 minimum: 301
[    0.036448@0] thread_stack_cache_init, vmap:ffffffc0a307a0c0, bitmap:ffffffc0a3074000, cache page:a3080
[    0.045872@0] thread_stack_cache_init, allocation vm area:ffffffc0a3039800, addr:ffffff8020000000, size:20001000
[    0.056096@0] cpu 0, vmap_stack:[ffffffc0a72efa20-ffffffc0a72f3a10]
[    0.062394@0] cpu 0, irq_stack: [ffffffc0a72e5060-ffffffc0a72e9050]
[    0.068694@0] cpu 1, vmap_stack:[ffffffc0a7309a20-ffffffc0a730da10]
[    0.074993@0] cpu 1, irq_stack: [ffffffc0a72ff060-ffffffc0a7303050]
[    0.081294@0] cpu 2, vmap_stack:[ffffffc0a7323a20-ffffffc0a7327a10]
[    0.087594@0] cpu 2, irq_stack: [ffffffc0a7319060-ffffffc0a731d050]
[    0.093894@0] cpu 3, vmap_stack:[ffffffc0a733da20-ffffffc0a7341a10]
[    0.100194@0] cpu 3, irq_stack: [ffffffc0a7333060-ffffffc0a7337050]
[    0.106494@0] cpu 4, vmap_stack:[ffffffc0a7357a20-ffffffc0a735ba10]
[    0.112793@0] cpu 4, irq_stack: [ffffffc0a734d060-ffffffc0a7351050]
[    0.119094@0] cpu 5, vmap_stack:[ffffffc0a7371a20-ffffffc0a7375a10]
[    0.125393@0] cpu 5, irq_stack: [ffffffc0a7367060-ffffffc0a736b050]
[    0.131752@0] Security Framework initialized
[    0.135985@0] SELinux:  Initializing.
[    0.139708@0] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.146575@0] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.154526@0] ftrace: allocating 40601 entries in 159 pages
[    0.234300@0] sched-energy: Sched-energy-costs installed from DT
[    0.234668@0] CPU0: update cpu_capacity 631
[    0.238902@0] ASID allocator initialised with 65536 entries
[    0.299784@0] secmon: clear_range:5100000 200000
[    0.349252@1] Detected VIPT I-cache on CPU1
[    0.349302@1] CPU1: update cpu_capacity 631
[    0.349305@1] CPU1: Booted secondary processor [410fd034]
[    0.381797@2] Detected VIPT I-cache on CPU2
[    0.381842@2] CPU2: update cpu_capacity 1192
[    0.381844@2] CPU2: Booted secondary processor [410fd092]
[    0.413354@3] Detected VIPT I-cache on CPU3
[    0.413377@3] CPU3: update cpu_capacity 1192
[    0.413379@3] CPU3: Booted secondary processor [410fd092]
[    0.445436@4] Detected VIPT I-cache on CPU4
[    0.445459@4] CPU4: update cpu_capacity 1192
[    0.445461@4] CPU4: Booted secondary processor [410fd092]
[    0.477529@5] Detected VIPT I-cache on CPU5
[    0.477552@5] CPU5: update cpu_capacity 1192
[    0.477554@5] CPU5: Booted secondary processor [410fd092]
[    0.477647@0] Brought up 6 CPUs
[    0.544601@0] SMP: Total of 6 processors activated.
[    0.549514@0] CPU features: detected feature: 32-bit EL0 Support
[    0.555671@0] CPU: All CPU(s) started at EL2
[    0.559870@0] alternatives: patching kernel code
[    0.566050@4] addr:ffffff80200c3e10 is in kernel, size fix 4096->10, data:mode=0755
[    0.572294@3] devtmpfs: initialized
[    0.593508@3] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.597840@3] futex hash table entries: 2048 (order: 5, 131072 bytes)
[    0.604459@3] pinctrl core: initialized pinctrl subsystem
[    0.610516@3] NET: Registered protocol family 16
[    0.616715@3] schedtune: init normalization constants...
[    0.619708@3] schedtune: CLUSTER[0-1]      min_pwr:     0 max_pwr:    42
[    0.626438@3] schedtune: CPU[0]            min_pwr:     0 max_pwr:   279
[    0.633174@3] schedtune: CPU[1]            min_pwr:     0 max_pwr:   279
[    0.639911@3] schedtune: CLUSTER[2-5]      min_pwr:     0 max_pwr:   110
[    0.646648@3] schedtune: CPU[2]            min_pwr:     0 max_pwr:  1048
[    0.653386@3] schedtune: CPU[3]            min_pwr:     0 max_pwr:  1048
[    0.660123@3] schedtune: CPU[4]            min_pwr:     0 max_pwr:  1048
[    0.666864@3] schedtune: CPU[5]            min_pwr:     0 max_pwr:  1048
[    0.673606@3] schedtune: SYSTEM            min_pwr:     0 max_pwr:  4902
[    0.680338@3] schedtune: using normalization constants mul: 2882587190 sh1: 1 sh2: 12
[    0.688215@3] schedtune: verify normalization constants...
[    0.693725@3] schedtune: max_pwr/2^0: 4902 => norm_pwr:  1024
[    0.699503@3] schedtune: max_pwr/2^1: 2451 => norm_pwr:   512
[    0.705274@3] schedtune: max_pwr/2^2: 1225 => norm_pwr:   255
[    0.711053@3] schedtune: max_pwr/2^3:  612 => norm_pwr:   127
[    0.716824@3] schedtune: max_pwr/2^4:  306 => norm_pwr:    63
[    0.722602@3] schedtune: max_pwr/2^5:  153 => norm_pwr:    31
[    0.728374@3] schedtune: configured to support 5 boost groups
[    0.750188@3] cpuidle: using governor menu
[    0.750293@3] register canvas platform driver
[    0.753025@3] register rdma platform driver
[    0.759537@3] vdso: 2 pages (1 code @ ffffff8009c97000, 1 data @ ffffff800a4b5000)
[    0.764829@3] hw-breakpoint: found 6 breakpoint and 2 watchpoint registers.
[    0.772369@3] DMA: preallocated 256 KiB pool for atomic allocations
[    0.778226@3] clkmsr: clkmsr: driver init
[    0.782150@3] codec_mm_module_init
[    0.785573@3] media_configs_system_init
[    0.789636@3] aml_watch_point_probe, in, wp:2
[    0.794250@3] pstore: using zlib compression
[    0.798752@3] console [pstore-1] enabled
[    0.802019@3] pstore: Registered ramoops as persistent store backend
[    0.808405@3] ramoops: attached 0x100000@0x7400000, ecc: 0/0
[    0.814085@3] ramoops: ramoops_io_en:0 1 old:0x0 ftrace_size:0x40000[    0.822251@3] aml_iomap: amlogic iomap probe done
[    0.825460@3] vpu: driver version: v20190329(8-g12b)
[    0.830020@3] vpu: load vpu_clk: 666666667Hz(7)
[    0.834780@3] vpu: clktree_init
[    0.837753@3] vpu: vpu_probe OK
[    0.844677@3] clkmsr: msr_clk_reg0=ffffff80081b7004,msr_clk_reg2=ffffff80081b900c
[    0.848398@3] clkmsr ffd18004.meson_clk_msr: failed to get msr ring reg0
[    0.857602@3] audio_clocks: audio_clocks_probe done
[    0.863111@3] aml_vdac_config_probe: cpu_id:4, name:meson-g12ab-vdac
[    0.866574@3] aml_vdac_probe: ok
[    0.869770@3] canvas_probe reg=00000000ff638000,size=2000
[    0.875087@3] canvas maped reg_base =ffffff80081be000
[    0.885776@3] rdma_probe,cpu_type:0, ver:0, len:8
[    0.886007@3] rdma_register, rdma_table_addr ffffff80081e1000 rdma_table_addr_phy a7440000 reg_buf ffffffc0a2ab8000
[    0.895389@3] rdma_register success, handle 1 table_size 32768
[    0.901241@3] set_rdma_handle video rdma handle = 1.
[    0.906243@3] classs created ok
[    0.909367@3] classs file created ok

......

[    7.214630@0] find tzd id: 0
[    7.214700@0] gpu cooling register okay with err=0
[    7.214896@0] find tzd id: 0
[    7.214928@0] gpu core cooling register okay with err=0
[    7.214958@0] shader_present=3, tiler_present=1, l2_present=1
[    7.215042@0] Mali_pwr_on:gpu_irq : 200
[    7.215354@5] hrtimer: interrupt took 9875 ns
[    7.215604@5] mali ffe40000.bifrost: GPU identified as 0x2 arch 7.2.1 r0p0 status 0
[    7.216091@5] mali ffe40000.bifrost: Using coherency mode 0 set from dtb
[    7.217419@5] mali ffe40000.bifrost: Probed as mali0
[    7.220311@3] PPMGRVPP: info: RegisterTB_Function: gfunc           (null), func: ffffff8001d29028, ver:TB detect: v2016.11.15a *** [ Fri Mar 22 13:01:02 CST 2019]-[ renjiang.han]-[* master]-[67f03d41e0612a58d1edd96db9bc8b92de1a59b1]-[Date: Wed Jul 4 00:44:57 2018 +0800]-[0]
[    7.420935@2] lcd vlock_en=1, vlock_mode=0x4
[    7.498849@2] ueventd: /vendor/ueventd.rc: 115: /sys/ lines must have 5 entries
[    7.505507@2] ueventd: Parsing file /odm/ueventd.rc...
[    7.510576@2] ueventd: Unable to read config file '/odm/ueventd.rc': open() failed: No such file or directory
[    7.520506@2] ueventd: Parsing file /ueventd.amlogic.rc...
[    7.525909@2] ueventd: Unable to read config file '/ueventd.amlogic.rc': open() failed: No such file or directory

[Micky]

Hi Xiong,
I tried to flash official ubuntu image vim3-ubuntu-20.04-server-linux-4.9-fenix-1.5-230425-emmc.img on my VIM3 device to find out more clue for you. I found my VIM3 was not able to bring up successfully with this image.
Here is the bring up logs:

G12B:BL:6e7c85:2a3b91;FEAT:E0F83180:402000;POC:F;RCY:0;EMMC:0;READ:0;0.\0
bl2_stage_init 0x01
bl2_stage_init 0x81
hw id: 0x0000 - pwm id 0x01
bl2_stage_init 0xc1
bl2_stage_init 0x02

L0:00000000
L1:20000703
L2:00008067
L3:14000000
B2:00402000
B1:e0f83180

TE: 150103

BL2 Built : 19:23:21, Sep 18 2020. g12b g9fde858 - gongwei.chen@droid11-sz

Board ID = 7
Set A53 clk to 24M
Set A73 clk to 24M
Set clk81 to 24M
A53 clk: 1200 MHz
A73 clk: 1200 MHz
CLK81: 166.6M
smccc: 0002933b
eMMC boot @ 0
sw8 s
board id: 7
Load FIP HDR DDR from eMMC, src: 0x00010200, des: 0xfffd0000, size: 0x00004000, part: 0
Get wrong ddr fw magic! Error!!
fw parse done
PIEI prepare done
00000000
emmc switch 1 ok
ddr saved addr:00016000
Load ddr parameter from eMMC, src: 0x02c00000, des: 0xfffd0000, size: 0x00001000, part: 0
00000000
emmc switch 0 ok
fastboot data verify
result: 255
Cfg max: 12, cur: 1. Board id: 255. Force loop cfg
LPDDR4 probe

LPDDR4_PHY_V_0_1_22-Built : 14:57:26, Sep  1 2020. g12b g3c95fed - gongwei.chen@droid11-sz
ddr clk to 1608MHz

dmc_version 0001
Check phy result
INFO : End of CA training
INFO : End of initialization
INFO : Training has run successfully!
Check phy result
INFO : End of initialization
INFO : End of read enable training
INFO : End of fine write leveling
INFO : End of read dq deskew training
INFO : End of MPR read delay center optimization
INFO : End of Write leveling coarse delay
INFO : End of read delay center optimization
INFO : Training has run successfully!
Check phy result
INFO : End of initialization
INFO : End of MPR read delay center optimization
INFO : End of write delay center optimization
INFO : End of read delay center optimization
INFO : End of max read latency training
INFO : Training has run successfully!
1D training succeed
Check phy result
INFO : End of initialization
INFO : End of 2D read delay Voltage center optimization
INFO : End of 2D read delay Voltage center optimization
INFO : End of 2D write delay Voltage center optimization
INFO : End of 2D write delay Voltage center optimization
INFO : Training has run successfully!

soc_vref_reg_value 0x 0000002b 00000028 0000002a 0000002a 00000029 00000028 00000029 00000029 00000028 00000027 00000028 00000029 00000029 0000002a 00000029 00000028 00000028 0000002a 0000002a 00000028 00000029 0000002a 00000029 00000028 00000029 00000029 0000002a 0000002a 0000002b 0000002a 00000028 00000029 dwc_ddrphy_apb_wr((0<<20)|(2<<16)|(0<<12)|(0xb0):0004 
 dram_vref_reg_value 0x 00000012
2D training succeed
auto size-- 65535DDR cs0 size: 2048MB
DDR cs1 size: 2048MB
DMC_DDR_CTRL: 00e00024DDR size: 3928MB
cs0 DataBus test pass
cs1 DataBus test pass
cs0 AddrBus test pass
cs1 AddrBus test pass

non-sec scramble use zero key
ddr scramble enabled

100bdlr_step_size ps== 398
result report
boot times 0Enable ddr reg access
00000000
emmc switch 3 ok
Authentication key not yet programmed
get rpmb counter error 0x00000007
00000000
emmc switch 0 ok
Load FIP HDR from eMMC, src: 0x00010200, des: 0x01700000, size: 0x00004000, part: 0
Load BL3X from eMMC, src: 0x00078200, des: 0x01768000, size: 0x000ce800, part: 0
0.0;M3 CHK:0;cm4_sp_mode 0
MVN_1=0x00000000
MVN_2=0x00000000
[Image: g12b_v1.1.3390-6ac5299 2019-09-26 14:10:05 luan.yuan@droid15-sz]
OPS=0x10
ring efuse init
chipver efuse init
29 0b 10 00 01 25 14 00 00 11 39 32 34 54 31 50 
[0.018961 Inits done]
secure task start!
high task start!
low task start!
run into bl31
NOTICE:  BL31: v1.3(release):4fc40b1
NOTICE:  BL31: Built : 15:58:17, May 22 2019
NOTICE:  BL31: G12A normal boot!
NOTICE:  BL31: BL33 decompress pass
ERROR:   Error initializing runtime service opteed_fast


U-Boot 2015.01 (Apr 25 2023 - 07:31:09)

DRAM:  3.8 GiB
Relocation Offset is: d6e42000
spi_post_bind(spifc): req_seq = 0
register usb cfg[0][1] = 00000000d7f39750
aml_i2c_init_port init regs for 0
MCU version: 0x00 0x03
MCU version is to low! Doesn't support froce boot from SD card.
MMC:   aml_priv->desc_buf = 0x00000000d3e32a70
aml_priv->desc_buf = 0x00000000d3e34db0
SDIO Port B: 0, SDIO Port C: 1
co-phase 0x3, tx-dly 0, clock 400000
co-phase 0x3, tx-dly 0, clock 400000
co-phase 0x3, tx-dly 0, clock 400000
emmc/sd response timeout, cmd8, status=0x3ff2800
emmc/sd response timeout, cmd55, status=0x3ff2800
co-phase 0x3, tx-dly 0, clock 400000
co-phase 0x1, tx-dly 0, clock 40000000
aml_sd_retry_refix[983]:delay = 0x0,gadjust =0x2000
[mmc_startup] mmc refix success
init_part() 297: PART_TYPE_AML
[mmc_init] mmc init success
start dts,buffer=00000000d3e37620,dt_addr=00000000d3e37620
get_partition_from_dts() 91: ret 0
parts: 3
00:      logo	0000000000800000 1
01:   ramdisk	0000000002000000 1
02:    rootfs	ffffffffffffffff 4
init_part() 297: PART_TYPE_AML
eMMC/TSD partition table have been checked OK!
crc32_s:0x1577dad == storage crc_pattern:0x1577dad!!!
crc32_s:0xee152b83 == storage crc_pattern:0xee152b83!!!
crc32_s:0x79f50f07 == storage crc_pattern:0x79f50f07!!!
mmc env offset: 0x6c00000 
In:    serial
Out:   serial
Err:   serial
reboot_mode=cold_boot
[store]To run cmd[emmc dtb_read 0x1000000 0x40000]
_verify_dtb_checksum()-3477: calc 1562f336, store 1562f336
_verify_dtb_checksum()-3477: calc 1562f336, store 1562f336
dtb_read()-3694: total valid 2
update_old_dtb()-3675: do nothing
aml_i2c_init_port init regs for 0
fusb302_init: Device ID: 0x91
CC connected in 1 as UFP
fusb302 detect chip.port_num = 0
gpio: pin GPIOA_13 (gpio 77) value is 1
gpio: pin GPIOA_13 (gpio 77) value is 0
gpio: pin GPIOA_8 (gpio 72) value is 0

amlkey_init() enter!
[EFUSE_MSG]keynum is 1
vpu: clk_level in dts: 7
vpu: vpu_power_on
vpu: set clk: 666667000Hz, readback: 666666667Hz(0x100)
vpu: vpu_module_init_config
vpp: vpp_init
vpp: vpp osd2 matrix rgb2yuv..............
cvbs: cpuid:0x29
LCD_RESET PIN: 0
lcd: detect mode: tablet, key_valid: 0
lcd: detect lcd_clk_path: 1
lcd: load config from dts
lcd: pinctrl_version: 2
lcd: use panel_type=lcd_0
lcd: bl: pinctrl_version: 2
lcd: bl: name: backlight_pwm, method: 1
lcd: bl: aml_bl_power_ctrl: 0
Net:   dwmac.ff3f0000
amlkey_init() enter!
amlkey_init() 71: already init!
[EFUSE_MSG]keynum is 1
MACADDR:02:00:00:14:25:01(from chipid)

upgrade_step=2
reboot_mode:::: cold_boot

amlkey_init() enter!
amlkey_init() 71: already init!
[EFUSE_MSG]keynum is 1
[KM]Error:f[key_manage_query_size]L515:key[usid] not programed yet

mac address: c8:63:14:72:61:bc
[KM]Error:f[key_manage_query_size]L515:key[deviceid] not programed yet
edid preferred_mode is 1080p60hz[16]
hdr mode is 0
dv  mode is ver:0  len: 0
hdr10+ mode is 0
edid extension block number : 2
Dump EDID Rawdata
00ffffffffffff0010ac6640534543302215010380331d78ea0cb4a257519f27
0a5054a54b00714f8180d1c001010101010101010101023a801871382d40582c
4500fd1e1100001e000000ff00374335524a31384b304345530a000000fc0044
454c4c205032333131480a20000000fd00384c1e5311000a20202020202001be
02031b71230907078301000067030c002000802d43100403e2000f8c0ad08a20
e02d10103e9600a05a0000000000000000000000000000000000000000000000
0000000000000000000000000000000000000000000000000000000000000000
0000000000000000000000000000000000000000000000000000000000000018
Manufacturer: DEL Model 4066 Serial Number 809715027
EDID version: 1.3
Established timings supported:
  720x400@70Hz
  640x480@60Hz
  640x480@75Hz
  800x600@60Hz
  800x600@75Hz
  1024x768@60Hz
  1024x768@75Hz
  1280x1024@75Hz
Standard timings supported:
  1152x864@75Hz
  1280x1024@60Hz
  1920x1080@60Hz
Detailed mode (1) : Clock 148 MHz, 509 mm x 286 mm
               1920 2008 2052 2200 hborder 0
               1080 1084 1089 1125 vborder 0
               +hsync +vsync 
Serial number: 7C5RJ18K0CES
Monitor name: DELL P2311H
Monitor ranges (GTF): 56-76Hz V, 30-83kHz H, max dotclock 170MHz
Has 1 extension blocks
Checksum: 0xbe (valid)

CEA extension block
Extension version: 3
23 bytes of CEA data
    Linear PCM, max channels 2
  Vendor-specific data block, OUI 000c03 (HDMI)
    Supports_AI
    Maximum TMDS clock: 225MHz
    VIC  16 1920x1080@60Hz 
    VIC   4 1280x720@60Hz 
    VIC   3 720x480@60Hz 
    YCbCr quantization: (0)
    RGB quantization: (0)
    PT scan behaviour: (0)
    IT scan behaviour: (3)
    CE scan behaviour: (3)
Detailed mode (1) : Clock 27 MHz, 160 mm x 90 mm
                720  736  798  858 hborder 0
                480  489  495  525 vborder 0
               -hsync -vsync analog composite 
Checksum: 0x18 (valid)

khadas_support_mode 1080p60hz
bestmode is 1080p60hz, IEEEOUI 0x000c03
HDMI Mode
hpd_state=1
[OSD]load fb addr from dts:/meson-fb
[OSD]set initrd_high: 0x7f800000
[OSD]fb_addr for logo: 0x7f800000
[OSD]load fb addr from dts:/meson-fb
[OSD]fb_addr for logo: 0x7f800000
[OSD]VPP_OFIFO_SIZE:0xfff01fff
[CANVAS]canvas init
[CANVAS]addr=0x7f800000 width=5760, height=2160
card out
** Bad device mmc 0 **
Failed to mount ext2 filesystem...
** Unrecognized filesystem type **
1080138 bytes read in 46 ms (22.4 MiB/s)
[OSD]osd_hw.free_dst_data: 0,1919,0,1079
[OSD]osd1_update_disp_freescale_enable
cvbs: outputmode[1080p60hz] is invalid
vpp: vpp_matrix_update: 2
set hdmitx VIC = 16
config HPLL = 5940000 frac_rate = 1
HPLL: 0x3b3a04f7
HPLL: 0x1b3a04f7
HPLLv1: 0xdb3a04f7
config HPLL done
j = 6  vid_clk_div = 1
hdmitx: set enc for VIC: 16
hdmitx phy setting done
enc_vpu_bridge_reset[1312]
rx version is 1.4 or below  div=10
vpp: Rx hdr_info.hdr_sup_eotf_smpte_st_2084 = 0

amlkey_init() enter!
amlkey_init() 71: already init!
[EFUSE_MSG]keynum is 1
[KM]Error:f[key_manage_query_size]L515:key[usid] not programed yet

mac address: c8:63:14:72:61:bc
[KM]Error:f[key_manage_query_size]L515:key[deviceid] not programed yet
gpio: pin GPIOAO_7 (gpio 7) value is 1
saradc: 0x285, hw_ver: 0x32 (VIM3.V12)
Product checking: pass! Hardware version: VIM3.V12
normal power on
boot wol: disable
port mode is usb3.0
Hit Enter or space or Ctrl+C key to stop autoboot -- :  1  0 
pll tsensor avg: 0x2198, u_efuse: 0x8112
temp1: 47
ddr tsensor avg: 0x21bd, u_efuse: 0x80df
temp2: 49
device cool done
cfgload: start ...
cfgload: reading /boot.ini from mmc 0:1 ...
card out
** Bad device mmc 0 **
cfgload: no /boot.ini or empty file on mmc 0:1
cfgload: reading /boot/boot.ini from mmc 0:1 ...
card out
** Bad device mmc 0 **
cfgload: no /boot/boot.ini or empty file on mmc 0:1
cfgload: reading /boot.ini from mmc 1:1 ...
Failed to mount ext2 filesystem...
** Unrecognized filesystem type **
cfgload: no /boot.ini or empty file on mmc 1:1
cfgload: reading /boot/boot.ini from mmc 1:1 ...
Failed to mount ext2 filesystem...
** Unrecognized filesystem type **
cfgload: no /boot/boot.ini or empty file on mmc 1:1
cfgload: reading /boot/boot.ini from mmc 1:5 ...
10279 bytes read in 5 ms (2 MiB/s)
cfgload: applying boot.ini...
[#] Script a:d3e38fa0 l:10259 c:0 s:0 - run
Starting boot.ini...
saradc: 0x285, hw_ver: 0x32 (VIM3.V12)

mac address: c8:63:14:72:61:bc
uboot type: vendor
Scanning mmc 0:1...
card out
** Bad device mmc 0 **
Scanning mmc 0:5...
card out
** Bad device mmc 0 **
Scanning mmc 1:1...
** Unrecognized filesystem type **
Scanning mmc 1:5...
9169076 bytes read in 265 ms (33 MiB/s)
29245952 bytes read in 824 ms (33.8 MiB/s)
93256 bytes read in 9 ms (9.9 MiB/s)
4561 bytes read in 5 ms (890.6 KiB/s)
Import env.txt
Can not get u-boot part UUID, set to NULL
Booting legacy kernel...
port mode is usb3.0
Apply dtbo uart3
225 bytes read in 10 ms (21.5 KiB/s)
Apply dtbo pwm_f
345 bytes read in 9 ms (37.1 KiB/s)
Apply dtbo i2c3
223 bytes read in 8 ms (26.4 KiB/s)
Apply dtbo i2s
3330 bytes read in 8 ms (406.3 KiB/s)
Apply dtbo os08a10
1072 bytes read in 9 ms (116.2 KiB/s)
Apply dtbo watchdog
222 bytes read in 10 ms (21.5 KiB/s)
HDMI: Autodetect: 1080p60hz
## Loading init Ramdisk from Legacy Image at 13000000 ...
   Image Name:   uInitrd
   Image Type:   AArch64 Linux RAMDisk Image (uncompressed)
   Data Size:    9169012 Bytes = 8.7 MiB
   Load Address: 00000000
   Entry Point:  00000000
   Verifying Checksum ... OK
load dtb from 0x1000000 ......
## Flattened Device Tree blob at 01000000
   Booting using the fdt blob at 0x1000000
   reserving fdt memory region: addr=1000000 size=17000
   Loading Ramdisk to 7ef41000, end 7f7ff874 ... OK
   Loading Device Tree to 000000001ffe6000, end 000000001fffffff ... OK

Starting kernel ...

uboot time: 5359769 us
[    0.369876@2] codec_mm_module_init
[    0.376741@2] clkmsr ffd18004.meson_clk_msr: failed to get msr ring reg0
[    0.556987@2] dmi: Firmware registration failed.
[    0.560692@2] lcd: error: failed to get null
[    0.560712@2] lcd: error: Out of clock range, reset to default setting
[    0.560786@2] lcd: error: lcd_debug_info_if is null
[    1.000256@5] mtdoops: mtd device (mtddev=name/number) must be supplied
[    1.021646@2] meson_cpufreq_init:don't find the node <dynamic_gp1_clk>
[    1.023432@2] meson_cpufreq_init:don't find the node <dynamic_gp1_clk>
[    1.025229@2] ff803000.serial: clock gate not found
[    1.081072@2] meson-remote ff808040.rc: don't find the node <led_blink>
[    1.082075@2] meson-remote ff808040.rc: don't find the node <led_blink_frq>
[    1.729765@0] efuse efuse:  open efuse clk gate error!!
[    1.763094@2] meson-fb meson-fb: create ion_client ffffffc07f246840, handle=ffffffc07f28b780
[    1.763096@2] meson-fb meson-fb: ion memory(0): created fb at 0x00000000e5800000, size 75 MiB
[    1.893240@0] di_get_vpu_clkb: get clk vpu error.
[    1.894542@0] get fclk_div5 err
[    1.895424@0] vdin_drv_probe: vdin cannot get msr clk !!!
[    1.901276@0] get fclk_div5 err
[    1.903974@0] vdin_drv_probe: vdin cannot get msr clk !!!
[    1.912598@0] amlvideo2 probe called
[    1.913084@0] amlvideo2 probe called
[    1.917066@0] Reserved memory: failed to init DMA memory pool at 0x00000000e3000000, size 0 MiB
[    1.928207@2] meson-mmc: >>>>>>>>hostbase ffffff80088f3000, dmode 
[    1.974290@0] meson-mmc: >>>>>>>>hostbase ffffff80088fc000, dmode 
[    2.014279@0] meson-mmc: >>>>>>>>hostbase ffffff8008b0a000, dmode 
[    2.021581@0] meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac
[    2.027243@0] meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac
[    2.034999@0] meson-mmc: Tuning transfer error: nmatch=26 tuning_err:0xffffffac
[    2.037759@0] meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac
[    2.055120@1] cectx ff80023c.aocec: cec driver date:2019/6/13:early suspend not report pw status
[    2.055120@1] 
[    2.060354@2] cectx ff80023c.aocec: compatible:amlogic, aocec-g12a
[    2.060359@2] cectx ff80023c.aocec: cecb_ver:0x1
[    2.060361@2] cectx ff80023c.aocec: line_reg:0x1
[    2.060362@2] cectx ff80023c.aocec: line_bit:0x3
[    2.060363@2] cectx ff80023c.aocec: ee_to_ao:0x1
[    2.066233@1] cectx ff80023c.aocec: not find 'port_num'
[    2.066237@1] cectx ff80023c.aocec: using cec:1
[    2.066270@1] cectx ff80023c.aocec: no hdmirx regs
[    2.066273@1] cectx ff80023c.aocec: no hhi regs
[    2.066281@1] cectx ff80023c.aocec: not find 'output'
[    2.067777@1] cectx ff80023c.aocec: irq cnt:2
[    2.075372@1] cectx ff80023c.aocec: wakeup_reason:0x0
[    2.079901@1] cectx ff80023c.aocec: cev val1: 0x0;val2: 0x0
[    2.079905@1] cectx ff80023c.aocec: aml_cec_probe success end
[    2.552809@1] defendkey ff630218.defendkey: Reserved memory is not enough!
[    2.777650@0] asoc-aml-card auge_sound: control 2:0:0:I2SIn CLK:0 is already present
[    2.779774@0] snd_tdm ff642000.audiobus:tdma: ASoC: Failed to add I2SIn CLK: -16
[    2.787160@0] aml_dai_tdm_probe, failed add snd tdm controls
[    2.792757@0] asoc-aml-card auge_sound: control 2:0:0:I2SIn CLK:0 is already present
[    2.800506@0] snd_tdm ff642000.audiobus:tdmc: ASoC: Failed to add I2SIn CLK: -16
[    2.807869@2] aml_dai_tdm_probe, failed add snd tdm controls
[    3.161577@5] edt_ft5x06 3-0038: touchscreen probe failed
[    3.282378@5] thermal thermal_zone0: binding zone soc_thermal with cdev thermal-cpufreq-0 failed:-22
[    3.285922@5] thermal thermal_zone0: binding zon

[Micky]

Hi Xiong,
Any clue from the logs I provided?
Can we tell if there is any problem on the VIM3, ex. some module is broken or eMMC has some issue?
Any experiment need I to do to find out the root cause?

[xiong.zhang]

Hello, according to the log messages you grabbed, I compared the logs that can be turned on normally. There is a high probability that there is a problem with the emmc of the board, and the messages of other modules are normal.

On the right is the vacant information in your log

[Micky]

Hi Xiong,
Thanks a lot for your analysis. I have two queries:

1. If my board has eMMC issue, ex. it has some bad block, can we use software (ex. bootloader) to mark these block and avoid system to use these bad blocks?
2. My VIM3 has been used for several times since I bought it, could I send back to repair my VIM3?

[Micky]

Hi Xiong,
I got the root cause for this issue now, this ticket can be closed.
Here is some details for you reference.
When I’m trying to use u-disk image to boot up and raised another ticket for it, as below link. numbqq suggested me to use your 24W power adapter, and this issue was gone with 24W power adapter. Just sync the info to you and thanks for your support:)

How to boot from USB when eMMC is flashed with image

Hello @Micky How you supply the power ? Do you use our 24W PD adaptor?

[xiong.zhang]

@xiong.zhang

vacant information in your log

Did you print out the blank log information in the picture above after you changed the adapter?

[Micky]

Hi Xiong,
When it could boot up successfully with the official image (vim3-android-9-64bit-v230719.img). I see the log info related to emmc is as below after I changed to the 24W adapter.

......
normal power on
boot wol: disable
[reg_31] 0xd8a
[reg_16] 0x0
[reg_17] 0x7fff
[reg_19] 0x0
[reg_31] 0x0
[reg_31] 0xd40
[reg_22] 0x0
[reg_31] 0x0
saradc: 0x287, hw_ver: 0x32 (VIM3.V12)
gpio: pin GPIOAO_7 (gpio 7) value is 1
port mode is usb3.0
Command: bcb uboot-command 
Start read misc partition datas!
BCB hasn't any datas,exit!
s_version: U-Boot 2015.01-gb6634a96e4-dirty
......

DRAM:  3.8 GiB
Relocation Offset is: d6e1d000
mmu cfg end: 0xe0000000
mmu cfg end: 0xe0000000
spi_post_bind(spifc): req_seq = 0
board init
register usb cfg[0][1] = 00000000d7f30708
aml_i2c_init_port init regs for 0
MCU version: 0x00 0x03
MCU version is to low! Doesn't support froce boot from SD card.
NAND:  MMC:   aml_priv->desc_buf = 0x00000000d3e0da70
aml_priv->desc_buf = 0x00000000d3e0fdb0
SDIO Port B: 0, SDIO Port C: 1
co-phase 0x3, tx-dly 0, clock 400000
co-phase 0x3, tx-dly 0, clock 400000
co-phase 0x3, tx-dly 0, clock 400000
emmc/sd response timeout, cmd8, cmd->cmdarg=0x1aa, status=0x3ff2800
emmc/sd response timeout, cmd55, cmd->cmdarg=0x0, status=0x3ff2800
co-phase 0x3, tx-dly 0, clock 400000
co-phase 0x1, tx-dly 0, clock 40000000
[set_emmc_calc_fixed_adj][875]find fixed adj_delay=20
init_part() 297: PART_TYPE_AML
[mmc_init] mmc init success