I am having a strange issue issue with Ring Groups.
I have set up a ring group with Enterprise strategy. It dials 3 extensions (2 x Grandstream GXP2135 and 1 x Grandstream DP750).
Everything works fine for the 2 x GXP2135 phones, but if you answer the call on the DP750, it just drops the call.
If I switch it to Simultaneous strategy, it works correctly.
Here is the log information for the failed call:
What gets me is it looks like it is trying to use G729 for the call, but G729 is not valid on the server, or is it on the phone. G729 is the 4th preferred codec after PCMU, PCMA and G722.
I can leave it at Simultaneous as they don't use FollowMe so it should be fine. More just don't uderstand why it would mess up on the one extension.
Thanks,
Shredder
I have set up a ring group with Enterprise strategy. It dials 3 extensions (2 x Grandstream GXP2135 and 1 x Grandstream DP750).
Everything works fine for the 2 x GXP2135 phones, but if you answer the call on the DP750, it just drops the call.
If I switch it to Simultaneous strategy, it works correctly.
Here is the log information for the failed call:
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/201@172.84.167.139:7276 [dbfcf333-2d16-4b46-bdb2-a42af829ef56]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] mod_sofia.c:5019 (sofia/internal/201@172.84.167.139:7276) State Change CS_NEW -> CS_INIT
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/201@172.84.167.139:7276) Running State Change CS_INIT (Cur 18 Tot 508656)
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/201@172.84.167.139:7276) State INIT
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] mod_sofia.c:93 sofia/internal/201@172.84.167.139:7276 SOFIA INIT
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] sofia_glue.c:1271 sip:201@172.84.167.139:44279;transport=tcp Setting proxy route to sofia/internal/201@172.84.167.139:7276
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] sofia_glue.c:1302 sofia/internal/201@172.84.167.139:7276 sending invite version: 1.8.5 -6-31281a0bf1 64bit
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 Local SDP:
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 v=0
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 o=FreeSWITCH 1599656773 1599656774 IN IP4 52.60.199.244
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 s=FreeSWITCH
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 c=IN IP4 52.60.199.244
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 t=0 0
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 m=audio 24192 RTP/AVP 9 18 0 8 3 101 13
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:9 G722/8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:18 G729/8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:0 PCMU/8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:8 PCMA/8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:3 GSM/8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:101 telephone-event/8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=fmtp:101 0-16
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:13 CN/8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=ptime:20
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=sendrecv
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 m=video 20628 RTP/AVP 31 34 102 103 104
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 b=AS:1024
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:31 H261/90000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:34 H263/90000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:102 H263-1998/90000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:103 H263-2000/90000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:104 H264/90000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=sendrecv
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:31 ccm fir
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:31 ccm tmmbr
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:31 nack
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:31 nack pli
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:34 ccm fir
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:34 ccm tmmbr
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:34 nack
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:34 nack pli
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:102 ccm fir
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:102 ccm tmmbr
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:102 nack
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:102 nack pli
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:103 ccm fir
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:103 ccm tmmbr
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:103 nack
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:103 nack pli
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:104 ccm fir
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:104 ccm tmmbr
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:104 nack
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtcp-fb:104 nack pli
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] switch_core_state_machine.c:40 sofia/internal/201@172.84.167.139:7276 Standard INIT
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/201@172.84.167.139:7276) State Change CS_INIT -> CS_ROUTING
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/201@172.84.167.139:7276) State INIT going to sleep
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/201@172.84.167.139:7276) Running State Change CS_ROUTING (Cur 18 Tot 508656)
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] sofia.c:7291 Channel sofia/internal/201@172.84.167.139:7276 entering state [calling][0]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/201@172.84.167.139:7276) State ROUTING
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] mod_sofia.c:154 sofia/internal/201@172.84.167.139:7276 SOFIA ROUTING
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/201@172.84.167.139:7276) State Change CS_ROUTING -> CS_CONSUME_MEDIA
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/201@172.84.167.139:7276) State ROUTING going to sleep
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/201@172.84.167.139:7276) Running State Change CS_CONSUME_MEDIA (Cur 18 Tot 508656)
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/201@172.84.167.139:7276) State CONSUME_MEDIA
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.754030 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/201@172.84.167.139:7276) State CONSUME_MEDIA going to sleep
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.894032 [DEBUG] sofia.c:7291 Channel sofia/internal/201@172.84.167.139:7276 entering state [proceeding][180]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.894032 [NOTICE] sofia.c:7401 Ring-Ready sofia/internal/201@172.84.167.139:7276!
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:25.894032 [DEBUG] switch_channel.c:3354 (sofia/internal/201@172.84.167.139:7276) Callstate Change DOWN -> RINGING
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] sofia.c:7291 Channel sofia/internal/201@172.84.167.139:7276 entering state [completing][200]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] sofia.c:7301 Remote SDP:
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 v=0
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 o=201 8000 8000 IN IP4 172.84.167.139
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 s=SIP Call
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 c=IN IP4 172.84.167.139
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 t=0 0
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 m=audio 7308 RTP/AVP 18 0 8 101
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:18 G729/8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:0 PCMU/8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:8 PCMA/8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=rtpmap:101 telephone-event/8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=fmtp:101 0-16,32-36,54
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 a=ptime:20
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 m=video 0 RTP/AVP 31 34 102 103 104
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [NOTICE] sofia.c:7304 Pre-Answer sofia/internal/201@172.84.167.139:7276!
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_channel.c:3482 (sofia/internal/201@172.84.167.139:7276) Callstate Change RINGING -> EARLY
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] sofia.c:7291 Channel sofia/internal/201@172.84.167.139:7276 entering state [ready][200]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5533 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5533 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5533 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5394 Set telephone-event payload to 101@8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:3781 Set Codec sofia/internal/201@172.84.167.139:7276 G729/8000 20 ms 160 samples 8000 bits 1 channels
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_codec.c:111 sofia/internal/201@172.84.167.139:7276 Original read codec set to G729:18
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5737 Set telephone-event payload to 101@8000
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:5795 sofia/internal/201@172.84.167.139:7276 Set 2833 dtmf send payload to 101 recv payload to 101
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:8511 AUDIO RTP [sofia/internal/201@172.84.167.139:7276] 172.31.26.181 port 24192 -> 172.84.167.139 port 7308 codec: 18 ms: 20
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_rtp.c:4300 Starting timer [soft] 160 bytes per 20ms
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:8815 sofia/internal/201@172.84.167.139:7276 Set 2833 dtmf send payload to 101
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:8822 sofia/internal/201@172.84.167.139:7276 Set 2833 dtmf receive payload to 101
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_core_media.c:8845 sofia/internal/201@172.84.167.139:7276 Set rtp dtmf delay to 40
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [NOTICE] sofia.c:8429 Channel [sofia/internal/201@172.84.167.139:7276] has been answered
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.774038 [DEBUG] switch_channel.c:3781 (sofia/internal/201@172.84.167.139:7276) Callstate Change EARLY -> ACTIVE
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.834025 [DEBUG] switch_ivr_bridge.c:1782 (sofia/internal/201@172.84.167.139:7276) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.834025 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/201@172.84.167.139:7276) Running State Change CS_EXCHANGE_MEDIA (Cur 18 Tot 508656)
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.834025 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/201@172.84.167.139:7276) State EXCHANGE_MEDIA
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.834025 [DEBUG] mod_sofia.c:646 SOFIA EXCHANGE_MEDIA
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.834025 [DEBUG] sofia.c:7291 Channel sofia/internal/201@172.84.167.139:7276 entering state [calling][0]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [ERR] switch_core_media.c:15810 Codec G.729 encoder error!
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_rtp.c:7550 Correct audio ip/port confirmed.
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_ivr_bridge.c:825 sofia/external/2044705008@208.81.0.252 ending bridge by request from write function
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/internal/201@172.84.167.139:7276]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [NOTICE] switch_ivr_bridge.c:1034 Hangup sofia/internal/201@172.84.167.139:7276 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/201@172.84.167.139:7276) State EXCHANGE_MEDIA going to sleep
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/201@172.84.167.139:7276) Running State Change CS_HANGUP (Cur 16 Tot 508656)
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] sofia.c:1529 Channel is already hungup.
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] sofia.c:1529 Channel is already hungup.
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] sofia.c:1529 Channel is already hungup.
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_state_machine.c:847 (sofia/internal/201@172.84.167.139:7276) Callstate Change ACTIVE -> HANGUP
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/201@172.84.167.139:7276) State HANGUP
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] mod_sofia.c:449 Channel sofia/internal/201@172.84.167.139:7276 hanging up, cause: NORMAL_CLEARING
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] mod_sofia.c:502 Sending BYE to sofia/internal/201@172.84.167.139:7276
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_ivr_bridge.c:1881 sofia/internal/201@172.84.167.139:7276 skip receive message [UNBRIDGE] (channel is hungup already)
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_state_machine.c:60 sofia/internal/201@172.84.167.139:7276 Standard HANGUP, cause: NORMAL_CLEARING
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/201@172.84.167.139:7276) State HANGUP going to sleep
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/201@172.84.167.139:7276) State Change CS_HANGUP -> CS_REPORTING
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/201@172.84.167.139:7276) Running State Change CS_REPORTING (Cur 16 Tot 508656)
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/201@172.84.167.139:7276) State REPORTING
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_state_machine.c:174 sofia/internal/201@172.84.167.139:7276 Standard REPORTING, cause: NORMAL_CLEARING
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/201@172.84.167.139:7276) State REPORTING going to sleep
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/201@172.84.167.139:7276) State Change CS_REPORTING -> CS_DESTROY
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [DEBUG] switch_core_session.c:1715 Session 508656 (sofia/internal/201@172.84.167.139:7276) Locked, Waiting on external entities
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [NOTICE] switch_core_session.c:1733 Session 508656 (sofia/internal/201@172.84.167.139:7276) Ended
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.894029 [NOTICE] switch_core_session.c:1737 Close Channel sofia/internal/201@172.84.167.139:7276 [CS_DESTROY]
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.914027 [DEBUG] switch_core_state_machine.c:738 (sofia/internal/201@172.84.167.139:7276) Running State Change CS_DESTROY (Cur 15 Tot 508656)
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.914027 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/201@172.84.167.139:7276) State DESTROY
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.914027 [DEBUG] mod_sofia.c:354 sofia/internal/201@172.84.167.139:7276 SOFIA DESTROY
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.914027 [DEBUG] switch_core_state_machine.c:181 sofia/internal/201@172.84.167.139:7276 Standard DESTROY
freeswitch.log:dbfcf333-2d16-4b46-bdb2-a42af829ef56 2020-09-09 13:49:29.914027 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/201@172.84.167.139:7276) State DESTROY going to sleep
What gets me is it looks like it is trying to use G729 for the call, but G729 is not valid on the server, or is it on the phone. G729 is the 4th preferred codec after PCMU, PCMA and G722.
I can leave it at Simultaneous as they don't use FollowMe so it should be fine. More just don't uderstand why it would mess up on the one extension.
Thanks,
Shredder