TEE IAC exception after Kernel prints "Started Calibrates HSI/CSI clocks" and crashes.

[Nisarg]

I have created a CubeMX project for STM32MP235.

I have successfully built, Flashed and able to boot it on STM32MP257-EV1 board. (I don't have my 235 custom board ready yet.)

Once I switch to normal boot after flashing, it runs until kernel tries to run some process printing message on Serial "[ OK ] Started Calibrates HSI/CSI clocks." after which IAC exception handler kicks in from OPTee and system crashes.

From the log what I can see that kernel is asking for access to peripheral ID 156, which I presume that is RCC. (found ID from reference manual and some online posts).

Can anyone help me which setting I am missing in my cube MX project or any User entry in any of DTS that cubeMX generates?

Here is the snippet of Console Log. IOC file for my project is also attached.

Welcome to ST OpenSTLinux - Weston - (A Yocto Project Based Distro) 5.0.8-snapshot-20250722 (scarthgap)!

[    2.783063] systemd[1]: Hostname set to <stm32mp23-trb-235-protoa>.
[    2.812342] systemd[1]: Using hardware watchdog 'arm_smc_wdt', version 0, device /dev/watchdog0
[    2.815761] systemd[1]: Watchdog running with a hardware timeout of 32s.
[    3.234406] systemd[1]: Queued start job for default target Multi-User System.
[    3.276712] systemd[1]: Created slice Slice /system/getty.
[  OK  ] Created slice Slice /system/getty.
[    3.294988] systemd[1]: Created slice Slice /system/modprobe.
[  OK  ] Created slice Slice /system/modprobe.
[    3.314818] systemd[1]: Created slice Slice /system/serial-getty.
[  OK  ] Created slice Slice /system/serial-getty.
[    3.334226] systemd[1]: Created slice User and Session Slice.
[  OK  ] Created slice User and Session Slice.
[    3.353071] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[    3.372935] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[    3.392778] systemd[1]: Expecting device /dev/tee0...
         Expecting device /dev/tee0...
[    3.408843] systemd[1]: Reached target Path Units.
[  OK  ] Reached target Path Units.
[    3.424718] systemd[1]: Reached target Remote File Systems.
[  OK  ] Reached target Remote File Systems.
[    3.444710] systemd[1]: Reached target Slice Units.
[  OK  ] Reached target Slice Units.
[    3.460755] systemd[1]: Reached target Swaps.
[  OK  ] Reached target Swaps.
[    3.515312] systemd[1]: Listening on RPCbind Server Activation Socket.
[  OK  ] Listening on RPCbind Server Activation Socket.
[    3.536883] systemd[1]: Reached target RPC Port Mapper.
[  OK  ] Reached target RPC Port Mapper.
[    3.556755] systemd[1]: Listening on Syslog Socket.
[  OK  ] Listening on Syslog Socket.
[    3.580513] systemd[1]: Listening on Process Core Dump Socket.
[  OK  ] Listening on Process Core Dump Socket.
[    3.597176] systemd[1]: Listening on initctl Compatibility Named Pipe.
[  OK  ] Listening on initctl Compatibility Named Pipe.
[    3.623950] systemd[1]: Journal Audit Socket was skipped because of an unmet condition check (ConditionSecurity=audit).
[    3.630037] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[    3.653582] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Journal Socket.
[    3.669700] systemd[1]: Listening on Network Service Netlink Socket.
[  OK  ] Listening on Network Service Netlink Socket.
[    3.689659] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[    3.705350] systemd[1]: Listening on udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    3.744903] systemd[1]: Mounting Huge Pages File System...
         Mounting Huge Pages File System...
[    3.764942] systemd[1]: Mounting POSIX Message Queue File System...
         Mounting POSIX Message Queue File System...
[    3.813161] systemd[1]: Mounting Kernel Debug File System...
         Mounting Kernel Debug File System...
[    3.833441] systemd[1]: Kernel Trace File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/tracing).
[    3.864967] systemd[1]: Mounting Temporary Directory /tmp...
         Mounting Temporary Directory /tmp...
[    3.890083] systemd[1]: Starting Create List of Static Device Nodes...
         Starting Create List of Static Device Nodes...
[    3.921670] systemd[1]: Starting Load Kernel Module configfs...
         Starting Load Kernel Module configfs...
[    3.945592] systemd[1]: Starting Load Kernel Module drm...
         Starting Load Kernel Module drm...
[    3.981192] systemd[1]: Starting Load Kernel Module fuse...
         Starting Load Kernel Module fuse...
[    4.017188] systemd[1]: Starting Mount partitions...
         Starting Mount partitions...
[    4.040928] systemd[1]: Starting RPC Bind...
         Starting RPC Bind...
[    4.057323] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
[    4.092204] systemd[1]: Starting Journal Service...
         Starting Journal Service...
[    4.121213] systemd[1]: Load Kernel Modules was skipped because no trigger condition checks were met.
[    4.153918] systemd[1]: Starting Generate network units from Kernel command line...
   [    4.157776] systemd-journald[252]: Collecting audit messages is disabled.
      Starting Generate network units from Kernel command line...
[    4.206603] systemd[1]: Starting Remount Root and Kernel File Systems...
         Starting Remount Root and Kernel File Systems...
[    4.254732] systemd[1]: Starting Apply Kernel Variables...
         Starting Apply Kernel Variables...
[    4.313083] EXT4-fs (mmcblk0p8): re-mounted b0d3c214-fc4d-4fa9-a745-529c139e4f27 r/w. Quota mode: none.
[    4.318918] systemd[1]: Starting Coldplug All udev Devices...
[    4.324232] EXT4-fs (mmcblk0p6): recovery complete
[    4.327758] EXT4-fs (mmcblk0p6): mounted filesystem cc04f7b2-9513-42e8-b498-9ad5fb284edb r/w with ordered data mode. Quota mode: none.
         Starting Coldplug All udev Devices...
[    4.398412] systemd[1]: Started RPC Bind.
[  OK  ] Started RPC Bind.
[    4.409895] systemd[1]: Started Journal Service.
[  OK  ] Started Journal Service.
[  OK  ] Mounted Huge Pages File System.
[  OK  ] Mounted POSIX Message Queue File System.
[  OK  ] Mounted Kernel Debug File System.
[  OK  ] Mounted Temporary Directory /tmp.
[  OK  ] Finished Create List of Static Device Nodes.
[  OK  ] Finished Load Kernel Module configfs.
[  OK  ] Finished Load Kernel Module drm.
[  OK  ] Finished Load Kernel Module fuse.
[    4.546888] EXT4-fs (mmcblk0p7): recovery complete
[    4.549025] EXT4-fs (mmcblk0p7): mounted filesystem 506d4500-9df5-467f-87a8-c7a35deb13c5 r/w with ordered data mode. Quota mode: none.
[  OK  ] Finished Generate network units from Kernel command line.
[  OK  ] Finished Remount Root and Kernel File Systems.
[  OK  ] Finished Apply Kernel Variables.
         Mounting FUSE Control File System...
         Mounting Kernel Configuration File System...
         Starting Flush Journal to Persistent Storage...
         Starting Create Static Device Nodes in /dev gracefully...
[    4.885839] systemd-journald[252]: Received client request to flush runtime journal.
[    4.896331] EXT4-fs (mmcblk0p9): recovery complete
[  OK  ] Mounted FUSE Cont[    4.900668] EXT4-fs (mmcblk0p9): mounted filesystem 28842aec-6a82-42b6-bff9-3fde8e9e5856 r/w with ordered data mode. Quota mode: none.
rol File System.
[  OK  ] Finished Mount partitions.
[  OK  ] Mounted Kernel Configuration File System.
[  OK  ] Finished Flush Journal to Persistent Storage.
[  OK  ] Finished Create Static Device Nodes in /dev gracefully.
         Starting Create System Users...
[  OK  ] Finished Create System Users.
         Starting Create Static Device Nodes in /dev...
[  OK  ] Finished Coldplug All udev Devices.
[  OK  ] Finished Create Static Device Nodes in /dev.
[  OK  ] Reached target Preparation for Local File Systems.
         Mounting /var/volatile...
         Starting Rule-based Manager for Device Events and Files...
[  OK  ] Mounted /var/volatile.
         Starting Load/Save OS Random Seed...
[  OK  ] Reached target Local File Systems.
         Starting Rebuild Dynamic Linker Cache...
         Starting Automatic Boot Loader Update...
         Starting Create System Files and Directories...
[  OK  ] Started Rule-based Manager for Device Events and Files.
[  OK  ] Finished Load/Save OS Random Seed.
[  OK  ] Finished Automatic Boot Loader Update.
[  OK  ] Finished Create System Files and Directories.
         Starting Rebuild Journal Catalog...
         Starting Network Name Resolution...
         Starting Network Time Synchronization...
         Starting Record System Boot/Shutdown in UTMP...
[  OK  ] Finished Rebuild Dynamic Linker Cache.
[  OK  ] Finished Rebuild Journal Catalog.
[  OK  ] Finished Record System Boot/Shutdown in UTMP.
[  OK  ] Started Network Time Synchronization.
[  OK  ] Started Network Name Resolution.
[  OK  ] Reached target Host and Network Name Lookups.
[  OK  ] Reached target System Time Set.
[    6.674260] stm32-rproc 0.m33: Support of signed firmware only
[    6.678344] stm32-rproc 0.m33: wdg irq registered
[    6.679207] stm32-rproc 0.m33: pdds sys config not defined
[    6.694617] stm32-rproc 0.m33: rsc tbl syscon not supported
[    6.694939] stm32-rproc 0.m33: mbox_request_channel_byname() could not locate channel named "detach"
[    6.703964] remoteproc remoteproc0: cannot get detach mbox
[    6.716632] usb-stm32-dwc3 42080000.bus:usb@48300000: configured in usb2 mode
[    6.723853] remoteproc remoteproc0: m33 is available
         Starting Update is Completed...
[    6.766805] /ahb@1/i2c@2/typec@35/connector: Fixed dependency cycle(s) with /soc@0/bus@42080000/usb@48300000/usb@48300000
[    6.780365] /soc@0/bus@42080000/usb@48300000/usb@48300000: Fixed dependency cycle(s) with /ahb@1/i2c@2/typec@35/connector
[    6.801136] stm32f7-i2c 40120000.i2c: using dma1chan2 (tx) and dma1chan3 (rx) for DMA transfers
[    6.806100] stm32-cryp 42030000.crypto: will run requests pump with realtime priority
[    6.809046] spi_stm32 40230000.spi: driver initialized (master mode)
[  O[    6.819668] stm32f7-i2c 40120000.i2c: STM32F7 I2C-0 bus adapter
[    6.827253] stm32-cryp 42030000.crypto: Initialized
K  ] Finished Update is Completed.
[  OK  ] Reached target System Initialization[    6.847407] stm32f7-i2c 40130000.i2c: using dma1chan6 (tx) and dma1chan7 (rx) for DMA transfers
.
[    6.865914] pca954x 1-0070: supply vdd not found, using dummy regulator
[    6.866831] ohci-platform 482e0000.usb: Generic Platform OHCI controller
[    6.867383] pca954x 1-0070: probe failed
[    6.873710] ohci-platform 482e0000.usb: new USB bus registered, assigned bus number 1
[    6.889148] stm32-usbh 42080000.bus:usb@482e0000: Enabling runtime PM for inactive device with active children
[    6.895533] ohci-platform 482e0000.usb: irq 70, io mem 0x482e0000
[    6.897244] ehci-platform 482f0000.usb: EHCI Host Controller
[    6.909309] ehci-platform 482f0000.usb: new USB bus registered, assigned bus number 2
[  [    6.915967] ehci-platform 482f0000.usb: irq 71, io mem 0x482f0000
OK  ] Started Serve[    6.926558] Bluetooth: Core ver 2.22
s stm32mp clocks calibration..
[    6.936752] ehci-platform 482f0000.usb: USB 2.0 started, EHCI 1.00
[    6.937305] NET: Registered PF_BLUETOOTH protocol family
[    6.943418] hub 2-0:1.0: USB hub found
[    6.948681] hub 2-0:1.0: 1 port detected
[    6.950912] stm32f7-i2c 40130000.i2c: STM32F7 I2C-1 bus adapter
[    6.955578] Bluetooth: HCI device and connection manager initialized
[    6.958539] stm32_rtc 46000000.rtc: registered as rtc0
[    6.963395] Bluetooth: HCI socket layer initialized
[    6.976388] hub 1-0:1.0: USB hub found
[    6.976451] hub 1-0:1.0: 1 port detected
[    6.977198] Bluetooth: L2CAP socket layer initialized
[    6.989850] stm32-dma3 40400000.dma-controller: No suitable channel found
[    6.992455] stm32_rtc 46000000.rtc: setting system clock to 2000-01-01T00:32:28 UTC (946686748)
[    6.993491] systemd-journald[252]: Time jumped backwards, rotating.
[  OK  ] Started Daily Cleanup of Temporary Direc[    7.013172] lp50xx: probe of 2-0028 failed with error -16
tories.
[    7.023077] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    7.028080] stm32f7-i2c 46040000.i2c: STM32F7 I2C-2 bus adapter
[    7.029016] Bluetooth: SCO socket layer initialized
[  OK  ] Reached target Timer Units.
[    7.052618] stm32_rtc 46000000.rtc: Date/Time must be initialized
[    7.053171] stm32_rtc 46000000.rtc: registered rev:3.4
[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
[  OK  ] Listening on D-Bus System Message Bu[    7.088172] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
s Socket.
[    7.129947] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
[  OK  ] Listening on dropbear.socket.
[    7.154895] mlan: loading out-of-tree module taints kernel.
[  OK  ] Reached target Socket Units.
[  OK  ] Reached target Basic System.
[    7.204648] usb 2-1: new high-speed USB device number 2 using ehci-platform
         Starting Avahi mDNS/DNS-SD Stack...
[    7.241078] wlan: Loading MWLAN driver
[    7.243513] wlan: Register to Bus Driver...
[    7.248885] vendor=0x0471 device=0x0205 class=0 function=1
[    7.250814] Attach moal handle ops, card interface type: 0x109
[    7.261692] rps set to 0 from module param
[    7.261741] No module param cfg file specified
[    7.266211] SDIO: max_segs=341 max_seg_size=131008
[  OK  ] Started Kernel Logging Service.
[    7.276201] rx_work=1 cpu_num=2
[    7.284327] Enable moal_recv_amsdu_packet
[    7.284408] Attach mlan adapter operations.card_type is 0x109.
[    7.293096] wlan: Enable TX SG mode
[    7.293140] wlan: Enable RX SG mode
[    7.303670] Request firmware: nxp/sduart_nw61x_v1.bin.se
[  OK  ] Started System Logging Service.
[    7.368421] hub 2-1:1.0: USB hub found
[    7.369772] hub 2-1:1.0: 4 ports detected
         Starting D-Bus System Message Bus...
[  OK  ] Started Getty on tty1.
[    7.507785] at24 2-0050: supply vcc not found, using dummy regulator
         Starting IPv6 Packet Filtering Framework...
         Starting IPv4 Packet Filtering Framework...
         Starting Telephony service...
[  OK  ] Started Serial Getty on ttySTM0.
[  OK  ] Reached target Login Prompts.
[  OK  ] Started Calibrates HSI/CSI clocks.
E/TC:1 1 stm32_iac_itr:192 IAC exceptions [159:128]: 0x10000000
E/TC:1 1 stm32_iac_itr:197 IAC exception ID: 156
E/TC:1 1 Panic at /usr/src/debug/optee-os-stm32mp/4.0.0-stm32mp-r2/core/drivers/firewall/stm32_iac.c:212 <stm32_iac_itr>
E/TC:1 1 TEE load address @ 0x82000000
E/TC:1 1 Call stack:
E/TC:1 1  0x82007ef0
E/TC:1 1  0x820432a8
E/TC:1 1  0x8202d538
E/TC:1 1  0x82040ddc
E/TC:1 1  0x820141d0
E/TC:1 1  0x820046ac
I/TC: Halting CPU 0

[Nisarg · Accepted solution]

After twiddling some Checkboxes in CubeMX I am able to successfully boot the machine. New IOC file is attached with this message if someone needs to check what I have changed. I am not sure which parameter enabled access to RCC, as I have changed many parameters.