On Aug 23, 2015, at 2:08 PM, Pete Kay <petedao@gmail.com> wrote:Hi MichaelThank you for your comment. My outbound gateway is a public IP voip gateway. It does not have any nat in it. Could you help to take a look at the log attached?
------------------------------------------------------------------------recv 3546 bytes from tcp/[163.125.130.79]:26869 at 16:38:06.597828:
------------------------------------------------------------------------
INVITE sip:1002@108.165.22.32:5060 SIP/2.0
Via: SIP/2.0/TCP 192.168.1.100:5060;branch=z9hG4bK-524287-1---e4f7b616f75a9478;rport
Max-Forwards: 70
Contact: <sip:1001@192.168.1.100:5060;transport=tcp>;+sip.instance="1001@108.165.22.32:5060"
To: <sip:1002@108.165.22.32:5060>
From: <sip:1001@108.165.22.32:5060>;tag=e090b871
Call-ID: YEXqB4WY4KZUOfmDabppqg..
CSeq: 2 INVITE
Session-Expires: 1800
Min-SE: 90
Accept-Language: en
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, NOTIFY, SUBSCRIBE, INFO, MESSAGE, PRACK
Content-Type: application/sdp
Proxy-Authorization: Digest username="1001",realm="108.165.22.32",nonce="54896b80-49b5-11e5-ba06-3bd1ff35cbfe",uri="sip:1002@108.165.22.32:5060",response="0afd4be94eedc61fadc74712a1376486",cnonce="55b6411c482ea7487d4a9c4496371213",nc=00000001,qop=auth,algorithm=MD5
Supported: timer, 100rel
Content-Length: 2681
v=0
o=- 7694721001483168063 2 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS media_stream
m=audio 9 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:PfQS5mTk8DdU0wfL
a=ice-pwd:/LwkxiZgwC7MiwSbG1Ypx5hJ
a=fingerprint:sha-1 0F:6D:76:5D:16:F5:37:8F:F9:80:1C:CC:54:F0:3E:61:13:A0:87:17
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=sendrecv
a=rtcp-mux
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10; useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:102 ILBC/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:127 red/8000
a=rtpmap:126 telephone-event/8000
a=maxptime:60
a=ssrc:718599481 cname:DOUxo2z76LkE5KZX
a=ssrc:718599481 msid:media_stream audio_track
a=ssrc:718599481 mslabel:media_stream
a=ssrc:718599481 label:audio_track
a=candidate:3013953624 1 udp 2122260223 192.168.1.100 55457 typ host generation 0
a=candidate:2117845908 1 udp 2122194687 10.162.22.234 50345 typ host generation 0
a=candidate:3013953624 2 udp 2122260222 192.168.1.100 52690 typ host generation 0
a=candidate:2117845908 2 udp 2122194686 10.162.22.234 53413 typ host generation 0
a=candidate:4247172264 1 tcp 1518280447 192.168.1.100 49472 typ host tcptype passive generation 0
a=candidate:817381220 1 tcp 1518214911 10.162.22.234 49473 typ host tcptype passive generation 0
a=candidate:4247172264 2 tcp 1518280446 192.168.1.100 49474 typ host tcptype passive generation 0
a=candidate:817381220 2 tcp 1518214910 10.162.22.234 49475 typ host tcptype passive generation 0
a=candidate:854413036 1 udp 1686052607 163.125.130.79 26990 typ srflx raddr 192.168.1.100 rport 55457 generation 0
a=candidate:854413036 2 udp 1686052606 163.125.130.79 26991 typ srflx raddr 192.168.1.100 rport 52690 generation 0
a=candidate:4285777184 1 udp 1685987071 112.97.55.70 59693 typ srflx raddr 10.162.22.234 rport 50345 generation 0
a=candidate:4285777184 2 udp 1685987070 112.97.55.70 59694 typ srflx raddr 10.162.22.234 rport 53413 generation 0
a=candidate:2922827951 1 udp 41885439 146.185.149.11 60858 typ relay raddr 163.125.130.79 rport 26990 generation 0
a=candidate:2922827951 2 udp 41885438 146.185.149.11 62660 typ relay raddr 163.125.130.79 rport 26991 generation 0
a=candidate:2922827951 2 udp 41819902 146.185.149.11 61723 typ relay raddr 112.97.51.70 rport 42163 generation 0
a=candidate:2922827951 1 udp 41819903 146.185.149.11 63902 typ relay raddr 112.97.51.70 rport 42162 generation 0
------------------------------------------------------------------------
send 371 bytes to tcp/[163.125.130.79]:26869 at 16:38:06.598163:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 192.168.1.100:5060;branch=z9hG4bK-524287-1---e4f7b616f75a9478;rport=26869;received=163.125.130.79
From: <sip:1001@108.165.22.32:5060>;tag=e090b871
To: <sip:1002@108.165.22.32:5060>
Call-ID: YEXqB4WY4KZUOfmDabppqg..
CSeq: 2 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit
Content-Length: 0
------------------------------------------------------------------------
2015-08-23 16:38:06.590319 [DEBUG] sofia.c:2255 Re-attaching to session 54895f1e-49b5-11e5-ba05-3bd1ff35cbfe
2015-08-23 16:38:06.590319 [DEBUG] sofia.c:9093 sofia/internal/1001@108.165.22.32:5060 receiving invite from 163.125.130.79:26869 version: 1.7.0 git 58f1272 2015-08-14 17:51:12Z 64bit
2015-08-23 16:38:06.590319 [DEBUG] sofia.c:9260 IP 163.125.130.79 Rejected by acl "domains". Falling back to Digest auth.
2015-08-23 16:38:06.590319 [DEBUG] sofia.c:10392 Setting NAT mode based on nat.auto
2015-08-23 16:38:06.590319 [DEBUG] sofia.c:6715 Channel sofia/internal/1001@108.165.22.32:5060 entering state [received][100]
2015-08-23 16:38:06.590319 [DEBUG] sofia.c:6725 Remote SDP:
v=0
o=- 7694721001483168063 2 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE audio
a=msid-semantic: WMS media_stream
m=audio 9 RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
c=IN IP4 0.0.0.0
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10; useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:9 G722/8000
a=rtpmap:102 ILBC/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:127 red/8000
a=rtpmap:126 telephone-event/8000
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:PfQS5mTk8DdU0wfL
a=ice-pwd:/LwkxiZgwC7MiwSbG1Ypx5hJ
a=fingerprint:sha-1 0F:6D:76:5D:16:F5:37:8F:F9:80:1C:CC:54:F0:3E:61:13:A0:87:17
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=rtcp-mux
a=maxptime:60
a=ssrc:718599481 cname:DOUxo2z76LkE5KZX
a=ssrc:718599481 msid:media_stream audio_track
a=ssrc:718599481 mslabel:media_stream
a=ssrc:718599481 label:audio_track
a=candidate:3013953624 1 udp 2122260223 192.168.1.100 55457 typ host generation 0
a=candidate:2117845908 1 udp 2122194687 10.162.22.234 50345 typ host generation 0
a=candidate:3013953624 2 udp 2122260222 192.168.1.100 52690 typ host generation 0
a=candidate:2117845908 2 udp 2122194686 10.162.22.234 53413 typ host generation 0
a=candidate:4247172264 1 tcp 1518280447 192.168.1.100 49472 typ host tcptype passive generation 0
a=candidate:817381220 1 tcp 1518214911 10.162.22.234 49473 typ host tcptype passive generation 0
a=candidate:4247172264 2 tcp 1518280446 192.168.1.100 49474 typ host tcptype passive generation 0
a=candidate:817381220 2 tcp 1518214910 10.162.22.234 49475 typ host tcptype passive generation 0
a=candidate:854413036 1 udp 1686052607 163.125.130.79 26990 typ srflx raddr 192.168.1.100 rport 55457 generation 0
a=candidate:854413036 2 udp 1686052606 163.125.130.79 26991 typ srflx raddr 192.168.1.100 rport 52690 generation 0
a=candidate:4285777184 1 udp 1685987071 112.97.55.70 59693 typ srflx raddr 10.162.22.234 rport 50345 generation 0
a=candidate:4285777184 2 udp 1685987070 112.97.55.70 59694 typ srflx raddr 10.162.22.234 rport 53413 generation 0
a=candidate:2922827951 1 udp 41885439 146.185.149.11 60858 typ relay raddr 163.125.130.79 rport 26990 generation 0
a=candidate:2922827951 2 udp 41885438 146.185.149.11 62660 typ relay raddr 163.125.130.79 rport 26991 generation 0
a=candidate:2922827951 2 udp 41819902 146.185.149.11 61723 typ relay raddr 112.97.51.70 rport 42163 generation 0
a=candidate:2922827951 1 udp 41819903 146.185.149.11 63902 typ relay raddr 112.97.51.70 rport 42162 generation 0
2015-08-23 16:38:06.590319 [DEBUG] sofia.c:6991 (sofia/internal/1001@108.165.22.32:5060) State Change CS_NEW -> CS_INIT
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@108.165.22.32:5060) Running State Change CS_INIT
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1001@108.165.22.32:5060) State INIT
2015-08-23 16:38:06.590319 [DEBUG] mod_sofia.c:88 sofia/internal/1001@108.165.22.32:5060 SOFIA INIT
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@108.165.22.32:5060 Standard INIT
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@108.165.22.32:5060) State Change CS_INIT -> CS_ROUTING
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1001@108.165.22.32:5060) State INIT going to sleep
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@108.165.22.32:5060) Running State Change CS_ROUTING
2015-08-23 16:38:06.590319 [DEBUG] switch_channel.c:2239 (sofia/internal/1001@108.165.22.32:5060) Callstate Change DOWN -> RINGING
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1001@108.165.22.32:5060) State ROUTING
2015-08-23 16:38:06.590319 [DEBUG] mod_sofia.c:141 sofia/internal/1001@108.165.22.32:5060 SOFIA ROUTING
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:166 sofia/internal/1001@108.165.22.32:5060 Standard ROUTING
2015-08-23 16:38:06.590319 [INFO] mod_dialplan_xml.c:637 Processing 1001 <1001>->1002 in context default
Dialplan: sofia/internal/1001@108.165.22.32:5060 parsing [default->incoming_calls] continue=false
Dialplan: sofia/internal/1001@108.165.22.32:5060 Regex (PASS) [incoming_calls] ${destination_number}(1002) =~ /1002/ break=on-false
Dialplan: sofia/internal/1001@108.165.22.32:5060 Action bridge({rtp_secure_media=false,sip_secure_mediai_outbound=false}[,sip_secure_media_outbound=false,sdp_secure_savp_only=false,origination_caller_id_number=17185345433]sofia/internal/75468613802573728@69.22.33.40)
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/1001@108.165.22.32:5060) State Change CS_ROUTING -> CS_EXECUTE
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1001@108.165.22.32:5060) State ROUTING going to sleep
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@108.165.22.32:5060) Running State Change CS_EXECUTE
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1001@108.165.22.32:5060) State EXECUTE
2015-08-23 16:38:06.590319 [DEBUG] mod_sofia.c:196 sofia/internal/1001@108.165.22.32:5060 SOFIA EXECUTE
2015-08-23 16:38:06.590319 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1001@108.165.22.32:5060 Standard EXECUTE
EXECUTE sofia/internal/1001@108.165.22.32:5060 bridge({rtp_secure_media=false,sip_secure_mediai_outbound=false}[,sip_secure_media_outbound=false,sdp_secure_savp_only=false,origination_caller_id_number=17185345433]sofia/internal/75468613802573728@69.22.33.40)
2015-08-23 16:38:06.610302 [DEBUG] switch_ivr_originate.c:2104 Parsing global variables
2015-08-23 16:38:06.610302 [DEBUG] switch_ivr_originate.c:2557 Parsing session specific variables
2015-08-23 16:38:06.610302 [NOTICE] switch_channel.c:1089 New Channel sofia/internal/75468613802573728@69.22.33.40 [54d94e84-49b5-11e5-ba0c-3bd1ff35cbfe]
2015-08-23 16:38:06.610302 [DEBUG] mod_sofia.c:4735 (sofia/internal/75468613802573728@69.22.33.40) State Change CS_NEW -> CS_INIT
2015-08-23 16:38:06.610302 [DEBUG] mod_sofia.c:4805 [zrtp_passthru] Setting a-leg inherit_codec=true
2015-08-23 16:38:06.610302 [DEBUG] mod_sofia.c:4808 [zrtp_passthru] Setting b-leg absolute_codec_string='mod_spandsp.G722@8000h@20i@64000b,CORE_PCM_MODULE.PCMU@8000h@20i@64000b,CORE_PCM_MODULE.PCMA@8000h@20i@64000b'
2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/75468613802573728@69.22.33.40) Running State Change CS_INIT
2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/75468613802573728@69.22.33.40) State INIT
2015-08-23 16:38:06.610302 [DEBUG] mod_sofia.c:88 sofia/internal/75468613802573728@69.22.33.40 SOFIA INIT
2015-08-23 16:38:06.610302 [DEBUG] sofia_glue.c:1257 sofia/internal/75468613802573728@69.22.33.40 sending invite version: 1.7.0 git 58f1272 2015-08-14 17:51:12Z 64bit
Local SDP:
v=0
o=FreeSWITCH 1440321260 1440321261 IN IP4 108.165.22.32
s=FreeSWITCH
c=IN IP4 108.165.22.32
t=0 0
m=audio 26626 RTP/AVP 9 0 8 101 13
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv
2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:40 sofia/internal/75468613802573728@69.22.33.40 Standard INIT
2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/75468613802573728@69.22.33.40) State Change CS_INIT -> CS_ROUTING
2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/75468613802573728@69.22.33.40) State INIT going to sleep
2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/75468613802573728@69.22.33.40) Running State Change CS_ROUTING
2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/75468613802573728@69.22.33.40) State ROUTING
2015-08-23 16:38:06.610302 [DEBUG] mod_sofia.c:141 sofia/internal/75468613802573728@69.22.33.40 SOFIA ROUTING
2015-08-23 16:38:06.610302 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/75468613802573728@69.22.33.40) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/75468613802573728@69.22.33.40) State ROUTING going to sleep
2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/75468613802573728@69.22.33.40) Running State Change CS_CONSUME_MEDIA
send 1281 bytes to udp/[69.22.33.40]:5060 at 16:38:06.611312:
------------------------------------------------------------------------
INVITE sip:75468613802573728@69.22.33.40 SIP/2.0
Via: SIP/2.0/UDP 108.165.22.32;rport;branch=z9hG4bKevaDa1tKZ99Uc
2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/75468613802573728@69.22.33.40) State CONSUME_MEDIA
Max-Forwards: 69
From: "Extension 1001" <sip:17185345433@108.165.22.32>;tag=j0D5DB23gpjve
To: <sip:75468613802573728@69.22.33.40>
2015-08-23 16:38:06.610302 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/75468613802573728@69.22.33.40) State CONSUME_MEDIA going to sleep
Call-ID: 2c37ae78-c458-1233-8384-60eb698211be
CSeq: 79837879 INVITE
Contact: <sip:mod_sofia@108.165.22.32:5060>
User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20150814T175112Z~58f1272490~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 294
X-FS-Support: update_display,send_info
Remote-Party-ID: "Extension 1001" <sip:17185345433@108.165.22.32>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1440321260 1440321261 IN IP4 108.165.22.32
s=FreeSWITCH
c=IN IP4 108.165.22.32
t=0 0
m=audio 26626 RTP/AVP 9 0 8 101 13
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
------------------------------------------------------------------------
2015-08-23 16:38:06.610302 [DEBUG] sofia.c:6715 Channel sofia/internal/75468613802573728@69.22.33.40 entering state [calling][0]