API tools faq
paste
DeeJae

FreeSwitch log

DeeJae
Nov 23rd, 2014
288
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
FreeSWITCH 61.19 KB | None | 0 0
raw download clone embed print report
  1. [root@freeswitch ~]# fs_cli
  2. .=======================================================.
  3. |            _____ ____     ____ _     ___              |
  4. |           |  ___/ ___|   / ___| |   |_ _|             |
  5. |           | |_  \___ \  | |   | |    | |              |
  6. |           |  _|  ___) | | |___| |___ | |              |
  7. |           |_|   |____/   \____|_____|___|             |
  8. |                                                       |
  9. .=======================================================.
  10. | Anthony Minessale II, Ken Rice,                       |
  11. | Michael Jerris, Travis Cross                          |
  12. | FreeSWITCH (http://www.freeswitch.org)                |
  13. | Paypal Donations Appreciated: paypal@freeswitch.org   |
  14. | Brought to you by ClueCon http://www.cluecon.com/     |
  15. .=======================================================.
  16.  
  17. .===============================================================.
  18. |       _                                                       |
  19. |   ___| |_   _  ___  ___ ___  _ __         ___ ___  _ __ ___   |
  20. |  / __| | | | |/ _ \/ __/ _ \| '_ \       / __/ _ \| '_ ` _ \  |
  21. | | (__| | |_| |  __/ (_| (_) | | | |  _  | (_| (_) | | | | | | |
  22. |  \___|_|\__,_|\___|\___\___/|_| |_| (_)  \___\___/|_| |_| |_| |
  23. |                                                               |
  24. .===============================================================.
  25.  
  26. Type /help <enter> to see a list of commands
  27.  
  28.  
  29.  
  30.  
  31. [This app Best viewed at 160x60 or more..]
  32. +OK log level  [7]
  33. freeswitch@internal> recv 312 bytes from udp/[198.27.82.183]:5060 at 14:43:24.160014:
  34.    ------------------------------------------------------------------------
  35.    OPTIONS sip:198.27.82.197:11000 SIP/2.0
  36.    Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK040a.d96d0fc3.0
  37.    To: sip:198.27.82.197:11000
  38.    From: sip:sipcheck@198.27.82.183;tag=ff021d86b7c45693467b7c7db964bf77-d790
  39.    CSeq: 10 OPTIONS
  40.    Call-ID: 3d16663b4d432d5c-24193@198.27.82.183
  41.    Max-Forwards: 70
  42.    Content-Length: 0
  43.  
  44.    ------------------------------------------------------------------------
  45. send 678 bytes to udp/[198.27.82.183]:5060 at 14:43:24.160349:
  46.    ------------------------------------------------------------------------
  47.    SIP/2.0 200 OK
  48.    Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK040a.d96d0fc3.0
  49.    From: sip:sipcheck@198.27.82.183;tag=ff021d86b7c45693467b7c7db964bf77-d790
  50.    To: <sip:198.27.82.197:11000>;tag=gZSQm83v8BrvB
  51.    Call-ID: 3d16663b4d432d5c-24193@198.27.82.183
  52.    CSeq: 10 OPTIONS
  53.    Contact: <sip:198.27.82.197:11000>
  54.    User-Agent: 2600hz
  55.    Accept: application/sdp
  56.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  57.    Supported: path, replaces
  58.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  59.    Content-Length: 0
  60.  
  61.    ------------------------------------------------------------------------
  62. recv 1209 bytes from udp/[198.27.82.183]:5060 at 14:43:25.545420:
  63.    ------------------------------------------------------------------------
  64.    INVITE sip:102@beta.revoltcloud.com;transport=UDP SIP/2.0
  65.    Record-Route: <sip:198.27.82.183;lr=on;ftag=d4b23858>
  66.    Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK7079.335563e7.0
  67.    Via: SIP/2.0/UDP 96.250.214.196:58144;branch=z9hG4bK-d8754z-7ed856ccd16e93af-1---d8754z-;rport=58144
  68.    Max-Forwards: 50
  69.    Contact: <sip:david@96.250.214.196:58144;transport=UDP>
  70.    To: <sip:102@beta.revoltcloud.com;transport=UDP>
  71.    From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=d4b23858
  72.    Call-ID: YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.
  73.    CSeq: 1 INVITE
  74.   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  75.    Content-Type: application/sdp
  76.    Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
  77.    User-Agent: Zoiper r27147
  78.    Allow-Events: presence, kpml
  79.    Content-Length: 371
  80.    X-AUTH-IP: 96.250.214.196
  81.  
  82.    v=0
  83.    o=Zoiper 0 0 IN IP4 96.250.214.196
  84.    s=Zoiper
  85.    c=IN IP4 96.250.214.196
  86.    t=0 0
  87.    m=audio 34314 RTP/AVP 110 3 0 8 97 101
  88.    a=rtpmap:110 speex/8000
  89.    a=rtpmap:3 GSM/8000
  90.    a=rtpmap:0 PCMU/8000
  91.    a=rtpmap:8 PCMA/8000
  92.    a=rtpmap:97 iLBC/8000
  93.    a=fmtp:97 mode=30
  94.    a=rtpmap:101 telephone-event/8000
  95.    a=fmtp:101 0-16
  96.    a=sendrecv
  97.    a=oldmediaip:192.168.1.3
  98.    a=oldmediaip:192.168.1.3
  99.    ------------------------------------------------------------------------
  100. send 468 bytes to udp/[198.27.82.183]:5060 at 14:43:25.545748:
  101.    ------------------------------------------------------------------------
  102.    SIP/2.0 100 Trying
  103.    Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK7079.335563e7.0
  104.    Via: SIP/2.0/UDP 96.250.214.196:58144;branch=z9hG4bK-d8754z-7ed856ccd16e93af-1---d8754z-;rport=58144
  105.    Record-Route: <sip:198.27.82.183;lr=on;ftag=d4b23858>
  106.    From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=d4b23858
  107.    To: <sip:102@beta.revoltcloud.com;transport=UDP>
  108.    Call-ID: YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.
  109.    CSeq: 1 INVITE
  110.    User-Agent: 2600hz
  111.    Content-Length: 0
  112.  
  113.    ------------------------------------------------------------------------
  114. 2014-11-23 14:43:25.540522 [NOTICE] switch_channel.c:1054 New Channel sofia/sipinterface_1/david@beta.revoltcloud.com [YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.]
  115. 2014-11-23 14:43:25.540522 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  116. 2014-11-23 14:43:25.540522 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  117. 2014-11-23 14:43:25.540522 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_NEW
  118. 2014-11-23 14:43:25.540522 [DEBUG] sofia.c:8703 sofia/sipinterface_1/david@beta.revoltcloud.com receiving invite from 198.27.82.183:5060 version: 1.4.7  64bit
  119. 2014-11-23 14:43:25.540522 [DEBUG] sofia.c:8825 1 acls to check for proxy
  120. 2014-11-23 14:43:25.540522 [DEBUG] sofia.c:8830 checking 198.27.82.183 against acl authoritative
  121. 2014-11-23 14:43:25.540522 [INFO] sofia.c:8832 198.27.82.183 is a proxy according to the authoritative acl
  122. 2014-11-23 14:43:25.540522 [DEBUG] sofia.c:8842 network ip is a proxy
  123. 2014-11-23 14:43:25.540522 [DEBUG] sofia.c:8846 found auth ip [X-AUTH-IP] header of [96.250.214.196]
  124. 2014-11-23 14:43:25.540522 [DEBUG] sofia.c:8870 IP 198.27.82.183 Rejected by acl "trusted". Falling back to Digest auth.
  125. 2014-11-23 14:43:25.540522 [DEBUG] sofia_reg.c:3345 sofia pre-register disabled
  126. 2014-11-23 14:43:25.540522 [DEBUG] switch_core_state_machine.c:489 (sofia/sipinterface_1/david@beta.revoltcloud.com) State NEW
  127. send 917 bytes to udp/[198.27.82.183]:5060 at 14:43:25.546611:
  128.    ------------------------------------------------------------------------
  129.    SIP/2.0 407 Proxy Authentication Required
  130.    Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK7079.335563e7.0
  131.    Via: SIP/2.0/UDP 96.250.214.196:58144;branch=z9hG4bK-d8754z-7ed856ccd16e93af-1---d8754z-;rport=58144
  132.    From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=d4b23858
  133.    To: <sip:102@beta.revoltcloud.com;transport=UDP>;tag=H8jgp3m05meFQ
  134.    Call-ID: YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.
  135.    CSeq: 1 INVITE
  136.    User-Agent: 2600hz
  137.    Accept: application/sdp
  138.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  139.    Supported: path, replaces
  140.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  141.    Proxy-Authenticate: Digest realm="beta.revoltcloud.com", nonce="fd7a84ca-7348-11e4-b94c-ede710af1ab8", algorithm=MD5, qop="auth"
  142.    Content-Length: 0
  143.  
  144.    ------------------------------------------------------------------------
  145. 2014-11-23 14:43:25.540522 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  146. 2014-11-23 14:43:25.540522 [DEBUG] sofia.c:2033 detaching session YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.
  147. recv 360 bytes from udp/[198.27.82.183]:5060 at 14:43:25.547759:
  148.    ------------------------------------------------------------------------
  149.    ACK sip:102@beta.revoltcloud.com;transport=UDP SIP/2.0
  150.    Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK7079.335563e7.0
  151.    Max-Forwards: 50
  152.    To: <sip:102@beta.revoltcloud.com;transport=UDP>;tag=H8jgp3m05meFQ
  153.    From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=d4b23858
  154.    Call-ID: YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.
  155.    CSeq: 1 ACK
  156.    Content-Length: 0
  157.  
  158.    ------------------------------------------------------------------------
  159. recv 1499 bytes from udp/[198.27.82.183]:5060 at 14:43:25.606373:
  160.    ------------------------------------------------------------------------
  161.    INVITE sip:102@beta.revoltcloud.com;transport=UDP SIP/2.0
  162.    Record-Route: <sip:198.27.82.183;lr=on;ftag=d4b23858>
  163.    Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK4079.17067d71.0
  164.    Via: SIP/2.0/UDP 96.250.214.196:58144;branch=z9hG4bK-d8754z-1327261b2f744843-1---d8754z-;rport=58144
  165.    Max-Forwards: 50
  166.    Contact: <sip:david@96.250.214.196:58144;transport=UDP>
  167.    To: <sip:102@beta.revoltcloud.com;transport=UDP>
  168.    From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=d4b23858
  169.    Call-ID: YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.
  170.    CSeq: 2 INVITE
  171.   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  172.    Content-Type: application/sdp
  173.    Proxy-Authorization: Digest username="david",realm="beta.revoltcloud.com",nonce="fd7a84ca-7348-11e4-b94c-ede710af1ab8",uri="sip:102@beta.revoltcloud.com;transport=UDP",response="89810b63c2b2fb79dce573acc5e133d8",cnonce="0702f3937ad899df736c24d587d35dfb",nc=00000001,qop=auth,algorithm=MD5
  174.    Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
  175.    User-Agent: Zoiper r27147
  176.    Allow-Events: presence, kpml
  177.    Content-Length: 371
  178.    X-AUTH-IP: 96.250.214.196
  179.  
  180.    v=0
  181.    o=Zoiper 0 0 IN IP4 96.250.214.196
  182.    s=Zoiper
  183.    c=IN IP4 96.250.214.196
  184.    t=0 0
  185.    m=audio 34314 RTP/AVP 110 3 0 8 97 101
  186.    a=rtpmap:110 speex/8000
  187.    a=rtpmap:3 GSM/8000
  188.    a=rtpmap:0 PCMU/8000
  189.    a=rtpmap:8 PCMA/8000
  190.    a=rtpmap:97 iLBC/8000
  191.    a=fmtp:97 mode=30
  192.    a=rtpmap:101 telephone-event/8000
  193.    a=fmtp:101 0-16
  194.    a=sendrecv
  195.    a=oldmediaip:192.168.1.3
  196.    a=oldmediaip:192.168.1.3
  197.    ------------------------------------------------------------------------
  198. send 468 bytes to udp/[198.27.82.183]:5060 at 14:43:25.606663:
  199.    ------------------------------------------------------------------------
  200.    SIP/2.0 100 Trying
  201.    Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK4079.17067d71.0
  202.    Via: SIP/2.0/UDP 96.250.214.196:58144;branch=z9hG4bK-d8754z-1327261b2f744843-1---d8754z-;rport=58144
  203.    Record-Route: <sip:198.27.82.183;lr=on;ftag=d4b23858>
  204.    From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=d4b23858
  205.    To: <sip:102@beta.revoltcloud.com;transport=UDP>
  206.    Call-ID: YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.
  207.    CSeq: 2 INVITE
  208.    User-Agent: 2600hz
  209.    Content-Length: 0
  210.  
  211.    ------------------------------------------------------------------------
  212. 2014-11-23 14:43:25.600510 [DEBUG] sofia.c:2138 Re-attaching to session YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.
  213. 2014-11-23 14:43:25.600510 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  214. 2014-11-23 14:43:25.600510 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  215. 2014-11-23 14:43:25.620514 [DEBUG] sofia.c:8703 sofia/sipinterface_1/david@beta.revoltcloud.com receiving invite from 198.27.82.183:5060 version: 1.4.7  64bit
  216. 2014-11-23 14:43:25.620514 [DEBUG] sofia.c:8825 1 acls to check for proxy
  217. 2014-11-23 14:43:25.620514 [DEBUG] sofia.c:8830 checking 198.27.82.183 against acl authoritative
  218. 2014-11-23 14:43:25.620514 [INFO] sofia.c:8832 198.27.82.183 is a proxy according to the authoritative acl
  219. 2014-11-23 14:43:25.620514 [DEBUG] sofia.c:8842 network ip is a proxy
  220. 2014-11-23 14:43:25.620514 [DEBUG] sofia.c:8846 found auth ip [X-AUTH-IP] header of [96.250.214.196]
  221. 2014-11-23 14:43:25.620514 [DEBUG] sofia.c:8870 IP 198.27.82.183 Rejected by acl "trusted". Falling back to Digest auth.
  222. 2014-11-23 14:43:25.620514 [DEBUG] switch_xml.c:2009 Cache Info - Time Now : 1416771805 - Expires : 1416772631
  223. 2014-11-23 14:43:25.620514 [DEBUG] switch_xml.c:2015 david@beta.revoltcloud.com fetched from cache
  224. 2014-11-23 14:43:25.620514 [DEBUG] sofia.c:6413 Channel sofia/sipinterface_1/david@beta.revoltcloud.com entering state [received][100]
  225. 2014-11-23 14:43:25.620514 [DEBUG] sofia.c:6423 Remote SDP:
  226. v=0
  227. o=Zoiper 0 0 IN IP4 96.250.214.196
  228. s=Zoiper
  229. c=IN IP4 96.250.214.196
  230. t=0 0
  231. m=audio 34314 RTP/AVP 110 3 0 8 97 101
  232. a=rtpmap:110 speex/8000
  233. a=rtpmap:3 GSM/8000
  234. a=rtpmap:0 PCMU/8000
  235. a=rtpmap:8 PCMA/8000
  236. a=rtpmap:97 iLBC/8000
  237. a=fmtp:97 mode=30
  238. a=rtpmap:101 telephone-event/8000
  239. a=fmtp:101 0-16
  240. a=oldmediaip:192.168.1.3
  241. a=oldmediaip:192.168.1.3
  242.  
  243. 2014-11-23 14:43:25.620514 [DEBUG] sofia.c:6678 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_NEW -> CS_INIT
  244. 2014-11-23 14:43:25.620514 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  245. 2014-11-23 14:43:25.620514 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_INIT
  246. 2014-11-23 14:43:25.620514 [DEBUG] switch_core_state_machine.c:510 (sofia/sipinterface_1/david@beta.revoltcloud.com) State INIT
  247. 2014-11-23 14:43:25.620514 [DEBUG] mod_sofia.c:87 sofia/sipinterface_1/david@beta.revoltcloud.com SOFIA INIT
  248. 2014-11-23 14:43:25.620514 [DEBUG] switch_core_state_machine.c:40 sofia/sipinterface_1/david@beta.revoltcloud.com Standard INIT
  249. 2014-11-23 14:43:25.620514 [DEBUG] switch_core_state_machine.c:48 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_INIT -> CS_ROUTING
  250. 2014-11-23 14:43:25.620514 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  251. 2014-11-23 14:43:25.620514 [DEBUG] switch_core_state_machine.c:510 (sofia/sipinterface_1/david@beta.revoltcloud.com) State INIT going to sleep
  252. 2014-11-23 14:43:25.620514 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_ROUTING
  253. 2014-11-23 14:43:25.620514 [DEBUG] switch_channel.c:2179 (sofia/sipinterface_1/david@beta.revoltcloud.com) Callstate Change DOWN -> RINGING
  254. 2014-11-23 14:43:25.620514 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/david@beta.revoltcloud.com) State ROUTING
  255. 2014-11-23 14:43:25.620514 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/david@beta.revoltcloud.com SOFIA ROUTING
  256. 2014-11-23 14:43:25.620514 [DEBUG] switch_core_state_machine.c:164 sofia/sipinterface_1/david@beta.revoltcloud.com Standard ROUTING
  257. 2014-11-23 14:43:25.620514 [INFO] mod_dialplan_xml.c:558 Processing david <david>->102 in context context_2
  258. 2014-11-23 14:43:25.620514 [DEBUG] kazoo_fetch_agent.c:216 Sending dialplan XML request (fd871bae-7348-11e4-b94e-ede710af1ab8) to ecallmgr@beta001.revoltcloud.com <3.847.0>
  259. 2014-11-23 14:43:25.720509 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecallmgr@beta001.revoltcloud.com <3.847.0>
  260. 2014-11-23 14:43:25.740508 [DEBUG] kazoo_fetch_agent.c:277 Received dialplan XML (fd871bae-7348-11e4-b94e-ede710af1ab8) after 119ms: <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>
  261. Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com parsing [context_2->park] continue=false
  262. |--- Dialplan: Processing recursive conditions level:1 [park_recur_1] require-nested=TRUE
  263. |--- Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Regex (PASS) [park_recur_1] variable_ecallmgr_Bridge-ID() =~ /^$/ break=on-false
  264. |--- Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Action export(ecallmgr_Bridge-ID=${UUID})
  265. Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Absolute Condition [park]
  266. Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Action log(NOTICE log|${uuid}|ecallmgr@beta001.revoltcloud.com won call control)
  267. Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Action export(ecallmgr_Ecallmgr-Node=ecallmgr@beta001.revoltcloud.com)
  268. Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Action set(ringback=%(2000,4000,440,480))
  269. Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Action set(transfer_ringback=%(2000,4000,440,480))
  270. Dialplan: sofia/sipinterface_1/david@beta.revoltcloud.com Action park()
  271. 2014-11-23 14:43:25.740508 [DEBUG] switch_core_state_machine.c:214 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_ROUTING -> CS_EXECUTE
  272. 2014-11-23 14:43:25.740508 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  273. 2014-11-23 14:43:25.740508 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/david@beta.revoltcloud.com) State ROUTING going to sleep
  274. 2014-11-23 14:43:25.740508 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_EXECUTE
  275. 2014-11-23 14:43:25.740508 [DEBUG] switch_core_state_machine.c:533 (sofia/sipinterface_1/david@beta.revoltcloud.com) State EXECUTE
  276. 2014-11-23 14:43:25.740508 [DEBUG] mod_sofia.c:178 sofia/sipinterface_1/david@beta.revoltcloud.com SOFIA EXECUTE
  277. 2014-11-23 14:43:25.740508 [DEBUG] switch_core_state_machine.c:256 sofia/sipinterface_1/david@beta.revoltcloud.com Standard EXECUTE
  278. EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com export(ecallmgr_Bridge-ID=YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.)
  279. 2014-11-23 14:43:25.740508 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [ecallmgr_Bridge-ID]=[YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.]
  280. EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com log(NOTICE log|YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.|ecallmgr@beta001.revoltcloud.com won call control)
  281. 2014-11-23 14:43:25.740508 [NOTICE] mod_dptools.c:1667 log|YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.|ecallmgr@beta001.revoltcloud.com won call control
  282. EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com export(ecallmgr_Ecallmgr-Node=ecallmgr@beta001.revoltcloud.com)
  283. 2014-11-23 14:43:25.740508 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [ecallmgr_Ecallmgr-Node]=[ecallmgr@beta001.revoltcloud.com]
  284. EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com set(ringback=%(2000,4000,440,480))
  285. 2014-11-23 14:43:25.740508 [DEBUG] mod_dptools.c:1435 sofia/sipinterface_1/david@beta.revoltcloud.com SET [ringback]=[%(2000,4000,440,480)]
  286. EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com set(transfer_ringback=%(2000,4000,440,480))
  287. 2014-11-23 14:43:25.740508 [DEBUG] mod_dptools.c:1435 sofia/sipinterface_1/david@beta.revoltcloud.com SET [transfer_ringback]=[%(2000,4000,440,480)]
  288. EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com park()
  289. 2014-11-23 14:43:25.940526 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecallmgr@beta001.revoltcloud.com <3.6615.0>
  290. 2014-11-23 14:43:25.960506 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI. effective_caller_id_name=David Johnson;effective_caller_id_number=+17184730090;rtp_secure_media=true;ecallmgr_Fetch-ID=fd871bae-7348-11e4-b94e-ede710af1ab8;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)
  291. 2014-11-23 14:43:26.160509 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecallmgr@beta001.revoltcloud.com <3.6649.0>
  292. 2014-11-23 14:43:26.160509 [NOTICE] kazoo_node.c:302 log|YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.|building xferext extension: set continue_on_fail=true
  293. 2014-11-23 14:43:26.160509 [NOTICE] kazoo_node.c:302 log|YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.|building xferext extension: export sip_redirect_context=context_2
  294. 2014-11-23 14:43:26.160509 [NOTICE] kazoo_node.c:302 log|YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.|building xferext extension: export ecallmgr_Inception=${ecallmgr_Inception}
  295. 2014-11-23 14:43:26.160509 [NOTICE] kazoo_node.c:302 log|YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.|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:59310;fs_path=sip:198.27.82.183:5060;lr;received='sip:96.250.214.196:59310'
  296. 2014-11-23 14:43:26.160509 [NOTICE] kazoo_node.c:302 log|YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.|building xferext extension: event Event-Name=CUSTOM,Event-Subclass=whistle::masquerade,whistle_event_name=CHANNEL_EXECUTE_COMPLETE,whistle_application_name=bridge
  297. 2014-11-23 14:43:26.160509 [NOTICE] kazoo_node.c:302 log|YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.|building xferext extension: park
  298. 2014-11-23 14:43:26.160509 [NOTICE] kazoo_node.c:305 log|YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.|transfered call to xferext extension
  299. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_session.c:1187 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  300. 2014-11-23 14:43:26.160509 [DEBUG] switch_channel.c:3098 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_EXECUTE -> CS_ROUTING
  301. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  302. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_state_machine.c:533 (sofia/sipinterface_1/david@beta.revoltcloud.com) State EXECUTE going to sleep
  303. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_ROUTING
  304. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/david@beta.revoltcloud.com) State ROUTING
  305. 2014-11-23 14:43:26.160509 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/david@beta.revoltcloud.com SOFIA ROUTING
  306. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_state_machine.c:164 sofia/sipinterface_1/david@beta.revoltcloud.com Standard ROUTING
  307. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_state_machine.c:184 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_ROUTING -> CS_EXECUTE
  308. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  309. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/david@beta.revoltcloud.com) State ROUTING going to sleep
  310. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_EXECUTE
  311. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_state_machine.c:533 (sofia/sipinterface_1/david@beta.revoltcloud.com) State EXECUTE
  312. 2014-11-23 14:43:26.160509 [DEBUG] mod_sofia.c:178 sofia/sipinterface_1/david@beta.revoltcloud.com SOFIA EXECUTE
  313. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_state_machine.c:256 sofia/sipinterface_1/david@beta.revoltcloud.com Standard EXECUTE
  314. EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com set(continue_on_fail=true)
  315. 2014-11-23 14:43:26.160509 [DEBUG] mod_dptools.c:1435 sofia/sipinterface_1/david@beta.revoltcloud.com SET [continue_on_fail]=[true]
  316. EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com export(sip_redirect_context=context_2)
  317. 2014-11-23 14:43:26.160509 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [sip_redirect_context]=[context_2]
  318. EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com export(ecallmgr_Inception=)
  319. 2014-11-23 14:43:26.160509 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [ecallmgr_Inception]=[UNDEF]
  320. 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:59310;fs_path=sip:198.27.82.183:5060;lr;received='sip:96.250.214.196:59310')
  321. 2014-11-23 14:43:26.160509 [DEBUG] kazoo_event_stream.c:242 increased event stream buffer size to 4907
  322. 2014-11-23 14:43:26.160509 [DEBUG] switch_channel.c:1200 sofia/sipinterface_1/david@beta.revoltcloud.com EXPORTING[export_vars] [ecallmgr_Bridge-ID]=[YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.] to event
  323. 2014-11-23 14:43:26.160509 [DEBUG] switch_channel.c:1200 sofia/sipinterface_1/david@beta.revoltcloud.com EXPORTING[export_vars] [ecallmgr_Ecallmgr-Node]=[ecallmgr@beta001.revoltcloud.com] to event
  324. 2014-11-23 14:43:26.160509 [DEBUG] switch_channel.c:1200 sofia/sipinterface_1/david@beta.revoltcloud.com EXPORTING[export_vars] [sip_redirect_context]=[context_2] to event
  325. 2014-11-23 14:43:26.160509 [DEBUG] switch_ivr_originate.c:2079 Parsing global variables
  326. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [call_timeout]=[20]
  327. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [originate_timeout]=[20]
  328. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [local_var_clobber]=[true]
  329. 2014-11-23 14:43:26.160509 [DEBUG] switch_ivr_originate.c:2526 Parsing session specific variables
  330. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [ecallmgr_Authorizing-ID]=[4475b86dc6c38cd77f30887d5abebf94]
  331. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [ecallmgr_Owner-ID]=[237117b58972853140384510b954bcc4]
  332. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [ecallmgr_Account-ID]=[d2ad30852ffb879da1a65dd55f49c6c7]
  333. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [sdp_secure_savp_only]=[true]
  334. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [rtp_secure_media]=[true]
  335. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [sip_invite_domain]=[beta.revoltcloud.com]
  336. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [presence_id]=[nina@beta.revoltcloud.com]
  337. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [absolute_codec_string]=[^^:OPUS:speex@32000h:speex@16000h:PCMU:PCMA:VP8:H264:H263:H261]
  338. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [leg_timeout]=[20]
  339. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [effective_callee_id_number]=[+19176278994]
  340. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [effective_callee_id_name]=[Nina Giordano]
  341. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [origination_callee_id_number]=[+19176278994]
  342. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [origination_callee_id_name]=[Nina Giordano]
  343. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [ecallmgr_Realm]=[beta.revoltcloud.com]
  344. 2014-11-23 14:43:26.160509 [DEBUG] switch_event.c:1688 Parsing variable [ecallmgr_Username]=[nina]
  345. 2014-11-23 14:43:26.160509 [NOTICE] switch_channel.c:1054 New Channel sofia/sipinterface_1/nina@96.250.214.196:59310 [fddb346e-7348-11e4-b95c-ede710af1ab8]
  346. 2014-11-23 14:43:26.160509 [DEBUG] mod_sofia.c:4591 (sofia/sipinterface_1/nina@96.250.214.196:59310) State Change CS_NEW -> CS_INIT
  347. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/nina@96.250.214.196:59310 [BREAK]
  348. 2014-11-23 14:43:26.160509 [DEBUG] switch_ivr_originate.c:2820 sofia/sipinterface_1/nina@96.250.214.196:59310 Setting leg timeout to 20
  349. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/nina@96.250.214.196:59310) Running State Change CS_INIT
  350. 2014-11-23 14:43:26.160509 [DEBUG] switch_core_state_machine.c:510 (sofia/sipinterface_1/nina@96.250.214.196:59310) State INIT
  351. 2014-11-23 14:43:26.160509 [DEBUG] mod_sofia.c:87 sofia/sipinterface_1/nina@96.250.214.196:59310 SOFIA INIT
  352. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [1 AEAD_AES_256_GCM_8 inline:XQjCxC9CV+3UBCEeD4KmoT/+lQn4Q2O2Nny+WQPZfrJboqIJT3zO81svoW4]
  353. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [1 AEAD_AES_256_GCM_8 inline:OHibs9ZPv0c6HO6P1W7b41tt4oTXxDiSZIKuyVISFGdXAV271IjVAko4Vt4]
  354. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [2 AEAD_AES_128_GCM_8 inline:oqBwssvomfIn4o81C3MdoA+p1eVAdJpiDMztzQ]
  355. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [2 AEAD_AES_128_GCM_8 inline:7QuUgutNY2eu9ljypYmIaxRLuW0/KbU0g//OiQ]
  356. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:5SeNizXCnVPwd+/LrYbsLYp3mfD5Z0KxOzqEqHSG6cv5VCbUJt0yhg0jRNui+A]
  357. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:cxSH0ga8d7hjP28vNg6mFOx0Idt/qzETt4LnqtJJ9e1oUDclN3tVDDj2Rl8ROQ]
  358. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:43M+qMC8q9SQW3br8nNtKmmUukLIv4FX84d5AdA3AZnDMZb5B2c]
  359. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:ytk2omdqQD2Y9kseIiyipby+Ph160zI+EujZhUK84UKNwTLXcGQ]
  360. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:SHIblOcyfCDbwNhgXUWkdXYA1EXwvnHqZdLMeVgk]
  361. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:wsT6nqruaVRfUG5WrdD59JaRMD+1KwEqFW0ajJ1R]
  362. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:j5jimTcbz/8u5uDGJ7iVVPJYiomce6O8vQMI+4soqAFcMZ5rycAEf7QeBQwU8Q]
  363. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:Ip+rMLdNIUWJZm7T689WbcOJozHtMIWGPUIFTuxYXKNHfFD+cuMOS82SiJTN2A]
  364. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:HEte6vOMjocmZUmp32EjtIg3bAvA6J+FdGCb2pC5dUMQqQeV1Wo]
  365. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:tvo+pz01W1dcV8gdLtJjzK04nCTWwv6uSX8dJzrgCG8ioRoRUto]
  366. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline:NUmOh8nJuQH5usx9SWQZQc4XQMVgNqxggRT3fshs]
  367. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline:6X9bxz0r4Ag/4L7D5TlJsa8aBAoCinGTrbbIO80l]
  368. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local audio crypto Key [9 AES_CM_128_NULL_AUTH inline:Dk8ePN4gbNbCdmJA3NawGXQmVCcJqEnxG5tVYc7H]
  369. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_media.c:922 Set Local video crypto Key [9 AES_CM_128_NULL_AUTH inline:v6omcDDTr/u7J+qecgGRJvo4aHlRLASnOdydkX5E]
  370. 2014-11-23 14:43:26.180502 [DEBUG] sofia_glue.c:1197 sip:198.27.82.183:5060 Setting proxy route to sofia/sipinterface_1/nina@96.250.214.196:59310
  371. 2014-11-23 14:43:26.180502 [DEBUG] sofia_glue.c:1226 sofia/sipinterface_1/nina@96.250.214.196:59310 sending invite version: 1.4.7  64bit
  372. Local SDP:
  373. v=0
  374. o=FreeSWITCH 1416742114 1416742115 IN IP4 198.27.82.197
  375. s=FreeSWITCH
  376. c=IN IP4 198.27.82.197
  377. t=0 0
  378. m=audio 29692 RTP/SAVP 98 110 0 8 101 13
  379. a=rtpmap:98 opus/48000/2
  380. a=fmtp:98 useinbandfec=1; usedtx=1; maxaveragebitrate=30000; ptime=20; minptime=20; maxptime=20; samplerate=48000
  381. a=rtpmap:110 SPEEX/32000
  382. a=rtpmap:0 PCMU/8000
  383. a=rtpmap:8 PCMA/8000
  384. a=rtpmap:101 telephone-event/8000
  385. a=fmtp:101 0-16
  386. a=crypto:1 AEAD_AES_256_GCM_8 inline:XQjCxC9CV+3UBCEeD4KmoT/+lQn4Q2O2Nny+WQPZfrJboqIJT3zO81svoW4
  387. a=crypto:2 AEAD_AES_128_GCM_8 inline:oqBwssvomfIn4o81C3MdoA+p1eVAdJpiDMztzQ
  388. a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:5SeNizXCnVPwd+/LrYbsLYp3mfD5Z0KxOzqEqHSG6cv5VCbUJt0yhg0jRNui+A
  389. a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:43M+qMC8q9SQW3br8nNtKmmUukLIv4FX84d5AdA3AZnDMZb5B2c
  390. a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:SHIblOcyfCDbwNhgXUWkdXYA1EXwvnHqZdLMeVgk
  391. a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:j5jimTcbz/8u5uDGJ7iVVPJYiomce6O8vQMI+4soqAFcMZ5rycAEf7QeBQwU8Q
  392. a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:HEte6vOMjocmZUmp32EjtIg3bAvA6J+FdGCb2pC5dUMQqQeV1Wo
  393. a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:NUmOh8nJuQH5usx9SWQZQc4XQMVgNqxggRT3fshs
  394. a=crypto:9 AES_CM_128_NULL_AUTH inline:Dk8ePN4gbNbCdmJA3NawGXQmVCcJqEnxG5tVYc7H
  395. a=ptime:20
  396. a=sendrecv
  397. m=video 28814 RTP/SAVP 99 34 31
  398. a=rtpmap:99 H264/90000
  399. a=rtpmap:34 H263/90000
  400. a=rtpmap:31 H261/90000
  401. a=crypto:1 AEAD_AES_256_GCM_8 inline:OHibs9ZPv0c6HO6P1W7b41tt4oTXxDiSZIKuyVISFGdXAV271IjVAko4Vt4
  402. a=crypto:2 AEAD_AES_128_GCM_8 inline:7QuUgutNY2eu9ljypYmIaxRLuW0/KbU0g//OiQ
  403. a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:cxSH0ga8d7hjP28vNg6mFOx0Idt/qzETt4LnqtJJ9e1oUDclN3tVDDj2Rl8ROQ
  404. a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:ytk2omdqQD2Y9kseIiyipby+Ph160zI+EujZhUK84UKNwTLXcGQ
  405. a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:wsT6nqruaVRfUG5WrdD59JaRMD+1KwEqFW0ajJ1R
  406. a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:Ip+rMLdNIUWJZm7T689WbcOJozHtMIWGPUIFTuxYXKNHfFD+cuMOS82SiJTN2A
  407. a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:tvo+pz01W1dcV8gdLtJjzK04nCTWwv6uSX8dJzrgCG8ioRoRUto
  408. a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:6X9bxz0r4Ag/4L7D5TlJsa8aBAoCinGTrbbIO80l
  409. a=crypto:9 AES_CM_128_NULL_AUTH inline:v6omcDDTr/u7J+qecgGRJvo4aHlRLASnOdydkX5E
  410.  
  411. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_state_machine.c:40 sofia/sipinterface_1/nina@96.250.214.196:59310 Standard INIT
  412. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_state_machine.c:48 (sofia/sipinterface_1/nina@96.250.214.196:59310) State Change CS_INIT -> CS_ROUTING
  413. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/nina@96.250.214.196:59310 [BREAK]
  414. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_state_machine.c:510 (sofia/sipinterface_1/nina@96.250.214.196:59310) State INIT going to sleep
  415. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/nina@96.250.214.196:59310) Running State Change CS_ROUTING
  416. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/nina@96.250.214.196:59310) State ROUTING
  417. 2014-11-23 14:43:26.180502 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/nina@96.250.214.196:59310 SOFIA ROUTING
  418. 2014-11-23 14:43:26.180502 [DEBUG] switch_ivr_originate.c:67 (sofia/sipinterface_1/nina@96.250.214.196:59310) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  419. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/nina@96.250.214.196:59310 [BREAK]
  420. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/nina@96.250.214.196:59310) State ROUTING going to sleep
  421. send 3175 bytes to udp/[198.27.82.183]:5060 at 14:43:26.181368:
  422.    ------------------------------------------------------------------------
  423.    INVITE sip:nina@96.250.214.196:59310 SIP/2.0
  424.    Via: SIP/2.0/UDP 198.27.82.197:11000;rport;branch=z9hG4bK584DH87NvUFDr
  425.    Route: <sip:198.27.82.183:5060>
  426.    Max-Forwards: 49
  427.    From: "David Johnson" <sip:+17184730090@beta.revoltcloud.com>;tag=Kt51SSp7Z6tme
  428.    To: <sip:nina@96.250.214.196:59310>
  429.    Call-ID: fddb346e-7348-11e4-b95c-ede710af1ab8
  430.    CSeq: 68049839 INVITE
  431.    Contact: <sip:mod_sofia@198.27.82.197:11000>
  432.    User-Agent: 2600hz
  433.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  434.    Supported: path, replaces
  435.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  436.    Content-Type: application/sdp
  437.    Content-Disposition: session
  438.    Content-Length: 2181
  439.    X-AUTH-IP: 96.250.214.196
  440.    X-FS-Support: update_display,send_info
  441.    Remote-Party-ID: "David Johnson" <sip:+17184730090@beta.revoltcloud.com>;party=calling;screen=yes;privacy=off
  442.  
  443.    v=0
  444.    o=FreeSWITCH 1416742114 1416742115 IN IP4 198.27.82.197
  445.    s=FreeSWITCH
  446.    c=IN IP4 198.27.82.197
  447.    t=0 0
  448.    m=audio 29692 RTP/SAVP 98 110 0 8 101 13
  449. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/nina@96.250.214.196:59310) Running State Change CS_CONSUME_MEDIA
  450.    a=rtpmap:98 opus/48000/2
  451.    a=fmtp:98 useinbandfec=1; usedtx=1; maxaveragebitrate=30000; ptime=20; minptime=20; maxptime=20; samplerate=48000
  452.    a=rtpmap:110 SPEEX/32000
  453.    a=rtpmap:0 PCMU/8000
  454.    a=rtpmap:8 PCMA/8000
  455.    a=rtpmap:101 telephone-event/8000
  456.    a=fmtp:101 0-16
  457.    a=crypto:1 AEAD_AES_256_GCM_8 inline:XQjCxC9CV+3UBCEeD4KmoT/+lQn4Q2O2Nny+WQPZfrJboqIJT3zO81svoW4
  458.    a=crypto:2 AEAD_AES_128_GCM_8 inline:oqBwssvomfIn4o81C3MdoA+p1eVAdJpiDMztzQ
  459.    a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:5SeNizXCnVPwd+/LrYbsLYp3mfD5Z0KxOzqEqHSG6cv5VCbUJt0yhg0jRNui+A
  460.    a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:43M+qMC8q9SQW3br8nNtKmmUukLIv4FX84d5AdA3AZnDMZb5B2c
  461.    a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:SHIblOcyfCDbwNhgXUWkdXYA1EXwvnHqZdLMeVgk
  462.    a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:j5jimTcbz/8u5uDGJ7iVVPJYiomce6O8vQMI+4soqAFcMZ5rycAEf7QeBQwU8Q
  463.    a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:HEte6vOMjocmZUmp32EjtIg3bAvA6J+FdGCb2pC5dUMQqQeV1Wo
  464.    a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:NUmOh8nJuQH5usx9SWQZQc4XQMVgNqxggRT3fshs
  465.    a=crypto:9 AES_CM_128_NULL_AUTH inline:Dk8ePN4gbNbCdmJA3NawGXQmVCcJqEnxG5tVYc7H
  466.    a=ptime:20
  467.    m=video 28814 RTP/SAVP 99 34 31
  468.    a=rtpmap:99 H264/90000
  469.    a=rtpmap:34 H263/90000
  470.    a=rtpmap:31 H261/90000
  471.    a=crypto:1 AEAD_AES_256_GCM_8 inline:OHibs9ZPv0c6HO6P1W7b41tt4oTXxDiSZIKuyVISFGdXAV271IjVAko4Vt4
  472.    a=crypto:2 AEAD_AES_128_GCM_8 inline:7QuUgutNY2eu9ljypYmIaxRLuW0/KbU0g//OiQ
  473.    a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:cxSH0ga8d7hjP28vNg6mFOx0Idt/qzETt4LnqtJJ9e1oUDclN3tVDDj2Rl8ROQ
  474.    a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:ytk2omdqQD2Y9kseIiyipby+Ph160zI+EujZhUK84UKNwTLXcGQ
  475.    a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:wsT6nqruaVRfUG5WrdD59JaRMD+1KwEqFW0ajJ1R
  476.    a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:Ip+rMLdNIUWJZm7T689WbcOJozHtMIWGPUIFTuxYXKNHfFD+cuMOS82SiJTN2A
  477.    a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:tvo+pz01W1dcV8gdLtJjzK04nCTWwv6uSX8dJzrgCG8ioRoRUto
  478.    a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:6X9bxz0r4Ag/4L7D5TlJsa8aBAoCinGTrbbIO80l
  479.    a=crypto:9 AES_CM_128_NULL_AUTH inline:v6omcDDTr/u7J+qecgGRJvo4aHlRLASnOdydkX5E
  480.    ------------------------------------------------------------------------
  481. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_state_machine.c:545 (sofia/sipinterface_1/nina@96.250.214.196:59310) State CONSUME_MEDIA
  482. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_state_machine.c:545 (sofia/sipinterface_1/nina@96.250.214.196:59310) State CONSUME_MEDIA going to sleep
  483. 2014-11-23 14:43:26.180502 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/nina@96.250.214.196:59310 [BREAK]
  484. 2014-11-23 14:43:26.180502 [DEBUG] sofia.c:6413 Channel sofia/sipinterface_1/nina@96.250.214.196:59310 entering state [calling][0]
  485. recv 332 bytes from udp/[198.27.82.183]:5060 at 14:43:26.182956:
  486.    ------------------------------------------------------------------------
  487.    SIP/2.0 100 Attempting to connect your call
  488.    Via: SIP/2.0/UDP 198.27.82.197:11000;rport=11000;branch=z9hG4bK584DH87NvUFDr
  489.    From: "David Johnson" <sip:+17184730090@beta.revoltcloud.com>;tag=Kt51SSp7Z6tme
  490.    To: <sip:nina@96.250.214.196:59310>
  491.    Call-ID: fddb346e-7348-11e4-b95c-ede710af1ab8
  492.    CSeq: 68049839 INVITE
  493.    Content-Length: 0
  494.  
  495.    ------------------------------------------------------------------------
  496. 2014-11-23 14:43:26.180502 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(fddb346e-7348-11e4-b95c-ede710af1ab8 ecallmgr_Channel-Authorized=true;ecallmgr_Account-ID=d2ad30852ffb879da1a65dd55f49c6c7)
  497. 2014-11-23 14:43:26.180502 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecallmgr@beta001.revoltcloud.com <3.6649.0>
  498. 2014-11-23 14:43:26.380529 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecallmgr@beta001.revoltcloud.com <3.6691.0>
  499. recv 362 bytes from udp/[198.27.82.183]:5060 at 14:43:26.418579:
  500.    ------------------------------------------------------------------------
  501.    SIP/2.0 415 Unsupported Media Type
  502.    Via: SIP/2.0/UDP 198.27.82.197:11000;rport=11000;branch=z9hG4bK584DH87NvUFDr
  503.    To: <sip:nina@96.250.214.196:59310>;tag=78e5fd76
  504.    From: "David Johnson"<sip:+17184730090@beta.revoltcloud.com>;tag=Kt51SSp7Z6tme
  505.    Call-ID: fddb346e-7348-11e4-b95c-ede710af1ab8
  506.    CSeq: 68049839 INVITE
  507.    User-Agent: Zoiper r27147
  508.    Content-Length: 0
  509.  
  510.    ------------------------------------------------------------------------
  511. send 385 bytes to udp/[198.27.82.183]:5060 at 14:43:26.418729:
  512.    ------------------------------------------------------------------------
  513.    ACK sip:nina@96.250.214.196:59310 SIP/2.0
  514.    Via: SIP/2.0/UDP 198.27.82.197:11000;rport;branch=z9hG4bK584DH87NvUFDr
  515.    Route: <sip:198.27.82.183:5060>
  516.    Max-Forwards: 49
  517.    From: "David Johnson" <sip:+17184730090@beta.revoltcloud.com>;tag=Kt51SSp7Z6tme
  518.    To: <sip:nina@96.250.214.196:59310>;tag=78e5fd76
  519.    Call-ID: fddb346e-7348-11e4-b95c-ede710af1ab8
  520.    CSeq: 68049839 ACK
  521.    Content-Length: 0
  522.  
  523.    ------------------------------------------------------------------------
  524. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/nina@96.250.214.196:59310 [BREAK]
  525. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/nina@96.250.214.196:59310 [BREAK]
  526. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/nina@96.250.214.196:59310 [BREAK]
  527. 2014-11-23 14:43:26.400522 [DEBUG] sofia.c:6413 Channel sofia/sipinterface_1/nina@96.250.214.196:59310 entering state [terminated][415]
  528. 2014-11-23 14:43:26.400522 [NOTICE] sofia.c:7299 Hangup sofia/sipinterface_1/nina@96.250.214.196:59310 [CS_CONSUME_MEDIA] [SERVICE_NOT_IMPLEMENTED]
  529. 2014-11-23 14:43:26.400522 [DEBUG] switch_channel.c:3217 Send signal sofia/sipinterface_1/nina@96.250.214.196:59310 [KILL]
  530. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/nina@96.250.214.196:59310 [BREAK]
  531. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/nina@96.250.214.196:59310) Running State Change CS_HANGUP
  532. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_state_machine.c:733 (sofia/sipinterface_1/nina@96.250.214.196:59310) Callstate Change DOWN -> HANGUP
  533. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/nina@96.250.214.196:59310) State HANGUP
  534. 2014-11-23 14:43:26.400522 [DEBUG] mod_sofia.c:413 Channel sofia/sipinterface_1/nina@96.250.214.196:59310 hanging up, cause: SERVICE_NOT_IMPLEMENTED
  535. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_state_machine.c:58 sofia/sipinterface_1/nina@96.250.214.196:59310 Standard HANGUP, cause: SERVICE_NOT_IMPLEMENTED
  536. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/nina@96.250.214.196:59310) State HANGUP going to sleep
  537. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_state_machine.c:502 (sofia/sipinterface_1/nina@96.250.214.196:59310) State Change CS_HANGUP -> CS_REPORTING
  538. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/nina@96.250.214.196:59310 [BREAK]
  539. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/nina@96.250.214.196:59310) Running State Change CS_REPORTING
  540. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_state_machine.c:821 (sofia/sipinterface_1/nina@96.250.214.196:59310) State REPORTING
  541. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_state_machine.c:102 sofia/sipinterface_1/nina@96.250.214.196:59310 Standard REPORTING, cause: SERVICE_NOT_IMPLEMENTED
  542. 2014-11-23 14:43:26.400522 [DEBUG] switch_core_state_machine.c:821 (sofia/sipinterface_1/nina@96.250.214.196:59310) State REPORTING going to sleep
  543. 2014-11-23 14:43:26.420525 [DEBUG] switch_core_state_machine.c:496 (sofia/sipinterface_1/nina@96.250.214.196:59310) State Change CS_REPORTING -> CS_DESTROY
  544. 2014-11-23 14:43:26.420525 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/nina@96.250.214.196:59310 [BREAK]
  545. 2014-11-23 14:43:26.420525 [DEBUG] switch_core_session.c:1614 Session 33 (sofia/sipinterface_1/nina@96.250.214.196:59310) Locked, Waiting on external entities
  546. 2014-11-23 14:43:26.420525 [DEBUG] switch_ivr_originate.c:3695 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED]
  547. 2014-11-23 14:43:26.420525 [NOTICE] switch_core_session.c:1632 Session 33 (sofia/sipinterface_1/nina@96.250.214.196:59310) Ended
  548. 2014-11-23 14:43:26.420525 [NOTICE] switch_core_session.c:1636 Close Channel sofia/sipinterface_1/nina@96.250.214.196:59310 [CS_DESTROY]
  549. 2014-11-23 14:43:26.420525 [DEBUG] switch_core_state_machine.c:624 (sofia/sipinterface_1/nina@96.250.214.196:59310) Running State Change CS_DESTROY
  550. 2014-11-23 14:43:26.420525 [DEBUG] switch_core_state_machine.c:634 (sofia/sipinterface_1/nina@96.250.214.196:59310) State DESTROY
  551. 2014-11-23 14:43:26.420525 [DEBUG] mod_sofia.c:323 sofia/sipinterface_1/nina@96.250.214.196:59310 SOFIA DESTROY
  552. 2014-11-23 14:43:26.420525 [DEBUG] switch_core_state_machine.c:109 sofia/sipinterface_1/nina@96.250.214.196:59310 Standard DESTROY
  553. 2014-11-23 14:43:26.420525 [DEBUG] switch_core_state_machine.c:634 (sofia/sipinterface_1/nina@96.250.214.196:59310) State DESTROY going to sleep
  554. 2014-11-23 14:43:26.420525 [INFO] mod_dptools.c:3277 Originate Failed.  Cause: SERVICE_NOT_IMPLEMENTED
  555. 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)
  556. 2014-11-23 14:43:26.420525 [DEBUG] kazoo_event_stream.c:242 increased event stream buffer size to 5048
  557. EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com park()
  558. 2014-11-23 14:43:26.420525 [NOTICE] kazoo_node.c:285 log|YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.|executing answer
  559. 2014-11-23 14:43:26.420525 [DEBUG] switch_core_session.c:1187 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  560. 2014-11-23 14:43:26.440510 [DEBUG] switch_ivr.c:614 sofia/sipinterface_1/david@beta.revoltcloud.com Command Execute answer()
  561. EXECUTE sofia/sipinterface_1/david@beta.revoltcloud.com answer()
  562. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[opus:116:48000:20:0:1]
  563. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[G7221:115:32000:20:48000:1]
  564. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[G7221:107:16000:20:32000:1]
  565. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[G722:9:8000:20:64000:1]
  566. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  567. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  568. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[GSM:3:8000:20:13200:1]
  569. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [speex:110:8000:20:0:1]/[SPEEX:99:8000:20:24600:1]
  570. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3528 Audio Codec Compare [SPEEX:99:8000:20:24600:1] ++++ is saved as a match
  571. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1]
  572. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G7221:115:32000:20:48000:1]
  573. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G7221:107:16000:20:32000:1]
  574. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
  575. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  576. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  577. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[GSM:3:8000:20:13200:1]
  578. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3528 Audio Codec Compare [GSM:3:8000:20:13200:1] ++++ is saved as a match
  579. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:20:13200:1]/[SPEEX:99:8000:20:24600:1]
  580. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  581. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G7221:115:32000:20:48000:1]
  582. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G7221:107:16000:20:32000:1]
  583. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  584. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  585. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3528 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  586. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  587. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  588. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[SPEEX:99:8000:20:24600:1]
  589. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  590. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G7221:115:32000:20:48000:1]
  591. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G7221:107:16000:20:32000:1]
  592. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  593. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  594. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  595. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3528 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  596. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  597. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[SPEEX:99:8000:20:24600:1]
  598. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[opus:116:48000:20:0:1]
  599. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G7221:115:32000:20:48000:1]
  600. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G7221:107:16000:20:32000:1]
  601. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G722:9:8000:20:64000:1]
  602. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  603. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  604. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[GSM:3:8000:20:13200:1]
  605. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:97:8000:30:0:1]/[SPEEX:99:8000:20:24600:1]
  606. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3395 Set telephone-event payload to 101
  607. 2014-11-23 14:43:26.440510 [WARNING] switch_core_media.c:3546 Crypto not negotiated but required.
  608. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_media.c:3719 Set 2833 dtmf send/recv payload to 101
  609. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_session.c:907 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  610. 2014-11-23 14:43:26.440510 [NOTICE] switch_channel.c:3722 Hangup sofia/sipinterface_1/david@beta.revoltcloud.com [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]
  611. 2014-11-23 14:43:26.440510 [DEBUG] switch_channel.c:3217 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [KILL]
  612. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  613. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_session.c:2887 sofia/sipinterface_1/david@beta.revoltcloud.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  614. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_session.c:2887 sofia/sipinterface_1/david@beta.revoltcloud.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  615. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:533 (sofia/sipinterface_1/david@beta.revoltcloud.com) State EXECUTE going to sleep
  616. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_HANGUP
  617. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:733 (sofia/sipinterface_1/david@beta.revoltcloud.com) Callstate Change RINGING -> HANGUP
  618. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/david@beta.revoltcloud.com) State HANGUP
  619. 2014-11-23 14:43:26.440510 [DEBUG] mod_sofia.c:407 sofia/sipinterface_1/david@beta.revoltcloud.com Overriding SIP cause 488 with 415 from the other leg
  620. 2014-11-23 14:43:26.440510 [DEBUG] mod_sofia.c:413 Channel sofia/sipinterface_1/david@beta.revoltcloud.com hanging up, cause: INCOMPATIBLE_DESTINATION
  621. 2014-11-23 14:43:26.440510 [DEBUG] mod_sofia.c:553 Responding to INVITE with: 415
  622. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:58 sofia/sipinterface_1/david@beta.revoltcloud.com Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
  623. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/david@beta.revoltcloud.com) State HANGUP going to sleep
  624. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:502 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_HANGUP -> CS_REPORTING
  625. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  626. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_REPORTING
  627. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:821 (sofia/sipinterface_1/david@beta.revoltcloud.com) State REPORTING
  628. send 945 bytes to udp/[198.27.82.183]:5060 at 14:43:26.446734:
  629.    ------------------------------------------------------------------------
  630.    SIP/2.0 415 Unsupported Media Type
  631. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:102 sofia/sipinterface_1/david@beta.revoltcloud.com Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
  632.    Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK4079.17067d71.0
  633. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:821 (sofia/sipinterface_1/david@beta.revoltcloud.com) State REPORTING going to sleep
  634.    Via: SIP/2.0/UDP 96.250.214.196:58144;branch=z9hG4bK-d8754z-1327261b2f744843-1---d8754z-;rport=58144
  635.    Max-Forwards: 50
  636.    From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=d4b23858
  637.    To: <sip:102@beta.revoltcloud.com;transport=UDP>;tag=jHc9Qy532X41j
  638.    Call-ID: YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.
  639.    CSeq: 2 INVITE
  640.    User-Agent: 2600hz
  641.    Accept: application/sdp
  642.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  643.    Supported: path, replaces
  644.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  645.    Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION"
  646.    Content-Length: 0
  647.    Remote-Party-ID: "102" <sip:102@beta.revoltcloud.com>;party=calling;privacy=off;screen=no
  648.  
  649.    ------------------------------------------------------------------------
  650. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:496 (sofia/sipinterface_1/david@beta.revoltcloud.com) State Change CS_REPORTING -> CS_DESTROY
  651. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/david@beta.revoltcloud.com [BREAK]
  652. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_session.c:1614 Session 32 (sofia/sipinterface_1/david@beta.revoltcloud.com) Locked, Waiting on external entities
  653. 2014-11-23 14:43:26.440510 [NOTICE] switch_core_session.c:1632 Session 32 (sofia/sipinterface_1/david@beta.revoltcloud.com) Ended
  654. 2014-11-23 14:43:26.440510 [NOTICE] switch_core_session.c:1636 Close Channel sofia/sipinterface_1/david@beta.revoltcloud.com [CS_DESTROY]
  655. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:624 (sofia/sipinterface_1/david@beta.revoltcloud.com) Running State Change CS_DESTROY
  656. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:634 (sofia/sipinterface_1/david@beta.revoltcloud.com) State DESTROY
  657. 2014-11-23 14:43:26.440510 [DEBUG] mod_sofia.c:323 sofia/sipinterface_1/david@beta.revoltcloud.com SOFIA DESTROY
  658. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:109 sofia/sipinterface_1/david@beta.revoltcloud.com Standard DESTROY
  659. 2014-11-23 14:43:26.440510 [DEBUG] switch_core_state_machine.c:634 (sofia/sipinterface_1/david@beta.revoltcloud.com) State DESTROY going to sleep
  660. recv 360 bytes from udp/[198.27.82.183]:5060 at 14:43:26.447755:
  661.    ------------------------------------------------------------------------
  662.    ACK sip:102@beta.revoltcloud.com;transport=UDP SIP/2.0
  663.    Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK4079.17067d71.0
  664.    Max-Forwards: 50
  665.    To: <sip:102@beta.revoltcloud.com;transport=UDP>;tag=jHc9Qy532X41j
  666.    From: <sip:david@beta.revoltcloud.com;transport=UDP>;tag=d4b23858
  667.    Call-ID: YjcyNTJlMTBiMzI2YmYyN2Y2NjRhMTcyYjAwNDBkZGI.
  668.    CSeq: 2 ACK
  669.    Content-Length: 0
  670.  
  671.    ------------------------------------------------------------------------
  672. 2014-11-23 14:43:26.620512 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecallmgr@beta001.revoltcloud.com <3.6649.0>
  673. recv 312 bytes from udp/[198.27.82.183]:5060 at 14:43:34.165906:
  674.    ------------------------------------------------------------------------
  675.    OPTIONS sip:198.27.82.197:11000 SIP/2.0
  676.    Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK930a.53284f12.0
  677.    To: sip:198.27.82.197:11000
  678.    From: sip:sipcheck@198.27.82.183;tag=ff021d86b7c45693467b7c7db964bf77-97d8
  679.    CSeq: 10 OPTIONS
  680.    Call-ID: 3d16663b4d432d5d-24193@198.27.82.183
  681.    Max-Forwards: 70
  682.    Content-Length: 0
  683.  
  684.    ------------------------------------------------------------------------
  685. send 678 bytes to udp/[198.27.82.183]:5060 at 14:43:34.166222:
  686.    ------------------------------------------------------------------------
  687.    SIP/2.0 200 OK
  688.    Via: SIP/2.0/UDP 198.27.82.183;branch=z9hG4bK930a.53284f12.0
  689.    From: sip:sipcheck@198.27.82.183;tag=ff021d86b7c45693467b7c7db964bf77-97d8
  690.    To: <sip:198.27.82.197:11000>;tag=m3ytUm7aXFH7S
  691.    Call-ID: 3d16663b4d432d5d-24193@198.27.82.183
  692.    CSeq: 10 OPTIONS
  693.    Contact: <sip:198.27.82.197:11000>
  694.    User-Agent: 2600hz
  695.    Accept: application/sdp
  696.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  697.    Supported: path, replaces
  698.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  699.    Content-Length: 0
  700.  
  701.    ------------------------------------------------------------------------
Add Comment
Please, Sign In to add comment
Public Pastes
We use cookies for various purposes including analytics. By continuing to use Pastebin, you agree to our use of cookies as described in the Cookies Policy.  OK, I Understand
Not a member of Pastebin yet?
Sign Up, it unlocks many cool features!