VoIP register become offline after a while (AUD-3615)

Open davidHuW opened this issue 3 years ago • 4 comments

IDF:V4.4 ADF:Master

rst:0x1 (POWERON_RESET),boot:0x1f (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:1 load:0x3fff0030,len:6948 load:0x40078000,len:15512 load:0x40080400,len:4492 entry 0x400806b4 [0;32mI (27) boot: ESP-IDF v4.4-157-gc7b9f94-dirty 2nd stage bootloader[0m [0;32mI (27) boot: compile time 10:52:39[0m [0;32mI (27) boot: chip revision: 3[0m [0;32mI (32) boot_comm: chip revision: 3, min. bootloader chip revision: 0[0m [0;32mI (39) qio_mode: Enabling default flash chip QIO[0m [0;32mI (44) boot.esp32: SPI Speed : 80MHz[0m [0;32mI (49) boot.esp32: SPI Mode : QIO[0m [0;32mI (53) boot.esp32: SPI Flash Size : 8MB[0m [0;32mI (58) boot: Enabling RNG early entropy source...[0m [0;32mI (63) boot: Partition Table:[0m [0;32mI (67) boot: ## Label Usage Type ST Offset Length[0m [0;32mI (74) boot: 0 nvs WiFi data 01 02 00009000 00006000[0m [0;32mI (81) boot: 1 phy_init RF data 01 01 0000f000 00001000[0m [0;32mI (89) boot: 2 factory factory app 00 00 00010000 00200000[0m [0;32mI (96) boot: 3 flash_tone Unknown data 01 ff 00210000 0007d000[0m [0;32mI (104) boot: End of partition table[0m [0;32mI (108) boot_comm: chip revision: 3, min. application chip revision: 0[0m [0;32mI (115) esp_image: segment 0: paddr=00010020 vaddr=3f400020 size=30ad8h (199384) map[0m [0;32mI (179) esp_image: segment 1: paddr=00040b00 vaddr=3ffb0000 size=03b10h ( 15120) load[0m [0;32mI (184) esp_image: segment 2: paddr=00044618 vaddr=40080000 size=0ba00h ( 47616) load[0m [0;32mI (200) esp_image: segment 3: paddr=00050020 vaddr=400d0020 size=d48e4h (870628) map[0m [0;32mI (440) esp_image: segment 4: paddr=0012490c vaddr=4008ba00 size=0b704h ( 46852) load[0m [0;32mI (456) esp_image: segment 5: paddr=00130018 vaddr=50000000 size=00010h ( 16) load[0m [0;32mI (467) boot: Loaded app from partition at offset 0x10000[0m [0;32mI (467) boot: Disabling RNG early entropy source...[0m [0;32mI (479) psram: This chip is ESP32-D0WD[0m [0;32mI (479) spiram: Found 64MBit SPI RAM device[0m [0;32mI (480) spiram: SPI RAM mode: flash 80m sram 80m[0m [0;32mI (482) spiram: PSRAM initialized, cache is in low/high (2-core) mode.[0m [0;32mI (489) cpu_start: Pro cpu up.[0m [0;32mI (493) cpu_start: Starting app cpu, entry point is 0x400816c8[0m [0;32mI (0) cpu_start: App cpu up.[0m [0;32mI (995) spiram: SPI SRAM memory test OK[0m [0;32mI (1003) cpu_start: Pro cpu start user code[0m [0;32mI (1003) cpu_start: cpu freq: 240000000[0m [0;32mI (1004) cpu_start: Application information:[0m [0;32mI (1007) cpu_start: Project name: voip_app[0m [0;32mI (1012) cpu_start: App version: v2.3-205-gecf62b4-dirty[0m [0;32mI (1018) cpu_start: Compile time: Feb 13 2022 10:53:37[0m [0;32mI (1024) cpu_start: ELF file SHA256: d102097703c17189...[0m [0;32mI (1030) cpu_start: ESP-IDF: v4.4-157-gc7b9f94-dirty[0m [0;32mI (1037) heap_init: Initializing. RAM available for dynamic allocation:[0m [0;32mI (1044) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM[0m [0;32mI (1050) heap_init: At 3FFB5230 len 0002ADD0 (171 KiB): DRAM[0m [0;32mI (1057) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM[0m [0;32mI (1063) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM[0m [0;32mI (1070) heap_init: At 40097104 len 00008EFC (35 KiB): IRAM[0m [0;32mI (1076) spiram: Adding pool of 4084K of external SPI memory to heap allocator[0m [0;32mI (1084) spi_flash: detected chip: gd[0m [0;32mI (1088) spi_flash: flash io: qio[0m [0;32mI (1093) cpu_start: Starting scheduler on PRO CPU.[0m [0;32mI (0) cpu_start: Starting scheduler on APP CPU.[0m [0;32mI (1103) spiram: Reserving pool of 18K of internal memory for DMA/internal allocations[0m [0;32mI (1143) VOIP_EXAMPLE: [1.0] Initialize peripherals management[0m [0;32mI (1143) VOIP_EXAMPLE: [1.1] Initialize and start peripherals[0m [0;32mI (1143) gpio: GPIO[36]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:3 [0m [0;32mI (1153) gpio: GPIO[39]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:3 [0m [0;32mI (1163) VOIP_EXAMPLE: [1.2] Create and start input key service[0m [0;33mW (1183) PERIPH_TOUCH: _touch_init[0m [0;32mI (1183) VOIP_EXAMPLE: [ 1.3 ] Create display service instance[0m [0;32mI (1183) VOIP_EXAMPLE: [ 2 ] Start codec chip[0m [0;32mI (1193) gpio: GPIO[19]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:3 [0m [0;31mE (1203) gpio: gpio_install_isr_service(449): GPIO isr service already installed[0m [0;32mI (1233) gpio: GPIO[21]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 [0m [0;32mI (1233) ES8388_DRIVER: init,out:02, in:00[0m [0;32mI (1243) AUDIO_HAL: Codec mode is 3, Ctrl:1[0m [0;32mI (1253) VOIP_EXAMPLE: [ 3 ] Initialize tone player[0m [0;32mI (1253) AUDIO_THREAD: The media_task task allocate stack on external memory[0m [0;32mI (1253) ESP_AUDIO_TASK: media_ctrl_task running...,0x3f803c30[0m

----------------------------- ESP Audio Platform ----------------------------- | | | ESP_AUDIO-v1.7.0-31-g5b8f999-3072767-09be8fe | | Compile date: Oct 14 2021-11:00:34 |

[0;32mI (1293) ESP_AUDIO_CTRL: Func:media_ctrl_create, Line:350, MEM Total:4420063 Bytes, Inter:296307 Bytes, Dram:261855 Bytes

[0m [0;32mI (1323) MP3_DECODER: MP3 init[0m [0;32mI (1323) I2S: APLL expected frequency is 8192000 Hz, real frequency is 8191999 Hz[0m [0;32mI (1323) I2S: DMA Malloc info, datalen=blocksize=1200, dma_buf_count=3[0m [0;32mI (1333) I2S: DMA Malloc info, datalen=blocksize=1200, dma_buf_count=3[0m [0;32mI (1333) I2S: I2S0, MCLK output by GPIO0[0m [0;32mI (1343) LYRAT_V4_3: I2S0, MCLK output by GPIO0[0m [0;32mI (1343) VOIP_EXAMPLE: [ 4 ] Create Wi-Fi service instance[0m [0;31mE (1353) DISPATCHER: exe first list: 0x0[0m [0;32mI (1353) DISPATCHER: dispatcher_event_task is running...[0m [0;32mI (1383) AUDIO_THREAD: The wifi_serv task allocate stack on external memory[0m I (1393) wifi:wifi driver task: 3ffc8028, prio:23, stack:6656, core=0 [0;32mI (1393) system_api: Base MAC address is not set[0m [0;32mI (1393) system_api: read default base MAC address from EFUSE[0m I (1413) wifi:wifi firmware version: 7679c42 I (1413) wifi:wifi certification version: v7.0 I (1413) wifi:config NVS flash: enabled I (1413) wifi:config nano formating: disabled I (1413) wifi:Init data frame dynamic rx buffer num: 128 I (1423) wifi:Init management frame dynamic rx buffer num: 128 I (1423) wifi:Init management short buffer num: 32 I (1433) wifi:Init static tx buffer num: 9 I (1433) wifi:Init tx cache buffer num: 32 I (1433) wifi:Init static rx buffer size: 1600 I (1443) wifi:Init static rx buffer num: 9 I (1443) wifi:Init dynamic rx buffer num: 128 [0;32mI (1453) wifi_init: rx ba win: 16[0m [0;32mI (1453) wifi_init: tcpip mbox: 32[0m [0;32mI (1453) wifi_init: udp mbox: 64[0m [0;32mI (1463) wifi_init: tcp mbox: 6[0m [0;32mI (1463) wifi_init: tcp tx win: 5744[0m [0;32mI (1473) wifi_init: tcp rx win: 5744[0m [0;32mI (1473) wifi_init: tcp mss: 1440[0m [0;32mI (1473) wifi_init: WiFi/LWIP prefer SPIRAM[0m [0;32mI (1483) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07[0m I (1573) wifi:mode : sta (44:17:93:e6:3c:ac) I (1573) wifi:enable tsf [0;32mI (1593) WIFI_SERV: Connect to wifi ssid: 1308, pwd: [0m I (1603) wifi:new:<1,1>, old:<1,0>, ap:<255,255>, sta:<1,1>, prof:1 I (2153) wifi:state: init -> auth (b0) I (2163) wifi:state: auth -> assoc (0) I (2173) wifi:state: assoc -> run (10) I (2183) wifi:connected with 1308, aid = 7, channel 1, 40U, bssid = f4:a5:9d:26:0e:1c I (2193) wifi:security: WPA2-PSK, phy: bgn, rssi: -53 I (2193) wifi:pm start, type: 1

[0;33mW (2193) WIFI_SERV: WiFi Event cb, Unhandle event_base:WIFI_EVENT, event_id:4[0m I (2233) wifi:AP's beacon interval = 102400 us, DTIM period = 1 [0;32mI (3143) esp_netif_handlers: sta ip: 192.168.2.11, mask: 255.255.255.0, gw: 192.168.2.1[0m [0;32mI (3143) WIFI_SERV: Got ip:192.168.2.11[0m [0;33mW (3143) WIFI_SERV: STATE type:2, pdata:0x0, len:0[0m [0;32mI (3173) VOIP_EXAMPLE: PERIPH_WIFI_CONNECTED [325][0m [0;32mI (3173) VOIP_EXAMPLE: [ 5 ] Create SIP Service[0m [0;32mI (3173) SIP: esp_sip ver : '1.0.2' Compile date: Feb 7 2022-11:38:19 [0m [0;32mI (3173) SIP: Conecting...[0m [0;33mW (3183) SIP: CHANGE STATE FROM 0, TO 1, :func: sip_connect:1641[0m [0;32mI (3183) SIP: [1970-01-01/00:00:01]=======WRITE 0595 bytes>>[0m [0;32mI (3193) SIP:

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK--136814133;rport

From: sip:[email protected]:5060;tag=-293336201

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: B07A8C8263DFC7A76C701B261C11008665E3657EF6FB

CSeq: 1 REGISTER

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (3253) SIP: [1970-01-01/00:00:01]=======================>>[0m [0;32mI (3293) SIP: [1970-01-01/00:00:01]<<=====READ 0396 bytes==[0m [0;32mI (3293) SIP:

SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK--136814133

To: sip:[email protected]:5060;tag=7377fa10

From: sip:[email protected]:5060;tag=-293336201

Call-ID: B07A8C8263DFC7A76C701B261C11008665E3657EF6FB

CSeq: 1 REGISTER

WWW-Authenticate: Digest algorithm=MD5,nonce="4A1E2D326B59183B30DD3F51315D10421AD89995",realm="newrocktech"

Content-Length: 0

[0m [0;32mI (3333) SIP: [1970-01-01/00:00:01]<<======================[0m [0;32mI (3333) SIP: Required authentication[0m [0;32mI (3343) SIP: [1970-01-01/00:00:01]=======WRITE 0835 bytes>>[0m [0;32mI (3343) SIP:

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK-2034971225;rport

From: sip:[email protected]:5060;tag=-1922604612

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: B07A8C8263DFC7A76C701B261C11008665E3657EF6FB

CSeq: 2 REGISTER

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

Authorization: Digest username="100", realm="newrocktech", nonce="4A1E2D326B59183B30DD3F51315D10421AD89995", uri="sip:192.168.2.218:5060", response="f2e52720056a5dc0db6093d4254272bb", algorithm=MD5, nc=00000001, cnonce="423ccdfd05e9ea0f"

[0m [0;32mI (3423) SIP: [1970-01-01/00:00:01]=======================>>[0m W (3453) wifi:idx:0 (ifx:0, f4:a5:9d:26:0e:1c), tid:0, ssn:1, winSize:64 [0;32mI (3463) SIP: [1970-01-01/00:00:01]<<=====READ 0412 bytes==[0m [0;32mI (3463) SIP:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK-2034971225

To: sip:[email protected]:5060;tag=41f98c43

From: sip:[email protected]:5060;tag=-1922604612

Date: Sun, 13 Feb 2022 03:02:05 GMT

Call-ID: B07A8C8263DFC7A76C701B261C11008665E3657EF6FB

CSeq: 2 REGISTER

Server: OM20G(Rev 2.1.5.192)

Contact: sip:[email protected]:14173;transport=UDP;expires=3600

Content-Length: 0

[0m [0;32mI (3503) SIP: [1970-01-01/00:00:01]<<======================[0m [0;32mI (3503) VOIP_EXAMPLE: SIP_EVENT_REGISTERED[0m [0;32mI (3513) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;32mI (3513) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0[0m [0;32mI (3523) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffaf7a4[0m [0;32mI (3533) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:771, MEM Total:4292191 Bytes, Inter:239055 Bytes, Dram:204603 Bytes

[0m [0;32mI (3543) ESP_AUDIO_TASK: It's a decoder[0m [0;32mI (3543) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[16000],OUT:[OUT_iis],rate:0,ch:0,pos:0[0m [0;32mI (3553) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804e90,CODEC:0x3f80501c,FILTER:0x3f818014,OUT:0x3f8051c4[0m [0;32mI (3563) ESP_AUDIO_TASK: 2.2 Update all pipeline[0m [0;32mI (3573) ESP_AUDIO_TASK: 2.3 Linked new pipeline[0m [0;32mI (3583) AUDIO_PIPELINE: link el->rb, el:0x3f804e90, tag:IN_flash, rb:0x3f8183ec[0m [0;32mI (3583) AUDIO_PIPELINE: link el->rb, el:0x3f80501c, tag:DEC_mp3, rb:0x3f81ac2c[0m [0;32mI (3593) AUDIO_PIPELINE: link el->rb, el:0x3f818014, tag:Audio_forge, rb:0x3f81cc6c[0m [0;32mI (3603) ESP_AUDIO_TASK: 3. Previous starting...[0m [0;32mI (3603) AUDIO_ELEMENT: [IN_flash-0x3f804e90] Element task created[0m [0;32mI (3613) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (3623) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory[0m [0;32mI (3623) TONE_PARTITION: tone partition format 0, total 21[0m [0;32mI (3633) AUDIO_ELEMENT: [DEC_mp3-0x3f80501c] Element task created[0m [0;32mI (3633) TONE_STREAM: Tone offset:00038c2c, Tone length:16344, pos:12 [0m [0;32mI (3643) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (3653) MP3_DECODER: MP3 opened[0m [0;32mI (3653) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO[0m [0;32mI (3663) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (3683) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (3693) ESP_AUDIO_TASK: Received muisc info then on play[0m [0;32mI (3693) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0[0m [0;32mI (3703) AUDIO_THREAD: The Audio_forge task allocate stack on external memory[0m [0;32mI (3713) AUDIO_ELEMENT: [Audio_forge-0x3f818014] Element task created[0m [0;32mI (3713) AUDIO_ELEMENT: [Audio_forge] AEL_MS_CMD_RESUME,state:1[0m [0;32mI (3723) AUDIO_FORGE: audio_forge opened[0m [0;32mI (3723) AUDIO_THREAD: The OUT_iis task allocate stack on external memory[0m [0;32mI (3733) AUDIO_FORGE: audio_forge reopen[0m [0;32mI (3733) AUDIO_ELEMENT: [OUT_iis-0x3f8051c4] Element task created[0m [0;32mI (3743) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (3753) I2S_STREAM: AUDIO_STREAM_WRITER[0m [0;32mI (3753) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (3773) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (3783) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0[0m [0;32mI (3793) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4193951 Bytes, Inter:233159 Bytes, Dram:198707 Bytes

[0m [0;32mI (3803) ESP_AUDIO_CTRL: Exit play procedure, ret:0[0m [0;33mW (3803) SIP: CHANGE STATE FROM 1, TO 2, :func: sip_register:1669[0m [0;33mW (3833) TONE_STREAM: No more data,ret:0 ,info.byte_pos:16344[0m [0;32mI (3833) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0[0m [0;32mI (3833) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (4753) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2[0m [0;32mI (4973) MP3_DECODER: Closed[0m [0;32mI (4973) ESP_AUDIO_TASK: Received last pos: 16344 bytes[0m [0;32mI (4973) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (5103) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2[0m [0;32mI (5103) AUDIO_FORGE: audio forge closed[0m [0;32mI (5103) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (5253) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2[0m [0;32mI (5363) ESP_AUDIO_TASK: Received last time: 1520 ms[0m [0;32mI (5363) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (5373) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0[0m [0;32mI (5383) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4240663 Bytes, Inter:233243 Bytes, Dram:198791 Bytes

[0m [0;33mW (5393) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED[0m [0;33mW (5403) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED[0m [0;32mI (22513) SIP: [1970-01-01/00:00:10]<<=====READ 0869 bytes==[0m [0;32mI (22513) SIP:

INVITE sip:100@localhost SIP/2.0

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447213441644721319

To: sip:100@localhost

From: sip:200@localhost:5060;tag=16447213441644721318-1

Call-ID: [email protected]

CSeq: 100060 INVITE

Max-Forwards: 70

Expires: 60000

Contact: sip:[email protected]:5060

Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,INFO,REFER,PRACK,UPDATE

User-Agent: NewRockTech OM20G-2S/2 VER.2.1.5.192/UA 2.0

Content-Type: application/sdp

Content-Length: 350

v=0

o=- 1644721344 971930 IN IP4 192.168.2.218

s=-

c=IN IP4 192.168.2.218

t=0 0

m=audio 10014 RTP/AVP 0 8 18 104 9 101

a=ptime:20

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:18 G729/8000

a=rtpmap:104 AMR/8000

a=fmtp:104 mode-change-capability=2;max-red=220

a=rtpmap:9 G722/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

[0m [0;32mI (22583) SIP: [1970-01-01/00:00:10]<<======================[0m [0;32mI (22593) SIP: Expires = 60000[0m [0;32mI (22593) SIP: Remote RTP port=10014[0m [0;32mI (22603) SIP: Remote RTP addr=192.168.2.218 [0m [0;32mI (22603) SIP: call from 200[0m [0;32mI (22613) SIP: [1970-01-01/00:00:10]=======WRITE 0414 bytes>>[0m [0;32mI (22613) SIP:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447213441644721319

Contact: sip:[email protected]:14173

From: sip:200@localhost:5060;tag=16447213441644721318-1

To: sip:100@localhost;tag=-1730913343

Call-ID: [email protected]

CSeq: 100060 INVITE

Server: ESP32 SIP/2.0

Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO

Content-Length: 0

[0m [0;32mI (22653) SIP: [1970-01-01/00:00:10]=======================>>[0m [0;32mI (22663) SIP: [1970-01-01/00:00:10]=======WRITE 0415 bytes>>[0m [0;32mI (22673) SIP:

SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447213441644721319

Contact: sip:[email protected]:14173

From: sip:200@localhost:5060;tag=16447213441644721318-1

To: sip:100@localhost;tag=-1146809499

Call-ID: [email protected]

CSeq: 100060 INVITE

Server: ESP32 SIP/2.0

Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO

Content-Length: 0

[0m [0;32mI (22713) SIP: [1970-01-01/00:00:10]=======================>>[0m [0;33mW (22713) SIP: CHANGE STATE FROM 2, TO 16, :func: _sip_uas_process_req_invite:868[0m [0;32mI (23823) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (23823) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;32mI (23823) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0[0m [0;32mI (23823) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffaf7a4[0m [0;32mI (23833) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:771, MEM Total:4262291 Bytes, Inter:237691 Bytes, Dram:203239 Bytes

[0m [0;32mI (23843) ESP_AUDIO_TASK: It's a decoder[0m [0;32mI (23853) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[16000],OUT:[OUT_iis],rate:0,ch:0,pos:0[0m [0;32mI (23863) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804e90,CODEC:0x3f80501c,FILTER:0x3f818014,OUT:0x3f8051c4[0m [0;32mI (23873) AUDIO_PIPELINE: audio_pipeline_unlinked[0m [0;33mW (23873) AUDIO_PIPELINE: There are no listener registered[0m [0;32mI (23883) ESP_AUDIO_TASK: 2.2 Update all pipeline[0m [0;32mI (23883) ESP_AUDIO_TASK: 2.3 Linked new pipeline[0m [0;32mI (23893) AUDIO_PIPELINE: link el->rb, el:0x3f804e90, tag:IN_flash, rb:0x3f81f040[0m [0;32mI (23903) AUDIO_PIPELINE: link el->rb, el:0x3f80501c, tag:DEC_mp3, rb:0x3f81f07c[0m [0;32mI (23913) AUDIO_PIPELINE: link el->rb, el:0x3f818014, tag:Audio_forge, rb:0x3f81f0b8[0m [0;32mI (23913) ESP_AUDIO_TASK: 3. Previous starting...[0m [0;32mI (23923) AUDIO_ELEMENT: [IN_flash-0x3f804e90] Element task created[0m [0;32mI (23933) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (23933) TONE_PARTITION: tone partition format 0, total 21[0m [0;32mI (23933) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory[0m [0;32mI (23943) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8 [0m [0;32mI (23953) AUDIO_ELEMENT: [DEC_mp3-0x3f80501c] Element task created[0m [0;32mI (23963) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (23973) MP3_DECODER: MP3 opened[0m [0;32mI (23973) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO[0m [0;32mI (23983) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (23993) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (24003) ESP_AUDIO_TASK: Received muisc info then on play[0m [0;32mI (24013) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0[0m [0;32mI (24013) AUDIO_THREAD: The Audio_forge task allocate stack on external memory[0m [0;32mI (24023) AUDIO_ELEMENT: [Audio_forge-0x3f818014] Element task created[0m [0;32mI (24033) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (24043) AUDIO_FORGE: audio_forge opened[0m [0;32mI (24043) AUDIO_THREAD: The OUT_iis task allocate stack on external memory[0m [0;32mI (24043) AUDIO_FORGE: audio_forge reopen[0m [0;32mI (24053) AUDIO_ELEMENT: [OUT_iis-0x3f8051c4] Element task created[0m [0;32mI (24063) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (24073) I2S_STREAM: AUDIO_STREAM_WRITER[0m [0;32mI (24073) ESP_AUDIO_CTRL: Exit play procedure, ret:0[0m [0;32mI (24073) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (24093) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (24103) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0[0m [0;32mI (24113) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4190087 Bytes, Inter:233159 Bytes, Dram:198707 Bytes

[0m [0;32mI (24883) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (24883) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (24883) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;33mW (25773) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018[0m [0;32mI (25773) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0[0m [0;32mI (25773) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (25883) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (25883) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (25883) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (26673) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2[0m [0;32mI (26873) MP3_DECODER: Closed[0m [0;32mI (26873) ESP_AUDIO_TASK: Received last pos: 36018 bytes[0m [0;32mI (26873) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (26883) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (26893) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (26893) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (26943) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2[0m [0;32mI (26943) AUDIO_FORGE: audio forge closed[0m [0;32mI (26943) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (27093) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2[0m [0;32mI (27213) ESP_AUDIO_TASK: Received last time: 3056 ms[0m [0;32mI (27213) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (27213) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0[0m [0;32mI (27223) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4239923 Bytes, Inter:233243 Bytes, Dram:198791 Bytes

[0m [0;33mW (27233) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED[0m [0;33mW (27243) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED[0m [0;32mI (27903) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (27903) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;32mI (27903) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0[0m [0;32mI (27903) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffaf7a4[0m [0;32mI (27913) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:771, MEM Total:4262291 Bytes, Inter:237691 Bytes, Dram:203239 Bytes

[0m [0;32mI (27923) ESP_AUDIO_TASK: It's a decoder[0m [0;32mI (27933) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[16000],OUT:[OUT_iis],rate:0,ch:0,pos:0[0m [0;32mI (27943) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804e90,CODEC:0x3f80501c,FILTER:0x3f818014,OUT:0x3f8051c4[0m [0;32mI (27953) AUDIO_PIPELINE: audio_pipeline_unlinked[0m [0;33mW (27953) AUDIO_PIPELINE: There are no listener registered[0m [0;32mI (27963) ESP_AUDIO_TASK: 2.2 Update all pipeline[0m [0;32mI (27963) ESP_AUDIO_TASK: 2.3 Linked new pipeline[0m [0;32mI (27973) AUDIO_PIPELINE: link el->rb, el:0x3f804e90, tag:IN_flash, rb:0x3f81f040[0m [0;32mI (27983) AUDIO_PIPELINE: link el->rb, el:0x3f80501c, tag:DEC_mp3, rb:0x3f81f07c[0m [0;32mI (27993) AUDIO_PIPELINE: link el->rb, el:0x3f818014, tag:Audio_forge, rb:0x3f81f0b8[0m [0;32mI (27993) ESP_AUDIO_TASK: 3. Previous starting...[0m [0;32mI (28003) AUDIO_ELEMENT: [IN_flash-0x3f804e90] Element task created[0m [0;32mI (28013) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (28013) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory[0m [0;32mI (28013) TONE_PARTITION: tone partition format 0, total 21[0m [0;32mI (28023) AUDIO_ELEMENT: [DEC_mp3-0x3f80501c] Element task created[0m [0;32mI (28033) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8 [0m [0;32mI (28033) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (28053) MP3_DECODER: MP3 opened[0m [0;32mI (28053) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO[0m [0;32mI (28063) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (28073) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (28083) ESP_AUDIO_TASK: Received muisc info then on play[0m [0;32mI (28093) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0[0m [0;32mI (28093) AUDIO_THREAD: The Audio_forge task allocate stack on external memory[0m [0;32mI (28103) AUDIO_ELEMENT: [Audio_forge-0x3f818014] Element task created[0m [0;32mI (28113) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (28123) AUDIO_FORGE: audio_forge opened[0m [0;32mI (28123) AUDIO_THREAD: The OUT_iis task allocate stack on external memory[0m [0;32mI (28133) AUDIO_ELEMENT: [OUT_iis-0x3f8051c4] Element task created[0m [0;32mI (28133) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (28143) I2S_STREAM: AUDIO_STREAM_WRITER[0m [0;32mI (28143) ESP_AUDIO_CTRL: Exit play procedure, ret:0[0m [0;32mI (28143) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (28163) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (28173) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0[0m [0;32mI (28183) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4190211 Bytes, Inter:233159 Bytes, Dram:198707 Bytes

[0m [0;32mI (28953) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (28953) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (28953) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;33mW (29843) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018[0m [0;32mI (29843) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0[0m [0;32mI (29843) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (29953) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (29953) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (29953) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (30723) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2[0m [0;32mI (30943) MP3_DECODER: Closed[0m [0;32mI (30943) ESP_AUDIO_TASK: Received last pos: 36018 bytes[0m [0;32mI (30943) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (30953) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (30953) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (30963) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (31013) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2[0m [0;32mI (31013) AUDIO_FORGE: audio forge closed[0m [0;32mI (31013) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (31163) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2[0m [0;32mI (31273) ESP_AUDIO_TASK: Received last time: 3056 ms[0m [0;32mI (31273) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (31283) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0[0m [0;32mI (31293) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4239923 Bytes, Inter:233243 Bytes, Dram:198791 Bytes

[0m [0;33mW (31303) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED[0m [0;33mW (31313) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED[0m [0;32mI (31533) ESP_AUDIO_CTRL: Enter media_ctrl_stop procedure, src:0, type:0, st:FINISHED, phase:0[0m [0;33mW (31533) ESP_AUDIO_CTRL: [media_ctrl_stop]-Already stopped, status is FINISHED, phase:0[0m [0;33mW (31573) SIP: Deny the call[0m [0;33mW (31573) SIP: CHANGE STATE FROM 16, TO 2, :func: _sip_uas_process_res_invite:1002[0m [0;32mI (31573) VOIP_EXAMPLE: SIP_EVENT_HANGUP[0m [0;32mI (31573) SIP: [1970-01-01/00:00:15]=======WRITE 0416 bytes>>[0m [0;32mI (31583) SIP:

SIP/2.0 486 Busy Here

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447213441644721319

Contact: sip:[email protected]:14173

From: sip:200@localhost:5060;tag=16447213441644721318-1

To: sip:100@localhost;tag=1355773217

Call-ID: [email protected]

CSeq: 100060 INVITE

Server: ESP32 SIP/2.0

Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO

Content-Length: 0

[0m [0;32mI (31623) SIP: [1970-01-01/00:00:15]=======================>>[0m [0;32mI (31653) SIP: [1970-01-01/00:00:15]<<=====READ 0321 bytes==[0m [0;32mI (31653) SIP:

ACK sip:100@localhost SIP/2.0

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447213441644721319

To: sip:100@localhost;tag=1355773217

From: sip:200@localhost:5060;tag=16447213441644721318-1

Call-ID: [email protected]

CSeq: 100060 ACK

Max-Forwards: 70

Content-Length: 0

[0m [0;32mI (31683) SIP: [1970-01-01/00:00:15]<<======================[0m [0;32mI (36193) SIP: [1970-01-01/00:00:17]=======WRITE 0832 bytes>>[0m [0;32mI (36193) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK-1406407689;rport

From: sip:[email protected]:5060;tag=1885857833

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: 43AAFA1ADADFB77003D9CE1D1BCA272B9BC847E965B4

CSeq: 3 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

Authorization: Digest username="100", realm="newrocktech", nonce="4A1E2D326B59183B30DD3F51315D10421AD89995", uri="sip:192.168.2.218:5060", response="f4527b96d6ac3f3c4397b384c507e707", algorithm=MD5, nc=00000002, cnonce="943aef6c1816ee81"

[0m [0;32mI (36263) SIP: [1970-01-01/00:00:17]=======================>>[0m [0;32mI (36303) SIP: [1970-01-01/00:00:17]<<=====READ 0283 bytes==[0m [0;32mI (36303) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK-1406407689

To: sip:[email protected]:5060;tag=6e4fcd0a

From: sip:[email protected]:5060;tag=1885857833

Call-ID: 43AAFA1ADADFB77003D9CE1D1BCA272B9BC847E965B4

CSeq: 3 OPTIONS

Content-Length: 0

[0m [0;32mI (36323) SIP: [1970-01-01/00:00:17]<<======================[0m [0;32mI (39513) SIP: [1970-01-01/00:00:19]<<=====READ 0869 bytes==[0m [0;32mI (39513) SIP:

INVITE sip:100@localhost SIP/2.0

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447213611644721325

To: sip:100@localhost

From: sip:200@localhost:5060;tag=16447213611644721324-1

Call-ID: [email protected]

CSeq: 100120 INVITE

Max-Forwards: 70

Expires: 60000

Contact: sip:[email protected]:5060

Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,INFO,REFER,PRACK,UPDATE

User-Agent: NewRockTech OM20G-2S/2 VER.2.1.5.192/UA 2.0

Content-Type: application/sdp

Content-Length: 350

v=0

o=- 1644721361 921942 IN IP4 192.168.2.218

s=-

c=IN IP4 192.168.2.218

t=0 0

m=audio 10020 RTP/AVP 0 8 18 104 9 101

a=ptime:20

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:18 G729/8000

a=rtpmap:104 AMR/8000

a=fmtp:104 mode-change-capability=2;max-red=220

a=rtpmap:9 G722/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

[0m [0;32mI (39583) SIP: [1970-01-01/00:00:19]<<======================[0m [0;32mI (39593) SIP: Expires = 60000[0m [0;32mI (39593) SIP: Remote RTP port=10020[0m [0;32mI (39603) SIP: Remote RTP addr=192.168.2.218 [0m [0;32mI (39603) SIP: call from 200[0m [0;32mI (39613) SIP: [1970-01-01/00:00:19]=======WRITE 0413 bytes>>[0m [0;32mI (39613) SIP:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447213611644721325

Contact: sip:[email protected]:14173

From: sip:200@localhost:5060;tag=16447213611644721324-1

To: sip:100@localhost;tag=1894901915

Call-ID: [email protected]

CSeq: 100120 INVITE

Server: ESP32 SIP/2.0

Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO

Content-Length: 0

[0m [0;32mI (39653) SIP: [1970-01-01/00:00:19]=======================>>[0m [0;32mI (39663) SIP: [1970-01-01/00:00:19]=======WRITE 0415 bytes>>[0m [0;32mI (39663) SIP:

SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447213611644721325

Contact: sip:[email protected]:14173

From: sip:200@localhost:5060;tag=16447213611644721324-1

To: sip:100@localhost;tag=-1991712488

Call-ID: [email protected]

CSeq: 100120 INVITE

Server: ESP32 SIP/2.0

Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO

Content-Length: 0

[0m [0;32mI (39703) SIP: [1970-01-01/00:00:19]=======================>>[0m [0;33mW (39713) SIP: CHANGE STATE FROM 2, TO 16, :func: _sip_uas_process_req_invite:868[0m [0;32mI (40823) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (40823) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;32mI (40823) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0[0m [0;32mI (40823) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffaf7a4[0m [0;32mI (40833) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:771, MEM Total:4262243 Bytes, Inter:237691 Bytes, Dram:203239 Bytes

[0m [0;32mI (40843) ESP_AUDIO_TASK: It's a decoder[0m [0;32mI (40853) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[16000],OUT:[OUT_iis],rate:0,ch:0,pos:0[0m [0;32mI (40863) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804e90,CODEC:0x3f80501c,FILTER:0x3f818014,OUT:0x3f8051c4[0m [0;32mI (40873) AUDIO_PIPELINE: audio_pipeline_unlinked[0m [0;33mW (40873) AUDIO_PIPELINE: There are no listener registered[0m [0;32mI (40883) ESP_AUDIO_TASK: 2.2 Update all pipeline[0m [0;32mI (40883) ESP_AUDIO_TASK: 2.3 Linked new pipeline[0m [0;32mI (40893) AUDIO_PIPELINE: link el->rb, el:0x3f804e90, tag:IN_flash, rb:0x3f81ef98[0m [0;32mI (40903) AUDIO_PIPELINE: link el->rb, el:0x3f80501c, tag:DEC_mp3, rb:0x3f81efd4[0m [0;32mI (40913) AUDIO_PIPELINE: link el->rb, el:0x3f818014, tag:Audio_forge, rb:0x3f81f010[0m [0;32mI (40913) ESP_AUDIO_TASK: 3. Previous starting...[0m [0;32mI (40923) AUDIO_ELEMENT: [IN_flash-0x3f804e90] Element task created[0m [0;32mI (40933) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (40933) TONE_PARTITION: tone partition format 0, total 21[0m [0;32mI (40933) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory[0m [0;32mI (40943) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8 [0m [0;32mI (40953) AUDIO_ELEMENT: [DEC_mp3-0x3f80501c] Element task created[0m [0;32mI (40963) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (40973) MP3_DECODER: MP3 opened[0m [0;32mI (40973) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO[0m [0;32mI (40983) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (40993) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (41003) ESP_AUDIO_TASK: Received muisc info then on play[0m [0;32mI (41013) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0[0m [0;32mI (41013) AUDIO_THREAD: The Audio_forge task allocate stack on external memory[0m [0;32mI (41023) AUDIO_ELEMENT: [Audio_forge-0x3f818014] Element task created[0m [0;32mI (41033) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (41043) AUDIO_FORGE: audio_forge opened[0m [0;32mI (41043) AUDIO_THREAD: The OUT_iis task allocate stack on external memory[0m [0;32mI (41053) AUDIO_ELEMENT: [OUT_iis-0x3f8051c4] Element task created[0m [0;32mI (41053) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (41063) I2S_STREAM: AUDIO_STREAM_WRITER[0m [0;32mI (41063) ESP_AUDIO_CTRL: Exit play procedure, ret:0[0m [0;32mI (41063) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (41083) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (41093) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0[0m [0;32mI (41103) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4190163 Bytes, Inter:233159 Bytes, Dram:198707 Bytes

[0m [0;32mI (41873) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (41873) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (41873) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;33mW (42763) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018[0m [0;32mI (42763) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0[0m [0;32mI (42763) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (42873) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (42873) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (42873) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (43103) VOIP_EXAMPLE: [ * ] [Rec] Set MIC Mute or not[0m [0;32mI (43643) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2[0m [0;32mI (43863) MP3_DECODER: Closed[0m [0;32mI (43863) ESP_AUDIO_TASK: Received last pos: 36018 bytes[0m [0;32mI (43863) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (43873) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (43873) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (43883) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (43933) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2[0m [0;32mI (43933) AUDIO_FORGE: audio forge closed[0m [0;32mI (43933) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (44083) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2[0m [0;32mI (44193) ESP_AUDIO_TASK: Received last time: 3056 ms[0m [0;32mI (44193) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (44203) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0[0m [0;32mI (44213) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4239835 Bytes, Inter:233203 Bytes, Dram:198751 Bytes

[0m [0;33mW (44223) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED[0m [0;33mW (44233) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED[0m [0;32mI (44893) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (44893) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;32mI (44893) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0[0m [0;32mI (44893) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffaf7a4[0m [0;32mI (44903) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:771, MEM Total:4262203 Bytes, Inter:237651 Bytes, Dram:203199 Bytes

[0m [0;32mI (44913) ESP_AUDIO_TASK: It's a decoder[0m [0;32mI (44923) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[16000],OUT:[OUT_iis],rate:0,ch:0,pos:0[0m [0;32mI (44933) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804e90,CODEC:0x3f80501c,FILTER:0x3f818014,OUT:0x3f8051c4[0m [0;32mI (44943) AUDIO_PIPELINE: audio_pipeline_unlinked[0m [0;33mW (44943) AUDIO_PIPELINE: There are no listener registered[0m [0;32mI (44953) ESP_AUDIO_TASK: 2.2 Update all pipeline[0m [0;32mI (44953) ESP_AUDIO_TASK: 2.3 Linked new pipeline[0m [0;32mI (44963) AUDIO_PIPELINE: link el->rb, el:0x3f804e90, tag:IN_flash, rb:0x3f81ef98[0m [0;32mI (44973) AUDIO_PIPELINE: link el->rb, el:0x3f80501c, tag:DEC_mp3, rb:0x3f81efd4[0m [0;32mI (44983) AUDIO_PIPELINE: link el->rb, el:0x3f818014, tag:Audio_forge, rb:0x3f81f010[0m [0;32mI (44983) ESP_AUDIO_TASK: 3. Previous starting...[0m [0;32mI (44993) AUDIO_ELEMENT: [IN_flash-0x3f804e90] Element task created[0m [0;32mI (45003) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (45003) TONE_PARTITION: tone partition format 0, total 21[0m [0;32mI (45003) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory[0m [0;32mI (45013) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8 [0m [0;32mI (45023) AUDIO_ELEMENT: [DEC_mp3-0x3f80501c] Element task created[0m [0;32mI (45033) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (45043) MP3_DECODER: MP3 opened[0m [0;32mI (45043) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO[0m [0;32mI (45053) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (45063) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (45073) ESP_AUDIO_TASK: Received muisc info then on play[0m [0;32mI (45083) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0[0m [0;32mI (45083) AUDIO_THREAD: The Audio_forge task allocate stack on external memory[0m [0;32mI (45093) AUDIO_ELEMENT: [Audio_forge-0x3f818014] Element task created[0m [0;32mI (45103) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (45113) AUDIO_FORGE: audio_forge opened[0m [0;32mI (45113) AUDIO_THREAD: The OUT_iis task allocate stack on external memory[0m [0;32mI (45123) AUDIO_ELEMENT: [OUT_iis-0x3f8051c4] Element task created[0m [0;32mI (45123) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (45133) I2S_STREAM: AUDIO_STREAM_WRITER[0m [0;32mI (45133) ESP_AUDIO_CTRL: Exit play procedure, ret:0[0m [0;32mI (45133) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (45153) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (45163) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0[0m [0;32mI (45173) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4190123 Bytes, Inter:233119 Bytes, Dram:198667 Bytes

[0m [0;32mI (45943) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (45943) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (45943) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;33mW (46823) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018[0m [0;32mI (46833) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0[0m [0;32mI (46833) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (46943) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (46943) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (46943) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (47713) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2[0m [0;32mI (47933) MP3_DECODER: Closed[0m [0;32mI (47933) ESP_AUDIO_TASK: Received last pos: 36018 bytes[0m [0;32mI (47933) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (47943) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (47943) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (47953) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (48003) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2[0m [0;32mI (48003) AUDIO_FORGE: audio forge closed[0m [0;32mI (48003) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (48153) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2[0m [0;32mI (48263) ESP_AUDIO_TASK: Received last time: 3056 ms[0m [0;32mI (48263) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (48273) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0[0m [0;32mI (48283) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4239835 Bytes, Inter:233203 Bytes, Dram:198751 Bytes

[0m [0;33mW (48293) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED[0m [0;33mW (48303) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED[0m [0;32mI (48963) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (48963) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;32mI (48963) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0[0m [0;32mI (48963) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffaf7a4[0m [0;32mI (48973) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:771, MEM Total:4262203 Bytes, Inter:237651 Bytes, Dram:203199 Bytes

[0m [0;32mI (48983) ESP_AUDIO_TASK: It's a decoder[0m [0;32mI (48993) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[16000],OUT:[OUT_iis],rate:0,ch:0,pos:0[0m [0;32mI (49003) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804e90,CODEC:0x3f80501c,FILTER:0x3f818014,OUT:0x3f8051c4[0m [0;32mI (49013) AUDIO_PIPELINE: audio_pipeline_unlinked[0m [0;33mW (49013) AUDIO_PIPELINE: There are no listener registered[0m [0;32mI (49023) ESP_AUDIO_TASK: 2.2 Update all pipeline[0m [0;32mI (49023) ESP_AUDIO_TASK: 2.3 Linked new pipeline[0m [0;32mI (49033) AUDIO_PIPELINE: link el->rb, el:0x3f804e90, tag:IN_flash, rb:0x3f81ef98[0m [0;32mI (49043) AUDIO_PIPELINE: link el->rb, el:0x3f80501c, tag:DEC_mp3, rb:0x3f81efd4[0m [0;32mI (49053) AUDIO_PIPELINE: link el->rb, el:0x3f818014, tag:Audio_forge, rb:0x3f81f010[0m [0;32mI (49053) ESP_AUDIO_TASK: 3. Previous starting...[0m [0;32mI (49063) AUDIO_ELEMENT: [IN_flash-0x3f804e90] Element task created[0m [0;32mI (49073) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (49073) TONE_PARTITION: tone partition format 0, total 21[0m [0;32mI (49073) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory[0m [0;32mI (49083) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8 [0m [0;32mI (49093) AUDIO_ELEMENT: [DEC_mp3-0x3f80501c] Element task created[0m [0;32mI (49103) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (49113) MP3_DECODER: MP3 opened[0m [0;32mI (49113) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO[0m [0;32mI (49123) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (49133) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (49143) ESP_AUDIO_TASK: Received muisc info then on play[0m [0;32mI (49153) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0[0m [0;32mI (49153) AUDIO_THREAD: The Audio_forge task allocate stack on external memory[0m [0;32mI (49163) AUDIO_ELEMENT: [Audio_forge-0x3f818014] Element task created[0m [0;32mI (49173) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (49183) AUDIO_FORGE: audio_forge opened[0m [0;32mI (49183) AUDIO_THREAD: The OUT_iis task allocate stack on external memory[0m [0;32mI (49193) AUDIO_ELEMENT: [OUT_iis-0x3f8051c4] Element task created[0m [0;32mI (49193) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (49203) I2S_STREAM: AUDIO_STREAM_WRITER[0m [0;32mI (49203) ESP_AUDIO_CTRL: Exit play procedure, ret:0[0m [0;32mI (49203) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (49223) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (49233) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0[0m [0;32mI (49243) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4190123 Bytes, Inter:233119 Bytes, Dram:198667 Bytes

[0m [0;32mI (50013) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (50013) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (50013) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (50393) VOIP_EXAMPLE: [ * ] [Rec] Set MIC Mute or not[0m [0;33mW (50893) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018[0m [0;32mI (50893) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0[0m [0;32mI (50903) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (51013) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (51013) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (51013) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (51773) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2[0m [0;32mI (51993) MP3_DECODER: Closed[0m [0;32mI (51993) ESP_AUDIO_TASK: Received last pos: 36018 bytes[0m [0;32mI (52003) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (52013) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (52013) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (52023) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (52073) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2[0m [0;32mI (52073) AUDIO_FORGE: audio forge closed[0m [0;32mI (52073) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (52203) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2[0m [0;32mI (52313) ESP_AUDIO_TASK: Received last time: 3056 ms[0m [0;32mI (52313) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (52323) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0[0m [0;32mI (52333) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4239835 Bytes, Inter:233203 Bytes, Dram:198751 Bytes

[0m [0;33mW (52343) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED[0m [0;33mW (52353) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED[0m [0;32mI (53023) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (53023) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;32mI (53023) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0[0m [0;32mI (53023) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffaf7a4[0m [0;32mI (53033) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:771, MEM Total:4262203 Bytes, Inter:237651 Bytes, Dram:203199 Bytes

[0m [0;32mI (53043) ESP_AUDIO_TASK: It's a decoder[0m [0;32mI (53053) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[16000],OUT:[OUT_iis],rate:0,ch:0,pos:0[0m [0;32mI (53063) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804e90,CODEC:0x3f80501c,FILTER:0x3f818014,OUT:0x3f8051c4[0m [0;32mI (53073) AUDIO_PIPELINE: audio_pipeline_unlinked[0m [0;33mW (53073) AUDIO_PIPELINE: There are no listener registered[0m [0;32mI (53083) ESP_AUDIO_TASK: 2.2 Update all pipeline[0m [0;32mI (53083) ESP_AUDIO_TASK: 2.3 Linked new pipeline[0m [0;32mI (53093) AUDIO_PIPELINE: link el->rb, el:0x3f804e90, tag:IN_flash, rb:0x3f81ef98[0m [0;32mI (53103) AUDIO_PIPELINE: link el->rb, el:0x3f80501c, tag:DEC_mp3, rb:0x3f81efd4[0m [0;32mI (53113) AUDIO_PIPELINE: link el->rb, el:0x3f818014, tag:Audio_forge, rb:0x3f81f010[0m [0;32mI (53113) ESP_AUDIO_TASK: 3. Previous starting...[0m [0;32mI (53123) AUDIO_ELEMENT: [IN_flash-0x3f804e90] Element task created[0m [0;32mI (53133) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (53133) TONE_PARTITION: tone partition format 0, total 21[0m [0;32mI (53133) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory[0m [0;32mI (53143) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8 [0m [0;32mI (53153) AUDIO_ELEMENT: [DEC_mp3-0x3f80501c] Element task created[0m [0;32mI (53163) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (53173) MP3_DECODER: MP3 opened[0m [0;32mI (53173) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO[0m [0;32mI (53183) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (53193) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (53203) ESP_AUDIO_TASK: Received muisc info then on play[0m [0;32mI (53213) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0[0m [0;32mI (53213) AUDIO_THREAD: The Audio_forge task allocate stack on external memory[0m [0;32mI (53223) AUDIO_ELEMENT: [Audio_forge-0x3f818014] Element task created[0m [0;32mI (53233) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (53243) AUDIO_FORGE: audio_forge opened[0m [0;32mI (53243) AUDIO_THREAD: The OUT_iis task allocate stack on external memory[0m [0;32mI (53253) AUDIO_ELEMENT: [OUT_iis-0x3f8051c4] Element task created[0m [0;32mI (53253) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (53263) I2S_STREAM: AUDIO_STREAM_WRITER[0m [0;32mI (53263) ESP_AUDIO_CTRL: Exit play procedure, ret:0[0m [0;32mI (53263) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (53283) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (53293) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0[0m [0;32mI (53303) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4190123 Bytes, Inter:233119 Bytes, Dram:198667 Bytes

[0m [0;32mI (54073) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (54073) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (54073) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;33mW (54963) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018[0m [0;32mI (54963) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0[0m [0;32mI (54973) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (55073) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (55073) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (55073) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (55843) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2[0m [0;32mI (56063) MP3_DECODER: Closed[0m [0;32mI (56063) ESP_AUDIO_TASK: Received last pos: 36018 bytes[0m [0;32mI (56063) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (56073) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (56083) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (56083) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (56143) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2[0m [0;32mI (56143) AUDIO_FORGE: audio forge closed[0m [0;32mI (56143) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (56273) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2[0m [0;32mI (56383) ESP_AUDIO_TASK: Received last time: 3056 ms[0m [0;32mI (56383) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (56393) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0[0m [0;32mI (56403) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4239835 Bytes, Inter:233203 Bytes, Dram:198751 Bytes

[0m [0;33mW (56413) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED[0m [0;33mW (56413) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED[0m [0;32mI (56923) ESP_AUDIO_CTRL: Enter media_ctrl_stop procedure, src:0, type:0, st:FINISHED, phase:0[0m [0;33mW (56923) ESP_AUDIO_CTRL: [media_ctrl_stop]-Already stopped, status is FINISHED, phase:0[0m [0;33mW (56993) SIP: Deny the call[0m [0;33mW (56993) SIP: CHANGE STATE FROM 16, TO 2, :func: _sip_uas_process_res_invite:1002[0m [0;32mI (56993) VOIP_EXAMPLE: SIP_EVENT_HANGUP[0m [0;32mI (56993) SIP: [1970-01-01/00:00:27]=======WRITE 0416 bytes>>[0m [0;32mI (57003) SIP:

SIP/2.0 486 Busy Here

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447213611644721325

Contact: sip:[email protected]:14173

From: sip:200@localhost:5060;tag=16447213611644721324-1

To: sip:100@localhost;tag=1579564474

Call-ID: [email protected]

CSeq: 100120 INVITE

Server: ESP32 SIP/2.0

Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO

Content-Length: 0

[0m [0;32mI (57043) SIP: [1970-01-01/00:00:28]=======================>>[0m [0;32mI (57083) SIP: [1970-01-01/00:00:28]<<=====READ 0321 bytes==[0m [0;32mI (57083) SIP:

ACK sip:100@localhost SIP/2.0

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447213611644721325

To: sip:100@localhost;tag=1579564474

From: sip:200@localhost:5060;tag=16447213611644721324-1

Call-ID: [email protected]

CSeq: 100120 ACK

Max-Forwards: 70

Content-Length: 0

[0m [0;32mI (57113) SIP: [1970-01-01/00:00:28]<<======================[0m [0;32mI (68823) SIP: [1970-01-01/00:01:03]=======WRITE 0832 bytes>>[0m [0;32mI (68823) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK-1095120209;rport

From: sip:[email protected]:5060;tag=1042418494

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: 4BBAF0967E182AD3061990281C764D0DD6C9082DF99F

CSeq: 4 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (68893) SIP: [1970-01-01/00:01:03]=======================>>[0m [0;32mI (68923) SIP: [1970-01-01/00:01:03]<<=====READ 0283 bytes==[0m [0;32mI (68923) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK-1095120209

To: sip:[email protected]:5060;tag=b00f4215

From: sip:[email protected]:5060;tag=1042418494

Call-ID: 4BBAF0967E182AD3061990281C764D0DD6C9082DF99F

CSeq: 4 OPTIONS

Content-Length: 0

[0m [0;32mI (68953) SIP: [1970-01-01/00:01:03]<<======================[0m [0;32mI (101753) SIP: [1970-01-01/00:01:20]=======WRITE 0831 bytes>>[0m [0;32mI (101753) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK-111148672;rport

From: sip:[email protected]:5060;tag=-260762895

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: A50CD28B227FC874F0FFCBE5A2067D6E449B2CCA67FA

CSeq: 5 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (101823) SIP: [1970-01-01/00:01:20]=======================>>[0m [0;32mI (101843) SIP: [1970-01-01/00:01:20]<<=====READ 0282 bytes==[0m [0;32mI (101843) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK-111148672

To: sip:[email protected]:5060;tag=83074848

From: sip:[email protected]:5060;tag=-260762895

Call-ID: A50CD28B227FC874F0FFCBE5A2067D6E449B2CCA67FA

CSeq: 5 OPTIONS

Content-Length: 0

[0m [0;32mI (101873) SIP: [1970-01-01/00:01:20]<<======================[0m [0;32mI (134673) SIP: [1970-01-01/00:02:06]=======WRITE 0833 bytes>>[0m [0;32mI (134673) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK--302761330;rport

From: sip:[email protected]:5060;tag=-1226422153

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: F6DD8141FFBC648EB40850C3FB894874AA2D6957230E

CSeq: 6 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (134743) SIP: [1970-01-01/00:02:06]=======================>>[0m [0;32mI (134773) SIP: [1970-01-01/00:02:06]<<=====READ 0284 bytes==[0m [0;32mI (134773) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK--302761330

To: sip:[email protected]:5060;tag=3dfee147

From: sip:[email protected]:5060;tag=-1226422153

Call-ID: F6DD8141FFBC648EB40850C3FB894874AA2D6957230E

CSeq: 6 OPTIONS

Content-Length: 0

[0m [0;32mI (134803) SIP: [1970-01-01/00:02:06]<<======================[0m [0;32mI (167503) SIP: [1970-01-01/00:02:23]=======WRITE 0832 bytes>>[0m [0;32mI (167503) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK-842183143;rport

From: sip:[email protected]:5060;tag=-1413043986

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: 3CF70BF4078E4A0D0E6A5D78A45B31AD0F62A0DACF89

CSeq: 7 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (167573) SIP: [1970-01-01/00:02:23]=======================>>[0m [0;32mI (167623) SIP: [1970-01-01/00:02:23]<<=====READ 0283 bytes==[0m [0;32mI (167623) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK-842183143

To: sip:[email protected]:5060;tag=3d66d472

From: sip:[email protected]:5060;tag=-1413043986

Call-ID: 3CF70BF4078E4A0D0E6A5D78A45B31AD0F62A0DACF89

CSeq: 7 OPTIONS

Content-Length: 0

[0m [0;32mI (167653) SIP: [1970-01-01/00:02:23]<<======================[0m [0;32mI (200453) SIP: [1970-01-01/00:03:09]=======WRITE 0832 bytes>>[0m [0;32mI (200453) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK-1326695868;rport

From: sip:[email protected]:5060;tag=1503145598

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: F38009A7461AA24E2F87BF83A0E129BB82A5C77FE0A0

CSeq: 8 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (200523) SIP: [1970-01-01/00:03:09]=======================>>[0m [0;32mI (200543) SIP: [1970-01-01/00:03:09]<<=====READ 0283 bytes==[0m [0;32mI (200543) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK-1326695868

To: sip:[email protected]:5060;tag=78df9572

From: sip:[email protected]:5060;tag=1503145598

Call-ID: F38009A7461AA24E2F87BF83A0E129BB82A5C77FE0A0

CSeq: 8 OPTIONS

Content-Length: 0

[0m [0;32mI (200573) SIP: [1970-01-01/00:03:09]<<======================[0m [0;32mI (233373) SIP: [1970-01-01/00:03:26]=======WRITE 0831 bytes>>[0m [0;32mI (233373) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK-1965430000;rport

From: sip:[email protected]:5060;tag=269720849

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: 1198AA5C93333760744DB47B9A9C4F5C546994B7FDC5

CSeq: 9 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (233443) SIP: [1970-01-01/00:03:26]=======================>>[0m [0;32mI (233473) SIP: [1970-01-01/00:03:26]<<=====READ 0282 bytes==[0m [0;32mI (233473) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK-1965430000

To: sip:[email protected]:5060;tag=fa6c1041

From: sip:[email protected]:5060;tag=269720849

Call-ID: 1198AA5C93333760744DB47B9A9C4F5C546994B7FDC5

CSeq: 9 OPTIONS

Content-Length: 0

[0m [0;32mI (233493) SIP: [1970-01-01/00:03:26]<<======================[0m [0;32mI (266203) SIP: [1970-01-01/00:04:12]=======WRITE 0833 bytes>>[0m [0;32mI (266203) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK--2104454243;rport

From: sip:[email protected]:5060;tag=134916891

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: 0DC7F0C12FBC7394B785C4D53DC59234C2491DC85D9F

CSeq: 10 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (266273) SIP: [1970-01-01/00:04:12]=======================>>[0m [0;32mI (266303) SIP: [1970-01-01/00:04:12]<<=====READ 0284 bytes==[0m [0;32mI (266303) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK--2104454243

To: sip:[email protected]:5060;tag=d9f7e231

From: sip:[email protected]:5060;tag=134916891

Call-ID: 0DC7F0C12FBC7394B785C4D53DC59234C2491DC85D9F

CSeq: 10 OPTIONS

Content-Length: 0

[0m [0;32mI (266323) SIP: [1970-01-01/00:04:12]<<======================[0m [0;32mI (299133) SIP: [1970-01-01/00:04:29]=======WRITE 0833 bytes>>[0m [0;32mI (299133) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK-1945787904;rport

From: sip:[email protected]:5060;tag=-380684132

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: 453821C214A8236DD854EEF09DC5E34817F3A566B7E3

CSeq: 11 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (299203) SIP: [1970-01-01/00:04:29]=======================>>[0m [0;32mI (299233) SIP: [1970-01-01/00:04:29]<<=====READ 0284 bytes==[0m [0;32mI (299233) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK-1945787904

To: sip:[email protected]:5060;tag=dd74752c

From: sip:[email protected]:5060;tag=-380684132

Call-ID: 453821C214A8236DD854EEF09DC5E34817F3A566B7E3

CSeq: 11 OPTIONS

Content-Length: 0

[0m [0;32mI (299263) SIP: [1970-01-01/00:04:29]<<======================[0m [0;32mI (332063) SIP: [1970-01-01/00:05:15]=======WRITE 0833 bytes>>[0m [0;32mI (332063) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK--2064961550;rport

From: sip:[email protected]:5060;tag=488116745

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: C2D035EB7F1859ED262276A10947D6ACF45DA14ACF11

CSeq: 12 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (332133) SIP: [1970-01-01/00:05:15]=======================>>[0m [0;32mI (332163) SIP: [1970-01-01/00:05:15]<<=====READ 0284 bytes==[0m [0;32mI (332163) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK--2064961550

To: sip:[email protected]:5060;tag=009e1c53

From: sip:[email protected]:5060;tag=488116745

Call-ID: C2D035EB7F1859ED262276A10947D6ACF45DA14ACF11

CSeq: 12 OPTIONS

Content-Length: 0

[0m [0;32mI (332183) SIP: [1970-01-01/00:05:15]<<======================[0m [0;32mI (364993) SIP: [1970-01-01/00:06:01]=======WRITE 0831 bytes>>[0m [0;32mI (364993) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK-999632439;rport

From: sip:[email protected]:5060;tag=311689268

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: C0CE5BEB4A3E27B42E695A9E7919F2E59232C11E0888

CSeq: 13 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (365063) SIP: [1970-01-01/00:06:02]=======================>>[0m [0;32mI (365093) SIP: [1970-01-01/00:06:02]<<=====READ 0282 bytes==[0m [0;32mI (365093) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK-999632439

To: sip:[email protected]:5060;tag=783da526

From: sip:[email protected]:5060;tag=311689268

Call-ID: C0CE5BEB4A3E27B42E695A9E7919F2E59232C11E0888

CSeq: 13 OPTIONS

Content-Length: 0

[0m [0;32mI (365123) SIP: [1970-01-01/00:06:02]<<======================[0m [0;32mI (397923) SIP: [1970-01-01/00:06:18]=======WRITE 0833 bytes>>[0m [0;32mI (397923) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK--227124585;rport

From: sip:[email protected]:5060;tag=1521662418

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: 31231825D8DD395464C44D937706BBD29E318B46E8E2

CSeq: 14 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (397993) SIP: [1970-01-01/00:06:18]=======================>>[0m [0;32mI (398033) SIP: [1970-01-01/00:06:18]<<=====READ 0284 bytes==[0m [0;32mI (398033) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK--227124585

To: sip:[email protected]:5060;tag=ce343478

From: sip:[email protected]:5060;tag=1521662418

Call-ID: 31231825D8DD395464C44D937706BBD29E318B46E8E2

CSeq: 14 OPTIONS

Content-Length: 0

[0m [0;32mI (398053) SIP: [1970-01-01/00:06:18]<<======================[0m [0;32mI (430763) SIP: [1970-01-01/00:07:04]=======WRITE 0832 bytes>>[0m [0;32mI (430763) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK-947735549;rport

From: sip:[email protected]:5060;tag=1846709836

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: 4E2AD639637A039A29442E8AA2191C0EC49E37C2DFB4

CSeq: 15 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (430833) SIP: [1970-01-01/00:07:04]=======================>>[0m [0;32mI (430853) SIP: [1970-01-01/00:07:04]<<=====READ 0283 bytes==[0m [0;32mI (430853) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK-947735549

To: sip:[email protected]:5060;tag=98a3af1e

From: sip:[email protected]:5060;tag=1846709836

Call-ID: 4E2AD639637A039A29442E8AA2191C0EC49E37C2DFB4

CSeq: 15 OPTIONS

Content-Length: 0

[0m [0;32mI (430883) SIP: [1970-01-01/00:07:04]<<======================[0m [0;32mI (463693) SIP: [1970-01-01/00:07:21]=======WRITE 0833 bytes>>[0m [0;32mI (463693) SIP:

OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.2.11:14173;branch=z9hG4bK-978145399;rport

From: sip:[email protected]:5060;tag=-1509188113

To: sip:[email protected]:5060

Contact: sip:[email protected]:14173;transport=UDP

Max-Forwards: 70

Call-ID: DC94410A8880E6D4798396013F7B7E9A40C1D888C214

CSeq: 16 OPTIONS

Expires: 3600

User-Agent: ESP32 SIP/2.0

Content-Length: 0

Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE

Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri

Allow-Events: presence, kpml

[0m [0;32mI (463763) SIP: [1970-01-01/00:07:21]=======================>>[0m [0;32mI (463793) SIP: [1970-01-01/00:07:21]<<=====READ 0284 bytes==[0m [0;32mI (463793) SIP:

SIP/2.0 403 Forbidden

Via: SIP/2.0/UDP 192.168.2.11:14173;rport;branch=z9hG4bK-978145399

To: sip:[email protected]:5060;tag=8e91d06b

From: sip:[email protected]:5060;tag=-1509188113

Call-ID: DC94410A8880E6D4798396013F7B7E9A40C1D888C214

CSeq: 16 OPTIONS

Content-Length: 0

[0m [0;32mI (463823) SIP: [1970-01-01/00:07:21]<<======================[0m [0;32mI (465703) SIP: [1970-01-01/00:07:22]<<=====READ 0869 bytes==[0m [0;32mI (465703) SIP:

INVITE sip:100@localhost SIP/2.0

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447217881644721337

To: sip:100@localhost

From: sip:200@localhost:5060;tag=16447217881644721336-1

Call-ID: [email protected]

CSeq: 100240 INVITE

Max-Forwards: 70

Expires: 60000

Contact: sip:[email protected]:5060

Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,INFO,REFER,PRACK,UPDATE

User-Agent: NewRockTech OM20G-2S/2 VER.2.1.5.192/UA 2.0

Content-Type: application/sdp

Content-Length: 350

v=0

o=- 1644721788 107171 IN IP4 192.168.2.218

s=-

c=IN IP4 192.168.2.218

t=0 0

m=audio 10032 RTP/AVP 0 8 18 104 9 101

a=ptime:20

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:18 G729/8000

a=rtpmap:104 AMR/8000

a=fmtp:104 mode-change-capability=2;max-red=220

a=rtpmap:9 G722/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

[0m [0;32mI (465783) SIP: [1970-01-01/00:07:22]<<======================[0m [0;32mI (465793) SIP: Expires = 60000[0m [0;32mI (465793) SIP: Remote RTP port=10032[0m [0;32mI (465793) SIP: Remote RTP addr=192.168.2.218 [0m [0;32mI (465803) SIP: call from 200[0m [0;32mI (465803) SIP: [1970-01-01/00:07:22]=======WRITE 0413 bytes>>[0m [0;32mI (465813) SIP:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447217881644721337

Contact: sip:[email protected]:14173

From: sip:200@localhost:5060;tag=16447217881644721336-1

To: sip:100@localhost;tag=1497512861

Call-ID: [email protected]

CSeq: 100240 INVITE

Server: ESP32 SIP/2.0

Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO

Content-Length: 0

[0m [0;32mI (465853) SIP: [1970-01-01/00:07:22]=======================>>[0m [0;32mI (465863) SIP: [1970-01-01/00:07:22]=======WRITE 0415 bytes>>[0m [0;32mI (465863) SIP:

SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 192.168.2.218:5060;rport;branch=z9hG4bK-63735993616447217881644721337

Contact: sip:[email protected]:14173

From: sip:200@localhost:5060;tag=16447217881644721336-1

To: sip:100@localhost;tag=-2013978980

Call-ID: [email protected]

CSeq: 100240 INVITE

Server: ESP32 SIP/2.0

Allow: ACK, INVITE, BYE, UPDATE, CANCEL, OPTIONS, INFO

Content-Length: 0

[0m [0;32mI (465903) SIP: [1970-01-01/00:07:22]=======================>>[0m [0;33mW (465913) SIP: CHANGE STATE FROM 2, TO 16, :func: _sip_uas_process_req_invite:868[0m [0;32mI (467023) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (467023) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;32mI (467023) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0[0m [0;32mI (467023) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffaf7a4[0m [0;32mI (467033) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:771, MEM Total:4262019 Bytes, Inter:237651 Bytes, Dram:203199 Bytes

[0m [0;32mI (467043) ESP_AUDIO_TASK: It's a decoder[0m [0;32mI (467053) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[16000],OUT:[OUT_iis],rate:0,ch:0,pos:0[0m [0;32mI (467063) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804e90,CODEC:0x3f80501c,FILTER:0x3f818014,OUT:0x3f8051c4[0m [0;32mI (467073) AUDIO_PIPELINE: audio_pipeline_unlinked[0m [0;33mW (467073) AUDIO_PIPELINE: There are no listener registered[0m [0;32mI (467083) ESP_AUDIO_TASK: 2.2 Update all pipeline[0m [0;32mI (467083) ESP_AUDIO_TASK: 2.3 Linked new pipeline[0m [0;32mI (467093) AUDIO_PIPELINE: link el->rb, el:0x3f804e90, tag:IN_flash, rb:0x3f81eeb4[0m [0;32mI (467103) AUDIO_PIPELINE: link el->rb, el:0x3f80501c, tag:DEC_mp3, rb:0x3f81eef0[0m [0;32mI (467113) AUDIO_PIPELINE: link el->rb, el:0x3f818014, tag:Audio_forge, rb:0x3f81ef2c[0m [0;32mI (467113) ESP_AUDIO_TASK: 3. Previous starting...[0m [0;32mI (467123) AUDIO_ELEMENT: [IN_flash-0x3f804e90] Element task created[0m [0;32mI (467133) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (467133) TONE_PARTITION: tone partition format 0, total 21[0m [0;32mI (467133) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory[0m [0;32mI (467143) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8 [0m [0;32mI (467153) AUDIO_ELEMENT: [DEC_mp3-0x3f80501c] Element task created[0m [0;32mI (467163) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (467173) MP3_DECODER: MP3 opened[0m [0;32mI (467173) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO[0m [0;32mI (467183) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (467193) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (467203) ESP_AUDIO_TASK: Received muisc info then on play[0m [0;32mI (467213) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0[0m [0;32mI (467223) AUDIO_THREAD: The Audio_forge task allocate stack on external memory[0m [0;32mI (467223) AUDIO_ELEMENT: [Audio_forge-0x3f818014] Element task created[0m [0;32mI (467233) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (467243) AUDIO_FORGE: audio_forge opened[0m [0;32mI (467243) AUDIO_THREAD: The OUT_iis task allocate stack on external memory[0m [0;32mI (467253) AUDIO_ELEMENT: [OUT_iis-0x3f8051c4] Element task created[0m [0;32mI (467263) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (467263) I2S_STREAM: AUDIO_STREAM_WRITER[0m [0;32mI (467263) ESP_AUDIO_CTRL: Exit play procedure, ret:0[0m [0;32mI (467263) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (467293) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (467303) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0[0m [0;32mI (467313) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4189939 Bytes, Inter:233119 Bytes, Dram:198667 Bytes

[0m [0;32mI (468083) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (468083) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (468083) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;33mW (468983) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018[0m [0;32mI (468983) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0[0m [0;32mI (468983) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (469083) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (469083) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (469083) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (469863) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2[0m [0;32mI (470083) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (470083) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (470083) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (470083) MP3_DECODER: Closed[0m [0;32mI (470093) ESP_AUDIO_TASK: Received last pos: 36018 bytes[0m [0;32mI (470093) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (470163) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2[0m [0;32mI (470163) AUDIO_FORGE: audio forge closed[0m [0;32mI (470163) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (470293) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2[0m [0;32mI (470403) ESP_AUDIO_TASK: Received last time: 3056 ms[0m [0;32mI (470403) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (470413) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0[0m [0;32mI (470423) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4239651 Bytes, Inter:233203 Bytes, Dram:198751 Bytes

[0m [0;33mW (470433) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED[0m [0;33mW (470433) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED[0m [0;32mI (471183) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (471183) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;32mI (471183) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0[0m [0;32mI (471183) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffaf7a4[0m [0;32mI (471193) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:771, MEM Total:4262019 Bytes, Inter:237651 Bytes, Dram:203199 Bytes

[0m [0;32mI (471203) ESP_AUDIO_TASK: It's a decoder[0m [0;32mI (471213) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[16000],OUT:[OUT_iis],rate:0,ch:0,pos:0[0m [0;32mI (471223) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804e90,CODEC:0x3f80501c,FILTER:0x3f818014,OUT:0x3f8051c4[0m [0;32mI (471233) AUDIO_PIPELINE: audio_pipeline_unlinked[0m [0;33mW (471233) AUDIO_PIPELINE: There are no listener registered[0m [0;32mI (471243) ESP_AUDIO_TASK: 2.2 Update all pipeline[0m [0;32mI (471243) ESP_AUDIO_TASK: 2.3 Linked new pipeline[0m [0;32mI (471253) AUDIO_PIPELINE: link el->rb, el:0x3f804e90, tag:IN_flash, rb:0x3f81eeb4[0m [0;32mI (471263) AUDIO_PIPELINE: link el->rb, el:0x3f80501c, tag:DEC_mp3, rb:0x3f81eef0[0m [0;32mI (471273) AUDIO_PIPELINE: link el->rb, el:0x3f818014, tag:Audio_forge, rb:0x3f81ef2c[0m [0;32mI (471273) ESP_AUDIO_TASK: 3. Previous starting...[0m [0;32mI (471283) AUDIO_ELEMENT: [IN_flash-0x3f804e90] Element task created[0m [0;32mI (471293) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (471293) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory[0m [0;32mI (471293) TONE_PARTITION: tone partition format 0, total 21[0m [0;32mI (471303) AUDIO_ELEMENT: [DEC_mp3-0x3f80501c] Element task created[0m [0;32mI (471313) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8 [0m [0;32mI (471313) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (471333) MP3_DECODER: MP3 opened[0m [0;32mI (471333) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO[0m [0;32mI (471343) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (471353) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (471363) ESP_AUDIO_TASK: Received muisc info then on play[0m [0;32mI (471373) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0[0m [0;32mI (471383) AUDIO_THREAD: The Audio_forge task allocate stack on external memory[0m [0;32mI (471383) AUDIO_ELEMENT: [Audio_forge-0x3f818014] Element task created[0m [0;32mI (471393) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (471403) AUDIO_FORGE: audio_forge opened[0m [0;32mI (471403) AUDIO_THREAD: The OUT_iis task allocate stack on external memory[0m [0;32mI (471413) AUDIO_ELEMENT: [OUT_iis-0x3f8051c4] Element task created[0m [0;32mI (471423) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (471423) I2S_STREAM: AUDIO_STREAM_WRITER[0m [0;32mI (471423) ESP_AUDIO_CTRL: Exit play procedure, ret:0[0m [0;32mI (471423) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (471453) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (471463) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0[0m [0;32mI (471473) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4189939 Bytes, Inter:233119 Bytes, Dram:198667 Bytes

[0m [0;32mI (472243) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (472243) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (472243) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;33mW (473113) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018[0m [0;32mI (473113) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0[0m [0;32mI (473113) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (473243) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (473243) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (473243) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (474023) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2[0m [0;32mI (474233) MP3_DECODER: Closed[0m [0;32mI (474233) ESP_AUDIO_TASK: Received last pos: 36018 bytes[0m [0;32mI (474233) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (474243) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (474253) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (474253) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (474283) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2[0m [0;32mI (474283) AUDIO_FORGE: audio forge closed[0m [0;32mI (474283) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (474433) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2[0m [0;32mI (474543) ESP_AUDIO_TASK: Received last time: 3056 ms[0m [0;32mI (474543) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (474553) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0[0m [0;32mI (474563) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4239651 Bytes, Inter:233203 Bytes, Dram:198751 Bytes

[0m [0;33mW (474573) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED[0m [0;33mW (474583) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED[0m [0;32mI (475263) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (475263) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;32mI (475263) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0[0m [0;32mI (475263) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffaf7a4[0m [0;32mI (475273) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:771, MEM Total:4262019 Bytes, Inter:237651 Bytes, Dram:203199 Bytes

[0m [0;32mI (475283) ESP_AUDIO_TASK: It's a decoder[0m [0;32mI (475293) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[16000],OUT:[OUT_iis],rate:0,ch:0,pos:0[0m [0;32mI (475303) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804e90,CODEC:0x3f80501c,FILTER:0x3f818014,OUT:0x3f8051c4[0m [0;32mI (475313) AUDIO_PIPELINE: audio_pipeline_unlinked[0m [0;33mW (475313) AUDIO_PIPELINE: There are no listener registered[0m [0;32mI (475323) ESP_AUDIO_TASK: 2.2 Update all pipeline[0m [0;32mI (475323) ESP_AUDIO_TASK: 2.3 Linked new pipeline[0m [0;32mI (475333) AUDIO_PIPELINE: link el->rb, el:0x3f804e90, tag:IN_flash, rb:0x3f81eeb4[0m [0;32mI (475343) AUDIO_PIPELINE: link el->rb, el:0x3f80501c, tag:DEC_mp3, rb:0x3f81eef0[0m [0;32mI (475353) AUDIO_PIPELINE: link el->rb, el:0x3f818014, tag:Audio_forge, rb:0x3f81ef2c[0m [0;32mI (475353) ESP_AUDIO_TASK: 3. Previous starting...[0m [0;32mI (475363) AUDIO_ELEMENT: [IN_flash-0x3f804e90] Element task created[0m [0;32mI (475373) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (475373) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory[0m [0;32mI (475373) TONE_PARTITION: tone partition format 0, total 21[0m [0;32mI (475383) AUDIO_ELEMENT: [DEC_mp3-0x3f80501c] Element task created[0m [0;32mI (475393) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8 [0m [0;32mI (475393) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (475413) MP3_DECODER: MP3 opened[0m [0;32mI (475413) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO[0m [0;32mI (475423) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (475433) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (475443) ESP_AUDIO_TASK: Received muisc info then on play[0m [0;32mI (475453) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0[0m [0;32mI (475463) AUDIO_THREAD: The Audio_forge task allocate stack on external memory[0m [0;32mI (475463) AUDIO_ELEMENT: [Audio_forge-0x3f818014] Element task created[0m [0;32mI (475473) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (475483) AUDIO_FORGE: audio_forge opened[0m [0;32mI (475483) AUDIO_THREAD: The OUT_iis task allocate stack on external memory[0m [0;32mI (475493) AUDIO_ELEMENT: [OUT_iis-0x3f8051c4] Element task created[0m [0;32mI (475503) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (475503) I2S_STREAM: AUDIO_STREAM_WRITER[0m [0;32mI (475503) ESP_AUDIO_CTRL: Exit play procedure, ret:0[0m [0;32mI (475503) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (475533) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (475543) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0[0m [0;32mI (475553) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4189939 Bytes, Inter:233119 Bytes, Dram:198667 Bytes

[0m [0;32mI (476323) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (476323) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (476323) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;33mW (477213) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018[0m [0;32mI (477213) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0[0m [0;32mI (477213) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (477323) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (477323) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (477323) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (478093) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2[0m [0;32mI (478313) MP3_DECODER: Closed[0m [0;32mI (478313) ESP_AUDIO_TASK: Received last pos: 36018 bytes[0m [0;32mI (478313) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (478323) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (478333) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (478343) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (478393) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2[0m [0;32mI (478393) AUDIO_FORGE: audio forge closed[0m [0;32mI (478393) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (478523) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2[0m [0;32mI (478633) ESP_AUDIO_TASK: Received last time: 3056 ms[0m [0;32mI (478633) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (478643) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:0[0m [0;32mI (478653) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4239651 Bytes, Inter:233203 Bytes, Dram:198751 Bytes

[0m [0;33mW (478663) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED[0m [0;33mW (478673) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED[0m [0;32mI (479343) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (479343) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;32mI (479343) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0[0m [0;32mI (479343) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3ffaf7a4[0m [0;32mI (479353) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:771, MEM Total:4262019 Bytes, Inter:237651 Bytes, Dram:203199 Bytes

[0m [0;32mI (479363) ESP_AUDIO_TASK: It's a decoder[0m [0;32mI (479373) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[16000],OUT:[OUT_iis],rate:0,ch:0,pos:0[0m [0;32mI (479383) ESP_AUDIO_TASK: 2.Handles,IN:0x3f804e90,CODEC:0x3f80501c,FILTER:0x3f818014,OUT:0x3f8051c4[0m [0;32mI (479393) AUDIO_PIPELINE: audio_pipeline_unlinked[0m [0;33mW (479393) AUDIO_PIPELINE: There are no listener registered[0m [0;32mI (479403) ESP_AUDIO_TASK: 2.2 Update all pipeline[0m [0;32mI (479403) ESP_AUDIO_TASK: 2.3 Linked new pipeline[0m [0;32mI (479413) AUDIO_PIPELINE: link el->rb, el:0x3f804e90, tag:IN_flash, rb:0x3f81eeb4[0m [0;32mI (479423) AUDIO_PIPELINE: link el->rb, el:0x3f80501c, tag:DEC_mp3, rb:0x3f81eef0[0m [0;32mI (479433) AUDIO_PIPELINE: link el->rb, el:0x3f818014, tag:Audio_forge, rb:0x3f81ef2c[0m [0;32mI (479433) ESP_AUDIO_TASK: 3. Previous starting...[0m [0;32mI (479443) AUDIO_ELEMENT: [IN_flash-0x3f804e90] Element task created[0m [0;32mI (479453) AUDIO_ELEMENT: [IN_flash] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (479453) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory[0m [0;32mI (479453) TONE_PARTITION: tone partition format 0, total 21[0m [0;32mI (479463) AUDIO_ELEMENT: [DEC_mp3-0x3f80501c] Element task created[0m [0;32mI (479473) TONE_STREAM: Tone offset:00020c78, Tone length:36018, pos:8 [0m [0;32mI (479473) AUDIO_ELEMENT: [DEC_mp3] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (479493) MP3_DECODER: MP3 opened[0m [0;32mI (479493) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO[0m [0;32mI (479503) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (479513) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (479523) ESP_AUDIO_TASK: Received muisc info then on play[0m [0;32mI (479533) ESP_AUDIO_TASK: On event play, status:UNKNOWN, 0[0m [0;32mI (479543) AUDIO_THREAD: The Audio_forge task allocate stack on external memory[0m [0;32mI (479543) AUDIO_ELEMENT: [Audio_forge-0x3f818014] Element task created[0m [0;32mI (479553) AUDIO_ELEMENT: [Audio_forge] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (479563) AUDIO_FORGE: audio_forge opened[0m [0;32mI (479563) AUDIO_THREAD: The OUT_iis task allocate stack on external memory[0m [0;32mI (479573) AUDIO_ELEMENT: [OUT_iis-0x3f8051c4] Element task created[0m [0;32mI (479583) AUDIO_ELEMENT: [OUT_iis] AEL_MSG_CMD_RESUME,state:1[0m [0;32mI (479583) I2S_STREAM: AUDIO_STREAM_WRITER[0m [0;32mI (479583) ESP_AUDIO_CTRL: Exit play procedure, ret:0[0m [0;32mI (479583) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (479613) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_RUNNING[0m [0;32mI (479623) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_RUNNING, 0, src:0, is_stopping:0[0m [0;32mI (479633) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4189939 Bytes, Inter:233119 Bytes, Dram:198667 Bytes

[0m [0;32mI (480403) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (480403) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (480403) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;33mW (481303) TONE_STREAM: No more data,ret:0 ,info.byte_pos:36018[0m [0;32mI (481303) AUDIO_ELEMENT: IN-[IN_flash] AEL_IO_DONE,0[0m [0;32mI (481303) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (481403) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (481403) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (481403) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (482183) AUDIO_ELEMENT: IN-[DEC_mp3] AEL_IO_DONE,-2[0m [0;32mI (482403) VOIP_EXAMPLE: ringing... RemotePhoneNum 200[0m [0;32mI (482403) ESP_AUDIO_CTRL: Enter play procedure, src:0[0m [0;33mW (482403) ESP_AUDIO_CTRL: Pipeline is already running. Please stop it first.[0m [0;32mI (482403) MP3_DECODER: Closed[0m [0;32mI (482413) ESP_AUDIO_TASK: Received last pos: 36018 bytes[0m [0;32mI (482413) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (482473) AUDIO_ELEMENT: IN-[Audio_forge] AEL_IO_DONE,-2[0m [0;32mI (482483) AUDIO_FORGE: audio forge closed[0m [0;32mI (482483) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;32mI (482613) AUDIO_ELEMENT: IN-[OUT_iis] AEL_IO_DONE,-2[0m [0;32mI (482683) VOIP_EXAMPLE: [ * ] [Play] input key event[0m [0;32mI (482683) ESP_AUDIO_CTRL: Enter media_ctrl_stop procedure, src:0, type:0, st:RUNNING, phase:4[0m [0;32mI (482683) ESP_AUDIO_CTRL: media_ctrl_stop, type:0, st:RUNNING, block_playing:1, MEDIA_CTRL_PHASE_WAIT_RUNNING[0m [0;32mI (482693) ESP_AUDIO_CTRL: media_ctrl_stop, src:0, type:0, st:RUNNING, phase:4[0m [0;32mI (482703) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:2, Available:5, que:0x3ffaf7a4[0m [0;32mI (482713) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP, status:RUNNING[0m [0;31mE (482713) AUDIO_ELEMENT: [IN_flash] Element already stopped[0m [0;31mE (482723) AUDIO_ELEMENT: [DEC_mp3] Element already stopped[0m [0;31mE (482723) AUDIO_ELEMENT: [OUT_iis] Element already stopped[0m [0;31mE (482733) AUDIO_ELEMENT: [Audio_forge] Element already stopped[0m [0;32mI (482743) ESP_AUDIO_TASK: MEDIA_CTRL_EVT_STOP Done, dat:0[0m [0;32mI (482743) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED[0m [0;33mW (482763) AUDIO_PIPELINE: Check AEL STOPPED, pl:0x3f803d2c, el:0x3f80501c, tag: DEC_mp3, state:15, wanted:14[0m [0;33mW (482773) AUDIO_PIPELINE: Check AEL STOPPED, pl:0x3f803d2c, el:0x3f818014, tag: Audio_forge, state:15, wanted:14[0m [0;32mI (482783) ESP_AUDIO_TASK: Received last time: 3056 ms[0m [0;32mI (482783) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_FINISHED[0m [0;33mW (482803) AUDIO_PIPELINE: Check AEL FINISHED, pl:0x3f803d2c, el:0x3f804e90, tag: IN_flash, state:14, wanted:15[0m [0;32mI (482813) ESP_AUDIO_TASK: ESP_AUDIO status is AEL_STATUS_STATE_FINISHED, 0, src:0, is_stopping:1[0m [0;32mI (482823) ESP_AUDIO_TASK: Func:media_ctrl_task, Line:984, MEM Total:4239651 Bytes, Inter:233203 Bytes, Dram:198751 Bytes

[0m [0;33mW (482833) ESP_AUDIO_TASK: Destroy the old pipeline, FINISHED[0m [0;33mW (482833) ESP_AUDIO_TASK: The old pipeline destroyed, FINISHED[0m [0;32mI (482843) ESP_AUDIO_TASK: Release the seeking process due to stopping[0m [0;32mI (482843) ESP_AUDIO_CTRL: Exit media_ctrl_stop procedure, ret:0[0m [0;32mI (482853) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3f80501c] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED[0m [0;32mI (482873) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3f8051c4] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED[0m [0;32mI (482883) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3f818014] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED[0m [0;32mI (482893) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3f804e90] MSG,type:20000,cmd:8,len:4

Feb 13 '22 04:02 davidHuW

Hi @davidHuW

I don't see where the offline appears, because the INVITE message is still received at the end.

But I also saw some abnormal logs, such as 403 Forbidden message when the ESP client sent the option to the server, so I guess your server doesn't support OPTION message, you can close it by setting send_options = false in sip_config_t.

hope it works for you.

Feb 15 '22 06:02 ahhfzhang

hi @ahhfzhang i have tried some three sip server include myvoipserver,yate and om20(one ipbx server) , event if i don't do anything about esp32 after it registed on the sip server , the esp client will drop the connection from sip server, but the heart message is still send and the sip server ack is ok, it is strange . hope some help. thanks.

Feb 15 '22 07:02 Bruce213

Hi @davidHuW

You can try Freeswitch, the yate server sometimes responds too slow.

br.

Feb 15 '22 08:02 ahhfzhang

@davidHuW So appreciate any progress update from your side.

Aug 09 '22 02:08 jason-mao