[Freeswitch-users] Getting issue with audio

4 Apr 2014

      Hi All,

I am new to Freeswitch and I have setup the Freeswitch server but, I got an
issue when I am trying to make call within a network or LAN the call is
working for only 30 seconds. But when I made a call outside from the LAN
from within the LAN, then the call is working for only 30 seconds as well.
And If I tried to call from outside the network into the LAN then this time
audio is not working anymore, this same issue with the call was made
outside to outside from the network. I have enter the Public IP in the
field of Ext-RTP-IP and Ext-SIP-IP.

I am pasting the logs, when I made a call outside from the LAN into the LAN.

recv 1302 bytes from udp/[172.28.129.249]:5060 at 13:14:41.435126:
...
------------------------------------------------------------------------
   INVITE sip:1001@sip.difbroker.com SIP/2.0
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   Via: SIP/2.0/UDP 80.251.161.254:5060;branch=z9hG4bKabe6.2d9f8c12.0
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjxWGCj7y2jEBuztIUAgR0hcX9pmZwi2rj
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>
   Contact: <sip:user3@112.196.33.74:4713;ob>
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27966 INVITE
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE,
NOTIFY, REFER, MESSAGE, OPTIONS
   Supported: replaces, 100rel, timer, norefersub
   Session-Expires: 1800
   Min-SE: 90
   User-Agent: CSipSimple_A89-15/r2330
   Content-Type: application/sdp
   Content-Length:   475
   X-AUTH-IP: 112.196.33.74
v=0
   o=- 3605519857 3605519857 IN IP4 112.196.33.74
   s=pjmedia
   c=IN IP4 112.196.33.74
   t=0 0
   m=audio 4006 RTP/AVP 99 0 8 9 3 101
   c=IN IP4 112.196.33.74
   a=rtcp:4007 IN IP4 192.168.1.107
   a=sendrecv
   a=rtpmap:99 SILK/24000
   a=fmtp:99 useinbandfec=0
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=rtpmap:9 G722/8000
   a=rtpmap:3 GSM/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-15
   a=oldmediaip:192.168.1.107
   a=oldmediaip:192.168.1.107
   a=oldmediaip:192.168.1.107
   ------------------------------------------------------------------------
send 525 bytes to udp/[172.28.129.249]:5060 at 13:14:41.435612:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKabe6.2d9f8c12.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjxWGCj7y2jEBuztIUAgR0hcX9pmZwi2rj
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27966 INVITE
   User-Agent: 2600hz
   Content-Length: 0
------------------------------------------------------------------------
2014-04-03 14:14:41.436572 [NOTICE] switch_channel.c:1055 New Channel
sofia/sipinterface_1/user3@sip.difbroker.com[jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE]
2014-04-03 14:14:41.436572 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.436572 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.436572 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user3@sip.difbroker.com) Running State Change CS_NEW
2014-04-03 14:14:41.436572 [DEBUG] sofia.c:8186 1 acls to check for proxy
2014-04-03 14:14:41.436572 [DEBUG] sofia.c:8191 checking 172.28.129.249
against acl authoritative
2014-04-03 14:14:41.436572 [INFO] sofia.c:8193 172.28.129.249 is a proxy
according to the authoritative acl
2014-04-03 14:14:41.436572 [DEBUG] sofia.c:8203 network ip is a proxy
2014-04-03 14:14:41.436572 [DEBUG] sofia.c:8207 found auth ip [X-AUTH-IP]
header of [112.196.33.74]
2014-04-03 14:14:41.436572 [DEBUG] sofia.c:8231 IP 172.28.129.249 Rejected
by acl "trusted". Falling back to Digest auth.
2014-04-03 14:14:41.436572 [WARNING] sofia_reg.c:1634 SIP auth challenge
(INVITE) on sofia profile 'sipinterface_1' for [1001@sip.difbroker.com]
from ip 172.28.129.249
2014-04-03 14:14:41.436572 [DEBUG] switch_core_state_machine.c:486
(sofia/sipinterface_1/user3@sip.difbroker.com) State NEW
send 960 bytes to udp/[172.28.129.249]:5060 at 13:14:41.437737:
   ------------------------------------------------------------------------
   SIP/2.0 407 Proxy Authentication Required
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKabe6.2d9f8c12.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjxWGCj7y2jEBuztIUAgR0hcX9pmZwi2rj
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=pjpUeS2B67apD
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27966 INVITE
   User-Agent: 2600hz
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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
   Proxy-Authenticate: Digest realm="sip.difbroker.com",
nonce="ea906dc2-bb31-11e3-bc8c-35dd2b4be3da", algorithm=MD5, qop="auth"
   Content-Length: 0
------------------------------------------------------------------------
2014-04-03 14:14:41.436572 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.436572 [DEBUG] sofia.c:1845 detaching session
jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
recv 315 bytes from udp/[172.28.129.249]:5060 at 13:14:41.438998:
   ------------------------------------------------------------------------
   ACK sip:1001@sip.difbroker.com SIP/2.0
   Via: SIP/2.0/UDP 80.251.161.254:5060;branch=z9hG4bKabe6.2d9f8c12.0
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=pjpUeS2B67apD
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27966 ACK
   Content-Length: 0
------------------------------------------------------------------------
recv 1581 bytes from udp/[172.28.129.249]:5060 at 13:14:41.656438:
   ------------------------------------------------------------------------
   INVITE sip:1001@sip.difbroker.com SIP/2.0
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   Via: SIP/2.0/UDP 80.251.161.254:5060;branch=z9hG4bKbbe6.305cc383.0
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>
   Contact: <sip:user3@112.196.33.74:4713;ob>
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE,
NOTIFY, REFER, MESSAGE, OPTIONS
   Supported: replaces, 100rel, timer, norefersub
   Session-Expires: 1800
   Min-SE: 90
   User-Agent: CSipSimple_A89-15/r2330
   Proxy-Authorization: Digest username="user3", realm="sip.difbroker.com",
nonce="ea906dc2-bb31-11e3-bc8c-35dd2b4be3da", uri="
sip:1001@sip.difbroker.com", response="93986330c155dd769e347f0299a9d0dc",
algorithm=MD5, cnonce="UoEHUKZhVkQTVk-nz8CZuqOnwbqksAfy", qop=auth,
nc=00000001
   Content-Type: application/sdp
   Content-Length:   475
   X-AUTH-IP: 112.196.33.74
v=0
   o=- 3605519857 3605519857 IN IP4 112.196.33.74
   s=pjmedia
   c=IN IP4 112.196.33.74
   t=0 0
   m=audio 4006 RTP/AVP 99 0 8 9 3 101
   c=IN IP4 112.196.33.74
   a=rtcp:4007 IN IP4 192.168.1.107
   a=sendrecv
   a=rtpmap:99 SILK/24000
   a=fmtp:99 useinbandfec=0
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=rtpmap:9 G722/8000
   a=rtpmap:3 GSM/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-15
   a=oldmediaip:192.168.1.107
   a=oldmediaip:192.168.1.107
   a=oldmediaip:192.168.1.107
   ------------------------------------------------------------------------
send 525 bytes to udp/[172.28.129.249]:5060 at 13:14:41.656879:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   User-Agent: 2600hz
   Content-Length: 0
------------------------------------------------------------------------
2014-04-03 14:14:41.657240 [DEBUG] sofia.c:1937 Re-attaching to session
jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
2014-04-03 14:14:41.657240 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.657240 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.657240 [DEBUG] sofia.c:8186 1 acls to check for proxy
2014-04-03 14:14:41.657240 [DEBUG] sofia.c:8191 checking 172.28.129.249
against acl authoritative
2014-04-03 14:14:41.657240 [INFO] sofia.c:8193 172.28.129.249 is a proxy
according to the authoritative acl
2014-04-03 14:14:41.657240 [DEBUG] sofia.c:8203 network ip is a proxy
2014-04-03 14:14:41.657240 [DEBUG] sofia.c:8207 found auth ip [X-AUTH-IP]
header of [112.196.33.74]
2014-04-03 14:14:41.657240 [DEBUG] sofia.c:8231 IP 172.28.129.249 Rejected
by acl "trusted". Falling back to Digest auth.
2014-04-03 14:14:41.657240 [DEBUG] switch_xml.c:1999 Cache Info
Time Now:    1396530881657240
Expires:    1396530849802816
2014-04-03 14:14:41.657240 [DEBUG] switch_xml.c:2001 Cache expired for
user3@sip.difbroker.com, doing fresh lookup
2014-04-03 14:14:41.657240 [DEBUG] kazoo_fetch_agent.c:216 Sending
directory XML request (eab24212-bb31-11e3-bc8d-35dd2b4be3da) to
ecallmgr@pbx.difbroker.com <2.1034.0>
2014-04-03 14:14:41.657240 [DEBUG] kazoo_node.c:1091 Sent erlang message
to ecallmgr@pbx.difbroker.com <2.1034.0>
2014-04-03 14:14:41.657240 [DEBUG] kazoo_fetch_agent.c:277 Received
directory XML (eab24212-bb31-11e3-bc8d-35dd2b4be3da) after 0ms: <document
type="freeswitch/xml"><section name="directory"><domain name="
sip.difbroker.com"><user id="user3"
cacheable="3600000000"><variables><variable name="ecallmgr_Inception"
value="on-net"/><variable name="ecallmgr_Authorizing-Type"
value="device"/><variable name="ecallmgr_Authorizing-ID"
value="49855e170e1cb8aa38ff5a831d194904"/><variable
name="ecallmgr_Account-ID"
value="eb94496d34bab9865a73b35ad452a03c"/><variable name="ecallmgr_Realm"
value="sip.difbroker.com"/><variable name="ecallmgr_Username"
value="user3"/></variables><params><param name="password"
value="user3"/></params></user></domain></section></document>
2014-04-03 14:14:41.657240 [DEBUG] switch_xml.c:2077 caching lookup for
user user3@sip.difbroker.com for -694967296 milliseconds
2014-04-03 14:14:41.657240 [DEBUG] sofia.c:5889 Channel
sofia/sipinterface_1/user3@sip.difbroker.com entering state
[received][100]
2014-04-03 14:14:41.657240 [DEBUG] sofia.c:5899 Remote SDP:
v=0
o=- 3605519857 3605519857 IN IP4 112.196.33.74
s=pjmedia
c=IN IP4 112.196.33.74
t=0 0
m=audio 4006 RTP/AVP 99 0 8 9 3 101
c=IN IP4 112.196.33.74
a=rtpmap:99 SILK/24000
a=fmtp:99 useinbandfec=0
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:4007 IN IP4 192.168.1.107
a=oldmediaip:192.168.1.107
a=oldmediaip:192.168.1.107
a=oldmediaip:192.168.1.107
2014-04-03 14:14:41.657240 [DEBUG] sofia.c:6144 (sofia/sipinterface_1/
user3@sip.difbroker.com) State Change CS_NEW -> CS_INIT
2014-04-03 14:14:41.657240 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.657240 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user3@sip.difbroker.com) Running State Change
CS_INIT
2014-04-03 14:14:41.657240 [DEBUG] switch_core_state_machine.c:507
(sofia/sipinterface_1/user3@sip.difbroker.com) State INIT
2014-04-03 14:14:41.657240 [DEBUG] mod_sofia.c:87 sofia/sipinterface_1/
user3@sip.difbroker.com SOFIA INIT
2014-04-03 14:14:41.657240 [DEBUG] switch_core_state_machine.c:40
sofia/sipinterface_1/user3@sip.difbroker.com Standard INIT
2014-04-03 14:14:41.657240 [DEBUG] switch_core_state_machine.c:48
(sofia/sipinterface_1/user3@sip.difbroker.com) State Change CS_INIT ->
CS_ROUTING
2014-04-03 14:14:41.657240 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.657240 [DEBUG] switch_core_state_machine.c:507
(sofia/sipinterface_1/user3@sip.difbroker.com) State INIT going to sleep
2014-04-03 14:14:41.657240 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user3@sip.difbroker.com) Running State Change
CS_ROUTING
2014-04-03 14:14:41.657240 [DEBUG] switch_channel.c:2179
(sofia/sipinterface_1/user3@sip.difbroker.com) Callstate Change DOWN ->
RINGING
2014-04-03 14:14:41.657240 [DEBUG] switch_core_state_machine.c:523
(sofia/sipinterface_1/user3@sip.difbroker.com) State ROUTING
2014-04-03 14:14:41.657240 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/
user3@sip.difbroker.com SOFIA ROUTING
2014-04-03 14:14:41.657240 [DEBUG] switch_core_state_machine.c:164
sofia/sipinterface_1/user3@sip.difbroker.com Standard ROUTING
2014-04-03 14:14:41.657240 [INFO] mod_dialplan_xml.c:558 Processing user3
<user3>->1001 in context context_2
2014-04-03 14:14:41.657240 [DEBUG] kazoo_fetch_agent.c:216 Sending
dialplan XML request (eab2c5ac-bb31-11e3-bc8f-35dd2b4be3da) to
ecallmgr@pbx.difbroker.com <2.1035.0>
2014-04-03 14:14:41.657240 [DEBUG] kazoo_node.c:1091 Sent erlang message
to ecallmgr@pbx.difbroker.com <2.1035.0>
2014-04-03 14:14:41.676550 [DEBUG] kazoo_fetch_agent.c:277 Received
dialplan XML (eab2c5ac-bb31-11e3-bc8f-35dd2b4be3da) after 19ms: <document
type="freeswitch/xml"><section name="dialplan" description="Route Park
Response"><context name="context_2"><extension
name="park"><condition><action application="log" data="NOTICE log|${uuid}|
ecallmgr@pbx.difbroker.com won call control"/><action
application="export" data="ecallmgr_Ecallmgr-Node=
ecallmgr@pbx.difbroker.com"/><condition
field="variable_ecallmgr_Bridge-ID" expression="^$"><action
application="export" data="ecallmgr_Bridge-ID=${UUID}"/></condition><action
application="set" data="ringback=%(2000,4000,440,480)"/><action
application="set" data="transfer_ringback=%(2000,4000,440,480)"/><action
application="park"/></condition></extension></context></section></document>
Dialplan: sofia/sipinterface_1/user3@sip.difbroker.com parsing
[context_2->park] continue=false
|--- Dialplan: Processing recursive conditions level:1 [park_recur_1]
require-nested=TRUE
|--- Dialplan: sofia/sipinterface_1/user3@sip.difbroker.com Regex (PASS)
[park_recur_1] variable_ecallmgr_Bridge-ID() =~ /^$/ break=on-false
|--- Dialplan: sofia/sipinterface_1/user3@sip.difbroker.com Action
export(ecallmgr_Bridge-ID=${UUID})
Dialplan: sofia/sipinterface_1/user3@sip.difbroker.com Absolute Condition
[park]
Dialplan: sofia/sipinterface_1/user3@sip.difbroker.com Action log(NOTICE
log|${uuid}|ecallmgr@pbx.difbroker.com won call control)
Dialplan: sofia/sipinterface_1/user3@sip.difbroker.com Action
export(ecallmgr_Ecallmgr-Node=ecallmgr@pbx.difbroker.com)
Dialplan: sofia/sipinterface_1/user3@sip.difbroker.com Action
set(ringback=%(2000,4000,440,480))
Dialplan: sofia/sipinterface_1/user3@sip.difbroker.com Action
set(transfer_ringback=%(2000,4000,440,480))
Dialplan: sofia/sipinterface_1/user3@sip.difbroker.com Action park()
2014-04-03 14:14:41.676550 [DEBUG] switch_core_state_machine.c:214
(sofia/sipinterface_1/user3@sip.difbroker.com) State Change CS_ROUTING ->
CS_EXECUTE
2014-04-03 14:14:41.676550 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.676550 [DEBUG] switch_core_state_machine.c:523
(sofia/sipinterface_1/user3@sip.difbroker.com) State ROUTING going to
sleep
2014-04-03 14:14:41.676550 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user3@sip.difbroker.com) Running State Change
CS_EXECUTE
2014-04-03 14:14:41.676550 [DEBUG] switch_core_state_machine.c:530
(sofia/sipinterface_1/user3@sip.difbroker.com) State EXECUTE
2014-04-03 14:14:41.676550 [DEBUG] mod_sofia.c:178 sofia/sipinterface_1/
user3@sip.difbroker.com SOFIA EXECUTE
2014-04-03 14:14:41.676550 [DEBUG] switch_core_state_machine.c:256
sofia/sipinterface_1/user3@sip.difbroker.com Standard EXECUTE
EXECUTE sofia/sipinterface_1/user3@sip.difbroker.comexport(ecallmgr_Bridge-ID=jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE)
2014-04-03 14:14:41.676550 [DEBUG] switch_channel.c:1247 EXPORT
(export_vars) [ecallmgr_Bridge-ID]=[jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE]
EXECUTE sofia/sipinterface_1/user3@sip.difbroker.com log(NOTICE
log|jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE|ecallmgr@pbx.difbroker.com won call
control)
2014-04-03 14:14:41.676550 [NOTICE] mod_dptools.c:1634
log|jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE|ecallmgr@pbx.difbroker.com won call
control
EXECUTE sofia/sipinterface_1/user3@sip.difbroker.comexport(ecallmgr_Ecallmgr-Node=
ecallmgr@pbx.difbroker.com)
2014-04-03 14:14:41.676550 [DEBUG] switch_channel.c:1247 EXPORT
(export_vars) [ecallmgr_Ecallmgr-Node]=[ecallmgr@pbx.difbroker.com]
EXECUTE sofia/sipinterface_1/user3@sip.difbroker.comset(ringback=%(2000,4000,440,480))
2014-04-03 14:14:41.676550 [DEBUG] mod_dptools.c:1402 sofia/sipinterface_1/
user3@sip.difbroker.com SET [ringback]=[%(2000,4000,440,480)]
EXECUTE sofia/sipinterface_1/user3@sip.difbroker.comset(transfer_ringback=%(2000,4000,440,480))
2014-04-03 14:14:41.676550 [DEBUG] mod_dptools.c:1402 sofia/sipinterface_1/
user3@sip.difbroker.com SET [transfer_ringback]=[%(2000,4000,440,480)]
EXECUTE sofia/sipinterface_1/user3@sip.difbroker.com park()
2014-04-03 14:14:41.676550 [DEBUG] kazoo_node.c:1091 Sent erlang message
to ecallmgr@pbx.difbroker.com <2.2946.0>
2014-04-03 14:14:41.698999 [INFO] kazoo_node.c:627 exec:
uuid_setvar_multi(jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
effective_caller_id_name=user3;effective_caller_id_number=user3;ecallmgr_Username=user3;ecallmgr_Realm=
sip.difbroker.com
;ecallmgr_Account-ID=eb94496d34bab9865a73b35ad452a03c;ecallmgr_Authorizing-ID=49855e170e1cb8aa38ff5a831d194904;ecallmgr_Authorizing-Type=device;ecallmgr_Inception=on-net;ecallmgr_Fetch-ID=eab2c5ac-bb31-11e3-bc8f-35dd2b4be3da)
2014-04-03 14:14:41.722675 [DEBUG] kazoo_node.c:1091 Sent erlang message
to ecallmgr@pbx.difbroker.com <2.2963.0>
2014-04-03 14:14:41.722675 [NOTICE] kazoo_node.c:302
log|jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE|building xferext extension: set
continue_on_fail=true
2014-04-03 14:14:41.722675 [NOTICE] kazoo_node.c:302
log|jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE|building xferext extension: export
sip_redirect_context=context_2
2014-04-03 14:14:41.722675 [NOTICE] kazoo_node.c:302
log|jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE|building xferext extension: bridge
{call_timeout=20,originate_timeout=20,local_var_clobber='true'}[ignore_completed_elsewhere='false',ecallmgr_Authorizing-ID='d1639ecfdc0f31d42cbb9f0f5d185d13',ecallmgr_Account-ID='eb94496d34bab9865a73b35ad452a03c',t38_passthrough='true',sdp_secure_savp_only='true',sip_invite_domain='
sip.difbroker.com',presence_id='user1@sip.difbroker.com
',absolute_codec_string='^^:PCMU:PCMA',leg_timeout='20',effective_callee_id_number='1001',effective_callee_id_name='Test1',origination_callee_id_number='1001',origination_callee_id_name='Test1',ecallmgr_Realm='
sip.difbroker.com
',ecallmgr_Username='user1']sofia/sipinterface_1/user1@172.28.129.231:56260
;fs_path=sip:172.28.129.249:5060;lr;received='sip:172.28.129.231:56260'
2014-04-03 14:14:41.722675 [NOTICE] kazoo_node.c:302
log|jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE|building xferext extension: event
Event-Name=CUSTOM,Event-Subclass=whistle::masquerade,whistle_event_name=CHANNEL_EXECUTE_COMPLETE,whistle_application_name=bridge
2014-04-03 14:14:41.722675 [NOTICE] kazoo_node.c:302
log|jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE|building xferext extension: park
2014-04-03 14:14:41.722675 [NOTICE] kazoo_node.c:305
log|jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE|transfered call to xferext extension
2014-04-03 14:14:41.722675 [DEBUG] switch_core_session.c:1183 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.736551 [DEBUG] switch_channel.c:3098
(sofia/sipinterface_1/user3@sip.difbroker.com) State Change CS_EXECUTE ->
CS_ROUTING
2014-04-03 14:14:41.736551 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:530
(sofia/sipinterface_1/user3@sip.difbroker.com) State EXECUTE going to
sleep
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user3@sip.difbroker.com) Running State Change
CS_ROUTING
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:523
(sofia/sipinterface_1/user3@sip.difbroker.com) State ROUTING
2014-04-03 14:14:41.736551 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/
user3@sip.difbroker.com SOFIA ROUTING
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:164
sofia/sipinterface_1/user3@sip.difbroker.com Standard ROUTING
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:184
(sofia/sipinterface_1/user3@sip.difbroker.com) State Change CS_ROUTING ->
CS_EXECUTE
2014-04-03 14:14:41.736551 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:523
(sofia/sipinterface_1/user3@sip.difbroker.com) State ROUTING going to
sleep
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user3@sip.difbroker.com) Running State Change
CS_EXECUTE
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:530
(sofia/sipinterface_1/user3@sip.difbroker.com) State EXECUTE
2014-04-03 14:14:41.736551 [DEBUG] mod_sofia.c:178 sofia/sipinterface_1/
user3@sip.difbroker.com SOFIA EXECUTE
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:256
sofia/sipinterface_1/user3@sip.difbroker.com Standard EXECUTE
EXECUTE sofia/sipinterface_1/user3@sip.difbroker.comset(continue_on_fail=true)
2014-04-03 14:14:41.736551 [DEBUG] mod_dptools.c:1402 sofia/sipinterface_1/
user3@sip.difbroker.com SET [continue_on_fail]=[true]
EXECUTE sofia/sipinterface_1/user3@sip.difbroker.comexport(sip_redirect_context=context_2)
2014-04-03 14:14:41.736551 [DEBUG] switch_channel.c:1247 EXPORT
(export_vars) [sip_redirect_context]=[context_2]
EXECUTE sofia/sipinterface_1/user3@sip.difbroker.combridge({call_timeout=20,originate_timeout=20,local_var_clobber='true'}[ignore_completed_elsewhere='false',ecallmgr_Authorizing-ID='d1639ecfdc0f31d42cbb9f0f5d185d13',ecallmgr_Account-ID='eb94496d34bab9865a73b35ad452a03c',t38_passthrough='true',sdp_secure_savp_only='true',sip_invite_domain='
sip.difbroker.com',presence_id='user1@sip.difbroker.com
',absolute_codec_string='^^:PCMU:PCMA',leg_timeout='20',effective_callee_id_number='1001',effective_callee_id_name='Test1',origination_callee_id_number='1001',origination_callee_id_name='Test1',ecallmgr_Realm='
sip.difbroker.com
',ecallmgr_Username='user1']sofia/sipinterface_1/user1@172.28.129.231:56260
;fs_path=sip:172.28.129.249:5060;lr;received='sip:172.28.129.231:56260')
2014-04-03 14:14:41.736551 [DEBUG] switch_channel.c:1201
sofia/sipinterface_1/user3@sip.difbroker.com EXPORTING[export_vars]
[ecallmgr_Bridge-ID]=[jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE] to event
2014-04-03 14:14:41.736551 [DEBUG] switch_channel.c:1201
sofia/sipinterface_1/user3@sip.difbroker.com EXPORTING[export_vars]
[ecallmgr_Ecallmgr-Node]=[ecallmgr@pbx.difbroker.com] to event
2014-04-03 14:14:41.736551 [DEBUG] switch_channel.c:1201
sofia/sipinterface_1/user3@sip.difbroker.com EXPORTING[export_vars]
[sip_redirect_context]=[context_2] to event
2014-04-03 14:14:41.736551 [DEBUG] switch_ivr_originate.c:2071 Parsing
global variables
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[call_timeout]=[20]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[originate_timeout]=[20]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[local_var_clobber]=[true]
2014-04-03 14:14:41.736551 [DEBUG] switch_ivr_originate.c:2518 Parsing
session specific variables
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[ignore_completed_elsewhere]=[false]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[ecallmgr_Authorizing-ID]=[d1639ecfdc0f31d42cbb9f0f5d185d13]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[ecallmgr_Account-ID]=[eb94496d34bab9865a73b35ad452a03c]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[t38_passthrough]=[true]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[sdp_secure_savp_only]=[true]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[sip_invite_domain]=[sip.difbroker.com]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[presence_id]=[user1@sip.difbroker.com]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[absolute_codec_string]=[^^:PCMU:PCMA]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[leg_timeout]=[20]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[effective_callee_id_number]=[1001]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[effective_callee_id_name]=[Test1]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[origination_callee_id_number]=[1001]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[origination_callee_id_name]=[Test1]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[ecallmgr_Realm]=[sip.difbroker.com]
2014-04-03 14:14:41.736551 [DEBUG] switch_event.c:1687 Parsing variable
[ecallmgr_Username]=[user1]
2014-04-03 14:14:41.736551 [NOTICE] switch_channel.c:1055 New Channel
sofia/sipinterface_1/user1@172.28.129.231:56260[eac00fdc-bb31-11e3-bc9c-35dd2b4be3da]
2014-04-03 14:14:41.736551 [DEBUG] mod_sofia.c:4456 (sofia/sipinterface_1/
user1@172.28.129.231:56260) State Change CS_NEW -> CS_INIT
2014-04-03 14:14:41.736551 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:14:41.736551 [DEBUG] switch_ivr_originate.c:2812
sofia/sipinterface_1/user1@172.28.129.231:56260 Setting leg timeout to 20
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user1@172.28.129.231:56260) Running State Change
CS_INIT
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:507
(sofia/sipinterface_1/user1@172.28.129.231:56260) State INIT
2014-04-03 14:14:41.736551 [DEBUG] mod_sofia.c:87 sofia/sipinterface_1/
user1@172.28.129.231:56260 SOFIA INIT
2014-04-03 14:14:41.736551 [DEBUG] sofia_glue.c:1200 sip:
172.28.129.249:5060 Setting proxy route to sofia/sipinterface_1/
user1@172.28.129.231:56260
2014-04-03 14:14:41.736551 [DEBUG] sofia_glue.c:1229 Local SDP:
v=0
o=FreeSWITCH 1396506509 1396506510 IN IP4 172.28.129.249
s=FreeSWITCH
c=IN IP4 172.28.129.249
t=0 0
m=audio 24372 RTP/AVP 0 8 101 13
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:40
sofia/sipinterface_1/user1@172.28.129.231:56260 Standard INIT
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:48
(sofia/sipinterface_1/user1@172.28.129.231:56260) State Change CS_INIT ->
CS_ROUTING
2014-04-03 14:14:41.736551 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:507
(sofia/sipinterface_1/user1@172.28.129.231:56260) State INIT going to
sleep
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user1@172.28.129.231:56260) Running State Change
CS_ROUTING
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:523
(sofia/sipinterface_1/user1@172.28.129.231:56260) State ROUTING
2014-04-03 14:14:41.736551 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/
user1@172.28.129.231:56260 SOFIA ROUTING
2014-04-03 14:14:41.736551 [DEBUG] switch_ivr_originate.c:67
(sofia/sipinterface_1/user1@172.28.129.231:56260) State Change CS_ROUTING
-> CS_CONSUME_MEDIA
2014-04-03 14:14:41.736551 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:523
(sofia/sipinterface_1/user1@172.28.129.231:56260) State ROUTING going to
sleep
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user1@172.28.129.231:56260) Running State Change
CS_CONSUME_MEDIA
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:542
(sofia/sipinterface_1/user1@172.28.129.231:56260) State CONSUME_MEDIA
send 1182 bytes to udp/[172.28.129.249]:5060 at 13:14:41.755233:
   ------------------------------------------------------------------------
   INVITE sip:user1@172.28.129.231:56260 SIP/2.0
   Via: SIP/2.0/UDP 80.251.161.254:11000;rport;branch=z9hG4bKHeB5026mSQFeN
   Route: <sip:172.28.129.249:5060>
   Max-Forwards: 70
   From: "user3" <sip:user3@sip.difbroker.com>;tag=r48cjF4j0SQUm
   To: <sip:user1@172.28.129.231:56260>
   Call-ID: eac00fdc-bb31-11e3-bc9c-35dd2b4be3da
   CSeq: 57929376 INVITE
   Contact: <sip:mod_sofia@80.251.161.254:11000>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 207
   X-AUTH-IP: 112.196.33.74
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "user3" <sip:user3@sip.difbroker.com
...
;party=calling;screen=yes;privacy=off
v=0
   o=FreeSWITCH 1396506509 1396506510 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 24372 RTP/AVP 0 8 101 13
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
2014-04-03 14:14:41.756711 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
recv 340 bytes from udp/[172.28.129.249]:5060 at 13:14:41.758132:
   ------------------------------------------------------------------------
   SIP/2.0 100 Attempting to connect your call
   Via: SIP/2.0/UDP 80.251.161.254:11000
;rport=11000;branch=z9hG4bKHeB5026mSQFeN;received=172.28.129.249
   From: "user3" <sip:user3@sip.difbroker.com>;tag=r48cjF4j0SQUm
   To: <sip:user1@172.28.129.231:56260>
   Call-ID: eac00fdc-bb31-11e3-bc9c-35dd2b4be3da
   CSeq: 57929376 INVITE
   Content-Length: 0
------------------------------------------------------------------------
2014-04-03 14:14:41.736551 [DEBUG] switch_core_state_machine.c:542
(sofia/sipinterface_1/user1@172.28.129.231:56260) State CONSUME_MEDIA
going to sleep
2014-04-03 14:14:41.756711 [DEBUG] sofia.c:5889 Channel
sofia/sipinterface_1/user1@172.28.129.231:56260 entering state
[calling][0]
recv 550 bytes from udp/[172.28.129.249]:5060 at 13:14:41.917022:
   ------------------------------------------------------------------------
   SIP/2.0 180 Ringing
   Via: SIP/2.0/UDP 80.251.161.254:11000
;rport=11000;received=172.28.129.249;branch=z9hG4bKHeB5026mSQFeN
   Record-Route: <sip:80.251.161.254;lr;ftag=r48cjF4j0SQUm>
   Call-ID: eac00fdc-bb31-11e3-bc9c-35dd2b4be3da
   From: "user3" <sip:user3@sip.difbroker.com>;tag=r48cjF4j0SQUm
   To: <sip:user1@172.28.129.231>;tag=PF74LQuHxsMZkOqSXrWYfqf.MYX9Cpbh
   CSeq: 57929376 INVITE
   Contact: <sip:user1@172.28.129.231:56260;ob>
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE,
NOTIFY, REFER, MESSAGE, OPTIONS
   Content-Length:  0
------------------------------------------------------------------------
2014-04-03 14:14:41.917112 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:14:41.917112 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:14:41.917112 [DEBUG] sofia.c:5889 Channel
sofia/sipinterface_1/user1@172.28.129.231:56260 entering state
[proceeding][180]
2014-04-03 14:14:41.917112 [NOTICE] sofia.c:5979 Ring-Ready
sofia/sipinterface_1/user1@172.28.129.231:56260!
2014-04-03 14:14:41.936581 [DEBUG] switch_channel.c:3267
(sofia/sipinterface_1/user1@172.28.129.231:56260) Callstate Change DOWN
-> RINGING
2014-04-03 14:14:41.936581 [INFO] switch_ivr_originate.c:1191 Sending
early media
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [SILK:99:24000:20:0]/[G7221:115:32000:20:48000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [SILK:99:24000:20:0]/[G7221:107:16000:20:32000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [SILK:99:24000:20:0]/[G722:9:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [SILK:99:24000:20:0]/[PCMU:0:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [SILK:99:24000:20:0]/[PCMA:8:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [SILK:99:24000:20:0]/[GSM:3:8000:20:13200]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [SILK:99:24000:20:0]/[opus:116:48000:20:0]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [SILK:99:24000:20:0]/[SPEEX:99:8000:20:24600]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMU:0:8000:20:64000]/[G7221:115:32000:20:48000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMU:0:8000:20:64000]/[G7221:107:16000:20:32000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3223 Audio Codec
Compare [PCMU:0:8000:20:64000] ++++ is saved as a match
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMU:0:8000:20:64000]/[PCMA:8:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMU:0:8000:20:64000]/[GSM:3:8000:20:13200]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMU:0:8000:20:64000]/[opus:116:48000:20:0]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMU:0:8000:20:64000]/[SPEEX:99:8000:20:24600]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMA:8:8000:20:64000]/[G7221:115:32000:20:48000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMA:8:8000:20:64000]/[G7221:107:16000:20:32000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMA:8:8000:20:64000]/[G722:9:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMA:8:8000:20:64000]/[PCMU:0:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3223 Audio Codec
Compare [PCMA:8:8000:20:64000] ++++ is saved as a match
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMA:8:8000:20:64000]/[GSM:3:8000:20:13200]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMA:8:8000:20:64000]/[opus:116:48000:20:0]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMA:8:8000:20:64000]/[SPEEX:99:8000:20:24600]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [G722:9:8000:20:64000]/[G7221:115:32000:20:48000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [G722:9:8000:20:64000]/[G7221:107:16000:20:32000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [G722:9:8000:20:64000]/[G722:9:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3223 Audio Codec
Compare [G722:9:8000:20:64000] ++++ is saved as a match
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [G722:9:8000:20:64000]/[PCMU:0:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [G722:9:8000:20:64000]/[PCMA:8:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [G722:9:8000:20:64000]/[GSM:3:8000:20:13200]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [G722:9:8000:20:64000]/[opus:116:48000:20:0]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [G722:9:8000:20:64000]/[SPEEX:99:8000:20:24600]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [GSM:3:8000:20:13200]/[G7221:115:32000:20:48000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [GSM:3:8000:20:13200]/[G7221:107:16000:20:32000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [GSM:3:8000:20:13200]/[G722:9:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [GSM:3:8000:20:13200]/[PCMU:0:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [GSM:3:8000:20:13200]/[PCMA:8:8000:20:64000]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [GSM:3:8000:20:13200]/[GSM:3:8000:20:13200]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3223 Audio Codec
Compare [GSM:3:8000:20:13200] ++++ is saved as a match
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [GSM:3:8000:20:13200]/[opus:116:48000:20:0]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [GSM:3:8000:20:13200]/[SPEEX:99:8000:20:24600]
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3095 Set
telephone-event payload to 101
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:2126 Set Codec
sofia/sipinterface_1/user3@sip.difbroker.com G722/8000 20 ms 160 samples
64000 bits
2014-04-03 14:14:41.956548 [DEBUG] switch_core_codec.c:111
sofia/sipinterface_1/user3@sip.difbroker.com Original read codec set to
G722:9
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:3401 Set 2833 dtmf
send/recv payload to 101
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:4589 AUDIO RTP
[sofia/sipinterface_1/user3@sip.difbroker.com] 172.28.129.249 port 20354
-> 112.196.33.74 port 4006 codec: 9 ms: 20
2014-04-03 14:14:41.956548 [DEBUG] switch_rtp.c:3068 Starting timer [soft]
160 bytes per 20ms
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:4933 Set 2833 dtmf
send payload to 101
2014-04-03 14:14:41.956548 [DEBUG] switch_core_media.c:4939 Set 2833 dtmf
receive payload to 101
2014-04-03 14:14:41.956548 [NOTICE] sofia_media.c:92 Pre-Answer
sofia/sipinterface_1/user3@sip.difbroker.com!
2014-04-03 14:14:41.956548 [DEBUG] switch_channel.c:3405
(sofia/sipinterface_1/user3@sip.difbroker.com) Callstate Change RINGING
-> EARLY
2014-04-03 14:14:41.956548 [DEBUG] mod_sofia.c:2099 Ring SDP:
v=0
o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
s=FreeSWITCH
c=IN IP4 172.28.129.249
t=0 0
m=audio 20354 RTP/AVP 9 101
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2014-04-03 14:14:41.956548 [DEBUG] switch_core_session.c:903 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.956548 [DEBUG] switch_ivr_originate.c:1248 Raw Codec
Activation Success L16@16000hz 1 channel 20ms
2014-04-03 14:14:41.956548 [DEBUG] switch_core_codec.c:221
sofia/sipinterface_1/user3@sip.difbroker.com Push codec L16:70
2014-04-03 14:14:41.956548 [DEBUG] switch_ivr_originate.c:1316 Play
Ringback Tone [%(2000,4000,440,480)]
send 1333 bytes to udp/[172.28.129.249]:5060 at 13:14:41.960838:
   ------------------------------------------------------------------------
   SIP/2.0 183 Session Progress
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 224
   Remote-Party-ID: "1001" <sip:1001@sip.difbroker.com
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 20354 RTP/AVP 9 101
   a=rtpmap:9 G722/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
2014-04-03 14:14:41.956548 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:41.976550 [DEBUG] sofia.c:5889 Channel
sofia/sipinterface_1/user3@sip.difbroker.com entering state [early][183]
recv 898 bytes from udp/[172.28.129.249]:5060 at 13:14:46.937232:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:11000
;rport=11000;received=172.28.129.249;branch=z9hG4bKHeB5026mSQFeN
   Record-Route: <sip:80.251.161.254;lr;ftag=r48cjF4j0SQUm>
   Call-ID: eac00fdc-bb31-11e3-bc9c-35dd2b4be3da
   From: "user3" <sip:user3@sip.difbroker.com>;tag=r48cjF4j0SQUm
   To: <sip:user1@172.28.129.231>;tag=PF74LQuHxsMZkOqSXrWYfqf.MYX9Cpbh
   CSeq: 57929376 INVITE
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE,
NOTIFY, REFER, MESSAGE, OPTIONS
   Contact: <sip:user1@172.28.129.231:56260;ob>
   Supported: replaces, 100rel, timer, norefersub
   Content-Type: application/sdp
   Content-Length:   271
v=0
   o=- 3605519858 3605519859 IN IP4 172.28.129.231
   s=pjmedia
   c=IN IP4 172.28.129.231
   t=0 0
   m=audio 4004 RTP/AVP 0 101
   c=IN IP4 172.28.129.231
   a=rtcp:4005 IN IP4 172.28.129.231
   a=sendrecv
   a=rtpmap:0 PCMU/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-15
   ------------------------------------------------------------------------
2014-04-03 14:14:46.936629 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:14:46.936629 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:14:46.936629 [DEBUG] sofia.c:5889 Channel
sofia/sipinterface_1/user1@172.28.129.231:56260 entering state
[completing][200]
2014-04-03 14:14:46.936629 [DEBUG] sofia.c:5899 Remote SDP:
v=0
o=- 3605519858 3605519859 IN IP4 172.28.129.231
s=pjmedia
c=IN IP4 172.28.129.231
t=0 0
m=audio 4004 RTP/AVP 0 101
c=IN IP4 172.28.129.231
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:4005 IN IP4 172.28.129.231
send 462 bytes to udp/[172.28.129.249]:5060 at 13:14:46.939587:
   ------------------------------------------------------------------------
   ACK sip:user1@172.28.129.231:56260;ob SIP/2.0
   Via: SIP/2.0/UDP 80.251.161.254:11000;rport;branch=z9hG4bKjQ4X2XQrp050g
   Route: <sip:80.251.161.254;lr;ftag=r48cjF4j0SQUm>
   Max-Forwards: 70
   From: "user3" <sip:user3@sip.difbroker.com>;tag=r48cjF4j0SQUm
   To: <sip:user1@172.28.129.231:56260
...
;tag=PF74LQuHxsMZkOqSXrWYfqf.MYX9Cpbh
   Call-ID: eac00fdc-bb31-11e3-bc9c-35dd2b4be3da
   CSeq: 57929376 ACK
   Contact: <sip:mod_sofia@80.251.161.254:11000>
   Content-Length: 0
------------------------------------------------------------------------
2014-04-03 14:14:46.936629 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:14:46.936629 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:14:46.936629 [DEBUG] sofia.c:5889 Channel
sofia/sipinterface_1/user1@172.28.129.231:56260 entering state
[ready][200]
2014-04-03 14:14:46.936629 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
2014-04-03 14:14:46.936629 [DEBUG] switch_core_media.c:3223 Audio Codec
Compare [PCMU:0:8000:20:64000] ++++ is saved as a match
2014-04-03 14:14:46.936629 [DEBUG] switch_core_media.c:3169 Audio Codec
Compare [PCMU:0:8000:20:64000]/[PCMA:8:8000:20:64000]
2014-04-03 14:14:46.936629 [DEBUG] switch_core_media.c:3095 Set
telephone-event payload to 101
2014-04-03 14:14:46.936629 [DEBUG] switch_core_media.c:2126 Set Codec
sofia/sipinterface_1/user1@172.28.129.231:56260 PCMU/8000 20 ms 160
samples 64000 bits
2014-04-03 14:14:46.936629 [DEBUG] switch_core_codec.c:111
sofia/sipinterface_1/user1@172.28.129.231:56260 Original read codec set
to PCMU:0
2014-04-03 14:14:46.936629 [DEBUG] switch_core_media.c:3392 Set 2833 dtmf
send payload to 101
2014-04-03 14:14:46.936629 [DEBUG] switch_core_media.c:4589 AUDIO RTP
[sofia/sipinterface_1/user1@172.28.129.231:56260] 172.28.129.249 port
24372 -> 172.28.129.231 port 4004 codec: 0 ms: 20
2014-04-03 14:14:46.936629 [DEBUG] switch_rtp.c:3068 Starting timer [soft]
160 bytes per 20ms
2014-04-03 14:14:46.936629 [DEBUG] switch_core_media.c:4933 Set 2833 dtmf
send payload to 101
2014-04-03 14:14:46.936629 [DEBUG] switch_core_media.c:4939 Set 2833 dtmf
receive payload to 101
2014-04-03 14:14:46.936629 [DEBUG] switch_channel.c:3640 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:46.936629 [NOTICE] sofia.c:6678 Channel
[sofia/sipinterface_1/user1@172.28.129.231:56260] has been answered
2014-04-03 14:14:46.936629 [DEBUG] switch_channel.c:3686
(sofia/sipinterface_1/user1@172.28.129.231:56260) Callstate Change
RINGING -> ACTIVE
2014-04-03 14:14:46.956550 [DEBUG] switch_core_codec.c:246
sofia/sipinterface_1/user3@sip.difbroker.com Restore previous codec
G722:9.
2014-04-03 14:14:46.956550 [DEBUG] mod_sofia.c:782 Local SDP
sofia/sipinterface_1/user3@sip.difbroker.com:
v=0
o=FreeSWITCH 1396510527 1396510529 IN IP4 172.28.129.249
s=FreeSWITCH
c=IN IP4 172.28.129.249
t=0 0
m=audio 20354 RTP/AVP 9 101
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2014-04-03 14:14:46.956550 [DEBUG] switch_core_session.c:903 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:46.956550 [NOTICE] switch_ivr_originate.c:3476 Channel
[sofia/sipinterface_1/user3@sip.difbroker.com] has been answered
send 1295 bytes to udp/[172.28.129.249]:5060 at 13:14:46.958679:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 224
   Remote-Party-ID: "Test1" <sip:1001@sip.difbroker.com
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 20354 RTP/AVP 9 101
   a=rtpmap:9 G722/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
2014-04-03 14:14:46.956550 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:46.956550 [DEBUG] switch_channel.c:3686
(sofia/sipinterface_1/user3@sip.difbroker.com) Callstate Change EARLY ->
ACTIVE
2014-04-03 14:14:46.956550 [DEBUG] sofia.c:5889 Channel
sofia/sipinterface_1/user3@sip.difbroker.com entering state
[completed][200]
2014-04-03 14:14:46.956550 [DEBUG] switch_ivr_originate.c:3534 Originate
Resulted in Success: [sofia/sipinterface_1/user1@172.28.129.231:56260]
2014-04-03 14:14:46.956550 [DEBUG] switch_core_session.c:903 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:14:46.956550 [DEBUG] switch_core_session.c:903 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:14:46.956550 [DEBUG] switch_ivr_bridge.c:1440
(sofia/sipinterface_1/user1@172.28.129.231:56260) State Change
CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2014-04-03 14:14:46.956550 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:14:46.956550 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user1@172.28.129.231:56260) Running State Change
CS_EXCHANGE_MEDIA
2014-04-03 14:14:46.956550 [DEBUG] switch_core_state_machine.c:533
(sofia/sipinterface_1/user1@172.28.129.231:56260) State EXCHANGE_MEDIA
2014-04-03 14:14:46.956550 [DEBUG] mod_sofia.c:599 SOFIA EXCHANGE_MEDIA
2014-04-03 14:14:46.976549 [DEBUG] switch_rtp.c:5191 Correct ip/port
confirmed.
recv 322 bytes from udp/[172.28.129.249]:5060 at 13:14:47.141279:
   ------------------------------------------------------------------------
   OPTIONS sip:172.28.129.249:11000 SIP/2.0
   Via: SIP/2.0/UDP 80.251.161.254:5060;branch=z9hG4bK56be.20a73e31.0
   To: sip:172.28.129.249:11000
   From: sip:sipcheck@172.28.129.249
;tag=53cd539e79aca6726a386a78d00cf06a-dbb4
   CSeq: 10 OPTIONS
   Call-ID: 45d8465e1d1f9616-31626@172.28.129.249
   Max-Forwards: 70
   Content-Length: 0
------------------------------------------------------------------------
send 726 bytes to udp/[172.28.129.249]:5060 at 13:14:47.141770:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bK56be.20a73e31.0;received=172.28.129.249
   From: sip:sipcheck@172.28.129.249
;tag=53cd539e79aca6726a386a78d00cf06a-dbb4
   To: <sip:172.28.129.249:11000>;tag=SD25KaNpX2Deg
   Call-ID: 45d8465e1d1f9616-31626@172.28.129.249
   CSeq: 10 OPTIONS
   Contact: <sip:80.251.161.254:11000>
   User-Agent: 2600hz
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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-Length: 0
------------------------------------------------------------------------
send 1295 bytes to udp/[172.28.129.249]:5060 at 13:14:47.460342:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 224
   Remote-Party-ID: "Test1" <sip:1001@sip.difbroker.com
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 20354 RTP/AVP 9 101
   a=rtpmap:9 G722/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
send 1295 bytes to udp/[172.28.129.249]:5060 at 13:14:48.462889:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 224
   Remote-Party-ID: "Test1" <sip:1001@sip.difbroker.com
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 20354 RTP/AVP 9 101
   a=rtpmap:9 G722/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
send 1295 bytes to udp/[172.28.129.249]:5060 at 13:14:50.464388:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 224
   Remote-Party-ID: "Test1" <sip:1001@sip.difbroker.com
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 20354 RTP/AVP 9 101
   a=rtpmap:9 G722/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
send 1295 bytes to udp/[172.28.129.249]:5060 at 13:14:54.465349:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 224
   Remote-Party-ID: "Test1" <sip:1001@sip.difbroker.com
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 20354 RTP/AVP 9 101
   a=rtpmap:9 G722/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
recv 322 bytes from udp/[172.28.129.249]:5060 at 13:14:57.128308:
   ------------------------------------------------------------------------
   OPTIONS sip:172.28.129.249:11000 SIP/2.0
   Via: SIP/2.0/UDP 80.251.161.254:5060;branch=z9hG4bK66be.23a4aeb4.0
   To: sip:172.28.129.249:11000
   From: sip:sipcheck@172.28.129.249
;tag=53cd539e79aca6726a386a78d00cf06a-0e2e
   CSeq: 10 OPTIONS
   Call-ID: 45d8465e1d1f9617-31626@172.28.129.249
   Max-Forwards: 70
   Content-Length: 0
------------------------------------------------------------------------
send 726 bytes to udp/[172.28.129.249]:5060 at 13:14:57.128685:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bK66be.23a4aeb4.0;received=172.28.129.249
   From: sip:sipcheck@172.28.129.249
;tag=53cd539e79aca6726a386a78d00cf06a-0e2e
   To: <sip:172.28.129.249:11000>;tag=tpUyN55StB40B
   Call-ID: 45d8465e1d1f9617-31626@172.28.129.249
   CSeq: 10 OPTIONS
   Contact: <sip:80.251.161.254:11000>
   User-Agent: 2600hz
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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-Length: 0
------------------------------------------------------------------------
send 1295 bytes to udp/[172.28.129.249]:5060 at 13:14:58.465792:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 224
   Remote-Party-ID: "Test1" <sip:1001@sip.difbroker.com
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 20354 RTP/AVP 9 101
   a=rtpmap:9 G722/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
send 1295 bytes to udp/[172.28.129.249]:5060 at 13:15:02.466909:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 224
   Remote-Party-ID: "Test1" <sip:1001@sip.difbroker.com
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 20354 RTP/AVP 9 101
   a=rtpmap:9 G722/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
send 1295 bytes to udp/[172.28.129.249]:5060 at 13:15:06.467707:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 224
   Remote-Party-ID: "Test1" <sip:1001@sip.difbroker.com
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 20354 RTP/AVP 9 101
   a=rtpmap:9 G722/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
recv 322 bytes from udp/[172.28.129.249]:5060 at 13:15:07.141292:
   ------------------------------------------------------------------------
   OPTIONS sip:172.28.129.249:11000 SIP/2.0
   Via: SIP/2.0/UDP 80.251.161.254:5060;branch=z9hG4bK47be.90dc90e6.0
   To: sip:172.28.129.249:11000
   From: sip:sipcheck@172.28.129.249
;tag=53cd539e79aca6726a386a78d00cf06a-df6c
   CSeq: 10 OPTIONS
   Call-ID: 45d8465e1d1f9618-31626@172.28.129.249
   Max-Forwards: 70
   Content-Length: 0
------------------------------------------------------------------------
send 726 bytes to udp/[172.28.129.249]:5060 at 13:15:07.141673:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bK47be.90dc90e6.0;received=172.28.129.249
   From: sip:sipcheck@172.28.129.249
;tag=53cd539e79aca6726a386a78d00cf06a-df6c
   To: <sip:172.28.129.249:11000>;tag=UZmQQ0pXQmtKQ
   Call-ID: 45d8465e1d1f9618-31626@172.28.129.249
   CSeq: 10 OPTIONS
   Contact: <sip:80.251.161.254:11000>
   User-Agent: 2600hz
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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-Length: 0
------------------------------------------------------------------------
send 1295 bytes to udp/[172.28.129.249]:5060 at 13:15:10.469755:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 224
   Remote-Party-ID: "Test1" <sip:1001@sip.difbroker.com
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 20354 RTP/AVP 9 101
   a=rtpmap:9 G722/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
send 1295 bytes to udp/[172.28.129.249]:5060 at 13:15:14.471390:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 224
   Remote-Party-ID: "Test1" <sip:1001@sip.difbroker.com
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 20354 RTP/AVP 9 101
   a=rtpmap:9 G722/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
recv 322 bytes from udp/[172.28.129.249]:5060 at 13:15:17.128326:
   ------------------------------------------------------------------------
   OPTIONS sip:172.28.129.249:11000 SIP/2.0
   Via: SIP/2.0/UDP 80.251.161.254:5060;branch=z9hG4bK37be.7292ed87.0
   To: sip:172.28.129.249:11000
   From: sip:sipcheck@172.28.129.249
;tag=53cd539e79aca6726a386a78d00cf06a-0af6
   CSeq: 10 OPTIONS
   Call-ID: 45d8465e1d1f9619-31626@172.28.129.249
   Max-Forwards: 70
   Content-Length: 0
------------------------------------------------------------------------
send 726 bytes to udp/[172.28.129.249]:5060 at 13:15:17.128660:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bK37be.7292ed87.0;received=172.28.129.249
   From: sip:sipcheck@172.28.129.249
;tag=53cd539e79aca6726a386a78d00cf06a-0af6
   To: <sip:172.28.129.249:11000>;tag=v8DgSU70mXg6j
   Call-ID: 45d8465e1d1f9619-31626@172.28.129.249
   CSeq: 10 OPTIONS
   Contact: <sip:80.251.161.254:11000>
   User-Agent: 2600hz
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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-Length: 0
------------------------------------------------------------------------
send 1295 bytes to udp/[172.28.129.249]:5060 at 13:15:18.473572:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:5060
;branch=z9hG4bKbbe6.305cc383.0;received=172.28.129.249
   Via: SIP/2.0/UDP 192.168.1.107:51281
;received=112.196.33.74;rport=4713;branch=z9hG4bKPjQanHWvyRMlaJSg8Q10iOVqNPAO6WiO0x
   Record-Route:
<sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   From: <sip:user3@sip.difbroker.com
...
;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   To: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 27967 INVITE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, 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: 224
   Remote-Party-ID: "Test1" <sip:1001@sip.difbroker.com
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1396510527 1396510528 IN IP4 172.28.129.249
   s=FreeSWITCH
   c=IN IP4 172.28.129.249
   t=0 0
   m=audio 20354 RTP/AVP 9 101
   a=rtpmap:9 G722/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
2014-04-03 14:15:18.956546 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
send 688 bytes to udp/[80.251.161.254]:5060 at 13:15:18.960410:
   ------------------------------------------------------------------------
   BYE sip:user3@112.196.33.74:4713;ob SIP/2.0
   Via: SIP/2.0/UDP 80.251.161.254:11000;rport;branch=z9hG4bKK0Xp4r8UK9UKc
   Route: <sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   Max-Forwards: 70
   From: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   To: <sip:user3@sip.difbroker.com>;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 57929395 BYE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Reason: SIP;cause=408;text="ACK Timeout"
   Content-Length: 0
------------------------------------------------------------------------
2014-04-03 14:15:18.956546 [DEBUG] switch_core_session.c:1048 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:15:18.976545 [DEBUG] sofia.c:5889 Channel
sofia/sipinterface_1/user3@sip.difbroker.com entering state
[terminating][0]
2014-04-03 14:15:18.976545 [NOTICE] sofia.c:6733 Hangup
sofia/sipinterface_1/user3@sip.difbroker.com [CS_EXECUTE]
[NORMAL_UNSPECIFIED]
2014-04-03 14:15:18.976545 [DEBUG] switch_channel.c:3212 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [KILL]
2014-04-03 14:15:18.976545 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:15:18.976545 [DEBUG] switch_ivr_bridge.c:647 BRIDGE THREAD
DONE [sofia/sipinterface_1/user3@sip.difbroker.com]
2014-04-03 14:15:18.976545 [DEBUG] switch_ivr_bridge.c:672 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:15:18.976545 [DEBUG] switch_ivr_bridge.c:566
sofia/sipinterface_1/user3@sip.difbroker.com ending bridge by request
from write function
2014-04-03 14:15:18.976545 [DEBUG] switch_ivr_bridge.c:647 BRIDGE THREAD
DONE [sofia/sipinterface_1/user1@172.28.129.231:56260]
2014-04-03 14:15:18.976545 [DEBUG] switch_ivr_bridge.c:672 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:15:18.976545 [NOTICE] switch_ivr_bridge.c:735 Hangup
sofia/sipinterface_1/user1@172.28.129.231:56260 [CS_EXCHANGE_MEDIA]
[NORMAL_CLEARING]
2014-04-03 14:15:18.976545 [DEBUG] switch_channel.c:3212 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [KILL]
2014-04-03 14:15:18.976545 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:533
(sofia/sipinterface_1/user1@172.28.129.231:56260) State EXCHANGE_MEDIA
going to sleep
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user1@172.28.129.231:56260) Running State Change
CS_HANGUP
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:731
(sofia/sipinterface_1/user1@172.28.129.231:56260) State HANGUP
2014-04-03 14:15:18.976545 [DEBUG] mod_sofia.c:413 Channel
sofia/sipinterface_1/user1@172.28.129.231:56260 hanging up, cause:
NORMAL_CLEARING
2014-04-03 14:15:18.976545 [DEBUG] mod_sofia.c:471 Sending BYE to
sofia/sipinterface_1/user1@172.28.129.231:56260
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:58
sofia/sipinterface_1/user1@172.28.129.231:56260 Standard HANGUP, cause:
NORMAL_CLEARING
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:731
(sofia/sipinterface_1/user1@172.28.129.231:56260) State HANGUP going to
sleep
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:744
(sofia/sipinterface_1/user1@172.28.129.231:56260) Callstate Change ACTIVE
-> HANGUP
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:499
(sofia/sipinterface_1/user1@172.28.129.231:56260) State Change CS_HANGUP
-> CS_REPORTING
2014-04-03 14:15:18.976545 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user1@172.28.129.231:56260) Running State Change
CS_REPORTING
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:816
(sofia/sipinterface_1/user1@172.28.129.231:56260) State REPORTING
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:102
sofia/sipinterface_1/user1@172.28.129.231:56260 Standard REPORTING,
cause: NORMAL_CLEARING
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:816
(sofia/sipinterface_1/user1@172.28.129.231:56260) State REPORTING going
to sleep
send 680 bytes to udp/[172.28.129.249]:5060 at 13:15:18.983374:
   ------------------------------------------------------------------------
   BYE sip:user1@172.28.129.231:56260;ob SIP/2.0
   Via: SIP/2.0/UDP 80.251.161.254:11000;rport;branch=z9hG4bKm9pF6KSZgjj6Q
   Route: <sip:80.251.161.254;lr;ftag=r48cjF4j0SQUm>
   Max-Forwards: 70
   From: "user3" <sip:user3@sip.difbroker.com>;tag=r48cjF4j0SQUm
   To: <sip:user1@172.28.129.231:56260
...
;tag=PF74LQuHxsMZkOqSXrWYfqf.MYX9Cpbh
   Call-ID: eac00fdc-bb31-11e3-bc9c-35dd2b4be3da
   CSeq: 57929377 BYE
   Contact: <sip:mod_sofia@80.251.161.254:11000>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Reason: Q.850;cause=16;text="NORMAL_CLEARING"
   Content-Length: 0
------------------------------------------------------------------------
2014-04-03 14:15:18.976545 [DEBUG] switch_ivr_bridge.c:1538
sofia/sipinterface_1/user1@172.28.129.231:56260 skip receive message
[UNBRIDGE] (channel is hungup already)
2014-04-03 14:15:18.976545 [DEBUG] switch_ivr_bridge.c:1541
sofia/sipinterface_1/user3@sip.difbroker.com skip receive message
[UNBRIDGE] (channel is hungup already)
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:493
(sofia/sipinterface_1/user1@172.28.129.231:56260) State Change
CS_REPORTING -> CS_DESTROY
2014-04-03 14:15:18.976545 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user1@172.28.129.231:56260 [BREAK]
2014-04-03 14:15:18.976545 [DEBUG] switch_core_session.c:1591 Session 15
(sofia/sipinterface_1/user1@172.28.129.231:56260) Locked, Waiting on
external entities
2014-04-03 14:15:18.976545 [NOTICE] switch_core_session.c:1609 Session 15
(sofia/sipinterface_1/user1@172.28.129.231:56260) Ended
2014-04-03 14:15:18.976545 [NOTICE] switch_core_session.c:1613 Close
Channel sofia/sipinterface_1/user1@172.28.129.231:56260 [CS_DESTROY]
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:618
(sofia/sipinterface_1/user1@172.28.129.231:56260) Callstate Change HANGUP
-> DOWN
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:621
(sofia/sipinterface_1/user1@172.28.129.231:56260) Running State Change
CS_DESTROY
2014-04-03 14:15:18.976545 [DEBUG] switch_core_session.c:2852
sofia/sipinterface_1/user3@sip.difbroker.com skip receive message
[APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:530
(sofia/sipinterface_1/user3@sip.difbroker.com) State EXECUTE going to
sleep
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user3@sip.difbroker.com) Running State Change
CS_HANGUP
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:631
(sofia/sipinterface_1/user1@172.28.129.231:56260) State DESTROY
2014-04-03 14:15:18.976545 [DEBUG] mod_sofia.c:323 sofia/sipinterface_1/
user1@172.28.129.231:56260 SOFIA DESTROY
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:109
sofia/sipinterface_1/user1@172.28.129.231:56260 Standard DESTROY
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:631
(sofia/sipinterface_1/user1@172.28.129.231:56260) State DESTROY going to
sleep
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:731
(sofia/sipinterface_1/user3@sip.difbroker.com) State HANGUP
2014-04-03 14:15:18.976545 [DEBUG] mod_sofia.c:413 Channel
sofia/sipinterface_1/user3@sip.difbroker.com hanging up, cause:
NORMAL_UNSPECIFIED
recv 340 bytes from udp/[172.28.129.249]:5060 at 13:15:18.996214:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 80.251.161.254:11000
;rport=11000;received=172.28.129.249;branch=z9hG4bKm9pF6KSZgjj6Q
   Call-ID: eac00fdc-bb31-11e3-bc9c-35dd2b4be3da
   From: "user3" <sip:user3@sip.difbroker.com>;tag=r48cjF4j0SQUm
   To: <sip:user1@172.28.129.231>;tag=PF74LQuHxsMZkOqSXrWYfqf.MYX9Cpbh
   CSeq: 57929377 BYE
   Content-Length:  0
------------------------------------------------------------------------
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:58
sofia/sipinterface_1/user3@sip.difbroker.com Standard HANGUP, cause:
NORMAL_UNSPECIFIED
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:731
(sofia/sipinterface_1/user3@sip.difbroker.com) State HANGUP going to sleep
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:744
(sofia/sipinterface_1/user3@sip.difbroker.com) Callstate Change ACTIVE ->
HANGUP
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:499
(sofia/sipinterface_1/user3@sip.difbroker.com) State Change CS_HANGUP ->
CS_REPORTING
2014-04-03 14:15:18.976545 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:15:18.976545 [DEBUG] switch_core_state_machine.c:467
(sofia/sipinterface_1/user3@sip.difbroker.com) Running State Change
CS_REPORTING
2014-04-03 14:15:19.000649 [DEBUG] switch_core_state_machine.c:816
(sofia/sipinterface_1/user3@sip.difbroker.com) State REPORTING
2014-04-03 14:15:19.000649 [DEBUG] switch_core_state_machine.c:102
sofia/sipinterface_1/user3@sip.difbroker.com Standard REPORTING, cause:
NORMAL_UNSPECIFIED
2014-04-03 14:15:19.000649 [DEBUG] switch_core_state_machine.c:816
(sofia/sipinterface_1/user3@sip.difbroker.com) State REPORTING going to
sleep
2014-04-03 14:15:19.000649 [DEBUG] switch_core_state_machine.c:493
(sofia/sipinterface_1/user3@sip.difbroker.com) State Change CS_REPORTING
-> CS_DESTROY
2014-04-03 14:15:19.000649 [DEBUG] switch_core_session.c:1383 Send signal
sofia/sipinterface_1/user3@sip.difbroker.com [BREAK]
2014-04-03 14:15:19.000649 [DEBUG] switch_core_session.c:1591 Session 14
(sofia/sipinterface_1/user3@sip.difbroker.com) Locked, Waiting on
external entities
2014-04-03 14:15:19.000649 [NOTICE] switch_core_session.c:1609 Session 14
(sofia/sipinterface_1/user3@sip.difbroker.com) Ended
2014-04-03 14:15:19.000649 [NOTICE] switch_core_session.c:1613 Close
Channel sofia/sipinterface_1/user3@sip.difbroker.com [CS_DESTROY]
2014-04-03 14:15:19.000649 [DEBUG] switch_core_state_machine.c:618
(sofia/sipinterface_1/user3@sip.difbroker.com) Callstate Change HANGUP ->
DOWN
2014-04-03 14:15:19.000649 [DEBUG] switch_core_state_machine.c:621
(sofia/sipinterface_1/user3@sip.difbroker.com) Running State Change
CS_DESTROY
2014-04-03 14:15:19.000649 [DEBUG] switch_core_state_machine.c:631
(sofia/sipinterface_1/user3@sip.difbroker.com) State DESTROY
2014-04-03 14:15:19.000649 [DEBUG] mod_sofia.c:323 sofia/sipinterface_1/
user3@sip.difbroker.com SOFIA DESTROY
2014-04-03 14:15:19.000649 [DEBUG] switch_core_state_machine.c:109
sofia/sipinterface_1/user3@sip.difbroker.com Standard DESTROY
2014-04-03 14:15:19.000649 [DEBUG] switch_core_state_machine.c:631
(sofia/sipinterface_1/user3@sip.difbroker.com) State DESTROY going to
sleep
send 688 bytes to udp/[80.251.161.254]:5060 at 13:15:19.965638:
   ------------------------------------------------------------------------
   BYE sip:user3@112.196.33.74:4713;ob SIP/2.0
   Via: SIP/2.0/UDP 80.251.161.254:11000;rport;branch=z9hG4bKK0Xp4r8UK9UKc
   Route: <sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   Max-Forwards: 70
   From: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   To: <sip:user3@sip.difbroker.com>;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 57929395 BYE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Reason: SIP;cause=408;text="ACK Timeout"
   Content-Length: 0
------------------------------------------------------------------------
send 688 bytes to udp/[80.251.161.254]:5060 at 13:15:21.968199:
   ------------------------------------------------------------------------
   BYE sip:user3@112.196.33.74:4713;ob SIP/2.0
   Via: SIP/2.0/UDP 80.251.161.254:11000;rport;branch=z9hG4bKK0Xp4r8UK9UKc
   Route: <sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   Max-Forwards: 70
   From: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   To: <sip:user3@sip.difbroker.com>;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 57929395 BYE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Reason: SIP;cause=408;text="ACK Timeout"
   Content-Length: 0
------------------------------------------------------------------------
send 688 bytes to udp/[80.251.161.254]:5060 at 13:15:25.971941:
   ------------------------------------------------------------------------
   BYE sip:user3@112.196.33.74:4713;ob SIP/2.0
   Via: SIP/2.0/UDP 80.251.161.254:11000;rport;branch=z9hG4bKK0Xp4r8UK9UKc
   Route: <sip:80.251.161.254;lr=on;ftag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u>
   Max-Forwards: 70
   From: <sip:1001@sip.difbroker.com>;tag=QUFmgmKF3g18r
   To: <sip:user3@sip.difbroker.com>;tag=a93uzpsvf1lldFdAo.iO.ILqQH-P9U9u
   Call-ID: jU74CGX9gw-rGt1yebJQeDjRfsi-WKBE
   CSeq: 57929395 BYE
   Contact: <sip:1001@80.251.161.254:11000;transport=udp>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Reason: SIP;cause=408;text="ACK Timeout"
   Content-Length: 0
Please can anyone give me some suggestions to get rid of this problem.

Thanks,
Anurag Mishra