Remote extensions register and call but no audio

Status
Not open for further replies.

kfeen

New Member
May 20, 2020
26
0
1
22
I'm new to FusionPBX and right now I'm just testing things out. I have FusionPBX on Debian running on a remote AWS lightsail server that has the following ports open; 5060-5090(TCP-UDP), and 16384-32768(UDP). I have two phones registering to my.domain.com on two separate extensions (101 and 102). Both phones are on the same network. When I call one extension from another they will ring and I can answer them but I get no audio from either phone. The external and internal sip profiles have ext-rtp-ip and ext-sip-ip pointing to my.domain.com. Any help is appreciated!
 

Adrian Fretwell

Well-Known Member
Aug 13, 2017
1,383
364
83
99% of the time no audio issues are caused by NAT problems. The way to diagnose for sure is to get a packet capture of the SIP messaging and look at the SDP body to see where the RTP (audio streams) are being sent. If you see an RFC1918 address in the SDP body, that will be the cause of the trouble.
 

kfeen

New Member
May 20, 2020
26
0
1
22
This is what I get in my logs when I call the 102 extension from 101. I don't quite know what to look for in these logs... I come from the asterisk world...

Code:
/101@MY.DOMAIN.NAME.COM Regex (PASS) [local_extension] ${user_exists}(true) =~ /true/ break=on-false

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action export(dialed_extension=${destination_number}) INLINE

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM export(dialed_extension=102)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [dialed_extension]=[102]

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action limit(hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination})

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Regex (PASS) [local_extension] () =~ // break=on-false

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(hangup_after_bridge=true)

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(continue_on_fail=true)

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)})

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid})

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(api_hangup_hook=lua app.lua hangup)

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action export(domain_name=${domain_name})

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action bridge(user/${destination_number}@${domain_name})

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action lua(app.lua failure_handler)

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM parsing [MY.DOMAIN.NAME.COM->voicemail] continue=false

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Regex (PASS) [voicemail] ${user_exists}(true) =~ /true/ break=on-false

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(record_append=false) INLINE

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(record_append=false)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [record_append]=[false]

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(voicemail_action=save) INLINE

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(voicemail_action=save)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [voicemail_action]=[save]

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(voicemail_id=${destination_number}) INLINE

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(voicemail_id=102)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [voicemail_id]=[102]

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(voicemail_profile=default) INLINE

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(voicemail_profile=default)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [voicemail_profile]=[default]

3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action lua(app.lua voicemail)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/101@MY.DOMAIN.NAME.COM) State Change CS_ROUTING -> CS_EXECUTE

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/101@MY.DOMAIN.NAME.COM) State ROUTING going to sleep

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/101@MY.DOMAIN.NAME.COM) Running State Change CS_EXECUTE (Cur 1 Tot 8)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/101@MY.DOMAIN.NAME.COM) State EXECUTE

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_sofia.c:209 sofia/internal/101@MY.DOMAIN.NAME.COM SOFIA EXECUTE

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:329 sofia/internal/101@MY.DOMAIN.NAME.COM Standard EXECUTE

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(caller_id_number=101)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [caller_id_number]=[101]

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(RFC2822_DATE=Sun, 24 May 2020 07:54:34 +0000)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [RFC2822_DATE]=[Sun, 24 May 2020 07:54:34 +0000]

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM export(origination_callee_id_name=102)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [origination_callee_id_name]=[102]

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM hash(insert/MY.DOMAIN.NAME.COM-last_dial/101/102)

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM eval(not_secure)

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM limit(hash MY.DOMAIN.NAME.COM 102 5 error/user_busy)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_limit.c:124 incr called: MY.DOMAIN.NAME.COM_102 max:5, interval:0

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_hash.c:196 Usage for MY.DOMAIN.NAME.COM_102 is now 1/5

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(hangup_after_bridge=true)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [hangup_after_bridge]=[true]

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(continue_on_fail=true)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [continue_on_fail]=[true]

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM hash(insert/MY.DOMAIN.NAME.COM-call_return/102/101)

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM hash(insert/MY.DOMAIN.NAME.COM-last_dial_ext/102/3ec21880-128b-42ad-bb7c-3c4033f0919e)

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(called_party_call_group=)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [called_party_call_group]=[UNDEF]

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM hash(insert/MY.DOMAIN.NAME.COM-last_dial//3ec21880-128b-42ad-bb7c-3c4033f0919e)

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(api_hangup_hook=lua app.lua hangup)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [api_hangup_hook]=[lua app.lua hangup]

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM export(domain_name=MY.DOMAIN.NAME.COM)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [domain_name]=[MY.DOMAIN.NAME.COM]

3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM bridge(user/102@MY.DOMAIN.NAME.COM)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [domain_name]=[MY.DOMAIN.NAME.COM] to event

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [call_direction]=[local] to event

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [dialed_extension]=[102] to event

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [origination_callee_id_name]=[102] to event

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [domain_name]=[MY.DOMAIN.NAME.COM] to event

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [domain_name]=[MY.DOMAIN.NAME.COM] to event

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [call_direction]=[local] to event

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [dialed_extension]=[102] to event

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [origination_callee_id_name]=[102] to event

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [domain_name]=[MY.DOMAIN.NAME.COM] to event

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/102@192.168.1.42:33795 [b6a7a9a2-3558-444e-bca8-659032801bc5]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] mod_sofia.c:5089 (sofia/internal/102@192.168.1.42:33795) State Change CS_NEW -> CS_INIT

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_INIT (Cur 2 Tot 9)

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/102@192.168.1.42:33795) State INIT

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] mod_sofia.c:93 sofia/internal/102@192.168.1.42:33795 SOFIA INIT

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] sofia_glue.c:1587 sip:102@50.75.94.242:33795;rinstance=3d6512 Setting proxy route to sofia/internal/102@192.168.1.42:33795

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] sofia_glue.c:1618 sofia/internal/102@192.168.1.42:33795 sending invite version: 1.10.3 -release-15-129de34d84 64bit

b6a7a9a2-3558-444e-bca8-659032801bc5 Local SDP:

b6a7a9a2-3558-444e-bca8-659032801bc5 v=0

b6a7a9a2-3558-444e-bca8-659032801bc5 o=FreeSWITCH 1590289280 1590289281 IN IP4 MY.DOMAIN.NAME.COM

b6a7a9a2-3558-444e-bca8-659032801bc5 s=FreeSWITCH

b6a7a9a2-3558-444e-bca8-659032801bc5 c=IN IP4 MY.DOMAIN.NAME.COM

b6a7a9a2-3558-444e-bca8-659032801bc5 t=0 0

b6a7a9a2-3558-444e-bca8-659032801bc5 m=audio 17594 RTP/AVP 0 9 8 101 13

b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:0 PCMU/8000

b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:9 G722/8000

b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:8 PCMA/8000

b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:101 telephone-event/8000

b6a7a9a2-3558-444e-bca8-659032801bc5 a=fmtp:101 0-16

b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:13 CN/8000

b6a7a9a2-3558-444e-bca8-659032801bc5 a=ptime:20

b6a7a9a2-3558-444e-bca8-659032801bc5 a=sendrecv

b6a7a9a2-3558-444e-bca8-659032801bc5

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:40 sofia/internal/102@192.168.1.42:33795 Standard INIT

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/102@192.168.1.42:33795) State Change CS_INIT -> CS_ROUTING

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/102@192.168.1.42:33795) State INIT going to sleep

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_ROUTING (Cur 2 Tot 9)

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] sofia.c:7325 Channel sofia/internal/102@192.168.1.42:33795 entering state [calling][0]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/102@192.168.1.42:33795) State ROUTING

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] mod_sofia.c:154 sofia/internal/102@192.168.1.42:33795 SOFIA ROUTING

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/102@192.168.1.42:33795) State Change CS_ROUTING -> CS_CONSUME_MEDIA

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/102@192.168.1.42:33795) State ROUTING going to sleep

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 9)

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/102@192.168.1.42:33795) State CONSUME_MEDIA

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/102@192.168.1.42:33795) State CONSUME_MEDIA going to sleep

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.612076 [DEBUG] sofia.c:7325 Channel sofia/internal/102@192.168.1.42:33795 entering state [proceeding][180]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.612076 [NOTICE] sofia.c:7436 Ring-Ready sofia/internal/102@192.168.1.42:33795!

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.612076 [DEBUG] switch_channel.c:3437 (sofia/internal/102@192.168.1.42:33795) Callstate Change DOWN -> RINGING

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.612076 [DEBUG] sofia.c:7325 Channel sofia/internal/102@192.168.1.42:33795 entering state [proceeding][180]

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.612076 [NOTICE] mod_sofia.c:2498 Ring-Ready sofia/internal/101@MY.DOMAIN.NAME.COM!

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.612076 [DEBUG] sofia.c:7325 Channel sofia/internal/101@MY.DOMAIN.NAME.COM entering state [early][180]

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.612076 [NOTICE] switch_ivr_originate.c:556 Ring Ready sofia/internal/101@MY.DOMAIN.NAME.COM!

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] sofia.c:7325 Channel sofia/internal/102@192.168.1.42:33795 entering state [completing][200]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] sofia.c:7335 Remote SDP:

b6a7a9a2-3558-444e-bca8-659032801bc5 v=0

b6a7a9a2-3558-444e-bca8-659032801bc5 o=- 0 1 IN IP4 192.168.0.250

b6a7a9a2-3558-444e-bca8-659032801bc5 s=-

b6a7a9a2-3558-444e-bca8-659032801bc5 c=IN IP4 192.168.1.42

b6a7a9a2-3558-444e-bca8-659032801bc5 t=0 0

b6a7a9a2-3558-444e-bca8-659032801bc5 m=audio 4010 RTP/AVP 0 101

b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:0 PCMU/8000

b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:101 telephone-event/8000

b6a7a9a2-3558-444e-bca8-659032801bc5 a=fmtp:101 0-15

b6a7a9a2-3558-444e-bca8-659032801bc5

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] sofia.c:7325 Channel sofia/internal/102@192.168.1.42:33795 entering state [ready][200]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:3837 Set Codec sofia/internal/102@192.168.1.42:33795 PCMU/8000 20 ms 160 samples 64000 bits 1 channels

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_codec.c:111 sofia/internal/102@192.168.1.42:33795 Original read codec set to PCMU:0

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5909 sofia/internal/102@192.168.1.42:33795 Set 2833 dtmf send payload to 101 recv payload to 101

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:8661 AUDIO RTP [sofia/internal/102@192.168.1.42:33795] 172.26.3.197 port 17594 -> 192.168.1.42 port 4010 codec: 0 ms: 20

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_rtp.c:4413 Starting timer [soft] 160 bytes per 20ms

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:8975 sofia/internal/102@192.168.1.42:33795 Set 2833 dtmf send payload to 101

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:8982 sofia/internal/102@192.168.1.42:33795 Set 2833 dtmf receive payload to 101

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:9005 sofia/internal/102@192.168.1.42:33795 Set rtp dtmf delay to 40

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [NOTICE] sofia.c:8503 Channel [sofia/internal/102@192.168.1.42:33795] has been answered

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_channel.c:3865 (sofia/internal/102@192.168.1.42:33795) Callstate Change RINGING -> ACTIVE

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_core_media.c:8661 AUDIO RTP [sofia/internal/101@MY.DOMAIN.NAME.COM] 172.26.3.197 port 28094 -> 192.168.1.48 port 12104 codec: 0 ms: 20

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_rtp.c:4413 Starting timer [soft] 160 bytes per 20ms

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_core_media.c:8975 sofia/internal/101@MY.DOMAIN.NAME.COM Set 2833 dtmf send payload to 101

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_core_media.c:8982 sofia/internal/101@MY.DOMAIN.NAME.COM Set 2833 dtmf receive payload to 101

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_core_media.c:9005 sofia/internal/101@MY.DOMAIN.NAME.COM Set rtp dtmf delay to 40

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] mod_sofia.c:898 Local SDP sofia/internal/101@MY.DOMAIN.NAME.COM:

3ec21880-128b-42ad-bb7c-3c4033f0919e v=0

3ec21880-128b-42ad-bb7c-3c4033f0919e o=FreeSWITCH 1590278785 1590278786 IN IP4 MY.DOMAIN.NAME.COM

3ec21880-128b-42ad-bb7c-3c4033f0919e s=FreeSWITCH

3ec21880-128b-42ad-bb7c-3c4033f0919e c=IN IP4 MY.DOMAIN.NAME.COM

3ec21880-128b-42ad-bb7c-3c4033f0919e t=0 0

3ec21880-128b-42ad-bb7c-3c4033f0919e m=audio 28094 RTP/AVP 0 101

3ec21880-128b-42ad-bb7c-3c4033f0919e a=rtpmap:0 PCMU/8000

3ec21880-128b-42ad-bb7c-3c4033f0919e a=rtpmap:101 telephone-event/8000

3ec21880-128b-42ad-bb7c-3c4033f0919e a=fmtp:101 0-16

3ec21880-128b-42ad-bb7c-3c4033f0919e a=ptime:20

3ec21880-128b-42ad-bb7c-3c4033f0919e a=sendrecv

3ec21880-128b-42ad-bb7c-3c4033f0919e

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] sofia.c:7325 Channel sofia/internal/101@MY.DOMAIN.NAME.COM entering state [completed][200]

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [NOTICE] switch_ivr_originate.c:3794 Channel [sofia/internal/101@MY.DOMAIN.NAME.COM] has been answered

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_channel.c:3865 (sofia/internal/101@MY.DOMAIN.NAME.COM) Callstate Change RINGING -> ACTIVE

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/internal/102@192.168.1.42:33795]

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/internal/102@192.168.1.42:33795]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.292049 [DEBUG] switch_ivr_bridge.c:1793 (sofia/internal/102@192.168.1.42:33795) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.292049 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 9)

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.292049 [DEBUG] switch_core_state_machine.c:654 (sofia/internal/102@192.168.1.42:33795) State EXCHANGE_MEDIA

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.292049 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.332051 [DEBUG] sofia.c:7325 Channel sofia/internal/101@MY.DOMAIN.NAME.COM entering state [ready][200]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.832052 [INFO] switch_rtp.c:7682 Auto Changing audio port from 192.168.1.42:4010 to 50.75.94.242:4010

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [NOTICE] sofia.c:1089 Hangup sofia/internal/102@192.168.1.42:33795 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/internal/102@192.168.1.42:33795]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:654 (sofia/internal/102@192.168.1.42:33795) State EXCHANGE_MEDIA going to sleep

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_HANGUP (Cur 2 Tot 9)

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/102@192.168.1.42:33795) Callstate Change ACTIVE -> HANGUP

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/102@192.168.1.42:33795) State HANGUP

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] mod_sofia.c:453 Channel sofia/internal/102@192.168.1.42:33795 hanging up, cause: NORMAL_CLEARING

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:60 sofia/internal/102@192.168.1.42:33795 Standard HANGUP, cause: NORMAL_CLEARING

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/102@192.168.1.42:33795) State HANGUP going to sleep

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/102@192.168.1.42:33795) State Change CS_HANGUP -> CS_REPORTING

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_REPORTING (Cur 2 Tot 9)

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/102@192.168.1.42:33795) State REPORTING

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:174 sofia/internal/102@192.168.1.42:33795 Standard REPORTING, cause: NORMAL_CLEARING

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/102@192.168.1.42:33795) State REPORTING going to sleep

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/102@192.168.1.42:33795) State Change CS_REPORTING -> CS_DESTROY

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_session.c:1726 Session 9 (sofia/internal/102@192.168.1.42:33795) Locked, Waiting on external entities

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/internal/101@MY.DOMAIN.NAME.COM]

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [NOTICE] switch_ivr_bridge.c:1944 Hangup sofia/internal/101@MY.DOMAIN.NAME.COM [CS_EXECUTE] [NORMAL_CLEARING]

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] mod_hash.c:296 Usage for MY.DOMAIN.NAME.COM_102 is now 0

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_session.c:2905 sofia/internal/101@MY.DOMAIN.NAME.COM skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/101@MY.DOMAIN.NAME.COM) State EXECUTE going to sleep

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/101@MY.DOMAIN.NAME.COM) Running State Change CS_HANGUP (Cur 2 Tot 9)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/101@MY.DOMAIN.NAME.COM) Callstate Change ACTIVE -> HANGUP

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/101@MY.DOMAIN.NAME.COM) State HANGUP

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] mod_sofia.c:447 sofia/internal/101@MY.DOMAIN.NAME.COM Overriding SIP cause 480 with 200 from the other leg

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] mod_sofia.c:453 Channel sofia/internal/101@MY.DOMAIN.NAME.COM hanging up, cause: NORMAL_CLEARING

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/internal/101@MY.DOMAIN.NAME.COM

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:60 sofia/internal/101@MY.DOMAIN.NAME.COM Standard HANGUP, cause: NORMAL_CLEARING

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/101@MY.DOMAIN.NAME.COM) State HANGUP going to sleep

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:781 Hangup Command with no Session lua(app.lua hangup):

3ec21880-128b-42ad-bb7c-3c4033f0919e

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/101@MY.DOMAIN.NAME.COM) State Change CS_HANGUP -> CS_REPORTING

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/101@MY.DOMAIN.NAME.COM) Running State Change CS_REPORTING (Cur 2 Tot 9)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/101@MY.DOMAIN.NAME.COM) State REPORTING

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [NOTICE] switch_core_session.c:1744 Session 9 (sofia/internal/102@192.168.1.42:33795) Ended

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/102@192.168.1.42:33795 [CS_DESTROY]

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_DESTROY (Cur 1 Tot 9)

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/102@192.168.1.42:33795) State DESTROY

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [DEBUG] mod_sofia.c:364 sofia/internal/102@192.168.1.42:33795 SOFIA DESTROY

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:181 sofia/internal/102@192.168.1.42:33795 Standard DESTROY

b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/102@192.168.1.42:33795) State DESTROY going to sleep

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:174 sofia/internal/101@MY.DOMAIN.NAME.COM Standard REPORTING, cause: NORMAL_CLEARING

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/101@MY.DOMAIN.NAME.COM) State REPORTING going to sleep

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/101@MY.DOMAIN.NAME.COM) State Change CS_REPORTING -> CS_DESTROY

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_session.c:1726 Session 8 (sofia/internal/101@MY.DOMAIN.NAME.COM) Locked, Waiting on external entities

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [NOTICE] switch_core_session.c:1744 Session 8 (sofia/internal/101@MY.DOMAIN.NAME.COM) Ended

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/101@MY.DOMAIN.NAME.COM [CS_DESTROY]

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/101@MY.DOMAIN.NAME.COM) Running State Change CS_DESTROY (Cur 0 Tot 9)

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/101@MY.DOMAIN.NAME.COM) State DESTROY

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] mod_sofia.c:364 sofia/internal/101@MY.DOMAIN.NAME.COM SOFIA DESTROY

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:181 sofia/internal/101@MY.DOMAIN.NAME.COM Standard DESTROY

3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/101@MY.DOMAIN.NAME.COM) State DESTROY going to sleep
 

Adrian Fretwell

Well-Known Member
Aug 13, 2017
1,383
364
83
Difficult to comment without fully understanding the network topology of your set up, from the log files it would appear there are two lots of RFC1918 (private addresses) in play, 192.168.1.42 and 172.26.3.197. I can only guess that MY.DOMAIN.NAME.COM resolves to 50.75.94.242, which I guess is the external IP of your Fusion Box.

The clue may be in the log:
Code:
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] sofia.c:7335 Remote SDP:
b6a7a9a2-3558-444e-bca8-659032801bc5 v=0
b6a7a9a2-3558-444e-bca8-659032801bc5 o=- 0 1 IN IP4 192.168.0.250
b6a7a9a2-3558-444e-bca8-659032801bc5 s=-
b6a7a9a2-3558-444e-bca8-659032801bc5 c=IN IP4 192.168.1.42
b6a7a9a2-3558-444e-bca8-659032801bc5 t=0 0
b6a7a9a2-3558-444e-bca8-659032801bc5 m=audio 4010 RTP/AVP 0 101
b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:0 PCMU/8000
b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:101 telephone-event/8000
b6a7a9a2-3558-444e-bca8-659032801bc5 a=fmtp:101 0-15

This is the part of the log that reflects what is created in the SDP body of the SIP packet, as you can see it has an RFC1918 address in the c= attribute.

You do not say what devices you are using but it would appear that they are not NAT aware, I.E. the device(s) does not know what it's external IP will be the other side of your NAT (router). Some devices will allow you to configure methods to work around this like STUN for example.
 
Last edited:

kfeen

New Member
May 20, 2020
26
0
1
22
The public IP address you mentioned does not resolve to MY.DOMAIN.NAME.COM it's the WAN IP of my office network where I was trying to make the calls from. MY.DOMAIN.NAME.COM points to the WAN IP assigned to my AWS lightsail server(fusion box). The 192.XXX private IP is the LAN IP address for my softphone and the other IP is the LAN IP for my AWS lightsail server (Fusion box). As for the phones, I'm using a softphone and a sangoma s505. I've used both of these clients before on a FreePBX system with basically the same network setup and I've never had to configure a STUN server to get around NAT.
 
Last edited:

Adrian Fretwell

Well-Known Member
Aug 13, 2017
1,383
364
83
Ok, that makes more sense, so we can see FreeSwitch overriding the SDP sent from the phone by substituting the office WAN IP:
Code:
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.832052 [INFO] switch_rtp.c:7682 Auto Changing audio port from 192.168.1.42:4010 to 50.75.94.242:4010

Clearly some further diagnostics is required.

At the CLI on your Fusion box run sngrep -c and then make a call between the extension. This performs a type of packet capture and display that will let you examine exactly what is being sent between the endpoints, look at the IPs and ports, see if it all adds up. pay special attention to where the RTP is being sent for both directions.

As your Fusion Box is technically behind a NAT (172.26.x.x), you may, at some point, need to perform a packet capture on the WAN side to check on what is actually happening. I have no experience with AWS, so can't comment, but some home/office routers will allow you to set up a WAN port mirror that allows you to plug in a PC to a specified router port and run a utility like Wireshark in order to see exactly what is happening on the WAN.

It's difficult to help at a distance, but I hope I'm giving you some clues.
 

kfeen

New Member
May 20, 2020
26
0
1
22
sngrep -c was interesting. Here's the output that I got when calling 102 from 101.
Screenshot (20).png

"MY.DOMAIN.NAME.COM" resolves to my FusionPBX. "MY PUBLIC IP" is the WAN address of the phones that I'm calling from... i.e. my office network.
Your clues are very helpful btw.
 

kfeen

New Member
May 20, 2020
26
0
1
22
I reinstalled FusionPBX, did some basic setup and now it's working... weird. I must have had something configured wrong and just couldn't catch it.
 
Status
Not open for further replies.