search

espressif / esp-adf

Espressif Audio Development Framework
Other
1.55k stars 688 forks source link

我使用ESP32-S3-Korvo-2板子使用esp-rtc例程与esp-rtc的客户端进行通信，手机端无法收到esp32的声音，并且一段时间会自动挂断 (AUD-5802) #1299

Open monskate opened 2 weeks ago

monskate commented 2 weeks ago

----------------------------- ESP Audio Platform ----------------------------- | | | ESP_AUDIO-v1.7.3-40-g00ffda8-dbab925-9815982 | | Compile date: Apr 12 2024-02:34:27 |

I (3180) ESP_AUDIO_CTRL: Func:media_ctrl_create, Line:355, MEM Total:6502652 Bytes, Inter:211155 Bytes, Dram:211155 Bytes, Dram largest free:135168Bytes

 I (3194) MP3_DECODER: MP3 init W (3198) I2S_STREAM_IDF5.x: I2S(2) already startup I (3203) ESP_RTC_Demo: [ 4 ] Initialize rtc service I (3209) ESP_RTC_CORE: esp_rtc ver: 1.1.0 compile date: Feb 5 2024-12:25:35  I (3217) SIP: Conecting... W (3220) SIP: CHANGE STATE FROM 0, TO 1, :func: sip_connect:1835 I (3228) SIP: [1970-01-01/00:00:01]=======WRITE 0602 bytes>> I (3233) SIP:

REGISTER sip:192.168.10.108:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.10.127:11082;branch=z9hG4bK-1783780767;rport

From: sip:1005@192.168.10.108:5060;tag=-1844547210

To: sip:1005@192.168.10.108:5060

Contact: sip:1005@192.168.10.127:11082;transport=UDP

Max-Forwards: 70

Call-ID: FF9DF909A7215939E3E29B9BBF45518BDB2BAA736106

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

 I (3289) SIP: [1970-01-01/00:00:01]=======================>> I (3220) ESP_RTC_CORE: esp_rtc_init done I (3439) wifi:idx:1 (ifx:0, 06:b8:e6:d8:14:d4), tid:0, ssn:0, winSize:64 I (3441) SIP: [1970-01-01/00:00:01]<<=====READ 0638 bytes== I (3441) SIP:

SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 192.168.10.127:11082;branch=z9hG4bK-1783780767;rport=11082

From: sip:1005@192.168.10.108:5060;tag=-1844547210

To: sip:1005@192.168.10.108:5060;tag=942c776QBBmXc

Call-ID: FF9DF909A7215939E3E29B9BBF45518BDB2BAA736106

CSeq: 1 REGISTER

User-Agent: FreeSWITCH-mod_sofia/1.10.12-release~a88d069d6f~64bit

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

Supported: timer, path, replaces

WWW-Authenticate: Digest realm="192.168.10.108", nonce="acb93e84-61e1-49fb-9579-3c30d299b9fd", algorithm=MD5, qop="auth"

Content-Length: 0

 I (3501) SIP: [1970-01-01/00:00:01]<<====================== I (3507) SIP: Required authentication I (3512) SIP: [1970-01-01/00:00:01]=======WRITE 0852 bytes>> I (3518) SIP:

REGISTER sip:192.168.10.108:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.10.127:11082;branch=z9hG4bK-2047138839;rport

From: sip:1005@192.168.10.108:5060;tag=-1463766708

To: sip:1005@192.168.10.108:5060

Contact: sip:1005@192.168.10.127:11082;transport=UDP

Max-Forwards: 70

Call-ID: FF9DF909A7215939E3E29B9BBF45518BDB2BAA736106

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="1005", realm="192.168.10.108", nonce="acb93e84-61e1-49fb-9579-3c30d299b9fd", uri="sip:192.168.10.108:5060", response="ebd11889c870be5a436ce90bab4ea877", algorithm=MD5, qop=auth, nc=00000001, cnonce="19735239e03fc126"

 I (3596) SIP: [1970-01-01/00:00:01]=======================>> I (3639) SIP: [1970-01-01/00:00:01]<<=====READ 0612 bytes== I (3639) SIP:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 192.168.10.127:11082;branch=z9hG4bK-2047138839;rport=11082

From: sip:1005@192.168.10.108:5060;tag=-1463766708

To: sip:1005@192.168.10.108:5060;tag=aev582QU8Kagr

Call-ID: FF9DF909A7215939E3E29B9BBF45518BDB2BAA736106

CSeq: 2 REGISTER

Contact: sip:1005@192.168.10.127:11082;transport=UDP;expires=3600

Date: Sun, 27 Oct 2024 08:10:48 GMT

User-Agent: FreeSWITCH-mod_sofia/1.10.12-release~a88d069d6f~64bit

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

Supported: timer, path, replaces

Content-Length: 0

 I (3692) SIP: [1970-01-01/00:00:01]<<====================== I (3698) RTC_SERVICE: ESP_RTC_EVENT_REGISTERED I (3703) ESP_AUDIO_CTRL: Enter play procedure, src:0 I (3709) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0 I (3715) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3fcc73dc I (3723) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:776, MEM Total:6474424 Bytes, Inter:208363 Bytes, Dram:208363 Bytes, Dram largest free:135168Bytes

 I (3737) ESP_AUDIO_TASK: It's a decoder I (3741) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0 I (3752) ESP_AUDIO_TASK: 2.Handles,IN:0x3c1fd6b8,CODEC:0x3c1fd840,FILTER:0x3c202f08,OUT:0x3c1fdadc I (3761) ESP_AUDIO_TASK: 2.2 Update all pipeline I (3766) ESP_AUDIO_TASK: 2.3 Linked new pipeline I (3772) AUDIO_PIPELINE: link el->rb, el:0x3c1fd6b8, tag:IN_flash, rb:0x3c203d44 I (3780) AUDIO_PIPELINE: link el->rb, el:0x3c1fd840, tag:DEC_mp3, rb:0x3c20658c I (3788) AUDIO_PIPELINE: link el->rb, el:0x3c202f08, tag:Audio_forge, rb:0x3c2085d4 I (3796) ESP_AUDIO_TASK: 3. Previous starting... I (3802) AUDIO_THREAD: The IN_flash task allocate stack on internal memory I (3809) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory E (3809) TONE_PARTITION: Not flash tone partition I (3817) MP3_DECODER: MP3 opened I (3817) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO E (3826) AUDIO_ELEMENT: [IN_flash] AEL_STATUS_ERROR_OPEN,-1 I (3840) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3c1fd6b8] MSG,type:20000,cmd:8,len:4,status:ESP_ERR_AUDIO_OPEN E (3840) TONE_PARTITION: tone_partition.c:205 (tone_partition_deinit): Got NULL Pointer E (3851) ESP_AUDIO_TASK: Got element[IN_flash] error:ESP_ERR_AUDIO_OPEN, stop pipeline[0] E (3860) MP3_DECODER: Failed to read audio data (line 129) I (3875) MP3_DECODER: Closed W (3878) ESP_AUDIO_TASK: Destroy the old pipeline, ELEMENT OCCUR ERROR W (3886) ESP_AUDIO_TASK: The old pipeline destroyed, ELEMENT OCCUR ERROR I (3893) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3c1fd6b8] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (3894) ESP_AUDIO_CTRL: Exit play procedure, ret:81100 W (3910) SIP: CHANGE STATE FROM 1, TO 2, :func: sip_register:1863 I (3917) SIP: [1970-01-01/00:00:01]<<=====READ 0960 bytes== I (3923) SIP:

NOTIFY sip:1005@192.168.10.127:11082;transport=UDP SIP/2.0

Via: SIP/2.0/UDP 58.56.10.220;rport;branch=z9hG4bK4Najjjaa623SH

Max-Forwards: 70

From: sip:1005@192.168.10.108;tag=BQNyay8y5v02K

To: sip:1005@192.168.10.108

Call-ID: d0813c9f-0edd-123e-87bc-01c9625d8141

CSeq: 90454532 NOTIFY

Contact: sip:mod_sofia@58.56.10.220:5060

User-Agent: FreeSWITCH-mod_sofia/1.10.12-release~a88d069d6f~64bit

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

Supported: timer, path, replaces

Event: message-summary

Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer

Subscription-State: terminated;reason=noresource

Content-Type: application/simple-message-summary

Content-Length: 93

Messages-Waiting: yes

Message-Account: sip:1005@192.168.10.108

Voice-Message: 1/0 (0/0)

 I (4011) SIP: [1970-01-01/00:00:01]<<====================== I (4019) ESP_AUDIO_TASK: Received last pos: 0 bytes I (4023) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3c1fd840] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (4034) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3c1fd840] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (4045) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3c1fd6b8] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (4057) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3c1fd840] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (4068) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3c202f08] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (4079) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3c1fdadc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED

I (4303) AUDIO_SYS: | Task | Run Time | Per | Prio | HWM | State | CoreId | Stack  I (4303) AUDIO_SYS: | main | 1751 | 0% | 1 | 1184 | Running | 0 | Intr I (4313) AUDIO_SYS: | IDLE | 964230 |48% | 0 | 696 | Ready | 1 | Intr I (4323) AUDIO_SYS: | IDLE | 401740 |20% | 0 | 692 | Ready | 0 | Intr I (4333) AUDIO_SYS: | button_task | 12857 | 0% | 10 | 1356 | Blocked | 0 | Extr I (4344) AUDIO_SYS: | tiT | 2185 | 0% | 18 | 1888 | Blocked | 7fffffff | Intr I (4354) AUDIO_SYS: | sip_task | 370744 |18% | 20 | 8052 | Blocked | 0 | Extr I (4364) AUDIO_SYS: | esp_periph | 1284 | 0% | 5 | 1624 | Blocked | 0 | Intr I (4375) AUDIO_SYS: | ipc1 | 0 | 0% | 24 | 552 | Blocked | 1 | Intr I (4385) AUDIO_SYS: | ipc0 | 0 | 0% | 1 | 500 | Blocked | 0 | Intr I (4395) AUDIO_SYS: | sys_evt | 0 | 0% | 20 | 1312 | Blocked | 0 | Intr I (4406) AUDIO_SYS: | input_key_servi | 0 | 0% | 5 | 3268 | Blocked | 1 | Extr I (4416) AUDIO_SYS: | media_task | 202612 |10% | 6 | 1840 | Blocked | 0 | Extr I (4426) AUDIO_SYS: | esp_timer | 636 | 0% | 22 | 3100 | Suspended | 0 | Intr I (4437) AUDIO_SYS: | wifi | 8555 | 0% | 23 | 3820 | Blocked | 0 | Intr I (4447) AUDIO_SYS: | cam_task | 1180 | 0% | 23 | 2304 | Blocked | 0 | Intr I (4457) AUDIO_SYS: | Tmr Svc | 0 | 0% | 1 | 1324 | Blocked | 0 | Intr

I (4468) ESP_RTC_Demo: Func:app_main, Line:170, MEM Total:6447272 Bytes, Inter:206847 Bytes, Dram:206847 Bytes, Dram largest free:131072Bytes



I (4730) SIP: [1970-01-01/00:00:01]<<=====READ 0960 bytes== I (4731) SIP:

NOTIFY sip:1005@192.168.10.127:11082;transport=UDP SIP/2.0

Via: SIP/2.0/UDP 58.56.10.220;rport;branch=z9hG4bK4Najjjaa623SH

Max-Forwards: 70

From: sip:1005@192.168.10.108;tag=BQNyay8y5v02K

To: sip:1005@192.168.10.108

Call-ID: d0813c9f-0edd-123e-87bc-01c9625d8141

CSeq: 90454532 NOTIFY

Contact: sip:mod_sofia@58.56.10.220:5060

User-Agent: FreeSWITCH-mod_sofia/1.10.12-release~a88d069d6f~64bit

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

Supported: timer, path, replaces

Event: message-summary

Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer

Subscription-State: terminated;reason=noresource

Content-Type: application/simple-message-summary

Content-Length: 93

Messages-Waiting: yes

Message-Account: sip:1005@192.168.10.108

Voice-Message: 1/0 (0/0)

 I (4814) SIP: [1970-01-01/00:00:01]<<======================

I (6672) SIP: [1970-01-01/00:00:02]<<=====READ 0960 bytes== I (6673) SIP:

NOTIFY sip:1005@192.168.10.127:11082;transport=UDP SIP/2.0

Via: SIP/2.0/UDP 58.56.10.220;rport;branch=z9hG4bK4Najjjaa623SH

Max-Forwards: 70

From: sip:1005@192.168.10.108;tag=BQNyay8y5v02K

To: sip:1005@192.168.10.108

Call-ID: d0813c9f-0edd-123e-87bc-01c9625d8141

CSeq: 90454532 NOTIFY

Contact: sip:mod_sofia@58.56.10.220:5060

User-Agent: FreeSWITCH-mod_sofia/1.10.12-release~a88d069d6f~64bit

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

Supported: timer, path, replaces

Event: message-summary

Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer

Subscription-State: terminated;reason=noresource

Content-Type: application/simple-message-summary

Content-Length: 93

Messages-Waiting: yes

Message-Account: sip:1005@192.168.10.108

Voice-Message: 1/0 (0/0)

 I (6756) SIP: [1970-01-01/00:00:02]<<======================

I (10667) SIP: [1970-01-01/00:00:04]<<=====READ 0960 bytes== I (10667) SIP:

NOTIFY sip:1005@192.168.10.127:11082;transport=UDP SIP/2.0

Via: SIP/2.0/UDP 58.56.10.220;rport;branch=z9hG4bK4Najjjaa623SH

Max-Forwards: 70

From: sip:1005@192.168.10.108;tag=BQNyay8y5v02K

To: sip:1005@192.168.10.108

Call-ID: d0813c9f-0edd-123e-87bc-01c9625d8141

CSeq: 90454532 NOTIFY

Contact: sip:mod_sofia@58.56.10.220:5060

User-Agent: FreeSWITCH-mod_sofia/1.10.12-release~a88d069d6f~64bit

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

Supported: timer, path, replaces

Event: message-summary

Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer

Subscription-State: terminated;reason=noresource

Content-Type: application/simple-message-summary

Content-Length: 93

Messages-Waiting: yes

Message-Account: sip:1005@192.168.10.108

Voice-Message: 1/0 (0/0)

 I (10750) SIP: [1970-01-01/00:00:04]<<======================

I (14659) SIP: [1970-01-01/00:00:06]<<=====READ 0960 bytes== I (14660) SIP:

NOTIFY sip:1005@192.168.10.127:11082;transport=UDP SIP/2.0

Via: SIP/2.0/UDP 58.56.10.220;rport;branch=z9hG4bK4Najjjaa623SH

Max-Forwards: 70

From: sip:1005@192.168.10.108;tag=BQNyay8y5v02K

To: sip:1005@192.168.10.108

Call-ID: d0813c9f-0edd-123e-87bc-01c9625d8141

CSeq: 90454532 NOTIFY

Contact: sip:mod_sofia@58.56.10.220:5060

User-Agent: FreeSWITCH-mod_sofia/1.10.12-release~a88d069d6f~64bit

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

Supported: timer, path, replaces

Event: message-summary

Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer

Subscription-State: terminated;reason=noresource

Content-Type: application/simple-message-summary

Content-Length: 93

Messages-Waiting: yes

Message-Account: sip:1005@192.168.10.108

Voice-Message: 1/0 (0/0)

 I (14743) SIP: [1970-01-01/00:00:06]<<======================

I (18448) SIP: [1970-01-01/00:00:08]<<=====READ 1440 bytes== I (18449) SIP:

INVITE sip:1005@192.168.10.127:11082;transport=UDP SIP/2.0

Via: SIP/2.0/UDP 58.56.10.220;rport;branch=z9hG4bK67v3N8BH0mgZr

Max-Forwards: 69

From: "Extension 1010" sip:1010@192.168.10.108;tag=KZZ4rpFUe4jrg

To: sip:1005@192.168.10.127:11082;transport=UDP

Call-ID: d950b884-0edd-123e-87bc-01c9625d8141

CSeq: 90454539 INVITE

Contact: sip:mod_sofia@58.56.10.220:5060

User-Agent: FreeSWITCH-mod_sofia/1.10.12-release~a88d069d6f~64bit

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

Supported: timer, path, replaces

Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer

Content-Type: application/sdp

Content-Disposition: session

Content-Length: 460

X-FS-Support: update_display,send_info

Remote-Party-ID: "Extension 1010" sip:1010@192.168.10.108;party=calling;screen=yes;privacy=off

v=0

o=FreeSWITCH 1729993514 1729993515 IN IP4 192.168.10.108

s=FreeSWITCH

c=IN IP4 192.168.10.108

t=0 0

m=audio 23148 RTP/AVP 8 0 9 101

a=rtpmap:8 PCMA/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:9 G722/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

a=ptime:20

m=video 20444 RTP/AVP 103

b=AS:3072

a=rtpmap:103 VP8/90000

a=fmtp:103 max-fr=30; max-fs=580

a=rtcp-fb:103 ccm fir

a=rtcp-fb:103 ccm tmmbr

a=rtcp-fb:103 nack

a=rtcp-fb:103 nack pli

 I (18575) SIP: [1970-01-01/00:00:08]<<====================== I (18582) SIP: Remote ARTP port=23148 I (18586) SIP: Remote VRTP port=20444 I (18590) SIP: Remote RTP addr=192.168.10.108  I (18595) SIP: call from 1010 I (18599) SIP: [1970-01-01/00:00:08]=======WRITE 0445 bytes>> I (18605) SIP:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 58.56.10.220;rport;branch=z9hG4bK67v3N8BH0mgZr

Contact: sip:1005@192.168.10.127:11082;transport=UDP

From: "Extension 1010" sip:1010@192.168.10.108;tag=KZZ4rpFUe4jrg

To: sip:1005@192.168.10.127:11082;transport=UDP;tag=1212128500

Call-ID: d950b884-0edd-123e-87bc-01c9625d8141

CSeq: 90454539 INVITE

Server: ESP32 SIP/2.0

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

Content-Length: 0

 I (18648) SIP: [1970-01-01/00:00:08]=======================>> I (18655) SIP: [1970-01-01/00:00:08]=======WRITE 0446 bytes>> I (18661) SIP:

SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 58.56.10.220;rport;branch=z9hG4bK67v3N8BH0mgZr

Contact: sip:1005@192.168.10.127:11082;transport=UDP

From: "Extension 1010" sip:1010@192.168.10.108;tag=KZZ4rpFUe4jrg

To: sip:1005@192.168.10.127:11082;transport=UDP;tag=2074666328

Call-ID: d950b884-0edd-123e-87bc-01c9625d8141

CSeq: 90454539 INVITE

Server: ESP32 SIP/2.0

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

Content-Length: 0

 I (18703) SIP: [1970-01-01/00:00:08]=======================>> W (18710) SIP: CHANGE STATE FROM 2, TO 16, :func: _sip_uas_process_req_invite:1003 I (18718) SIP: [1970-01-01/00:00:08]<<=====READ 0960 bytes== I (18724) SIP:

NOTIFY sip:1005@192.168.10.127:11082;transport=UDP SIP/2.0

Via: SIP/2.0/UDP 58.56.10.220;rport;branch=z9hG4bK4Najjjaa623SH

Max-Forwards: 70

From: sip:1005@192.168.10.108;tag=BQNyay8y5v02K

To: sip:1005@192.168.10.108

Call-ID: d0813c9f-0edd-123e-87bc-01c9625d8141

CSeq: 90454532 NOTIFY

Contact: sip:mod_sofia@58.56.10.220:5060

User-Agent: FreeSWITCH-mod_sofia/1.10.12-release~a88d069d6f~64bit

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

Supported: timer, path, replaces

Event: message-summary

Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer

Subscription-State: terminated;reason=noresource

Content-Type: application/simple-message-summary

Content-Length: 93

Messages-Waiting: yes

Message-Account: sip:1005@192.168.10.108

Voice-Message: 1/0 (0/0)

 I (18812) SIP: [1970-01-01/00:00:08]<<====================== I (18819) SIP: [1970-01-01/00:00:08]=======WRITE 0410 bytes>> I (18825) SIP:

SIP/2.0 486 Busy Here

Via: SIP/2.0/UDP 58.56.10.220;rport;branch=z9hG4bK4Najjjaa623SH

Contact: sip:1005@192.168.10.127:11082;transport=UDP

From: sip:1005@192.168.10.108;tag=BQNyay8y5v02K

To: sip:1005@192.168.10.108;tag=311775178

Call-ID: d0813c9f-0edd-123e-87bc-01c9625d8141

CSeq: 90454532 NOTIFY

Server: ESP32 SIP/2.0

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

Content-Length: 0

 I (18865) SIP: [1970-01-01/00:00:08]=======================>>

I (19781) RTC_SERVICE: ESP_RTC_EVENT_INCOMING... I (19781) ESP_AUDIO_CTRL: Enter play procedure, src:0 I (19781) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0 I (19787) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3fcc73dc I (19795) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:776, MEM Total:6445836 Bytes, Inter:206847 Bytes, Dram:206847 Bytes, Dram largest free:131072Bytes

 I (19809) ESP_AUDIO_TASK: It's a decoder I (19813) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0 I (19824) ESP_AUDIO_TASK: 2.Handles,IN:0x3c1fd6b8,CODEC:0x3c1fd840,FILTER:0x3c202f08,OUT:0x3c1fdadc W (19834) AUDIO_PIPELINE: Without stop, st:1 W (19839) AUDIO_PIPELINE: Without wait stop, st:1 I (19844) AUDIO_PIPELINE: audio_pipeline_unlinked W (19849) AUDIO_PIPELINE: There are no listener registered I (19856) ESP_AUDIO_TASK: 2.2 Update all pipeline I (19861) ESP_AUDIO_TASK: 2.3 Linked new pipeline I (19867) AUDIO_PIPELINE: link el->rb, el:0x3c1fd6b8, tag:IN_flash, rb:0x3c203a88 I (19875) AUDIO_PIPELINE: link el->rb, el:0x3c1fd840, tag:DEC_mp3, rb:0x3c203acc I (19883) AUDIO_PIPELINE: link el->rb, el:0x3c202f08, tag:Audio_forge, rb:0x3c203b10 I (19891) ESP_AUDIO_TASK: 3. Previous starting... I (19897) AUDIO_THREAD: The IN_flash task allocate stack on internal memory I (19904) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory E (19904) TONE_PARTITION: Not flash tone partition I (19912) MP3_DECODER: MP3 opened I (19912) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO E (19921) AUDIO_ELEMENT: [IN_flash] AEL_STATUS_ERROR_OPEN,-1 I (19936) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3c1fd6b8] MSG,type:20000,cmd:8,len:4,status:ESP_ERR_AUDIO_OPEN E (19936) TONE_PARTITION: tone_partition.c:205 (tone_partition_deinit): Got NULL Pointer E (19947) ESP_AUDIO_TASK: Got element[IN_flash] error:ESP_ERR_AUDIO_OPEN, stop pipeline[0] E (19956) MP3_DECODER: Failed to read audio data (line 129) I (19971) MP3_DECODER: Closed W (19974) ESP_AUDIO_TASK: Destroy the old pipeline, ELEMENT OCCUR ERROR W (19982) ESP_AUDIO_TASK: The old pipeline destroyed, ELEMENT OCCUR ERROR I (19989) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3c1fd6b8] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (19990) ESP_AUDIO_CTRL: Exit play procedure, ret:81100 I (20007) ESP_AUDIO_TASK: Received last pos: 0 bytes I (20012) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3c1fd840] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (20023) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3c1fd840] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (20035) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3c1fd6b8] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (20046) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3c1fd840] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (20057) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3c202f08] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (20069) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3c1fdadc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED

I (20483) AUDIO_SYS: | Task | Run Time | Per | Prio | HWM | State | CoreId | Stack  I (20483) AUDIO_SYS: | main | 1088 | 0% | 1 | 1184 | Running | 0 | Intr I (20493) AUDIO_SYS: | IDLE | 965339 |48% | 0 | 696 | Ready | 1 | Intr I (20503) AUDIO_SYS: | IDLE | 714063 |35% | 0 | 692 | Ready | 0 | Intr I (20514) AUDIO_SYS: | button_task | 18671 | 0% | 10 | 1356 | Blocked | 0 | Extr I (20524) AUDIO_SYS: | sip_task | 35503 | 1% | 20 | 7796 | Blocked | 0 | Extr I (20534) AUDIO_SYS: | tiT | 456 | 0% | 18 | 1888 | Blocked | 7fffffff | Intr I (20545) AUDIO_SYS: | esp_periph | 2044 | 0% | 5 | 1624 | Blocked | 0 | Intr I (20555) AUDIO_SYS: | sys_evt | 0 | 0% | 20 | 1312 | Blocked | 0 | Intr I (20566) AUDIO_SYS: | input_key_servi | 0 | 0% | 5 | 3268 | Blocked | 1 | Extr I (20576) AUDIO_SYS: | media_task | 224804 |11% | 6 | 1648 | Blocked | 0 | Extr I (20587) AUDIO_SYS: | Tmr Svc | 0 | 0% | 1 | 1324 | Blocked | 0 | Intr I (20597) AUDIO_SYS: | esp_timer | 181 | 0% | 22 | 3100 | Suspended | 0 | Intr I (20608) AUDIO_SYS: | wifi | 3477 | 0% | 23 | 3820 | Blocked | 0 | Intr I (20618) AUDIO_SYS: | cam_task | 1033 | 0% | 23 | 2240 | Blocked | 0 | Intr I (20628) AUDIO_SYS: | ipc1 | 0 | 0% | 24 | 552 | Blocked | 1 | Intr I (20639) AUDIO_SYS: | ipc0 | 0 | 0% | 1 | 500 | Blocked | 0 | Intr

I (20649) ESP_RTC_Demo: Func:app_main, Line:170, MEM Total:6446800 Bytes, Inter:206847 Bytes, Dram:206847 Bytes, Dram largest free:131072Bytes

 I (20815) RTC_SERVICE: ESP_RTC_EVENT_INCOMING... I (20815) ESP_AUDIO_CTRL: Enter play procedure, src:0 I (20815) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0 I (20821) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3fcc73dc I (20829) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:776, MEM Total:6446800 Bytes, Inter:206847 Bytes, Dram:206847 Bytes, Dram largest free:131072Bytes

 I (20843) ESP_AUDIO_TASK: It's a decoder I (20847) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0 I (20858) ESP_AUDIO_TASK: 2.Handles,IN:0x3c1fd6b8,CODEC:0x3c1fd840,FILTER:0x3c202f08,OUT:0x3c1fdadc W (20868) AUDIO_PIPELINE: Without stop, st:1 W (20873) AUDIO_PIPELINE: Without wait stop, st:1 I (20878) AUDIO_PIPELINE: audio_pipeline_unlinked W (20883) AUDIO_PIPELINE: There are no listener registered I (20890) ESP_AUDIO_TASK: 2.2 Update all pipeline I (20895) ESP_AUDIO_TASK: 2.3 Linked new pipeline I (20901) AUDIO_PIPELINE: link el->rb, el:0x3c1fd6b8, tag:IN_flash, rb:0x3c2031f8 I (20909) AUDIO_PIPELINE: link el->rb, el:0x3c1fd840, tag:DEC_mp3, rb:0x3c20323c I (20917) AUDIO_PIPELINE: link el->rb, el:0x3c202f08, tag:Audio_forge, rb:0x3c203280 I (20925) ESP_AUDIO_TASK: 3. Previous starting... I (20930) AUDIO_THREAD: The IN_flash task allocate stack on internal memory I (20938) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory E (20938) TONE_PARTITION: Not flash tone partition I (20946) MP3_DECODER: MP3 opened I (20946) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO E (20955) AUDIO_ELEMENT: [IN_flash] AEL_STATUS_ERROR_OPEN,-1 I (20970) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3c1fd6b8] MSG,type:20000,cmd:8,len:4,status:ESP_ERR_AUDIO_OPEN E (20970) TONE_PARTITION: tone_partition.c:205 (tone_partition_deinit): Got NULL Pointer E (20981) ESP_AUDIO_TASK: Got element[IN_flash] error:ESP_ERR_AUDIO_OPEN, stop pipeline[0] E (20990) MP3_DECODER: Failed to read audio data (line 129) I (21005) MP3_DECODER: Closed W (21008) ESP_AUDIO_TASK: Destroy the old pipeline, ELEMENT OCCUR ERROR W (21016) ESP_AUDIO_TASK: The old pipeline destroyed, ELEMENT OCCUR ERROR I (21023) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3c1fd6b8] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (21024) ESP_AUDIO_CTRL: Exit play procedure, ret:81100 I (21040) ESP_AUDIO_TASK: Received last pos: 0 bytes I (21046) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3c1fd840] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (21057) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3c1fd840] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (21068) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3c1fd6b8] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (21080) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3c1fd840] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (21091) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3c202f08] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (21103) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3c1fdadc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED

I (21848) RTC_SERVICE: ESP_RTC_EVENT_INCOMING... I (21848) ESP_AUDIO_CTRL: Enter play procedure, src:0 I (21848) ESP_AUDIO_CTRL: Play procedure, URL is ok, src:0 I (21854) ESP_AUDIO_CTRL: Request_CMD_Queue CMD:0, Available:5, que:0x3fcc73dc I (21862) ESP_AUDIO_CTRL: Func:_ctrl_play, Line:776, MEM Total:6446800 Bytes, Inter:206847 Bytes, Dram:206847 Bytes, Dram largest free:131072Bytes

 I (21876) ESP_AUDIO_TASK: It's a decoder I (21880) ESP_AUDIO_TASK: 1.CUR IN:[IN_flash],CODEC:[DEC_mp3],RESAMPLE:[8000],OUT:[OUT_iis],rate:0,ch:0,pos:0 I (21891) ESP_AUDIO_TASK: 2.Handles,IN:0x3c1fd6b8,CODEC:0x3c1fd840,FILTER:0x3c202f08,OUT:0x3c1fdadc W (21901) AUDIO_PIPELINE: Without stop, st:1 W (21906) AUDIO_PIPELINE: Without wait stop, st:1 I (21911) AUDIO_PIPELINE: audio_pipeline_unlinked W (21916) AUDIO_PIPELINE: There are no listener registered I (21923) ESP_AUDIO_TASK: 2.2 Update all pipeline I (21928) ESP_AUDIO_TASK: 2.3 Linked new pipeline I (21934) AUDIO_PIPELINE: link el->rb, el:0x3c1fd6b8, tag:IN_flash, rb:0x3c2031f8 I (21942) AUDIO_PIPELINE: link el->rb, el:0x3c1fd840, tag:DEC_mp3, rb:0x3c20323c I (21950) AUDIO_PIPELINE: link el->rb, el:0x3c202f08, tag:Audio_forge, rb:0x3c203280 I (21958) ESP_AUDIO_TASK: 3. Previous starting... I (21963) AUDIO_THREAD: The IN_flash task allocate stack on internal memory I (21971) AUDIO_THREAD: The DEC_mp3 task allocate stack on external memory E (21971) TONE_PARTITION: Not flash tone partition I (21979) MP3_DECODER: MP3 opened I (21979) ESP_AUDIO_TASK: Blocking play until received AEL_MSG_CMD_REPORT_MUSIC_INFO E (21988) AUDIO_ELEMENT: [IN_flash] AEL_STATUS_ERROR_OPEN,-1 E (22003) TONE_PARTITION: tone_partition.c:205 (tone_partition_deinit): Got NULL Pointer I (22003) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3c1fd6b8] MSG,type:20000,cmd:8,len:4,status:ESP_ERR_AUDIO_OPEN E (22012) MP3_DECODER: Failed to read audio data (line 129) E (22022) ESP_AUDIO_TASK: Got element[IN_flash] error:ESP_ERR_AUDIO_OPEN, stop pipeline[0] I (22029) MP3_DECODER: Closed W (22041) ESP_AUDIO_TASK: Destroy the old pipeline, ELEMENT OCCUR ERROR W (22049) ESP_AUDIO_TASK: The old pipeline destroyed, ELEMENT OCCUR ERROR I (22056) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3c1fd6b8] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (22057) ESP_AUDIO_CTRL: Exit play procedure, ret:81100 I (22074) ESP_AUDIO_TASK: Received last pos: 0 bytes I (22079) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3c1fd840] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (22090) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3c1fd840] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (22101) ESP_AUDIO_TASK: Recv Element[IN_flash-0x3c1fd6b8] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (22113) ESP_AUDIO_TASK: Recv Element[DEC_mp3-0x3c1fd840] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (22124) ESP_AUDIO_TASK: Recv Element[Audio_forge-0x3c202f08] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (22136) ESP_AUDIO_TASK: Recv Element[OUT_iis-0x3c1fdadc] MSG,type:20000,cmd:8,len:4,status:AEL_STATUS_STATE_STOPPED I (22154) ESP_RTC_Demo: [ * ] [Rec] answer I (22154) ESP_AUDIO_CTRL: Enter media_ctrl_stop procedure, src:0, type:0, st:ERROR, phase:0 W (22161) ESP_AUDIO_CTRL: [media_ctrl_stop]-Already stopped, status is ERROR, phase:0 I (22174) SIP: [1970-01-01/00:00:10]=======WRITE 0685 bytes>> I (22176) SIP:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 58.56.10.220;rport;branch=z9hG4bK67v3N8BH0mgZr

Contact: sip:1005@192.168.10.127:11082;transport=UDP

From: "Extension 1010" sip:1010@192.168.10.108;tag=KZZ4rpFUe4jrg

To: sip:1005@192.168.10.127:11082;transport=UDP;tag=-1234195747

Call-ID: d950b884-0edd-123e-87bc-01c9625d8141

CSeq: 90454539 INVITE

Server: ESP32 SIP/2.0

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

Content-Length: 210

Content-Type: application/sdp

v=0

o=Z 0 3 IN IP4 192.168.10.127

s=Z

t=0 0

c=IN IP4 192.168.10.127

m=audio 34432 RTP/AVP 8 101

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

m=video 23457 RTP/AVP 113

a=rtpmap:113 VP8/90000

 I (22240) SIP: [1970-01-01/00:00:10]=======================>> I (22268) SIP: [1970-01-01/00:00:10]<<=====READ 0407 bytes== I (22268) SIP:

ACK sip:1005@192.168.10.127:11082;transport=UDP SIP/2.0

Via: SIP/2.0/UDP 58.56.10.220;rport;branch=z9hG4bK7gpvQ3vmXX6Hm

Max-Forwards: 70

From: "Extension 1010" sip:1010@192.168.10.108;tag=KZZ4rpFUe4jrg

To: sip:1005@192.168.10.127:11082;transport=UDP;tag=-1234195747

Call-ID: d950b884-0edd-123e-87bc-01c9625d8141

CSeq: 90454539 ACK

Contact: sip:mod_sofia@58.56.10.220:5060

Content-Length: 0

 I (22302) SIP: [1970-01-01/00:00:10]<<====================== I (22309) RTC_SERVICE: ESP_RTC_EVENT_AUDIO_SESSION_BEGIN I (22315) AUDIO_PIPELINE: link el->rb, el:0x3c203a44, tag:algo, rb:0x3c20ad6c I (22323) AUDIO_THREAD: The algo task allocate stack on external memory I (22330) AUDIO_PIPELINE: Func:audio_pipeline_run, Line:359, MEM Total:6430376 Bytes, Inter:204563 Bytes, Dram:204563 Bytes, Dram largest free:131072Bytes

 W (22345) SR_SYS: Data cache recommends 64KB W (22349) SR_SYS: Data cache line recommends 64B I (22364) AUDIO_PIPELINE: Pipeline started I (22364) AUDIO_THREAD: The _audio_enc task allocate stack on external memory I (22368) AV_STREAM: audio_enc started I (22372) AUDIO_THREAD: The _audio_dec task allocate stack on external memory I (22374) AUDIO_THREAD: The algo_fetch task allocate stack on external memory I (22388) AV_STREAM: _audio_enc task stoped I (22392) AV_STREAM: audio_dec started I (22397) RTC_SERVICE: ESP_RTC_EVENT_VIDEO_SESSION_BEGIN I (22403) AUDIO_THREAD: The _video_enc task allocate stack on external memory I (22419) ALGORITHM_STREAM: _algo_fetch_task is stopped W (22431) SIP: CHANGE STATE FROM 16, TO 32, :func: sip_uas_process_req:1078

W (32001) AV_STREAM: audio decoder ringbuf write timeout

I (33745) SIP: Sending keep-alive to server

monskate commented 2 weeks ago

Screenshot_20241027_144059

TempoTian commented 2 weeks ago

请帮忙提供下具体的ADF版本信息，最新修复了一个因为I2S stream升级导致的读数据卡住的问题，可以检查下你的版本有没有修复。 https://github.com/espressif/esp-adf/commit/41f94ef398e63897bf4f7074a48456b5eb1b737e

monskate commented 2 weeks ago

请帮忙提供下具体的ADF版本信息，最新修复了一个因为I2S stream升级导致的读数据卡住的问题，可以检查下你的版本有没有修复。 41f94ef

我尝试更改之后，摄像头数据也无法传输过来

TempoTian commented 2 weeks ago

提供下log，帮你看下可能什么原因。你也可以在examples/protocols/components/av_stream/av_stream.c 加一些log诊断下