403 error

[root@freeswitch ~]# fs_cli
.=======================================================.
|            _____ ____     ____ _     ___              |
|           |  ___/ ___|   / ___| |   |_ _|             |
|           | |_  \___ \  | |   | |    | |              |
|           |  _|  ___) | | |___| |___ | |              |
|           |_|   |____/   \____|_____|___|             |
|                                                       |
.=======================================================.
| Anthony Minessale II, Ken Rice,                       |
| Michael Jerris, Travis Cross                          |
| FreeSWITCH (http://www.freeswitch.org)                |
| Paypal Donations Appreciated: paypal@freeswitch.org   |
| Brought to you by ClueCon http://www.cluecon.com/     |
.=======================================================.

.===============================================================.
|       _                                                       |
|   ___| |_   _  ___  ___ ___  _ __         ___ ___  _ __ ___   |
|  / __| | | | |/ _ \/ __/ _ \| '_ \       / __/ _ \| '_ ` _ \  |
| | (__| | |_| |  __/ (_| (_) | | | |  _  | (_| (_) | | | | | | |
|  \___|_|\__,_|\___|\___\___/|_| |_| (_)  \___\___/|_| |_| |_| |
|                                                               |
.===============================================================.

Type /help <enter> to see a list of commands




[This app Best viewed at 160x60 or more..]
+OK log level  [7]
freeswitch@internal> recv 1209 bytes from udp/[198.27.82.183]:5060 at 21:35:51.660191:
   ------------------------------------------------------------------------
   INVITE sip:102@beta.revoltcloud.com;transport=UDP SIP/2.0
   Record-Route: <sip:198.27.82.183;lr=on;ftag=5d74853b>
   Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK0a13.36c6fc56.0
   Via: SIP/2.0/UDP 96.250.214.196:58144;branch=z9hG4bK-d8754z-16435aa8d7f72a77-1---d8754z-;rport=58144
   Max-Forwards: 50
   Contact: <sip:david@96.250.214.196:58144;transport=UDP>
   To: <sip:102@beta.revoltcloud.com;transport=UDP>
   From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=5d74853b
   Call-ID: N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.
   CSeq: 1 INVITE
   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
   Content-Type: application/sdp
   Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
   User-Agent: Zoiper r27147
   Allow-Events: presence, kpml
   Content-Length: 371
   X-AUTH-IP: 96.250.214.196

   v=0
   o=Zoiper 0 0 IN IP4 96.250.214.196
   s=Zoiper
   c=IN IP4 96.250.214.196
   t=0 0
   m=audio 34314 RTP/AVP 110 3 0 8 97 101
   a=rtpmap:110 speex/8000
   a=rtpmap:3 GSM/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=rtpmap:97 iLBC/8000
   a=fmtp:97 mode=30
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=sendrecv
   a=oldmediaip:192.168.1.3
   a=oldmediaip:192.168.1.3
   ------------------------------------------------------------------------
send 468 bytes to udp/[198.27.82.183]:5060 at 21:35:51.660571:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK0a13.36c6fc56.0
   Via: SIP/2.0/UDP 96.250.214.196:58144;branch=z9hG4bK-d8754z-16435aa8d7f72a77-1---d8754z-;rport=58144
   Record-Route: <sip:198.27.82.183;lr=on;ftag=5d74853b>
   From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=5d74853b
   To: <sip:102@beta.revoltcloud.com;transport=UDP>
   Call-ID: N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.
   CSeq: 1 INVITE
   User-Agent: 2600hz
   Content-Length: 0

   ------------------------------------------------------------------------
2014-11-24 21:35:51.660415 [NOTICE] switch_channel.c:1054 New Channel sofia/sipinterface_1/david@beta.revoltcloud.com [N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.]
2014-11-24 21:35:51.660415 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:51.660415 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:51.660415 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_NEW
2014-11-24 21:35:51.660415 [DEBUG] sofia.c:8703 sofia/sipinterface_1/david@beta.revoltcloud.com receiving invite from 198.27.82.183:5060 version: 1.4.7  64bit
2014-11-24 21:35:51.660415 [DEBUG] sofia.c:8825 1 acls to check for proxy
2014-11-24 21:35:51.660415 [DEBUG] sofia.c:8830 checking 198.27.82.183 against acl authoritative
2014-11-24 21:35:51.660415 [INFO] sofia.c:8832 198.27.82.183 is a proxy according to the authoritative acl
2014-11-24 21:35:51.660415 [DEBUG] sofia.c:8842 network ip is a proxy
2014-11-24 21:35:51.660415 [DEBUG] sofia.c:8846 found auth ip [X-AUTH-IP] header of [96.250.214.196]
2014-11-24 21:35:51.660415 [DEBUG] sofia.c:8870 IP 198.27.82.183 Rejected by acl "trusted". Falling back to Digest auth.
2014-11-24 21:35:51.660415 [DEBUG] sofia_reg.c:3345 sofia pre-register disabled
2014-11-24 21:35:51.660415 [DEBUG] switch_core_state_machine.c:489 (sofia/sipinterface_1/david@beta.revoltcloud.com) State NEW
send 917 bytes to udp/[198.27.82.183]:5060 at 21:35:51.661437:
   ------------------------------------------------------------------------
   SIP/2.0 407 Proxy Authentication Required
   Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK0a13.36c6fc56.0
   Via: SIP/2.0/UDP 96.250.214.196:58144;branch=z9hG4bK-d8754z-16435aa8d7f72a77-1---d8754z-;rport=58144
   From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=5d74853b
   To: <sip:102@beta.revoltcloud.com;transport=UDP>;tag=Xrtpc75aUeZmH
   Call-ID: N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.
   CSeq: 1 INVITE
   User-Agent: 2600hz
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: 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="beta.revoltcloud.com", nonce="c5b9bb4c-744b-11e4-a4ee-69437e0ffad5", algorithm=MD5, qop="auth"
   Content-Length: 0

   ------------------------------------------------------------------------
2014-11-24 21:35:51.660415 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:51.660415 [DEBUG] sofia.c:2033 detaching session N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.
recv 360 bytes from udp/[198.27.82.183]:5060 at 21:35:51.662578:
   ------------------------------------------------------------------------
   ACK sip:102@beta.revoltcloud.com;transport=UDP SIP/2.0
   Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK0a13.36c6fc56.0
   Max-Forwards: 50
   To: <sip:102@beta.revoltcloud.com;transport=UDP>;tag=Xrtpc75aUeZmH
   From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=5d74853b
   Call-ID: N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.
   CSeq: 1 ACK
   Content-Length: 0

   ------------------------------------------------------------------------
recv 1498 bytes from udp/[198.27.82.183]:5060 at 21:35:51.726578:
   ------------------------------------------------------------------------
   INVITE sip:102@beta.revoltcloud.com;transport=UDP SIP/2.0
   Record-Route: <sip:198.27.82.183;lr=on;ftag=5d74853b>
   Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bKd913.6cb4e55.0
   Via: SIP/2.0/UDP 96.250.214.196:58144;branch=z9hG4bK-d8754z-a457c2692cfbcf65-1---d8754z-;rport=58144
   Max-Forwards: 50
   Contact: <sip:david@96.250.214.196:58144;transport=UDP>
   To: <sip:102@beta.revoltcloud.com;transport=UDP>
   From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=5d74853b
   Call-ID: N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.
   CSeq: 2 INVITE
   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
   Content-Type: application/sdp
   Proxy-Authorization: Digest username="david",realm="beta.revoltcloud.com",nonce="c5b9bb4c-744b-11e4-a4ee-69437e0ffad5",uri="sip:102@beta.revoltcloud.com;transport=UDP",response="2d74e7baf918d3f9ac24ef00118e6e93",cnonce="604e2d2687bd474f16f94eb5c04d23e3",nc=00000001,qop=auth,algorithm=MD5
   Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
   User-Agent: Zoiper r27147
   Allow-Events: presence, kpml
   Content-Length: 371
   X-AUTH-IP: 96.250.214.196

   v=0
   o=Zoiper 0 0 IN IP4 96.250.214.196
   s=Zoiper
   c=IN IP4 96.250.214.196
   t=0 0
   m=audio 34314 RTP/AVP 110 3 0 8 97 101
   a=rtpmap:110 speex/8000
   a=rtpmap:3 GSM/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=rtpmap:97 iLBC/8000
   a=fmtp:97 mode=30
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=sendrecv
   a=oldmediaip:192.168.1.3
   a=oldmediaip:192.168.1.3
   ------------------------------------------------------------------------
send 467 bytes to udp/[198.27.82.183]:5060 at 21:35:51.726863:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bKd913.6cb4e55.0
   Via: SIP/2.0/UDP 96.250.214.196:58144;branch=z9hG4bK-d8754z-a457c2692cfbcf65-1---d8754z-;rport=58144
   Record-Route: <sip:198.27.82.183;lr=on;ftag=5d74853b>
   From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=5d74853b
   To: <sip:102@beta.revoltcloud.com;transport=UDP>
   Call-ID: N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.
   CSeq: 2 INVITE
   User-Agent: 2600hz
   Content-Length: 0

   ------------------------------------------------------------------------
2014-11-24 21:35:51.720397 [DEBUG] sofia.c:2138 Re-attaching to session N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.
2014-11-24 21:35:51.720397 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:51.720397 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:51.740398 [DEBUG] sofia.c:8703 sofia/sipinterface_1/david@beta.revoltcloud.com receiving invite from 198.27.82.183:5060 version: 1.4.7  64bit
2014-11-24 21:35:51.740398 [DEBUG] sofia.c:8825 1 acls to check for proxy
2014-11-24 21:35:51.740398 [DEBUG] sofia.c:8830 checking 198.27.82.183 against acl authoritative
2014-11-24 21:35:51.740398 [INFO] sofia.c:8832 198.27.82.183 is a proxy according to the authoritative acl
2014-11-24 21:35:51.740398 [DEBUG] sofia.c:8842 network ip is a proxy
2014-11-24 21:35:51.740398 [DEBUG] sofia.c:8846 found auth ip [X-AUTH-IP] header of [96.250.214.196]
2014-11-24 21:35:51.740398 [DEBUG] sofia.c:8870 IP 198.27.82.183 Rejected by acl "trusted". Falling back to Digest auth.
2014-11-24 21:35:51.740398 [DEBUG] switch_xml.c:2009 Cache Info - Time Now : 1416882951 - Expires : 1416885116
2014-11-24 21:35:51.740398 [DEBUG] switch_xml.c:2015 david@beta.revoltcloud.com fetched from cache
2014-11-24 21:35:51.740398 [DEBUG] sofia.c:6413 Channel sofia/sipinterface_1/david@beta.revoltcloud.com entering state [received][100]
2014-11-24 21:35:51.740398 [DEBUG] sofia.c:6423 Remote SDP:
v=0
o=Zoiper 0 0 IN IP4 96.250.214.196
s=Zoiper
c=IN IP4 96.250.214.196
t=0 0
m=audio 34314 RTP/AVP 110 3 0 8 97 101
a=rtpmap:110 speex/8000
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=oldmediaip:192.168.1.3
a=oldmediaip:192.168.1.3

2014-11-24 21:35:51.740398 [DEBUG] sofia.c:6678 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_NEW -> CS_INIT
2014-11-24 21:35:51.740398 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:51.740398 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_INIT
2014-11-24 21:35:51.740398 [DEBUG] switch_core_state_machine.c:510 (sofia/sipinterface_1/david@beta.revoltcloud.com) State INIT
2014-11-24 21:35:51.740398 [DEBUG] mod_sofia.c:87 sofia/sipinterface_1/david@beta.revoltcloud.com SOFIA INIT
2014-11-24 21:35:51.740398 [DEBUG] switch_core_state_machine.c:40 sofia/sipinterface_1/david@beta.revoltcloud.com Standard INIT
2014-11-24 21:35:51.740398 [DEBUG] switch_core_state_machine.c:48 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_INIT -> CS_ROUTING
2014-11-24 21:35:51.740398 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:51.740398 [DEBUG] switch_core_state_machine.c:510 (sofia/sipinterface_1/david@beta.revoltcloud.com) State INIT going to sleep
2014-11-24 21:35:51.740398 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_ROUTING
2014-11-24 21:35:51.740398 [DEBUG] switch_channel.c:2179 (sofia/sipinterface_1/david@beta.revoltcloud.com) Callstate Change DOWN -> RINGING
2014-11-24 21:35:51.740398 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/david@beta.revoltcloud.com) State ROUTING
2014-11-24 21:35:51.740398 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/david@beta.revoltcloud.com SOFIA ROUTING
2014-11-24 21:35:51.740398 [DEBUG] switch_core_state_machine.c:164 sofia/sipinterface_1/david@beta.revoltcloud.com Standard ROUTING
2014-11-24 21:35:51.740398 [INFO] mod_dialplan_xml.c:558 Processing david <david>->102 in context context_2
2014-11-24 21:35:51.740398 [DEBUG] kazoo_fetch_agent.c:216 Sending dialplan XML request (c5c64466-744b-11e4-a4f0-69437e0ffad5) to ecallmgr@beta001.revoltcloud.com <1.860.0>
2014-11-24 21:35:51.860423 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecallmgr@beta001.revoltcloud.com <1.860.0>
2014-11-24 21:35:51.880397 [DEBUG] kazoo_fetch_agent.c:277 Received dialplan XML (c5c64466-744b-11e4-a4f0-69437e0ffad5) after 139ms: <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@beta001.revoltcloud.com won call control"/><action application="export" data="ecallmgr_Ecallmgr-Node=ecallmgr@beta001.revoltcloud.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/david@beta.revoltcloud.com parsing [context_2->park] continue=false
|--- Dialplan: Processing recursive conditions level:1 [park_recur_1] require-nested=TRUE
|--- Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Regex (PASS) [park_recur_1] variable_ecallmgr_Bridge-ID() =~ /^$/ break=on-false
|--- Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Action export(ecallmgr_Bridge-ID=${UUID})
Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Absolute Condition [park]
Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Action log(NOTICE log|${uuid}|ecallmgr@beta001.revoltcloud.com won call control)
Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Action export(ecallmgr_Ecallmgr-Node=ecallmgr@beta001.revoltcloud.com)
Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Action set(ringback=%(2000,4000,440,480))
Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Action set(transfer_ringback=%(2000,4000,440,480))
Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Action park()
2014-11-24 21:35:51.880397 [DEBUG] switch_core_state_machine.c:214 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_ROUTING -> CS_EXECUTE
2014-11-24 21:35:51.880397 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:51.880397 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/david@beta.revoltcloud.com) State ROUTING going to sleep
2014-11-24 21:35:51.880397 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_EXECUTE
2014-11-24 21:35:51.880397 [DEBUG] switch_core_state_machine.c:533 (sofia/sipinterface_1/david@beta.revoltcloud.com) State EXECUTE
2014-11-24 21:35:51.880397 [DEBUG] mod_sofia.c:178 sofia/sipinterface_1/david@beta.revoltcloud.com SOFIA EXECUTE
2014-11-24 21:35:51.880397 [DEBUG] switch_core_state_machine.c:256 sofia/sipinterface_1/david@beta.revoltcloud.com Standard EXECUTE
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com export(ecallmgr_Bridge-ID=N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.)
2014-11-24 21:35:51.880397 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [ecallmgr_Bridge-ID]=[N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.]
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com log(NOTICE log|N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.|ecallmgr@beta001.revoltcloud.com won call control)
2014-11-24 21:35:51.880397 [NOTICE] mod_dptools.c:1667 log|N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.|ecallmgr@beta001.revoltcloud.com won call control
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com export(ecallmgr_Ecallmgr-Node=ecallmgr@beta001.revoltcloud.com)
2014-11-24 21:35:51.880397 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [ecallmgr_Ecallmgr-Node]=[ecallmgr@beta001.revoltcloud.com]
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com set(ringback=%(2000,4000,440,480))
2014-11-24 21:35:51.880397 [DEBUG] mod_dptools.c:1435 sofia/sipinterface_1/david@beta.revoltcloud.com SET [ringback]=[%(2000,4000,440,480)]
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com set(transfer_ringback=%(2000,4000,440,480))
2014-11-24 21:35:51.880397 [DEBUG] mod_dptools.c:1435 sofia/sipinterface_1/david@beta.revoltcloud.com SET [transfer_ringback]=[%(2000,4000,440,480)]
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com park()
2014-11-24 21:35:52.080397 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecallmgr@beta001.revoltcloud.com <1.16790.4>
2014-11-24 21:35:52.080397 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA. effective_caller_id_name=David Johnson;effective_caller_id_number=+17184730090;rtp_secure_media=true;ecallmgr_Fetch-ID=c5c64466-744b-11e4-a4f0-69437e0ffad5;ecallmgr_Username=david;ecallmgr_Realm=beta.revoltcloud.com;ecallmgr_Account-ID=d2ad30852ffb879da1a65dd55f49c6c7;ecallmgr_Authorizing-ID=e9bed3fa34d9d6d984405712d0195368;ecallmgr_Authorizing-Type=device;ecallmgr_Owner-ID=06138dc20d3e6b75ada3b44dcdc4e53c;ecallmgr_Account-Realm=beta.revoltcloud.com;ecallmgr_Account-Name=ReVolt Cloud;presence_id=david@beta.revoltcloud.com)
2014-11-24 21:35:52.300404 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecallmgr@beta001.revoltcloud.com <1.16827.4>
2014-11-24 21:35:52.300404 [NOTICE] kazoo_node.c:302 log|N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.|building xferext extension: set continue_on_fail=true
2014-11-24 21:35:52.300404 [NOTICE] kazoo_node.c:302 log|N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.|building xferext extension: export sip_redirect_context=context_2
2014-11-24 21:35:52.300404 [NOTICE] kazoo_node.c:302 log|N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.|building xferext extension: export ecallmgr_Inception=${ecallmgr_Inception}
2014-11-24 21:35:52.300404 [NOTICE] kazoo_node.c:302 log|N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.|building xferext extension: bridge {call_timeout=20,originate_timeout=20,local_var_clobber='true'}[ecallmgr_Authorizing-ID='4475b86dc6c38cd77f30887d5abebf94',ecallmgr_Owner-ID='237117b58972853140384510b954bcc4',ecallmgr_Account-ID='d2ad30852ffb879da1a65dd55f49c6c7',sdp_secure_savp_only='true',rtp_secure_media='true',sip_invite_domain='beta.revoltcloud.com',presence_id='nina@beta.revoltcloud.com',absolute_codec_string='^^:OPUS:speex@32000h:speex@16000h:PCMU:PCMA:VP8:H264:H263:H261',leg_timeout='20',effective_callee_id_number='+19176278994',effective_callee_id_name='Nina Giordano',origination_callee_id_number='+19176278994',origination_callee_id_name='Nina Giordano',ecallmgr_Realm='beta.revoltcloud.com',ecallmgr_Username='nina']sofia/sipinterface_1/nina@96.250.214.196:50072;fs_path=sip:198.27.82.183:5060;lr;received='sip:96.250.214.196:50072'
2014-11-24 21:35:52.300404 [NOTICE] kazoo_node.c:302 log|N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.|building xferext extension: event Event-Name=CUSTOM,Event-Subclass=whistle::masquerade,whistle_event_name=CHANNEL_EXECUTE_COMPLETE,whistle_application_name=bridge
2014-11-24 21:35:52.300404 [NOTICE] kazoo_node.c:302 log|N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.|building xferext extension: park
2014-11-24 21:35:52.300404 [NOTICE] kazoo_node.c:305 log|N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.|transfered call to xferext extension
2014-11-24 21:35:52.300404 [DEBUG] switch_core_session.c:1187 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:52.300404 [DEBUG] switch_channel.c:3098 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_EXECUTE -> CS_ROUTING
2014-11-24 21:35:52.300404 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:533 (sofia/sipinterface_1/david@beta.revoltcloud.com) State EXECUTE going to sleep
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_ROUTING
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/david@beta.revoltcloud.com) State ROUTING
2014-11-24 21:35:52.300404 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/david@beta.revoltcloud.com SOFIA ROUTING
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:164 sofia/sipinterface_1/david@beta.revoltcloud.com Standard ROUTING
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:184 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_ROUTING -> CS_EXECUTE
2014-11-24 21:35:52.300404 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/david@beta.revoltcloud.com) State ROUTING going to sleep
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_EXECUTE
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:533 (sofia/sipinterface_1/david@beta.revoltcloud.com) State EXECUTE
2014-11-24 21:35:52.300404 [DEBUG] mod_sofia.c:178 sofia/sipinterface_1/david@beta.revoltcloud.com SOFIA EXECUTE
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:256 sofia/sipinterface_1/david@beta.revoltcloud.com Standard EXECUTE
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com set(continue_on_fail=true)
2014-11-24 21:35:52.300404 [DEBUG] mod_dptools.c:1435 sofia/sipinterface_1/david@beta.revoltcloud.com SET [continue_on_fail]=[true]
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com export(sip_redirect_context=context_2)
2014-11-24 21:35:52.300404 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [sip_redirect_context]=[context_2]
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com export(ecallmgr_Inception=)
2014-11-24 21:35:52.300404 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [ecallmgr_Inception]=[UNDEF]
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com bridge({call_timeout=20,originate_timeout=20,local_var_clobber='true'}[ecallmgr_Authorizing-ID='4475b86dc6c38cd77f30887d5abebf94',ecallmgr_Owner-ID='237117b58972853140384510b954bcc4',ecallmgr_Account-ID='d2ad30852ffb879da1a65dd55f49c6c7',sdp_secure_savp_only='true',rtp_secure_media='true',sip_invite_domain='beta.revoltcloud.com',presence_id='nina@beta.revoltcloud.com',absolute_codec_string='^^:OPUS:speex@32000h:speex@16000h:PCMU:PCMA:VP8:H264:H263:H261',leg_timeout='20',effective_callee_id_number='+19176278994',effective_callee_id_name='Nina Giordano',origination_callee_id_number='+19176278994',origination_callee_id_name='Nina Giordano',ecallmgr_Realm='beta.revoltcloud.com',ecallmgr_Username='nina']sofia/sipinterface_1/nina@96.250.214.196:50072;fs_path=sip:198.27.82.183:5060;lr;received='sip:96.250.214.196:50072')
2014-11-24 21:35:52.300404 [DEBUG] kazoo_event_stream.c:242 increased event stream buffer size to 4907
2014-11-24 21:35:52.300404 [DEBUG] switch_channel.c:1200 sofia/sipinterface_1/david@beta.revoltcloud.com EXPORTING[export_vars] [ecallmgr_Bridge-ID]=[N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.] to event
2014-11-24 21:35:52.300404 [DEBUG] switch_channel.c:1200 sofia/sipinterface_1/david@beta.revoltcloud.com EXPORTING[export_vars] [ecallmgr_Ecallmgr-Node]=[ecallmgr@beta001.revoltcloud.com] to event
2014-11-24 21:35:52.300404 [DEBUG] switch_channel.c:1200 sofia/sipinterface_1/david@beta.revoltcloud.com EXPORTING[export_vars] [sip_redirect_context]=[context_2] to event
2014-11-24 21:35:52.300404 [DEBUG] switch_ivr_originate.c:2079 Parsing global variables
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [call_timeout]=[20]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [originate_timeout]=[20]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [local_var_clobber]=[true]
2014-11-24 21:35:52.300404 [DEBUG] switch_ivr_originate.c:2526 Parsing session specific variables
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [ecallmgr_Authorizing-ID]=[4475b86dc6c38cd77f30887d5abebf94]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [ecallmgr_Owner-ID]=[237117b58972853140384510b954bcc4]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [ecallmgr_Account-ID]=[d2ad30852ffb879da1a65dd55f49c6c7]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [sdp_secure_savp_only]=[true]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [rtp_secure_media]=[true]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [sip_invite_domain]=[beta.revoltcloud.com]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [presence_id]=[nina@beta.revoltcloud.com]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [absolute_codec_string]=[^^:OPUS:speex@32000h:speex@16000h:PCMU:PCMA:VP8:H264:H263:H261]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [leg_timeout]=[20]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [effective_callee_id_number]=[+19176278994]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [effective_callee_id_name]=[Nina Giordano]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [origination_callee_id_number]=[+19176278994]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [origination_callee_id_name]=[Nina Giordano]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [ecallmgr_Realm]=[beta.revoltcloud.com]
2014-11-24 21:35:52.300404 [DEBUG] switch_event.c:1688 Parsing variable [ecallmgr_Username]=[nina]
2014-11-24 21:35:52.300404 [NOTICE] switch_channel.c:1054 New Channel sofia/sipinterface_1/nina@96.250.214.196:50072 [c61cb3e6-744b-11e4-a4fe-69437e0ffad5]
2014-11-24 21:35:52.300404 [DEBUG] mod_sofia.c:4591 (sofia/sipinterface_1/nina@96.250.214.196:50072) State Change CS_NEW -> CS_INIT
2014-11-24 21:35:52.300404 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/nina@96.250.214.196:50072 [BREAK]
2014-11-24 21:35:52.300404 [DEBUG] switch_ivr_originate.c:2820 sofia/sipinterface_1/nina@96.250.214.196:50072 Setting leg timeout to 20
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/nina@96.250.214.196:50072) Running State Change CS_INIT
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:510 (sofia/sipinterface_1/nina@96.250.214.196:50072) State INIT
2014-11-24 21:35:52.300404 [DEBUG] mod_sofia.c:87 sofia/sipinterface_1/nina@96.250.214.196:50072 SOFIA INIT
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [1 AEAD_AES_256_GCM_8 inline:9to+w5mTyHz/U2Za7X4sonYx8jiT18D5/v0icYkW1+dqMiqXgqpXnf/S+JY]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [1 AEAD_AES_256_GCM_8 inline:j+GSbkjDkuBBiqiHD3AHgnLtThloW+lkey61JcAptvyFI+2IBsN3RiJboIU]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [2 AEAD_AES_128_GCM_8 inline:IZ4lUuQNe2pHhhj0FPMcKcF6z680Ivp0/ucgVg]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [2 AEAD_AES_128_GCM_8 inline:UruruMvNExVOqOKByFCAUEOj/6AznEl7offmQA]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:9cbC0yGdtTm7v7gNlKNf3vYskuKi9Pz7OZwZ1byIdU+q1ZfLmRfyGIhrbQ0oVQ]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:Wn/AwBV8XmxEukyK8MtakzhQtD+ZCpXhd0c6y+GlwiQuwoIPKfdrtSHYcJXY9g]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:Pk+G4y9vQe1DfBKQwqTTvNvwHE4eAwpLSTQQ925Iu8OJKxDmyEw]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:Tlq0l3FeQkf7pkOeqnIexGrqRj1P/kv522U8aZ9hyUvmRzJNE8g]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:hTkIFT8QpRCHYIgbF/tfdC/rE/cbMr+UU2OQYPZy]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:HABKqIu/SEyJzWan/8vxfsOxNgeQL8RBaPAzcqcH]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:DghhYBtLoR+YUnzWSl71WyUOzYYhkpauiNRGxHL78CArcTg0vszU+allR+GJBA]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:eMGT5p3EyNXntb58mZ8/pvfVfEyC6YuidKpHs+u8spUNQcySZMZSDVxLOwHfFg]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:adBI2gsEIReXbfRTEVVa5HJMFI5afz1BydhrozmBr5Ip1jhvPrU]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:L9R6eepDQtP9vR63sWY3Ixc1nuyYVM9jf++L/BlXPg5RZbLSrow]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline:PKXtpbQaQawBYupazB0OtUH+6g1fAzldwWZdVtmF]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline:rVAMdLjuorsxSqgb/HtCcXUTbVsYGQrQCPWFsR1P]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [9 AES_CM_128_NULL_AUTH inline:EqfW1FTQtMrjp814AS+gzvQceT+6wXUo5ZgVsaaY]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [9 AES_CM_128_NULL_AUTH inline:v8mVS5k7hG/e0f3K16zM1cjxqnAjXAxCOPEPzH8K]
2014-11-24 21:35:52.300404 [DEBUG] sofia_glue.c:1197 sip:198.27.82.183:5060 Setting proxy route to sofia/sipinterface_1/nina@96.250.214.196:50072
2014-11-24 21:35:52.300404 [DEBUG] sofia_glue.c:1226 sofia/sipinterface_1/nina@96.250.214.196:50072 sending invite version: 1.4.7  64bit
Local SDP:
v=0
o=FreeSWITCH 1416851830 1416851831 IN IP4 198.27.82.197
s=FreeSWITCH
c=IN IP4 198.27.82.197
t=0 0
m=audio 31122 RTP/SAVP 98 110 0 8 101 13
a=rtpmap:98 opus/48000/2
a=fmtp:98 useinbandfec=1; usedtx=1; maxaveragebitrate=30000; ptime=20; minptime=20; maxptime=20; samplerate=48000
a=rtpmap:110 SPEEX/32000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=crypto:1 AEAD_AES_256_GCM_8 inline:9to+w5mTyHz/U2Za7X4sonYx8jiT18D5/v0icYkW1+dqMiqXgqpXnf/S+JY
a=crypto:2 AEAD_AES_128_GCM_8 inline:IZ4lUuQNe2pHhhj0FPMcKcF6z680Ivp0/ucgVg
a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:9cbC0yGdtTm7v7gNlKNf3vYskuKi9Pz7OZwZ1byIdU+q1ZfLmRfyGIhrbQ0oVQ
a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:Pk+G4y9vQe1DfBKQwqTTvNvwHE4eAwpLSTQQ925Iu8OJKxDmyEw
a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:hTkIFT8QpRCHYIgbF/tfdC/rE/cbMr+UU2OQYPZy
a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:DghhYBtLoR+YUnzWSl71WyUOzYYhkpauiNRGxHL78CArcTg0vszU+allR+GJBA
a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:adBI2gsEIReXbfRTEVVa5HJMFI5afz1BydhrozmBr5Ip1jhvPrU
a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:PKXtpbQaQawBYupazB0OtUH+6g1fAzldwWZdVtmF
a=crypto:9 AES_CM_128_NULL_AUTH inline:EqfW1FTQtMrjp814AS+gzvQceT+6wXUo5ZgVsaaY
a=ptime:20
a=sendrecv
m=video 18614 RTP/SAVP 99 34 31
a=rtpmap:99 H264/90000
a=rtpmap:34 H263/90000
a=rtpmap:31 H261/90000
a=crypto:1 AEAD_AES_256_GCM_8 inline:j+GSbkjDkuBBiqiHD3AHgnLtThloW+lkey61JcAptvyFI+2IBsN3RiJboIU
a=crypto:2 AEAD_AES_128_GCM_8 inline:UruruMvNExVOqOKByFCAUEOj/6AznEl7offmQA
a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:Wn/AwBV8XmxEukyK8MtakzhQtD+ZCpXhd0c6y+GlwiQuwoIPKfdrtSHYcJXY9g
a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:Tlq0l3FeQkf7pkOeqnIexGrqRj1P/kv522U8aZ9hyUvmRzJNE8g
a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:HABKqIu/SEyJzWan/8vxfsOxNgeQL8RBaPAzcqcH
a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:eMGT5p3EyNXntb58mZ8/pvfVfEyC6YuidKpHs+u8spUNQcySZMZSDVxLOwHfFg
a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:L9R6eepDQtP9vR63sWY3Ixc1nuyYVM9jf++L/BlXPg5RZbLSrow
a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:rVAMdLjuorsxSqgb/HtCcXUTbVsYGQrQCPWFsR1P
a=crypto:9 AES_CM_128_NULL_AUTH inline:v8mVS5k7hG/e0f3K16zM1cjxqnAjXAxCOPEPzH8K

2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:40 sofia/sipinterface_1/nina@96.250.214.196:50072 Standard INIT
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:48 (sofia/sipinterface_1/nina@96.250.214.196:50072) State Change CS_INIT -> CS_ROUTING
2014-11-24 21:35:52.300404 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/nina@96.250.214.196:50072 [BREAK]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:510 (sofia/sipinterface_1/nina@96.250.214.196:50072) State INIT going to sleep
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/nina@96.250.214.196:50072) Running State Change CS_ROUTING
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/nina@96.250.214.196:50072) State ROUTING
2014-11-24 21:35:52.300404 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/nina@96.250.214.196:50072 SOFIA ROUTING
2014-11-24 21:35:52.300404 [DEBUG] switch_ivr_originate.c:67 (sofia/sipinterface_1/nina@96.250.214.196:50072) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2014-11-24 21:35:52.300404 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/nina@96.250.214.196:50072 [BREAK]
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/nina@96.250.214.196:50072) State ROUTING going to sleep
send 3175 bytes to udp/[198.27.82.183]:5060 at 21:35:52.311385:
   ------------------------------------------------------------------------
   INVITE sip:nina@96.250.214.196:50072 SIP/2.0
   Via: SIP/2.0/UDP 198.27.82.197:11000;rport;branch=z9hG4bKvSvm1ZHKS1Xej
   Route: <sip:198.27.82.183:5060>
   Max-Forwards: 49
   From: "David Johnson" <sip:+17184730090@beta.revoltcloud.com>;tag=ZaD8FX7HN0Btr
   To: <sip:nina@96.250.214.196:50072>
   Call-ID: c61cb3e6-744b-11e4-a4fe-69437e0ffad5
   CSeq: 68105412 INVITE
   Contact: <sip:mod_sofia@198.27.82.197:11000>
   User-Agent: 2600hz
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: 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: 2181
   X-AUTH-IP: 96.250.214.196
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "David Johnson" <sip:+17184730090@beta.revoltcloud.com>;party=calling;screen=yes;privacy=off

   v=0
   o=FreeSWITCH 1416851830 1416851831 IN IP4 198.27.82.197
   s=FreeSWITCH
   c=IN IP4 198.27.82.197
   t=0 0
   m=audio 31122 RTP/SAVP 98 110 0 8 101 13
   a=rtpmap:98 opus/48000/2
   a=fmtp:98 useinbandfec=1; usedtx=1; maxaveragebitrate=30000; ptime=20; minptime=20; maxptime=20; samplerate=48000
   a=rtpmap:110 SPEEX/32000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/nina@96.250.214.196:50072) Running State Change CS_CONSUME_MEDIA
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=crypto:1 AEAD_AES_256_GCM_8 inline:9to+w5mTyHz/U2Za7X4sonYx8jiT18D5/v0icYkW1+dqMiqXgqpXnf/S+JY
   a=crypto:2 AEAD_AES_128_GCM_8 inline:IZ4lUuQNe2pHhhj0FPMcKcF6z680Ivp0/ucgVg
   a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:9cbC0yGdtTm7v7gNlKNf3vYskuKi9Pz7OZwZ1byIdU+q1ZfLmRfyGIhrbQ0oVQ
   a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:Pk+G4y9vQe1DfBKQwqTTvNvwHE4eAwpLSTQQ925Iu8OJKxDmyEw
   a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:hTkIFT8QpRCHYIgbF/tfdC/rE/cbMr+UU2OQYPZy
   a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:DghhYBtLoR+YUnzWSl71WyUOzYYhkpauiNRGxHL78CArcTg0vszU+allR+GJBA
   a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:adBI2gsEIReXbfRTEVVa5HJMFI5afz1BydhrozmBr5Ip1jhvPrU
   a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:PKXtpbQaQawBYupazB0OtUH+6g1fAzldwWZdVtmF
   a=crypto:9 AES_CM_128_NULL_AUTH inline:EqfW1FTQtMrjp814AS+gzvQceT+6wXUo5ZgVsaaY
   a=ptime:20
   m=video 18614 RTP/SAVP 99 34 31
   a=rtpmap:99 H264/90000
   a=rtpmap:34 H263/90000
   a=rtpmap:31 H261/90000
   a=crypto:1 AEAD_AES_256_GCM_8 inline:j+GSbkjDkuBBiqiHD3AHgnLtThloW+lkey61JcAptvyFI+2IBsN3RiJboIU
   a=crypto:2 AEAD_AES_128_GCM_8 inline:UruruMvNExVOqOKByFCAUEOj/6AznEl7offmQA
   a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:Wn/AwBV8XmxEukyK8MtakzhQtD+ZCpXhd0c6y+GlwiQuwoIPKfdrtSHYcJXY9g
   a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:Tlq0l3FeQkf7pkOeqnIexGrqRj1P/kv522U8aZ9hyUvmRzJNE8g
   a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:HABKqIu/SEyJzWan/8vxfsOxNgeQL8RBaPAzcqcH
   a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:eMGT5p3EyNXntb58mZ8/pvfVfEyC6YuidKpHs+u8spUNQcySZMZSDVxLOwHfFg
   a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:L9R6eepDQtP9vR63sWY3Ixc1nuyYVM9jf++L/BlXPg5RZbLSrow
   a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:rVAMdLjuorsxSqgb/HtCcXUTbVsYGQrQCPWFsR1P
   a=crypto:9 AES_CM_128_NULL_AUTH inline:v8mVS5k7hG/e0f3K16zM1cjxqnAjXAxCOPEPzH8K
   ------------------------------------------------------------------------
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:545 (sofia/sipinterface_1/nina@96.250.214.196:50072) State CONSUME_MEDIA
2014-11-24 21:35:52.300404 [DEBUG] switch_core_state_machine.c:545 (sofia/sipinterface_1/nina@96.250.214.196:50072) State CONSUME_MEDIA going to sleep
2014-11-24 21:35:52.300404 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/nina@96.250.214.196:50072 [BREAK]
2014-11-24 21:35:52.300404 [DEBUG] sofia.c:6413 Channel sofia/sipinterface_1/nina@96.250.214.196:50072 entering state [calling][0]
recv 332 bytes from udp/[198.27.82.183]:5060 at 21:35:52.312961:
   ------------------------------------------------------------------------
   SIP/2.0 100 Attempting to connect your call
   Via: SIP/2.0/UDP 198.27.82.197:11000;rport=11000;branch=z9hG4bKvSvm1ZHKS1Xej
   From: "David Johnson" <sip:+17184730090@beta.revoltcloud.com>;tag=ZaD8FX7HN0Btr
   To: <sip:nina@96.250.214.196:50072>
   Call-ID: c61cb3e6-744b-11e4-a4fe-69437e0ffad5
   CSeq: 68105412 INVITE
   Content-Length: 0

   ------------------------------------------------------------------------
2014-11-24 21:35:52.300404 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecallmgr@beta001.revoltcloud.com <1.16827.4>
2014-11-24 21:35:52.300404 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(c61cb3e6-744b-11e4-a4fe-69437e0ffad5 ecallmgr_Channel-Authorized=true;ecallmgr_Account-ID=d2ad30852ffb879da1a65dd55f49c6c7)
recv 362 bytes from udp/[198.27.82.183]:5060 at 21:35:52.439132:
   ------------------------------------------------------------------------
   SIP/2.0 415 Unsupported Media Type
   Via: SIP/2.0/UDP 198.27.82.197:11000;rport=11000;branch=z9hG4bKvSvm1ZHKS1Xej
   To: <sip:nina@96.250.214.196:50072>;tag=cd865948
   From: "David Johnson"<sip:+17184730090@beta.revoltcloud.com>;tag=ZaD8FX7HN0Btr
   Call-ID: c61cb3e6-744b-11e4-a4fe-69437e0ffad5
   CSeq: 68105412 INVITE
   User-Agent: Zoiper r27147
   Content-Length: 0

   ------------------------------------------------------------------------
send 385 bytes to udp/[198.27.82.183]:5060 at 21:35:52.439280:
   ------------------------------------------------------------------------
   ACK sip:nina@96.250.214.196:50072 SIP/2.0
   Via: SIP/2.0/UDP 198.27.82.197:11000;rport;branch=z9hG4bKvSvm1ZHKS1Xej
   Route: <sip:198.27.82.183:5060>
   Max-Forwards: 49
   From: "David Johnson" <sip:+17184730090@beta.revoltcloud.com>;tag=ZaD8FX7HN0Btr
   To: <sip:nina@96.250.214.196:50072>;tag=cd865948
   Call-ID: c61cb3e6-744b-11e4-a4fe-69437e0ffad5
   CSeq: 68105412 ACK
   Content-Length: 0

   ------------------------------------------------------------------------
2014-11-24 21:35:52.420397 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/nina@96.250.214.196:50072 [BREAK]
2014-11-24 21:35:52.420397 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/nina@96.250.214.196:50072 [BREAK]
2014-11-24 21:35:52.420397 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/nina@96.250.214.196:50072 [BREAK]
2014-11-24 21:35:52.420397 [DEBUG] sofia.c:6413 Channel sofia/sipinterface_1/nina@96.250.214.196:50072 entering state [terminated][415]
2014-11-24 21:35:52.420397 [NOTICE] sofia.c:7299 Hangup sofia/sipinterface_1/nina@96.250.214.196:50072 [CS_CONSUME_MEDIA] [SERVICE_NOT_IMPLEMENTED]
2014-11-24 21:35:52.420397 [DEBUG] switch_channel.c:3217 Send signal sofia/sipinterface_1/nina@96.250.214.196:50072 [KILL]
2014-11-24 21:35:52.420397 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/nina@96.250.214.196:50072 [BREAK]
2014-11-24 21:35:52.420397 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/nina@96.250.214.196:50072) Running State Change CS_HANGUP
2014-11-24 21:35:52.420397 [DEBUG] switch_core_state_machine.c:733 (sofia/sipinterface_1/nina@96.250.214.196:50072) Callstate Change DOWN -> HANGUP
2014-11-24 21:35:52.420397 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/nina@96.250.214.196:50072) State HANGUP
2014-11-24 21:35:52.420397 [DEBUG] mod_sofia.c:413 Channel sofia/sipinterface_1/nina@96.250.214.196:50072 hanging up, cause: SERVICE_NOT_IMPLEMENTED
2014-11-24 21:35:52.440402 [DEBUG] switch_core_state_machine.c:58 sofia/sipinterface_1/nina@96.250.214.196:50072 Standard HANGUP, cause: SERVICE_NOT_IMPLEMENTED
2014-11-24 21:35:52.440402 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/nina@96.250.214.196:50072) State HANGUP going to sleep
2014-11-24 21:35:52.440402 [DEBUG] switch_core_state_machine.c:502 (sofia/sipinterface_1/nina@96.250.214.196:50072) State Change CS_HANGUP -> CS_REPORTING
2014-11-24 21:35:52.440402 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/nina@96.250.214.196:50072 [BREAK]
2014-11-24 21:35:52.440402 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/nina@96.250.214.196:50072) Running State Change CS_REPORTING
2014-11-24 21:35:52.440402 [DEBUG] switch_core_state_machine.c:821 (sofia/sipinterface_1/nina@96.250.214.196:50072) State REPORTING
2014-11-24 21:35:52.440402 [DEBUG] switch_core_state_machine.c:102 sofia/sipinterface_1/nina@96.250.214.196:50072 Standard REPORTING, cause: SERVICE_NOT_IMPLEMENTED
2014-11-24 21:35:52.440402 [DEBUG] switch_core_state_machine.c:821 (sofia/sipinterface_1/nina@96.250.214.196:50072) State REPORTING going to sleep
2014-11-24 21:35:52.440402 [DEBUG] switch_core_state_machine.c:496 (sofia/sipinterface_1/nina@96.250.214.196:50072) State Change CS_REPORTING -> CS_DESTROY
2014-11-24 21:35:52.440402 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/nina@96.250.214.196:50072 [BREAK]
2014-11-24 21:35:52.440402 [DEBUG] switch_core_session.c:1614 Session 76 (sofia/sipinterface_1/nina@96.250.214.196:50072) Locked, Waiting on external entities
2014-11-24 21:35:52.440402 [DEBUG] switch_ivr_originate.c:3695 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED]
2014-11-24 21:35:52.440402 [NOTICE] switch_core_session.c:1632 Session 76 (sofia/sipinterface_1/nina@96.250.214.196:50072) Ended
2014-11-24 21:35:52.440402 [NOTICE] switch_core_session.c:1636 Close Channel sofia/sipinterface_1/nina@96.250.214.196:50072 [CS_DESTROY]
2014-11-24 21:35:52.440402 [DEBUG] switch_core_state_machine.c:624 (sofia/sipinterface_1/nina@96.250.214.196:50072) Running State Change CS_DESTROY
2014-11-24 21:35:52.440402 [INFO] mod_dptools.c:3277 Originate Failed.  Cause: SERVICE_NOT_IMPLEMENTED
2014-11-24 21:35:52.440402 [DEBUG] switch_core_state_machine.c:634 (sofia/sipinterface_1/nina@96.250.214.196:50072) State DESTROY
2014-11-24 21:35:52.440402 [DEBUG] mod_sofia.c:323 sofia/sipinterface_1/nina@96.250.214.196:50072 SOFIA DESTROY
2014-11-24 21:35:52.440402 [DEBUG] switch_core_state_machine.c:109 sofia/sipinterface_1/nina@96.250.214.196:50072 Standard DESTROY
2014-11-24 21:35:52.440402 [DEBUG] switch_core_state_machine.c:634 (sofia/sipinterface_1/nina@96.250.214.196:50072) State DESTROY going to sleep
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com event(Event-Name=CUSTOM,Event-Subclass=whistle::masquerade,whistle_event_name=CHANNEL_EXECUTE_COMPLETE,whistle_application_name=bridge)
2014-11-24 21:35:52.440402 [DEBUG] kazoo_event_stream.c:242 increased event stream buffer size to 5048
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com park()
2014-11-24 21:35:52.440402 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecallmgr@beta001.revoltcloud.com <1.16866.4>
2014-11-24 21:35:52.440402 [NOTICE] kazoo_node.c:285 log|N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.|executing answer
2014-11-24 21:35:52.440402 [DEBUG] switch_core_session.c:1187 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:52.460397 [DEBUG] switch_ivr.c:614 sofia/sipinterface_1/david@beta.revoltcloud.com Command Execute answer()
EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com answer()
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[opus:116:48000:20:0:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[G7221:115:32000:20:48000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[G7221:107:16000:20:32000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[G722:9:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[GSM:3:8000:20:13200:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[SPEEX:99:8000:20:24600:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3528 Audio Codec Compare [SPEEX:99:8000:20:24600:1] ++++ is saved as a match
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G7221:115:32000:20:48000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G7221:107:16000:20:32000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[GSM:3:8000:20:13200:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3528 Audio Codec Compare [GSM:3:8000:20:13200:1] ++++ is saved as a match
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[SPEEX:99:8000:20:24600:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G7221:115:32000:20:48000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G7221:107:16000:20:32000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3528 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[SPEEX:99:8000:20:24600:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G7221:115:32000:20:48000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G7221:107:16000:20:32000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3528 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[SPEEX:99:8000:20:24600:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[opus:116:48000:20:0:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G7221:115:32000:20:48000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G7221:107:16000:20:32000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G722:9:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[GSM:3:8000:20:13200:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[SPEEX:99:8000:20:24600:1]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3395 Set telephone-event payload to 101
2014-11-24 21:35:52.460397 [WARNING] switch_core_media.c:3546 Crypto not negotiated but required.
2014-11-24 21:35:52.460397 [DEBUG] switch_core_media.c:3719 Set 2833 dtmf send/recv payload to 101
2014-11-24 21:35:52.460397 [DEBUG] switch_core_session.c:907 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:52.460397 [NOTICE] switch_channel.c:3722 Hangup sofia/sipinterface_1/david@beta.revoltcloud.com [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]
2014-11-24 21:35:52.460397 [DEBUG] switch_channel.c:3217 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [KILL]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_session.c:2887 sofia/sipinterface_1/david@beta.revoltcloud.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2014-11-24 21:35:52.460397 [DEBUG] switch_core_session.c:2887 sofia/sipinterface_1/david@beta.revoltcloud.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:533 (sofia/sipinterface_1/david@beta.revoltcloud.com) State EXECUTE going to sleep
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_HANGUP
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:733 (sofia/sipinterface_1/david@beta.revoltcloud.com) Callstate Change RINGING -> HANGUP
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/david@beta.revoltcloud.com) State HANGUP
2014-11-24 21:35:52.460397 [DEBUG] mod_sofia.c:407 sofia/sipinterface_1/david@beta.revoltcloud.com Overriding SIP cause 488 with 415 from the other leg
2014-11-24 21:35:52.460397 [DEBUG] mod_sofia.c:413 Channel sofia/sipinterface_1/david@beta.revoltcloud.com hanging up, cause: INCOMPATIBLE_DESTINATION
2014-11-24 21:35:52.460397 [DEBUG] mod_sofia.c:553 Responding to INVITE with: 415
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:58 sofia/sipinterface_1/david@beta.revoltcloud.com Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/david@beta.revoltcloud.com) State HANGUP going to sleep
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:502 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_HANGUP -> CS_REPORTING
2014-11-24 21:35:52.460397 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_REPORTING
send 944 bytes to udp/[198.27.82.183]:5060 at 21:35:52.474865:
   ------------------------------------------------------------------------
   SIP/2.0 415 Unsupported Media Type
   Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bKd913.6cb4e55.0
   Via: SIP/2.0/UDP 96.250.214.196:58144;branch=z9hG4bK-d8754z-a457c2692cfbcf65-1---d8754z-;rport=58144
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:821 (sofia/sipinterface_1/david@beta.revoltcloud.com) State REPORTING
   Max-Forwards: 50
   From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=5d74853b
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:102 sofia/sipinterface_1/david@beta.revoltcloud.com Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
   To: <sip:102@beta.revoltcloud.com;transport=UDP>;tag=y1KFe2perQN7c
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:821 (sofia/sipinterface_1/david@beta.revoltcloud.com) State REPORTING going to sleep
   Call-ID: N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.
   CSeq: 2 INVITE
   User-Agent: 2600hz
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: 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
   Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION"
   Content-Length: 0
   Remote-Party-ID: "102" <sip:102@beta.revoltcloud.com>;party=calling;privacy=off;screen=no

   ------------------------------------------------------------------------
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:496 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_REPORTING -> CS_DESTROY
2014-11-24 21:35:52.460397 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_session.c:1614 Session 75 (sofia/sipinterface_1/david@beta.revoltcloud.com) Locked, Waiting on external entities
2014-11-24 21:35:52.460397 [NOTICE] switch_core_session.c:1632 Session 75 (sofia/sipinterface_1/david@beta.revoltcloud.com) Ended
2014-11-24 21:35:52.460397 [NOTICE] switch_core_session.c:1636 Close Channel sofia/sipinterface_1/david@beta.revoltcloud.com [CS_DESTROY]
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:624 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_DESTROY
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:634 (sofia/sipinterface_1/david@beta.revoltcloud.com) State DESTROY
2014-11-24 21:35:52.460397 [DEBUG] mod_sofia.c:323 sofia/sipinterface_1/david@beta.revoltcloud.com SOFIA DESTROY
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:109 sofia/sipinterface_1/david@beta.revoltcloud.com Standard DESTROY
2014-11-24 21:35:52.460397 [DEBUG] switch_core_state_machine.c:634 (sofia/sipinterface_1/david@beta.revoltcloud.com) State DESTROY going to sleep
recv 359 bytes from udp/[198.27.82.183]:5060 at 21:35:52.475708:
   ------------------------------------------------------------------------
   ACK sip:102@beta.revoltcloud.com;transport=UDP SIP/2.0
   Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bKd913.6cb4e55.0
   Max-Forwards: 50
   To: <sip:102@beta.revoltcloud.com;transport=UDP>;tag=y1KFe2perQN7c
   From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=5d74853b
   Call-ID: N2E3ZDg0ZDc1MGY1ZDNkMzZhMDA3NjQ1M2UyZTM3OTA.
   CSeq: 2 ACK
   Content-Length: 0

   ------------------------------------------------------------------------
2014-11-24 21:35:52.640397 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecallmgr@beta001.revoltcloud.com <1.16827.4>
recv 311 bytes from udp/[198.27.82.183]:5060 at 21:35:55.524062:
   ------------------------------------------------------------------------
   OPTIONS sip:198.27.82.197:11000 SIP/2.0
   Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK75ce.e5e09132.0
   To: sip:198.27.82.197:11000
   From: sip:sipcheck@198.27.82.183;tag=ff021d86b7c45693467b7c7db964bf77-c414
   CSeq: 10 OPTIONS
   Call-ID: 2374459d34e8a483-3041@198.27.82.183
   Max-Forwards: 70
   Content-Length: 0

   ------------------------------------------------------------------------
send 677 bytes to udp/[198.27.82.183]:5060 at 21:35:55.524375:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK75ce.e5e09132.0
   From: sip:sipcheck@198.27.82.183;tag=ff021d86b7c45693467b7c7db964bf77-c414
   To: <sip:198.27.82.197:11000>;tag=0K60HrrNj91cm
   Call-ID: 2374459d34e8a483-3041@198.27.82.183
   CSeq: 10 OPTIONS
   Contact: <sip:198.27.82.197:11000>
   User-Agent: 2600hz
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: 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

   ------------------------------------------------------------------------