<div dir="ltr">Is NAT involved?</div><div class="gmail_extra"><br><div class="gmail_quote">On Sun, Oct 25, 2015 at 8:18 PM, Dada P <span dir="ltr"><<a href="mailto:dada-p@yandex.com" target="_blank">dada-p@yandex.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">Hello,<br>
<br>
I have encountered strange problem - there is no audible ringback on DID routing.<br>
<br>
Dialplan is simple, on DID call gets transferred to local extension. Local extension is pretty much vanilla.<br>
Log shows that both 183/180 is being fired and ringback activated but no sound coming. Ringing is going ok though, as well as connection afterwords.<br>
Is that incoming provider's problem or freeswitch?<br>
<br>
Regards,<br>
Paul Kazakov<br>
<br>
Log:<br>
Dialplan: sofia/external/mobile@provider Action transfer(20 XML default)<br>
2015-10-26 01:39:27.463856 [DEBUG] switch_core_state_machine.c:216 (sofia/external/mobile@provider) State Change CS_ROUTING -> CS_EXECUTE<br>
2015-10-26 01:39:27.463856 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/mobile@provider [BREAK]<br>
2015-10-26 01:39:27.463856 [DEBUG] switch_core_state_machine.c:528 (sofia/external/mobile@provider) State ROUTING going to sleep<br>
2015-10-26 01:39:27.463856 [DEBUG] switch_core_state_machine.c:472 (sofia/external/mobile@provider) Running State Change CS_EXECUTE<br>
2015-10-26 01:39:27.463856 [DEBUG] switch_core_state_machine.c:535 (sofia/external/mobile@provider) State EXECUTE<br>
2015-10-26 01:39:27.463856 [DEBUG] mod_sofia.c:178 sofia/external/mobile@provider SOFIA EXECUTE<br>
2015-10-26 01:39:27.463856 [DEBUG] switch_core_state_machine.c:258 sofia/external/mobile@provider Standard EXECUTE<br>
EXECUTE sofia/external/mobile@provider set(outside_call=true)<br>
2015-10-26 01:39:27.463856 [DEBUG] mod_dptools.c:1477 sofia/external/mobile@provider SET [outside_call]=[true]<br>
EXECUTE sofia/external/mobile@provider export(RFC2822_DATE=Mon, 26 Oct 2015 01:39:27 +0100)<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_channel.c:1267 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 26 Oct 2015 01:39:27 +0100]<br>
EXECUTE sofia/external/mobile@provider set(domain_name=192.168.1.5)<br>
2015-10-26 01:39:27.483860 [DEBUG] mod_dptools.c:1477 sofia/external/mobile@provider SET [domain_name]=[192.168.1.5]<br>
EXECUTE sofia/external/mobile@provider transfer(20 XML default)<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_ivr.c:1856 (sofia/external/mobile@provider) State Change CS_EXECUTE -> CS_ROUTING<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/mobile@provider [BREAK]<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_core_session.c:913 Send signal sofia/external/mobile@provider [BREAK]<br>
2015-10-26 01:39:27.483860 [NOTICE] switch_ivr.c:1863 Transfer sofia/external/mobile@provider to XML[20@default]<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_core_state_machine.c:535 (sofia/external/mobile@provider) State EXECUTE going to sleep<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_core_state_machine.c:472 (sofia/external/mobile@provider) Running State Change CS_ROUTING<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_core_state_machine.c:528 (sofia/external/mobile@provider) State ROUTING<br>
2015-10-26 01:39:27.483860 [DEBUG] mod_sofia.c:123 sofia/external/mobile@provider SOFIA ROUTING<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_core_state_machine.c:166 sofia/external/mobile@provider Standard ROUTING<br>
2015-10-26 01:39:27.483860 [INFO] mod_dialplan_xml.c:635 Processing mobile <mobile>->20 in context default<br>
Dialplan: sofia/external/mobile@provider parsing [default->unloop] continue=false<br>
Dialplan: sofia/external/mobile@provider Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false<br>
Dialplan: sofia/external/mobile@provider Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false<br>
Dialplan: sofia/external/mobile@provider Absolute Condition [global]<br>
Dialplan: sofia/external/mobile@provider Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})<br>
Dialplan: sofia/external/mobile@provider Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})<br>
Dialplan: sofia/external/mobile@provider Action hash(insert/${domain_name}-last_dial/global/${uuid})<br>
Dialplan: sofia/external/mobile@provider Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})<br>
Dialplan: sofia/external/mobile@provider parsing [default->Local_Extension] continue=false<br>
Dialplan: sofia/external/mobile@provider Regex (PASS) [Local_Extension] destination_number(20) =~ /^([2-5][0-9])$/ break=on-false<br>
Dialplan: sofia/external/mobile@provider Action export(dialed_extension=20)<br>
Dialplan: sofia/external/mobile@provider Action bind_meta_app(1 b s execute_extension::dx XML features)<br>
Dialplan: sofia/external/mobile@provider Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav)<br>
Dialplan: sofia/external/mobile@provider Action bind_meta_app(3 b s execute_extension::cf XML features)<br>
Dialplan: sofia/external/mobile@provider Action bind_meta_app(4 b s execute_extension::att_xfer XML features)<br>
Dialplan: sofia/external/mobile@provider Action set(ringback=%(1000,4000,425))<br>
Dialplan: sofia/external/mobile@provider Action set(transfer_ringback=local_stream://moh)<br>
Dialplan: sofia/external/mobile@provider Action set(call_timeout=30)<br>
Dialplan: sofia/external/mobile@provider Action set(hangup_after_bridge=true)<br>
Dialplan: sofia/external/mobile@provider Action set(continue_on_fail=true)<br>
Dialplan: sofia/external/mobile@provider Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})<br>
Dialplan: sofia/external/mobile@provider Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})<br>
Dialplan: sofia/external/mobile@provider Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)})<br>
Dialplan: sofia/external/mobile@provider Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid})<br>
Dialplan: sofia/external/mobile@provider Action hash(insert/${domain_name}-last_dial_ext/global/${uuid})<br>
Dialplan: sofia/external/mobile@provider Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})<br>
Dialplan: sofia/external/mobile@provider Action bridge(user/${dialed_extension}@${domain_name})<br>
Dialplan: sofia/external/mobile@provider Action answer()<br>
Dialplan: sofia/external/mobile@provider Action sleep(1000)<br>
Dialplan: sofia/external/mobile@provider Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension})<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_core_state_machine.c:216 (sofia/external/mobile@provider) State Change CS_ROUTING -> CS_EXECUTE<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/mobile@provider [BREAK]<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_core_state_machine.c:528 (sofia/external/mobile@provider) State ROUTING going to sleep<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_core_state_machine.c:472 (sofia/external/mobile@provider) Running State Change CS_EXECUTE<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_core_state_machine.c:535 (sofia/external/mobile@provider) State EXECUTE<br>
2015-10-26 01:39:27.483860 [DEBUG] mod_sofia.c:178 sofia/external/mobile@provider SOFIA EXECUTE<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_core_state_machine.c:258 sofia/external/mobile@provider Standard EXECUTE<br>
EXECUTE sofia/external/mobile@provider hash(insert/192.168.1.5-spymap/mobile/0335befe-7b7a-11e5-a603-cb96e1b49eeb)<br>
EXECUTE sofia/external/mobile@provider hash(insert/192.168.1.5-last_dial/mobile/20)<br>
EXECUTE sofia/external/mobile@provider hash(insert/192.168.1.5-last_dial/global/0335befe-7b7a-11e5-a603-cb96e1b49eeb)<br>
EXECUTE sofia/external/mobile@provider export(RFC2822_DATE=Mon, 26 Oct 2015 01:39:27 +0100)<br>
2015-10-26 01:39:27.483860 [DEBUG] switch_channel.c:1267 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 26 Oct 2015 01:39:27 +0100]<br>
EXECUTE sofia/external/mobile@provider export(dialed_extension=20)<br>
2015-10-26 01:39:27.503879 [DEBUG] switch_channel.c:1267 EXPORT (export_vars) [dialed_extension]=[20]<br>
EXECUTE sofia/external/mobile@provider bind_meta_app(1 b s execute_extension::dx XML features)<br>
2015-10-26 01:39:27.503879 [INFO] switch_ivr_async.c:3932 Bound B-Leg: *1 execute_extension::dx XML features<br>
EXECUTE sofia/external/mobile@provider bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/mobile.2015-10-26-01-39-27.wav)<br>
2015-10-26 01:39:27.503879 [INFO] switch_ivr_async.c:3932 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/mobile.2015-10-26-01-39-27.wav<br>
EXECUTE sofia/external/mobile@provider bind_meta_app(3 b s execute_extension::cf XML features)<br>
2015-10-26 01:39:27.503879 [INFO] switch_ivr_async.c:3932 Bound B-Leg: *3 execute_extension::cf XML features<br>
EXECUTE sofia/external/mobile@provider bind_meta_app(4 b s execute_extension::att_xfer XML features)<br>
2015-10-26 01:39:27.503879 [INFO] switch_ivr_async.c:3932 Bound B-Leg: *4 execute_extension::att_xfer XML features<br>
EXECUTE sofia/external/mobile@provider set(ringback=%(1000,4000,425))<br>
2015-10-26 01:39:27.503879 [DEBUG] mod_dptools.c:1477 sofia/external/mobile@provider SET [ringback]=[%(1000,4000,425)]<br>
EXECUTE sofia/external/mobile@provider set(instant_ringback=true)<br>
2015-10-26 01:39:27.503879 [DEBUG] mod_dptools.c:1477 sofia/external/mobile@provider SET [instant_ringback]=[true]<br>
EXECUTE sofia/external/mobile@provider set(transfer_ringback=local_stream://moh)<br>
2015-10-26 01:39:27.503879 [DEBUG] mod_dptools.c:1477 sofia/external/mobile@provider SET [transfer_ringback]=[local_stream://moh]<br>
EXECUTE sofia/external/mobile@provider set(call_timeout=30)<br>
2015-10-26 01:39:27.503879 [DEBUG] mod_dptools.c:1477 sofia/external/mobile@provider SET [call_timeout]=[30]<br>
EXECUTE sofia/external/mobile@provider set(hangup_after_bridge=true)<br>
2015-10-26 01:39:27.503879 [DEBUG] mod_dptools.c:1477 sofia/external/mobile@provider SET [hangup_after_bridge]=[true]<br>
EXECUTE sofia/external/mobile@provider set(continue_on_fail=true)<br>
2015-10-26 01:39:27.523862 [DEBUG] mod_dptools.c:1477 sofia/external/mobile@provider SET [continue_on_fail]=[true]<br>
EXECUTE sofia/external/mobile@provider hash(insert/192.168.1.5-call_return/20/mobile)<br>
EXECUTE sofia/external/mobile@provider hash(insert/192.168.1.5-last_dial_ext/20/0335befe-7b7a-11e5-a603-cb96e1b49eeb)<br>
EXECUTE sofia/external/mobile@provider set(called_party_callgroup=bakery)<br>
2015-10-26 01:39:27.523862 [DEBUG] mod_dptools.c:1477 sofia/external/mobile@provider SET [called_party_callgroup]=[bakery]<br>
EXECUTE sofia/external/mobile@provider hash(insert/192.168.1.5-last_dial_ext/bakery/0335befe-7b7a-11e5-a603-cb96e1b49eeb)<br>
EXECUTE sofia/external/mobile@provider hash(insert/192.168.1.5-last_dial_ext/global/0335befe-7b7a-11e5-a603-cb96e1b49eeb)<br>
EXECUTE sofia/external/mobile@provider hash(insert/192.168.1.5-last_dial/bakery/0335befe-7b7a-11e5-a603-cb96e1b49eeb)<br>
EXECUTE sofia/external/mobile@provider bridge(user/<a href="mailto:20@192.168.1.5">20@192.168.1.5</a>)<br>
2015-10-26 01:39:27.523862 [DEBUG] switch_channel.c:1221 sofia/external/mobile@provider EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 26 Oct 2015 01:39:27 +0100] to event<br>
2015-10-26 01:39:27.523862 [DEBUG] switch_channel.c:1221 sofia/external/mobile@provider EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 26 Oct 2015 01:39:27 +0100] to event<br>
2015-10-26 01:39:27.523862 [DEBUG] switch_channel.c:1221 sofia/external/mobile@provider EXPORTING[export_vars] [dialed_extension]=[20] to event<br>
2015-10-26 01:39:27.523862 [DEBUG] switch_ivr_originate.c:2101 Parsing global variables<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_channel.c:1221 sofia/external/mobile@provider EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 26 Oct 2015 01:39:27 +0100] to event<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_channel.c:1221 sofia/external/mobile@provider EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 26 Oct 2015 01:39:27 +0100] to event<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_channel.c:1221 sofia/external/mobile@provider EXPORTING[export_vars] [dialed_extension]=[20] to event<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_ivr_originate.c:2101 Parsing global variables<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_event.c:1698 Parsing variable [sip_invite_domain]=[192.168.1.5]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_event.c:1698 Parsing variable [presence_id]=[<a href="mailto:20@192.168.1.5">20@192.168.1.5</a>]<br>
2015-10-26 01:39:27.543880 [NOTICE] switch_channel.c:1075 New Channel sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> [034101c4-7b7a-11e5-a624-cb96e1b49eeb]<br>
2015-10-26 01:39:27.543880 [DEBUG] mod_sofia.c:4700 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) State Change CS_NEW -> CS_INIT<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> [BREAK]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) Running State Change CS_INIT<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) State INIT<br>
2015-10-26 01:39:27.543880 [DEBUG] mod_sofia.c:87 sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> SOFIA INIT<br>
2015-10-26 01:39:27.543880 [DEBUG] sofia_glue.c:1241 sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> sending invite version: 1.4.23 git 1665168 2015-10-01 18:31:59Z 32bit<br>
Local SDP:<br>
v=0<br>
o=FreeSWITCH 1445799433 1445799434 IN IP4 192.168.1.5<br>
s=FreeSWITCH<br>
c=IN IP4 192.168.1.5<br>
t=0 0<br>
m=audio 20534 RTP/AVP 8 101 13<br>
a=rtpmap:8 PCMA/8000<br>
a=rtpmap:101 telephone-event/8000<br>
a=fmtp:101 0-16<br>
a=ptime:20<br>
a=sendrecv<br>
<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_state_machine.c:40 sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> Standard INIT<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) State Change CS_INIT -> CS_ROUTING<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> [BREAK]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) State INIT going to sleep<br>
2015-10-26 01:39:27.543880 [INFO] switch_ivr_originate.c:1193 Sending early media<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) Running State Change CS_ROUTING<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3595 Set telephone-event payload to 101<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:2506 Set Codec sofia/external/mobile@provider PCMA/8000 20 ms 160 samples 64000 bits 1 channels<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_codec.c:111 sofia/external/mobile@provider Original read codec set to PCMA:8<br>
<br>
send 1183 bytes to udp/[192.168.1.13]:5060 at 01:39:27.556739:<br>
------------------------------------------------------------------------<br>
INVITE <a href="http://sip:20@192.168.1.13:5060" rel="noreferrer" target="_blank">sip:20@192.168.1.13:5060</a> SIP/2.0<br>
Via: SIP/2.0/UDP 192.168.1.5;rport;branch=z9hG4bK01mvc388g3F4e<br>
Max-Forwards: 68<br>
From: "mobile" <<a href="mailto:sip%3Amobile@192.168.1.5">sip:mobile@192.168.1.5</a>>;tag=mX9UByt7H37DQ<br>
To: <<a href="http://sip:20@192.168.1.13:5060" rel="noreferrer" target="_blank">sip:20@192.168.1.13:5060</a>><br>
Call-ID: daa0593a-f61c-1233-8791-b827eb32337b<br>
CSeq: 82573919 INVITE<br>
Contact: <<a href="http://sip:mod_sofia@192.168.1.5:5060" rel="noreferrer" target="_blank">sip:mod_sofia@192.168.1.5:5060</a>><br>
User-Agent: FreeSWITCH-mod_sofia/1.4.23+git~20151001T183159Z~1665168d37~32bit<br>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE<br>
Supported: timer, path, replaces<br>
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer<br>
Content-Type: application/sdp<br>
Content-Disposition: session<br>
Content-Length: 221<br>
X-Ecan: On<br>
X-FS-Support: update_display,send_info<br>
Remote-Party-ID: "mobile" <<a href="mailto:sip%3Amobile@192.168.1.5">sip:mobile@192.168.1.5</a>>;party=calling;screen=yes;privacy=off<br>
<br>
v=0<br>
o=FreeSWITCH 1445799433 1445799434 IN IP4 192.168.1.5<br>
s=FreeSWITCH<br>
c=IN IP4 192.168.1.5<br>
t=0 0<br>
m=audio 20534 RTP/AVP 8 101 13<br>
a=rtpmap:8 PCMA/8000<br>
a=rtpmap:101 telephone-event/8000<br>
a=fmtp:101 0-16<br>
a=ptime:20<br>
------------------------------------------------------------------------<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) State ROUTING<br>
2015-10-26 01:39:27.543880 [DEBUG] mod_sofia.c:123 sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> SOFIA ROUTING<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) State Change CS_ROUTING -> CS_CONSUME_MEDIA<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> [BREAK]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) State ROUTING going to sleep<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> [BREAK]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) Running State Change CS_CONSUME_MEDIA<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:3942 Set 2833 dtmf send/recv payload to 101<br>
2015-10-26 01:39:27.543880 [INFO] switch_nat.c:588 NAT port mapping disabled<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:5178 AUDIO RTP [sofia/external/mobile@provider] 192.168.1.5 port 29360 -> xx.xx.xx.xx port 59648 codec: 8 ms: 20<br>
2015-10-26 01:39:27.543880 [DEBUG] sofia.c:6658 Channel sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> entering state [calling][0]<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_state_machine.c:547 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) State CONSUME_MEDIA<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_state_machine.c:547 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) State CONSUME_MEDIA going to sleep<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_rtp.c:3579 Starting timer [soft] 160 bytes per 20ms<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:5476 Set 2833 dtmf send payload to 101<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:5482 Set 2833 dtmf receive payload to 101<br>
2015-10-26 01:39:27.543880 [DEBUG] switch_core_media.c:5504 sofia/external/mobile@provider Set rtp dtmf delay to 40<br>
2015-10-26 01:39:27.543880 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/mobile@provider!<br>
2015-10-26 01:39:27.563871 [DEBUG] switch_channel.c:3419 (sofia/external/mobile@provider) Callstate Change RINGING -> EARLY<br>
2015-10-26 01:39:27.563871 [DEBUG] mod_sofia.c:2264 Ring SDP:<br>
v=0<br>
o=FreeSWITCH 1445790607 1445790608 IN IP4 xx.xx.xx.xx<br>
s=FreeSWITCH<br>
c=IN IP4 ext_ip_addr<br>
t=0 0<br>
m=audio 29360 RTP/AVP 8 101<br>
a=rtpmap:8 PCMA/8000<br>
a=rtpmap:101 telephone-event/8000<br>
a=fmtp:101 0-16<br>
a=ptime:20<br>
a=sendrecv<br>
<br>
2015-10-26 01:39:27.563871 [DEBUG] switch_core_session.c:913 Send signal sofia/external/mobile@provider [BREAK]<br>
2015-10-26 01:39:27.563871 [DEBUG] switch_ivr_originate.c:1250 Raw Codec Activation Success L16@8000hz 1 channel 20ms<br>
2015-10-26 01:39:27.563871 [DEBUG] switch_core_codec.c:221 sofia/external/mobile@provider Push codec L16:100<br>
2015-10-26 01:39:27.563871 [DEBUG] switch_ivr_originate.c:1318 Play Ringback Tone [%(1000,4000,425)]<br>
<br>
send 1067 bytes to udp/[xx.xx.xx.xx]:5060 at 01:39:27.575578:<br>
------------------------------------------------------------------------<br>
SIP/2.0 183 Session Progress<br>
Via: SIP/2.0/UDP 62.80.209.30:5060;branch=z9hG4bK-524287-1---f0231a59e0656a3a;rport=5060<br>
From: "mobile"<sip:mobile@provider;user=phone>;tag=b2a8ce1b<br>
To: "pstn_did" <sip:pstn_did@siptrunk;user=phone>;tag=gmUjH321ep8jF<br>
Call-ID: pSnT7Y8CK7u_vpaaKgC0bw..<br>
CSeq: 1 INVITE<br>
Contact: <sip:pstn_did@ext_ip_addr:5080;transport=udp><br>
User-Agent: FreeSWITCH-mod_sofia/1.4.23+git~20151001T183159Z~1665168d37~32bit<br>
Accept: application/sdp<br>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY<br>
Supported: timer, path, replaces<br>
Allow-Events: talk, hold, conference, refer<br>
Content-Type: application/sdp<br>
Content-Disposition: session<br>
Content-Length: 224<br>
Remote-Party-ID: "pstn_did" <sip:pstn_did@siptrunk>;party=calling;privacy=off;screen=no<br>
<br>
v=0<br>
o=FreeSWITCH 1445790607 1445790608 IN IP4 ext_ip_addr<br>
s=FreeSWITCH<br>
c=IN IP4 ext_ip_addr<br>
t=0 0<br>
m=audio 29360 RTP/AVP 8 101<br>
a=rtpmap:8 PCMA/8000<br>
a=rtpmap:101 telephone-event/8000<br>
a=fmtp:101 0-16<br>
a=ptime:20<br>
------------------------------------------------------------------------<br>
2015-10-26 01:39:27.563871 [DEBUG] switch_core_session.c:1062 Send signal sofia/external/mobile@provider [BREAK]<br>
2015-10-26 01:39:27.583855 [DEBUG] sofia.c:6658 Channel sofia/external/mobile@provider entering state [early][183]<br>
recv 370 bytes from udp/[192.168.1.13]:5060 at 01:39:27.628443:<br>
------------------------------------------------------------------------<br>
SIP/2.0 100 Trying<br>
Via: SIP/2.0/UDP 192.168.1.5;rport=5060;branch=z9hG4bK01mvc388g3F4e<br>
From: "mobile" <<a href="mailto:sip%3Amobile@192.168.1.5">sip:mobile@192.168.1.5</a>>;tag=mX9UByt7H37DQ<br>
To: <<a href="http://sip:20@192.168.1.13:5060" rel="noreferrer" target="_blank">sip:20@192.168.1.13:5060</a>>;tag=<a href="tel:2174874080" value="+12174874080">2174874080</a><br>
Call-ID: daa0593a-f61c-1233-8791-b827eb32337b<br>
CSeq: 82573919 INVITE<br>
Contact: <<a href="http://sip:20@192.168.1.13:5060" rel="noreferrer" target="_blank">sip:20@192.168.1.13:5060</a>><br>
User-Agent: C530 IP/42.207.00.000.000<br>
Content-Length: 0<br>
<br>
------------------------------------------------------------------------<br>
recv 421 bytes from udp/[192.168.1.13]:5060 at 01:39:28.125276:<br>
------------------------------------------------------------------------<br>
SIP/2.0 180 Ringing<br>
Via: SIP/2.0/UDP 192.168.1.5;rport=5060;branch=z9hG4bK01mvc388g3F4e<br>
From: "mobile" <<a href="mailto:sip%3Amobile@192.168.1.5">sip:mobile@192.168.1.5</a>>;tag=mX9UByt7H37DQ<br>
To: <<a href="http://sip:20@192.168.1.13:5060" rel="noreferrer" target="_blank">sip:20@192.168.1.13:5060</a>>;tag=<a href="tel:2174874080" value="+12174874080">2174874080</a><br>
Call-ID: daa0593a-f61c-1233-8791-b827eb32337b<br>
CSeq: 82573919 INVITE<br>
Contact: <<a href="http://sip:20@192.168.1.13:5060" rel="noreferrer" target="_blank">sip:20@192.168.1.13:5060</a>><br>
Allow-Events: message-summary, refer, ua-profile<br>
User-Agent: C530 IP/42.207.00.000.000<br>
Content-Length: 0<br>
<br>
------------------------------------------------------------------------<br>
2015-10-26 01:39:28.123847 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> [BREAK]<br>
2015-10-26 01:39:28.123847 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> [BREAK]<br>
2015-10-26 01:39:28.123847 [DEBUG] sofia.c:6658 Channel sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a> entering state [proceeding][180]<br>
2015-10-26 01:39:28.123847 [NOTICE] sofia.c:6760 Ring-Ready sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>!<br>
2015-10-26 01:39:28.123847 [DEBUG] switch_channel.c:3297 (sofia/internal/<a href="http://20@192.168.1.13:5060" rel="noreferrer" target="_blank">20@192.168.1.13:5060</a>) Callstate Change DOWN -> RINGING<br>
<br>
_________________________________________________________________________<br>
Professional FreeSWITCH Consulting Services:<br>
<a href="mailto:consulting@freeswitch.org">consulting@freeswitch.org</a><br>
<a href="http://www.freeswitchsolutions.com" rel="noreferrer" target="_blank">http://www.freeswitchsolutions.com</a><br>
<br>
Official FreeSWITCH Sites<br>
<a href="http://www.freeswitch.org" rel="noreferrer" target="_blank">http://www.freeswitch.org</a><br>
<a href="http://confluence.freeswitch.org" rel="noreferrer" target="_blank">http://confluence.freeswitch.org</a><br>
<a href="http://www.cluecon.com" rel="noreferrer" target="_blank">http://www.cluecon.com</a><br>
<br>
FreeSWITCH-users mailing list<br>
<a href="mailto:FreeSWITCH-users@lists.freeswitch.org">FreeSWITCH-users@lists.freeswitch.org</a><br>
<a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" rel="noreferrer" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>
UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" rel="noreferrer" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br>
<a href="http://www.freeswitch.org" rel="noreferrer" target="_blank">http://www.freeswitch.org</a><br>
</blockquote></div><br><br clear="all"><div><br></div>-- <br><div class="gmail_signature"><div dir="ltr"><div><div dir="ltr"><div><div dir="ltr"><div><div dir="ltr"><div><div dir="ltr">
<p><font face="courier new, monospace"><b><i><font size="4">Brian West</font></i></b><br><span style="font-size:x-small"><a href="mailto:brian@freeswitch.org" target="_blank">brian@freeswitch.org</a></span></font></p>
<p><font size="1" face="courier new, monospace"><img src="http://billing.freeswitch.org/templates/default/img/whmcslogo.png"><br></font></p><p><font size="2" face="monospace, monospace"><b><i>Twitter: @FreeSWITCH , @briankwest</i></b><br><a href="http://www.freeswitchbook.com" target="_blank">http://www.freeswitchbook.com</a><br><a href="http://www.freeswitchcookbook.com" target="_blank">http://www.freeswitchcookbook.com</a></font></p><p><font face="monospace, monospace">Got Bugs? Report them <a href="https://freeswitch.org/jira" target="_blank">here</a>! | Reddit: <a href="https://www.reddit.com/r/freeswitch" target="_blank">/r/freeswitch</a></font></p>
<p><font size="2" face="monospace, monospace"><b>T:</b>+19184209001 | <b>F:</b>+19184209002 | <b>M:</b>+1918424WEST (9378)<br><b>iNUM:</b>+883 5100 1420 9001 | <b>ISN:</b>410*543 | <b>Skype:</b>briankwest</font></p></div></div></div></div></div></div></div></div></div></div>
</div>