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.com export(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.com export(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.com set(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.com set(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.com set(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.com export(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.com 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.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