No voice on forwarded call

Status
Not open for further replies.

albert-g

Member
Aug 11, 2020
83
2
8
43
I have an issue with forwarded calls,
outside caller -> outside SIP number -> extension -> forwarded outbound number - Call is successfully initiating, but with no voice on both side.
extension calling -> the same outbound number - No problem.
Until here, I checked and played with every codec, since I thought it is codec problem, but nothing helped.
Before seeing the logs, Please advise what may be, the possible issue.
 

albert-g

Member
Aug 11, 2020
83
2
8
43
This is the log of the call, As I said call succesfully initiating but with no voice on both side (!!only when I use forwarding. In outbound/inbound cal,l all is ok)

Code:
EXECUTE [depth=0] sofia/external/anonymous@anonymous.invalid bridge(sofia/gateway/2f308a05-8752-475b-aa1f-1b249173075f/003******600)
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [call_direction]=[outbound] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [call_direction]=[outbound] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [default_dialect]=[us] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [default_voice]=[callie] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [sound_prefix]=[/usr/share/freeswitch/sounds/en/us/callie] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [default_language]=[en] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [origination_callee_id_name]=[003******600] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [sip_h_Diversion]=[<sip:0486571575@192.168.11.206:5060>;reason=unconditional] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [default_dialect]=[us] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [default_voice]=[callie] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [sound_prefix]=[/usr/share/freeswitch/sounds/en/us/callie] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [default_language]=[en] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [origination_callee_id_name]=[003******600] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_channel.c:1264 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [call_direction]=[outbound] to event
2020-12-23 09:56:25.160092 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
2020-12-23 09:56:25.160092 [NOTICE] switch_channel.c:1118 New Channel sofia/external/003******600 [5923424e-d955-48f0-a5bb-04ab7f0b7d80]
2020-12-23 09:56:25.160092 [DEBUG] mod_sofia.c:5089 (sofia/external/003******600) State Change CS_NEW -> CS_INIT
2020-12-23 09:56:25.160092 [DEBUG] switch_core_state_machine.c:585 (sofia/external/003******600) Running State Change CS_INIT (Cur 2 Tot 24)
2020-12-23 09:56:25.160092 [DEBUG] switch_core_state_machine.c:628 (sofia/external/003******600) State INIT
2020-12-23 09:56:25.160092 [DEBUG] mod_sofia.c:93 sofia/external/003******600 SOFIA INIT
2020-12-23 09:56:25.160092 [DEBUG] sofia_glue.c:1618 sofia/external/003******600 sending invite version: 1.10.5 -release-17-25569c1631 64bit
Local SDP:
v=0
o=FreeSWITCH 1608689255 1608689256 IN IP4 93.**.**46
s=FreeSWITCH
c=IN IP4 93.**.**46
t=0 0
m=audio 24530 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

2020-12-23 09:56:25.160092 [DEBUG] switch_core_state_machine.c:40 sofia/external/003******600 Standard INIT
2020-12-23 09:56:25.160092 [DEBUG] switch_core_state_machine.c:48 (sofia/external/003******600) State Change CS_INIT -> CS_ROUTING
2020-12-23 09:56:25.160092 [DEBUG] switch_core_state_machine.c:628 (sofia/external/003******600) State INIT going to sleep
2020-12-23 09:56:25.160092 [DEBUG] switch_core_state_machine.c:585 (sofia/external/003******600) Running State Change CS_ROUTING (Cur 2 Tot 24)
2020-12-23 09:56:25.160092 [DEBUG] switch_core_state_machine.c:644 (sofia/external/003******600) State ROUTING
2020-12-23 09:56:25.160092 [DEBUG] mod_sofia.c:154 sofia/external/003******600 SOFIA ROUTING
2020-12-23 09:56:25.160092 [DEBUG] switch_ivr_originate.c:67 (sofia/external/003******600) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2020-12-23 09:56:25.160092 [DEBUG] switch_core_state_machine.c:644 (sofia/external/003******600) State ROUTING going to sleep
2020-12-23 09:56:25.160092 [DEBUG] switch_core_state_machine.c:585 (sofia/external/003******600) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 24)
2020-12-23 09:56:25.160092 [DEBUG] sofia.c:7326 Channel sofia/external/003******600 entering state [calling][0]
2020-12-23 09:56:25.160092 [DEBUG] switch_core_state_machine.c:663 (sofia/external/003******600) State CONSUME_MEDIA
2020-12-23 09:56:25.160092 [DEBUG] switch_core_state_machine.c:663 (sofia/external/003******600) State CONSUME_MEDIA going to sleep
2020-12-23 09:56:25.220090 [DEBUG] sofia.c:7326 Channel sofia/external/003******600 entering state [calling][0]
2020-12-23 09:56:25.560096 [INFO] sofia.c:1369 sofia/external/003******600 Update Callee ID to "003******600" <0413615600>
2020-12-23 09:56:25.560096 [DEBUG] sofia.c:7326 Channel sofia/external/003******600 entering state [completing][200]
2020-12-23 09:56:25.560096 [DEBUG] sofia.c:7336 Remote SDP:
v=0
o=anonymous 160871378506 160871378507 IN IP4 91.121.129.23
s=SIP Call
c=IN IP4 91.121.128.138
t=0 0
m=audio 30868 RTP/AVP 8 0 101
b=AS:82
a=rtpmap:8 PCMA/8000/1
a=rtpmap:0 PCMU/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20

2020-12-23 09:56:25.560096 [DEBUG] sofia.c:7326 Channel sofia/external/003******600 entering state [ready][200]
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/003******600 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2020-12-23 09:56:25.560096 [DEBUG] switch_core_codec.c:111 sofia/external/003******600 Original read codec set to PCMA:8
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:5911 sofia/external/003******600 Set 2833 dtmf send payload to 101 recv payload to 101
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/003******600] 192.168.11.206 port 24530 -> 91.121.128.138 port 30868 codec: 8 ms: 20
2020-12-23 09:56:25.560096 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:8977 sofia/external/003******600 Set 2833 dtmf send payload to 101
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:8984 sofia/external/003******600 Set 2833 dtmf receive payload to 101
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:9007 sofia/external/003******600 Set rtp dtmf delay to 40
2020-12-23 09:56:25.560096 [NOTICE] sofia.c:8504 Channel [sofia/external/003******600] has been answered
2020-12-23 09:56:25.560096 [DEBUG] switch_channel.c:3865 (sofia/external/003******600) Callstate Change DOWN -> ACTIVE
2020-12-23 09:56:25.560096 [DEBUG] switch_ivr_originate.c:439 Setting codec string on sofia/external/anonymous@anonymous.invalid to PCMA@8000h@20i
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/anonymous@anonymous.invalid] 192.168.11.206 port 28134 -> 91.121.129.143 port 34426 codec: 8 ms: 20
2020-12-23 09:56:25.560096 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:8977 sofia/external/anonymous@anonymous.invalid Set 2833 dtmf send payload to 101
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:8984 sofia/external/anonymous@anonymous.invalid Set 2833 dtmf receive payload to 101
2020-12-23 09:56:25.560096 [DEBUG] switch_core_media.c:9007 sofia/external/anonymous@anonymous.invalid Set rtp dtmf delay to 40
2020-12-23 09:56:25.560096 [DEBUG] mod_sofia.c:898 Local SDP sofia/external/anonymous@anonymous.invalid:
v=0
o=FreeSWITCH 1608685651 1608685652 IN IP4 93.**.**46
s=FreeSWITCH
c=IN IP4 93.**.**46
t=0 0
m=audio 28134 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

2020-12-23 09:56:25.560096 [NOTICE] switch_ivr_originate.c:3794 Channel [sofia/external/anonymous@anonymous.invalid] has been answered
2020-12-23 09:56:25.560096 [DEBUG] switch_channel.c:3865 (sofia/external/anonymous@anonymous.invalid) Callstate Change RINGING -> ACTIVE
2020-12-23 09:56:25.560096 [DEBUG] sofia.c:7326 Channel sofia/external/anonymous@anonymous.invalid entering state [completed][200]
2020-12-23 09:56:25.560096 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/external/003******600]
2020-12-23 09:56:25.560096 [DEBUG] switch_ivr_bridge.c:1793 (sofia/external/003******600) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2020-12-23 09:56:25.560096 [DEBUG] switch_core_state_machine.c:585 (sofia/external/003******600) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 24)
2020-12-23 09:56:25.560096 [DEBUG] switch_core_state_machine.c:654 (sofia/external/003******600) State EXCHANGE_MEDIA
2020-12-23 09:56:25.560096 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
2020-12-23 09:56:25.580093 [DEBUG] sofia.c:7326 Channel sofia/external/anonymous@anonymous.invalid entering state [ready][200]



And here succesfully hung up.

2020-12-23 09:56:33.600153 [NOTICE] sofia.c:1089 Hangup sofia/external/003******600 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2020-12-23 09:56:33.600153 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/external/003******600]
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:654 (sofia/external/003******600) State EXCHANGE_MEDIA going to sleep
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:585 (sofia/external/003******600) Running State Change CS_HANGUP (Cur 2 Tot 24)
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:848 (sofia/external/003******600) Callstate Change ACTIVE -> HANGUP
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:850 (sofia/external/003******600) State HANGUP
2020-12-23 09:56:33.600153 [DEBUG] mod_sofia.c:453 Channel sofia/external/003******600 hanging up, cause: NORMAL_CLEARING
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:60 sofia/external/003******600 Standard HANGUP, cause: NORMAL_CLEARING
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:850 (sofia/external/003******600) State HANGUP going to sleep
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:620 (sofia/external/003******600) State Change CS_HANGUP -> CS_REPORTING
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:585 (sofia/external/003******600) Running State Change CS_REPORTING (Cur 2 Tot 24)
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:936 (sofia/external/003******600) State REPORTING
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:174 sofia/external/003******600 Standard REPORTING, cause: NORMAL_CLEARING
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:936 (sofia/external/003******600) State REPORTING going to sleep
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:611 (sofia/external/003******600) State Change CS_REPORTING -> CS_DESTROY
2020-12-23 09:56:33.600153 [DEBUG] switch_core_session.c:1726 Session 24 (sofia/external/003******600) Locked, Waiting on external entities
2020-12-23 09:56:33.600153 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/external/anonymous@anonymous.invalid]
2020-12-23 09:56:33.600153 [NOTICE] switch_ivr_bridge.c:1944 Hangup sofia/external/anonymous@anonymous.invalid [CS_EXECUTE] [NORMAL_CLEARING]
2020-12-23 09:56:33.600153 [NOTICE] switch_core_session.c:1744 Session 24 (sofia/external/003******600) Ended
2020-12-23 09:56:33.600153 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/003******600 [CS_DESTROY]
2020-12-23 09:56:33.600153 [DEBUG] switch_core_session.c:2905 sofia/external/anonymous@anonymous.invalid skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:651 (sofia/external/anonymous@anonymous.invalid) State EXECUTE going to sleep
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:585 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_HANGUP (Cur 1 Tot 24)
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:739 (sofia/external/003******600) Running State Change CS_DESTROY (Cur 1 Tot 24)
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:848 (sofia/external/anonymous@anonymous.invalid) Callstate Change ACTIVE -> HANGUP
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:749 (sofia/external/003******600) State DESTROY
2020-12-23 09:56:33.600153 [DEBUG] mod_sofia.c:364 sofia/external/003******600 SOFIA DESTROY
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:181 sofia/external/003******600 Standard DESTROY
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:749 (sofia/external/003******600) State DESTROY going to sleep
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:850 (sofia/external/anonymous@anonymous.invalid) State HANGUP
2020-12-23 09:56:33.600153 [DEBUG] mod_sofia.c:447 sofia/external/anonymous@anonymous.invalid Overriding SIP cause 480 with 200 from the other leg
2020-12-23 09:56:33.600153 [DEBUG] mod_sofia.c:453 Channel sofia/external/anonymous@anonymous.invalid hanging up, cause: NORMAL_CLEARING
2020-12-23 09:56:33.600153 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/external/anonymous@anonymous.invalid
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:60 sofia/external/anonymous@anonymous.invalid Standard HANGUP, cause: NORMAL_CLEARING
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:850 (sofia/external/anonymous@anonymous.invalid) State HANGUP going to sleep
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:620 (sofia/external/anonymous@anonymous.invalid) State Change CS_HANGUP -> CS_REPORTING
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:585 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_REPORTING (Cur 1 Tot 24)
2020-12-23 09:56:33.600153 [DEBUG] switch_core_state_machine.c:936 (sofia/external/anonymous@anonymous.invalid) State REPORTING
2020-12-23 09:56:33.660094 [DEBUG] switch_core_state_machine.c:174 sofia/external/anonymous@anonymous.invalid Standard REPORTING, cause: NORMAL_CLEARING
2020-12-23 09:56:33.660094 [DEBUG] switch_core_state_machine.c:936 (sofia/external/anonymous@anonymous.invalid) State REPORTING going to sleep
2020-12-23 09:56:33.660094 [DEBUG] switch_core_state_machine.c:611 (sofia/external/anonymous@anonymous.invalid) State Change CS_REPORTING -> CS_DESTROY
2020-12-23 09:56:33.660094 [DEBUG] switch_core_session.c:1726 Session 23 (sofia/external/anonymous@anonymous.invalid) Locked, Waiting on external entities
2020-12-23 09:56:33.660094 [NOTICE] switch_core_session.c:1744 Session 23 (sofia/external/anonymous@anonymous.invalid) Ended
2020-12-23 09:56:33.660094 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/anonymous@anonymous.invalid [CS_DESTROY]
2020-12-23 09:56:33.660094 [DEBUG] switch_core_state_machine.c:739 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_DESTROY (Cur 0 Tot 24)
2020-12-23 09:56:33.660094 [DEBUG] switch_core_state_machine.c:749 (sofia/external/anonymous@anonymous.invalid) State DESTROY
2020-12-23 09:56:33.660094 [DEBUG] mod_sofia.c:364 sofia/external/anonymous@anonymous.invalid SOFIA DESTROY
2020-12-23 09:56:33.660094 [DEBUG] switch_core_state_machine.c:181 sofia/external/anonymous@anonymous.invalid Standard DESTROY
2020-12-23 09:56:33.660094 [DEBUG] switch_core_state_machine.c:749 (sofia/external/anonymous@anonymous.invalid) State DESTROY going to sleep
 

albert-g

Member
Aug 11, 2020
83
2
8
43
Sure, let me little explain what I did and also share SNGREP results. There is two ext. 2 and 20, 20-is forwarding to external number, and also I have incoming destination which is direct inbound call to extension 20 (which next should forward it to another external number).

1. When I am calling from internal extension 2 to internal extension 20, ext. 20 is successfully forwarding to external number, and voice is OK.

1610355611655.png

2. When I am calling from outside to the number which should direct to extension 20 (which should forward it on another external number), call is initiating but with no voice on both side.

1610355621018.png

The following is the SNGREP result of the call that have problem with voice. (I masked some things with same color so you can retrieve easier)

P.S. Let me know which part is important on this results, so I will share it.

1610356100469.png
 

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,038
556
113
Could it be that its the anonymous? I cannot here send calls to my carrier without a valid caller ID?
 

albert-g

Member
Aug 11, 2020
83
2
8
43
Actually, I dont know why call is going with "Anonymous", on my extension 20 I set valid outbound called ID, and on outbound call from extension 20 this Out. Caller ID, is working good.
1610359193000.png
 

albert-g

Member
Aug 11, 2020
83
2
8
43
In the logs I found that in normal call there is line "Correct audio RTCP ip/port confirmed" but in forwarded call where there is no voice, there is no such lines. Please advise.

Normal Call:


Less:
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [NOTICE] sofia.c:7339 Pre-Answer sofia/external/003****180!
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_channel.c:3565 (sofia/external/003****180) Callstate Change DOWN -> EARLY
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/003****180 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_codec.c:111 sofia/external/003****180 Original read codec set to PCMA:8
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:5911 sofia/external/003****180 Set 2833 dtmf send payload to 101 recv payload to 101
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/003****180] 192.168.11.206 port 28964 -> 91.**.**.138 port 37814 codec: 8 ms: 20
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:8977 sofia/external/003****180 Set 2833 dtmf send payload to 101
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:8984 sofia/external/003****180 Set 2833 dtmf receive payload to 101
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.402568 [DEBUG] switch_core_media.c:9007 sofia/external/003****180 Set rtp dtmf delay to 40
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] switch_ivr_originate.c:439 Setting codec string on sofia/internal/2@INTERNAL.DOMAIN to PCMA@8000h@20i
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [INFO] switch_ivr_originate.c:3801 Sending early media
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/internal/2@INTERNAL.DOMAIN] 192.168.11.206 port 27308 -> 192.168.11.117 port 4062 codec: 8 ms: 20
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] switch_core_media.c:8885 Activating RTCP PORT 4063
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] switch_rtp.c:4848 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 4063
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] switch_rtp.c:2636 Setting RTCP remote addr to 192.168.11.117:4063 2
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] switch_core_media.c:8977 sofia/internal/2@INTERNAL.DOMAIN Set 2833 dtmf send payload to 101
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] switch_core_media.c:8984 sofia/internal/2@INTERNAL.DOMAIN Set 2833 dtmf receive payload to 101
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] switch_core_media.c:9007 sofia/internal/2@INTERNAL.DOMAIN Set rtp dtmf delay to 40
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] mod_sofia.c:2593 Ring SDP:
2c630f0a-c780-4efc-86b5-cddbbbdf1894 v=0
2c630f0a-c780-4efc-86b5-cddbbbdf1894 o=FreeSWITCH 1610419128 1610419129 IN IP4 192.168.11.206
2c630f0a-c780-4efc-86b5-cddbbbdf1894 s=FreeSWITCH
2c630f0a-c780-4efc-86b5-cddbbbdf1894 c=IN IP4 192.168.11.206
2c630f0a-c780-4efc-86b5-cddbbbdf1894 t=0 0
2c630f0a-c780-4efc-86b5-cddbbbdf1894 m=audio 27308 RTP/AVP 8 101
2c630f0a-c780-4efc-86b5-cddbbbdf1894 a=rtpmap:8 PCMA/8000
2c630f0a-c780-4efc-86b5-cddbbbdf1894 a=rtpmap:101 telephone-event/8000
2c630f0a-c780-4efc-86b5-cddbbbdf1894 a=fmtp:101 0-16
2c630f0a-c780-4efc-86b5-cddbbbdf1894 a=ptime:20
2c630f0a-c780-4efc-86b5-cddbbbdf1894 a=sendrecv
2c630f0a-c780-4efc-86b5-cddbbbdf1894 a=rtcp:27309 IN IP4 192.168.11.206
2c630f0a-c780-4efc-86b5-cddbbbdf1894
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [NOTICE] mod_sofia.c:2596 Pre-Answer sofia/internal/2@INTERNAL.DOMAIN!
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] switch_channel.c:3565 (sofia/internal/2@INTERNAL.DOMAIN) Callstate Change RINGING -> EARLY
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/external/003****180]
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.422558 [DEBUG] sofia.c:7326 Channel sofia/internal/2@INTERNAL.DOMAIN entering state [early][183]
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.422558 [DEBUG] switch_ivr_bridge.c:1793 (sofia/external/003****180) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.422558 [DEBUG] switch_core_state_machine.c:585 (sofia/external/003****180) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 28)
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.422558 [DEBUG] switch_core_state_machine.c:654 (sofia/external/003****180) State EXCHANGE_MEDIA
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.422558 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.442557 [DEBUG] switch_rtp.c:7157 Correct audio RTCP ip/port confirmed.
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.462565 [DEBUG] switch_rtp.c:1921 rtcp_stats_init: audio ssrc[2033994283] base_seq[18848]
2c630f0a-c780-4efc-86b5-cddbbbdf1894 2021-01-12 11:13:56.482563 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
0c92eadc-17d1-4db4-905e-b21830a94363 2021-01-12 11:13:56.502561 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.



This is the log from call with no voice:

Code:
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [NOTICE] sofia.c:7339 Pre-Answer sofia/external/003****180!
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_channel.c:3565 (sofia/external/003****180) Callstate Change DOWN -> EARLY
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/003****180 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_codec.c:111 sofia/external/003****180 Original read codec set to PCMA:8
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:5911 sofia/external/003****180 Set 2833 dtmf send payload to 101 recv payload to 101
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/003****180] 192.168.11.206 port 21066 -> 91.**.**.143 port 37282 codec: 8 ms: 20
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:8977 sofia/external/003****180 Set 2833 dtmf send payload to 101
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:8984 sofia/external/003****180 Set 2833 dtmf receive payload to 101
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.082568 [DEBUG] switch_core_media.c:9007 sofia/external/003****180 Set rtp dtmf delay to 40
ff378503-6363-4056-9780-37591a8e24eb 2021-01-12 11:15:06.102557 [DEBUG] switch_ivr_originate.c:439 Setting codec string on sofia/external/anonymous@anonymous.invalid to PCMA@8000h@20i
ff378503-6363-4056-9780-37591a8e24eb 2021-01-12 11:15:06.102557 [INFO] switch_ivr_originate.c:3801 Sending early media
ff378503-6363-4056-9780-37591a8e24eb 2021-01-12 11:15:06.102557 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/anonymous@anonymous.invalid] 192.168.11.206 port 19190 -> 91.**.**.143 port 35626 codec: 8 ms: 20
ff378503-6363-4056-9780-37591a8e24eb 2021-01-12 11:15:06.102557 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
ff378503-6363-4056-9780-37591a8e24eb 2021-01-12 11:15:06.102557 [DEBUG] switch_core_media.c:8977 sofia/external/anonymous@anonymous.invalid Set 2833 dtmf send payload to 101
ff378503-6363-4056-9780-37591a8e24eb 2021-01-12 11:15:06.102557 [DEBUG] switch_core_media.c:8984 sofia/external/anonymous@anonymous.invalid Set 2833 dtmf receive payload to 101
ff378503-6363-4056-9780-37591a8e24eb 2021-01-12 11:15:06.102557 [DEBUG] switch_core_media.c:9007 sofia/external/anonymous@anonymous.invalid Set rtp dtmf delay to 40
ff378503-6363-4056-9780-37591a8e24eb 2021-01-12 11:15:06.102557 [DEBUG] mod_sofia.c:2593 Ring SDP:
ff378503-6363-4056-9780-37591a8e24eb v=0
ff378503-6363-4056-9780-37591a8e24eb o=FreeSWITCH 1610427316 1610427317 IN IP4 93.**.**.246
ff378503-6363-4056-9780-37591a8e24eb s=FreeSWITCH
ff378503-6363-4056-9780-37591a8e24eb c=IN IP4 93.**.**.246
ff378503-6363-4056-9780-37591a8e24eb t=0 0
ff378503-6363-4056-9780-37591a8e24eb m=audio 19190 RTP/AVP 8 101
ff378503-6363-4056-9780-37591a8e24eb a=rtpmap:8 PCMA/8000
ff378503-6363-4056-9780-37591a8e24eb a=rtpmap:101 telephone-event/8000
ff378503-6363-4056-9780-37591a8e24eb a=fmtp:101 0-16
ff378503-6363-4056-9780-37591a8e24eb a=silenceSupp:off - - - -
ff378503-6363-4056-9780-37591a8e24eb a=ptime:20
ff378503-6363-4056-9780-37591a8e24eb a=sendrecv
ff378503-6363-4056-9780-37591a8e24eb
ff378503-6363-4056-9780-37591a8e24eb 2021-01-12 11:15:06.102557 [NOTICE] mod_sofia.c:2596 Pre-Answer sofia/external/anonymous@anonymous.invalid!
ff378503-6363-4056-9780-37591a8e24eb 2021-01-12 11:15:06.102557 [DEBUG] switch_channel.c:3565 (sofia/external/anonymous@anonymous.invalid) Callstate Change RINGING -> EARLY
ff378503-6363-4056-9780-37591a8e24eb 2021-01-12 11:15:06.102557 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/external/003****180]
ff378503-6363-4056-9780-37591a8e24eb 2021-01-12 11:15:06.102557 [DEBUG] sofia.c:7326 Channel sofia/external/anonymous@anonymous.invalid entering state [early][183]
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.102557 [DEBUG] switch_ivr_bridge.c:1793 (sofia/external/003****180) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.102557 [DEBUG] switch_core_state_machine.c:585 (sofia/external/003****180) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 32)
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.102557 [DEBUG] switch_core_state_machine.c:654 (sofia/external/003****180) State EXCHANGE_MEDIA
f288406a-db43-4425-bb41-3dc31eef1f44 2021-01-12 11:15:06.102557 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
 

falk

Member
Feb 2, 2019
76
5
8
53
Try to put caller id
Go to : app -call forword - ext
then add new id "Select a number to override the original caller ID number".
 

albert-g

Member
Aug 11, 2020
83
2
8
43
Unfortunately it is not helped. Also I tried to set manually "outbound caller id number" on dialplan manager, and the caller number really changed (forwarded call is showing the number that I manually set) but there is still no voice and the log almost the same.

1610527347134.png



Code:
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.415053 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [NOTICE] switch_channel.c:1118 New Channel sofia/external/003****600 [2effa6da-615f-4906-af8e-7550b671ca75]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] mod_sofia.c:5089 (sofia/external/003****600) State Change CS_NEW -> CS_INIT
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] switch_core_state_machine.c:585 (sofia/external/003****600) Running State Change CS_INIT (Cur 2 Tot 31)
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] switch_core_state_machine.c:628 (sofia/external/003****600) State INIT
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] mod_sofia.c:93 sofia/external/003****600 SOFIA INIT
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] sofia_glue.c:1618 sofia/external/003****600 sending invite version: 1.10.5 -release-17-25569c1631 64bit
2effa6da-615f-4906-af8e-7550b671ca75 Local SDP:
2effa6da-615f-4906-af8e-7550b671ca75 v=0
2effa6da-615f-4906-af8e-7550b671ca75 o=FreeSWITCH 1610494677 1610494678 IN IP4 93.**.**.246
2effa6da-615f-4906-af8e-7550b671ca75 s=FreeSWITCH
2effa6da-615f-4906-af8e-7550b671ca75 c=IN IP4 93.**.**.246
2effa6da-615f-4906-af8e-7550b671ca75 t=0 0
2effa6da-615f-4906-af8e-7550b671ca75 m=audio 31784 RTP/AVP 8 0 3 18 101
2effa6da-615f-4906-af8e-7550b671ca75 a=rtpmap:8 PCMA/8000
2effa6da-615f-4906-af8e-7550b671ca75 a=rtpmap:0 PCMU/8000
2effa6da-615f-4906-af8e-7550b671ca75 a=rtpmap:3 GSM/8000
2effa6da-615f-4906-af8e-7550b671ca75 a=rtpmap:18 G729/8000
2effa6da-615f-4906-af8e-7550b671ca75 a=rtpmap:101 telephone-event/8000
2effa6da-615f-4906-af8e-7550b671ca75 a=fmtp:101 0-16
2effa6da-615f-4906-af8e-7550b671ca75 a=silenceSupp:off - - - -
2effa6da-615f-4906-af8e-7550b671ca75 a=ptime:20
2effa6da-615f-4906-af8e-7550b671ca75 a=sendrecv
2effa6da-615f-4906-af8e-7550b671ca75
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] switch_core_state_machine.c:40 sofia/external/003****600 Standard INIT
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] switch_core_state_machine.c:48 (sofia/external/003****600) State Change CS_INIT -> CS_ROUTING
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] switch_core_state_machine.c:628 (sofia/external/003****600) State INIT going to sleep
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] switch_core_state_machine.c:585 (sofia/external/003****600) Running State Change CS_ROUTING (Cur 2 Tot 31)
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] sofia.c:7326 Channel sofia/external/003****600 entering state [calling][0]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] switch_core_state_machine.c:644 (sofia/external/003****600) State ROUTING
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] mod_sofia.c:154 sofia/external/003****600 SOFIA ROUTING
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] switch_ivr_originate.c:67 (sofia/external/003****600) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] switch_core_state_machine.c:644 (sofia/external/003****600) State ROUTING going to sleep
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] switch_core_state_machine.c:585 (sofia/external/003****600) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 31)
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] switch_core_state_machine.c:663 (sofia/external/003****600) State CONSUME_MEDIA
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.415053 [DEBUG] switch_core_state_machine.c:663 (sofia/external/003****600) State CONSUME_MEDIA going to sleep
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.495052 [DEBUG] sofia.c:7326 Channel sofia/external/003****600 entering state [calling][0]
2021-01-13 09:27:41.775058 [INFO] sofia.c:1369 sofia/external/003****600 Update Callee ID to "003****600" <04***600>
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] sofia.c:7326 Channel sofia/external/003****600 entering state [completing][200]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] sofia.c:7336 Remote SDP:
2effa6da-615f-4906-af8e-7550b671ca75 v=0
2effa6da-615f-4906-af8e-7550b671ca75 o=anonymous 161052646181 161052646182 IN IP4 91.121.129.23
2effa6da-615f-4906-af8e-7550b671ca75 s=SIP Call
2effa6da-615f-4906-af8e-7550b671ca75 c=IN IP4 91.121.129.141
2effa6da-615f-4906-af8e-7550b671ca75 t=0 0
2effa6da-615f-4906-af8e-7550b671ca75 m=audio 33102 RTP/AVP 8 0 18 3 101
2effa6da-615f-4906-af8e-7550b671ca75 b=AS:82
2effa6da-615f-4906-af8e-7550b671ca75 a=rtpmap:8 PCMA/8000/1
2effa6da-615f-4906-af8e-7550b671ca75 a=rtpmap:0 PCMU/8000/1
2effa6da-615f-4906-af8e-7550b671ca75 a=rtpmap:18 G729/8000/1
2effa6da-615f-4906-af8e-7550b671ca75 a=fmtp:18 annexb=no
2effa6da-615f-4906-af8e-7550b671ca75 a=rtpmap:3 GSM/8000/1
2effa6da-615f-4906-af8e-7550b671ca75 a=rtpmap:101 telephone-event/8000
2effa6da-615f-4906-af8e-7550b671ca75 a=fmtp:101 0-15
2effa6da-615f-4906-af8e-7550b671ca75 a=ptime:20
2effa6da-615f-4906-af8e-7550b671ca75
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] sofia.c:7326 Channel sofia/external/003****600 entering state [ready][200]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[GSM:3:8000:20:13200:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [GSM:3:8000:20:13200:1] ++++ is saved as a match
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/003****600 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_codec.c:111 sofia/external/003****600 Original read codec set to PCMA:8
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:5911 sofia/external/003****600 Set 2833 dtmf send payload to 101 recv payload to 101
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/003****600] 192.168.11.206 port 31784 -> 91.121.129.141 port 33102 codec: 8 ms: 20
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:8977 sofia/external/003****600 Set 2833 dtmf send payload to 101
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:8984 sofia/external/003****600 Set 2833 dtmf receive payload to 101
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:9007 sofia/external/003****600 Set rtp dtmf delay to 40
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [NOTICE] sofia.c:8504 Channel [sofia/external/003****600] has been answered
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_channel.c:3865 (sofia/external/003****600) Callstate Change DOWN -> ACTIVE
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.775058 [DEBUG] switch_ivr_originate.c:439 Setting codec string on sofia/external/anonymous@anonymous.invalid to PCMA@8000h@20i
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/anonymous@anonymous.invalid] 192.168.11.206 port 20350 -> 91.121.128.137 port 34318 codec: 8 ms: 20
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.775058 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:8977 sofia/external/anonymous@anonymous.invalid Set 2833 dtmf send payload to 101
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:8984 sofia/external/anonymous@anonymous.invalid Set 2833 dtmf receive payload to 101
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.775058 [DEBUG] switch_core_media.c:9007 sofia/external/anonymous@anonymous.invalid Set rtp dtmf delay to 40
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.775058 [DEBUG] mod_sofia.c:898 Local SDP sofia/external/anonymous@anonymous.invalid:
93beeaa2-89d5-4609-a4dc-23b8550d63cd v=0
93beeaa2-89d5-4609-a4dc-23b8550d63cd o=FreeSWITCH 1610506111 1610506112 IN IP4 93.**.**.246
93beeaa2-89d5-4609-a4dc-23b8550d63cd s=FreeSWITCH
93beeaa2-89d5-4609-a4dc-23b8550d63cd c=IN IP4 93.**.**.246
93beeaa2-89d5-4609-a4dc-23b8550d63cd t=0 0
93beeaa2-89d5-4609-a4dc-23b8550d63cd m=audio 20350 RTP/AVP 8 101
93beeaa2-89d5-4609-a4dc-23b8550d63cd a=rtpmap:8 PCMA/8000
93beeaa2-89d5-4609-a4dc-23b8550d63cd a=rtpmap:101 telephone-event/8000
93beeaa2-89d5-4609-a4dc-23b8550d63cd a=fmtp:101 0-16
93beeaa2-89d5-4609-a4dc-23b8550d63cd a=silenceSupp:off - - - -
93beeaa2-89d5-4609-a4dc-23b8550d63cd a=ptime:20
93beeaa2-89d5-4609-a4dc-23b8550d63cd a=sendrecv
93beeaa2-89d5-4609-a4dc-23b8550d63cd
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.775058 [NOTICE] switch_ivr_originate.c:3794 Channel [sofia/external/anonymous@anonymous.invalid] has been answered
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.775058 [DEBUG] switch_channel.c:3865 (sofia/external/anonymous@anonymous.invalid) Callstate Change RINGING -> ACTIVE
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.775058 [DEBUG] sofia.c:7326 Channel sofia/external/anonymous@anonymous.invalid entering state [completed][200]
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.775058 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/external/003****600]
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_ivr_bridge.c:1793 (sofia/external/003****600) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_state_machine.c:585 (sofia/external/003****600) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 31)
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] switch_core_state_machine.c:654 (sofia/external/003****600) State EXCHANGE_MEDIA
2effa6da-615f-4906-af8e-7550b671ca75 2021-01-13 09:27:41.775058 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
93beeaa2-89d5-4609-a4dc-23b8550d63cd 2021-01-13 09:27:41.815055 [DEBUG] sofia.c:7326 Channel sofia/external/anonymous@anonymous.invalid entering state [ready][200]



I think maybe the problem with this " sofia/external/anonymous@anonymous.invalid ". Because in all calls where all is ok, there is no such thing, maybe need to somehow change this, or I don't know( . Please advise.
 

albert-g

Member
Aug 11, 2020
83
2
8
43
Yes you are right, I should try with another sip provider,
But one thing, that I want to note, I configured second gateway (with the same sip provider) and configure outbound call to use second gateway, and it start working, you think it is sip provider issue?
 

falk

Member
Feb 2, 2019
76
5
8
53
i think its connected to provider caller identification
- best things to do is to try with different one.
 
Last edited:
Status
Not open for further replies.