esp32p4 issues on testing play_mp3_control example (AUD-5944)

[tpang0609]

Environment

• Audio development kit: [ESP32 P4]
• [Required] Module or chip used: [ESP32P4]
• [Required] IDF version (run git describe --tags in $IDF_PATH folder to find it):
  // v5.3.2
• [Required] ADF version (run git describe --tags in $ADF_PATH folder to find it):
  // v2.7-40-gab43323e
• Build system: [idf.py]
• [Required] Running log:
  idf.py -p /dev/ttyUSB0 flash monitor
  Executing action: flash
  Running ninja in directory /home/tpang/esp32p4/play_mp3_control/build
  Executing "ninja flash"...
  [1/5] cd /home/tpang/esp32p4/play_mp3_cont...play_mp3_control/build/play_mp3_control.bin
  play_mp3_control.bin binary size 0xe2260 bytes. Smallest app partition is 0x177000 bytes. 0x94da0 bytes (40%) free.
  [1/1] cd /home/tpang/esp32p4/play_mp3_cont...mp3_control/build/bootloader/bootloader.bin
  Bootloader binary size 0x58e0 bytes. 0x720 bytes (7%) free.
  [2/3] cd /home/tpang/esp32p4/esp-idf/compo...components/esptool_py/run_serial_tool.cmake
  esptool.py --chip esp32p4 -p /dev/ttyUSB0 -b 460800 --before=default_reset --after=hard_reset write_flash --flash_mode dio --flash_freq 80m --flash_size detect 0x2000 bootloader/bootloader.bin 0x10000 play_mp3_control.bin 0x8000 partition_table/partition-table.bin
  esptool.py v4.8.1
  Serial port /dev/ttyUSB0
  Connecting....
  Chip is ESP32-P4 (revision v0.1)
  Features: High-Performance MCU
  Crystal is 40MHz
  MAC: 60:55:f9:f9:49:9b
  Uploading stub...
  Running stub...
  Stub running...
  Changing baud rate to 460800
  Changed.
  Configuring flash size...
  Auto-detected Flash size: 16MB
  Flash will be erased from 0x00002000 to 0x00007fff...
  Flash will be erased from 0x00010000 to 0x000f2fff...
  Flash will be erased from 0x00008000 to 0x00008fff...
  Flash params set to 0x024f
  SHA digest in image updated
  Compressed 22752 bytes to 13562...
  Writing at 0x00002000... (100 %)
  Wrote 22752 bytes (13562 compressed) at 0x00002000 in 0.7 seconds (effective 276.5 kbit/s)...
  Hash of data verified.
  Compressed 926304 bytes to 710443...
  Writing at 0x000ee8ea... (100 %)
  Wrote 926304 bytes (710443 compressed) at 0x00010000 in 16.8 seconds (effective 441.5 kbit/s)...
  Hash of data verified.
  Compressed 3072 bytes to 105...
  Writing at 0x00008000... (100 %)
  Wrote 3072 bytes (105 compressed) at 0x00008000 in 0.1 seconds (effective 422.8 kbit/s)...
  Hash of data verified.

Leaving...
Hard resetting via RTS pin...
Executing action: monitor
Running idf_monitor in directory /home/tpang/esp32p4/play_mp3_control
Executing "/home/tpang/.espressif/python_env/idf5.3_py3.10_env/bin/python /home/tpang/esp32p4/esp-idf/tools/idf_monitor.py -p /dev/ttyUSB0 -b 115200 --toolchain-prefix riscv32-esp-elf- --target esp32p4 --revision 1 --decode-panic backtrace /home/tpang/esp32p4/play_mp3_control/build/play_mp3_control.elf -m '/home/tpang/.espressif/python_env/idf5.3_py3.10_env/bin/python' '/home/tpang/esp32p4/esp-idf/tools/idf.py' '-p' '/dev/ttyUSB0'"...
--- esp-idf-monitor 1.5.0 on /dev/ttyUSB0 115200
--- Quit: Ctrl+] | Menu: Ctrl+T | Help: Ctrl+T followed by Ctrl+H
�xx�x��xx�x�x�xxxx�x��xx�xxx'�������ESP-ROM:esp32p4-eco1-20240205
Build:Feb 5 2024
rst:0x1 (POWERON),boot:0x30f (SPI_FAST_FLASH_BOOT)
SPI mode:DIO, clock div:1
load:0x4ff33ce0,len:0x18e4
load:0x4ff2abd0,len:0xd74
load:0x4ff2cbd0,len:0x322c
entry 0x4ff2abda
I (25) boot: ESP-IDF v5.3.2-dirty 2nd stage bootloader
I (26) boot: compile time Dec 25 2024 20:51:27
I (26) boot: Multicore bootloader
I (30) boot: chip revision: v0.1
I (33) boot: efuse block revision: v0.0
I (37) boot.esp32p4: SPI Speed : 80MHz
I (42) boot.esp32p4: SPI Mode : DIO
I (47) boot.esp32p4: SPI Flash Size : 16MB
I (52) boot: Enabling RNG early entropy source...
I (57) boot: Partition Table:
I (61) boot: ## Label Usage Type ST Offset Length
I (68) boot: 0 nvs WiFi data 01 02 00009000 00006000
I (75) boot: 1 phy_init RF data 01 01 0000f000 00001000
I (83) boot: 2 factory factory app 00 00 00010000 00177000
I (92) boot: End of partition table
I (95) esp_image: segment 0: paddr=00010020 vaddr=40040020 size=90458h (590936) map
I (202) esp_image: segment 1: paddr=000a0480 vaddr=30100000 size=00020h ( 32) load
I (204) esp_image: segment 2: paddr=000a04a8 vaddr=30100020 size=0003ch ( 60) load
I (209) esp_image: segment 3: paddr=000a04ec vaddr=4ff00000 size=0fb2ch ( 64300) load
I (230) esp_image: segment 4: paddr=000b0020 vaddr=40000020 size=3ce48h (249416) map
I (273) esp_image: segment 5: paddr=000ece70 vaddr=4ff0fb2c size=02b88h ( 11144) load
I (277) esp_image: segment 6: paddr=000efa00 vaddr=4ff12700 size=0283ch ( 10300) load
I (286) boot: Loaded app from partition at offset 0x10000
I (286) boot: Disabling RNG early entropy source...
I (302) hex_psram: vendor id : 0x0d (AP)
I (303) hex_psram: Latency : 0x01 (Fixed)
I (303) hex_psram: DriveStr. : 0x00 (25 Ohm)
I (306) hex_psram: dev id : 0x03 (generation 4)
I (312) hex_psram: density : 0x07 (256 Mbit)
I (317) hex_psram: good-die : 0x06 (Pass)
I (322) hex_psram: SRF : 0x02 (Slow Refresh)
I (328) hex_psram: BurstType : 0x00 ( Wrap)
I (333) hex_psram: BurstLen : 0x03 (2048 Byte)
I (339) hex_psram: BitMode : 0x01 (X16 Mode)
I (344) hex_psram: Readlatency : 0x04 (14 cycles@Fixed)
I (350) hex_psram: DriveStrength: 0x00 (1/1)
I (355) MSPI DQS: tuning success, best phase id is 2
I (543) MSPI DQS: tuning success, best delayline id is 12
I (543) esp_psram: Found 32MB PSRAM device
I (544) esp_psram: Speed: 200MHz
I (547) hex_psram: psram CS IO is dedicated
I (551) cpu_start: Multicore app
I (1506) esp_psram: SPI SRAM memory test OK
W (1515) clk: esp_perip_clk_init() has not been implemented yet
I (1522) cpu_start: Pro cpu start user code
I (1522) cpu_start: cpu freq: 360000000 Hz
I (1522) app_init: Application information:
I (1525) app_init: Project name: play_mp3_control
I (1531) app_init: App version: 1
I (1535) app_init: Compile time: Dec 25 2024 20:51:23
I (1541) app_init: ELF file SHA256: 3576910ea...
I (1547) app_init: ESP-IDF: v5.3.2-dirty
I (1552) efuse_init: Min chip rev: v0.1
I (1557) efuse_init: Max chip rev: v1.99
I (1562) efuse_init: Chip rev: v0.1
I (1567) heap_init: Initializing. RAM available for dynamic allocation:
I (1574) heap_init: At 4FF16B30 len 00024490 (145 KiB): RAM
I (1580) heap_init: At 4FF3AFC0 len 00004BF0 (18 KiB): RAM
I (1587) heap_init: At 4FF40000 len 00060000 (384 KiB): RAM
I (1593) heap_init: At 50108080 len 00007F80 (31 KiB): RTCRAM
I (1599) heap_init: At 3010005C len 00001FA4 (7 KiB): TCM
I (1606) esp_psram: Adding pool of 32768K of PSRAM memory to heap allocator
I (1614) spi_flash: detected chip: generic
I (1618) spi_flash: flash io: dio
I (1622) main_task: Started on CPU0
I (1662) esp_psram: Reserving pool of 32K of internal memory for DMA/internal allocations
I (1662) main_task: Calling app_main()
I (1662) PLAY_FLASH_MP3_CONTROL: [ 1 ] Start audio codec chip
W (1672) i2c_bus_v2: I2C master handle is NULL, will create new one
I (1702) PLAY_FLASH_MP3_CONTROL: [ 2 ] Create audio pipeline, add all elements to pipeline, and subscribe pipeline event
I (1702) PLAY_FLASH_MP3_CONTROL: [2.1] Create mp3 decoder to decode mp3 file and set custom read callback
I (1712) PLAY_FLASH_MP3_CONTROL: [2.2] Create i2s stream to write data to codec chip
W (1722) i2s_common: dma frame num is adjusted to 320 to align the dma buffer with 64, bufsize = 1280
I (1732) PLAY_FLASH_MP3_CONTROL: [2.3] Register all elements to audio pipeline
I (1742) PLAY_FLASH_MP3_CONTROL: [2.4] Link it together [mp3_music_read_cb]-->mp3_decoder-->i2s_stream-->[codec_chip]
I (1752) PLAY_FLASH_MP3_CONTROL: [ 3 ] Initialize peripherals
I (1762) PLAY_FLASH_MP3_CONTROL: [3.1] Initialize keys on board
E (1762) AUDIO_BOARD: esp32_p4_function_ev_board not support key
I (1772) PLAY_FLASH_MP3_CONTROL: [ 4 ] Set up event listener
I (1772) PLAY_FLASH_MP3_CONTROL: [4.1] Listening event from all elements of pipeline
I (1782) PLAY_FLASH_MP3_CONTROL: [4.2] Listening event from peripherals
W (1792) PLAY_FLASH_MP3_CONTROL: [ 5 ] Tap touch buttons to control music player:
W (1802) PLAY_FLASH_MP3_CONTROL: [Play] to start, pause and resume, [Set] to stop.
W (1812) PLAY_FLASH_MP3_CONTROL: [Vol-] or [Vol+] to adjust volume.
I (1812) PLAY_FLASH_MP3_CONTROL: [ 5.1 ] Start audio_pipeline
I (1832) PLAY_FLASH_MP3_CONTROL: [ * ] Receive music info from mp3 decoder, sample_rates=8000, bits=16, ch=2
W (1852) i2s_common: dma frame num is adjusted to 320 to align the dma buffer with 64, bufsize = 1280
--- Error: device reports readiness to read but returned no data (device disconnected or multiple access on port?)
--- Waiting for the device to reconnect....
I (1506) esp_psram: SPI SRAM memory test OK
W (1515) clk: esp_perip_clk_init() has not been implemented yet
I (1522) cpu_start: Pro cpu start user code
I (1522) cpu_start: cpu freq: 360000000 Hz
I (1522) app_init: Application information:
I (1525) app_init: Project name: play_mp3_control
I (1531) app_init: App version: 1
I (1535) app_init: Compile time: Dec 25 2024 20:51:23
I (1541) app_init: ELF file SHA256: 3576910ea...
I (1547) app_init: ESP-IDF: v5.3.2-dirty
I (1552) efuse_init: Min chip rev: v0.1
I (1557) efuse_init: Max chip rev: v1.99
I (1562) efuse_init: Chip rev: v0.1
I (1567) heap_init: Initializing. RAM available for dynamic allocation:
I (1574) heap_init: At 4FF16B30 len 00024490 (145 KiB): RAM
I (1580) heap_init: At 4FF3AFC0 len 00004BF0 (18 KiB): RAM
I (1586) heap_init: At 4FF40000 len 00060000 (384 KiB): RAM
I (1593) heap_init: At 50108080 len 00007F80 (31 KiB): RTCRAM
I (1599) heap_init: At 3010005C len 00001FA4 (7 KiB): TCM
I (1605) esp_psram: Adding pool of 32768K of PSRAM memory to heap allocator
I (1614) spi_flash: detected chip: generic
I (1618) spi_flash: flash io: dio
I (1622) main_task: Started on CPU0
I (1662) esp_psram: Reserving pool of 32K of internal memory for DMA/internal allocations
I (1662) main_task: Calling app_main()
I (1662) PLAY_FLASH_MP3_CONTROL: [ 1 ] Start audio codec chip
W (1672) i2c_bus_v2: I2C master handle is NULL, will create new one
I (1702) PLAY_FLASH_MP3_CONTROL: [ 2 ] Create audio pipeline, add all elements to pipeline, and subscribe pipeline event
I (1702) PLAY_FLASH_MP3_CONTROL: [2.1] Create mp3 decoder to decode mp3 file and set custom read callback
I (1712) PLAY_FLASH_MP3_CONTROL: [2.2] Create i2s stream to write data to codec chip
W (1722) i2s_common: dma frame num is adjusted to 320 to align the dma buffer with 64, bufsize = 1280
I (1732) PLAY_FLASH_MP3_CONTROL: [2.3] Register all elements to audio pipeline
I (1742) PLAY_FLASH_MP3_CONTROL: [2.4] Link it together [mp3_music_read_cb]-->mp3_decoder-->i2s_stream-->[codec_chip]
I (1752) PLAY_FLASH_MP3_CONTROL: [ 3 ] Initialize peripherals
I (1762) PLAY_FLASH_MP3_CONTROL: [3.1] Initialize keys on board
E (1762) AUDIO_BOARD: esp32_p4_function_ev_board not support key
I (1772) PLAY_FLASH_MP3_CONTROL: [ 4 ] Set up event listener
I (1772) PLAY_FLASH_MP3_CONTROL: [4.1] Listening event from all elements of pipeline
I (1782) PLAY_FLASH_MP3_CONTROL: [4.2] Listening event from peripherals
W (1792) PLAY_FLASH_MP3_CONTROL: [ 5 ] Tap touch buttons to control music player:
W (1802) PLAY_FLASH_MP3_CONTROL: [Play] to start, pause and resume, [Set] to stop.
W (1812) PLAY_FLASH_MP3_CONTROL: [Vol-] or [Vol+] to adjust volume.
I (1812) PLAY_FLASH_MP3_CONTROL: [ 5.1 ] Start audio_pipeline
I (1832) PLAY_FLASH_MP3_CONTROL: [ * ] Receive music info from mp3 decoder, sample_rates=8000, bits=16, ch=2
W (1852) i2s_common: dma frame num is adjusted to 320 to align the dma buffer with 64, bufsize = 1280

• Compiler version (run xtensa-esp32-elf-gcc --version in your project folder to find it):
  // esp32P4

• Operating system: [Ubuntu22.04]

• Using an IDE?: [No]

• Power supply: [USB]

Problem Description

1, esp32p4 touch screen is blank after running 'hello world' test
2. The touch screen is still blank after executing 'idf.py -p /dev/ttyUSB0 flash monitor'
3. There is an error message: E (1762) AUDIO_BOARD: esp32_p4_function_ev_board not support key
4. The program runs but get stuck somewhere without stopping and no audio output heard
// Detailed problem description goes here.
As described above.

Expected Behavior

1. The touch screen should show touch buttons to control music player:
   W (1802) PLAY_FLASH_MP3_CONTROL: [Play] to start, pause and resume, [Set] to stop.
   W (1812) PLAY_FLASH_MP3_CONTROL: [Vol-] or [Vol+] to adjust volume.
2. The program should end with success message not get stuck somewhere without ending.
3. The audio output need be heard

Actual Behavior

None of the expected output happened

Steps to Reproduce

cd ~/esp32p4
cp -r ~/esp32p4/esp-adf/examples/get-started/play_mp3_control .
cd ~/esp32p4/play_mp3_control
idf.py set-target esp32p4
idf.py menuconfig
idf.py build
idf.py -p /dev/ttyUSB0 flash monitor

// If possible, attach a picture of your setup/wiring here.

Code to Reproduce This Issue

Use play_mp3_control codes