voip例程，按play键无法接听
Posted: Thu Feb 09, 2023 7:21 am
esp-adf-v2.4.1
esp-idf-v4.4.3
ESP32-LyraT V4.3
Freeswitch 服务器
MicroSIP客户端
如果是ESP32-LyraT V4.3呼出，MicroSIP接听，可以正常接通并通话
但如果是MicroSIP呼出，ESP32-LyraT V4.3可以收到电话呼入，也有振铃响声，但是按play键后，MicroSIP显示已接通，但是ESP32-LyraT V4.3还是一直显示有来电呼入，一直振铃，MicroSIP和ESP32-LyraT V4.3均没有任何声音。
以下是ESP32-LyraT V4.3有电话呼入log。
请问哪里的问题？
顺便问问，esp-adf如何设置使用内置的esp-idf？
I (35480) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:775, MEM Total:4255519 Bytes, Inter:241331 Bytes, Dram:202319 Bytes
I (35490) ESP_AUDIO_TASK: It's a decoder
I (35500) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0
I (35510) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804f14,CODEC:0x3f8050a4,FILTER:0x3f8144bc,OUT:0x3f805250
I (35520) AUDIO_PIPELINE: audio_pipeline_unlinked
W (35520) AUDIO_PIPELINE: There are no listener registered
I (35530) ESP_AUDIO_TASK: 2.2 Update all pipeline
I (35530) ESP_AUDIO_TASK: 2.3 Linked new pipeline
I (35540) AUDIO_PIPELINE: link el->rb, el:0x3f804f14, tag:IN_flash, rb:0x3f814aa8
I (35550) AUDIO_PIPELINE: link el->rb, el:0x3f8050a4, tag:DEC_mp3, rb:0x3f81480c
I (35560) AUDIO_PIPELINE: link el->rb, el:0x3f8144bc, tag:Audio_forge, rb:0x3f814848
I (35560) ESP_AUDIO_TASK: 3. Previous starting...
I (35570) AUDIO_ELEMENT: [IN_flash-0x3f804f14] Element task created
I (35580) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1
I (35580) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory
I (35580) TONE_PARTITION: tone partition format 0, total 21
I (35590) AUDIO_ELEMENT: [DEC_mp3-0x3f8050a4] Element task created
I (35600) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8
I (35600) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1
I (35620) MP3_DECODER: MP3 opened
I (35620) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO
I (35630) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (35640) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (35720) ESP_AUDIO_TASK: Received muisc info then on play
I (35720) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0
I (35720) AUDIO_THREAD: The Audio_forge task allocate stack on external memory
I (35730) AUDIO_ELEMENT: [Audio_forge-0x3f8144bc] Element task created
I (35740) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1
I (35740) AUDIO_FORGE: audio_forge opened
I (35740) AUDIO_THREAD: The OUT_iis task allocate stack on external memory
I (35760) AUDIO_ELEMENT: [OUT_iis-0x3f805250] Element task created
I (35760) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1
I (3570) I2S_STREAM: AUDIO_STREAM_WRITER
I (35770) ESP_AUDIO_CTRL: Exit play procedure, ret:0
I (35780) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (35780) AUDIO_FORGE: audio_forge reopen
I (35790) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (35810) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0
I (35820) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:3873251 Bytes, Inter:210687 Bytes, Dram:171675 Bytes
I (36480) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (36480) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (36480) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
W (37400) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018
I (37400) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0
I (37400) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (37480) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (37480) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (37480) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (38290) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2
I (38480) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (38480) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (38480) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (38490) MP3_DECODER: Closed
I (38490) ESP_AUDIO_TASK: Received last pos: 36018 bytes
I (38490) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (38600) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2
I (38600) AUDIO_FORGE: audio forge closed
I (38600) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (38890) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2
I (39110) ESP_AUDIO_TASK: Received last time: 3040 ms
I (39110) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (39120) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0
I (39130) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:4227979 Bytes, Inter:236863 Bytes, Dram:197851 Bytes
W (39140) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED
W (39150) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED
I (39480) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (39480) ESP_AUDIO_CTRL: Enter play procedure, src:0
I (39480) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0
I (39480) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffc3ad4
I (39490) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:775, MEM Total:4255507 Bytes, Inter:241331 Bytes, Dram:202319 Bytes
I (39500) ESP_AUDIO_TASK: It's a decoder
I (39510) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0
I (39520) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804f14,CODEC:0x3f8050a4,FILTER:0x3f8144bc,OUT:0x3f805250
I (39530) AUDIO_PIPELINE: audio_pipeline_unlinked
W (39530) AUDIO_PIPELINE: There are no listener registered
I (39540) ESP_AUDIO_TASK: 2.2 Update all pipeline
I (39540) ESP_AUDIO_TASK: 2.3 Linked new pipeline
I (39550) AUDIO_PIPELINE: link el->rb, el:0x3f804f14, tag:IN_flash, rb:0x3f814aa8
I (39560) AUDIO_PIPELINE: link el->rb, el:0x3f8050a4, tag:DEC_mp3, rb:0x3f81480c
I (39570) AUDIO_PIPELINE: link el->rb, el:0x3f8144bc, tag:Audio_forge, rb:0x3f814848
I (39570) ESP_AUDIO_TASK: 3. Previous starting...
I (39580) AUDIO_ELEMENT: [IN_flash-0x3f804f14] Element task created
I (39590) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1
I (39590) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory
I (39590) TONE_PARTITION: tone partition format 0, total 21
I (39600) AUDIO_ELEMENT: [DEC_mp3-0x3f8050a4] Element task created
I (39610) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8
I (39610) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1
I (39630) MP3_DECODER: MP3 opened
I (39630) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO
I (39640) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (39650) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (39730) ESP_AUDIO_TASK: Received muisc info then on play
I (39730) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0
I (39730) AUDIO_THREAD: The Audio_forge task allocate stack on external memory
I (39740) AUDIO_ELEMENT: [Audio_forge-0x3f8144bc] Element task created
I (39740) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUM,state:1
I (39750) AUDIO_FORGE: audio_forge opened
I (39750) AUDIO_THREAD: The OUT_iis task allocate stack on external memory
I (39760) AUDIO_ELEMENT: [OUT_iis-0x3f805250] Element task created
I (39770) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1
I (39780) I2S_STREAM: AUDIO_STREAM_WRITER
I (39780) ESP_AUDIO_CTRL: Exit play procedure, ret:0
I (39790) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (39800) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (39810) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0
I (39820) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:3873251 Bytes, Inter:210687 Bytes, Dram:171675 Bytes
I (40240) VOIP_EXAMPLE: [ * ] [Play] input key event
I (40240) ESP_AUDIO_CTRL: Enter media_ctrl_stop procedure, sc:0, type:0, st:RUNNING, phase:4
I (4040) ESP_AUDIO_CTRL: media_ctrl_stop, type:0, st:RUNNING, block_playing:1, MEDIA_CTRL_PHASE_WAIT_RUNNING
I (40260) ESP_AUDIO_CTRL: media_ctrl_stop, src:0, type:0, st:RUNNING, phase:4
I (40260) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:2, Available:5, que:0x3ffc3ad4
I (40270) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP, status:RUNNING
W (40270) AUDIO_ELEMENT: OUT-[DEC_mp3] AEL_IO_ABORT
W (40280) MP3_DECODER: outut aborted -3
I (40280) MP3_DECODER: Closed
W (40290) AUDIELEMENT: OUT-[Audio_forge] AEL_IO_ABORT
I (40290) AUDIO_FORGE: audio forge closed
W (40300) AUDIO_ELEMENT: OUT-[IN_flash] AEL_IO_ABORT
I (40410) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP Done, dat:0
I (40420) ESP_AUDIO_TASK: Received last pos: 11233 bytes
I (40420) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (40430) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (40440) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (40450) ESP_AUDIO_TASK: Received last time: 448 ms
I (40460) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (40470) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_STOPPED, 0, src:0, is_stopping:1
I (40480) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:4227975 Bytes, Inter:236863 Bytes, Dram:197851 Bytes
I (40490) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (40490) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (40500) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
W (40510) ESP_AUDIO_TASK: Destroy the old pipeline, STOPPED
W (40510) ESP_AUDIO_TASK: The old pipeline destroyed, STOPPED
I (40520) ESP_AUDIO_TASK: Release the seeking process due to stopping
I (40520) ESP_AUDIO_CTRL: Exit media_ctrl_stop procedure, ret:0
I (40530) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (40610) SIP: [1970-01-01/00:00:19]=======WRITE 0603 bytes>>
I (40610) SIP:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.2.55;branch=z9hG4bKta5N5ac0g6y2D
Contact: <sip:1001@192.168.2.99:64152>
From: "Extension 1000" <sip:1000@192.168.2.55>;tag=F6XUN4QyeN9Br
To: <sip:1001@192.168.2.99:64152;transport=TCP>;tag=1275716600
Call-ID: 39df6c7f-22eb-123c-3f96-11167911e9b9
CSeq: 63409426 INVITE
Server: ESP32 SIP/2.0
Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO
Content-Length: 155
Content-Type: application/sdp
v=0
o=Z 0 3 IN IP4 192.168.2.99
s=Z
c=IN IP4 192.168.2.99
t=0 0
m=audio 35224 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
I (40660) SIP: [1970-01-01/00:00:19]=======================>>
I (41570) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (41570) ESP_AUDIO_CTRL: Enter play procedure, src:0
I (41570) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0
I (41570) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffc3ad4
I (41580) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:775, MEM Total:4255507 Bytes, Inter:241331 Bytes, Dram:202319 Bytes
I (41590) ESP_AUDIO_TASK: It's a decoder
I (41600) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0
I (41610) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804f14,CODEC:0x3f8050a4,FILTER:0x3f8144bc,OUT:0x3f805250
W (41620) AUDIO_PIPELINE: Without stop, st:1
W (41620) AUDIO_PIPELINE: Without wait stop, st:1
I (41630) AUDIO_PIPELINE: audio_pipeline_unlinked
W (41630) AUDIO_PIPELINE: There are no listener registered
I (41640) ESP_AUDIO_TASK: 2.2 Update all pipeline
I (41640) ESP_AUDIO_TASK: 2.3 Linked new pipeline
I (41650) AUDIO_PIPELINE: link el->rb, el:0x3f804f14, tag:IN_flash, rb:0x3f814aa8
I (41660) AUDIO_PIPELINE: link el->rb, el:0x3f8050a4, tag:DEC_mp3, rb:0x3f81480c
I (41670) AUDIO_PIPELINE: link el->rb, el:0x3f8144bc, tag:Audio_forge, rb:0x3f814848
I (41670) ESP_AUDIO_TASK: 3. Previous starting...
I (41680) AUDIO_ELEMENT: [IN_flash-0x3f804f14] Element task created
I (41690) AUDIO_ELEMENT: [IN_flash] AEL_MSGCMD_RESUME,state:1
I (41690) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory
I (41690) TONE_PARTITION: tone partition format 0, total 21
I (41700) AUDIO_ELEMENT: [DEC_mp3-0x3f8050a4] Element task created
I (41710) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8
I (41710) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1
I (41730) MP3_DECODER: MP3 opened
I (41730) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO
I (41740) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (41750) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (41830) ESP_AUDIO_TASK: Received muisc info then on play
I (41840) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0
I (41840) AUDIO_THREAD: The Audio_forge task allocate stack on external memory
I (41840) AUDIO_ELEMENT: [Audio_forge-0x3f8144bc] Element task created
I (41850) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1
I (41860) AUDIO_FORGE: audio_forge opened
I (41860) AUDIO_THREAD: The OUT_iis task allocate stack on external memory
I (41870) AUDIO_ELEMENT: [OUT_iis-0x3f805250] Element task created
I (41880) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1
I (41880) I2S_STREAM: AUDIO_STREAM_WRITER
I (41880) ESP_AUDIO_CTRL: Exit play procedure, ret:0
I (41890) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (41910) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (41920) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0
I (41930) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:3873251 Bytes, Inter:210687 Bytes, Dram:171675 Bytes
I (42590) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (42590) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (42590) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
W (43520) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018
I (43520) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DON,0
I (43520) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (43590) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (43590) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (43590) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (44410) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2
I (44590) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (44590) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (44590) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (44610) MP3_DECODER: Closed
I (44610) ESP_AUDIO_TASK: Received last pos: 36018 bytes
I (44610) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (44720) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2
I (44720) AUDIO_FORGE: audio forge closed
I (44720) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (45010) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2
I (45230) ESP_AUDIO_TASK: Received last time: 3040 ms
I (45230) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (45240) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0
I (45250) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:4227975 Bytes, Inter:236863 Bytes, Dram:197851 Bytes
W (45260) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED
W (45270) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED
I (45590) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (45590) ESP_AUDIO_CTRL: Enter play procedure, src:0
I (45590) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0
I (45590) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffc3ad4
I (45600) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:775, MEM Total:4255507 Bytes, Inter:241331 Bytes, Dram:202319 Bytes
I (45610) ESP_AUDIO_TASK: It's a decoder
I (45620) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0
I (45630) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804f14,CODEC:0x3f8050a4,FILTER:0x3f8144bc,OUT:0x3f805250
I (45640) AUDIO_PIPELINE: audio_pipeline_unlinked
W (45640) AUDIO_PIPELINE: There are no listener registered
I (45650) ESP_AUDIO_TASK: 2.2 Update all pipeline
I (45650) ESP_AUDIO_TASK: 2.3 Linked new pipeline
I (45660) AUDIO_PIPELINE: link el->rb, el:0x3f804f14, tag:IN_flash, rb:0x3f814aa8
I (45670) AUDIO_PIPELINE: link el->rb, el:0x3f8050a4, tag:DEC_mp3, rb:0x3f81480c
I (45680) AUDIO_PIPELINE: link el->rb, el:0x3f8144bc, tag:Audio_forge, rb:0x3f814848
I (45680) ESP_AUDIO_TASK: 3. Previous starting...
I (45690) AUDIO_ELEMENT: [IN_flash-0x3f804f14] Element task created
I (45700) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1
I (45700) TONE_PARTITION: tone partition format 0, total 21
I (45700) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory
I (45710) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8
I (45720) AUDIO_ELEMENT: [DEC_mp3-0x3f8050a4] Element task created
I (45730) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1
I (45740) MP3_DECODER: MP3 opened
I (45740) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO
I (45750) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (45760) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (45850) ESP_AUDIO_TASK: Received muisc info then on play
I (45850) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0
I (45850) AUDIO_THREAD: The Audio_forge task allocate stack on external memory
I (45860) AUDIO_ELEMENT: [Audio_forge-0x3f8144bc] Element task created
I (45870) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1
I (45870) AUDIO_FORGE: audio_forge opened
I (45870) AUDIO_THREAD: The OUT_iis task allocate stack on external memory
I (45880) AUDIO_ELEMENT: [OUT_iis-0x3f805250] Element task created
I (45890) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1
I (45900) I2S_STREAM: AUDIO_STREAM_WRITER
I (45900) ESP_AUDIO_CTRL: Exit play procedure, ret:0
I (45910) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (45920) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (45930) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0
I (45940) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:3873263 Bytes, Inter:210687 Bytes, Dram:171675 Bytes
I (46610) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (46610) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (46610) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
W (47530) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018
I (47530) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0
I (47530) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (47610) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (47610) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (47610) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (48040) VOIP_EXAMPLE: [ * ] [Play] input key event
I (48040) ESP_AUDIO_CTRL: Enter media_ctrl_stop procedure, src:0, type:0, st:RUNNING, phase:4
I (48050) ESP_AUDIO_CTRL: media_ctrl_stop, type:0, st:RUNNING, block_playing:1, MEDIA_CTRL_PHASE_WAIT_RUNNING
I (48060) ESP_AUDIO_CTRL: media_ctrl_stop, src:0, type:0, st:RUNNING, phase:4
I (48070) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:2, Available:5, que:0x3ffc3ad4
I (48070) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP, status:RUNNING
E (48080) AUDIO_ELEMENT: [IN_flash] Element already stopped
W (4809 AUDIO_ELEMENT: OUT-[DEC_mp3] AEL_I_ABORT
W (48090) MP3_DECODER: output aborted -3
I (48100) MP3_DECODER: Closed
W (48100) AUDIO_ELEMENT: OUT-[Audio_forge] AEL_IO_ABORT
I (48110) AUDIO_FORGE: audio forge closed
I (48200) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP Done, dat:0
I (48200) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (48210) ESP_AUDIO_TASK: Received last pos: 29600 bytes
I (48210) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (48220) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (48230) ESP_AUDIO_TASK: Received last time: 2112 ms
I (48240) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (48250) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_STOPPED, 0, src:0, is_stopping:1
I (48260) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:4227975 Bytes, Inter:236863 Bytes, Dram:197851 Bytes
W (48270) ESP_AUDIO_TASK: Destroy the old pipeline, STOPPED
W (48280) ESP_AUDIO_TASK: The old pipeline destroyed, STOPPED
I (48290) ESP_AUDIO_TASK: Release the seeking process due to stopping
I (48290) ESP_AUDIO_CTRL: Exit media_ctrl_stop procedure, ret:0
I (48290) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (48310) SIP: [1970-01-01/00:00:23]=======WRITE 0603 bytes>>
I (48320) SIP:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.2.55;branch=z9hG4bKta5N5ac0g6y2D
Contact: <sip:1001@192.168.2.99:64152>
From: "Extension 1000" <sip:1000@192.168.2.55>;tag=F6XUN4QyeN9Br
To: <sip:1001@192.168.2.99:64152;transport=TCP>;tag=1794862698
Call-ID: 39df6c7f-22eb-123c-3f96-11167911e9b9
CSeq: 63409426 INVITE
Server: ESP32 SIP/2.0
Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO
Content-Length: 155
Content-Type: application/sdp
v=0
o=Z 0 3 IN IP4 192.168.2.99
s=Z
c=IN IP4 192.168.2.99
t=0 0
m=audio 34200 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
I (48370) SIP: [1970-01-01/00:00:23]=======================>>
I (48680) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (48680) ESP_AUDIO_CTRL: Enter play procedure, src:0
I (48680) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0
I (48680) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffc3ad4
I (48690) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:775, MEM Total:4255507 Bytes, Inter:241331 Bytes, Dram:202319 Bytes
I (48700) ESP_AUDIO_TASK: It's a decoder
I (48710) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0
I (48720) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804f14,CODEC:0x3f8050a4,FILTER:0x3f8144bc,OUT:0x3f805250
W (48730) AUDIO_PIPELINE: Without stop, st:1
W (48730) AUDIO_PIPELINE: Without wait stop, st:1
I (48740) AUDIO_PIPELINE: audio_pipeline_unlinked
W (48740) AUDIO_PIPELINE: There are no listener registered
I (48750) ESP_AUDIO_TASK: 2.2 Update all pipeline
I (48750) ESP_AUDIO_TASK: 2.3 Linked new pipeline
I (48760) AUDIO_PIPELINE: link el->rb, el:0x3f804f14, tag:IN_flash, rb:0x3f814aa8
I (48770) AUDIO_PIPELINE: link el->rb, el:0x3f8050a4, tag:DEC_mp3, rb:0x3f81480c
I (48780) AUDIO_PIPELINE: link el->rb, el:0x3f8144bc, tag:Audio_forge, rb:0x3f814848
I (48780) ESP_AUDIO_TASK: 3. Previous starting...
I (48790) AUDIO_ELEMENT: [IN_flash-0x3f804f14] Element task created
I (48800) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1
I (48800) TONE_PARTITION: tone partition format 0, total 21
I (48800) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory
I (48810) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8
I (48820) AUDIO_ELEMENT: [DEC_mp3-0x3f8050a4] Element task created
I (48830) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1
I (48840) MP3_DECODER: MP3 opened
I (48840) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO
I (48850) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (48860) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (48950) ESP_AUDIO_TASK: Received muisc info then on play
I (48950) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0
I (48950) AUDIO_THREAD: The Audio_forge task allocate stack on external memory
I (48960) AUDIO_ELEMENT: [Audio_forge-0x3f8144bc] Element task created
I (48960) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1
I (48970) AUDIO_FORGE: audio_forge opened
I (48970) AUDIO_THREAD: The OUT_iis task allocate stack on external memory
I (48980) AUDIO_ELEMENT: [OUT_iis-0x3f805250] Element task created
I (48990) AUIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1
I (48990) I2S_STREAM: AUDIO_STREAM_WRITER
I (48990) ESP_AUDIO_CTRL: Exit play procedure, ret:0
I (49010) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (49020) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (49030) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0
I (49040) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:3873251 Bytes, Inter:210687 Bytes, Dram:171675 Bytes
I (49710) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (49710) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (49710) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
W (50640) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018
I (50640) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE0
I (50640) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (50710) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (50710) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (50710) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (51530) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2
I (51710) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (51710) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (51710) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (51720) MP3_DECODER: Closed
I (51720) ESP_AUDIO_TASK: Received last pos: 36018 bytes
I (51720) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (51830) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2
I (51830) AUDIO_FORGE: audio forge closed
I (51830) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (52120) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2
I (52340) ESP_AUDIO_TASK: Received last time: 3040 ms
I (52340) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (52350) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0
I (52360) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:4227975 Bytes, Inter:236863 Bytes, Dram:197851 Bytes
W (52370) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED
W (52380) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED
I (52710) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (52710) ESP_AUDIO_CTRL: Enter play procedure, src:0
I (52710) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0
I (52710) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffc3ad4
I (52720) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:775, MEM Total:4255507 Bytes, Inter:241331 Bytes, Dram:202319 Bytes
I (52730) ESP_AUDIO_TASK: It's a decoder
I (52740) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0
I (52750) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804f14,CODEC:0x3f8050a4,FILTER:0x3f8144bc,OUT:0x3f805250
I (52760) AUDIO_PIPELINE: audio_pipeline_unlinked
W (52760) AUDIO_PIPELINE: There are no listener registered
I (52770) ESP_AUDIO_TASK: 2.2 Update all pipeline
I (52770) ESP_AUDIO_TASK: 2.3 Linked new pipeline
I (52780) AUDIO_PIPELINE: link el->rb, el:0x3f804f14, tag:IN_flash, rb:0x3f814aa8
I (52790) AUDIO_PIPELINE: link el->rb, el:0x3f8050a4, tag:DEC_mp3, rb:0x3f81480c
I (52800) AUDIO_PIPELINE: link el->rb, el:0x3f8144bc, tag:Audio_forge, rb:0x3f814848
I (52800) ESP_AUDIO_TASK: 3. Previous starting...
I (52810) AUDIO_ELEMENT: [IN_flash-0x3f804f14] Element task created
I (52820) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1
I (52820) TONE_PARTITION: tone partition format 0, total 21
I (52830) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8
I (52820) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory
I (52840) AUDIO_ELEMENT: [DEC_mp3-0x3f8050a4] Element task created
I (52850) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1
I (52860) MP3_DECODER: MP3 opened
I (52860) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO
I (52870) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (52880) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (52960) ESP_AUDIO_TASK: Received muisc info then on play
I (52960) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0
I (52970) AUDIO_THREAD: The Audio_forge task allocate stack on external memory
I (52970) AUDIO_ELEMENT: [Audio_forge-0x3f8144bc] Element task created
I (52980) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1
I (52990) AUDIO_FORGE: audio_forge opened
I (52990) AUDIO_THREAD: The OUT_iis task allocate stack on external memory
I (53000) AUDIO_ELEMENT: [OUT_iis-0x3f805250] Element task created
I (53010) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1
I (53010) I2S_STREAM: AUDIO_STREAM_WRITER
I (53010) ESP_AUDIO_CTRL: Exit play procedure, ret:0
I (53020) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (53030) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (53050) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0
I (53060) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:3873251 Bytes, Inter:210687 Bytes, Dram:171675 Bytes
I (53720) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (53720) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (53720) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
W (54650) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018
I (54650) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0
I (54650) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (54720) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (54720) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (54720) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (55550) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2
I (55720) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (55720) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (55720) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (55740) MP3_DECODER: Closed
I (55740) ESP_AUDIO_TASK: Received last pos: 36018 bytes
I (55740) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (55850) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2
I (55850) AUDIO_FORGE: audio forge closed
I (55850) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (56130) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2
I (56190) ESP_AUDIO_CTRL: Enter media_ctrl_stop procedure, src:0, type:0, st:RUNNING, phase:4
I (56190) ESP_AUDIOTRL: media_ctrl_stop, type:0, st:RUNNING, block_playng:1, MEDIA_CTRL_PHASE_WAIT_RUNNING
I (56190) ESP_AUDIO_CTRL: media_ctrl_stop, src:0, type:0, st:RUNNING,phase:4
I (56200) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:2, Available:5, que:0x3ffc3ad4
I (56210) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP, status:RUNNING
E (56220) AUDIO_ELEMENT: [IN_flash] Element already stopped
E (56220) AUDIO_ELEMENT: [DEC_mp3] Element already stopped
E (56230) AUDIO_ELEMENT: [Audio_forge] Element already stopped
I (56360) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP Done, dat:0
I (56360) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
W (56370) AUDIO_PIPELINE: Check AEL STOPPED, pl:0x3f803db0, el:0x3f8050a4, tag: DEC_mp3, state:15, wanted:14
W (56380) AUDIO_PIPELINE: Check AEL STOPPED, pl:0x3f803db0, el:0x3f8144bc, tag: Audio_forge, state:15, wanted:14
I (56390) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
W (56400) AUDIO_PIPELINE: Check AEL STOPPED, pl:0x3f803db0, el:0x3f8144bc, tag: Audio_forge, state:15, wanted:14
I (56410) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (56420) ESP_AUDIO_TASK: Received last time: 3040 ms
I (56430) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
W (56440) AUDIO_PIPELINE: Check AEL FINISHED, pl:0x3f803db0, el:0x3f804f14, tag: IN_flash, state:14, wanted:15
W (56450) AUDIO_PIPELINE: Check AEL FINISHED, pl:0x3f803db0, el:0x3f8050a4, tag: DEC_mp3, state:14, wanted:15
W (56460) AUDIO_PIPELINE: Check AEL FINISHED, pl:0x3f803db0, el:0x3f8144bc, tag: Audio_forge, state:14, wanted:15
I (56470) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:1
I (56480) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:4227975 Bytes, Inter:236863 Bytes, Dram:197851 Bytes
W (56490) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED
W (56500) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED
I (56510) ESP_AUDIO_TASK: Release the seeking process due to stopping
I (56510) ESP_AUDIO_CTRL: Exit media_ctrl_stop procedure, ret:0
I (56510) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (56530) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
W (56620) SIP: Deny the call
W (56620) SIP: CHANGE STATE FROM 16, TO 2, :func: _sip_uas_process_res_invite:1007
I (56620) VOIP_EXAMPLE: SIP_EVENT_HANGUP
I (56620) SIP: [1970-01-01/00:00:27]=======WRITE 0421 bytes>>
I (56630) SIP:
SIP/2.0 486 Busy Here
Via: SIP/2.0/TCP 192.168.2.55;branch=z9hG4bKta5N5ac0g6y2D
Contact: <sip:1001@192.168.2.99:64152>
From: "Extension 1000" <sip:1000@192.168.2.55>;tag=F6XUN4QyeN9Br
To: <sip:1001@192.168.2.99:64152;transport=TCP>;tag=-59658848
Call-ID: 39df6c7f-22eb-123c-3f96-11167911e9b9
CSeq: 63409426 INVITE
Server: ESP32 SIP/2.0
Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO
Content-Length: 0
I (56670) SIP: [1970-01-01/00:00:27]=======================>>
I (56690) SIP: [1970-01-01/00:00:27]<<=====READ 0349 bytes==
I (56690) SIP:
ACK sip:1001@192.168.2.99:64152;transport=TCP SIP/2.0
Via: SIP/2.0/TCP 192.168.2.55;branch=z9hG4bKta5N5ac0g6y2D
Max-Forwards: 69
From: "Extension 1000" <sip:1000@192.168.2.55>;tag=F6XUN4QyeN9Br
To: <sip:1001@192.168.2.99:64152;transport=TCP>;tag=-59658848
Call-ID: 39df6c7f-22eb-123c-3f96-11167911e9b9
CSeq: 63409426 ACK
Content-Length: 0
I (56720) SIP: [1970-01-01/00:00:27]<<======================
esp-idf-v4.4.3
ESP32-LyraT V4.3
Freeswitch 服务器
MicroSIP客户端
如果是ESP32-LyraT V4.3呼出，MicroSIP接听，可以正常接通并通话
但如果是MicroSIP呼出，ESP32-LyraT V4.3可以收到电话呼入，也有振铃响声，但是按play键后，MicroSIP显示已接通，但是ESP32-LyraT V4.3还是一直显示有来电呼入，一直振铃，MicroSIP和ESP32-LyraT V4.3均没有任何声音。
以下是ESP32-LyraT V4.3有电话呼入log。
请问哪里的问题？
顺便问问，esp-adf如何设置使用内置的esp-idf？
I (35480) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:775, MEM Total:4255519 Bytes, Inter:241331 Bytes, Dram:202319 Bytes
I (35490) ESP_AUDIO_TASK: It's a decoder
I (35500) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0
I (35510) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804f14,CODEC:0x3f8050a4,FILTER:0x3f8144bc,OUT:0x3f805250
I (35520) AUDIO_PIPELINE: audio_pipeline_unlinked
W (35520) AUDIO_PIPELINE: There are no listener registered
I (35530) ESP_AUDIO_TASK: 2.2 Update all pipeline
I (35530) ESP_AUDIO_TASK: 2.3 Linked new pipeline
I (35540) AUDIO_PIPELINE: link el->rb, el:0x3f804f14, tag:IN_flash, rb:0x3f814aa8
I (35550) AUDIO_PIPELINE: link el->rb, el:0x3f8050a4, tag:DEC_mp3, rb:0x3f81480c
I (35560) AUDIO_PIPELINE: link el->rb, el:0x3f8144bc, tag:Audio_forge, rb:0x3f814848
I (35560) ESP_AUDIO_TASK: 3. Previous starting...
I (35570) AUDIO_ELEMENT: [IN_flash-0x3f804f14] Element task created
I (35580) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1
I (35580) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory
I (35580) TONE_PARTITION: tone partition format 0, total 21
I (35590) AUDIO_ELEMENT: [DEC_mp3-0x3f8050a4] Element task created
I (35600) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8
I (35600) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1
I (35620) MP3_DECODER: MP3 opened
I (35620) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO
I (35630) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (35640) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (35720) ESP_AUDIO_TASK: Received muisc info then on play
I (35720) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0
I (35720) AUDIO_THREAD: The Audio_forge task allocate stack on external memory
I (35730) AUDIO_ELEMENT: [Audio_forge-0x3f8144bc] Element task created
I (35740) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1
I (35740) AUDIO_FORGE: audio_forge opened
I (35740) AUDIO_THREAD: The OUT_iis task allocate stack on external memory
I (35760) AUDIO_ELEMENT: [OUT_iis-0x3f805250] Element task created
I (35760) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1
I (3570) I2S_STREAM: AUDIO_STREAM_WRITER
I (35770) ESP_AUDIO_CTRL: Exit play procedure, ret:0
I (35780) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (35780) AUDIO_FORGE: audio_forge reopen
I (35790) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (35810) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0
I (35820) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:3873251 Bytes, Inter:210687 Bytes, Dram:171675 Bytes
I (36480) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (36480) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (36480) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
W (37400) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018
I (37400) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0
I (37400) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (37480) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (37480) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (37480) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (38290) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2
I (38480) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (38480) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (38480) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (38490) MP3_DECODER: Closed
I (38490) ESP_AUDIO_TASK: Received last pos: 36018 bytes
I (38490) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (38600) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2
I (38600) AUDIO_FORGE: audio forge closed
I (38600) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (38890) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2
I (39110) ESP_AUDIO_TASK: Received last time: 3040 ms
I (39110) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (39120) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0
I (39130) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:4227979 Bytes, Inter:236863 Bytes, Dram:197851 Bytes
W (39140) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED
W (39150) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED
I (39480) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (39480) ESP_AUDIO_CTRL: Enter play procedure, src:0
I (39480) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0
I (39480) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffc3ad4
I (39490) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:775, MEM Total:4255507 Bytes, Inter:241331 Bytes, Dram:202319 Bytes
I (39500) ESP_AUDIO_TASK: It's a decoder
I (39510) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0
I (39520) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804f14,CODEC:0x3f8050a4,FILTER:0x3f8144bc,OUT:0x3f805250
I (39530) AUDIO_PIPELINE: audio_pipeline_unlinked
W (39530) AUDIO_PIPELINE: There are no listener registered
I (39540) ESP_AUDIO_TASK: 2.2 Update all pipeline
I (39540) ESP_AUDIO_TASK: 2.3 Linked new pipeline
I (39550) AUDIO_PIPELINE: link el->rb, el:0x3f804f14, tag:IN_flash, rb:0x3f814aa8
I (39560) AUDIO_PIPELINE: link el->rb, el:0x3f8050a4, tag:DEC_mp3, rb:0x3f81480c
I (39570) AUDIO_PIPELINE: link el->rb, el:0x3f8144bc, tag:Audio_forge, rb:0x3f814848
I (39570) ESP_AUDIO_TASK: 3. Previous starting...
I (39580) AUDIO_ELEMENT: [IN_flash-0x3f804f14] Element task created
I (39590) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1
I (39590) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory
I (39590) TONE_PARTITION: tone partition format 0, total 21
I (39600) AUDIO_ELEMENT: [DEC_mp3-0x3f8050a4] Element task created
I (39610) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8
I (39610) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1
I (39630) MP3_DECODER: MP3 opened
I (39630) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO
I (39640) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (39650) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (39730) ESP_AUDIO_TASK: Received muisc info then on play
I (39730) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0
I (39730) AUDIO_THREAD: The Audio_forge task allocate stack on external memory
I (39740) AUDIO_ELEMENT: [Audio_forge-0x3f8144bc] Element task created
I (39740) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUM,state:1
I (39750) AUDIO_FORGE: audio_forge opened
I (39750) AUDIO_THREAD: The OUT_iis task allocate stack on external memory
I (39760) AUDIO_ELEMENT: [OUT_iis-0x3f805250] Element task created
I (39770) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1
I (39780) I2S_STREAM: AUDIO_STREAM_WRITER
I (39780) ESP_AUDIO_CTRL: Exit play procedure, ret:0
I (39790) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (39800) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (39810) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0
I (39820) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:3873251 Bytes, Inter:210687 Bytes, Dram:171675 Bytes
I (40240) VOIP_EXAMPLE: [ * ] [Play] input key event
I (40240) ESP_AUDIO_CTRL: Enter media_ctrl_stop procedure, sc:0, type:0, st:RUNNING, phase:4
I (4040) ESP_AUDIO_CTRL: media_ctrl_stop, type:0, st:RUNNING, block_playing:1, MEDIA_CTRL_PHASE_WAIT_RUNNING
I (40260) ESP_AUDIO_CTRL: media_ctrl_stop, src:0, type:0, st:RUNNING, phase:4
I (40260) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:2, Available:5, que:0x3ffc3ad4
I (40270) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP, status:RUNNING
W (40270) AUDIO_ELEMENT: OUT-[DEC_mp3] AEL_IO_ABORT
W (40280) MP3_DECODER: outut aborted -3
I (40280) MP3_DECODER: Closed
W (40290) AUDIELEMENT: OUT-[Audio_forge] AEL_IO_ABORT
I (40290) AUDIO_FORGE: audio forge closed
W (40300) AUDIO_ELEMENT: OUT-[IN_flash] AEL_IO_ABORT
I (40410) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP Done, dat:0
I (40420) ESP_AUDIO_TASK: Received last pos: 11233 bytes
I (40420) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (40430) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (40440) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (40450) ESP_AUDIO_TASK: Received last time: 448 ms
I (40460) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (40470) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_STOPPED, 0, src:0, is_stopping:1
I (40480) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:4227975 Bytes, Inter:236863 Bytes, Dram:197851 Bytes
I (40490) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (40490) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (40500) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
W (40510) ESP_AUDIO_TASK: Destroy the old pipeline, STOPPED
W (40510) ESP_AUDIO_TASK: The old pipeline destroyed, STOPPED
I (40520) ESP_AUDIO_TASK: Release the seeking process due to stopping
I (40520) ESP_AUDIO_CTRL: Exit media_ctrl_stop procedure, ret:0
I (40530) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (40610) SIP: [1970-01-01/00:00:19]=======WRITE 0603 bytes>>
I (40610) SIP:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.2.55;branch=z9hG4bKta5N5ac0g6y2D
Contact: <sip:1001@192.168.2.99:64152>
From: "Extension 1000" <sip:1000@192.168.2.55>;tag=F6XUN4QyeN9Br
To: <sip:1001@192.168.2.99:64152;transport=TCP>;tag=1275716600
Call-ID: 39df6c7f-22eb-123c-3f96-11167911e9b9
CSeq: 63409426 INVITE
Server: ESP32 SIP/2.0
Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO
Content-Length: 155
Content-Type: application/sdp
v=0
o=Z 0 3 IN IP4 192.168.2.99
s=Z
c=IN IP4 192.168.2.99
t=0 0
m=audio 35224 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
I (40660) SIP: [1970-01-01/00:00:19]=======================>>
I (41570) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (41570) ESP_AUDIO_CTRL: Enter play procedure, src:0
I (41570) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0
I (41570) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffc3ad4
I (41580) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:775, MEM Total:4255507 Bytes, Inter:241331 Bytes, Dram:202319 Bytes
I (41590) ESP_AUDIO_TASK: It's a decoder
I (41600) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0
I (41610) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804f14,CODEC:0x3f8050a4,FILTER:0x3f8144bc,OUT:0x3f805250
W (41620) AUDIO_PIPELINE: Without stop, st:1
W (41620) AUDIO_PIPELINE: Without wait stop, st:1
I (41630) AUDIO_PIPELINE: audio_pipeline_unlinked
W (41630) AUDIO_PIPELINE: There are no listener registered
I (41640) ESP_AUDIO_TASK: 2.2 Update all pipeline
I (41640) ESP_AUDIO_TASK: 2.3 Linked new pipeline
I (41650) AUDIO_PIPELINE: link el->rb, el:0x3f804f14, tag:IN_flash, rb:0x3f814aa8
I (41660) AUDIO_PIPELINE: link el->rb, el:0x3f8050a4, tag:DEC_mp3, rb:0x3f81480c
I (41670) AUDIO_PIPELINE: link el->rb, el:0x3f8144bc, tag:Audio_forge, rb:0x3f814848
I (41670) ESP_AUDIO_TASK: 3. Previous starting...
I (41680) AUDIO_ELEMENT: [IN_flash-0x3f804f14] Element task created
I (41690) AUDIO_ELEMENT: [IN_flash] AEL_MSGCMD_RESUME,state:1
I (41690) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory
I (41690) TONE_PARTITION: tone partition format 0, total 21
I (41700) AUDIO_ELEMENT: [DEC_mp3-0x3f8050a4] Element task created
I (41710) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8
I (41710) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1
I (41730) MP3_DECODER: MP3 opened
I (41730) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO
I (41740) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (41750) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (41830) ESP_AUDIO_TASK: Received muisc info then on play
I (41840) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0
I (41840) AUDIO_THREAD: The Audio_forge task allocate stack on external memory
I (41840) AUDIO_ELEMENT: [Audio_forge-0x3f8144bc] Element task created
I (41850) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1
I (41860) AUDIO_FORGE: audio_forge opened
I (41860) AUDIO_THREAD: The OUT_iis task allocate stack on external memory
I (41870) AUDIO_ELEMENT: [OUT_iis-0x3f805250] Element task created
I (41880) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1
I (41880) I2S_STREAM: AUDIO_STREAM_WRITER
I (41880) ESP_AUDIO_CTRL: Exit play procedure, ret:0
I (41890) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (41910) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (41920) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0
I (41930) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:3873251 Bytes, Inter:210687 Bytes, Dram:171675 Bytes
I (42590) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (42590) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (42590) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
W (43520) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018
I (43520) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DON,0
I (43520) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (43590) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (43590) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (43590) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (44410) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2
I (44590) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (44590) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (44590) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (44610) MP3_DECODER: Closed
I (44610) ESP_AUDIO_TASK: Received last pos: 36018 bytes
I (44610) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (44720) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2
I (44720) AUDIO_FORGE: audio forge closed
I (44720) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (45010) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2
I (45230) ESP_AUDIO_TASK: Received last time: 3040 ms
I (45230) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (45240) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0
I (45250) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:4227975 Bytes, Inter:236863 Bytes, Dram:197851 Bytes
W (45260) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED
W (45270) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED
I (45590) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (45590) ESP_AUDIO_CTRL: Enter play procedure, src:0
I (45590) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0
I (45590) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffc3ad4
I (45600) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:775, MEM Total:4255507 Bytes, Inter:241331 Bytes, Dram:202319 Bytes
I (45610) ESP_AUDIO_TASK: It's a decoder
I (45620) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0
I (45630) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804f14,CODEC:0x3f8050a4,FILTER:0x3f8144bc,OUT:0x3f805250
I (45640) AUDIO_PIPELINE: audio_pipeline_unlinked
W (45640) AUDIO_PIPELINE: There are no listener registered
I (45650) ESP_AUDIO_TASK: 2.2 Update all pipeline
I (45650) ESP_AUDIO_TASK: 2.3 Linked new pipeline
I (45660) AUDIO_PIPELINE: link el->rb, el:0x3f804f14, tag:IN_flash, rb:0x3f814aa8
I (45670) AUDIO_PIPELINE: link el->rb, el:0x3f8050a4, tag:DEC_mp3, rb:0x3f81480c
I (45680) AUDIO_PIPELINE: link el->rb, el:0x3f8144bc, tag:Audio_forge, rb:0x3f814848
I (45680) ESP_AUDIO_TASK: 3. Previous starting...
I (45690) AUDIO_ELEMENT: [IN_flash-0x3f804f14] Element task created
I (45700) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1
I (45700) TONE_PARTITION: tone partition format 0, total 21
I (45700) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory
I (45710) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8
I (45720) AUDIO_ELEMENT: [DEC_mp3-0x3f8050a4] Element task created
I (45730) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1
I (45740) MP3_DECODER: MP3 opened
I (45740) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO
I (45750) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (45760) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (45850) ESP_AUDIO_TASK: Received muisc info then on play
I (45850) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0
I (45850) AUDIO_THREAD: The Audio_forge task allocate stack on external memory
I (45860) AUDIO_ELEMENT: [Audio_forge-0x3f8144bc] Element task created
I (45870) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1
I (45870) AUDIO_FORGE: audio_forge opened
I (45870) AUDIO_THREAD: The OUT_iis task allocate stack on external memory
I (45880) AUDIO_ELEMENT: [OUT_iis-0x3f805250] Element task created
I (45890) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1
I (45900) I2S_STREAM: AUDIO_STREAM_WRITER
I (45900) ESP_AUDIO_CTRL: Exit play procedure, ret:0
I (45910) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (45920) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (45930) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0
I (45940) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:3873263 Bytes, Inter:210687 Bytes, Dram:171675 Bytes
I (46610) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (46610) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (46610) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
W (47530) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018
I (47530) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0
I (47530) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (47610) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (47610) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (47610) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (48040) VOIP_EXAMPLE: [ * ] [Play] input key event
I (48040) ESP_AUDIO_CTRL: Enter media_ctrl_stop procedure, src:0, type:0, st:RUNNING, phase:4
I (48050) ESP_AUDIO_CTRL: media_ctrl_stop, type:0, st:RUNNING, block_playing:1, MEDIA_CTRL_PHASE_WAIT_RUNNING
I (48060) ESP_AUDIO_CTRL: media_ctrl_stop, src:0, type:0, st:RUNNING, phase:4
I (48070) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:2, Available:5, que:0x3ffc3ad4
I (48070) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP, status:RUNNING
E (48080) AUDIO_ELEMENT: [IN_flash] Element already stopped
W (4809 AUDIO_ELEMENT: OUT-[DEC_mp3] AEL_I_ABORT
W (48090) MP3_DECODER: output aborted -3
I (48100) MP3_DECODER: Closed
W (48100) AUDIO_ELEMENT: OUT-[Audio_forge] AEL_IO_ABORT
I (48110) AUDIO_FORGE: audio forge closed
I (48200) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP Done, dat:0
I (48200) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (48210) ESP_AUDIO_TASK: Received last pos: 29600 bytes
I (48210) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (48220) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (48230) ESP_AUDIO_TASK: Received last time: 2112 ms
I (48240) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (48250) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_STOPPED, 0, src:0, is_stopping:1
I (48260) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:4227975 Bytes, Inter:236863 Bytes, Dram:197851 Bytes
W (48270) ESP_AUDIO_TASK: Destroy the old pipeline, STOPPED
W (48280) ESP_AUDIO_TASK: The old pipeline destroyed, STOPPED
I (48290) ESP_AUDIO_TASK: Release the seeking process due to stopping
I (48290) ESP_AUDIO_CTRL: Exit media_ctrl_stop procedure, ret:0
I (48290) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (48310) SIP: [1970-01-01/00:00:23]=======WRITE 0603 bytes>>
I (48320) SIP:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.2.55;branch=z9hG4bKta5N5ac0g6y2D
Contact: <sip:1001@192.168.2.99:64152>
From: "Extension 1000" <sip:1000@192.168.2.55>;tag=F6XUN4QyeN9Br
To: <sip:1001@192.168.2.99:64152;transport=TCP>;tag=1794862698
Call-ID: 39df6c7f-22eb-123c-3f96-11167911e9b9
CSeq: 63409426 INVITE
Server: ESP32 SIP/2.0
Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO
Content-Length: 155
Content-Type: application/sdp
v=0
o=Z 0 3 IN IP4 192.168.2.99
s=Z
c=IN IP4 192.168.2.99
t=0 0
m=audio 34200 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
I (48370) SIP: [1970-01-01/00:00:23]=======================>>
I (48680) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (48680) ESP_AUDIO_CTRL: Enter play procedure, src:0
I (48680) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0
I (48680) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffc3ad4
I (48690) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:775, MEM Total:4255507 Bytes, Inter:241331 Bytes, Dram:202319 Bytes
I (48700) ESP_AUDIO_TASK: It's a decoder
I (48710) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0
I (48720) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804f14,CODEC:0x3f8050a4,FILTER:0x3f8144bc,OUT:0x3f805250
W (48730) AUDIO_PIPELINE: Without stop, st:1
W (48730) AUDIO_PIPELINE: Without wait stop, st:1
I (48740) AUDIO_PIPELINE: audio_pipeline_unlinked
W (48740) AUDIO_PIPELINE: There are no listener registered
I (48750) ESP_AUDIO_TASK: 2.2 Update all pipeline
I (48750) ESP_AUDIO_TASK: 2.3 Linked new pipeline
I (48760) AUDIO_PIPELINE: link el->rb, el:0x3f804f14, tag:IN_flash, rb:0x3f814aa8
I (48770) AUDIO_PIPELINE: link el->rb, el:0x3f8050a4, tag:DEC_mp3, rb:0x3f81480c
I (48780) AUDIO_PIPELINE: link el->rb, el:0x3f8144bc, tag:Audio_forge, rb:0x3f814848
I (48780) ESP_AUDIO_TASK: 3. Previous starting...
I (48790) AUDIO_ELEMENT: [IN_flash-0x3f804f14] Element task created
I (48800) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1
I (48800) TONE_PARTITION: tone partition format 0, total 21
I (48800) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory
I (48810) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8
I (48820) AUDIO_ELEMENT: [DEC_mp3-0x3f8050a4] Element task created
I (48830) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1
I (48840) MP3_DECODER: MP3 opened
I (48840) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO
I (48850) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (48860) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (48950) ESP_AUDIO_TASK: Received muisc info then on play
I (48950) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0
I (48950) AUDIO_THREAD: The Audio_forge task allocate stack on external memory
I (48960) AUDIO_ELEMENT: [Audio_forge-0x3f8144bc] Element task created
I (48960) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1
I (48970) AUDIO_FORGE: audio_forge opened
I (48970) AUDIO_THREAD: The OUT_iis task allocate stack on external memory
I (48980) AUDIO_ELEMENT: [OUT_iis-0x3f805250] Element task created
I (48990) AUIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1
I (48990) I2S_STREAM: AUDIO_STREAM_WRITER
I (48990) ESP_AUDIO_CTRL: Exit play procedure, ret:0
I (49010) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (49020) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (49030) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0
I (49040) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:3873251 Bytes, Inter:210687 Bytes, Dram:171675 Bytes
I (49710) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (49710) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (49710) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
W (50640) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018
I (50640) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE0
I (50640) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (50710) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (50710) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (50710) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (51530) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2
I (51710) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (51710) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (51710) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (51720) MP3_DECODER: Closed
I (51720) ESP_AUDIO_TASK: Received last pos: 36018 bytes
I (51720) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (51830) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2
I (51830) AUDIO_FORGE: audio forge closed
I (51830) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (52120) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2
I (52340) ESP_AUDIO_TASK: Received last time: 3040 ms
I (52340) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (52350) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0
I (52360) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:4227975 Bytes, Inter:236863 Bytes, Dram:197851 Bytes
W (52370) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED
W (52380) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED
I (52710) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (52710) ESP_AUDIO_CTRL: Enter play procedure, src:0
I (52710) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0
I (52710) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffc3ad4
I (52720) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:775, MEM Total:4255507 Bytes, Inter:241331 Bytes, Dram:202319 Bytes
I (52730) ESP_AUDIO_TASK: It's a decoder
I (52740) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0
I (52750) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804f14,CODEC:0x3f8050a4,FILTER:0x3f8144bc,OUT:0x3f805250
I (52760) AUDIO_PIPELINE: audio_pipeline_unlinked
W (52760) AUDIO_PIPELINE: There are no listener registered
I (52770) ESP_AUDIO_TASK: 2.2 Update all pipeline
I (52770) ESP_AUDIO_TASK: 2.3 Linked new pipeline
I (52780) AUDIO_PIPELINE: link el->rb, el:0x3f804f14, tag:IN_flash, rb:0x3f814aa8
I (52790) AUDIO_PIPELINE: link el->rb, el:0x3f8050a4, tag:DEC_mp3, rb:0x3f81480c
I (52800) AUDIO_PIPELINE: link el->rb, el:0x3f8144bc, tag:Audio_forge, rb:0x3f814848
I (52800) ESP_AUDIO_TASK: 3. Previous starting...
I (52810) AUDIO_ELEMENT: [IN_flash-0x3f804f14] Element task created
I (52820) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1
I (52820) TONE_PARTITION: tone partition format 0, total 21
I (52830) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8
I (52820) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory
I (52840) AUDIO_ELEMENT: [DEC_mp3-0x3f8050a4] Element task created
I (52850) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1
I (52860) MP3_DECODER: MP3 opened
I (52860) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO
I (52870) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (52880) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (52960) ESP_AUDIO_TASK: Received muisc info then on play
I (52960) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0
I (52970) AUDIO_THREAD: The Audio_forge task allocate stack on external memory
I (52970) AUDIO_ELEMENT: [Audio_forge-0x3f8144bc] Element task created
I (52980) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1
I (52990) AUDIO_FORGE: audio_forge opened
I (52990) AUDIO_THREAD: The OUT_iis task allocate stack on external memory
I (53000) AUDIO_ELEMENT: [OUT_iis-0x3f805250] Element task created
I (53010) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1
I (53010) I2S_STREAM: AUDIO_STREAM_WRITER
I (53010) ESP_AUDIO_CTRL: Exit play procedure, ret:0
I (53020) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (53030) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING
I (53050) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0
I (53060) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:3873251 Bytes, Inter:210687 Bytes, Dram:171675 Bytes
I (53720) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (53720) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (53720) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
W (54650) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018
I (54650) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0
I (54650) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (54720) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (54720) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (54720) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (55550) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2
I (55720) VOIP_EXAMPLE: ringing... RemotePhoneNum 1000
I (55720) ESP_AUDIO_CTRL: Enter play procedure, src:0
W (55720) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.
I (55740) MP3_DECODER: Closed
I (55740) ESP_AUDIO_TASK: Received last pos: 36018 bytes
I (55740) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (55850) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2
I (55850) AUDIO_FORGE: audio forge closed
I (55850) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
I (56130) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2
I (56190) ESP_AUDIO_CTRL: Enter media_ctrl_stop procedure, src:0, type:0, st:RUNNING, phase:4
I (56190) ESP_AUDIOTRL: media_ctrl_stop, type:0, st:RUNNING, block_playng:1, MEDIA_CTRL_PHASE_WAIT_RUNNING
I (56190) ESP_AUDIO_CTRL: media_ctrl_stop, src:0, type:0, st:RUNNING,phase:4
I (56200) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:2, Available:5, que:0x3ffc3ad4
I (56210) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP, status:RUNNING
E (56220) AUDIO_ELEMENT: [IN_flash] Element already stopped
E (56220) AUDIO_ELEMENT: [DEC_mp3] Element already stopped
E (56230) AUDIO_ELEMENT: [Audio_forge] Element already stopped
I (56360) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP Done, dat:0
I (56360) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
W (56370) AUDIO_PIPELINE: Check AEL STOPPED, pl:0x3f803db0, el:0x3f8050a4, tag: DEC_mp3, state:15, wanted:14
W (56380) AUDIO_PIPELINE: Check AEL STOPPED, pl:0x3f803db0, el:0x3f8144bc, tag: Audio_forge, state:15, wanted:14
I (56390) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f8050a4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
W (56400) AUDIO_PIPELINE: Check AEL STOPPED, pl:0x3f803db0, el:0x3f8144bc, tag: Audio_forge, state:15, wanted:14
I (56410) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f8144bc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (56420) ESP_AUDIO_TASK: Received last time: 3040 ms
I (56430) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED
W (56440) AUDIO_PIPELINE: Check AEL FINISHED, pl:0x3f803db0, el:0x3f804f14, tag: IN_flash, state:14, wanted:15
W (56450) AUDIO_PIPELINE: Check AEL FINISHED, pl:0x3f803db0, el:0x3f8050a4, tag: DEC_mp3, state:14, wanted:15
W (56460) AUDIO_PIPELINE: Check AEL FINISHED, pl:0x3f803db0, el:0x3f8144bc, tag: Audio_forge, state:14, wanted:15
I (56470) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:1
I (56480) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:1030, MEM Total:4227975 Bytes, Inter:236863 Bytes, Dram:197851 Bytes
W (56490) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED
W (56500) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED
I (56510) ESP_AUDIO_TASK: Release the seeking process due to stopping
I (56510) ESP_AUDIO_CTRL: Exit media_ctrl_stop procedure, ret:0
I (56510) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f805250] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
I (56530) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804f14] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED
W (56620) SIP: Deny the call
W (56620) SIP: CHANGE STATE FROM 16, TO 2, :func: _sip_uas_process_res_invite:1007
I (56620) VOIP_EXAMPLE: SIP_EVENT_HANGUP
I (56620) SIP: [1970-01-01/00:00:27]=======WRITE 0421 bytes>>
I (56630) SIP:
SIP/2.0 486 Busy Here
Via: SIP/2.0/TCP 192.168.2.55;branch=z9hG4bKta5N5ac0g6y2D
Contact: <sip:1001@192.168.2.99:64152>
From: "Extension 1000" <sip:1000@192.168.2.55>;tag=F6XUN4QyeN9Br
To: <sip:1001@192.168.2.99:64152;transport=TCP>;tag=-59658848
Call-ID: 39df6c7f-22eb-123c-3f96-11167911e9b9
CSeq: 63409426 INVITE
Server: ESP32 SIP/2.0
Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO
Content-Length: 0
I (56670) SIP: [1970-01-01/00:00:27]=======================>>
I (56690) SIP: [1970-01-01/00:00:27]<<=====READ 0349 bytes==
I (56690) SIP:
ACK sip:1001@192.168.2.99:64152;transport=TCP SIP/2.0
Via: SIP/2.0/TCP 192.168.2.55;branch=z9hG4bKta5N5ac0g6y2D
Max-Forwards: 69
From: "Extension 1000" <sip:1000@192.168.2.55>;tag=F6XUN4QyeN9Br
To: <sip:1001@192.168.2.99:64152;transport=TCP>;tag=-59658848
Call-ID: 39df6c7f-22eb-123c-3f96-11167911e9b9
CSeq: 63409426 ACK
Content-Length: 0
I (56720) SIP: [1970-01-01/00:00:27]<<======================