OP-TEE / optee_os

Trusted side of the TEE
Other
1.58k stars 1.06k forks source link

optee stm32mp1 hangs #5903

Closed embetrix closed 1 year ago

embetrix commented 1 year ago

Hi I use the following versions of arm-trusted-firmware 2.6 and optee_os 3.16 on a stm32mp175f-dk2 board

here are the exact commits I built the sources from:

arm-trusted-firmware

optee_os

u-boot

The boot process reset after the message : Primary CPU switching to normal world boot and I do not see any errors !

I cannot figure out what the problem is !

NOTICE:  CPU: STM32MP157FAC Rev.Z
NOTICE:  Model: STMicroelectronics STM32MP157F-DK2 Discovery Board
NOTICE:  Board: MB1272 Var4.0 Rev.C-02
NOTICE:  BL2: v2.6-stm32mp1-r2.0(release):v2.6-stm32mp-r2(4b6e8e9b)
NOTICE:  BL2: Built : 13:55:00, Oct 20 2022
NOTICE:  BL2: Booting BL32
I/TC: Early console on UART#4
D/TC:0   add_phys_mem:596 TEE_SHMEM_START type NSEC_SHM 0xde000000 size 0x00200000
D/TC:0   add_phys_mem:596 TA_RAM_START type TA_RAM 0xde200000 size 0x01e00000
D/TC:0   add_phys_mem:596 VCORE_INIT_RX_PA type INIT_RAM_RX 0x2fff3000 size 0x0000c000
D/TC:0   add_phys_mem:596 VCORE_UNPG_RW_PA type TEE_RAM_RW 0x2ffd8000 size 0x0001b000
D/TC:0   add_phys_mem:596 VCORE_UNPG_RX_PA type TEE_RAM_RX 0x2ffc0000 size 0x00018000
D/TC:0   add_phys_mem:596 TEE_RAM_START type ROM_SEC 0x2ffc0000 size 0x0003f000
D/TC:0   add_phys_mem:596 DDR_BASE type RAM_NSEC 0xc0000000 size 0x00001000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0xa0021000ul, CORE_MMU_PGDIR_SIZE) type IO_SEC 0xa0000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x54000000, CORE_MMU_PGDIR_SIZE) type IO_SEC 0x54000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x50000000, CORE_MMU_PGDIR_SIZE) type IO_SEC 0x50000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x5c000000, CORE_MMU_PGDIR_SIZE) type IO_SEC 0x5c000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x5a000000, CORE_MMU_PGDIR_SIZE) type IO_SEC 0x5a000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x50020000, CORE_MMU_PGDIR_SIZE) type IO_SEC 0x50000000 size 0x00200000
D/TC:0   add_phys_mem:610 Physical mem map overlaps 0x50000000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x54000000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x54000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x50000000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x50000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x5c000000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x5c000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x5a000000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x5a000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x50020000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x50000000 size 0x00200000
D/TC:0   add_phys_mem:610 Physical mem map overlaps 0x50000000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x44000000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x44000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x40000000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x40000000 size 0x00200000
D/TC:0   add_phys_mem:596 CFG_STM32MP1_SCMI_SHM_BASE type IO_NSEC 0x2ffff000 size 0x00001000
D/TC:0   add_va_space:636 type RES_VASPACE size 0x00a00000
D/TC:0   add_va_space:636 type SHM_VASPACE size 0x02000000
D/TC:0   dump_mmap_table:751 type TA_RAM       va 0x29800000..0x2b5fffff pa 0xde200000..0xdfffffff size 0x01e00000 (pgdir)
D/TC:0   dump_mmap_table:751 type NSEC_SHM     va 0x2b800000..0x2b9fffff pa 0xde000000..0xde1fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_SEC       va 0x2ba00000..0x2bbfffff pa 0xa0000000..0xa01fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2bc00000..0x2bdfffff pa 0x5c000000..0x5c1fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_SEC       va 0x2be00000..0x2bffffff pa 0x5c000000..0x5c1fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_SEC       va 0x2c000000..0x2c1fffff pa 0x5a000000..0x5a1fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2c200000..0x2c3fffff pa 0x5a000000..0x5a1fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_SEC       va 0x2c400000..0x2c5fffff pa 0x54000000..0x541fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2c600000..0x2c7fffff pa 0x54000000..0x541fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2c800000..0x2c9fffff pa 0x50000000..0x501fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_SEC       va 0x2ca00000..0x2cbfffff pa 0x50000000..0x501fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2cc00000..0x2cdfffff pa 0x44000000..0x441fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2ce00000..0x2cffffff pa 0x40000000..0x401fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type RES_VASPACE  va 0x2d000000..0x2d9fffff pa 0x00000000..0x009fffff size 0x00a00000 (pgdir)
D/TC:0   dump_mmap_table:751 type SHM_VASPACE  va 0x2dc00000..0x2fbfffff pa 0x00000000..0x01ffffff size 0x02000000 (pgdir)
D/TC:0   dump_mmap_table:751 type RAM_NSEC     va 0x2ff7f000..0x2ff7ffff pa 0xc0000000..0xc0000fff size 0x00001000 (smallpg)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2ff80000..0x2ff80fff pa 0x2ffff000..0x2fffffff size 0x00001000 (smallpg)
D/TC:0   dump_mmap_table:751 type ROM_SEC      va 0x2ff81000..0x2ffbffff pa 0x2ffc0000..0x2fffefff size 0x0003f000 (smallpg)
D/TC:0   dump_mmap_table:751 type TEE_RAM_RX   va 0x2ffc0000..0x2ffd7fff pa 0x2ffc0000..0x2ffd7fff size 0x00018000 (smallpg)
D/TC:0   dump_mmap_table:751 type TEE_RAM_RW   va 0x2ffd8000..0x2fff2fff pa 0x2ffd8000..0x2fff2fff size 0x0001b000 (smallpg)
D/TC:0   dump_mmap_table:751 type INIT_RAM_RX  va 0x2fff3000..0x2fffefff pa 0x2fff3000..0x2fffefff size 0x0000c000 (smallpg)
D/TC:0   dump_mmap_table:751 type PAGER_VASPACE va 0x2ffff000..0x301bffff pa 0x00000000..0x001c0fff size 0x001c1000 (smallpg)
D/TC:0   core_mmu_xlat_table_alloc:513 xlat tables used 1 / 4
D/TC:0   core_mmu_xlat_table_alloc:513 xlat tables used 2 / 4
D/TC:0   core_mmu_xlat_table_alloc:513 xlat tables used 3 / 4
I/TC: 
I/TC: Pager is enabled. Hashes: 2944 bytes
D/TC:0 0 init_runtime:467 Checking hashes of pageable area
D/TC:0 0 init_runtime:473 hash pg_idx 0 hash 0x2fff2478 page 0x29800000
D/TC:0 0 init_runtime:473 hash pg_idx 1 hash 0x2fff2498 page 0x29801000
D/TC:0 0 init_runtime:473 hash pg_idx 2 hash 0x2fff24b8 page 0x29802000
D/TC:0 0 init_runtime:473 hash pg_idx 3 hash 0x2fff24d8 page 0x29803000
D/TC:0 0 init_runtime:473 hash pg_idx 4 hash 0x2fff24f8 page 0x29804000
D/TC:0 0 init_runtime:473 hash pg_idx 5 hash 0x2fff2518 page 0x29805000
D/TC:0 0 init_runtime:473 hash pg_idx 6 hash 0x2fff2538 page 0x29806000
D/TC:0 0 init_runtime:473 hash pg_idx 7 hash 0x2fff2558 page 0x29807000
D/TC:0 0 init_runtime:473 hash pg_idx 8 hash 0x2fff2578 page 0x29808000
D/TC:0 0 init_runtime:473 hash pg_idx 9 hash 0x2fff2598 page 0x29809000
D/TC:0 0 init_runtime:473 hash pg_idx 10 hash 0x2fff25b8 page 0x2980a000
D/TC:0 0 init_runtime:473 hash pg_idx 11 hash 0x2fff25d8 page 0x2980b000
D/TC:0 0 init_runtime:473 hash pg_idx 12 hash 0x2fff25f8 page 0x2980c000
D/TC:0 0 init_runtime:473 hash pg_idx 13 hash 0x2fff2618 page 0x2980d000
D/TC:0 0 init_runtime:473 hash pg_idx 14 hash 0x2fff2638 page 0x2980e000
D/TC:0 0 init_runtime:473 hash pg_idx 15 hash 0x2fff2658 page 0x2980f000
D/TC:0 0 init_runtime:473 hash pg_idx 16 hash 0x2fff2678 page 0x29810000
D/TC:0 0 init_runtime:473 hash pg_idx 17 hash 0x2fff2698 page 0x29811000
D/TC:0 0 init_runtime:473 hash pg_idx 18 hash 0x2fff26b8 page 0x29812000
D/TC:0 0 init_runtime:473 hash pg_idx 19 hash 0x2fff26d8 page 0x29813000
D/TC:0 0 init_runtime:473 hash pg_idx 20 hash 0x2fff26f8 page 0x29814000
D/TC:0 0 init_runtime:473 hash pg_idx 21 hash 0x2fff2718 page 0x29815000
D/TC:0 0 init_runtime:473 hash pg_idx 22 hash 0x2fff2738 page 0x29816000
D/TC:0 0 init_runtime:473 hash pg_idx 23 hash 0x2fff2758 page 0x29817000
D/TC:0 0 init_runtime:473 hash pg_idx 24 hash 0x2fff2778 page 0x29818000
D/TC:0 0 init_runtime:473 hash pg_idx 25 hash 0x2fff2798 page 0x29819000
D/TC:0 0 init_runtime:473 hash pg_idx 26 hash 0x2fff27b8 page 0x2981a000
D/TC:0 0 init_runtime:473 hash pg_idx 27 hash 0x2fff27d8 page 0x2981b000
D/TC:0 0 init_runtime:473 hash pg_idx 28 hash 0x2fff27f8 page 0x2981c000
D/TC:0 0 init_runtime:473 hash pg_idx 29 hash 0x2fff2818 page 0x2981d000
D/TC:0 0 init_runtime:473 hash pg_idx 30 hash 0x2fff2838 page 0x2981e000
D/TC:0 0 init_runtime:473 hash pg_idx 31 hash 0x2fff2858 page 0x2981f000
D/TC:0 0 init_runtime:473 hash pg_idx 32 hash 0x2fff2878 page 0x29820000
D/TC:0 0 init_runtime:473 hash pg_idx 33 hash 0x2fff2898 page 0x29821000
D/TC:0 0 init_runtime:473 hash pg_idx 34 hash 0x2fff28b8 page 0x29822000
D/TC:0 0 init_runtime:473 hash pg_idx 35 hash 0x2fff28d8 page 0x29823000
D/TC:0 0 init_runtime:473 hash pg_idx 36 hash 0x2fff28f8 page 0x29824000
D/TC:0 0 init_runtime:473 hash pg_idx 37 hash 0x2fff2918 page 0x29825000
D/TC:0 0 init_runtime:473 hash pg_idx 38 hash 0x2fff2938 page 0x29826000
D/TC:0 0 init_runtime:473 hash pg_idx 39 hash 0x2fff2958 page 0x29827000
D/TC:0 0 init_runtime:473 hash pg_idx 40 hash 0x2fff2978 page 0x29828000
D/TC:0 0 init_runtime:473 hash pg_idx 41 hash 0x2fff2998 page 0x29829000
D/TC:0 0 init_runtime:473 hash pg_idx 42 hash 0x2fff29b8 page 0x2982a000
D/TC:0 0 init_runtime:473 hash pg_idx 43 hash 0x2fff29d8 page 0x2982b000
D/TC:0 0 init_runtime:473 hash pg_idx 44 hash 0x2fff29f8 page 0x2982c000
D/TC:0 0 init_runtime:473 hash pg_idx 45 hash 0x2fff2a18 page 0x2982d000
D/TC:0 0 init_runtime:473 hash pg_idx 46 hash 0x2fff2a38 page 0x2982e000
D/TC:0 0 init_runtime:473 hash pg_idx 47 hash 0x2fff2a58 page 0x2982f000
D/TC:0 0 init_runtime:473 hash pg_idx 48 hash 0x2fff2a78 page 0x29830000
D/TC:0 0 init_runtime:473 hash pg_idx 49 hash 0x2fff2a98 page 0x29831000
D/TC:0 0 init_runtime:473 hash pg_idx 50 hash 0x2fff2ab8 page 0x29832000
D/TC:0 0 init_runtime:473 hash pg_idx 51 hash 0x2fff2ad8 page 0x29833000
D/TC:0 0 init_runtime:473 hash pg_idx 52 hash 0x2fff2af8 page 0x29834000
D/TC:0 0 init_runtime:473 hash pg_idx 53 hash 0x2fff2b18 page 0x29835000
D/TC:0 0 init_runtime:473 hash pg_idx 54 hash 0x2fff2b38 page 0x29836000
D/TC:0 0 init_runtime:473 hash pg_idx 55 hash 0x2fff2b58 page 0x29837000
D/TC:0 0 init_runtime:473 hash pg_idx 56 hash 0x2fff2b78 page 0x29838000
D/TC:0 0 init_runtime:473 hash pg_idx 57 hash 0x2fff2b98 page 0x29839000
D/TC:0 0 init_runtime:473 hash pg_idx 58 hash 0x2fff2bb8 page 0x2983a000
D/TC:0 0 init_runtime:473 hash pg_idx 59 hash 0x2fff2bd8 page 0x2983b000
D/TC:0 0 init_runtime:473 hash pg_idx 60 hash 0x2fff2bf8 page 0x2983c000
D/TC:0 0 init_runtime:473 hash pg_idx 61 hash 0x2fff2c18 page 0x2983d000
D/TC:0 0 init_runtime:473 hash pg_idx 62 hash 0x2fff2c38 page 0x2983e000
D/TC:0 0 init_runtime:473 hash pg_idx 63 hash 0x2fff2c58 page 0x2983f000
D/TC:0 0 init_runtime:473 hash pg_idx 64 hash 0x2fff2c78 page 0x29840000
D/TC:0 0 init_runtime:473 hash pg_idx 65 hash 0x2fff2c98 page 0x29841000
D/TC:0 0 init_runtime:473 hash pg_idx 66 hash 0x2fff2cb8 page 0x29842000
D/TC:0 0 init_runtime:473 hash pg_idx 67 hash 0x2fff2cd8 page 0x29843000
D/TC:0 0 init_runtime:473 hash pg_idx 68 hash 0x2fff2cf8 page 0x29844000
D/TC:0 0 init_runtime:473 hash pg_idx 69 hash 0x2fff2d18 page 0x29845000
D/TC:0 0 init_runtime:473 hash pg_idx 70 hash 0x2fff2d38 page 0x29846000
D/TC:0 0 init_runtime:473 hash pg_idx 71 hash 0x2fff2d58 page 0x29847000
D/TC:0 0 init_runtime:473 hash pg_idx 72 hash 0x2fff2d78 page 0x29848000
D/TC:0 0 init_runtime:473 hash pg_idx 73 hash 0x2fff2d98 page 0x29849000
D/TC:0 0 init_runtime:473 hash pg_idx 74 hash 0x2fff2db8 page 0x2984a000
D/TC:0 0 init_runtime:473 hash pg_idx 75 hash 0x2fff2dd8 page 0x2984b000
D/TC:0 0 init_runtime:473 hash pg_idx 76 hash 0x2fff2df8 page 0x2984c000
D/TC:0 0 init_runtime:473 hash pg_idx 77 hash 0x2fff2e18 page 0x2984d000
D/TC:0 0 init_runtime:473 hash pg_idx 78 hash 0x2fff2e38 page 0x2984e000
D/TC:0 0 init_runtime:473 hash pg_idx 79 hash 0x2fff2e58 page 0x2984f000
D/TC:0 0 init_runtime:473 hash pg_idx 80 hash 0x2fff2e78 page 0x29850000
D/TC:0 0 init_runtime:473 hash pg_idx 81 hash 0x2fff2e98 page 0x29851000
D/TC:0 0 init_runtime:473 hash pg_idx 82 hash 0x2fff2eb8 page 0x29852000
D/TC:0 0 init_runtime:473 hash pg_idx 83 hash 0x2fff2ed8 page 0x29853000
D/TC:0 0 init_runtime:473 hash pg_idx 84 hash 0x2fff2ef8 page 0x29854000
D/TC:0 0 init_runtime:473 hash pg_idx 85 hash 0x2fff2f18 page 0x29855000
D/TC:0 0 init_runtime:473 hash pg_idx 86 hash 0x2fff2f38 page 0x29856000
D/TC:0 0 init_runtime:473 hash pg_idx 87 hash 0x2fff2f58 page 0x29857000
D/TC:0 0 init_runtime:473 hash pg_idx 88 hash 0x2fff2f78 page 0x29858000
D/TC:0 0 init_runtime:473 hash pg_idx 89 hash 0x2fff2f98 page 0x29859000
D/TC:0 0 init_runtime:473 hash pg_idx 90 hash 0x2fff2fb8 page 0x2985a000
D/TC:0 0 init_runtime:473 hash pg_idx 91 hash 0x2fff2fd8 page 0x2985b000
D/TC:0 0 tee_pager_set_alias_area:388 0x30181000 - 0x301c0000
D/TC:0 0 tee_pager_add_core_region:633 0x2fff3000 - 0x3004f000 : type 0
D/TC:0 0 tee_pager_add_pages:1830 0x2fff3000 - 0x2fff7000 : 0
D/TC:0 0 pager_add_alias_page:572 0x2fff3000
D/TC:0 0 pager_add_alias_page:572 0x2fff4000
D/TC:0 0 pager_add_alias_page:572 0x2fff5000
D/TC:0 0 pager_add_alias_page:572 0x2fff6000
D/TC:0 0 tee_pager_add_pages:1830 0x2fff7000 - 0x3004f000 : 1
D/TC:0 0 pager_add_alias_page:572 0x2fff7000
D/TC:0 0 pager_add_alias_page:572 0x2fff8000
D/TC:0 0 pager_add_alias_page:572 0x2fff9000
D/TC:0 0 pager_add_alias_page:572 0x2fffa000
D/TC:0 0 pager_add_alias_page:572 0x2fffb000
D/TC:0 0 pager_add_alias_page:572 0x2fffc000
D/TC:0 0 pager_add_alias_page:572 0x2fffd000
D/TC:0 0 pager_add_alias_page:572 0x2fffe000
D/TC:0 0 tee_pager_add_pages:1830 0x2ffc0000 - 0x2ffc0000 : 1
I/TC: Pager pool size: 44kB
D/TC:0 0 tee_pager_add_pages:1830 0x3004f000 - 0x30052000 : 1
D/TC:0 0 tee_pager_add_core_region:633 0x30050000 - 0x30052000 : type 2
D/TC:0 0 tee_pager_add_pages:1830 0x30052000 - 0x30055000 : 1
D/TC:0 0 tee_pager_add_core_region:633 0x30053000 - 0x30055000 : type 2
D/TC:0 0 tee_pager_add_pages:1830 0x30055000 - 0x30058000 : 1
D/TC:0 0 tee_pager_add_core_region:633 0x30056000 - 0x30058000 : type 2
D/TC:0 0 tee_pager_add_core_region:633 0x30058000 - 0x30059000 : type 2
D/TC:0 0 tee_pager_add_core_region:633 0x30059000 - 0x3005a000 : type 2
D/TC:0 0 tee_pager_add_core_region:633 0x3005a000 - 0x3005b000 : type 2
D/TC:0 0 tee_pager_add_core_region:633 0x3005b000 - 0x3005c000 : type 2
D/TC:0 0 tee_pager_add_core_region:633 0x3005c000 - 0x3005d000 : type 2
D/TC:0 0 tee_pager_add_core_region:633 0x3005d000 - 0x3005e000 : type 2
D/TC:0 0 stat_handle_fault:1740 nfaults 1 npages 11 (min 4294967295)
D/TC:0 0 stat_handle_fault:1740 nfaults 2 npages 10 (min 11)
I/TC: Non-secure external DT found
D/TC:0 0 carve_out_phys_mem:300 No need to carve out 0x2ffc0000 size 0x3f000
D/TC:0 0 add_optee_dt_node:710 OP-TEE Device Tree node already exists!
I/TC: Embedded DTB found
I/TC: OP-TEE version: 3.16.0-stm32mp-r2-1-g6c446f21 (gcc version 11.3.0 (GCC)) #1 Tue Feb 14 08:36:08 AM UTC 2023 arm
I/TC: WARNING: This OP-TEE configuration might be insecure!
I/TC: WARNING: Please check https://optee.readthedocs.io/en/latest/architecture/porting_guidelines.html
I/TC: Primary CPU initializing
D/TC:0 0 call_preinitcalls:21 level 2 mobj_mapped_shm_init()
D/TC:0 0 mobj_mapped_shm_init:463 Shared memory address range: 2dc00000, 2fc00000
D/TC:0 0 call_initcalls:40 level 1 register_time_source()
D/TC:0 0 call_initcalls:40 level 1 teecore_init_pub_ram()
D/TC:0 0 call_initcalls:40 level 1 initialize_bsec()
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[1] = cfg0_otp 0 8
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[2] = part_number_otp 1 8
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[3] = monotonic_otp 4 32
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[4] = nand_otp 9 32
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[5] = uid_otp 13 96
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[6] = package_otp 16 32
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[7] = hw2_otp 18 32
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[8] = calib 23 16
D/TC:0 0 save_dt_nvmem_layout:849 Misaligned nvmem calib@5e: ignored
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[9] = pkh_otp 24 256
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[10] = mac 57 64
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[11] = board_id 59 32
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[12] = huk_otp 60 128
D/TC:0 0 call_initcalls:40 level 1 clk_dt_probe()
D/TC:0 0 clk_dt_probe:201 Probing clocks from devicetree
D/TC:0 0 stat_handle_fault:1740 nfaults 1024 npages 10 (min 10)
D/TC:0 0 get_osc_freq_from_dt:1436 Osc clk-hsi: 64000000 Hz
D/TC:0 0 get_osc_freq_from_dt:1436 Osc clk-hse: 24000000 Hz
D/TC:0 0 get_osc_freq_from_dt:1436 Osc clk-csi: 4000000 Hz
D/TC:0 0 get_osc_freq_from_dt:1436 Osc clk-lsi: 32000 Hz
D/TC:0 0 get_osc_freq_from_dt:1436 Osc clk-lse: 32768 Hz
D/TC:0 0 get_osc_freq_from_dt:1442 Osc i2s_ckin: no frequency info
D/TC:0 0 get_osc_freq_from_dt:1442 Osc ck_usbo_48m: no frequency info
D/TC:0 0 stm32mp1_clk_fdt_init:1475 Ignore source clocks configuration from DT
D/TC:0 0 stm32mp1_clk_fdt_init:1479 Ignore clock divisors configuration from DT
D/TC:0 0 stm32mp1_clk_fdt_init:1483 Ignore peripheral clocks tree configuration from DT
D/TC:0 0 stm32mp1_clk_fdt_init:1497 Ignore PLL1 configurations from DT
D/TC:0 0 stm32mp1_clk_fdt_init:1497 Ignore PLL2 configurations from DT
D/TC:0 0 stm32mp1_clk_fdt_init:1497 Ignore PLL3 configurations from DT
I/TC: DT clock tree configurations were ignored
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 533000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 533000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 133250000
D/TC:0 0 clk_register:108 Registered clock (null), freq 133250000
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 133250000
D/TC:0 0 clk_register:108 Registered clock (null), freq 133250000
D/TC:0 0 clk_register:108 Registered clock spi6, freq 64000000
D/TC:0 0 clk_register:108 Registered clock i2c4, freq 64000000
D/TC:0 0 clk_register:108 Registered clock i2c6, freq 64000000
D/TC:0 0 clk_register:108 Registered clock usart1, freq 64000000
D/TC:0 0 clk_register:108 Registered clock rtcapb, freq 66625000
D/TC:0 0 clk_register:108 Registered clock (null), freq 66625000
D/TC:0 0 clk_register:108 Registered clock (null), freq 66625000
D/TC:0 0 clk_register:108 Registered clock (null), freq 66625000
D/TC:0 0 clk_register:108 Registered clock iwdg, freq 66625000
D/TC:0 0 clk_register:108 Registered clock (null), freq 66625000
D/TC:0 0 clk_register:108 Registered clock (null), freq 24000000
D/TC:0 0 clk_register:108 Registered clock gpioz, freq 266500000
D/TC:0 0 clk_register:108 Registered clock crpy1, freq 266500000
D/TC:0 0 clk_register:108 Registered clock hash1, freq 266500000
D/TC:0 0 clk_register:108 Registered clock rng1, freq 4000000
D/TC:0 0 clk_register:108 Registered clock bkpsram, freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock rtc, freq 32768
D/TC:0 0 clk_register:108 Registered clock gpioa, freq 0
D/TC:0 0 clk_register:108 Registered clock gpiob, freq 0
D/TC:0 0 clk_register:108 Registered clock gpioc, freq 0
D/TC:0 0 clk_register:108 Registered clock gpiod, freq 0
D/TC:0 0 clk_register:108 Registered clock gpioe, freq 0
D/TC:0 0 clk_register:108 Registered clock gpiof, freq 0
D/TC:0 0 clk_register:108 Registered clock gpiog, freq 0
D/TC:0 0 clk_register:108 Registered clock gpioh, freq 0
D/TC:0 0 clk_register:108 Registered clock gpioi, freq 0
D/TC:0 0 clk_register:108 Registered clock gpioj, freq 0
D/TC:0 0 clk_register:108 Registered clock gpiok, freq 0
D/TC:0 0 clk_register:108 Registered clock (null), freq 208877928
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 208877928
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock syscfg, freq 0
D/TC:0 0 clk_register:108 Registered clock (null), freq 0
D/TC:0 0 clk_register:108 Registered clock (null), freq 0
D/TC:0 0 clk_register:108 Registered clock dbg, freq 0
D/TC:0 0 clk_register:108 Registered clock hse, freq 24000000
D/TC:0 0 clk_register:108 Registered clock csi, freq 4000000
D/TC:0 0 clk_register:108 Registered clock lsi, freq 32000
D/TC:0 0 clk_register:108 Registered clock lse, freq 32768
D/TC:0 0 clk_register:108 Registered clock hsi, freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 12000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 650000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 650000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 650000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock pll2q, freq 533000000
D/TC:0 0 clk_register:108 Registered clock pll2r, freq 533000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 208877929
D/TC:0 0 clk_register:108 Registered clock pll3q, freq 24573874
D/TC:0 0 clk_register:108 Registered clock pll3r, freq 11290698
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 650000000
D/TC:0 0 clk_register:108 Registered clock mcu, freq 208877929
D/TC:0 0 probe_driver_node:390 element: stm32mp1_clock on node rcc@50000000 initialized
D/TC:0 0 stat_handle_fault:1740 nfaults 2048 npages 10 (min 10)
D/TC:0 0 stat_handle_fault:1740 nfaults 3072 npages 10 (min 10)
D/TC:0 0 stat_handle_fault:1740 nfaults 4096 npages 10 (min 10)
D/TC:0 0 stat_handle_fault:1740 nfaults 5120 npages 10 (min 10)
D/TC:0 0 stat_handle_fault:1740 nfaults 6144 npages 10 (min 10)
D/TC:0 0 call_initcalls:40 level 2 init_debug()
D/TC:0 0 stm32_bsec_find_otp_in_nvmem_layout:655 nvmem cfg0_otp = 0: 0 8
I/TC: WARNING: All debug access are allowed
D/TC:0 0 call_initcalls:40 level 2 probe_dt_drivers_early()
D/TC:0 0 add_node_to_probe:585 element: cpu0-opp-table on node stm32mp1-cpu-opp
D/TC:0 0 add_node_to_probe:585 element: soc on node simple-bus
D/TC:0 0 add_node_to_probe:585 element: vin on node fixed-regulator
D/TC:0 0 probe_driver_node:390 element: fixed-regulator on node vin initialized
D/TC:0 0 add_node_to_probe:585 element: timer@44006000 on node stm32-timer
D/TC:0 0 add_node_to_probe:585 element: pwr@50001000 on node stm32mp1-pwr-regu
D/TC:0 0 add_node_to_probe:585 element: watchdog@5a002000 on node stm32-iwdg
D/TC:0 0 add_node_to_probe:585 element: rtc@5c004000 on node stm32-rtc
D/TC:0 0 add_node_to_probe:585 element: tamp@5c00a000 on node stm32-tamp
D/TC:0 0 add_node_to_probe:585 element: tzc@5c006000 on node stm32mp1-tzc400
D/TC:0 0 add_node_to_probe:585 element: pin-controller@50002000 on node stm32_gpio
D/TC:0 0 add_node_to_probe:585 element: pin-controller-z@54004000 on node stm32_gpio
D/TC:0 0 stat_handle_fault:1740 nfaults 7168 npages 10 (min 10)
D/TC:0 0 add_node_to_probe:585 element: etzpc@5c007000 on node stm32-etzpc
D/TC:0 0 probe_driver_node:390 element: simple-bus on node soc initialized
D/TC:0 0 regulator_get_by_phandle:54 phandle 4 not found
D/TC:0 0 probe_driver_node:396 element: stm32mp1-cpu-opp on node cpu0-opp-table deferred 1 time(s)
D/TC:0 0 regulator_get_by_phandle:54 phandle 4 not found
D/TC:0 0 probe_driver_node:396 element: stm32mp1-cpu-opp on node cpu0-opp-table deferred 2 time(s)
D/TC:0 0 stm32_etzpc_set_driverdata:512 ETZPC revision 0x32, per_sec 96, ahb_sec 0, tzma 2
D/TC:0 0 add_node_to_probe:585 element: rng@54003000 on node stm32_rng
D/TC:0 0 add_node_to_probe:585 element: i2c@5c002000 on node stm32_i2c
D/TC:0 0 probe_driver_node:390 element: stm32-etzpc on node etzpc@5c007000 initialized
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@54004000
D/TC:0 0 _fdt_stm32_gpio_controller:717 GPIO bank Z assigned to non-secure
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank Z (8 pins) @2c404000
D/TC:0 0 probe_driver_node:390 element: stm32_gpio on node pin-controller-z@54004000 initialized
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50002000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50003000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50004000
D/TC:0 0 stat_handle_fault:1740 nfaults 8192 npages 10 (min 10)
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50005000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50006000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50007000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50008000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50009000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@5000a000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank Z (8 pins) @2c404000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank A (16 pins) @2c802000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank B (16 pins) @2c803000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank C (16 pins) @2c804000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank D (16 pins) @2c805000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank E (16 pins) @2c806000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank F (16 pins) @2c807000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank G (16 pins) @2c808000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank H (16 pins) @2c809000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank I (12 pins) @2c80a000
D/TC:0 0 probe_driver_node:390 element: stm32_gpio on node pin-controller@50002000 initialized
D/TC:0 0 tzc_set_driverdata:114 TZC400 Filters 2 Regions 8
D/TC:0 0 stm32mp1_tzc_probe:485 0x0xc0000000 - 0x0xddffffff : Sec access 0 NS access 0x87fb87fb
D/TC:0 0 tzc_dump_state:473 region 0
D/TC:0 0 tzc_dump_state:476 region_base: 0x0000000000000000
D/TC:0 0 tzc_dump_state:479 region_top: 0x00000000ffffffff
D/TC:0 0 tzc_dump_state:481 secure rw: TZC_REGION_S_NONE
D/TC:0 0 tzc_dump_state:486 filter 0 enable
D/TC:0 0 tzc_dump_state:486 filter 1 enable
D/TC:0 0 tzc_dump_state:473 region 1
D/TC:0 0 tzc_dump_state:476 region_base: 0x00000000de200000
D/TC:0 0 tzc_dump_state:479 region_top: 0x00000000dfffffff
D/TC:0 0 tzc_dump_state:481 secure rw: TZC_REGION_S_RDWR
D/TC:0 0 tzc_dump_state:486 filter 0 enable
D/TC:0 0 tzc_dump_state:486 filter 1 enable
D/TC:0 0 tzc_dump_state:473 region 2
D/TC:0 0 tzc_dump_state:476 region_base: 0x00000000de000000
D/TC:0 0 tzc_dump_state:479 region_top: 0x00000000de1fffff
D/TC:0 0 tzc_dump_state:481 secure rw: TZC_REGION_S_NONE
D/TC:0 0 tzc_dump_state:486 filter 0 enable
D/TC:0 0 tzc_dump_state:486 filter 1 enable
D/TC:0 0 tzc_dump_state:473 region 3
D/TC:0 0 tzc_dump_state:476 region_base: 0x00000000c0000000
D/TC:0 0 tzc_dump_state:479 region_top: 0x00000000ddffffff
D/TC:0 0 tzc_dump_state:481 secure rw: TZC_REGION_S_NONE
D/TC:0 0 tzc_dump_state:486 filter 0 enable
D/TC:0 0 tzc_dump_state:486 filter 1 enable
D/TC:0 0 gic_it_set_cpu_mask:330 cpu_mask: writing 0xff to 0x2ba21824
D/TC:0 0 gic_it_set_cpu_mask:332 cpu_mask: 0x3
D/TC:0 0 gic_it_set_prio:341 prio: writing 0x1 to 0x2ba21424
D/TC:0 0 probe_driver_node:390 element: stm32mp1-tzc400 on node tzc@5c006000 initialized
D/TC:0 0 stm32_tamp_parse_passive_conf:1083 Passive conf from dt: precharge=0, nb_sample=0, clk_div=32768
D/TC:0 0 stm32_tamp_parse_active_conf:1199 Active conf from dt: no filter clk_div=1
D/TC:0 0 gic_it_set_cpu_mask:330 cpu_mask: writing 0xff00 to 0x2ba218e4
D/TC:0 0 gic_it_set_cpu_mask:332 cpu_mask: 0x300
D/TC:0 0 gic_it_set_prio:341 prio: writing 0x1 to 0x2ba214e5
D/TC:0 0 probe_driver_node:390 element: stm32-tamp on node tamp@5c00a000 initialized
D/TC:0 0 stat_handle_fault:1740 nfaults 9216 npages 10 (min 10)
D/TC:0 0 probe_driver_node:390 element: stm32-rtc on node rtc@5c004000 initialized
D/TC:0 0 stm32mp_iwdg_iomem2instance:506 Base 5a002000
D/TC:0 0 stm32_bsec_find_otp_in_nvmem_layout:655 nvmem hw2_otp = 6: 18 32
D/TC:0 0 gic_it_set_cpu_mask:330 cpu_mask: writing 0xff000000 to 0x2ba218b4
D/TC:0 0 gic_it_set_cpu_mask:332 cpu_mask: 0x3000000
D/TC:0 0 gic_it_set_prio:341 prio: writing 0x1 to 0x2ba214b7
D/TC:0 0 probe_driver_node:390 element: stm32-iwdg on node watchdog@5a002000 initialized
D/TC:0 0 probe_driver_node:390 element: stm32mp1-pwr-regu on node pwr@50001000 initialized
D/TC:0 0 probe_driver_node:390 element: stm32-timer on node timer@44006000 initialized
D/TC:0 0 i2c_compute_timing:453 I2C SDADEL(min/max): 18/519, SCLDEL(Min): 285
D/TC:0 0 i2c_compute_timing:551 I2C TIMINGR (PRESC/SCLDEL/SDADEL): 1/8/1
D/TC:0 0 i2c_compute_timing:553 I2C TIMINGR (SCLH/SCLL): 27/38
D/TC:0 0 i2c_compute_timing:555 I2C TIMINGR: 0x10811b26
D/TC:0 0 i2c_setup_timing:642 I2C Freq(400000Hz), Clk Source(64000000)
D/TC:0 0 i2c_setup_timing:644 I2C Rise(185) and Fall(20) Time
D/TC:0 0 i2c_setup_timing:646 I2C Analog Filter(On), DNF(0)
D/TC:0 0 add_node_to_probe:585 element: stpmic@33 on node stm32_pmic
D/TC:0 0 probe_driver_node:390 element: stm32_i2c on node i2c@5c002000 initialized
D/TC:0 0 stat_handle_fault:1740 nfaults 10240 npages 10 (min 10)
D/TC:0 0 register_periph:172 Register RNG1 as secure
D/TC:0 0 probe_driver_node:390 element: stm32_rng on node rng@54003000 initialized
D/TC:0 0 regulator_get_by_phandle:54 phandle 4 not found
D/TC:0 0 probe_driver_node:396 element: stm32mp1-cpu-opp on node cpu0-opp-table deferred 3 time(s)
D/TC:0 0 regulator_get_by_phandle:54 phandle 4 not found
D/TC:0 0 probe_driver_node:396 element: stm32mp1-cpu-opp on node cpu0-opp-table deferred 4 time(s)
D/TC:0 0 register_periph:172 Register I2C4 as non-secure
D/TC:0 0 initialize_pmic:577 PMIC version = 0x20
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul buck1: enable, 1200mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul buck2: enable, 1350mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul buck3: enable, 3300mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul buck4: enable, 3300mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul ldo1: disable, 1800mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul ldo2: disable, 1800mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul ldo3: enable, 3300mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul ldo4: enable, 3300mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul ldo5: enable, 2900mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul ldo6: disable, 1000mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul vref_ddr: enable, 3300mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul boost: disable, 5000mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul pwr_sw1: disable, 5000mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul pwr_sw2: disable, 5000mV
D/TC:0 0 stat_handle_fault:1740 nfaults 11264 npages 10 (min 10)
D/TC:0 0 probe_driver_node:390 element: stm32_pmic on node stpmic@33 initialized
D/TC:0 0 stm32_bsec_find_otp_in_nvmem_layout:655 nvmem part_number_otp = 1: 1 8
D/TC:0 0 stm32mp1_cpu_opp_get_dt_subnode:265 Found OPP 0 (800000kHz/1350mV) from DT
D/TC:0 0 stm32mp1_cpu_opp_get_dt_subnode:265 Found OPP 1 (400000kHz/1200mV) from DT
D/TC:0 0 probe_driver_node:390 element: stm32mp1-cpu-opp on node cpu0-opp-table initialized
D/TC:0 0 call_initcalls:40 level 3 platform_banner()
I/TC: Platform stm32mp1: flavor PLATFORM_FLAVOR - DT stm32mp157f-dk2.dts
D/TC:0 0 call_initcalls:40 level 3 check_ta_store()
D/TC:0 0 check_ta_store:408 TA store: "early TA"
D/TC:0 0 check_ta_store:408 TA store: "Secure Storage TA"
D/TC:0 0 check_ta_store:408 TA store: "REE"
D/TC:0 0 call_initcalls:40 level 3 early_ta_init()
D/TC:0 0 early_ta_init:56 Early TA 1a8342cc-81a5-4512-99fe-9e2b3e37d626 size 42664
D/TC:0 0 call_initcalls:40 level 3 verify_pseudo_tas_conformance()
D/TC:0 0 call_initcalls:40 level 3 mobj_init()
D/TC:0 0 call_initcalls:40 level 3 default_mobj_init()
D/TC:0 0 call_initcalls:40 level 3 tee_cryp_init()
D/TC:0 0 tee_pager_add_core_region:633 0x3005e000 - 0x3006a000 : type 2
D/TC:0 0 stat_handle_fault:1740 nfaults 12288 npages 10 (min 10)
D/TC:0 0 call_initcalls:40 level 4 init_console_from_dt()
D/TC:0 0 register_periph_iomem:271 IO for non-secure resource 0x40010000
I/TC: DTB enables console (non-secure)
D/TC:0 0 call_initcalls:40 level 4 tee_fs_init_key_manager()
D/TC:0 0 call_initcalls:40 level 5 stm32mp1_iocomp()
D/TC:0 0 call_initcalls:40 level 5 init_pm_support()
D/TC:0 0 call_initcalls:40 level 5 probe_dt_drivers()
D/TC:0 0 call_initcalls:40 level 6 stm32_configure_tamp()
D/TC:0 0 stm32_tamp_set_int_config:412 INT_TAMP1 enabled as a full tamper
D/TC:0 0 stm32_tamp_set_int_config:412 INT_TAMP2 enabled as a full tamper
D/TC:0 0 stm32_tamp_set_int_config:412 INT_TAMP3 enabled as a full tamper
D/TC:0 0 stm32_tamp_set_int_config:412 INT_TAMP4 enabled as a full tamper
D/TC:0 0 call_initcalls:40 level 6 init_stm32mp1_drivers()
D/TC:0 0 call_initcalls:40 level 6 stm32mp1_init_scmi_server()
D/TC:0 0 call_initcalls:40 level 6 stm32mp1_init_final_shres()
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp IWDG1    ( 0): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp USART1   ( 1): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp SPI6     ( 2): non-secure    
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp I2C4     ( 3): non-secure    
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp RNG1     ( 4): secure        
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp HASH1    ( 5): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp CRYP1    ( 6): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp I2C6     ( 7): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp RTC      ( 8): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp MCU      ( 9): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp PLL3     (10): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp MDMA     (11): unregistered  
D/TC:0 0 stm32_bsec_find_otp_in_nvmem_layout:655 nvmem cfg0_otp = 0: 0 8
D/TC:0 0 check_rcc_secure_configuration:506 RCC/PWR secure hardening: TZEN enable, MCKPROT enable
D/TC:0 0 call_initcalls:40 level 6 init_stm32mp1_calib()
D/TC:0 0 init_stm32mp1_calib:547 Calibration csi-calibration enabled
D/TC:0 0 init_stm32mp1_calib:547 Calibration hsi-calibration enabled
D/TC:0 0 osc_calibration:305 Calibration : Freq 63885312 , trim 282
D/TC:0 0 osc_calibration:305 Calibration : Freq 3985024 , trim 117
D/TC:0 0 call_initcalls:40 level 6 stm32mp_syscfg_set_hslv()
D/TC:0 0 stm32_bsec_find_otp_in_nvmem_layout:655 nvmem hw2_otp = 6: 18 32
D/TC:0 0 call_initcalls:40 level 6 init_low_power()
D/TC:0 0 gic_it_set_cpu_mask:330 cpu_mask: writing 0xff00 to 0x2ba218b0
D/TC:0 0 gic_it_set_cpu_mask:332 cpu_mask: 0x300
D/TC:0 0 gic_it_set_prio:341 prio: writing 0x1 to 0x2ba214b1
D/TC:0 0 gic_it_set_cpu_mask:330 cpu_mask: writing 0x101ff01 to 0x2ba21808
D/TC:0 0 gic_it_set_cpu_mask:332 cpu_mask: 0x1010101
D/TC:0 0 gic_it_set_prio:341 prio: writing 0x1 to 0x2ba21409
D/TC:0 0 call_initcalls:40 level 6 stm32mp1_init_lp_states()
D/TC:0 0 stm32mp1_init_lp_states:236 Power configuration: shutdown to 6, suspend to 2
D/TC:0 0 call_initcalls:40 level 6 regulator_core_init()
D/TC:0 0 call_initcalls:40 level 6 scmi_regulator_consumer_init()
D/TC:0 0 scmi_regulator_consumer_init:348 scmi voltd shares reg11 (node reg11) on domain ID 0
D/TC:0 0 scmi_regulator_consumer_init:348 scmi voltd shares reg18 (node reg18) on domain ID 1
D/TC:0 0 scmi_regulator_consumer_init:348 scmi voltd shares usb33 (node usb33) on domain ID 2
D/TC:0 0 call_initcalls:40 level 6 rwp_init()
D/TC:0 0 tee_pager_add_core_region:633 0x3006a000 - 0x300a6000 : type 1
D/TC:0 0 call_initcalls:40 level 7 regulator_core_cleanup()
D/TC:0 0 regulator_core_cleanup:1009 disable pwr_sw2
D/TC:0 0 regulator_core_cleanup:1009 disable pwr_sw1
D/TC:0 0 regulator_core_cleanup:1009 disable boost
D/TC:0 0 regulator_core_cleanup:1009 disable ldo5
D/TC:0 0 regulator_core_cleanup:1009 disable ldo4
D/TC:0 0 regulator_core_cleanup:1009 disable usb33
D/TC:0 0 regulator_core_cleanup:1009 disable reg18
D/TC:0 0 regulator_core_cleanup:1009 disable reg11
D/TC:0 0 call_initcalls:40 level 7 release_probe_lists()
D/TC:0 0 call_finalcalls:59 level 1 release_external_dt()
I/TC: Primary CPU switching to normal world boot
NOTICE:  CPU: STM32MP157FAC Rev.Z
NOTICE:  Model: STMicroelectronics STM32MP157F-DK2 Discovery Board
NOTICE:  Board: MB1272 Var4.0 Rev.C-02
NOTICE:  BL2: v2.6-stm32mp1-r2.0(release):v2.6-stm32mp-r2(4b6e8e9b)
NOTICE:  BL2: Built : 13:55:00, Oct 20 2022
NOTICE:  BL2: Booting BL32
etienne-lms commented 1 year ago

Hello @embetrix. These repos are ST forks, not mainline repositories. I'm afraid you won't get accurate answer from OP-TEE forums on issues you can face. That said, add some verbosity in TF-A (LOG_LEVEL) to see where U-Boot is loaded and make sure is executes. U-Boot has early printk means that could help (CONFIG_DEBUG_UART_STM32=y + CONFIG_DEBUG_UART=y).

embetrix commented 1 year ago

Hello thanks for the tip but enabling debug in TF-A hangs the board very early on ! probably some size limits ?

embetrix commented 1 year ago

when enabling debug in u-boot I see the following after CPU switching to normal world boot

I/TC: Primary CPU switching to normal world boot
Channel unexpectedly busy, reset channel
Channel unexpectedly busy, reset channel
Channel unexpectedly busy, reset channel
embetrix commented 1 year ago

I did manage to build TF-A with debug support by adding the flags : DEBUG=1 LOG_LEVEL=40 over level 40 the build breaks because the size does not fit anymore

here is the output:

NOTICE:  CPU: STM32MP157FAC Rev.Z
NOTICE:  Model: STMicroelectronics STM32MP157F-DK2 Discovery Board
NOTICE:  Board: MB1272 Var4.0 Rev.C-02
INFO:    PMIC version = 0x20
INFO:    Reset reason (0x15):
INFO:      Power-on Reset (rst_por)
INFO:    FCONF: Reading TB_FW firmware configuration file from: 0x2ffe2000
INFO:    FCONF: Reading firmware configuration information for: stm32mp_io
INFO:    Using SDMMC
INFO:      Instance 1
INFO:    Boot used partition fsbl1
NOTICE:  BL2: v2.6-stm32mp1-r2.0(debug):v2.6-stm32mp-r2(4b6e8e9b)
NOTICE:  BL2: Built : 13:55:00, Oct 20 2022
INFO:    BL2: Doing platform setup
INFO:    RAM: DDR3-DDR3L 16bits 533000kHz
INFO:    Memory size = 0x20000000 (512 MB)
INFO:    BL2: Loading image id 1
INFO:    Loading image id=1 at address 0x2ffff000
INFO:    Image id=1 loaded: 0x2ffff000 - 0x2ffff1fa
INFO:    FCONF: Reading FW_CONFIG firmware configuration file from: 0x2ffff000
INFO:    FCONF: Reading firmware configuration information for: dyn_cfg
INFO:    FCONF: Reading firmware configuration information for: stm32mp1_firewall
INFO:    BL2: Loading image id 4
INFO:    Loading image id=4 at address 0x2ffc0000
INFO:    Image id=4 loaded: 0x2ffc0000 - 0x2ffc002c
INFO:    OPTEE ep=0x2ffc0000
INFO:    OPTEE header info:
INFO:          magic=0x4554504f
INFO:          version=0x2
INFO:          arch=0x0
INFO:          flags=0x0
INFO:          nb_images=0x2
INFO:    BL2: Loading image id 8
INFO:    Loading image id=8 at address 0x2ffc0000
INFO:    Image id=8 loaded: 0x2ffc0000 - 0x2ffdf720
INFO:    BL2: Loading image id 9
INFO:    Loading image id=9 at address 0xde200000
INFO:    Image id=9 loaded: 0xde200000 - 0xde258000
INFO:    BL2: Loading image id 2
INFO:    Loading image id=2 at address 0xc0500000
INFO:    Image id=2 loaded: 0xc0500000 - 0xc051287c
INFO:    BL2: Skip loading image id 16
INFO:    BL2: Loading image id 5
INFO:    Loading image id=5 at address 0xc0100000
INFO:    Image id=5 loaded: 0xc0100000 - 0xc01ca6b8
NOTICE:  BL2: Booting BL32
INFO:    Entry point address = 0x2ffc0000
INFO:    SPSR = 0x1d3
I/TC: Early console on UART#4
D/TC:0   add_phys_mem:596 TEE_SHMEM_START type NSEC_SHM 0xde000000 size 0x00200000
D/TC:0   add_phys_mem:596 TA_RAM_START type TA_RAM 0xde200000 size 0x01e00000
D/TC:0   add_phys_mem:596 VCORE_INIT_RX_PA type INIT_RAM_RX 0x2fff3000 size 0x0000c000
D/TC:0   add_phys_mem:596 VCORE_UNPG_RW_PA type TEE_RAM_RW 0x2ffd8000 size 0x0001b000
D/TC:0   add_phys_mem:596 VCORE_UNPG_RX_PA type TEE_RAM_RX 0x2ffc0000 size 0x00018000
D/TC:0   add_phys_mem:596 TEE_RAM_START type ROM_SEC 0x2ffc0000 size 0x0003f000
D/TC:0   add_phys_mem:596 DDR_BASE type RAM_NSEC 0xc0000000 size 0x00001000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0xa0021000ul, CORE_MMU_PGDIR_SIZE) type IO_SEC 0xa0000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x54000000, CORE_MMU_PGDIR_SIZE) type IO_SEC 0x54000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x50000000, CORE_MMU_PGDIR_SIZE) type IO_SEC 0x50000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x5c000000, CORE_MMU_PGDIR_SIZE) type IO_SEC 0x5c000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x5a000000, CORE_MMU_PGDIR_SIZE) type IO_SEC 0x5a000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x50020000, CORE_MMU_PGDIR_SIZE) type IO_SEC 0x50000000 size 0x00200000
D/TC:0   add_phys_mem:610 Physical mem map overlaps 0x50000000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x54000000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x54000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x50000000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x50000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x5c000000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x5c000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x5a000000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x5a000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x50020000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x50000000 size 0x00200000
D/TC:0   add_phys_mem:610 Physical mem map overlaps 0x50000000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x44000000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x44000000 size 0x00200000
D/TC:0   add_phys_mem:596 ROUNDDOWN(0x40000000, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x40000000 size 0x00200000
D/TC:0   add_phys_mem:596 CFG_STM32MP1_SCMI_SHM_BASE type IO_NSEC 0x2ffff000 size 0x00001000
D/TC:0   add_va_space:636 type RES_VASPACE size 0x00a00000
D/TC:0   add_va_space:636 type SHM_VASPACE size 0x02000000
D/TC:0   dump_mmap_table:751 type TA_RAM       va 0x29800000..0x2b5fffff pa 0xde200000..0xdfffffff size 0x01e00000 (pgdir)
D/TC:0   dump_mmap_table:751 type NSEC_SHM     va 0x2b800000..0x2b9fffff pa 0xde000000..0xde1fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_SEC       va 0x2ba00000..0x2bbfffff pa 0xa0000000..0xa01fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2bc00000..0x2bdfffff pa 0x5c000000..0x5c1fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_SEC       va 0x2be00000..0x2bffffff pa 0x5c000000..0x5c1fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_SEC       va 0x2c000000..0x2c1fffff pa 0x5a000000..0x5a1fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2c200000..0x2c3fffff pa 0x5a000000..0x5a1fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_SEC       va 0x2c400000..0x2c5fffff pa 0x54000000..0x541fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2c600000..0x2c7fffff pa 0x54000000..0x541fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2c800000..0x2c9fffff pa 0x50000000..0x501fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_SEC       va 0x2ca00000..0x2cbfffff pa 0x50000000..0x501fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2cc00000..0x2cdfffff pa 0x44000000..0x441fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2ce00000..0x2cffffff pa 0x40000000..0x401fffff size 0x00200000 (pgdir)
D/TC:0   dump_mmap_table:751 type RES_VASPACE  va 0x2d000000..0x2d9fffff pa 0x00000000..0x009fffff size 0x00a00000 (pgdir)
D/TC:0   dump_mmap_table:751 type SHM_VASPACE  va 0x2dc00000..0x2fbfffff pa 0x00000000..0x01ffffff size 0x02000000 (pgdir)
D/TC:0   dump_mmap_table:751 type RAM_NSEC     va 0x2ff7f000..0x2ff7ffff pa 0xc0000000..0xc0000fff size 0x00001000 (smallpg)
D/TC:0   dump_mmap_table:751 type IO_NSEC      va 0x2ff80000..0x2ff80fff pa 0x2ffff000..0x2fffffff size 0x00001000 (smallpg)
D/TC:0   dump_mmap_table:751 type ROM_SEC      va 0x2ff81000..0x2ffbffff pa 0x2ffc0000..0x2fffefff size 0x0003f000 (smallpg)
D/TC:0   dump_mmap_table:751 type TEE_RAM_RX   va 0x2ffc0000..0x2ffd7fff pa 0x2ffc0000..0x2ffd7fff size 0x00018000 (smallpg)
D/TC:0   dump_mmap_table:751 type TEE_RAM_RW   va 0x2ffd8000..0x2fff2fff pa 0x2ffd8000..0x2fff2fff size 0x0001b000 (smallpg)
D/TC:0   dump_mmap_table:751 type INIT_RAM_RX  va 0x2fff3000..0x2fffefff pa 0x2fff3000..0x2fffefff size 0x0000c000 (smallpg)
D/TC:0   dump_mmap_table:751 type PAGER_VASPACE va 0x2ffff000..0x301bffff pa 0x00000000..0x001c0fff size 0x001c1000 (smallpg)
D/TC:0   core_mmu_xlat_table_alloc:513 xlat tables used 1 / 4
D/TC:0   core_mmu_xlat_table_alloc:513 xlat tables used 2 / 4
D/TC:0   core_mmu_xlat_table_alloc:513 xlat tables used 3 / 4
I/TC: 
I/TC: Pager is enabled. Hashes: 2944 bytes
D/TC:0 0 init_runtime:467 Checking hashes of pageable area
D/TC:0 0 init_runtime:473 hash pg_idx 0 hash 0x2fff2478 page 0x29800000
D/TC:0 0 init_runtime:473 hash pg_idx 1 hash 0x2fff2498 page 0x29801000
D/TC:0 0 init_runtime:473 hash pg_idx 2 hash 0x2fff24b8 page 0x29802000
D/TC:0 0 init_runtime:473 hash pg_idx 3 hash 0x2fff24d8 page 0x29803000
D/TC:0 0 init_runtime:473 hash pg_idx 4 hash 0x2fff24f8 page 0x29804000
D/TC:0 0 init_runtime:473 hash pg_idx 5 hash 0x2fff2518 page 0x29805000
D/TC:0 0 init_runtime:473 hash pg_idx 6 hash 0x2fff2538 page 0x29806000
D/TC:0 0 init_runtime:473 hash pg_idx 7 hash 0x2fff2558 page 0x29807000
D/TC:0 0 init_runtime:473 hash pg_idx 8 hash 0x2fff2578 page 0x29808000
D/TC:0 0 init_runtime:473 hash pg_idx 9 hash 0x2fff2598 page 0x29809000
D/TC:0 0 init_runtime:473 hash pg_idx 10 hash 0x2fff25b8 page 0x2980a000
D/TC:0 0 init_runtime:473 hash pg_idx 11 hash 0x2fff25d8 page 0x2980b000
D/TC:0 0 init_runtime:473 hash pg_idx 12 hash 0x2fff25f8 page 0x2980c000
D/TC:0 0 init_runtime:473 hash pg_idx 13 hash 0x2fff2618 page 0x2980d000
D/TC:0 0 init_runtime:473 hash pg_idx 14 hash 0x2fff2638 page 0x2980e000
D/TC:0 0 init_runtime:473 hash pg_idx 15 hash 0x2fff2658 page 0x2980f000
D/TC:0 0 init_runtime:473 hash pg_idx 16 hash 0x2fff2678 page 0x29810000
D/TC:0 0 init_runtime:473 hash pg_idx 17 hash 0x2fff2698 page 0x29811000
D/TC:0 0 init_runtime:473 hash pg_idx 18 hash 0x2fff26b8 page 0x29812000
D/TC:0 0 init_runtime:473 hash pg_idx 19 hash 0x2fff26d8 page 0x29813000
D/TC:0 0 init_runtime:473 hash pg_idx 20 hash 0x2fff26f8 page 0x29814000
D/TC:0 0 init_runtime:473 hash pg_idx 21 hash 0x2fff2718 page 0x29815000
D/TC:0 0 init_runtime:473 hash pg_idx 22 hash 0x2fff2738 page 0x29816000
D/TC:0 0 init_runtime:473 hash pg_idx 23 hash 0x2fff2758 page 0x29817000
D/TC:0 0 init_runtime:473 hash pg_idx 24 hash 0x2fff2778 page 0x29818000
D/TC:0 0 init_runtime:473 hash pg_idx 25 hash 0x2fff2798 page 0x29819000
D/TC:0 0 init_runtime:473 hash pg_idx 26 hash 0x2fff27b8 page 0x2981a000
D/TC:0 0 init_runtime:473 hash pg_idx 27 hash 0x2fff27d8 page 0x2981b000
D/TC:0 0 init_runtime:473 hash pg_idx 28 hash 0x2fff27f8 page 0x2981c000
D/TC:0 0 init_runtime:473 hash pg_idx 29 hash 0x2fff2818 page 0x2981d000
D/TC:0 0 init_runtime:473 hash pg_idx 30 hash 0x2fff2838 page 0x2981e000
D/TC:0 0 init_runtime:473 hash pg_idx 31 hash 0x2fff2858 page 0x2981f000
D/TC:0 0 init_runtime:473 hash pg_idx 32 hash 0x2fff2878 page 0x29820000
D/TC:0 0 init_runtime:473 hash pg_idx 33 hash 0x2fff2898 page 0x29821000
D/TC:0 0 init_runtime:473 hash pg_idx 34 hash 0x2fff28b8 page 0x29822000
D/TC:0 0 init_runtime:473 hash pg_idx 35 hash 0x2fff28d8 page 0x29823000
D/TC:0 0 init_runtime:473 hash pg_idx 36 hash 0x2fff28f8 page 0x29824000
D/TC:0 0 init_runtime:473 hash pg_idx 37 hash 0x2fff2918 page 0x29825000
D/TC:0 0 init_runtime:473 hash pg_idx 38 hash 0x2fff2938 page 0x29826000
D/TC:0 0 init_runtime:473 hash pg_idx 39 hash 0x2fff2958 page 0x29827000
D/TC:0 0 init_runtime:473 hash pg_idx 40 hash 0x2fff2978 page 0x29828000
D/TC:0 0 init_runtime:473 hash pg_idx 41 hash 0x2fff2998 page 0x29829000
D/TC:0 0 init_runtime:473 hash pg_idx 42 hash 0x2fff29b8 page 0x2982a000
D/TC:0 0 init_runtime:473 hash pg_idx 43 hash 0x2fff29d8 page 0x2982b000
D/TC:0 0 init_runtime:473 hash pg_idx 44 hash 0x2fff29f8 page 0x2982c000
D/TC:0 0 init_runtime:473 hash pg_idx 45 hash 0x2fff2a18 page 0x2982d000
D/TC:0 0 init_runtime:473 hash pg_idx 46 hash 0x2fff2a38 page 0x2982e000
D/TC:0 0 init_runtime:473 hash pg_idx 47 hash 0x2fff2a58 page 0x2982f000
D/TC:0 0 init_runtime:473 hash pg_idx 48 hash 0x2fff2a78 page 0x29830000
D/TC:0 0 init_runtime:473 hash pg_idx 49 hash 0x2fff2a98 page 0x29831000
D/TC:0 0 init_runtime:473 hash pg_idx 50 hash 0x2fff2ab8 page 0x29832000
D/TC:0 0 init_runtime:473 hash pg_idx 51 hash 0x2fff2ad8 page 0x29833000
D/TC:0 0 init_runtime:473 hash pg_idx 52 hash 0x2fff2af8 page 0x29834000
D/TC:0 0 init_runtime:473 hash pg_idx 53 hash 0x2fff2b18 page 0x29835000
D/TC:0 0 init_runtime:473 hash pg_idx 54 hash 0x2fff2b38 page 0x29836000
D/TC:0 0 init_runtime:473 hash pg_idx 55 hash 0x2fff2b58 page 0x29837000
D/TC:0 0 init_runtime:473 hash pg_idx 56 hash 0x2fff2b78 page 0x29838000
D/TC:0 0 init_runtime:473 hash pg_idx 57 hash 0x2fff2b98 page 0x29839000
D/TC:0 0 init_runtime:473 hash pg_idx 58 hash 0x2fff2bb8 page 0x2983a000
D/TC:0 0 init_runtime:473 hash pg_idx 59 hash 0x2fff2bd8 page 0x2983b000
D/TC:0 0 init_runtime:473 hash pg_idx 60 hash 0x2fff2bf8 page 0x2983c000
D/TC:0 0 init_runtime:473 hash pg_idx 61 hash 0x2fff2c18 page 0x2983d000
D/TC:0 0 init_runtime:473 hash pg_idx 62 hash 0x2fff2c38 page 0x2983e000
D/TC:0 0 init_runtime:473 hash pg_idx 63 hash 0x2fff2c58 page 0x2983f000
D/TC:0 0 init_runtime:473 hash pg_idx 64 hash 0x2fff2c78 page 0x29840000
D/TC:0 0 init_runtime:473 hash pg_idx 65 hash 0x2fff2c98 page 0x29841000
D/TC:0 0 init_runtime:473 hash pg_idx 66 hash 0x2fff2cb8 page 0x29842000
D/TC:0 0 init_runtime:473 hash pg_idx 67 hash 0x2fff2cd8 page 0x29843000
D/TC:0 0 init_runtime:473 hash pg_idx 68 hash 0x2fff2cf8 page 0x29844000
D/TC:0 0 init_runtime:473 hash pg_idx 69 hash 0x2fff2d18 page 0x29845000
D/TC:0 0 init_runtime:473 hash pg_idx 70 hash 0x2fff2d38 page 0x29846000
D/TC:0 0 init_runtime:473 hash pg_idx 71 hash 0x2fff2d58 page 0x29847000
D/TC:0 0 init_runtime:473 hash pg_idx 72 hash 0x2fff2d78 page 0x29848000
D/TC:0 0 init_runtime:473 hash pg_idx 73 hash 0x2fff2d98 page 0x29849000
D/TC:0 0 init_runtime:473 hash pg_idx 74 hash 0x2fff2db8 page 0x2984a000
D/TC:0 0 init_runtime:473 hash pg_idx 75 hash 0x2fff2dd8 page 0x2984b000
D/TC:0 0 init_runtime:473 hash pg_idx 76 hash 0x2fff2df8 page 0x2984c000
D/TC:0 0 init_runtime:473 hash pg_idx 77 hash 0x2fff2e18 page 0x2984d000
D/TC:0 0 init_runtime:473 hash pg_idx 78 hash 0x2fff2e38 page 0x2984e000
D/TC:0 0 init_runtime:473 hash pg_idx 79 hash 0x2fff2e58 page 0x2984f000
D/TC:0 0 init_runtime:473 hash pg_idx 80 hash 0x2fff2e78 page 0x29850000
D/TC:0 0 init_runtime:473 hash pg_idx 81 hash 0x2fff2e98 page 0x29851000
D/TC:0 0 init_runtime:473 hash pg_idx 82 hash 0x2fff2eb8 page 0x29852000
D/TC:0 0 init_runtime:473 hash pg_idx 83 hash 0x2fff2ed8 page 0x29853000
D/TC:0 0 init_runtime:473 hash pg_idx 84 hash 0x2fff2ef8 page 0x29854000
D/TC:0 0 init_runtime:473 hash pg_idx 85 hash 0x2fff2f18 page 0x29855000
D/TC:0 0 init_runtime:473 hash pg_idx 86 hash 0x2fff2f38 page 0x29856000
D/TC:0 0 init_runtime:473 hash pg_idx 87 hash 0x2fff2f58 page 0x29857000
D/TC:0 0 init_runtime:473 hash pg_idx 88 hash 0x2fff2f78 page 0x29858000
D/TC:0 0 init_runtime:473 hash pg_idx 89 hash 0x2fff2f98 page 0x29859000
D/TC:0 0 init_runtime:473 hash pg_idx 90 hash 0x2fff2fb8 page 0x2985a000
D/TC:0 0 init_runtime:473 hash pg_idx 91 hash 0x2fff2fd8 page 0x2985b000
D/TC:0 0 tee_pager_set_alias_area:388 0x30181000 - 0x301c0000
D/TC:0 0 tee_pager_add_core_region:633 0x2fff3000 - 0x3004f000 : type 0
D/TC:0 0 tee_pager_add_pages:1830 0x2fff3000 - 0x2fff7000 : 0
D/TC:0 0 pager_add_alias_page:572 0x2fff3000
D/TC:0 0 pager_add_alias_page:572 0x2fff4000
D/TC:0 0 pager_add_alias_page:572 0x2fff5000
D/TC:0 0 pager_add_alias_page:572 0x2fff6000
D/TC:0 0 tee_pager_add_pages:1830 0x2fff7000 - 0x3004f000 : 1
D/TC:0 0 pager_add_alias_page:572 0x2fff7000
D/TC:0 0 pager_add_alias_page:572 0x2fff8000
D/TC:0 0 pager_add_alias_page:572 0x2fff9000
D/TC:0 0 pager_add_alias_page:572 0x2fffa000
D/TC:0 0 pager_add_alias_page:572 0x2fffb000
D/TC:0 0 pager_add_alias_page:572 0x2fffc000
D/TC:0 0 pager_add_alias_page:572 0x2fffd000
D/TC:0 0 pager_add_alias_page:572 0x2fffe000
D/TC:0 0 tee_pager_add_pages:1830 0x2ffc0000 - 0x2ffc0000 : 1
I/TC: Pager pool size: 44kB
D/TC:0 0 tee_pager_add_pages:1830 0x3004f000 - 0x30052000 : 1
D/TC:0 0 tee_pager_add_core_region:633 0x30050000 - 0x30052000 : type 2
D/TC:0 0 tee_pager_add_pages:1830 0x30052000 - 0x30055000 : 1
D/TC:0 0 tee_pager_add_core_region:633 0x30053000 - 0x30055000 : type 2
D/TC:0 0 tee_pager_add_pages:1830 0x30055000 - 0x30058000 : 1
D/TC:0 0 tee_pager_add_core_region:633 0x30056000 - 0x30058000 : type 2
D/TC:0 0 tee_pager_add_core_region:633 0x30058000 - 0x30059000 : type 2
D/TC:0 0 tee_pager_add_core_region:633 0x30059000 - 0x3005a000 : type 2
D/TC:0 0 tee_pager_add_core_region:633 0x3005a000 - 0x3005b000 : type 2
D/TC:0 0 tee_pager_add_core_region:633 0x3005b000 - 0x3005c000 : type 2
D/TC:0 0 tee_pager_add_core_region:633 0x3005c000 - 0x3005d000 : type 2
D/TC:0 0 tee_pager_add_core_region:633 0x3005d000 - 0x3005e000 : type 2
D/TC:0 0 stat_handle_fault:1740 nfaults 1 npages 11 (min 4294967295)
D/TC:0 0 stat_handle_fault:1740 nfaults 2 npages 10 (min 11)
I/TC: Non-secure external DT found
D/TC:0 0 carve_out_phys_mem:300 No need to carve out 0x2ffc0000 size 0x3f000
D/TC:0 0 add_optee_dt_node:710 OP-TEE Device Tree node already exists!
I/TC: Embedded DTB found
I/TC: OP-TEE version: 3.16.0-stm32mp-r2-1-g6c446f21 (gcc version 11.3.0 (GCC)) #1 Tue Feb 14 08:36:08 AM UTC 2023 arm
I/TC: WARNING: This OP-TEE configuration might be insecure!
I/TC: WARNING: Please check https://optee.readthedocs.io/en/latest/architecture/porting_guidelines.html
I/TC: Primary CPU initializing
D/TC:0 0 call_preinitcalls:21 level 2 mobj_mapped_shm_init()
D/TC:0 0 mobj_mapped_shm_init:463 Shared memory address range: 2dc00000, 2fc00000
D/TC:0 0 call_initcalls:40 level 1 register_time_source()
D/TC:0 0 call_initcalls:40 level 1 teecore_init_pub_ram()
D/TC:0 0 call_initcalls:40 level 1 initialize_bsec()
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[1] = cfg0_otp 0 8
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[2] = part_number_otp 1 8
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[3] = monotonic_otp 4 32
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[4] = nand_otp 9 32
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[5] = uid_otp 13 96
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[6] = package_otp 16 32
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[7] = hw2_otp 18 32
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[8] = calib 23 16
D/TC:0 0 save_dt_nvmem_layout:849 Misaligned nvmem calib@5e: ignored
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[9] = pkh_otp 24 256
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[10] = mac 57 64
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[11] = board_id 59 32
D/TC:0 0 save_dt_nvmem_layout:865 nvmem[12] = huk_otp 60 128
D/TC:0 0 call_initcalls:40 level 1 clk_dt_probe()
D/TC:0 0 clk_dt_probe:201 Probing clocks from devicetree
D/TC:0 0 stat_handle_fault:1740 nfaults 1024 npages 10 (min 10)
D/TC:0 0 get_osc_freq_from_dt:1436 Osc clk-hsi: 64000000 Hz
D/TC:0 0 get_osc_freq_from_dt:1436 Osc clk-hse: 24000000 Hz
D/TC:0 0 get_osc_freq_from_dt:1436 Osc clk-csi: 4000000 Hz
D/TC:0 0 get_osc_freq_from_dt:1436 Osc clk-lsi: 32000 Hz
D/TC:0 0 get_osc_freq_from_dt:1436 Osc clk-lse: 32768 Hz
D/TC:0 0 get_osc_freq_from_dt:1442 Osc i2s_ckin: no frequency info
D/TC:0 0 get_osc_freq_from_dt:1442 Osc ck_usbo_48m: no frequency info
D/TC:0 0 stm32mp1_clk_fdt_init:1475 Ignore source clocks configuration from DT
D/TC:0 0 stm32mp1_clk_fdt_init:1479 Ignore clock divisors configuration from DT
D/TC:0 0 stm32mp1_clk_fdt_init:1483 Ignore peripheral clocks tree configuration from DT
D/TC:0 0 stm32mp1_clk_fdt_init:1497 Ignore PLL1 configurations from DT
D/TC:0 0 stm32mp1_clk_fdt_init:1497 Ignore PLL2 configurations from DT
D/TC:0 0 stm32mp1_clk_fdt_init:1497 Ignore PLL3 configurations from DT
I/TC: DT clock tree configurations were ignored
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 533000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 533000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 133250000
D/TC:0 0 clk_register:108 Registered clock (null), freq 133250000
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 133250000
D/TC:0 0 clk_register:108 Registered clock (null), freq 133250000
D/TC:0 0 clk_register:108 Registered clock spi6, freq 64000000
D/TC:0 0 clk_register:108 Registered clock i2c4, freq 64000000
D/TC:0 0 clk_register:108 Registered clock i2c6, freq 64000000
D/TC:0 0 clk_register:108 Registered clock usart1, freq 64000000
D/TC:0 0 clk_register:108 Registered clock rtcapb, freq 66625000
D/TC:0 0 clk_register:108 Registered clock (null), freq 66625000
D/TC:0 0 clk_register:108 Registered clock (null), freq 66625000
D/TC:0 0 clk_register:108 Registered clock (null), freq 66625000
D/TC:0 0 clk_register:108 Registered clock iwdg, freq 66625000
D/TC:0 0 clk_register:108 Registered clock (null), freq 66625000
D/TC:0 0 clk_register:108 Registered clock (null), freq 24000000
D/TC:0 0 clk_register:108 Registered clock gpioz, freq 266500000
D/TC:0 0 clk_register:108 Registered clock crpy1, freq 266500000
D/TC:0 0 clk_register:108 Registered clock hash1, freq 266500000
D/TC:0 0 clk_register:108 Registered clock rng1, freq 4000000
D/TC:0 0 clk_register:108 Registered clock bkpsram, freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock rtc, freq 32768
D/TC:0 0 clk_register:108 Registered clock gpioa, freq 0
D/TC:0 0 clk_register:108 Registered clock gpiob, freq 0
D/TC:0 0 clk_register:108 Registered clock gpioc, freq 0
D/TC:0 0 clk_register:108 Registered clock gpiod, freq 0
D/TC:0 0 clk_register:108 Registered clock gpioe, freq 0
D/TC:0 0 clk_register:108 Registered clock gpiof, freq 0
D/TC:0 0 clk_register:108 Registered clock gpiog, freq 0
D/TC:0 0 clk_register:108 Registered clock gpioh, freq 0
D/TC:0 0 clk_register:108 Registered clock gpioi, freq 0
D/TC:0 0 clk_register:108 Registered clock gpioj, freq 0
D/TC:0 0 clk_register:108 Registered clock gpiok, freq 0
D/TC:0 0 clk_register:108 Registered clock (null), freq 208877928
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 208877928
D/TC:0 0 clk_register:108 Registered clock (null), freq 64000000
D/TC:0 0 clk_register:108 Registered clock syscfg, freq 0
D/TC:0 0 clk_register:108 Registered clock (null), freq 0
D/TC:0 0 clk_register:108 Registered clock (null), freq 0
D/TC:0 0 clk_register:108 Registered clock dbg, freq 0
D/TC:0 0 clk_register:108 Registered clock hse, freq 24000000
D/TC:0 0 clk_register:108 Registered clock csi, freq 4000000
D/TC:0 0 clk_register:108 Registered clock lsi, freq 32000
D/TC:0 0 clk_register:108 Registered clock lse, freq 32768
D/TC:0 0 clk_register:108 Registered clock hsi, freq 64000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 12000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 650000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 650000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 650000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock pll2q, freq 533000000
D/TC:0 0 clk_register:108 Registered clock pll2r, freq 533000000
D/TC:0 0 clk_register:108 Registered clock (null), freq 208877929
D/TC:0 0 clk_register:108 Registered clock pll3q, freq 24573874
D/TC:0 0 clk_register:108 Registered clock pll3r, freq 11290698
D/TC:0 0 clk_register:108 Registered clock (null), freq 266500000
D/TC:0 0 clk_register:108 Registered clock (null), freq 650000000
D/TC:0 0 clk_register:108 Registered clock mcu, freq 208877929
D/TC:0 0 probe_driver_node:390 element: stm32mp1_clock on node rcc@50000000 initialized
D/TC:0 0 stat_handle_fault:1740 nfaults 2048 npages 10 (min 10)
D/TC:0 0 stat_handle_fault:1740 nfaults 3072 npages 10 (min 10)
D/TC:0 0 stat_handle_fault:1740 nfaults 4096 npages 10 (min 10)
D/TC:0 0 stat_handle_fault:1740 nfaults 5120 npages 10 (min 10)
D/TC:0 0 stat_handle_fault:1740 nfaults 6144 npages 10 (min 10)
D/TC:0 0 call_initcalls:40 level 2 init_debug()
D/TC:0 0 stm32_bsec_find_otp_in_nvmem_layout:655 nvmem cfg0_otp = 0: 0 8
I/TC: WARNING: All debug access are allowed
D/TC:0 0 call_initcalls:40 level 2 probe_dt_drivers_early()
D/TC:0 0 add_node_to_probe:585 element: cpu0-opp-table on node stm32mp1-cpu-opp
D/TC:0 0 add_node_to_probe:585 element: soc on node simple-bus
D/TC:0 0 add_node_to_probe:585 element: vin on node fixed-regulator
D/TC:0 0 probe_driver_node:390 element: fixed-regulator on node vin initialized
D/TC:0 0 add_node_to_probe:585 element: timer@44006000 on node stm32-timer
D/TC:0 0 add_node_to_probe:585 element: pwr@50001000 on node stm32mp1-pwr-regu
D/TC:0 0 add_node_to_probe:585 element: watchdog@5a002000 on node stm32-iwdg
D/TC:0 0 add_node_to_probe:585 element: rtc@5c004000 on node stm32-rtc
D/TC:0 0 add_node_to_probe:585 element: tamp@5c00a000 on node stm32-tamp
D/TC:0 0 add_node_to_probe:585 element: tzc@5c006000 on node stm32mp1-tzc400
D/TC:0 0 add_node_to_probe:585 element: pin-controller@50002000 on node stm32_gpio
D/TC:0 0 add_node_to_probe:585 element: pin-controller-z@54004000 on node stm32_gpio
D/TC:0 0 stat_handle_fault:1740 nfaults 7168 npages 10 (min 10)
D/TC:0 0 add_node_to_probe:585 element: etzpc@5c007000 on node stm32-etzpc
D/TC:0 0 probe_driver_node:390 element: simple-bus on node soc initialized
D/TC:0 0 regulator_get_by_phandle:54 phandle 4 not found
D/TC:0 0 probe_driver_node:396 element: stm32mp1-cpu-opp on node cpu0-opp-table deferred 1 time(s)
D/TC:0 0 regulator_get_by_phandle:54 phandle 4 not found
D/TC:0 0 probe_driver_node:396 element: stm32mp1-cpu-opp on node cpu0-opp-table deferred 2 time(s)
D/TC:0 0 stm32_etzpc_set_driverdata:512 ETZPC revision 0x32, per_sec 96, ahb_sec 0, tzma 2
D/TC:0 0 add_node_to_probe:585 element: rng@54003000 on node stm32_rng
D/TC:0 0 add_node_to_probe:585 element: i2c@5c002000 on node stm32_i2c
D/TC:0 0 probe_driver_node:390 element: stm32-etzpc on node etzpc@5c007000 initialized
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@54004000
D/TC:0 0 _fdt_stm32_gpio_controller:717 GPIO bank Z assigned to non-secure
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank Z (8 pins) @2c404000
D/TC:0 0 probe_driver_node:390 element: stm32_gpio on node pin-controller-z@54004000 initialized
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50002000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50003000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50004000
D/TC:0 0 stat_handle_fault:1740 nfaults 8192 npages 10 (min 10)
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50005000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50006000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50007000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50008000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@50009000
D/TC:0 0 _fdt_stm32_gpio_controller:697 Bank name gpio@5000a000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank Z (8 pins) @2c404000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank A (16 pins) @2c802000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank B (16 pins) @2c803000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank C (16 pins) @2c804000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank D (16 pins) @2c805000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank E (16 pins) @2c806000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank F (16 pins) @2c807000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank G (16 pins) @2c808000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank H (16 pins) @2c809000
D/TC:0 0 stm32_gpio_probe:903 Registered GPIO bank I (12 pins) @2c80a000
D/TC:0 0 probe_driver_node:390 element: stm32_gpio on node pin-controller@50002000 initialized
D/TC:0 0 tzc_set_driverdata:114 TZC400 Filters 2 Regions 8
D/TC:0 0 stm32mp1_tzc_probe:485 0x0xc0000000 - 0x0xddffffff : Sec access 0 NS access 0x87fb87fb
D/TC:0 0 tzc_dump_state:473 region 0
D/TC:0 0 tzc_dump_state:476 region_base: 0x0000000000000000
D/TC:0 0 tzc_dump_state:479 region_top: 0x00000000ffffffff
D/TC:0 0 tzc_dump_state:481 secure rw: TZC_REGION_S_NONE
D/TC:0 0 tzc_dump_state:486 filter 0 enable
D/TC:0 0 tzc_dump_state:486 filter 1 enable
D/TC:0 0 tzc_dump_state:473 region 1
D/TC:0 0 tzc_dump_state:476 region_base: 0x00000000de200000
D/TC:0 0 tzc_dump_state:479 region_top: 0x00000000dfffffff
D/TC:0 0 tzc_dump_state:481 secure rw: TZC_REGION_S_RDWR
D/TC:0 0 tzc_dump_state:486 filter 0 enable
D/TC:0 0 tzc_dump_state:486 filter 1 enable
D/TC:0 0 tzc_dump_state:473 region 2
D/TC:0 0 tzc_dump_state:476 region_base: 0x00000000de000000
D/TC:0 0 tzc_dump_state:479 region_top: 0x00000000de1fffff
D/TC:0 0 tzc_dump_state:481 secure rw: TZC_REGION_S_NONE
D/TC:0 0 tzc_dump_state:486 filter 0 enable
D/TC:0 0 tzc_dump_state:486 filter 1 enable
D/TC:0 0 tzc_dump_state:473 region 3
D/TC:0 0 tzc_dump_state:476 region_base: 0x00000000c0000000
D/TC:0 0 tzc_dump_state:479 region_top: 0x00000000ddffffff
D/TC:0 0 tzc_dump_state:481 secure rw: TZC_REGION_S_NONE
D/TC:0 0 tzc_dump_state:486 filter 0 enable
D/TC:0 0 tzc_dump_state:486 filter 1 enable
D/TC:0 0 gic_it_set_cpu_mask:330 cpu_mask: writing 0xff to 0x2ba21824
D/TC:0 0 gic_it_set_cpu_mask:332 cpu_mask: 0x3
D/TC:0 0 gic_it_set_prio:341 prio: writing 0x1 to 0x2ba21424
D/TC:0 0 probe_driver_node:390 element: stm32mp1-tzc400 on node tzc@5c006000 initialized
D/TC:0 0 stm32_tamp_parse_passive_conf:1083 Passive conf from dt: precharge=0, nb_sample=0, clk_div=32768
D/TC:0 0 stm32_tamp_parse_active_conf:1199 Active conf from dt: no filter clk_div=1
D/TC:0 0 gic_it_set_cpu_mask:330 cpu_mask: writing 0xff00 to 0x2ba218e4
D/TC:0 0 gic_it_set_cpu_mask:332 cpu_mask: 0x300
D/TC:0 0 gic_it_set_prio:341 prio: writing 0x1 to 0x2ba214e5
D/TC:0 0 probe_driver_node:390 element: stm32-tamp on node tamp@5c00a000 initialized
D/TC:0 0 stat_handle_fault:1740 nfaults 9216 npages 10 (min 10)
D/TC:0 0 probe_driver_node:390 element: stm32-rtc on node rtc@5c004000 initialized
D/TC:0 0 stm32mp_iwdg_iomem2instance:506 Base 5a002000
D/TC:0 0 stm32_bsec_find_otp_in_nvmem_layout:655 nvmem hw2_otp = 6: 18 32
D/TC:0 0 gic_it_set_cpu_mask:330 cpu_mask: writing 0xff000000 to 0x2ba218b4
D/TC:0 0 gic_it_set_cpu_mask:332 cpu_mask: 0x3000000
D/TC:0 0 gic_it_set_prio:341 prio: writing 0x1 to 0x2ba214b7
D/TC:0 0 probe_driver_node:390 element: stm32-iwdg on node watchdog@5a002000 initialized
D/TC:0 0 probe_driver_node:390 element: stm32mp1-pwr-regu on node pwr@50001000 initialized
D/TC:0 0 probe_driver_node:390 element: stm32-timer on node timer@44006000 initialized
D/TC:0 0 i2c_compute_timing:453 I2C SDADEL(min/max): 18/519, SCLDEL(Min): 285
D/TC:0 0 i2c_compute_timing:551 I2C TIMINGR (PRESC/SCLDEL/SDADEL): 1/8/1
D/TC:0 0 i2c_compute_timing:553 I2C TIMINGR (SCLH/SCLL): 27/38
D/TC:0 0 i2c_compute_timing:555 I2C TIMINGR: 0x10811b26
D/TC:0 0 i2c_setup_timing:642 I2C Freq(400000Hz), Clk Source(64000000)
D/TC:0 0 i2c_setup_timing:644 I2C Rise(185) and Fall(20) Time
D/TC:0 0 i2c_setup_timing:646 I2C Analog Filter(On), DNF(0)
D/TC:0 0 add_node_to_probe:585 element: stpmic@33 on node stm32_pmic
D/TC:0 0 probe_driver_node:390 element: stm32_i2c on node i2c@5c002000 initialized
D/TC:0 0 stat_handle_fault:1740 nfaults 10240 npages 10 (min 10)
D/TC:0 0 register_periph:172 Register RNG1 as secure
D/TC:0 0 probe_driver_node:390 element: stm32_rng on node rng@54003000 initialized
D/TC:0 0 regulator_get_by_phandle:54 phandle 4 not found
D/TC:0 0 probe_driver_node:396 element: stm32mp1-cpu-opp on node cpu0-opp-table deferred 3 time(s)
D/TC:0 0 regulator_get_by_phandle:54 phandle 4 not found
D/TC:0 0 probe_driver_node:396 element: stm32mp1-cpu-opp on node cpu0-opp-table deferred 4 time(s)
D/TC:0 0 register_periph:172 Register I2C4 as non-secure
D/TC:0 0 initialize_pmic:577 PMIC version = 0x20
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul buck1: enable, 1200mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul buck2: enable, 1350mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul buck3: enable, 3300mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul buck4: enable, 3300mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul ldo1: disable, 1800mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul ldo2: disable, 1800mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul ldo3: enable, 3300mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul ldo4: enable, 3300mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul ldo5: enable, 2900mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul ldo6: disable, 1000mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul vref_ddr: enable, 3300mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul boost: disable, 5000mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul pwr_sw1: disable, 5000mV
D/TC:0 0 stpmic1_dump_regulators:1136 PMIC regul pwr_sw2: disable, 5000mV
D/TC:0 0 stat_handle_fault:1740 nfaults 11264 npages 10 (min 10)
D/TC:0 0 probe_driver_node:390 element: stm32_pmic on node stpmic@33 initialized
D/TC:0 0 stm32_bsec_find_otp_in_nvmem_layout:655 nvmem part_number_otp = 1: 1 8
D/TC:0 0 stm32mp1_cpu_opp_get_dt_subnode:265 Found OPP 0 (800000kHz/1350mV) from DT
D/TC:0 0 stm32mp1_cpu_opp_get_dt_subnode:265 Found OPP 1 (400000kHz/1200mV) from DT
D/TC:0 0 probe_driver_node:390 element: stm32mp1-cpu-opp on node cpu0-opp-table initialized
D/TC:0 0 call_initcalls:40 level 3 platform_banner()
I/TC: Platform stm32mp1: flavor PLATFORM_FLAVOR - DT stm32mp157f-dk2.dts
D/TC:0 0 call_initcalls:40 level 3 check_ta_store()
D/TC:0 0 check_ta_store:408 TA store: "early TA"
D/TC:0 0 check_ta_store:408 TA store: "Secure Storage TA"
D/TC:0 0 check_ta_store:408 TA store: "REE"
D/TC:0 0 call_initcalls:40 level 3 early_ta_init()
D/TC:0 0 early_ta_init:56 Early TA 1a8342cc-81a5-4512-99fe-9e2b3e37d626 size 42664
D/TC:0 0 call_initcalls:40 level 3 verify_pseudo_tas_conformance()
D/TC:0 0 call_initcalls:40 level 3 mobj_init()
D/TC:0 0 call_initcalls:40 level 3 default_mobj_init()
D/TC:0 0 call_initcalls:40 level 3 tee_cryp_init()
D/TC:0 0 tee_pager_add_core_region:633 0x3005e000 - 0x3006a000 : type 2
D/TC:0 0 stat_handle_fault:1740 nfaults 12288 npages 10 (min 10)
D/TC:0 0 call_initcalls:40 level 4 init_console_from_dt()
D/TC:0 0 register_periph_iomem:271 IO for non-secure resource 0x40010000
I/TC: DTB enables console (non-secure)
D/TC:0 0 call_initcalls:40 level 4 tee_fs_init_key_manager()
D/TC:0 0 call_initcalls:40 level 5 stm32mp1_iocomp()
D/TC:0 0 call_initcalls:40 level 5 init_pm_support()
D/TC:0 0 call_initcalls:40 level 5 probe_dt_drivers()
D/TC:0 0 call_initcalls:40 level 6 stm32_configure_tamp()
D/TC:0 0 stm32_tamp_set_int_config:412 INT_TAMP1 enabled as a full tamper
D/TC:0 0 stm32_tamp_set_int_config:412 INT_TAMP2 enabled as a full tamper
D/TC:0 0 stm32_tamp_set_int_config:412 INT_TAMP3 enabled as a full tamper
D/TC:0 0 stm32_tamp_set_int_config:412 INT_TAMP4 enabled as a full tamper
D/TC:0 0 call_initcalls:40 level 6 init_stm32mp1_drivers()
D/TC:0 0 call_initcalls:40 level 6 stm32mp1_init_scmi_server()
D/TC:0 0 call_initcalls:40 level 6 stm32mp1_init_final_shres()
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp IWDG1    ( 0): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp USART1   ( 1): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp SPI6     ( 2): non-secure    
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp I2C4     ( 3): non-secure    
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp RNG1     ( 4): secure        
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp HASH1    ( 5): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp CRYP1    ( 6): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp I2C6     ( 7): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp RTC      ( 8): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp MCU      ( 9): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp PLL3     (10): unregistered  
D/TC:0 0 stm32mp1_init_final_shres:524 stm32mp MDMA     (11): unregistered  
D/TC:0 0 stm32_bsec_find_otp_in_nvmem_layout:655 nvmem cfg0_otp = 0: 0 8
D/TC:0 0 check_rcc_secure_configuration:506 RCC/PWR secure hardening: TZEN enable, MCKPROT enable
D/TC:0 0 call_initcalls:40 level 6 init_stm32mp1_calib()
D/TC:0 0 init_stm32mp1_calib:547 Calibration csi-calibration enabled
D/TC:0 0 init_stm32mp1_calib:547 Calibration hsi-calibration enabled
D/TC:0 0 osc_calibration:305 Calibration : Freq 63866880 , trim 282
D/TC:0 0 osc_calibration:305 Calibration : Freq 3986816 , trim 117
D/TC:0 0 call_initcalls:40 level 6 stm32mp_syscfg_set_hslv()
D/TC:0 0 stm32_bsec_find_otp_in_nvmem_layout:655 nvmem hw2_otp = 6: 18 32
D/TC:0 0 call_initcalls:40 level 6 init_low_power()
D/TC:0 0 gic_it_set_cpu_mask:330 cpu_mask: writing 0xff00 to 0x2ba218b0
D/TC:0 0 gic_it_set_cpu_mask:332 cpu_mask: 0x300
D/TC:0 0 gic_it_set_prio:341 prio: writing 0x1 to 0x2ba214b1
D/TC:0 0 gic_it_set_cpu_mask:330 cpu_mask: writing 0x101ff01 to 0x2ba21808
D/TC:0 0 gic_it_set_cpu_mask:332 cpu_mask: 0x1010101
D/TC:0 0 gic_it_set_prio:341 prio: writing 0x1 to 0x2ba21409
D/TC:0 0 call_initcalls:40 level 6 stm32mp1_init_lp_states()
D/TC:0 0 stm32mp1_init_lp_states:236 Power configuration: shutdown to 6, suspend to 2
D/TC:0 0 call_initcalls:40 level 6 regulator_core_init()
D/TC:0 0 call_initcalls:40 level 6 scmi_regulator_consumer_init()
D/TC:0 0 scmi_regulator_consumer_init:348 scmi voltd shares reg11 (node reg11) on domain ID 0
D/TC:0 0 scmi_regulator_consumer_init:348 scmi voltd shares reg18 (node reg18) on domain ID 1
D/TC:0 0 scmi_regulator_consumer_init:348 scmi voltd shares usb33 (node usb33) on domain ID 2
D/TC:0 0 call_initcalls:40 level 6 rwp_init()
D/TC:0 0 tee_pager_add_core_region:633 0x3006a000 - 0x300a6000 : type 1
D/TC:0 0 call_initcalls:40 level 7 regulator_core_cleanup()
D/TC:0 0 regulator_core_cleanup:1009 disable pwr_sw2
D/TC:0 0 regulator_core_cleanup:1009 disable pwr_sw1
D/TC:0 0 regulator_core_cleanup:1009 disable boost
D/TC:0 0 regulator_core_cleanup:1009 disable ldo5
D/TC:0 0 regulator_core_cleanup:1009 disable ldo4
D/TC:0 0 regulator_core_cleanup:1009 disable usb33
D/TC:0 0 regulator_core_cleanup:1009 disable reg18
D/TC:0 0 regulator_core_cleanup:1009 disable reg11
D/TC:0 0 call_initcalls:40 level 7 release_probe_lists()
D/TC:0 0 call_finalcalls:59 level 1 release_external_dt()
I/TC: Primary CPU switching to normal world boot
Channel unexpectedly busy, reset channel
Channel unexpectedly busy, reset channel
Channel unexpectedly busy, reset channel
etienne-lms commented 1 year ago

Looking into the code there are few inconsistencies. First, check that you configure U-Boot with _stm32mp15defconfig, it should bring to right features. Note also that U-Boot message "Channel unexpectedly busy, reset channel" is not embedded in the u-boot you point to in https://github.com/OP-TEE/optee_os/issues/5903#issue-1634472788. Make sure you build the right source tree.

Last, there is a forum dedicated to ST stm32mp1 software deliveries https://community.st.com/s/topic/0TO0X0000003u2AWAQ/stm32-mpus. You should better ask your questions there to get accurante answer ;)

embetrix commented 1 year ago

@etienne-lms you are right ! I was using stm32mp15_trusted_defconfig and wrong u-boot version, now it works like a charm :-) thank you for your support very much appreciated :-)

etienne-lms commented 1 year ago

@embetrix, can you close this issue?