VIM1 linux4.9 stuck in boot

Hello,

I have seen this a couple of times, when I boot I get stuck. When looked in the log I can see it always get stuck on “[ 16.091696@3] snd_spdif_dai: Fine spdif sysclk setting range(0~2000000), 24575970”. Why is this and how can I resolve this?

Below is the complete log.

Kind regards,
Dieter

@Frank @numbqq

BL2 Built : 15:21:18, Aug 28 2019. gxl g1bf2b53 - luan.yuan@droid15-sz



set vcck to 1120 mv

set vddee to 1000 mv

Board ID = 8

CPU clk: 1200MHz

DQS-corr enabled

DDR scramble enabled

DDR3 chl: Rank0+1 @ 768MHz

bist_test rank: 0 1f 00 3f 33 14 53 21 00 43 33 12 55 22 01 43 31 12 51 23 05 42 31 11 52 664  rank: 1 1c 02 37 34 14 55 1f 00 3f 33 11 55 22 05 3f 32 12 52 22 06 3e 32 12 52 664   - PASS



Rank0: 1024MB(auto)-2T-11



Rank1: 1024MB(auto)-2T-11

AddrBus test pass!

eMMC boot @ 0

sw8 s

emmc switch 3 ok

Authentication key not yet programmed

get rpmb counter error 0x00000007

emmc switch 0 ok

Load fip header from eMMC, src: 0x0000c200, des: 0x01400000, size: 0x00004000, part: 0

New fip structure!

Load bl30 from eMMC, src: 0x00010200, des: 0x013c0000, size: 0x0000d600, part: 0

Load bl31 from eMMC, src: 0x00020200, des: 0x05100000, size: 0x0002b400, part: 0

Load bl33 from eMMC, src: 0x0004c200, des: 0x01000000, size: 0x00074400, part: 0

NOTICE:  BL3-1: v1.0(release):53f813e

NOTICE:  BL3-1: Built : 15:51:23, May 22 2019

[BL31]: GXL CPU setup!

NOTICE:  BL3-1: GXL normal boot!

NOTICE:  BL3-1: BL33 decompress pass

mpu_config_enable:system pre init ok


dmc sec lock


[Image: gxl_v1.1.3390-6ac5299 2019-09-26 14:09:24 luan.yuan@droid15-sz]


OPS=0x85


21 0e 85 00 55 94 13 9c 65 fb 41 47 b6 96 8b e6


[0.638730 Inits done]


secure task start!

high task start!

low task start!

ERROR:   Error initializing runtime service opteed_fast





U-Boot 2015.01 (Jul 25 2022 - 15:16:53)



DRAM:  2 GiB

Relocation Offset is: 76e92000

register usb cfg[0][1] = 0000000077f4cb28

[CANVAS]canvas init

aml_i2c_init_port init regs for 2

MMC:   aml_priv->desc_buf = 0x0000000073e92640

aml_priv->desc_buf = 0x0000000073e94980

SDIO Port B: 0, SDIO Port C: 1

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 400000

emmc/sd response timeout, cmd8, status=0x3ff2800

emmc/sd response timeout, cmd55, status=0x3ff2800

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 40000000

[mmc_startup] mmc refix success

init_part() 297: PART_TYPE_AML

[mmc_init] mmc init success

start dts,buffer=0000000073e971f0,dt_addr=0000000073e971f0

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

## defenv_reserve

Saving Environment to aml-storage...

mmc env offset: 0x6c00000

Writing to MMC(1)... done

reboot_mode=cold_boot

[store]To run cmd[emmc dtb_read 0x1000000 0x40000]

_verify_dtb_checksum()-3477: calc e0bec91f, store e0bec91f

_verify_dtb_checksum()-3477: calc e0bec91f, store e0bec91f

dtb_read()-3694: total valid 2

update_old_dtb()-3675: do nothing

vpu: clk_level in dts: 7

vpu: vpu_power_on

vpu: set clk: 666667000Hz, readback: 666666667Hz(0x300)

vpu: vpu_module_init_config

vpp: vpp_init

hpd_state=0

vpp: vpp_matrix_update: 2

set hdmitx VIC = 16

config HPLL = 2970000

HPLL: 0xc000027b

config HPLL done

j = 6  vid_clk_div = 1

hdmitx: set enc for VIC: 16

hdmitx phy setting done

rx version is 1.4 or below  div=10

hdmtix: set audio

Net:   dwmac.c9410000

amlkey_init() enter!

[EFUSE_MSG]keynum is 4

[BL31]: tee size: 0

MACADDR:02:65:9c:13:94:55(from chipid)



Warning: dwmac.c9410000 MAC addresses don't match:

Address in SROM is         b6:3c:5d:bd:f1:59

Address in environment is  02:65:9c:13:94:55



upgrade_step=2

reboot_mode:::: cold_boot



amlkey_init() enter!

amlkey_init() 71: already init!

[EFUSE_MSG]keynum is 4

[BL31]: tee size: 0

[KM]Error:f[key_manage_query_size]L515:key[usid] not programed yet

[KM]Error:f[key_manage_query_size]L515:key[mac] not programed yet

[KM]Error:f[key_manage_query_size]L515:key[deviceid] not programed yet

[KM]Error:f[keymanage_dts_get_key_device]L105:region_code key name is not exist

[KM]Error:f[_get_km_ops_by_name]L248:key region_code not know device 4

[KM]Error:f[key_manage_query_exist]L554:There isn't dts cfg for key[region_code]

[KM]Error:f[key_manage_query_size]L511:Fail in query key exist

edid preferred_mode is <NULL>[0]

hdr mode is 0

dv  mode is ver:0  len: 0

hdr10+ mode is 0

HDMI cable is NOT connected

hpd_state=0

[OSD]load fb addr from dts:/meson-fb

[OSD]fb_addr for logo: 0x7dc00000

[OSD]load fb addr from dts:/meson-fb

[OSD]fb_addr for logo: 0x7dc00000

[OSD]VPP_OFIFO_SIZE:0xfff00fff

[CANVAS]addr=0x7dc00000 width=5760, height=2160

card out

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 400000

emmc/sd response timeout, cmd8, status=0x1ff2800

emmc/sd response timeout, cmd55, status=0x1ff2800

emmc/sd response timeout, cmd1, status=0x1ff2800

** 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



amlkey_init() enter!

amlkey_init() 71: already init!

[EFUSE_MSG]keynum is 4

[BL31]: tee size: 0

[KM]Error:f[key_manage_query_size]L515:key[usid] not programed yet

[KM]Error:f[key_manage_query_size]L515:key[mac] not programed yet

[KM]Error:f[key_manage_query_size]L515:key[deviceid] not programed yet

[KM]Error:f[keymanage_dts_get_key_device]L105:region_code key name is not exist

[KM]Error:f[_get_km_ops_by_name]L248:key region_code not know device 4

[KM]Error:f[key_manage_query_exist]L554:There isn't dts cfg for key[region_code]

[KM]Error:f[key_manage_query_size]L511:Fail in query key exist

saradc - saradc sub-system



Usage:

saradc saradc open <channel>    - open a SARADC channel

saradc close    - close the SARADC

saradc getval   - get the value in current channel

saradc test     - test the SARADC by channel-7

saradc get_in_range <min> <max> - return 0 if current value in the range of current channel



gpio: pin GPIOAO_2 (gpio 102) value is 1

saradc: 0x28c, hw_ver: 0x14 (VIM1.V14)

Product checking: pass! Hardware version: VIM1.V14



amlkey_init() enter!

amlkey_init() 71: already init!

[EFUSE_MSG]keynum is 4

[BL31]: tee size: 0

[KM]Error:f[key_manage_query_size]L515:key[usid] not programed yet

[KM]Error:f[key_manage_query_size]L515:key[mac] not programed yet

[KM]Error:f[key_manage_query_size]L515:key[deviceid] not programed yet

[KM]Error:f[keymanage_dts_get_key_device]L105:region_code key name is not exist

[KM]Error:f[_get_km_ops_by_name]L248:key region_code not know device 4

[KM]Error:f[key_manage_query_exist]L554:There isn't dts cfg for key[region_code]

[KM]Error:f[key_manage_query_size]L511:Fail in query key exist

Hit Enter or space or Ctrl+C key to stop autoboot -- :  0

cfgload: start ...

cfgload: reading /boot.ini from mmc 0:1 ...

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 400000

emmc/sd response timeout, cmd8, status=0x1ff2800

emmc/sd response timeout, cmd55, status=0x1ff2800

emmc/sd response timeout, cmd1, status=0x1ff2800

** Bad device mmc 0 **

cfgload: no /boot.ini or empty file on mmc 0:1

cfgload: reading /boot/boot.ini from mmc 0:1 ...

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 400000

emmc/sd response timeout, cmd8, status=0x1ff2800

emmc/sd response timeout, cmd55, status=0x1ff2800

emmc/sd response timeout, cmd1, status=0x1ff2800

** 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 ...

10145 bytes read in 5 ms (1.9 MiB/s)

cfgload: applying boot.ini...

[#] Script a:73e856d0 l:10125 c:0 s:0 - run

Starting boot.ini...

saradc: 0x28b, hw_ver: 0x14 (VIM1.V14)

[BL31]: tee size: 0



mac address: 00:00:00:00:00:00

uboot type: vendor

Scanning mmc 0:1...

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 400000

emmc/sd response timeout, cmd8, status=0x1ff2800

emmc/sd response timeout, cmd55, status=0x1ff2800

emmc/sd response timeout, cmd1, status=0x1ff2800

** Bad device mmc 0 **

Scanning mmc 0:5...

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 400000

co-phase 0x2, tx-dly 0, clock 400000

emmc/sd response timeout, cmd8, status=0x1ff2800

emmc/sd response timeout, cmd55, status=0x1ff2800

emmc/sd response timeout, cmd1, status=0x1ff2800

** Bad device mmc 0 **

Scanning mmc 1:1...

** Unrecognized filesystem type **

Scanning mmc 1:5...

8768854 bytes read in 252 ms (33.2 MiB/s)

29248000 bytes read in 825 ms (33.8 MiB/s)

55468 bytes read in 7 ms (7.6 MiB/s)

3772 bytes read in 5 ms (736.3 KiB/s)

Import env.txt

Can not get u-boot part UUID, set to NULL

Found custom ethmac: 02:65:9c:13:94:55, overwrite eth_mac!

Booting legacy kernel...

Apply dtbo uart4

228 bytes read in 7 ms (31.3 KiB/s)

Apply dtbo watchdog

222 bytes read in 6 ms (36.1 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:    8768790 Bytes = 8.4 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=e000

   Loading Ramdisk to 73622000, end 73e7ed16 ... OK

   Loading Device Tree to 000000001ffef000, end 000000001fffffff ... OK



Starting kernel ...



uboot time: 3408318 us

domain-0 init dvfs: 4


[    0.000000@0] Reserved memory: unable to setup CMA region

[    0.285004@0] codec_mm_module_init

[    0.297239@0] clkmsr c110875c.meson_clk_msr: failed to get msr ring reg0

[    0.563435@0] dmi: Firmware registration failed.

[    1.098582@0] mtdoops: mtd device (mtddev=name/number) must be supplied

[    1.124605@0] c81004c0.serial: clock gate not found

[    1.151055@0] c11084c0.serial: clock gate not found

[    1.155969@0] c81004e0.serial: clock gate not found

[    1.161994@0] amlogic-new-usb3 d0078080.usb3phy: This phy has no usb port

[    1.177995@0] meson-remote c8100580.rc: don't find the node <led_blink>

[    1.179006@0] meson-remote c8100580.rc: don't find the node <led_blink_frq>

[    1.191973@0] ge2d: register address resource is not found

[    1.195193@0] fb: failed to init reserved memory

[    1.330681@0] get fclk_div5 err

[    1.330710@0] vdin_drv_probe: vdin cannot get msr clk !!!

[    1.334126@0] get fclk_div5 err

[    1.336686@0] vdin_drv_probe: vdin cannot get msr clk !!!

[    1.345767@0] amlvideo2 probe called

[    1.346112@0] amlvideo2 probe called

[    1.349842@0] Reserved memory: failed to init DMA memory pool at 0x0000000075000000, size 0 MiB

[    1.364332@0] meson-mmc: >>>>>>>>hostbase ffffff8008786000, dmode

[    1.410571@0] meson-mmc: >>>>>>>>hostbase ffffff80087c2000, dmode

[    1.451574@0] meson-mmc: >>>>>>>>hostbase ffffff80087cc000, dmode

[    1.491842@0] cectx c810023c.aocec: cec driver date:2019/6/13:early suspend not report pw status

[    1.491842@0]

[    1.497039@0] cectx c810023c.aocec: compatible:amlogic, amlogic-aocec

[    1.503170@0] cectx c810023c.aocec: cecb_ver:0x0

[    1.507665@0] cectx c810023c.aocec: line_reg:0x0

[    1.512473@0] cectx c810023c.aocec: line_bit:0x8

[    1.516927@0] cectx c810023c.aocec: ee_to_ao:0x0

[    1.522138@0] cectx c810023c.aocec: not find 'port_num'

[    1.526495@0] cectx c810023c.aocec: using cec:0

[    1.531094@0] cectx c810023c.aocec: get sleep state error!

[    1.536610@0] cectx c810023c.aocec: no hdmirx regs

[    1.541317@0] cectx c810023c.aocec: no hhi regs

[    1.545862@0] cectx c810023c.aocec: no periphs regs

[    1.550624@0] cectx c810023c.aocec: not find 'output'

[    1.559108@0] cectx c810023c.aocec: irq cnt:1

[    1.560480@0] cectx c810023c.aocec: wakeup_reason:0x0

[    1.565027@0] cectx c810023c.aocec: cev val1: 0x0;val2: 0x0

[    1.570326@0] cectx c810023c.aocec: aml_cec_probe success end

[    1.599648@0] meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac

[    1.604485@0] meson-mmc: emmc: warning... response crc,vstat:0xa1ff2400,virqc:3fff

[    1.608730@0] meson-mmc: emmc: err: wait for irq service, bus_fsm:0x8

[    1.615108@0] meson-mmc: emmc: cmd 18, arg 0x12000, operation is in [reserved] disk!

[    1.622800@1] meson-mmc: meson_mmc_irq_thread() 2786: set 1st retry!

[    1.629106@1] meson-mmc: retry cmd 18 the 10-th time(s)

[    1.634267@1] meson-mmc: cmd_delay change to 2

[    1.638678@0] meson-mmc: emmc: resp_timeout,vstat:0xa1ff2800,virqc:3fff

[    2.043058@0] defendkey c8834500.defendkey: Reserved memory is not enough!

[    2.055657@0] spdif-dit spdif_codec: audio_spdif_in_mute can't get pinctrl

[    2.056891@0] spdif-dit spdif_codec: audio_spdif_in can't get pinctrl

[    2.272783@0] aml_meson_snd_card aml_sound_meson: ASoC: no source widget found for LOUTL

[    2.275285@0] aml_meson_snd_card aml_sound_meson: ASoC: Failed to add route LOUTL -> direct -> Ext Spk

[    2.284605@0] aml_meson_snd_card aml_sound_meson: ASoC: no source widget found for LOUTR

[    2.292492@0] aml_meson_snd_card aml_sound_meson: ASoC: Failed to add route LOUTR -> direct -> Ext Spk

[    2.841413@0] hub 2-0:1.0: config failed, hub doesn't have any ports! (err -19)

[    2.843315@0] usb usb2: Unsupported the hub

[    2.852002@0] thermal: read num_of_pp failed

e2fsck 1.44.1 (24-Mar-2018)

ROOTFS: recovering journal

ROOTFS: clean, 138863/463296 files, 792312/1862656 blocks

SELinux:  Could not open policy file <= /etc/selinux/targeted/policy/policy.31:  No such file or directory

[   12.914841@2] cgroup: cgroup2: unknown option "nsdelegate"

[   13.341953@1] thermal thermal_zone0: binding zone soc_thermal with cdev thermal-gpufreq-0 failed:-22

[   14.285783@0] snd_i2s_dai: Fine tdm clk setting range (0~2000000), 12287985

[   14.295641@0] snd_spdif_dai: Fine spdif sysclk setting range(0~2000000), 24575970

[   14.534824@0] Amlogic A/V streaming port init

[   14.751906@0] meson-mmc: sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff

[   14.752881@0] meson-mmc: sdio: err: wait for irq service, bus_fsm:0x8

[   14.760401@0] meson-mmc: sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff

[   14.765822@0] meson-mmc: sdio: err: wait for irq service, bus_fsm:0x8

[   14.777389@0] meson-mmc: sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff

[   14.778757@0] meson-mmc: sdio: err: wait for irq service, bus_fsm:0x8

[   15.889408@3] meson-remote c8100580.rc: remote_ioctl invalid user space pointer

[   16.090161@3] snd_i2s_dai: Fine tdm clk setting range (0~2000000), 12287985

[   16.091696@3] snd_spdif_dai: Fine spdif sysclk setting range(0~2000000), 24575970