espressif / esp-adf

Espressif Audio Development Framework
Other
1.53k stars 672 forks source link

DLNA m4a support (AUD-596) #107

Closed arlaor closed 4 years ago

arlaor commented 5 years ago

Is possible DLNA example m4a support? and foobar2000 upnp media server support?

tuanpmt commented 5 years ago

Hi @arlaor

The DLNA can be supported m4a by implementing an m4a player https://github.com/espressif/esp-adf/blob/master/examples/advanced_examples/dlna/main/audio_player.c#L166

With foobar2000, could you please give a try?

thanks

arlaor commented 5 years ago

Hi @tuanpmt

then I must include something like this code inside the file ?

and tried to use foobar2000 with UPNP server and selecting my esp32-Lyrat as audio output, but it stops at 2 seconds, without emitting sound

tuanpmt commented 5 years ago

Could you please provide the log

Thanks

arlaor commented 5 years ago

Hi @tuanpmt

send various logs

for foobar2000 with my code modify, this is the log for AAC file in foobar2000

I must clarify that the code works partially on the universal media server, but for some reason the mp3 format is disabled, some lines of code must be missing to enable it when recognizing the file extension to be played Also, when a song is paused or stopped in the universal media server, there are no options to play it again, until you reset the esp32-lyrat and play a new song again. The play button disappears and only have the option to stop

In foobar2000 with AAC file not working

ets Jun  8 2016 00:22:57

rst:0x1 (POWERON_RESET),boot:0x3f (SPI_FAST_FLASH_BOOT)
flash read err, 1000
ets_main.c 371 
ets Jun  8 2016 00:22:57

rst:0x10 (RTCWDT_RTC_RESET),boot:0x3f (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0018,len:4
load:0x3fff001c,len:5876
load:0x40078000,len:9288
load:0x40080000,len:6008
entry 0x4008032c
I (29) boot: ESP-IDF v3.1.1-6-g2aa9a2118 2nd stage bootloader
I (29) boot: compile time 23:04:12
I (31) boot: Enabling RNG early entropy source...
I (35) boot: SPI Speed      : 40MHz
I (39) boot: SPI Mode       : DIO
I (43) boot: SPI Flash Size : 4MB
I (47) boot: Partition Table:
I (50) boot: ## Label            Usage          Type ST Offset   Length
I (58) boot:  0 nvs              WiFi data        01 02 00009000 00006000
I (65) boot:  1 phy_init         RF data          01 01 0000f000 00001000
I (73) boot:  2 factory          factory app      00 00 00010000 00200000
I (80) boot: End of partition table
I (84) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x68a8c (428684) map
I (243) esp_image: segment 1: paddr=0x00078ab4 vaddr=0x3ffb0000 size=0x03874 ( 14452) load
I (249) esp_image: segment 2: paddr=0x0007c330 vaddr=0x3ffb3874 size=0x00000 (     0) load
I (250) esp_image: segment 3: paddr=0x0007c338 vaddr=0x40080000 size=0x00400 (  1024) load
I (260) esp_image: segment 4: paddr=0x0007c740 vaddr=0x40080400 size=0x038d0 ( 14544) load
I (274) esp_image: segment 5: paddr=0x00080018 vaddr=0x400d0018 size=0xc0cc8 (789704) map
I (553) esp_image: segment 6: paddr=0x00140ce8 vaddr=0x40083cd0 size=0x1508c ( 86156) load
I (589) esp_image: segment 7: paddr=0x00155d7c vaddr=0x400c0000 size=0x00000 (     0) load
I (590) esp_image: segment 8: paddr=0x00155d84 vaddr=0x50000000 size=0x00000 (     0) load
I (611) boot: Loaded app from partition at offset 0x10000
I (611) boot: Disabling RNG early entropy source...
I (613) spiram: SPI RAM mode: flash 40m sram 40m
I (616) spiram: PSRAM initialized, cache is in low/high (2-core) mode.
I (623) cpu_start: Pro cpu up.
I (627) cpu_start: Starting app cpu, entry point is 0x4008146c
I (0) cpu_start: App cpu up.
I (1539) spiram: SPI SRAM memory test OK
I (1540) heap_init: Initializing. RAM available for dynamic allocation:
I (1540) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM
I (1546) heap_init: At 3FFB9BC0 len 00026440 (153 KiB): DRAM
I (1552) heap_init: At 3FFE0440 len 00003BC0 (14 KiB): D/IRAM
I (1559) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
I (1565) heap_init: At 40098D5C len 000072A4 (28 KiB): IRAM
I (1572) cpu_start: Pro cpu start user code
I (1576) spiram: Adding pool of 4096K of external SPI memory to heap allocator
I (1584) spiram: Reserving pool of 32K of internal memory for DMA/internal allocations
I (264) cpu_start: Starting scheduler on PRO CPU.
I (0) cpu_start: Starting scheduler on APP CPU.
I (391) wifi: wifi driver task: 3ffc1a50, prio:23, stack:3584, core=0
I (391) wifi: wifi firmware version: b2c9a19
I (391) wifi: config NVS flash: enabled
I (391) wifi: config nano formating: disabled
I (401) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
I (411) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
I (431) wifi: Init dynamic tx buffer num: 32
I (431) wifi: Init data frame dynamic rx buffer num: 32
I (441) wifi: Init management frame dynamic rx buffer num: 32
I (441) wifi: Init static tx buffer num: 16
I (441) wifi: Init static rx buffer size: 1600
I (451) wifi: Init static rx buffer num: 10
I (451) wifi: Init dynamic rx buffer num: 32
I (521) phy: phy_version: 4000, b6198fa, Sep  3 2018, 15:11:06, 0, 0
I (521) wifi: mode : sta (80:7d:3a:e1:51:2c)
I (881) wifi: n:3 1, o:1 0, ap:255 255, sta:3 1, prof:1
I (2011) wifi: state: init -> auth (b0)
I (2021) wifi: state: auth -> assoc (0)
I (2031) wifi: state: assoc -> run (10)
I (2141) wifi: connected with DESPLAZADOS, channel 3
I (2141) wifi: pm start, type: 1

I (2891) event: sta ip: 192.168.1.215, mask: 255.255.255.0, gw: 192.168.1.1
I (2891) AUDIO_PLAYER: [ 1 ] Start audio codec chip
I (2891) gpio: GPIO[19]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:3 
E (2901) gpio: gpio_install_isr_service(394): GPIO isr service already installed
I (2921) gpio: GPIO[21]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 
I (2921) ES8388_DRIVER: init,out:02, in:00
I (2931) AUDIO_HAL: Codec mode is 2, Ctrl:1
I (2931) AUDIO_PLAYER: [2.0] Create audio pipeline for playback
I (2931) AUDIO_PLAYER: [2.1] Create http stream to read data
I (2941) AUDIO_PLAYER: [2.2] Create i2s stream to write data to codec chip
I (2951) I2S: DMA Malloc info, datalen=blocksize=1200, dma_buf_count=3
I (2961) I2S: DMA Malloc info, datalen=blocksize=1200, dma_buf_count=3
I (2971) I2S: APLL: Req RATE: 44100, real rate: 43945.238, BITS: 16, CLKM: 1, BCK_M: 8, MCLK: 11249981.000, SCLK: 1406247.625000, diva: 1, divb: 0
I (2981) AUDIO_PLAYER: [2.3] Create mp3 decoder to decode mp3 file
I (2981) STAGEFRIGHTMP3_DECODER: MP3 init
I (2991) AUDIO_PLAYER: [2.4] Register all elements to audio pipeline
I (3001) AUDIO_PLAYER: [2.5] Link elements together http_stream-->mp3_decoder-->i2s_stream-->[codec_chip]
I (3011) AUDIO_PIPELINE: audio_pipeline_link:0x3f80008c, http, 0x3f8003b0
I (3011) AUDIO_PIPELINE: audio_pipeline_link:0x3f8002a8, mp3, 0x3f805400
I (3021) AUDIO_PLAYER: [2.6] Create aac decoder to decode aac file
I (3031) AUDIO_PLAYER: [2.7] Register all elements to audio pipeline
I (3031) AUDIO_PLAYER: [2.8] Link elements together http_stream-->aac_decoder-->i2s_stream-->[codec_chip]
W (3041) AUDIO_PIPELINE: There are no listener registered
I (3051) AUDIO_PIPELINE: audio_pipeline_unlinked
I (3061) AUDIO_PIPELINE: audio_pipeline_link:0x3f80008c, http, 0x3f8003b0
I (3061) AUDIO_PIPELINE: audio_pipeline_link:0x3f8074b0, aac, 0x3f805400
I (3071) AUDIO_PLAYER: [ 3 ] Setup event listener
I (3081) AUDIO_PLAYER: [3.1] Listening event from all elements of pipeline
I (3101) DLNA_EXAMPLE: DLNA Started...
E (4041) HTTP_CLIENT: Error parse url uuid:5851f42d-8ccf-f646-3129-30705b045db4
E (4041) UPNP_NOTIFY: Invalid URI
E (4101) HTTP_CLIENT: Error parse url uuid:5851f42d-8ccf-f646-3129-30705b045db4
E (4101) UPNP_NOTIFY: Invalid URI
E (4161) HTTP_CLIENT: Error parse url uuid:5851f42d-8ccf-f646-3129-30705b045db4
E (4161) UPNP_NOTIFY: Invalid URI
I (4351) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
I (14991) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
W (15011) httpd_txrx: error in recv = 104
W (15011) httpd_txrx: 408 Request Timeout - Server closed this connection
W (15011) httpd_txrx: error in send = 104
I (15021) DLNA_EXAMPLE: Stop instance=0
I (15191) DLNA_EXAMPLE: Stop instance=0
I (15811) DLNA_EXAMPLE: SetAVTransportURI=http://192.168.1.5:49195/2/stream.l16
I (16121) DLNA_EXAMPLE: Play with speed=1
I (16121) AUDIO_ELEMENT: [http] Element task created
I (16121) AUDIO_ELEMENT: [aac] Element task created
I (16121) AUDIO_ELEMENT: [i2s] Element task created
I (16131) AUDIO_PIPELINE: Func:audio_pipeline_run, Line:278, MEM Total:4287636 Bytes, Inter:155308 Bytes, Dram:125996 Bytes


I (16141) AUDIO_ELEMENT: [aac] AEL_MSG_CMD_RESUME,state:1
I (16141) AUDIO_ELEMENT: [i2s] AEL_MSG_CMD_RESUME,state:1
I (16151) I2S_STREAM: AUDIO_STREAM_WRITER
I (16161) AUDIO_PIPELINE: Pipeline started
I (16171) AUDIO_ELEMENT: [http] AEL_MSG_CMD_RESUME,state:1
I (16441) AAC_DECODER: new aduio song
I (16441) AAC_DECODER: this audio is RAW AAC
I (17381) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
I (27841) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
I (38601) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
I (49131) DLNA_EXAMPLE: _avt_get_trans_state STOPPED

This is the log in Foobar2000 for mp3 file not working

[0;31mE (85021) AUDIO_ELEMENT: [aac] AEL_STATUS_ERROR_OPEN
I (85031) AAC_DECODER: Closed
I (85041) AUDIO_PLAYER: Stop pipeline
I (85041) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
I (87041) DLNA_EXAMPLE: Stop instance=0
I (87521) DLNA_EXAMPLE: SetAVTransportURI=http://192.168.1.5:49195/3/stream.l16
I (87801) DLNA_EXAMPLE: Play with speed=1
I (87801) AUDIO_ELEMENT: [aac] Element task created
I (87801) AUDIO_PIPELINE: Func:audio_pipeline_run, Line:278, MEM Total:4283996 Bytes, Inter:152700 Bytes, Dram:123388 Bytes


I (87811) AUDIO_ELEMENT: [aac] AEL_MSG_CMD_RESUME,state:1
I (87821) AUDIO_ELEMENT: [i2s] AEL_MSG_CMD_RESUME,state:1
I (87821) I2S_STREAM: AUDIO_STREAM_WRITER
I (87831) AUDIO_PIPELINE: Pipeline started
I (87841) AUDIO_ELEMENT: [http] AEL_MSG_CMD_RESUME,state:4
I (87941) AAC_DECODER: new aduio song
I (87941) AAC_DECODER: this audio is RAW AAC
W (88001) httpd_txrx: error in recv = 104
W (88001) httpd_txrx: 408 Request Timeout - Server closed this connection
W (88001) httpd_txrx: error in send = 104
I (88021) DLNA_EXAMPLE: Stop instance=0
W (88021) AUDIO_ELEMENT: IN-[aac] AEL_IO_ABORT
Encountered error reading stream
E (88031) AUDIO_ELEMENT: [aac] AEL_STATUS_ERROR_OPEN
I (88031) AAC_DECODER: Closed
I (88051) AUDIO_PLAYER: Stop pipeline
I (88051) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
I (90401) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
W (91061) httpd_txrx: error in recv = 104
W (91061) httpd_txrx: 408 Request Timeout - Server closed this connection
W (91071) httpd_txrx: error in send = 104
I (91081) DLNA_EXAMPLE: Stop instance=0
I (101111) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
I (111761) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
I (122411) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
I (133061) DLNA_EXAMPLE: _avt_get_trans_state STOPPED

This is the log in universal media server with AAC file working

[0;32mI (1567881) DLNA_EXAMPLE: SetAVTransportURI=http://192.168.1.5:5001/get/76$Temp/D%3A%5Cm4a%5CABELPI%7E1.M4A
I (1568191) DLNA_EXAMPLE: Play with speed=1
I (1568191) AUDIO_ELEMENT: [aac] Element task created
I (1568191) AUDIO_PIPELINE: Func:audio_pipeline_run, Line:278, MEM Total:4283084 Bytes, Inter:151788 Bytes, Dram:122476 Bytes


I (1568201) AUDIO_ELEMENT: [aac] AEL_MSG_CMD_RESUME,state:1
I (1568211) AUDIO_ELEMENT: [i2s] AEL_MSG_CMD_RESUME,state:1
I (1568211) I2S_STREAM: AUDIO_STREAM_WRITER
I (1568221) AUDIO_PIPELINE: Pipeline started
I (1568231) AUDIO_ELEMENT: [http] AEL_MSG_CMD_RESUME,state:4
I (1568531) AAC_DECODER: new aduio song
I (1569991) DLNA_EXAMPLE: _avt_get_trans_state PLAYING
I (1570011) AAC_DECODER: I2S setup: sample_rate = 44100 : channels = 2
I (1570011) AUDIO_PLAYER: [ * ] Receive music info from aac decoder, sample_rates=44100, bits=16, ch=2
I (1570011) AAC_DECODER: M4A sampleRate = 44100, Channels = 2, frameSize = 1024
I (1570041) AUDIO_ELEMENT: [i2s] AEL_MSG_CMD_PAUSE
I (1570041) I2S: APLL: Req RATE: 44100, real rate: 43945.238, BITS: 16, CLKM: 1, BCK_M: 8, MCLK: 11249981.000, SCLK: 1406247.625000, diva: 1, divb: 0
I (1570051) AUDIO_ELEMENT: [i2s] AEL_MSG_CMD_RESUME,state:3
I (1570061) I2S_STREAM: AUDIO_STREAM_WRITER
W (1570271) UPNP_NOTIFY: Invalid subscription, Unsubscribe=uuid:70a3a52b-baf1-41fc-6fd9-742d2f7a0796
I (1573081) DLNA_EXAMPLE: _avt_get_pos 00:00:04
I (1573081) DLNA_EXAMPLE: _avt_get_pos 00:00:04
I (1573371) DLNA_EXAMPLE: _avt_get_trans_state PLAYING
I (1583631) DLNA_EXAMPLE: _avt_get_pos 00:00:15
I (1583641) DLNA_EXAMPLE: _avt_get_pos 00:00:15
I (1584011) DLNA_EXAMPLE: _avt_get_trans_state PLAYING
I (1591011) gpio: GPIO[21]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 
W (1591281) HEADPHONE: Headphone jack inserted
I (1594321) DLNA_EXAMPLE: _avt_get_pos 00:00:25
I (1594321) DLNA_EXAMPLE: _avt_get_pos 00:00:25
I (1594591) DLNA_EXAMPLE: _avt_get_trans_state PLAYING
I (1604861) DLNA_EXAMPLE: _avt_get_pos 00:00:36
I (1604861) DLNA_EXAMPLE: _avt_get_pos 00:00:36
I (1605131) DLNA_EXAMPLE: _avt_get_trans_state PLAYING
I (1615421) DLNA_EXAMPLE: _avt_get_pos 00:00:47
I (1615421) DLNA_EXAMPLE: _avt_get_pos 00:00:47
I (1615751) DLNA_EXAMPLE: _avt_get_trans_state PLAYING

this is the log in universal media server with MP3 file not working

[0;32mI (1847121) DLNA_EXAMPLE: SetAVTransportURI=http://192.168.1.5:5001/get/108$Temp/D%3A%5Cm4a%5Camanece.mp3
I (1847431) DLNA_EXAMPLE: Play with speed=1
I (1847431) AUDIO_PIPELINE: Func:audio_pipeline_run, Line:278, MEM Total:4282832 Bytes, Inter:151536 Bytes, Dram:122224 Bytes


I (1847441) AUDIO_ELEMENT: [aac] AEL_MSG_CMD_RESUME,state:4
I (1847451) AUDIO_ELEMENT: [i2s] AEL_MSG_CMD_RESUME,state:1
I (1847451) I2S_STREAM: AUDIO_STREAM_WRITER
W (1847461) AUDIO_ELEMENT: IN-[i2s] AEL_IO_ABORT
I (1847461) AUDIO_PIPELINE: Pipeline started
W (1847461) AUDIO_ELEMENT: IN-[aac] AEL_IO_ABORT
E (1847471) AAC_DECODER: empty aac file
E (1847481) AUDIO_ELEMENT: [aac] AEL_STATUS_ERROR_OPEN
I (1847481) AAC_DECODER: Closed
I (1847481) AUDIO_PLAYER: Stop pipeline
I (1847491) AUDIO_ELEMENT: [http] AEL_MSG_CMD_RESUME,state:4
I (1847491) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
W (1847751) UPNP_NOTIFY: Invalid subscription, Unsubscribe=uuid:6931589d-e07e-bb77-16c6-1dee132f7726
I (1853161) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
I (1864631) DLNA_EXAMPLE: _avt_get_trans_state STOPPED
I (1875301) DLNA_EXAMPLE: _avt_get_trans_state STOPPED

I must also clarify that I am not a programmer or something similar, I am an amateur that I have only made the union of code trying to carry a logical sequence but i have failed

tuanpmt commented 5 years ago

Hi @arlaor

The DLNA still working good, there are some points:

arlaor commented 5 years ago
  • audio_pipeline_link(ap->pipeline, (const char *[]) {"http", "mp3", "i2s"}, 3);

Wich is the way to go? that I must modify or include in my code, I am not clear about what is missing

tuanpmt commented 5 years ago

If you want to play the mp3 file, you must remove the red rectangle image

arlaor commented 5 years ago

the idea is to be able to reproduce the two formats or more, if possible

I am reviewing this code to try to adapt it to DLNA. Is it possible that it will be recognized and work?

arlaor commented 5 years ago

Hi @tuanpmt, @krzychb

I have adapted part of this code to my DLNA code, but I can not verify selected_decoder to make the change of decoder The aac file working The MP3 file don't working can you verify it please

This is The log with this code modified ets Jun 8 2016 00:22:57

rst:0x1 (POWERON_RESET),boot:0x3f (SPI_FAST_FLASH_BOOT) flash read err, 1000 ets_main.c 371 ets Jun 8 2016 00:22:57

rst:0x10 (RTCWDT_RTC_RESET),boot:0x3f (SPI_FAST_FLASH_BOOT) configsip: 0, SPIWP:0xee clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00 mode:DIO, clock div:2 load:0x3fff0018,len:4 load:0x3fff001c,len:5876 load:0x40078000,len:9288 load:0x40080000,len:6008 0x40080000: _iram_start at D:/ARDUINO/espressif/lyrat/esp-adf/esp-idf/components /freertos/xtensa_vectors.S:1685

entry 0x4008032c 0x4008032c: _KernelExceptionVector at ??:?

I (29) boot: ESP-IDF v3.1.1-6-g2aa9a2118 2nd stage bootloader I (29) boot: compile time 23:04:12 I (31) boot: Enabling RNG early entropy source... I (35) boot: SPI Speed : 40MHz I (39) boot: SPI Mode : DIO I (43) boot: SPI Flash Size : 4MB I (47) boot: Partition Table: I (51) boot: ## Label Usage Type ST Offset Length I (58) boot: 0 nvs WiFi data 01 02 00009000 00006000 I (65) boot: 1 phy_init RF data 01 01 0000f000 00001000 I (73) boot: 2 factory factory app 00 00 00010000 00200000 I (80) boot: End of partition table I (84) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x657d4 (415 700) map I (239) esp_image: segment 1: paddr=0x000757fc vaddr=0x3ffb0000 size=0x03874 ( 1 4452) load I (245) esp_image: segment 2: paddr=0x00079078 vaddr=0x3ffb3874 size=0x00000 ( 0) load I (246) esp_image: segment 3: paddr=0x00079080 vaddr=0x40080000 size=0x00400 ( 1024) load 0x40080000: _iram_start at D:/ARDUINO/espressif/lyrat/esp-adf/esp-idf/components /freertos/xtensa_vectors.S:1685

I (255) esp_image: segment 4: paddr=0x00079488 vaddr=0x40080400 size=0x06b88 ( 2 7528) load I (275) esp_image: segment 5: paddr=0x00080018 vaddr=0x400d0018 size=0xbaa4c (76 4492) map 0x400d0018: _stext at ??:?

I (543) esp_image: segment 6: paddr=0x0013aa6c vaddr=0x40086f88 size=0x11dd4 ( 7 3172) load 0x40086f88: i2c_isr_handler_default at D:/ARDUINO/espressif/lyrat/esp-adf/esp-id f/components/driver/i2c.c:1074

I (574) esp_image: segment 7: paddr=0x0014c848 vaddr=0x400c0000 size=0x00000 ( 0) load I (574) esp_image: segment 8: paddr=0x0014c850 vaddr=0x50000000 size=0x00000 ( 0) load I (595) boot: Loaded app from partition at offset 0x10000 I (595) boot: Disabling RNG early entropy source... I (597) spiram: SPI RAM mode: flash 40m sram 40m I (600) spiram: PSRAM initialized, cache is in low/high (2-core) mode. I (608) cpu_start: Pro cpu up. I (611) cpu_start: Starting app cpu, entry point is 0x4008146c 0x4008146c: call_start_cpu1 at D:/ARDUINO/espressif/lyrat/esp-adf/esp-idf/compon ents/esp32/cpu_start.c:231

I (0) cpu_start: App cpu up. I (1523) spiram: SPI SRAM memory test OK I (1524) heap_init: Initializing. RAM available for dynamic allocation: I (1524) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM I (1530) heap_init: At 3FFB9BC0 len 00026440 (153 KiB): DRAM I (1537) heap_init: At 3FFE0440 len 00003BC0 (14 KiB): D/IRAM I (1543) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (1549) heap_init: At 40098D5C len 000072A4 (28 KiB): IRAM I (1556) cpu_start: Pro cpu start user code I (1561) spiram: Adding pool of 4096K of external SPI memory to heap allocator I (1569) spiram: Reserving pool of 32K of internal memory for DMA/internal alloc ations I (248) cpu_start: Starting scheduler on PRO CPU. I (0) cpu_start: Starting scheduler on APP CPU. I (411) wifi: wifi driver task: 3ffc1a50, prio:23, stack:3584, core=0 I (411) wifi: wifi firmware version: b2c9a19 I (411) wifi: config NVS flash: enabled I (411) wifi: config nano formating: disabled I (411) system_api: Base MAC address is not set, read default base MAC address f rom BLK0 of EFUSE I (421) system_api: Base MAC address is not set, read default base MAC address f rom BLK0 of EFUSE I (451) wifi: Init dynamic tx buffer num: 32 I (451) wifi: Init data frame dynamic rx buffer num: 32 I (451) wifi: Init management frame dynamic rx buffer num: 32 I (461) wifi: Init static tx buffer num: 16 I (461) wifi: Init static rx buffer size: 1600 I (471) wifi: Init static rx buffer num: 10 I (471) wifi: Init dynamic rx buffer num: 32 W (481) phy_init: failed to load RF calibration data (0x1102), falling back to f ull calibration I (631) phy: phy_version: 4000, b6198fa, Sep 3 2018, 15:11:06, 0, 2 I (641) wifi: mode : sta (80:7d:3a:e1:51:2c) I (1011) wifi: n:3 1, o:1 0, ap:255 255, sta:3 1, prof:1 I (2141) wifi: state: init -> auth (b0) I (2141) wifi: state: auth -> assoc (0) I (3141) wifi: state: assoc -> init (400) I (3141) wifi: n:3 0, o:3 1, ap:255 255, sta:3 1, prof:1 W (3151) PERIPH_WIFI: Wi-Fi disconnected from SSID DESPLAZADOS, auto-reconnect e nabled, reconnect after 1000 ms W (6561) PERIPH_WIFI: Wi-Fi disconnected from SSID DESPLAZADOS, auto-reconnect e nabled, reconnect after 1000 ms I (7681) wifi: n:3 1, o:3 0, ap:255 255, sta:3 1, prof:1 I (7681) wifi: state: init -> auth (b0) I (7681) wifi: state: auth -> assoc (0) I (7681) wifi: state: assoc -> run (10) I (7791) wifi: connected with DESPLAZADOS, channel 3 I (7791) wifi: pm start, type: 1

I (8401) event: sta ip: 192.168.1.215, mask: 255.255.255.0, gw: 192.168.1.1 I (8401) HTTP_SELECT_AAC_EXAMPLE: [ 1 ] Start audio codec chip I (8401) gpio: GPIO[19]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulld own: 0| Intr:3 E (8411) gpio: gpio_install_isr_service(394): GPIO isr service already installed

I (8431) gpio: GPIO[21]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulld own: 0| Intr:0 I (8431) ES8388_DRIVER: init,out:02, in:00 I (8441) AUDIO_HAL: Codec mode is 2, Ctrl:1 I (8451) HTTP_SELECT_AAC_EXAMPLE: [2.0] Create audio pipeline for playback I (8451) HTTP_SELECT_AAC_EXAMPLE: [2.1] Create http stream to read data I (8461) HTTP_SELECT_AAC_EXAMPLE: [2.2] Create aac decoder to decode aac file I (8461) HTTP_SELECT_AAC_EXAMPLE: [2.3] Create i2s stream to write data to codec chip I (8471) I2S: DMA Malloc info, datalen=blocksize=1200, dma_buf_count=3 I (8481) I2S: DMA Malloc info, datalen=blocksize=1200, dma_buf_count=3 I (8491) I2S: APLL: Req RATE: 44100, real rate: 43945.238, BITS: 16, CLKM: 1, BC K_M: 8, MCLK: 11249981.000, SCLK: 1406247.625000, diva: 1, divb: 0 I (8501) HTTP_SELECT_AAC_EXAMPLE: [2.4] Register all elements to audio pipeline I (8511) HTTP_SELECT_AAC_EXAMPLE: [2.5] Link it together http_stream-->aac_decod er-->i2s_stream-->[codec_chip] I (8521) AUDIO_PIPELINE: audio_pipeline_link:0x3f80008c, http, 0x3f8003d8 I (8531) AUDIO_PIPELINE: audio_pipeline_link:0x3f8001b8, aac, 0x3f805428 I (8531) HTTP_SELECT_AAC_EXAMPLE: [ 3 ] Setup event listener I (8541) HTTP_SELECT_AAC_EXAMPLE: [3.1] Listening event from all elements of pip eline I (8561) DLNA_EXAMPLE: DLNA Started... W (13411) httpd: error in accept (23) W (13411) httpd: error accepting new connection W (13431) httpd: error in accept (23) W (13431) httpd: error accepting new connection I (13631) DLNA_EXAMPLE: _avt_get_trans_state STOPPED I (24241) DLNA_EXAMPLE: _avt_get_trans_state STOPPED I (33861) DLNA_EXAMPLE: SetAVTransportURI=http://192.168.1.5:5001/get/953$Temp/D %3A%5Cm4a%5C01+Dieciocho.m4a I (34171) DLNA_EXAMPLE: Play with speed=1 I (34171) AUDIO_ELEMENT: [http] Element task created I (34171) AUDIO_ELEMENT: [aac] Element task created I (34181) AUDIO_ELEMENT: [i2s] Element task created I (34181) AUDIO_PIPELINE: Func:audio_pipeline_run, Line:278, MEM Total:4284088 B ytes, Inter:152508 Bytes, Dram:123196 Bytes

I (34191) AUDIO_PIPELINE: Pipeline started I (34191) AUDIO_ELEMENT: [i2s] AEL_MSG_CMD_RESUME,state:1 I (34201) I2S_STREAM: AUDIO_STREAM_WRITER I (34191) AUDIO_ELEMENT: [aac] AEL_MSG_CMD_RESUME,state:1 I (34191) AUDIO_ELEMENT: [http] AEL_MSG_CMD_RESUME,state:1 I (34371) AAC_DECODER: new aduio song I (34731) DLNA_EXAMPLE: _avt_get_trans_state STOPPED I (35661) DLNA_EXAMPLE: _avt_get_trans_state PLAYING I (35671) AAC_DECODER: I2S setup: sample_rate = 44100 : channels = 2 I (35671) AAC_DECODER: M4A sampleRate = 44100, Channels = 2, frameSize = 1024 I (35671) HTTP_SELECT_AAC_EXAMPLE: [ ] Receive music info from aac decoder, sa mple_rates=44100, bits=16, ch=2 I (35721) AUDIO_ELEMENT: [i2s] AEL_MSG_CMD_PAUSE I (35721) I2S: APLL: Req RATE: 44100, real rate: 43945.238, BITS: 16, CLKM: 1, B CK_M: 8, MCLK: 11249981.000, SCLK: 1406247.625000, diva: 1, divb: 0 I (35731) AUDIO_ELEMENT: [i2s] AEL_MSG_CMD_RESUME,state:3 I (35731) I2S_STREAM: AUDIO_STREAM_WRITER I (37061) DLNA_EXAMPLE: _avt_get_pos 00:00:02 I (37061) DLNA_EXAMPLE: _avt_get_pos 00:00:02 I (38371) DLNA_EXAMPLE: _avt_get_pos 00:00:04 I (38371) DLNA_EXAMPLE: _avt_get_pos 00:00:04 I (39611) DLNA_EXAMPLE: _avt_get_pos 00:00:05 I (39611) DLNA_EXAMPLE: _avt_get_pos 00:00:05 I (40841) DLNA_EXAMPLE: _avt_get_pos 00:00:06 I (40841) DLNA_EXAMPLE: _avt_get_pos 00:00:06 I (42071) DLNA_EXAMPLE: _avt_get_pos 00:00:07 I (42071) DLNA_EXAMPLE: _avt_get_pos 00:00:07 I (43301) DLNA_EXAMPLE: _avt_get_pos 00:00:09 I (43311) DLNA_EXAMPLE: _avt_get_pos 00:00:09 I (44551) DLNA_EXAMPLE: _avt_get_pos 00:00:10 I (44551) DLNA_EXAMPLE: _avt_get_pos 00:00:10 I (44971) DLNA_EXAMPLE: _avt_get_pos 00:00:10 I (44971) DLNA_EXAMPLE: _avt_get_pos 00:00:10 I (45201) DLNA_EXAMPLE: _avt_get_trans_state PLAYING I (45851) DLNA_EXAMPLE: _avt_get_pos 00:00:11 I (45861) DLNA_EXAMPLE: _avt_get_pos 00:00:11 I (47101) DLNA_EXAMPLE: _avt_get_pos 00:00:12 I (47101) DLNA_EXAMPLE: _avt_get_pos 00:00:12 I (48331) DLNA_EXAMPLE: _avt_get_pos 00:00:14 I (48331) DLNA_EXAMPLE: _avt_get_pos 00:00:14 I (49571) DLNA_EXAMPLE: _avt_get_pos 00:00:15 I (49571) DLNA_EXAMPLE: _avt_get_pos 00:00:15 I (50821) DLNA_EXAMPLE: _avt_get_pos 00:00:16 I (50821) DLNA_EXAMPLE: _avt_get_pos 00:00:16 I (52141) DLNA_EXAMPLE: _avt_get_pos 00:00:17 I (52141) DLNA_EXAMPLE: _avt_get_pos 00:00:17 I (53381) DLNA_EXAMPLE: _avt_get_pos 00:00:19 I (53381) DLNA_EXAMPLE: _avt_get_pos 00:00:19 I (54621) DLNA_EXAMPLE: _avt_get_pos 00:00:20 I (54621) DLNA_EXAMPLE: _avt_get_pos 00:00:20 I (55451) DLNA_EXAMPLE: _avt_get_pos 00:00:21 I (55451) DLNA_EXAMPLE: _avt_get_pos 00:00:21 I (55681) DLNA_EXAMPLE: _avt_get_trans_state PLAYING I (55861) DLNA_EXAMPLE: _avt_get_pos 00:00:21 I (55861) DLNA_EXAMPLE: _avt_get_pos 00:00:21 I (57101) DLNA_EXAMPLE: _avt_get_pos 00:00:22 I (57101) DLNA_EXAMPLE: _avt_get_pos 00:00:22 I (58331) DLNA_EXAMPLE: _avt_get_pos 00:00:24 I (58331) DLNA_EXAMPLE: _avt_get_pos 00:00:24 I (59561) DLNA_EXAMPLE: _avt_get_pos 00:00:25 I (59571) DLNA_EXAMPLE: _avt_get_pos 00:00:25 I (60871) DLNA_EXAMPLE: _avt_get_pos 00:00:26 I (60871) DLNA_EXAMPLE: _avt_get_pos 00:00:26 I (62121) DLNA_EXAMPLE: _avt_get_pos 00:00:27 I (62121) DLNA_EXAMPLE: _avt_get_pos 00:00:27 I (62551) DLNA_EXAMPLE: Stop instance=0 I (62581) HTTP_SELECT_AAC_EXAMPLE: Stop pipeline W (62581) AUDIO_ELEMENT: OUT-[aac] AEL_IO_ABORT W (62581) HTTP_SELECT_AAC_EXAMPLE: [ ] Stop event received W (62581) AAC_DECODER: output aborted -3 4096 I (62581) DLNA_EXAMPLE: _avt_get_trans_state STOPPED I (62591) AAC_DECODER: Closed I (64781) DLNA_EXAMPLE: SetAVTransportURI=http://192.168.1.5:5001/get/984$Temp/D %3A%5Cm4a%5Camanece.mp3 I (65091) DLNA_EXAMPLE: Play with speed=1 I (65101) AUDIO_PIPELINE: Func:audio_pipeline_run, Line:278, MEM Total:4282784 B ytes, Inter:151204 Bytes, Dram:121892 Bytes

I (65101) AUDIO_PIPELINE: Pipeline started I (65101) AUDIO_ELEMENT: [i2s] AEL_MSG_CMD_RESUME,state:1 I (65111) I2S_STREAM: AUDIO_STREAM_WRITER I (65101) AUDIO_ELEMENT: [aac] AEL_MSG_CMD_RESUME,state:1 I (65101) AUDIO_ELEMENT: [http] AEL_MSG_CMD_RESUME,state:4 I (65291) AAC_DECODER: new aduio song I (65291) AAC_DECODER: this audio is RAW AAC I (66161) DLNA_EXAMPLE: _avt_get_trans_state STOPPED I (70081) DLNA_EXAMPLE: SetAVTransportURI=http://192.168.1.5:5001/get/null$Temp/ C%3A%5CProgram+Files+%28x86%29%5CUniversal+Media+Server%5Camanece I (70321) DLNA_EXAMPLE: Play with speed=1 W (70341) AUDIO_ELEMENT: IN-[aac] AEL_IO_ABORT Encountered error reading stream E (70341) AUDIO_ELEMENT: [aac] AEL_STATUS_ERROR_OPEN I (70341) AAC_DECODER: Closed I (70351) HTTP_SELECT_AAC_EXAMPLE: Stop pipeline W (70351) HTTP_SELECT_AAC_EXAMPLE: [ * ] Stop event received I (70361) DLNA_EXAMPLE: _avt_get_trans_state STOPPED I (70361) AUDIO_ELEMENT: [aac] Element task created I (70371) AUDIO_PIPELINE: Func:audio_pipeline_run, Line:278, MEM Total:4282100 B ytes, Inter:150520 Bytes, Dram:121208 Bytes

I (70381) AUDIO_ELEMENT: [i2s] AEL_MSG_CMD_RESUME,state:4 I (70381) AUDIO_PIPELINE: Pipeline started I (70391) I2S_STREAM: AUDIO_STREAM_WRITER W (70391) AUDIO_ELEMENT: IN-[i2s] AEL_IO_ABORT I (70391) AUDIO_ELEMENT: [aac] AEL_MSG_CMD_RESUME,state:4 W (70401) UPNP_NOTIFY: Invalid subscription, Unsubscribe=uuid:1a8b7f78-59d8-4868 -56a7-08cdb7ffd43f W (70421) AUDIO_ELEMENT: IN-[aac] AEL_IO_ABORT E (70421) AAC_DECODER: empty aac file I (70421) HTTP_SELECT_AAC_EXAMPLE: Stop pipeline E (70421) AUDIO_ELEMENT: [aac] AEL_STATUS_ERROR_OPEN W (70431) HTTP_SELECT_AAC_EXAMPLE: [ * ] Stop event received I (70441) AAC_DECODER: Closed I (70441) DLNA_EXAMPLE: _avt_get_trans_state STOPPED I (70391) AUDIO_ELEMENT: [http] AEL_MSG_CMD_RESUME,state:4 E (70571) HTTP_STREAM: Invalid HTTP stream W (70571) HTTP_STREAM: No more data,errno:0, total_bytes:0 I (70571) AUDIO_ELEMENT: IN-[http] AEL_IO_DONE,0 I (72871) DLNA_EXAMPLE: SetAVTransportURI=http://192.168.1.5:5001/get/null$Temp/ C%3A%5CProgram+Files+%28x86%29%5CUniversal+Media+Server%5Camanece I (73181) DLNA_EXAMPLE: Play with speed=1 I (73181) AUDIO_ELEMENT: [aac] Element task created I (73181) AUDIO_PIPELINE: Func:audio_pipeline_run, Line:278, MEM Total:4282580 B ytes, Inter:151000 Bytes, Dram:121688 Bytes

I (73201) AUDIO_ELEMENT: [http] RESUME: Element has finished, state:5 I (73201) AUDIO_PIPELINE: Pipeline started I (73201) AUDIO_ELEMENT: [i2s] AEL_MSG_CMD_RESUME,state:1 I (73211) I2S_STREAM: AUDIO_STREAM_WRITER I (73201) AUDIO_ELEMENT: [aac] AEL_MSG_CMD_RESUME,state:1 I (73221) AUDIO_ELEMENT: IN-[aac] AEL_IO_DONE,-2 E (73231) AAC_DECODER: empty aac file E (73231) AUDIO_ELEMENT: [aac] AEL_STATUS_ERROR_OPEN W (73241) AUDIO_ELEMENT: IN-[i2s] AEL_IO_ABORT I (73241) AAC_DECODER: Closed I (73271) HTTP_SELECT_AAC_EXAMPLE: Stop pipeline W (73271) HTTP_SELECT_AAC_EXAMPLE: [ * ] Stop event received I (73271) DLNA_EXAMPLE: _avt_get_trans_state STOPPED I (76771) DLNA_EXAMPLE: _avt_get_trans_state STOPPED