From Vitaly, 2 Years ago, written in FreeSWITCH.
- view diff
Embed
  1.  
  2.    ------------------------------------------------------------------------
  3. recv 2217 bytes from ws/[134.213.206.157]:47652 at 18:12:01.780365:
  4.    ------------------------------------------------------------------------
  5.    INVITE sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com SIP/2.0
  6.    Via: SIP/2.0/WS amicqbtrlg6o.invalid;branch=z9hG4bK9539584
  7.    Max-Forwards: 70
  8.    To: <sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com>
  9.    From: <sip:280@3waytalk.com>;tag=g294kid7sg
  10.    Call-ID: dot4lkudmc6ubterpeth
  11.    CSeq: 3067 INVITE
  12.    Contact: <sip:1kfef9k8@amicqbtrlg6o.invalid;transport=ws;ob>
  13.   Allow: ACK,CANCEL,INVITE,MESSAGE,BYE,OPTIONS,INFO,NOTIFY,REFER
  14.    Content-Type: application/sdp
  15.    Supported: outbound
  16.    User-Agent: SIP.js/0.7.5
  17.    Content-Length: 1679
  18.    
  19.    v=0
  20.    o=- 4552988607047744733 2 IN IP4 127.0.0.1
  21.    s=-
  22.    t=0 0
  23.    a=group:BUNDLE audio
  24.    a=msid-semantic: WMS ARDAMS
  25.    m=audio 42527 UDP/TLS/RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
  26.    c=IN IP4 46.159.161.140
  27.    a=rtcp:38050 IN IP4 46.159.161.140
  28.    a=candidate:3350409123 1 udp 2122260223 192.168.0.101 42527 typ host generation 0
  29.    a=candidate:3350409123 2 udp 2122260222 192.168.0.101 38050 typ host generation 0
  30.    a=candidate:1190865175 2 udp 1686052606 46.159.161.140 38050 typ srflx raddr 192.168.0.101 rport 38050 generation 0
  31.    a=candidate:1190865175 1 udp 1686052607 46.159.161.140 42527 typ srflx raddr 192.168.0.101 rport 42527 generation 0
  32.    a=candidate:2301678419 1 tcp 1518280447 192.168.0.101 37171 typ host tcptype passive generation 0
  33.    a=candidate:2301678419 2 tcp 1518280446 192.168.0.101 34973 typ host tcptype passive generation 0
  34.    a=ice-ufrag:zlFE8VtX26klZtLa
  35.    a=ice-pwd:0LGWvsW5Zazi35Xk8BlzzkOY
  36.    a=fingerprint:sha-256 E5:C8:6E:BD:1C:03:D6:B4:4F:E2:8F:B1:84:DE:A1:6A:37:4C:D7:C6:F2:A5:6D:47:45:DD:CA:7A:30:6E:93:BE
  37.    a=setup:actpass
  38.    a=mid:audio
  39.    a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
  40.    a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
  41.    a=sendrecv
  42.    a=rtcp-mux
  43.    a=rtpmap:111 opus/48000/2
  44.    a=fmtp:111 minptime=10; useinbandfec=1
  45.    a=rtpmap:103 ISAC/16000
  46.    a=rtpmap:9 G722/8000
  47.    a=rtpmap:102 ILBC/8000
  48.    a=rtpmap:0 PCMU/8000
  49.    a=rtpmap:8 PCMA/8000
  50.    a=rtpmap:106 CN/32000
  51.    a=rtpmap:105 CN/16000
  52.    a=rtpmap:13 CN/8000
  53.    a=rtpmap:127 red/8000
  54.    a=rtpmap:126 telephone-event/8000
  55.    a=maxptime:60
  56.    a=ssrc:2537041755 cname:7CbYDw1WUcfBYXkJ
  57.    a=ssrc:2537041755 msid:ARDAMS ARDAMSa0
  58.    a=ssrc:2537041755 mslabel:ARDAMS
  59.    a=ssrc:2537041755 label:ARDAMSa0
  60.    ------------------------------------------------------------------------
  61. send 355 bytes to ws/[134.213.206.157]:47652 at 18:12:01.780822:
  62.    ------------------------------------------------------------------------
  63.    SIP/2.0 100 Trying
  64.    Via: SIP/2.0/WS amicqbtrlg6o.invalid;branch=z9hG4bK9539584;received=134.213.206.157;rport=47652
  65.    From: <sip:280@3waytalk.com>;tag=g294kid7sg
  66.    To: <sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com>
  67.    Call-ID: dot4lkudmc6ubterpeth
  68.    CSeq: 3067 INVITE
  69.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  70.    Content-Length: 0
  71.    
  72.    ------------------------------------------------------------------------
  73. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.772593 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/280@3waytalk.com [a39771e4-fbf1-4964-b499-e9843b7bfe59]
  74. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.772593 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/280@3waytalk.com) Running State Change CS_NEW
  75. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.772593 [DEBUG] sofia.c:9520 sofia/internal/280@3waytalk.com receiving invite from 134.213.206.157:47652 version: 1.7.0 -915-35ee4ee 64bit
  76. 2016-09-01 18:12:01.772593 [DEBUG] sofia.c:9687 IP 134.213.206.157 Rejected by acl "domains". Falling back to Digest auth.
  77. 2016-09-01 18:12:01.772593 [WARNING] sofia_reg.c:1787 SIP auth challenge (INVITE) on sofia profile 'internal' for [9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com] from ip 134.213.206.157
  78. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.772593 [DEBUG] switch_core_state_machine.c:562 (sofia/internal/280@3waytalk.com) State NEW
  79. send 712 bytes to ws/[134.213.206.157]:47652 at 18:12:01.781774:
  80.    ------------------------------------------------------------------------
  81.    SIP/2.0 407 Proxy Authentication Required
  82.    Via: SIP/2.0/WS amicqbtrlg6o.invalid;branch=z9hG4bK9539584;received=134.213.206.157;rport=47652
  83.    From: <sip:280@3waytalk.com>;tag=g294kid7sg
  84.    To: <sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com>;tag=8FFmSNUcy7NUN
  85.    Call-ID: dot4lkudmc6ubterpeth
  86.    CSeq: 3067 INVITE
  87.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  88.    Accept: application/sdp
  89.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  90.    Supported: timer, path, replaces
  91.    Allow-Events: talk, hold, conference, refer
  92.    Proxy-Authenticate: Digest realm="3waytalk.com", nonce="f77993ea-b1e3-4aa3-ad6e-5a2f30d0e121", algorithm=MD5, qop="auth"
  93.    Content-Length: 0
  94.    
  95.    ------------------------------------------------------------------------
  96. 2016-09-01 18:12:01.772593 [DEBUG] sofia.c:2248 detaching session a39771e4-fbf1-4964-b499-e9843b7bfe59
  97. recv 325 bytes from ws/[134.213.206.157]:47652 at 18:12:01.786830:
  98.    ------------------------------------------------------------------------
  99.    ACK sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com SIP/2.0
  100.    Via: SIP/2.0/WS amicqbtrlg6o.invalid;branch=z9hG4bK9539584
  101.    To: <sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com>;tag=8FFmSNUcy7NUN
  102.    From: <sip:280@3waytalk.com>;tag=g294kid7sg
  103.    Call-ID: dot4lkudmc6ubterpeth
  104.    Content-Length: 0
  105.    CSeq: 3067 ACK
  106.    
  107.    ------------------------------------------------------------------------
  108. recv 2499 bytes from ws/[134.213.206.157]:47652 at 18:12:01.788439:
  109.    ------------------------------------------------------------------------
  110.    INVITE sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com SIP/2.0
  111.    Via: SIP/2.0/WS amicqbtrlg6o.invalid;branch=z9hG4bK7963946
  112.    Max-Forwards: 70
  113.    To: <sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com>
  114.    From: <sip:280@3waytalk.com>;tag=g294kid7sg
  115.    Call-ID: dot4lkudmc6ubterpeth
  116.    CSeq: 3068 INVITE
  117.    Proxy-Authorization: Digest algorithm=MD5, username="280", realm="3waytalk.com", nonce="f77993ea-b1e3-4aa3-ad6e-5a2f30d0e121", uri="sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com", response="4a7b1162f5959bbae35884e7c56c16fd", qop=auth, cnonce="f7e6rci30534", nc=00000001
  118.    Contact: <sip:1kfef9k8@amicqbtrlg6o.invalid;transport=ws;ob>
  119.   Allow: ACK,CANCEL,INVITE,MESSAGE,BYE,OPTIONS,INFO,NOTIFY,REFER
  120.    Content-Type: application/sdp
  121.    Supported: outbound
  122.    User-Agent: SIP.js/0.7.5
  123.    Content-Length: 1679
  124.    
  125.    v=0
  126.    o=- 4552988607047744733 2 IN IP4 127.0.0.1
  127.    s=-
  128.    t=0 0
  129.    a=group:BUNDLE audio
  130.    a=msid-semantic: WMS ARDAMS
  131.    m=audio 42527 UDP/TLS/RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
  132.    c=IN IP4 46.159.161.140
  133.    a=rtcp:38050 IN IP4 46.159.161.140
  134.    a=candidate:3350409123 1 udp 2122260223 192.168.0.101 42527 typ host generation 0
  135.    a=candidate:3350409123 2 udp 2122260222 192.168.0.101 38050 typ host generation 0
  136.    a=candidate:1190865175 2 udp 1686052606 46.159.161.140 38050 typ srflx raddr 192.168.0.101 rport 38050 generation 0
  137.    a=candidate:1190865175 1 udp 1686052607 46.159.161.140 42527 typ srflx raddr 192.168.0.101 rport 42527 generation 0
  138.    a=candidate:2301678419 1 tcp 1518280447 192.168.0.101 37171 typ host tcptype passive generation 0
  139.    a=candidate:2301678419 2 tcp 1518280446 192.168.0.101 34973 typ host tcptype passive generation 0
  140.    a=ice-ufrag:zlFE8VtX26klZtLa
  141.    a=ice-pwd:0LGWvsW5Zazi35Xk8BlzzkOY
  142.    a=fingerprint:sha-256 E5:C8:6E:BD:1C:03:D6:B4:4F:E2:8F:B1:84:DE:A1:6A:37:4C:D7:C6:F2:A5:6D:47:45:DD:CA:7A:30:6E:93:BE
  143.    a=setup:actpass
  144.    a=mid:audio
  145.    a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
  146.    a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
  147.    a=sendrecv
  148.    a=rtcp-mux
  149.    a=rtpmap:111 opus/48000/2
  150.    a=fmtp:111 minptime=10; useinbandfec=1
  151.    a=rtpmap:103 ISAC/16000
  152.    a=rtpmap:9 G722/8000
  153.    a=rtpmap:102 ILBC/8000
  154.    a=rtpmap:0 PCMU/8000
  155.    a=rtpmap:8 PCMA/8000
  156.    a=rtpmap:106 CN/32000
  157.    a=rtpmap:105 CN/16000
  158.    a=rtpmap:13 CN/8000
  159.    a=rtpmap:127 red/8000
  160.    a=rtpmap:126 telephone-event/8000
  161.    a=maxptime:60
  162.    a=ssrc:2537041755 cname:7CbYDw1WUcfBYXkJ
  163.    a=ssrc:2537041755 msid:ARDAMS ARDAMSa0
  164.    a=ssrc:2537041755 mslabel:ARDAMS
  165.    a=ssrc:2537041755 label:ARDAMSa0
  166.    ------------------------------------------------------------------------
  167. send 355 bytes to ws/[134.213.206.157]:47652 at 18:12:01.789007:
  168.    ------------------------------------------------------------------------
  169.    SIP/2.0 100 Trying
  170.    Via: SIP/2.0/WS amicqbtrlg6o.invalid;branch=z9hG4bK7963946;received=134.213.206.157;rport=47652
  171.    From: <sip:280@3waytalk.com>;tag=g294kid7sg
  172.    To: <sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com>
  173.    Call-ID: dot4lkudmc6ubterpeth
  174.    CSeq: 3068 INVITE
  175.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  176.    Content-Length: 0
  177.    
  178.    ------------------------------------------------------------------------
  179. 2016-09-01 18:12:01.772593 [DEBUG] sofia.c:2356 Re-attaching to session a39771e4-fbf1-4964-b499-e9843b7bfe59
  180. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.792592 [DEBUG] sofia.c:9520 sofia/internal/280@3waytalk.com receiving invite from 134.213.206.157:47652 version: 1.7.0 -915-35ee4ee 64bit
  181. 2016-09-01 18:12:01.792592 [DEBUG] sofia.c:9687 IP 134.213.206.157 Rejected by acl "domains". Falling back to Digest auth.
  182. 2016-09-01 18:12:01.792592 [NOTICE] switch_cpp.cpp:1360 directory request
  183. 2016-09-01 18:12:01.852591 [NOTICE] switch_cpp.cpp:1360 Authenticated user 2747
  184. send 568 bytes to wss/[186.7.200.185]:62075 at 18:12:01.856891:
  185.    ------------------------------------------------------------------------
  186.    SIP/2.0 200 OK
  187.    Via: SIP/2.0/WSS inkljrtqk4e4.invalid;branch=z9hG4bK6858992;received=186.7.200.185;rport=62075
  188.    From: <sip:2747@3waytalk.com>;tag=631gm8n46g
  189.    To: <sip:2747@3waytalk.com>;tag=76NUQta90yZ8S
  190.    Call-ID: ogp0fs00ul7q5ce8jdni5n
  191.    CSeq: 514 REGISTER
  192.    Contact: <sip:k4b6h1cv@inkljrtqk4e4.invalid;transport=ws>;expires=60
  193.    Date: Thu, 01 Sep 2016 18:12:01 GMT
  194.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  195.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  196.    Supported: timer, path, replaces
  197.    Content-Length: 0
  198.    
  199.    ------------------------------------------------------------------------
  200. 2016-09-01 18:12:01.952593 [NOTICE] switch_cpp.cpp:1360 Authenticated user 280
  201. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] sofia.c:10823 Setting NAT mode based on via received
  202. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] sofia.c:6937 Channel sofia/internal/280@3waytalk.com entering state [received][100]
  203. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] sofia.c:6947 Remote SDP:
  204. a39771e4-fbf1-4964-b499-e9843b7bfe59 v=0
  205. a39771e4-fbf1-4964-b499-e9843b7bfe59 o=- 4552988607047744733 2 IN IP4 127.0.0.1
  206. a39771e4-fbf1-4964-b499-e9843b7bfe59 s=-
  207. a39771e4-fbf1-4964-b499-e9843b7bfe59 t=0 0
  208. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=group:BUNDLE audio
  209. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=msid-semantic: WMS ARDAMS
  210. a39771e4-fbf1-4964-b499-e9843b7bfe59 m=audio 42527 UDP/TLS/RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
  211. a39771e4-fbf1-4964-b499-e9843b7bfe59 c=IN IP4 46.159.161.140
  212. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:111 opus/48000/2
  213. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=fmtp:111 minptime=10; useinbandfec=1
  214. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:103 ISAC/16000
  215. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:9 G722/8000
  216. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:102 ILBC/8000
  217. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:0 PCMU/8000
  218. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:8 PCMA/8000
  219. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:106 CN/32000
  220. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:105 CN/16000
  221. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:13 CN/8000
  222. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:127 red/8000
  223. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:126 telephone-event/8000
  224. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtcp:38050 IN IP4 46.159.161.140
  225. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=candidate:3350409123 1 udp 2122260223 192.168.0.101 42527 typ host generation 0
  226. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=candidate:3350409123 2 udp 2122260222 192.168.0.101 38050 typ host generation 0
  227. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=candidate:1190865175 2 udp 1686052606 46.159.161.140 38050 typ srflx raddr 192.168.0.101 rport 38050 generation 0
  228. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=candidate:1190865175 1 udp 1686052607 46.159.161.140 42527 typ srflx raddr 192.168.0.101 rport 42527 generation 0
  229. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=candidate:2301678419 1 tcp 1518280447 192.168.0.101 37171 typ host tcptype passive generation 0
  230. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=candidate:2301678419 2 tcp 1518280446 192.168.0.101 34973 typ host tcptype passive generation 0
  231. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ice-ufrag:zlFE8VtX26klZtLa
  232. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ice-pwd:0LGWvsW5Zazi35Xk8BlzzkOY
  233. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=fingerprint:sha-256 E5:C8:6E:BD:1C:03:D6:B4:4F:E2:8F:B1:84:DE:A1:6A:37:4C:D7:C6:F2:A5:6D:47:45:DD:CA:7A:30:6E:93:BE
  234. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=setup:actpass
  235. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=mid:audio
  236. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
  237. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
  238. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtcp-mux
  239. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=maxptime:60
  240. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ssrc:2537041755 cname:7CbYDw1WUcfBYXkJ
  241. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ssrc:2537041755 msid:ARDAMS ARDAMSa0
  242. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ssrc:2537041755 mslabel:ARDAMS
  243. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ssrc:2537041755 label:ARDAMSa0
  244. a39771e4-fbf1-4964-b499-e9843b7bfe59
  245. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [opus:111:48000:60:0:1]/[opus:116:48000:20:0:1]
  246. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4366 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  247. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[opus:116:48000:20:0:1]
  248. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [G722:9:8000:60:64000:1]/[opus:116:48000:20:0:1]
  249. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [ILBC:102:8000:30:13330:1]/[opus:116:48000:20:0:1]
  250. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[opus:116:48000:20:0:1]
  251. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[opus:116:48000:20:0:1]
  252. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [CN:106:32000:60:0:1]/[opus:116:48000:20:0:1]
  253. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [CN:105:16000:60:0:1]/[opus:116:48000:20:0:1]
  254. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [CN:13:8000:60:0:1]/[opus:116:48000:20:0:1]
  255. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [red:127:8000:60:0:1]/[opus:116:48000:20:0:1]
  256. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4227 Set telephone-event payload to 126@8000
  257. 2016-09-01 18:12:01.952593 [DEBUG] mod_opus.c:559 Opus encoder: set bitrate to local settings [72000bps]
  258. 2016-09-01 18:12:01.952593 [DEBUG] mod_opus.c:578 Opus encoder: CBR mode enabled
  259. 2016-09-01 18:12:01.952593 [DEBUG] mod_opus.c:559 Opus encoder: set bitrate to local settings [72000bps]
  260. 2016-09-01 18:12:01.952593 [DEBUG] mod_opus.c:578 Opus encoder: CBR mode enabled
  261. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:3018 Set Codec sofia/internal/280@3waytalk.com opus/48000 20 ms 960 samples 0 bits 1 channels
  262. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_codec.c:111 sofia/internal/280@3waytalk.com Original read codec set to opus:116
  263. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [WARNING] switch_core_media.c:3392 NO candidate ACL defined, Defaulting to wan.auto
  264. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:3422 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.0.101:42527
  265. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:3422 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.0.101:38050
  266. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:3422 Save audio Candidate cid: 2 proto: udp type: srflx addr: 46.159.161.140:38050
  267. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:3422 Save audio Candidate cid: 1 proto: udp type: srflx addr: 46.159.161.140:42527
  268. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:3464 Searching for rtp candidate.
  269. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:3469 Choose rtp candidate, index 1, 46.159.161.140:42527
  270. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:3247 sofia/internal/280@3waytalk.com choosing family v4
  271. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:3480 Choose same candidate, index 2, for rtcp based on rtcp-mux attribute 46.159.161.140:42527
  272. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:3525 setting remote audio ice addr to index 1 46.159.161.140:42527 based on candidate
  273. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:3546 Setting remote rtcp audio addr to 46.159.161.140:42527 based on candidate
  274. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_media.c:4639 sofia/internal/280@3waytalk.com Set 2833 dtmf send payload to 126 recv payload to 126
  275. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] sofia.c:7336 (sofia/internal/280@3waytalk.com) State Change CS_NEW -> CS_INIT
  276. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/280@3waytalk.com) Running State Change CS_INIT
  277. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/280@3waytalk.com) State INIT
  278. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] mod_sofia.c:90 sofia/internal/280@3waytalk.com SOFIA INIT
  279. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:40 sofia/internal/280@3waytalk.com Standard INIT
  280. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/280@3waytalk.com) State Change CS_INIT -> CS_ROUTING
  281. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/280@3waytalk.com) State INIT going to sleep
  282. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/280@3waytalk.com) Running State Change CS_ROUTING
  283. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_channel.c:2249 (sofia/internal/280@3waytalk.com) Callstate Change DOWN -> RINGING
  284. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/280@3waytalk.com) State ROUTING
  285. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] mod_sofia.c:143 sofia/internal/280@3waytalk.com SOFIA ROUTING
  286. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:236 sofia/internal/280@3waytalk.com Standard ROUTING
  287. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [INFO] mod_dialplan_xml.c:637 Processing 280 <280>->9d1e1675fa8a804e12512d65e0388421_rukoru in context default
  288. a39771e4-fbf1-4964-b499-e9843b7bfe59 Dialplan: sofia/internal/280@3waytalk.com parsing [default->test] continue=false
  289. a39771e4-fbf1-4964-b499-e9843b7bfe59 Dialplan: sofia/internal/280@3waytalk.com Regex (FAIL) [test] destination_number(9d1e1675fa8a804e12512d65e0388421_rukoru) =~ /^test_(.*)$/ break=on-false
  290. a39771e4-fbf1-4964-b499-e9843b7bfe59 Dialplan: sofia/internal/280@3waytalk.com parsing [default->translator] continue=false
  291. a39771e4-fbf1-4964-b499-e9843b7bfe59 Dialplan: sofia/internal/280@3waytalk.com Regex (PASS) [translator] destination_number(9d1e1675fa8a804e12512d65e0388421_rukoru) =~ /^(.*)$/ break=on-false
  292. a39771e4-fbf1-4964-b499-e9843b7bfe59 Dialplan: sofia/internal/280@3waytalk.com Action socket(127.0.0.1:8085 async full)
  293. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/280@3waytalk.com) State Change CS_ROUTING -> CS_EXECUTE
  294. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/280@3waytalk.com) State ROUTING going to sleep
  295. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/280@3waytalk.com) Running State Change CS_EXECUTE
  296. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/280@3waytalk.com) State EXECUTE
  297. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] mod_sofia.c:198 sofia/internal/280@3waytalk.com SOFIA EXECUTE
  298. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [DEBUG] switch_core_state_machine.c:328 sofia/internal/280@3waytalk.com Standard EXECUTE
  299. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com socket(127.0.0.1:8085 async full)
  300. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:01.952593 [NOTICE] mod_event_socket.c:432 Trying host: 127.0.0.1:8085
  301. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute answer(undefined)
  302. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com answer(undefined)
  303. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [DEBUG] switch_core_media.c:6465 AUDIO RTP [sofia/internal/280@3waytalk.com] 134.213.206.157 port 23278 -> 46.159.161.140 port 42527 codec: 111 ms: 20
  304. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 960 bytes per 20ms
  305. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [INFO] switch_core_media.c:6640 Activating Audio ICE
  306. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [NOTICE] switch_rtp.c:4314 Activating RTP audio ICE: zlFE8VtX26klZtLa:mR4D536XcQ1YUJEM 46.159.161.140:42527
  307. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [DEBUG] switch_core_media.c:6683 Activating RTCP PORT 42527
  308. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [DEBUG] switch_rtp.c:4211 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 42527
  309. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [INFO] switch_core_media.c:6691 Skipping RTCP ICE (Same as RTP)
  310. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [INFO] switch_rtp.c:3328 Activate RTP/RTCP audio DTLS client
  311. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [INFO] switch_rtp.c:3459 Changing audio DTLS state from OFF to HANDSHAKE
  312. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [DEBUG] switch_core_media.c:1989 Setting Jitterbuffer to 60ms (3 frames) (50 max frames)
  313. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [DEBUG] switch_core_media.c:6764 sofia/internal/280@3waytalk.com Set 2833 dtmf send payload to 126
  314. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [DEBUG] switch_core_media.c:6771 sofia/internal/280@3waytalk.com Set 2833 dtmf receive payload to 126
  315. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [DEBUG] switch_core_media.c:6794 sofia/internal/280@3waytalk.com Set rtp dtmf delay to 40
  316. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [DEBUG] mod_sofia.c:822 Local SDP sofia/internal/280@3waytalk.com:
  317. a39771e4-fbf1-4964-b499-e9843b7bfe59 v=0
  318. a39771e4-fbf1-4964-b499-e9843b7bfe59 o=FreeSWITCH 1472730244 1472730245 IN IP4 134.213.206.157
  319. a39771e4-fbf1-4964-b499-e9843b7bfe59 s=FreeSWITCH
  320. a39771e4-fbf1-4964-b499-e9843b7bfe59 c=IN IP4 134.213.206.157
  321. a39771e4-fbf1-4964-b499-e9843b7bfe59 t=0 0
  322. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=msid-semantic: WMS 4YYCC0e5BNe59O8tbDG3ro9C1iUeVdRQ
  323. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=end-of-candidates
  324. a39771e4-fbf1-4964-b499-e9843b7bfe59 m=audio 23278 UDP/TLS/RTP/SAVPF 111 126
  325. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:111 opus/48000/2
  326. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=fmtp:111 useinbandfec=0; cbr=1; minptime=10
  327. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtpmap:126 telephone-event/8000
  328. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=silenceSupp:off - - - -
  329. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ptime:20
  330. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=sendrecv
  331. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=fingerprint:sha-256 96:C6:72:D0:36:F1:BA:16:EA:24:7E:A3:3B:27:E8:E7:A1:C1:4B:D6:CD:DD:4E:9E:8C:39:F4:80:38:67:74:09
  332. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=setup:active
  333. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtcp-mux
  334. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=rtcp:23278 IN IP4 134.213.206.157
  335. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ice-ufrag:mR4D536XcQ1YUJEM
  336. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ice-pwd:fN7D1otSIeJIXTI9ADDwxzjz
  337. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=candidate:1264925573 1 udp 659136 134.213.206.157 23278 typ host generation 0
  338. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ssrc:399277753 cname:2GivxnqZ1uU09Tka
  339. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ssrc:399277753 msid:4YYCC0e5BNe59O8tbDG3ro9C1iUeVdRQ a0
  340. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ssrc:399277753 mslabel:4YYCC0e5BNe59O8tbDG3ro9C1iUeVdRQ
  341. a39771e4-fbf1-4964-b499-e9843b7bfe59 a=ssrc:399277753 label:4YYCC0e5BNe59O8tbDG3ro9C1iUeVdRQa0
  342. a39771e4-fbf1-4964-b499-e9843b7bfe59
  343. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [NOTICE] mod_dptools.c:1309 Channel [sofia/internal/280@3waytalk.com] has been answered
  344. send 1797 bytes to ws/[134.213.206.157]:47652 at 18:12:02.044712:
  345.    ------------------------------------------------------------------------
  346.    SIP/2.0 200 OK
  347.    Via: SIP/2.0/WS amicqbtrlg6o.invalid;branch=z9hG4bK7963946;received=134.213.206.157;rport=47652
  348.    From: <sip:280@3waytalk.com>;tag=g294kid7sg
  349.    To: <sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com>;tag=9r8cUgcgUgceH
  350.    Call-ID: dot4lkudmc6ubterpeth
  351.    CSeq: 3068 INVITE
  352.    Contact: <sip:9d1e1675fa8a804e12512d65e0388421_rukoru@134.213.206.157:5060;transport=udp>
  353.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  354.    Accept: application/sdp
  355.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  356.    Supported: timer, path, replaces
  357.    Allow-Events: talk, hold, conference, refer
  358.    Content-Type: application/sdp
  359.    Content-Disposition: session
  360.    Content-Length: 925
  361.    Remote-Party-ID: "9d1e1675fa8a804e12512d65e0388421_rukoru" <sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com>;party=calling;privacy=off;screen=no
  362.    
  363.    v=0
  364.    o=FreeSWITCH 1472730244 1472730245 IN IP4 134.213.206.157
  365.    s=FreeSWITCH
  366.    c=IN IP4 134.213.206.157
  367.    t=0 0
  368.    a=msid-semantic: WMS 4YYCC0e5BNe59O8tbDG3ro9C1iUeVdRQ
  369.    a=end-of-candidates
  370.    m=audio 23278 UDP/TLS/RTP/SAVPF 111 126
  371.    a=rtpmap:111 opus/48000/2
  372.    a=fmtp:111 useinbandfec=0; cbr=1; minptime=10
  373.    a=rtpmap:126 telephone-event/8000
  374.    a=silenceSupp:off - - - -
  375.    a=ptime:20
  376.    a=fingerprint:sha-256 96:C6:72:D0:36:F1:BA:16:EA:24:7E:A3:3B:27:E8:E7:A1:C1:4B:D6:CD:DD:4E:9E:8C:39:F4:80:38:67:74:09
  377.    a=setup:active
  378.    a=rtcp-mux
  379.    a=rtcp:23278 IN IP4 134.213.206.157
  380.    a=ice-ufrag:mR4D536XcQ1YUJEM
  381.    a=ice-pwd:fN7D1otSIeJIXTI9ADDwxzjz
  382.    a=candidate:1264925573 1 udp 659136 134.213.206.157 23278 typ host generation 0
  383.    a=ssrc:399277753 cname:2GivxnqZ1uU09Tka
  384.    a=ssrc:399277753 msid:4YYCC0e5BNe59O8tbDG3ro9C1iUeVdRQ a0
  385.    a=ssrc:399277753 mslabel:4YYCC0e5BNe59O8tbDG3ro9C1iUeVdRQ
  386.    a=ssrc:399277753 label:4YYCC0e5BNe59O8tbDG3ro9C1iUeVdRQa0
  387.    ------------------------------------------------------------------------
  388. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [DEBUG] switch_channel.c:3770 (sofia/internal/280@3waytalk.com) Callstate Change RINGING -> ACTIVE
  389. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.032588 [DEBUG] sofia.c:6937 Channel sofia/internal/280@3waytalk.com entering state [completed][200]
  390. recv 412 bytes from ws/[134.213.206.157]:47652 at 18:12:02.050097:
  391.    ------------------------------------------------------------------------
  392.    ACK sip:9d1e1675fa8a804e12512d65e0388421_rukoru@134.213.206.157:5060;transport=udp SIP/2.0
  393.    Via: SIP/2.0/WS amicqbtrlg6o.invalid;branch=z9hG4bK5939068
  394.    Max-Forwards: 70
  395.    To: <sip:9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com>;tag=9r8cUgcgUgceH
  396.    From: <sip:280@3waytalk.com>;tag=g294kid7sg
  397.    Call-ID: dot4lkudmc6ubterpeth
  398.    CSeq: 3068 ACK
  399.    Supported: outbound
  400.    User-Agent: SIP.js/0.7.5
  401.    Content-Length: 0
  402.    
  403.    ------------------------------------------------------------------------
  404. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.052588 [DEBUG] sofia.c:6937 Channel sofia/internal/280@3waytalk.com entering state [ready][200]
  405. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.092583 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute info(notice)
  406. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com info(notice)
  407. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.092583 [NOTICE] mod_dptools.c:1732 CHANNEL_DATA:
  408. a39771e4-fbf1-4964-b499-e9843b7bfe59 Channel-State: [CS_EXECUTE]
  409. a39771e4-fbf1-4964-b499-e9843b7bfe59 Channel-Call-State: [ACTIVE]
  410. a39771e4-fbf1-4964-b499-e9843b7bfe59 Channel-State-Number: [4]
  411. a39771e4-fbf1-4964-b499-e9843b7bfe59 Channel-Name: [sofia/internal/280@3waytalk.com]
  412. a39771e4-fbf1-4964-b499-e9843b7bfe59 Unique-ID: [a39771e4-fbf1-4964-b499-e9843b7bfe59]
  413. a39771e4-fbf1-4964-b499-e9843b7bfe59 Call-Direction: [inbound]
  414. a39771e4-fbf1-4964-b499-e9843b7bfe59 Presence-Call-Direction: [inbound]
  415. a39771e4-fbf1-4964-b499-e9843b7bfe59 Channel-HIT-Dialplan: [true]
  416. a39771e4-fbf1-4964-b499-e9843b7bfe59 Channel-Call-UUID: [a39771e4-fbf1-4964-b499-e9843b7bfe59]
  417. a39771e4-fbf1-4964-b499-e9843b7bfe59 Answer-State: [answered]
  418. a39771e4-fbf1-4964-b499-e9843b7bfe59 Channel-Read-Codec-Name: [opus]
  419. a39771e4-fbf1-4964-b499-e9843b7bfe59 Channel-Read-Codec-Rate: [48000]
  420. a39771e4-fbf1-4964-b499-e9843b7bfe59 Channel-Read-Codec-Bit-Rate: [0]
  421. a39771e4-fbf1-4964-b499-e9843b7bfe59 Channel-Write-Codec-Name: [opus]
  422. a39771e4-fbf1-4964-b499-e9843b7bfe59 Channel-Write-Codec-Rate: [48000]
  423. a39771e4-fbf1-4964-b499-e9843b7bfe59 Channel-Write-Codec-Bit-Rate: [0]
  424. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Direction: [inbound]
  425. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Logical-Direction: [inbound]
  426. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Username: [280]
  427. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Dialplan: [XML]
  428. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Caller-ID-Name: [280]
  429. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Caller-ID-Number: [280]
  430. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Orig-Caller-ID-Name: [280]
  431. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Orig-Caller-ID-Number: [280]
  432. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Network-Addr: [134.213.206.157]
  433. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-ANI: [280]
  434. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Destination-Number: [9d1e1675fa8a804e12512d65e0388421_rukoru]
  435. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Unique-ID: [a39771e4-fbf1-4964-b499-e9843b7bfe59]
  436. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Source: [mod_sofia]
  437. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Context: [default]
  438. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Channel-Name: [sofia/internal/280@3waytalk.com]
  439. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Profile-Index: [1]
  440. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Profile-Created-Time: [1472753521952593]
  441. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Channel-Created-Time: [1472753521952593]
  442. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Channel-Answered-Time: [1472753522032588]
  443. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Channel-Progress-Time: [0]
  444. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Channel-Progress-Media-Time: [0]
  445. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Channel-Hangup-Time: [0]
  446. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Channel-Transfer-Time: [0]
  447. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Channel-Resurrect-Time: [0]
  448. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Channel-Bridged-Time: [0]
  449. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Channel-Last-Hold: [0]
  450. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Channel-Hold-Accum: [0]
  451. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Screen-Bit: [true]
  452. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Privacy-Hide-Name: [false]
  453. a39771e4-fbf1-4964-b499-e9843b7bfe59 Caller-Privacy-Hide-Number: [false]
  454. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_direction: [inbound]
  455. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_uuid: [a39771e4-fbf1-4964-b499-e9843b7bfe59]
  456. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_session_id: [210]
  457. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_from_user: [280]
  458. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_from_uri: [280@3waytalk.com]
  459. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_from_host: [3waytalk.com]
  460. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_video_media_flow: [sendrecv]
  461. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_channel_name: [sofia/internal/280@3waytalk.com]
  462. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_ep_codec_string: [mod_opus.opus@48000h@20i@2c]
  463. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_local_network_addr: [134.213.206.157]
  464. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_network_ip: [134.213.206.157]
  465. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_network_port: [47652]
  466. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_received_ip: [134.213.206.157]
  467. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_received_port: [47652]
  468. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_via_protocol: [ws]
  469. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_authorized: [true]
  470. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_Event-Name: [REQUEST_PARAMS]
  471. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_Core-UUID: [123f7e3b-36a3-44d6-b6aa-8b98c8273e98]
  472. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_FreeSWITCH-Hostname: [voip-prod]
  473. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_FreeSWITCH-Switchname: [voip-prod]
  474. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_FreeSWITCH-IPv4: [134.213.206.157]
  475. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_FreeSWITCH-IPv6: [2a00:1a48:7812:102:be76:4eff:fe08:bec0]
  476. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_Event-Date-Local: [2016-09-01 18:12:01]
  477. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_Event-Date-GMT: [Thu, 01 Sep 2016 18:12:01 GMT]
  478. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_Event-Date-Timestamp: [1472753521792592]
  479. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_Event-Calling-File: [sofia.c]
  480. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_Event-Calling-Function: [sofia_handle_sip_i_invite]
  481. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_Event-Calling-Line-Number: [9734]
  482. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_Event-Sequence: [26722]
  483. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_number_alias: [280]
  484. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_auth_username: [280]
  485. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_auth_realm: [3waytalk.com]
  486. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_number_alias: [280]
  487. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_requested_user_name: [280]
  488. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_requested_domain_name: [3waytalk.com]
  489. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_user_context: [default]
  490. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_user_name: [280]
  491. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_domain_name: [3waytalk.com]
  492. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_from_user_stripped: [280]
  493. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sofia_profile_name: [internal]
  494. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_recovery_profile_name: [internal]
  495. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_req_user: [9d1e1675fa8a804e12512d65e0388421_rukoru]
  496. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_req_uri: [9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com]
  497. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_req_host: [3waytalk.com]
  498. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_to_user: [9d1e1675fa8a804e12512d65e0388421_rukoru]
  499. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_to_uri: [9d1e1675fa8a804e12512d65e0388421_rukoru@3waytalk.com]
  500. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_to_host: [3waytalk.com]
  501. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_contact_params: [transport=ws;ob]
  502. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_contact_user: [1kfef9k8]
  503. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_contact_uri: [1kfef9k8@amicqbtrlg6o.invalid]
  504. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_contact_host: [amicqbtrlg6o.invalid]
  505. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_user_agent: [SIP.js/0.7.5]
  506. a39771e4-fbf1-4964-b499-e9843b7bfe59 variable_sip_via_host: [amicqbtrlg6o.invalid]
  507. variable_sip_via_rport: [47652]
  508. variable_max_forwards: [70]
  509. variable_switch_r_sdp: [v=0
  510. o=- 4552988607047744733 2 IN IP4 127.0.0.1
  511. s=-
  512. t=0 0
  513. a=group:BUNDLE audio
  514. a=msid-semantic: WMS ARDAMS
  515. m=audio 42527 UDP/TLS/RTP/SAVPF 111 103 9 102 0 8 106 105 13 127 126
  516. c=IN IP4 46.159.161.140
  517. a=rtpmap:111 opus/48000/2
  518. a=fmtp:111 minptime=10; useinbandfec=1
  519. a=rtpmap:103 ISAC/16000
  520. a=rtpmap:9 G722/8000
  521. a=rtpmap:102 ILBC/8000
  522. a=rtpmap:0 PCMU/8000
  523. a=rtpmap:8 PCMA/8000
  524. a=rtpmap:106 CN/32000
  525. a=rtpmap:105 CN/16000
  526. a=rtpmap:13 CN/8000
  527. a=rtpmap:127 red/8000
  528. a=rtpmap:126 telephone-event/8000
  529. a=rtcp:38050 IN IP4 46.159.161.140
  530. a=candidate:3350409123 1 udp 2122260223 192.168.0.101 42527 typ host generation 0
  531. a=candidate:3350409123 2 udp 2122260222 192.168.0.101 38050 typ host generation 0
  532. a=candidate:1190865175 2 udp 1686052606 46.159.161.140 38050 typ srflx raddr 192.168.0.101 rport 38050 generation 0
  533. a=candidate:1190865175 1 udp 1686052607 46.159.161.140 42527 typ srflx raddr 192.168.0.101 rport 42527 generation 0
  534. a=candidate:2301678419 1 tcp 1518280447 192.168.0.101 37171 typ host tcptype passive generation 0
  535. a=candidate:2301678419 2 tcp 1518280446 192.168.0.101 34973 typ host tcptype passive generation 0
  536. a=ice-ufrag:zlFE8VtX26klZtLa
  537. a=ice-pwd:0LGWvsW5Zazi35Xk8BlzzkOY
  538. a=fingerprint:sha-256 E5:C8:6E:BD:1C:03:D6:B4:4F:E2:8F:B1:84:DE:A1:6A:37:4C:D7:C6:F2:A5:6D:47:45:DD:CA:7A:30:6E:93:BE
  539. a=setup:actpass
  540. a=mid:audio
  541. a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
  542. a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
  543. a=rtcp-mux
  544. a=maxptime:60
  545. a=ssrc:2537041755 cname:7CbYDw1WUcfBYXkJ
  546. a=ssrc:2537041755 msid:ARDAMS ARDAMSa0
  547. a=ssrc:2537041755 mslabel:ARDAMS
  548. a=ssrc:2537041755 label:ARDAMSa0
  549. ]
  550. variable_audio_media_flow: [sendrecv]
  551. variable_rtp_use_codec_string: [OPUS]
  552. variable_rtp_remote_audio_rtcp_port: [38050 IN IP4 46.159.161.140]
  553. variable_rtp_audio_recv_pt: [111]
  554. variable_rtp_use_codec_name: [opusa39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.512591 [INFO] switch_rtp.c:3119 Changing audio DTLS state from HANDSHAKE to SETUP
  555. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.532598 [INFO] switch_rtp.c:3028 audio Fingerprint Verified.
  556. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.532598 [INFO] switch_rtp.c:3644 Activating Audio Secure RTP SEND
  557. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.532598 [INFO] switch_rtp.c:3622 Activating Audio Secure RTP RECV
  558. 2016-09-01 18:12:02.532598 [DEBUG] switch_core_sqldb.c:2612 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
  559. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.532598 [INFO] switch_rtp.c:3068 Changing audio DTLS state from SETUP to READY
  560. 2016-09-01 18:12:02.532598 [DEBUG] switch_core_sqldb.c:2612 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
  561. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:02.772593 [DEBUG] switch_rtp.c:1846 rtcp_stats_init: audio ssrc[2537041755] base_seq[22618]
  562. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute set(hangup_after_bridge=false)
  563. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com set(hangup_after_bridge=false)
  564. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] mod_dptools.c:1519 SET sofia/internal/280@3waytalk.com [hangup_after_bridge]=[false]
  565. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute set(ringback=file_string:///home/threewaytalk/3waytalk/audio/please_wait.wav!tone_stream://${us-ring};loops=-1)
  566. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com set(ringback=file_string:///home/threewaytalk/3waytalk/audio/please_wait.wav!tone_stream://%(2000,4000,440,480);loops=-1)
  567. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] mod_dptools.c:1519 SET sofia/internal/280@3waytalk.com [ringback]=[file_string:///home/threewaytalk/3waytalk/audio/please_wait.wav!tone_stream://%(2000,4000,440,480);loops=-1]
  568. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute set(continue_on_fail=true)
  569. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com set(continue_on_fail=true)
  570. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] mod_dptools.c:1519 SET sofia/internal/280@3waytalk.com [continue_on_fail]=[true]
  571. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute set(bypass_media=false)
  572. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com set(bypass_media=false)
  573. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] mod_dptools.c:1519 SET sofia/internal/280@3waytalk.com [bypass_media]=[false]
  574. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute set(effective_caller_id_name=incoming call (Russian))
  575. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com set(effective_caller_id_name=incoming call (Russian))
  576. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] mod_dptools.c:1519 SET sofia/internal/280@3waytalk.com [effective_caller_id_name]=[incoming call (Russian)]
  577. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute set(jitterbuffer_msec=90:300:600)
  578. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com set(jitterbuffer_msec=90:300:600)
  579. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] mod_dptools.c:1519 SET sofia/internal/280@3waytalk.com [jitterbuffer_msec]=[90:300:600]
  580. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute set(sip_jitter_buffer_during_bridge=true)
  581. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com set(sip_jitter_buffer_during_bridge=true)
  582. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] mod_dptools.c:1519 SET sofia/internal/280@3waytalk.com [sip_jitter_buffer_during_bridge]=[true]
  583. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute export(nolocal:jitterbuffer_msec=90:300:600)
  584. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com export(nolocal:jitterbuffer_msec=90:300:600)
  585. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [jitterbuffer_msec]=[90:300:600]
  586. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute export(bridge_generate_comfort_noise=true)
  587. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com export(bridge_generate_comfort_noise=true)
  588. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [bridge_generate_comfort_noise]=[true]
  589. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute set(call_timeout=20)
  590. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com set(call_timeout=20)
  591. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] mod_dptools.c:1519 SET sofia/internal/280@3waytalk.com [call_timeout]=[20]
  592. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute export(nolocal:absolute_codec_string=OPUS)
  593. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com export(nolocal:absolute_codec_string=OPUS)
  594. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [absolute_codec_string]=[OPUS]
  595. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute bridge({ignore_early_media=false}user/2183@${domain_name})
  596. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com bridge({ignore_early_media=false}user/2183@3waytalk.com)
  597. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_channel.c:1823 (sofia/internal/280@3waytalk.com) Callstate Change ACTIVE -> RING_WAIT
  598. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_channel.c:1250 sofia/internal/280@3waytalk.com EXPORTING[export_vars] [jitterbuffer_msec]=[90:300:600] to event
  599. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_channel.c:1250 sofia/internal/280@3waytalk.com EXPORTING[export_vars] [bridge_generate_comfort_noise]=[true] to event
  600. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_channel.c:1250 sofia/internal/280@3waytalk.com EXPORTING[export_vars] [absolute_codec_string]=[OPUS] to event
  601. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.032589 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
  602. 2016-09-01 18:12:03.032589 [NOTICE] switch_cpp.cpp:1360 directory request
  603. 2016-09-01 18:12:03.852586 [NOTICE] switch_cpp.cpp:1360 Authenticated user 2183
  604. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.852586 [DEBUG] switch_channel.c:1250 sofia/internal/280@3waytalk.com EXPORTING[export_vars] [jitterbuffer_msec]=[90:300:600] to event
  605. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.852586 [DEBUG] switch_channel.c:1250 sofia/internal/280@3waytalk.com EXPORTING[export_vars] [bridge_generate_comfort_noise]=[true] to event
  606. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.852586 [DEBUG] switch_channel.c:1250 sofia/internal/280@3waytalk.com EXPORTING[export_vars] [absolute_codec_string]=[OPUS] to event
  607. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.852586 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
  608. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid [5b8b1c43-a010-4570-b282-6eb4a965a4db]
  609. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] mod_sofia.c:4754 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State Change CS_NEW -> CS_INIT
  610. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Running State Change CS_INIT
  611. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State INIT
  612. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] mod_sofia.c:90 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid SOFIA INIT
  613. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] sofia_glue.c:1220 sip:qiin1j5e@46.159.161.140:56849;transport=wss Setting proxy route to sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid
  614. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] sofia_glue.c:1249 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid sending invite version: 1.7.0 -915-35ee4ee 64bit
  615. 5b8b1c43-a010-4570-b282-6eb4a965a4db Local SDP:
  616. 5b8b1c43-a010-4570-b282-6eb4a965a4db v=0
  617. 5b8b1c43-a010-4570-b282-6eb4a965a4db o=FreeSWITCH 1472730617 1472730618 IN IP4 134.213.206.157
  618. 5b8b1c43-a010-4570-b282-6eb4a965a4db s=FreeSWITCH
  619. 5b8b1c43-a010-4570-b282-6eb4a965a4db c=IN IP4 134.213.206.157
  620. 5b8b1c43-a010-4570-b282-6eb4a965a4db t=0 0
  621. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=msid-semantic: WMS EPL0w1YessmR7IRxAwu5xOVMMjHG6kaB
  622. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=end-of-candidates
  623. 5b8b1c43-a010-4570-b282-6eb4a965a4db m=audio 22906 RTP/SAVPF 102 101
  624. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=rtpmap:102 opus/48000/2
  625. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=fmtp:102 useinbandfec=0; cbr=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  626. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=rtpmap:101 telephone-event/48000
  627. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=fingerprint:sha-256 96:C6:72:D0:36:F1:BA:16:EA:24:7E:A3:3B:27:E8:E7:A1:C1:4B:D6:CD:DD:4E:9E:8C:39:F4:80:38:67:74:09
  628. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=setup:actpass
  629. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=rtcp-mux
  630. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=rtcp:22906 IN IP4 134.213.206.157
  631. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ssrc:3152737755 cname:0MsmDgrVzEDwckUD
  632. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ssrc:3152737755 msid:EPL0w1YessmR7IRxAwu5xOVMMjHG6kaB a0
  633. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ssrc:3152737755 mslabel:EPL0w1YessmR7IRxAwu5xOVMMjHG6kaB
  634. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ssrc:3152737755 label:EPL0w1YessmR7IRxAwu5xOVMMjHG6kaBa0
  635. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ice-ufrag:Hey5TkHwte2qp3Rf
  636. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ice-pwd:F9s8gJUGeo2gzNK6S92BjzYC
  637. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=candidate:0578070984 1 udp 659136 134.213.206.157 22906 typ host generation 0
  638. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=candidate:0578070984 2 udp 659136 134.213.206.157 22906 typ host generation 0
  639. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=silenceSupp:off - - - -
  640. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ptime:20
  641. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=sendrecv
  642. 5b8b1c43-a010-4570-b282-6eb4a965a4db
  643. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] switch_core_state_machine.c:40 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Standard INIT
  644. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State Change CS_INIT -> CS_ROUTING
  645. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State INIT going to sleep
  646. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Running State Change CS_ROUTING
  647. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State ROUTING
  648. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] mod_sofia.c:143 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid SOFIA ROUTING
  649. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  650. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State ROUTING going to sleep
  651. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Running State Change CS_CONSUME_MEDIA
  652. send 2038 bytes to wss/[46.159.161.140]:56849 at 18:12:03.857631:
  653.    ------------------------------------------------------------------------
  654.    INVITE sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws SIP/2.0
  655.    Via: SIP/2.0/WSS 134.213.206.157:7443;rport;branch=z9hG4bK5gSv9QXKr1U1B
  656.    Route: <sip:qiin1j5e@46.159.161.140:56849>;transport=wss
  657.    Max-Forwards: 69
  658.    From: "incoming call (Russian)" <sip:280@3waytalk.com>;tag=a215vBXKrS20c
  659.    To: <sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws>
  660.    Call-ID: 6d2f7341-eb12-1234-368a-bc764e08bec0
  661.    CSeq: 96040697 INVITE
  662.    Contact: <sip:mod_sofia@134.213.206.157:5060>
  663.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  664.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  665.    Supported: timer, path, replaces
  666.    Allow-Events: talk, hold, conference, refer
  667.    Session-Expires: 1800;refresher=uac
  668.    Min-SE: 120
  669.    Content-Type: application/sdp
  670.    Content-Disposition: session
  671.    Content-Length: 1075
  672.    X-FS-Support: update_display,send_info
  673.    Remote-Party-ID: "incoming call (Russian)" <sip:280@3waytalk.com>;party=calling;screen=yes;privacy=off
  674.  
  675.    v=0
  676.    o=FreeSWITCH 1472730617 1472730618 IN IP4 134.213.206.157
  677.    s=FreeSWITCH
  678.  
  679.    c=IN IP4 134.213.206.157
  680.    t=0 0
  681.    a=msid-semantic: WMS EPL0w1YessmR7IRxAwu5xOVMMjHG6kaB
  682.    a=end-of-candidates
  683.    m=audio 22906 RTP/SAVPF 102 101
  684.    a=rtpmap:102 opus/48000/2
  685.    a=fmtp:102 useinbandfec=0; cbr=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  686.    a=rtpmap:101 telephone-event/48000
  687.    a=fingerprint:sha-256 96:C6:72:D0:36:F1:BA:16:EA:24:7E:A3:3B:27:E8:E7:A1:C1:4B:D6:CD:DD:4E:9E:8C:39:F4:80:38:67:74:09
  688.    a=setup:actpass
  689.    a=rtcp-mux
  690.    a=rtcp:22906 IN IP4 134.213.206.157
  691.    a=ssrc:3152737755 cname:0MsmDgrVzEDwckUD
  692.    a=ssrc:3152737755 msid:EPL0w1YessmR7IRxAwu5xOVMMjHG6kaB a0
  693.    a=ssrc:3152737755 mslabel:EPL0w1YessmR7IRxAwu5xOVMMjHG6kaB
  694.    a=ssrc:3152737755 label:EPL0w1YessmR7IRxAwu5xOVMMjHG6kaBa0
  695.    a=ice-ufrag:Hey5TkHwte2qp3Rf
  696.    a=ice-pwd:F9s8gJUGeo2gzNK6S92BjzYC
  697.    a=candidate:0578070984 1 udp 659136 134.213.206.157 22906 typ host generation 0
  698.    a=candidate:0578070984 2 udp 659136 134.213.206.157 22906 typ host generation 0
  699.    a=silenceSupp:off - - - -
  700.    a=ptime:20
  701.    ------------------------------------------------------------------------
  702. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State CONSUME_MEDIA
  703. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State CONSUME_MEDIA going to sleep
  704. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.852586 [DEBUG] sofia.c:6937 Channel sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid entering state [calling][0]
  705. recv 359 bytes from wss/[46.159.161.140]:56849 at 18:12:03.955287:
  706.    ------------------------------------------------------------------------
  707.    SIP/2.0 100 Trying
  708.    Via: SIP/2.0/WSS 134.213.206.157:7443;rport;branch=z9hG4bK5gSv9QXKr1U1B
  709.    To: <sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws>
  710.    From: "incoming call (Russian)" <sip:280@3waytalk.com>;tag=a215vBXKrS20c
  711.    Call-ID: 6d2f7341-eb12-1234-368a-bc764e08bec0
  712.    CSeq: 96040697 INVITE
  713.    Supported: outbound
  714.    User-Agent: SIP.js/0.7.5
  715.    Content-Length: 0
  716.    
  717.    ------------------------------------------------------------------------
  718. recv 434 bytes from wss/[46.159.161.140]:56849 at 18:12:03.961962:
  719.    ------------------------------------------------------------------------
  720.    SIP/2.0 180 Ringing
  721.    Via: SIP/2.0/WSS 134.213.206.157:7443;rport;branch=z9hG4bK5gSv9QXKr1U1B
  722.    To: <sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws>;tag=fm2qiedr5v
  723.    From: "incoming call (Russian)" <sip:280@3waytalk.com>;tag=a215vBXKrS20c
  724.    Call-ID: 6d2f7341-eb12-1234-368a-bc764e08bec0
  725.    CSeq: 96040697 INVITE
  726.    Contact: <sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws>
  727.    Supported: outbound
  728.    User-Agent: SIP.js/0.7.5
  729.    Content-Length: 0
  730.    
  731.    ------------------------------------------------------------------------
  732. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.952585 [DEBUG] sofia.c:6937 Channel sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid entering state [proceeding][180]
  733. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.952585 [NOTICE] sofia.c:7045 Ring-Ready sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid!
  734. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:03.952585 [DEBUG] switch_channel.c:3343 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Callstate Change DOWN -> RINGING
  735. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.972592 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@48000hz 1 channel 20ms
  736. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.972592 [DEBUG] switch_core_codec.c:221 sofia/internal/280@3waytalk.com Push codec L16:100
  737. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:03.972592 [DEBUG] switch_ivr_originate.c:1305 Play Ringback File [file_string:///home/threewaytalk/3waytalk/audio/please_wait.wav!tone_stream://%(2000,4000,440,480);loops=-1]
  738. 2016-09-01 18:12:03.972592 [DEBUG] switch_core_file.c:330 File /home/threewaytalk/3waytalk/audio/please_wait.wav sample rate 44100 doesn't match requested rate 48000
  739. recv 2040 bytes from wss/[46.159.161.140]:56849 at 18:12:12.063548:
  740.    ------------------------------------------------------------------------
  741.    SIP/2.0 200 OK
  742.    Via: SIP/2.0/WSS 134.213.206.157:7443;rport;branch=z9hG4bK5gSv9QXKr1U1B
  743.    To: <sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws>;tag=fm2qiedr5v
  744.    From: "incoming call (Russian)" <sip:280@3waytalk.com>;tag=a215vBXKrS20c
  745.    Call-ID: 6d2f7341-eb12-1234-368a-bc764e08bec0
  746.    CSeq: 96040697 INVITE
  747.    Contact: <sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws>
  748.   Allow: ACK,CANCEL,INVITE,MESSAGE,BYE,OPTIONS,INFO,NOTIFY,REFER
  749.    Supported: outbound
  750.    User-Agent: SIP.js/0.7.5
  751.    Content-Type: application/sdp
  752.    Content-Length: 1513
  753.    
  754.    v=0
  755.    o=- 746996579908061199 2 IN IP4 127.0.0.1
  756.    s=-
  757.    t=0 0
  758.    a=msid-semantic: WMS 8A92izLjuVeE3mQmph5V0EMsmUiXGRJ5byy5
  759.    m=audio 57792 UDP/TLS/RTP/SAVPF 102
  760.    c=IN IP4 46.159.161.140
  761.    a=rtcp:9 IN IP4 0.0.0.0
  762.    a=candidate:3573973069 1 udp 2122260223 192.168.0.102 57792 typ host generation 0 network-id 3
  763.    a=candidate:2999745851 1 udp 2122194687 192.168.56.1 57793 typ host generation 0 network-id 2
  764.    a=candidate:3013953624 1 udp 2122129151 192.168.1.100 57794 typ host generation 0 network-id 1
  765.    a=candidate:1414432505 1 udp 1686052607 46.159.161.140 57792 typ srflx raddr 192.168.0.102 rport 57792 generation 0 network-id 3
  766.    a=candidate:2609216701 1 tcp 1518280447 192.168.0.102 9 typ host tcptype active generation 0 network-id 3
  767.    a=candidate:4233069003 1 tcp 1518214911 192.168.56.1 9 typ host tcptype active generation 0 network-id 2
  768.    a=candidate:4247172264 1 tcp 1518149375 192.168.1.100 9 typ host tcptype active generation 0 network-id 1
  769.    a=ice-ufrag:IqBC5aTb1e50SUKi
  770.    a=ice-pwd:V2chuu0uZjXEnxUMukcz0b34
  771.    a=fingerprint:sha-256 AD:1F:11:FC:6C:91:DC:4D:A3:5B:9C:F4:93:16:50:B0:81:B3:CB:D4:FE:62:2A:2C:A9:9A:F3:5A:0C:5C:44:20
  772.    a=setup:active
  773.    a=mid:audio
  774.    a=sendrecv
  775.    a=rtcp-mux
  776.    a=rtpmap:102 opus/48000/2
  777.    a=fmtp:102 minptime=10;useinbandfec=1
  778.    a=ssrc:1546595284 cname:+d2oOSujhW7VK759
  779.    a=ssrc:1546595284 msid:8A92izLjuVeE3mQmph5V0EMsmUiXGRJ5byy5 053c5609-13b8-4081-a1b0-cb099a328ae6
  780.    a=ssrc:1546595284 mslabel:8A92izLjuVeE3mQmph5V0EMsmUiXGRJ5byy5
  781.    a=ssrc:1546595284 label:053c5609-13b8-4081-a1b0-cb099a328ae6
  782.    ------------------------------------------------------------------------
  783. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] sofia.c:6937 Channel sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid entering state [completing][200]
  784. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] sofia.c:6947 Remote SDP:
  785. 5b8b1c43-a010-4570-b282-6eb4a965a4db v=0
  786. 5b8b1c43-a010-4570-b282-6eb4a965a4db o=- 746996579908061199 2 IN IP4 127.0.0.1
  787. 5b8b1c43-a010-4570-b282-6eb4a965a4db s=-
  788. 5b8b1c43-a010-4570-b282-6eb4a965a4db t=0 0
  789. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=msid-semantic: WMS 8A92izLjuVeE3mQmph5V0EMsmUiXGRJ5byy5
  790. 5b8b1c43-a010-4570-b282-6eb4a965a4db m=audio 57792 UDP/TLS/RTP/SAVPF 102
  791. 5b8b1c43-a010-4570-b282-6eb4a965a4db c=IN IP4 46.159.161.140
  792. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=rtpmap:102 opus/48000/2
  793. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=fmtp:102 minptime=10;useinbandfec=1
  794. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=rtcp:9 IN IP4 0.0.0.0
  795. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=candidate:3573973069 1 udp 2122260223 192.168.0.102 57792 typ host generation 0 network-id 3
  796. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=candidate:2999745851 1 udp 2122194687 192.168.56.1 57793 typ host generation 0 network-id 2
  797. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=candidate:3013953624 1 udp 2122129151 192.168.1.100 57794 typ host generation 0 network-id 1
  798. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=candidate:1414432505 1 udp 1686052607 46.159.161.140 57792 typ srflx raddr 192.168.0.102 rport 57792 generation 0 network-id 3
  799. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=candidate:2609216701 1 tcp 1518280447 192.168.0.102 9 typ host tcptype active generation 0 network-id 3
  800. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=candidate:4233069003 1 tcp 1518214911 192.168.56.1 9 typ host tcptype active generation 0 network-id 2
  801. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=candidate:4247172264 1 tcp 1518149375 192.168.1.100 9 typ host tcptype active generation 0 network-id 1
  802. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ice-ufrag:IqBC5aTb1e50SUKi
  803. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ice-pwd:V2chuu0uZjXEnxUMukcz0b34
  804. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=fingerprint:sha-256 AD:1F:11:FC:6C:91:DC:4D:A3:5B:9C:F4:93:16:50:B0:81:B3:CB:D4:FE:62:2A:2C:A9:9A:F3:5A:0C:5C:44:20
  805. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=setup:active
  806. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=mid:audio
  807. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=rtcp-mux
  808. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ssrc:1546595284 cname:+d2oOSujhW7VK759
  809. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ssrc:1546595284 msid:8A92izLjuVeE3mQmph5V0EMsmUiXGRJ5byy5 053c5609-13b8-4081-a1b0-cb099a328ae6
  810. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ssrc:1546595284 mslabel:8A92izLjuVeE3mQmph5V0EMsmUiXGRJ5byy5
  811. 5b8b1c43-a010-4570-b282-6eb4a965a4db a=ssrc:1546595284 label:053c5609-13b8-4081-a1b0-cb099a328ae6
  812. 5b8b1c43-a010-4570-b282-6eb4a965a4db
  813. send 429 bytes to wss/[46.159.161.140]:56849 at 18:12:12.064682:
  814.    ------------------------------------------------------------------------
  815.    ACK sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws SIP/2.0
  816.    Via: SIP/2.0/WSS 134.213.206.157:7443;rport;branch=z9hG4bK6SjNBKeQNajmQ
  817.    Max-Forwards: 70
  818.    From: "incoming call (Russian)" <sip:280@3waytalk.com>;tag=a215vBXKrS20c
  819.    To: <sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws>;tag=fm2qiedr5v
  820.    Call-ID: 6d2f7341-eb12-1234-368a-bc764e08bec0
  821.    CSeq: 96040697 ACK
  822.    Contact: <sip:mod_sofia@134.213.206.157:5060>
  823.    Content-Length: 0
  824.    
  825.    ------------------------------------------------------------------------
  826. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] sofia.c:6937 Channel sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid entering state [ready][200]
  827. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
  828. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:4366 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  829. 2016-09-01 18:12:12.052597 [DEBUG] mod_opus.c:559 Opus encoder: set bitrate to local settings [72000bps]
  830. 2016-09-01 18:12:12.052597 [DEBUG] mod_opus.c:578 Opus encoder: CBR mode enabled
  831. 2016-09-01 18:12:12.052597 [DEBUG] mod_opus.c:559 Opus encoder: set bitrate to local settings [72000bps]
  832. 2016-09-01 18:12:12.052597 [DEBUG] mod_opus.c:578 Opus encoder: CBR mode enabled
  833. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:3018 Set Codec sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid opus/48000 20 ms 960 samples 0 bits 1 channels
  834. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_codec.c:111 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Original read codec set to opus:116
  835. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [WARNING] switch_core_media.c:3392 NO candidate ACL defined, Defaulting to wan.auto
  836. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:3422 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.0.102:57792
  837. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:3422 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.56.1:57793
  838. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:3422 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.1.100:57794
  839. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:3422 Save audio Candidate cid: 1 proto: udp type: srflx addr: 46.159.161.140:57792
  840. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:3464 Searching for rtp candidate.
  841. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:3469 Choose rtp candidate, index 3, 46.159.161.140:57792
  842. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:3247 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid choosing family v4
  843. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:3480 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 46.159.161.140:57792
  844. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:3525 setting remote audio ice addr to index 3 46.159.161.140:57792 based on candidate
  845. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:3546 Setting remote rtcp audio addr to 46.159.161.140:57792 based on candidate
  846. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:4646 No 2833 in SDP.  Disable 2833 dtmf and switch to INFO
  847. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:6465 AUDIO RTP [sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid] 134.213.206.157 port 22906 -> 46.159.161.140 port 57792 codec: 102 ms: 20
  848. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 960 bytes per 20ms
  849. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [INFO] switch_core_media.c:6640 Activating Audio ICE
  850. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [NOTICE] switch_rtp.c:4314 Activating RTP audio ICE: IqBC5aTb1e50SUKi:Hey5TkHwte2qp3Rf 46.159.161.140:57792
  851. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:6683 Activating RTCP PORT 57792
  852. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_rtp.c:4211 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 57792
  853. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [INFO] switch_core_media.c:6691 Skipping RTCP ICE (Same as RTP)
  854. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [INFO] switch_rtp.c:3328 Activate RTP/RTCP audio DTLS server
  855. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [INFO] switch_rtp.c:3459 Changing audio DTLS state from OFF to HANDSHAKE
  856. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:1989 Setting Jitterbuffer to 90ms (4 frames) (15 max frames)
  857. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_core_media.c:6794 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Set rtp dtmf delay to 40
  858. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [NOTICE] sofia.c:7970 Channel [sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid] has been answered
  859. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.052597 [DEBUG] switch_channel.c:3770 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Callstate Change RINGING -> ACTIVE
  860. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.072591 [DEBUG] switch_core_codec.c:246 sofia/internal/280@3waytalk.com Restore previous codec opus:116.
  861. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.072591 [DEBUG] switch_ivr_originate.c:3607 Originate Resulted in Success: [sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid]
  862. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.072591 [DEBUG] switch_channel.c:2047 (sofia/internal/280@3waytalk.com) Callstate Change RING_WAIT -> ACTIVE
  863. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.072591 [DEBUG] switch_ivr_originate.c:3607 Originate Resulted in Success: [sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid]
  864. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.072591 [DEBUG] switch_core_media.c:9640 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid PAUSE Jitterbuffer
  865. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.072591 [DEBUG] switch_core_media.c:9640 sofia/internal/280@3waytalk.com PAUSE Jitterbuffer
  866. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.072591 [DEBUG] switch_ivr_bridge.c:1599 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  867. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.072591 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Running State Change CS_EXCHANGE_MEDIA
  868. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.072591 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State EXCHANGE_MEDIA
  869. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.072591 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  870. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.072591 [DEBUG] switch_ivr_bridge.c:439 Setup generated silence from sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid to sofia/internal/280@3waytalk.com at 1400
  871. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.072591 [DEBUG] switch_ivr_bridge.c:439 Setup generated silence from sofia/internal/280@3waytalk.com to sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid at 1400
  872. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.112591 [DEBUG] switch_core_media.c:2474 [VBR]: Correcting calculated ptime value from [840] to [20] to compensate for [41] lost packet(s)
  873. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.112591 [DEBUG] mod_opus.c:661 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  874. 2016-09-01 18:12:12.112591 [DEBUG] mod_opus.c:559 Opus encoder: set bitrate to local settings [72000bps]
  875. 2016-09-01 18:12:12.112591 [DEBUG] mod_opus.c:578 Opus encoder: CBR mode enabled
  876. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.292592 [DEBUG] switch_core_media.c:9697 sofia/internal/280@3waytalk.com RESUME Jitterbuffer
  877. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.292592 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute conference(9d1e1675fa8a804e12512d65e0388421)
  878. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com conference(9d1e1675fa8a804e12512d65e0388421)
  879. 2016-09-01 18:12:12.292592 [DEBUG] mod_conference.c:3017 using channel sound prefix: /usr/share/freeswitch/sounds/ru/RU/elena
  880. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.292592 [DEBUG] conference_member.c:1654 Raw Codec Activation Success L16@48000hz 1 channel 20ms
  881. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.292592 [DEBUG] conference_member.c:1701 Raw Codec Activation Success L16@48000hz 1 channel 20ms
  882. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.292592 [DEBUG] switch_core_codec.c:221 sofia/internal/280@3waytalk.com Push codec L16:100
  883. 2016-09-01 18:12:12.292592 [DEBUG] mod_conference.c:220 Setup timer success interval: 20  samples: 960
  884. 2016-09-01 18:12:12.292592 [DEBUG] conference_member.c:128 sofia/internal/280@3waytalk.com binding '9' to 'energy up'
  885. 2016-09-01 18:12:12.292592 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
  886. 2016-09-01 18:12:12.292592 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fa3e557ab30 data: 0x7fa36431d8a0
  887. 2016-09-01 18:12:12.292592 [DEBUG] conference_member.c:128 sofia/internal/280@3waytalk.com binding '7' to 'energy dn'
  888. 2016-09-01 18:12:12.292592 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fa3e557ab30 data: 0x7fa36431d8d0
  889. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.292592 [DEBUG] switch_core_media.c:9640 sofia/internal/280@3waytalk.com PAUSE Jitterbuffer
  890. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.292592 [DEBUG] conference_loop.c:1101 Setup timer soft success interval: 20  samples: 960
  891. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.292592 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute transfer(-bleg 9d1e1675fa8a804e12512d65e0388421 XML conf)
  892. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com transfer(-bleg 9d1e1675fa8a804e12512d65e0388421 XML conf)
  893. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] switch_ivr.c:2160 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State Change CS_EXCHANGE_MEDIA -> CS_ROUTING
  894. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [NOTICE] switch_ivr.c:2167 Transfer sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid to XML[9d1e1675fa8a804e12512d65e0388421@conf]
  895. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] switch_ivr_bridge.c:785 BRIDGE THREAD DONE [sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid]
  896. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State EXCHANGE_MEDIA going to sleep
  897. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Running State Change CS_ROUTING
  898. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State ROUTING
  899. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] mod_sofia.c:143 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid SOFIA ROUTING
  900. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] switch_core_state_machine.c:236 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Standard ROUTING
  901. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [INFO] mod_dialplan_xml.c:637 Processing incoming call (Russian) <280>->9d1e1675fa8a804e12512d65e0388421 in context conf
  902. 5b8b1c43-a010-4570-b282-6eb4a965a4db Dialplan: sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid parsing [conf->conference] continue=false
  903. 5b8b1c43-a010-4570-b282-6eb4a965a4db Dialplan: sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Regex (PASS) [conference] destination_number(9d1e1675fa8a804e12512d65e0388421) =~ /^(.*)$/ break=on-false
  904. 5b8b1c43-a010-4570-b282-6eb4a965a4db Dialplan: sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Action socket(127.0.0.1:8085 async full)
  905. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [INFO] switch_channel.c:3127 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Flipping CID from "incoming call (Russian)" <280> to "Outbound Call" <qiin1j5e>
  906. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State Change CS_ROUTING -> CS_EXECUTE
  907. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State ROUTING going to sleep
  908. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Running State Change CS_EXECUTE
  909. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State EXECUTE
  910. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] mod_sofia.c:198 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid SOFIA EXECUTE
  911. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [DEBUG] switch_core_state_machine.c:328 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Standard EXECUTE
  912. 5b8b1c43-a010-4570-b282-6eb4a965a4db EXECUTE sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid socket(127.0.0.1:8085 async full)
  913. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.292592 [NOTICE] mod_event_socket.c:432 Trying host: 127.0.0.1:8085
  914. 2016-09-01 18:12:12.312591 [INFO] mod_conference.c:361 Auto recording file: /var/www/recordings/9d1e1675fa8a804e12512d65e0388421.wav
  915. 2016-09-01 18:12:12.312591 [DEBUG] conference_record.c:279 Setup timer success interval: 20  samples: 960
  916. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.372588 [INFO] switch_rtp.c:3119 Changing audio DTLS state from HANDSHAKE to SETUP
  917. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.392589 [DEBUG] switch_core_media.c:2474 [VBR]: Correcting calculated ptime value from [120] to [20] to compensate for [5] lost packet(s)
  918. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.392589 [INFO] switch_rtp.c:3028 audio Fingerprint Verified.
  919. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.392589 [INFO] switch_rtp.c:3644 Activating Audio Secure RTP SEND
  920. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.392589 [INFO] switch_rtp.c:3622 Activating Audio Secure RTP RECV
  921. 2016-09-01 18:12:12.392589 [DEBUG] switch_core_sqldb.c:2612 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
  922. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.392589 [INFO] switch_rtp.c:3068 Changing audio DTLS state from SETUP to READY
  923. 2016-09-01 18:12:12.392589 [DEBUG] switch_core_sqldb.c:2612 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
  924. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.392589 [DEBUG] switch_ivr.c:623 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Command Execute conference(9d1e1675fa8a804e12512d65e0388421)
  925. 5b8b1c43-a010-4570-b282-6eb4a965a4db EXECUTE sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid conference(9d1e1675fa8a804e12512d65e0388421)
  926. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.392589 [DEBUG] conference_member.c:1654 Raw Codec Activation Success L16@48000hz 1 channel 20ms
  927. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.392589 [DEBUG] conference_member.c:1701 Raw Codec Activation Success L16@48000hz 1 channel 20ms
  928. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.392589 [DEBUG] switch_core_codec.c:221 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Push codec L16:100
  929. 2016-09-01 18:12:12.392589 [DEBUG] conference_member.c:128 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid binding '9' to 'energy up'
  930. 2016-09-01 18:12:12.392589 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
  931. 2016-09-01 18:12:12.392589 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fa3e557ab30 data: 0x7fa34c1e2c00
  932. 2016-09-01 18:12:12.392589 [DEBUG] conference_member.c:128 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid binding '7' to 'energy dn'
  933. 2016-09-01 18:12:12.392589 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fa3e557ab30 data: 0x7fa34c1e2c30
  934. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.392589 [DEBUG] conference_loop.c:1101 Setup timer soft success interval: 20  samples: 960
  935. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.452588 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute jitterbuffer(debug:off)
  936. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com jitterbuffer(debug:off)
  937. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:12.452588 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute jitterbuffer(resume)
  938. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com jitterbuffer(resume)
  939. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:12.492587 [DEBUG] switch_rtp.c:1846 rtcp_stats_init: audio ssrc[1546595284] base_seq[23543]
  940. 2016-09-01 18:12:19.372586 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
  941. 2016-09-01 18:12:19.372586 [DEBUG] switch_ivr_originate.c:2580 Parsing session specific variables
  942. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [NOTICE] switch_channel.c:1104 New Channel sofia/external/+79001234567 [51d3d083-740d-4133-ab84-a6b169be9de0]
  943. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] mod_sofia.c:4754 (sofia/external/+79001234567) State Change CS_NEW -> CS_INIT
  944. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_core_session.c:613 sofia/external/+79001234567 set UUID=51d3d083-740d-4133-ab84-a6b169be9de0
  945. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_core_state_machine.c:543 (sofia/external/+79001234567) Running State Change CS_INIT
  946. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_core_state_machine.c:586 (sofia/external/+79001234567) State INIT
  947. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] mod_sofia.c:90 sofia/external/+79001234567 SOFIA INIT
  948. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] sofia_glue.c:1249 sofia/external/+79001234567 sending invite version: 1.7.0 -915-35ee4ee 64bit
  949. 51d3d083-740d-4133-ab84-a6b169be9de0 Local SDP:
  950. 51d3d083-740d-4133-ab84-a6b169be9de0 v=0
  951. 51d3d083-740d-4133-ab84-a6b169be9de0 o=FreeSWITCH 1472732073 1472732074 IN IP4 134.213.206.157
  952. 51d3d083-740d-4133-ab84-a6b169be9de0 s=FreeSWITCH
  953. 51d3d083-740d-4133-ab84-a6b169be9de0 c=IN IP4 134.213.206.157
  954. 51d3d083-740d-4133-ab84-a6b169be9de0 t=0 0
  955. 51d3d083-740d-4133-ab84-a6b169be9de0 m=audio 21466 RTP/AVP 8 0 101 13
  956. 51d3d083-740d-4133-ab84-a6b169be9de0 a=rtpmap:8 PCMA/8000
  957. 51d3d083-740d-4133-ab84-a6b169be9de0 a=rtpmap:0 PCMU/8000
  958. 51d3d083-740d-4133-ab84-a6b169be9de0 a=rtpmap:101 telephone-event/8000
  959. 51d3d083-740d-4133-ab84-a6b169be9de0 a=fmtp:101 0-16
  960. 51d3d083-740d-4133-ab84-a6b169be9de0 a=rtpmap:13 CN/8000
  961. 51d3d083-740d-4133-ab84-a6b169be9de0 a=ptime:20
  962. 51d3d083-740d-4133-ab84-a6b169be9de0 a=sendrecv
  963. 51d3d083-740d-4133-ab84-a6b169be9de0
  964. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_core_state_machine.c:40 sofia/external/+79001234567 Standard INIT
  965. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_core_state_machine.c:48 (sofia/external/+79001234567) State Change CS_INIT -> CS_ROUTING
  966. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_core_state_machine.c:586 (sofia/external/+79001234567) State INIT going to sleep
  967. send 1185 bytes to udp/[54.171.127.193]:5060 at 18:12:19.376996:
  968.    ------------------------------------------------------------------------
  969.    INVITE sip:+79001234567@3waytalk.pstn.ie1.twilio.com SIP/2.0
  970.    Via: SIP/2.0/UDP 134.213.206.157:5080;rport;branch=z9hG4bKU9NvDBUae3HrQ
  971.    Max-Forwards: 70
  972.    From: "" <sip:+441277420392@134.213.206.157>;tag=Ft6DDX0mX2mXc
  973.    To: <sip:+79001234567@3waytalk.pstn.ie1.twilio.com>
  974.    Call-ID: 766f85b5-eb12-1234-368a-bc764e08bec0
  975.    CSeq: 96040705 INVITE
  976.  
  977.    Contact: <sip:gw+Twilio-outbound@134.213.206.157:5080;transport=udp;gw=Twilio-outbound>
  978.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  979.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REFER, NOTIFY
  980.    Supported: timer, path, replaces
  981.    Allow-Events: talk, hold, conference, refer
  982.    Session-Expires: 1800;refresher=uac
  983.    Min-SE: 120
  984.    Content-Type: application/sdp
  985.    Content-Disposition: session
  986.    Content-Length: 274
  987.    X-FS-Support: update_display,send_info
  988.    Remote-Party-ID: <sip:+441277420392@134.213.206.157>;party=calling;screen=yes;privacy=off
  989.    
  990.    v=0
  991.    o=FreeSWITCH 1472732073 1472732074 IN IP4 134.213.206.157
  992.    s=FreeSWITCH
  993.    c=IN IP4 134.213.206.157
  994.    t=0 0
  995.    m=audio 21466 RTP/AVP 8 0 101 13
  996.    a=rtpmap:8 PCMA/8000
  997.    a=rtpmap:0 PCMU/8000
  998.    a=rtpmap:101 telephone-event/8000
  999.    a=fmtp:101 0-16
  1000.    a=rtpmap:13 CN/8000
  1001.    a=ptime:20
  1002.    ------------------------------------------------------------------------
  1003. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_core_state_machine.c:543 (sofia/external/+79001234567) Running State Change CS_ROUTING
  1004. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_core_state_machine.c:602 (sofia/external/+79001234567) State ROUTING
  1005. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] mod_sofia.c:143 sofia/external/+79001234567 SOFIA ROUTING
  1006. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_ivr_originate.c:67 (sofia/external/+79001234567) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1007. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_core_state_machine.c:602 (sofia/external/+79001234567) State ROUTING going to sleep
  1008. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_core_state_machine.c:543 (sofia/external/+79001234567) Running State Change CS_CONSUME_MEDIA
  1009. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] sofia.c:6937 Channel sofia/external/+79001234567 entering state [calling][0]
  1010. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_core_state_machine.c:621 (sofia/external/+79001234567) State CONSUME_MEDIA
  1011. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.372586 [DEBUG] switch_core_state_machine.c:621 (sofia/external/+79001234567) State CONSUME_MEDIA going to sleep
  1012. recv 361 bytes from udp/[54.171.127.193]:5060 at 18:12:19.392173:
  1013.    ------------------------------------------------------------------------
  1014.    SIP/2.0 100 Giving a try
  1015.    Via: SIP/2.0/UDP 134.213.206.157:5080;received=134.213.206.157;rport=5080;branch=z9hG4bKU9NvDBUae3HrQ
  1016.    From: "" <sip:+441277420392@134.213.206.157>;tag=Ft6DDX0mX2mXc
  1017.    To: <sip:+79001234567@3waytalk.pstn.ie1.twilio.com>
  1018.    Call-ID: 766f85b5-eb12-1234-368a-bc764e08bec0
  1019.    CSeq: 96040705 INVITE
  1020.    Server: Twilio Gateway
  1021.    Content-Length: 0
  1022.    
  1023.    ------------------------------------------------------------------------
  1024. recv 610 bytes from udp/[54.171.127.193]:5060 at 18:12:19.663291:
  1025.    ------------------------------------------------------------------------
  1026.    SIP/2.0 407 Proxy Authentication required
  1027.    CSeq: 96040705 INVITE
  1028.    Call-ID: 766f85b5-eb12-1234-368a-bc764e08bec0
  1029.    From: "" <sip:+441277420392@134.213.206.157>;tag=Ft6DDX0mX2mXc
  1030.    To: <sip:+79001234567@3waytalk.pstn.ie1.twilio.com>;tag=38114634_6772d868_8588d2da-cc47-40a2-bc09-44781d4b2646
  1031.    Via: SIP/2.0/UDP 134.213.206.157:5080;received=134.213.206.157;rport=5080;branch=z9hG4bKU9NvDBUae3HrQ
  1032.    Server: Twilio
  1033.    Contact: <sip:172.18.211.153:5060>
  1034.    Proxy-Authenticate: Digest realm="sip.twilio.com",qop="auth",nonce="c55064a1af158115e3504b058309bf0f",opaque="16f00e973dac288064caa33b910f0c68"
  1035.    Content-Length: 0
  1036.    
  1037.    ------------------------------------------------------------------------
  1038. send 414 bytes to udp/[54.171.127.193]:5060 at 18:12:19.663438:
  1039.    ------------------------------------------------------------------------
  1040.    ACK sip:+79001234567@3waytalk.pstn.ie1.twilio.com SIP/2.0
  1041.    Via: SIP/2.0/UDP 134.213.206.157:5080;rport;branch=z9hG4bKU9NvDBUae3HrQ
  1042.    Max-Forwards: 70
  1043.    From: "" <sip:+441277420392@134.213.206.157>;tag=Ft6DDX0mX2mXc
  1044.    To: <sip:+79001234567@3waytalk.pstn.ie1.twilio.com>;tag=38114634_6772d868_8588d2da-cc47-40a2-bc09-44781d4b2646
  1045.    Call-ID: 766f85b5-eb12-1234-368a-bc764e08bec0
  1046.    CSeq: 96040705 ACK
  1047.    Content-Length: 0
  1048.    
  1049.    ------------------------------------------------------------------------
  1050. send 1509 bytes to udp/[54.171.127.193]:5060 at 18:12:19.663717:
  1051.    ------------------------------------------------------------------------
  1052.    INVITE sip:+79001234567@3waytalk.pstn.ie1.twilio.com SIP/2.0
  1053.    Via: SIP/2.0/UDP 134.213.206.157:5080;rport;branch=z9hG4bKvjFNF6BeBc8aK
  1054.    Max-Forwards: 70
  1055.    From: "" <sip:+441277420392@134.213.206.157>;tag=Ft6DDX0mX2mXc
  1056.    To: <sip:+79001234567@3waytalk.pstn.ie1.twilio.com>
  1057.    Call-ID: 766f85b5-eb12-1234-368a-bc764e08bec0
  1058.    CSeq: 96040706 INVITE
  1059.    Contact: <sip:gw+Twilio-outbound@134.213.206.157:5080;transport=udp;gw=Twilio-outbound>
  1060.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  1061.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REFER, NOTIFY
  1062.    Supported: timer, path, replaces
  1063.    Allow-Events: talk, hold, conference, refer
  1064.    Proxy-Authorization: Digest username="02345", realm="sip.twilio.com", nonce="c55064a1af158115e3504b058309bf0f", cnonce="dptBZOsSEjSKNrx2Tgi+wA", opaque="16f00e973dac288064caa33b910f0c68", algorithm=MD5, uri="sip:+79001234567@3waytalk.pstn.ie1.twilio.com", response="d9a0c66afc6892cc9b029b232f4dfaa6", qop=auth, nc=00000001
  1065.    Session-Expires: 1800;refresher=uac
  1066.    Min-SE: 120
  1067.    Content-Type: application/sdp
  1068.    Content-Disposition: session
  1069.    Content-Length: 274
  1070.    X-FS-Support: update_display,send_info
  1071.    Remote-Party-ID: <sip:+441277420392@134.213.206.157>;party=calling;screen=yes;privacy=off
  1072.    
  1073.    v=0
  1074.    o=FreeSWITCH 1472732073 1472732074 IN IP4 134.213.206.157
  1075.    s=FreeSWITCH
  1076.    c=IN IP4 134.213.206.157
  1077.    t=0 0
  1078.    m=audio 21466 RTP/AVP 8 0 101 13
  1079.    a=rtpmap:8 PCMA/8000
  1080.    a=rtpmap:0 PCMU/8000
  1081.    a=rtpmap:101 telephone-event/8000
  1082.    a=fmtp:101 0-16
  1083.    a=rtpmap:13 CN/8000
  1084.    a=ptime:20
  1085.    ------------------------------------------------------------------------
  1086. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:19.652588 [DEBUG] sofia.c:6937 Channel sofia/external/+79001234567 entering state [calling][0]
  1087. recv 361 bytes from udp/[54.171.127.193]:5060 at 18:12:19.677973:
  1088.    ------------------------------------------------------------------------
  1089.    SIP/2.0 100 Giving a try
  1090.    Via: SIP/2.0/UDP 134.213.206.157:5080;received=134.213.206.157;rport=5080;branch=z9hG4bKvjFNF6BeBc8aK
  1091.    From: "" <sip:+441277420392@134.213.206.157>;tag=Ft6DDX0mX2mXc
  1092.    To: <sip:+79001234567@3waytalk.pstn.ie1.twilio.com>
  1093.    Call-ID: 766f85b5-eb12-1234-368a-bc764e08bec0
  1094.    CSeq: 96040706 INVITE
  1095.    Server: Twilio Gateway
  1096.    Content-Length: 0
  1097.    
  1098.    ------------------------------------------------------------------------
  1099. recv 767 bytes from udp/[89.163.148.171]:5070 at 18:12:27.523449:
  1100.    ------------------------------------------------------------------------
  1101.    INVITE sip:00441904891563@134.213.206.157 SIP/2.0
  1102.    To: 00441904891563<sip:00441904891563@134.213.206.157>
  1103.    From: 90000<sip:90000@134.213.206.157>;tag=217fb9db
  1104.    Via: SIP/2.0/UDP 89.163.148.171:5070;branch=z9hG4bK-7dc16171259d5a30313463c2ec3b752c;rport
  1105.    Call-ID: 7dc16171259d5a30313463c2ec3b752c
  1106.    CSeq: 1 INVITE
  1107.    Contact: <sip:90000@89.163.148.171:5070>
  1108.    Max-Forwards: 70
  1109.    Allow: INVITE, ACK, CANCEL, BYE
  1110.    User-Agent: sipcli/v1.8
  1111.    Content-Type: application/sdp
  1112.    Content-Length: 284
  1113.    
  1114.    v=0
  1115.    o=sipcli-Session 1276903744 965574643 IN IP4 89.163.148.171
  1116.    s=sipcli
  1117.    c=IN IP4 89.163.148.171
  1118.    t=0 0
  1119.    m=audio 5072 RTP/AVP 18 0 8 101
  1120.    a=fmtp:101 0-15
  1121.    a=rtpmap:18 G729/8000
  1122.    a=rtpmap:0 PCMU/8000
  1123.    a=rtpmap:8 PCMA/8000
  1124.    a=rtpmap:101 telephone-event/8000
  1125.    a=ptime:20
  1126.    a=sendrecv
  1127.    ------------------------------------------------------------------------
  1128. send 364 bytes to udp/[89.163.148.171]:5070 at 18:12:27.523717:
  1129.    ------------------------------------------------------------------------
  1130.    SIP/2.0 100 Trying
  1131.    Via: SIP/2.0/UDP 89.163.148.171:5070;branch=z9hG4bK-7dc16171259d5a30313463c2ec3b752c;rport=5070
  1132.    From: 90000<sip:90000@134.213.206.157>;tag=217fb9db
  1133.    To: 00441904891563<sip:00441904891563@134.213.206.157>
  1134.    Call-ID: 7dc16171259d5a30313463c2ec3b752c
  1135.    CSeq: 1 INVITE
  1136.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  1137.    Content-Length: 0
  1138.    
  1139.    ------------------------------------------------------------------------
  1140. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:27.512587 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/90000@134.213.206.157 [6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6]
  1141. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:27.512587 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/90000@134.213.206.157) Running State Change CS_NEW
  1142. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:27.512587 [DEBUG] sofia.c:9520 sofia/internal/90000@134.213.206.157 receiving invite from 89.163.148.171:5070 version: 1.7.0 -915-35ee4ee 64bit
  1143. 2016-09-01 18:12:27.512587 [DEBUG] sofia.c:9687 IP 89.163.148.171 Rejected by acl "domains". Falling back to Digest auth.
  1144. 2016-09-01 18:12:27.512587 [WARNING] sofia_reg.c:1787 SIP auth challenge (INVITE) on sofia profile 'internal' for [00441904891563@134.213.206.157] from ip 89.163.148.171
  1145. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:27.512587 [DEBUG] switch_core_state_machine.c:562 (sofia/internal/90000@134.213.206.157) State NEW
  1146. send 725 bytes to udp/[89.163.148.171]:5070 at 18:12:27.524735:
  1147.    ------------------------------------------------------------------------
  1148.    SIP/2.0 407 Proxy Authentication Required
  1149.    Via: SIP/2.0/UDP 89.163.148.171:5070;branch=z9hG4bK-7dc16171259d5a30313463c2ec3b752c;rport=5070
  1150.    From: 90000<sip:90000@134.213.206.157>;tag=217fb9db
  1151.    To: 00441904891563 <sip:00441904891563@134.213.206.157>;tag=BBUyy6DQN2rKr
  1152.    Call-ID: 7dc16171259d5a30313463c2ec3b752c
  1153.    CSeq: 1 INVITE
  1154.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  1155.    Accept: application/sdp
  1156.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1157.    Supported: timer, path, replaces
  1158.    Allow-Events: talk, hold, conference, refer
  1159.    Proxy-Authenticate: Digest realm="134.213.206.157", nonce="c45b1ac3-6b1b-4cac-8ddd-52ce5e5f96da", algorithm=MD5, qop="auth"
  1160.    Content-Length: 0
  1161.    
  1162.    ------------------------------------------------------------------------
  1163. 2016-09-01 18:12:27.512587 [DEBUG] sofia.c:2248 detaching session 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6
  1164. recv 560 bytes from udp/[54.171.127.193]:5060 at 18:12:27.793264:
  1165.    ------------------------------------------------------------------------
  1166.    SIP/2.0 180 Ringing
  1167.    CSeq: 96040706 INVITE
  1168.    Call-ID: 766f85b5-eb12-1234-368a-bc764e08bec0
  1169.    From: "" <sip:+441277420392@134.213.206.157>;tag=Ft6DDX0mX2mXc
  1170.    To: <sip:+79001234567@3waytalk.pstn.ie1.twilio.com>;tag=17511146_6772d868_457731f5-5d71-4662-9b26-c2603858a736
  1171.    Via: SIP/2.0/UDP 134.213.206.157:5080;received=134.213.206.157;rport=5080;branch=z9hG4bKvjFNF6BeBc8aK
  1172.    Record-Route: <sip:54.171.127.193:5060;lr;ftag=Ft6DDX0mX2mXc>
  1173.    Server: Twilio
  1174.    Contact: <sip:172.18.197.204:5060>
  1175.    X-Twilio-CallSid: CAaa11f79f30a6d8af6d7bcb46422d636e
  1176.    Content-Length: 0
  1177.    
  1178.    ------------------------------------------------------------------------
  1179. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:27.792592 [DEBUG] sofia.c:6937 Channel sofia/external/+79001234567 entering state [proceeding][180]
  1180. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:27.792592 [NOTICE] sofia.c:7045 Ring-Ready sofia/external/+79001234567!
  1181. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:27.792592 [DEBUG] switch_channel.c:3343 (sofia/external/+79001234567) Callstate Change DOWN -> RINGING
  1182. send 725 bytes to udp/[89.163.148.171]:5070 at 18:12:28.024701:
  1183.    ------------------------------------------------------------------------
  1184.    SIP/2.0 407 Proxy Authentication Required
  1185.    Via: SIP/2.0/UDP 89.163.148.171:5070;branch=z9hG4bK-7dc16171259d5a30313463c2ec3b752c;rport=5070
  1186.    From: 90000<sip:90000@134.213.206.157>;tag=217fb9db
  1187.    To: 00441904891563 <sip:00441904891563@134.213.206.157>;tag=BBUyy6DQN2rKr
  1188.    Call-ID: 7dc16171259d5a30313463c2ec3b752c
  1189.    CSeq: 1 INVITE
  1190.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  1191.    Accept: application/sdp
  1192.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1193.    Supported: timer, path, replaces
  1194.    Allow-Events: talk, hold, conference, refer
  1195.    Proxy-Authenticate: Digest realm="134.213.206.157", nonce="c45b1ac3-6b1b-4cac-8ddd-52ce5e5f96da", algorithm=MD5, qop="auth"
  1196.    Content-Length: 0
  1197.    
  1198.    ------------------------------------------------------------------------
  1199. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:28.492589 [DEBUG] switch_core_media.c:2474 [VBR]: Correcting calculated ptime value from [40] to [20] to compensate for [1] lost packet(s)
  1200. send 725 bytes to udp/[89.163.148.171]:5070 at 18:12:29.025178:
  1201.    ------------------------------------------------------------------------
  1202.    SIP/2.0 407 Proxy Authentication Required
  1203.    Via: SIP/2.0/UDP 89.163.148.171:5070;branch=z9hG4bK-7dc16171259d5a30313463c2ec3b752c;rport=5070
  1204.    From: 90000<sip:90000@134.213.206.157>;tag=217fb9db
  1205.    To: 00441904891563 <sip:00441904891563@134.213.206.157>;tag=BBUyy6DQN2rKr
  1206.    Call-ID: 7dc16171259d5a30313463c2ec3b752c
  1207.    CSeq: 1 INVITE
  1208.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  1209.    Accept: application/sdp
  1210.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1211.    Supported: timer, path, replaces
  1212.    Allow-Events: talk, hold, conference, refer
  1213.    Proxy-Authenticate: Digest realm="134.213.206.157", nonce="c45b1ac3-6b1b-4cac-8ddd-52ce5e5f96da", algorithm=MD5, qop="auth"
  1214.    Content-Length: 0
  1215.    
  1216.    ------------------------------------------------------------------------
  1217. send 725 bytes to udp/[89.163.148.171]:5070 at 18:12:31.026265:
  1218.    ------------------------------------------------------------------------
  1219.    SIP/2.0 407 Proxy Authentication Required
  1220.    Via: SIP/2.0/UDP 89.163.148.171:5070;branch=z9hG4bK-7dc16171259d5a30313463c2ec3b752c;rport=5070
  1221.    From: 90000<sip:90000@134.213.206.157>;tag=217fb9db
  1222.    To: 00441904891563 <sip:00441904891563@134.213.206.157>;tag=BBUyy6DQN2rKr
  1223.    Call-ID: 7dc16171259d5a30313463c2ec3b752c
  1224.    CSeq: 1 INVITE
  1225.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  1226.    Accept: application/sdp
  1227.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1228.    Supported: timer, path, replaces
  1229.    Allow-Events: talk, hold, conference, refer
  1230.    Proxy-Authenticate: Digest realm="134.213.206.157", nonce="c45b1ac3-6b1b-4cac-8ddd-52ce5e5f96da", algorithm=MD5, qop="auth"
  1231.    Content-Length: 0
  1232.    
  1233.    ------------------------------------------------------------------------
  1234. recv 838 bytes from udp/[54.171.127.193]:5060 at 18:12:32.794562:
  1235.    ------------------------------------------------------------------------
  1236.    SIP/2.0 200 OK
  1237.    CSeq: 96040706 INVITE
  1238.    Call-ID: 766f85b5-eb12-1234-368a-bc764e08bec0
  1239.    From: "" <sip:+441277420392@134.213.206.157>;tag=Ft6DDX0mX2mXc
  1240.    To: <sip:+79001234567@3waytalk.pstn.ie1.twilio.com>;tag=17511146_6772d868_457731f5-5d71-4662-9b26-c2603858a736
  1241.    Via: SIP/2.0/UDP 134.213.206.157:5080;received=134.213.206.157;rport=5080;branch=z9hG4bKvjFNF6BeBc8aK
  1242.    Record-Route: <sip:54.171.127.193:5060;lr;ftag=Ft6DDX0mX2mXc>
  1243.    Server: Twilio
  1244.    Contact: <sip:172.18.197.204:5060>
  1245.    Content-Type: application/sdp
  1246.    X-Twilio-CallSid: CAaa11f79f30a6d8af6d7bcb46422d636e
  1247.    Content-Length: 250
  1248.    
  1249.    v=0
  1250.    o=- 110710448 110710448 IN IP4 54.171.127.237
  1251.    s=Twilio Media Gateway
  1252.    c=IN IP4 54.171.127.237
  1253.    t=0 0
  1254.    m=audio 15410 RTP/AVP 0 101
  1255.    a=rtpmap:0 PCMU/8000
  1256.    a=rtpmap:101 telephone-event/8000
  1257.    a=fmtp:101 0-15
  1258.    a=maxptime:20
  1259.    a=ptime:20
  1260.    a=sendrecv
  1261.    ------------------------------------------------------------------------
  1262. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] sofia.c:6937 Channel sofia/external/+79001234567 entering state [completing][200]
  1263. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] sofia.c:6947 Remote SDP:
  1264. 51d3d083-740d-4133-ab84-a6b169be9de0 v=0
  1265. 51d3d083-740d-4133-ab84-a6b169be9de0 o=- 110710448 110710448 IN IP4 54.171.127.237
  1266. 51d3d083-740d-4133-ab84-a6b169be9de0 s=Twilio Media Gateway
  1267. 51d3d083-740d-4133-ab84-a6b169be9de0 c=IN IP4 54.171.127.237
  1268. 51d3d083-740d-4133-ab84-a6b169be9de0 t=0 0
  1269. 51d3d083-740d-4133-ab84-a6b169be9de0 m=audio 15410 RTP/AVP 0 101
  1270. 51d3d083-740d-4133-ab84-a6b169be9de0 a=rtpmap:0 PCMU/8000
  1271. 51d3d083-740d-4133-ab84-a6b169be9de0 a=rtpmap:101 telephone-event/8000
  1272. 51d3d083-740d-4133-ab84-a6b169be9de0 a=fmtp:101 0-15
  1273. 51d3d083-740d-4133-ab84-a6b169be9de0 a=maxptime:20
  1274. 51d3d083-740d-4133-ab84-a6b169be9de0 a=ptime:20
  1275. 51d3d083-740d-4133-ab84-a6b169be9de0
  1276. send 861 bytes to udp/[54.171.127.193]:5060 at 18:12:32.795542:
  1277.    ------------------------------------------------------------------------
  1278.    ACK sip:172.18.197.204:5060 SIP/2.0
  1279.    Via: SIP/2.0/UDP 134.213.206.157:5080;rport;branch=z9hG4bKXU8DH1vH8myXe
  1280.    Route: <sip:54.171.127.193:5060;lr;ftag=Ft6DDX0mX2mXc>
  1281.    Max-Forwards: 70
  1282.    From: "" <sip:+441277420392@134.213.206.157>;tag=Ft6DDX0mX2mXc
  1283.    To: <sip:+79001234567@3waytalk.pstn.ie1.twilio.com>;tag=17511146_6772d868_457731f5-5d71-4662-9b26-c2603858a736
  1284.    Call-ID: 766f85b5-eb12-1234-368a-bc764e08bec0
  1285.    CSeq: 96040706 ACK
  1286.    Contact: <sip:gw+Twilio-outbound@134.213.206.157:5080;transport=udp;gw=Twilio-outbound>
  1287.    Proxy-Authorization: Digest username="02345", realm="sip.twilio.com", nonce="c55064a1af158115e3504b058309bf0f", cnonce="dptBZOsSEjSKNrx2Tgi+wA", opaque="16f00e973dac288064caa33b910f0c68", algorithm=MD5, uri="sip:+79001234567@3waytalk.pstn.ie1.twilio.com", response="d9a0c66afc6892cc9b029b232f4dfaa6", qop=auth, nc=00000001
  1288.    Content-Length: 0
  1289.    
  1290.    ------------------------------------------------------------------------
  1291. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] sofia.c:6937 Channel sofia/external/+79001234567 entering state [ready][200]
  1292. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1293. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_media.c:4311 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1294. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_media.c:4366 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1295. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_media.c:4227 Set telephone-event payload to 101@8000
  1296. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_media.c:3018 Set Codec sofia/external/+79001234567 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  1297. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_codec.c:111 sofia/external/+79001234567 Original read codec set to PCMU:0
  1298. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_media.c:4580 Set telephone-event payload to 101@8000
  1299. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_media.c:4639 sofia/external/+79001234567 Set 2833 dtmf send payload to 101 recv payload to 101
  1300. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_media.c:6465 AUDIO RTP [sofia/external/+79001234567] 134.213.206.157 port 21466 -> 54.171.127.237 port 15410 codec: 0 ms: 20
  1301. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_rtp.c:3850 Not using a timer
  1302. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_media.c:1989 Setting Jitterbuffer to 60ms (3 frames) (50 max frames)
  1303. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_media.c:6764 sofia/external/+79001234567 Set 2833 dtmf send payload to 101
  1304. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_media.c:6771 sofia/external/+79001234567 Set 2833 dtmf receive payload to 101
  1305. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_media.c:6794 sofia/external/+79001234567 Set rtp dtmf delay to 40
  1306. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [NOTICE] sofia.c:7970 Channel [sofia/external/+79001234567] has been answered
  1307. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_channel.c:3770 (sofia/external/+79001234567) Callstate Change RINGING -> ACTIVE
  1308. 2016-09-01 18:12:32.792593 [DEBUG] switch_ivr_originate.c:3607 Originate Resulted in Success: [sofia/external/+79001234567]
  1309. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_ivr.c:2160 (sofia/external/+79001234567) State Change CS_CONSUME_MEDIA -> CS_ROUTING
  1310. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_state_machine.c:543 (sofia/external/+79001234567) Running State Change CS_ROUTING
  1311. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [NOTICE] switch_ivr.c:2167 Transfer sofia/external/+79001234567 to XML[9d1e1675fa8a804e12512d65e0388421@external]
  1312. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_state_machine.c:602 (sofia/external/+79001234567) State ROUTING
  1313. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] mod_sofia.c:143 sofia/external/+79001234567 SOFIA ROUTING
  1314. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_state_machine.c:236 sofia/external/+79001234567 Standard ROUTING
  1315. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [INFO] mod_dialplan_xml.c:637 Processing  <+441277420392>->9d1e1675fa8a804e12512d65e0388421 in context external
  1316. 51d3d083-740d-4133-ab84-a6b169be9de0 Dialplan: sofia/external/+79001234567 parsing [external->3waycall] continue=false
  1317. 51d3d083-740d-4133-ab84-a6b169be9de0 Dialplan: sofia/external/+79001234567 Regex (PASS) [3waycall] destination_number(9d1e1675fa8a804e12512d65e0388421) =~ /^(.*)$/ break=on-false
  1318. 51d3d083-740d-4133-ab84-a6b169be9de0 Dialplan: sofia/external/+79001234567 Action socket(127.0.0.1:8085 async full)
  1319. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [INFO] switch_channel.c:3127 sofia/external/+79001234567 Flipping CID from "" <+441277420392> to "Outbound Call" <+79001234567>
  1320. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+79001234567) State Change CS_ROUTING -> CS_EXECUTE
  1321. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_state_machine.c:602 (sofia/external/+79001234567) State ROUTING going to sleep
  1322. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_state_machine.c:543 (sofia/external/+79001234567) Running State Change CS_EXECUTE
  1323. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_state_machine.c:609 (sofia/external/+79001234567) State EXECUTE
  1324. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] mod_sofia.c:198 sofia/external/+79001234567 SOFIA EXECUTE
  1325. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [DEBUG] switch_core_state_machine.c:328 sofia/external/+79001234567 Standard EXECUTE
  1326. 51d3d083-740d-4133-ab84-a6b169be9de0 EXECUTE sofia/external/+79001234567 socket(127.0.0.1:8085 async full)
  1327. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.792593 [NOTICE] mod_event_socket.c:432 Trying host: 127.0.0.1:8085
  1328. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.872589 [DEBUG] switch_rtp.c:6711 Correct audio ip/port confirmed.
  1329. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.892589 [DEBUG] switch_ivr.c:623 sofia/external/+79001234567 Command Execute set(jitterbuffer_msec=60:200:20)
  1330. 51d3d083-740d-4133-ab84-a6b169be9de0 EXECUTE sofia/external/+79001234567 set(jitterbuffer_msec=60:200:20)
  1331. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.892589 [DEBUG] mod_dptools.c:1519 SET sofia/external/+79001234567 [jitterbuffer_msec]=[60:200:20]
  1332. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.892589 [DEBUG] switch_ivr.c:623 sofia/external/+79001234567 Command Execute answer(undefined)
  1333. 51d3d083-740d-4133-ab84-a6b169be9de0 EXECUTE sofia/external/+79001234567 answer(undefined)
  1334. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.952589 [DEBUG] switch_ivr.c:623 sofia/external/+79001234567 Command Execute conference(9d1e1675fa8a804e12512d65e0388421)
  1335. 51d3d083-740d-4133-ab84-a6b169be9de0 EXECUTE sofia/external/+79001234567 conference(9d1e1675fa8a804e12512d65e0388421)
  1336. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.952589 [DEBUG] conference_member.c:1654 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  1337. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.952589 [DEBUG] conference_member.c:1701 Raw Codec Activation Success L16@48000hz 1 channel 20ms
  1338. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.952589 [DEBUG] switch_core_codec.c:221 sofia/external/+79001234567 Push codec L16:100
  1339. 2016-09-01 18:12:32.952589 [DEBUG] conference_member.c:128 sofia/external/+79001234567 binding '9' to 'energy up'
  1340. 2016-09-01 18:12:32.952589 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
  1341. 2016-09-01 18:12:32.952589 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fa3e557ab30 data: 0x7fa3501df450
  1342. 2016-09-01 18:12:32.952589 [DEBUG] conference_member.c:128 sofia/external/+79001234567 binding '7' to 'energy dn'
  1343. 2016-09-01 18:12:32.952589 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fa3e557ab30 data: 0x7fa3501df480
  1344. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.952589 [DEBUG] conference_loop.c:1101 Setup timer soft success interval: 20  samples: 160
  1345. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:32.972588 [NOTICE] switch_core_io.c:1202 Activating write resampler
  1346. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:33.212590 [DEBUG] conference_member.c:1654 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  1347. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:33.212590 [DEBUG] conference_member.c:1701 Raw Codec Activation Success L16@48000hz 1 channel 20ms
  1348. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:33.212590 [DEBUG] conference_loop.c:1101 Setup timer soft success interval: 20  samples: 160
  1349. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:33.512588 [DEBUG] switch_core_media.c:2474 [VBR]: Correcting calculated ptime value from [40] to [20] to compensate for [1] lost packet(s)
  1350. send 725 bytes to udp/[89.163.148.171]:5070 at 18:12:35.026549:
  1351.    ------------------------------------------------------------------------
  1352.    SIP/2.0 407 Proxy Authentication Required
  1353.    Via: SIP/2.0/UDP 89.163.148.171:5070;branch=z9hG4bK-7dc16171259d5a30313463c2ec3b752c;rport=5070
  1354.    From: 90000<sip:90000@134.213.206.157>;tag=217fb9db
  1355.    To: 00441904891563 <sip:00441904891563@134.213.206.157>;tag=BBUyy6DQN2rKr
  1356.    Call-ID: 7dc16171259d5a30313463c2ec3b752c
  1357.    CSeq: 1 INVITE
  1358.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  1359.    Accept: application/sdp
  1360.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1361.    Supported: timer, path, replaces
  1362.    Allow-Events: talk, hold, conference, refer
  1363.    Proxy-Authenticate: Digest realm="134.213.206.157", nonce="c45b1ac3-6b1b-4cac-8ddd-52ce5e5f96da", algorithm=MD5, qop="auth"
  1364.    Content-Length: 0
  1365.    
  1366.    ------------------------------------------------------------------------
  1367. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [WARNING] switch_core_state_machine.c:646 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 sofia/internal/90000@134.213.206.157 Abandoned
  1368. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [NOTICE] switch_core_state_machine.c:649 Hangup sofia/internal/90000@134.213.206.157 [CS_NEW] [WRONG_CALL_STATE]
  1369. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/90000@134.213.206.157) Running State Change CS_HANGUP
  1370. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/90000@134.213.206.157) Callstate Change DOWN -> HANGUP
  1371. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/90000@134.213.206.157) State HANGUP
  1372. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] mod_sofia.c:438 Channel sofia/internal/90000@134.213.206.157 hanging up, cause: WRONG_CALL_STATE
  1373. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:60 sofia/internal/90000@134.213.206.157 Standard HANGUP, cause: WRONG_CALL_STATE
  1374. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/90000@134.213.206.157) State HANGUP going to sleep
  1375. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/90000@134.213.206.157) State Change CS_HANGUP -> CS_REPORTING
  1376. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/90000@134.213.206.157) Running State Change CS_REPORTING
  1377. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/90000@134.213.206.157) State REPORTING
  1378. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:174 sofia/internal/90000@134.213.206.157 Standard REPORTING, cause: WRONG_CALL_STATE
  1379. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/90000@134.213.206.157) State REPORTING going to sleep
  1380. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/90000@134.213.206.157) State Change CS_REPORTING -> CS_DESTROY
  1381. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_session.c:1647 Session 213 (sofia/internal/90000@134.213.206.157) Locked, Waiting on external entities
  1382. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [NOTICE] switch_core_session.c:1665 Session 213 (sofia/internal/90000@134.213.206.157) Ended
  1383. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/90000@134.213.206.157 [CS_DESTROY]
  1384. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/90000@134.213.206.157) Running State Change CS_DESTROY
  1385. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/90000@134.213.206.157) State DESTROY
  1386. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] mod_sofia.c:343 sofia/internal/90000@134.213.206.157 SOFIA DESTROY
  1387. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:181 sofia/internal/90000@134.213.206.157 Standard DESTROY
  1388. 6fa5e3ba-96d2-4ceb-b3c4-a52339fbf6d6 2016-09-01 18:12:37.552595 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/90000@134.213.206.157) State DESTROY going to sleep
  1389. send 725 bytes to udp/[89.163.148.171]:5070 at 18:12:39.026787:
  1390.    ------------------------------------------------------------------------
  1391.    SIP/2.0 407 Proxy Authentication Required
  1392.    Via: SIP/2.0/UDP 89.163.148.171:5070;branch=z9hG4bK-7dc16171259d5a30313463c2ec3b752c;rport=5070
  1393.    From: 90000<sip:90000@134.213.206.157>;tag=217fb9db
  1394.    To: 00441904891563 <sip:00441904891563@134.213.206.157>;tag=BBUyy6DQN2rKr
  1395.    Call-ID: 7dc16171259d5a30313463c2ec3b752c
  1396.    CSeq: 1 INVITE
  1397.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  1398.    Accept: application/sdp
  1399.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1400.    Supported: timer, path, replaces
  1401.    Allow-Events: talk, hold, conference, refer
  1402.    Proxy-Authenticate: Digest realm="134.213.206.157", nonce="c45b1ac3-6b1b-4cac-8ddd-52ce5e5f96da", algorithm=MD5, qop="auth"
  1403.    Content-Length: 0
  1404.    
  1405.    ------------------------------------------------------------------------
  1406. recv 765 bytes from wss/[46.159.161.140]:56849 at 18:12:39.655113:
  1407.    ------------------------------------------------------------------------
  1408.    REGISTER sip:3waytalk.com SIP/2.0
  1409.    Via: SIP/2.0/WSS bvhvt1dfbk4m.invalid;branch=z9hG4bK5077393
  1410.    Max-Forwards: 70
  1411.    To: <sip:2183@3waytalk.com>
  1412.    From: <sip:2183@3waytalk.com>;tag=58kqj6906r
  1413.    Call-ID: tpvh45d1k0vvdqmpf5m86u
  1414.    CSeq: 85 REGISTER
  1415.    Authorization: Digest algorithm=MD5, username="2183", realm="3waytalk.com", nonce="6b337996-e90b-4087-aada-a5875132f88e", uri="sip:3waytalk.com", response="a9959700b600305be674905d5b2f249d", qop=auth, cnonce="0ea45i7s00l1", nc=00000001
  1416.    Contact: <sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws>;reg-id=1;+sip.instance="<urn:uuid:bc182ad2-20ca-4b4e-b5a1-cd1dfd718967>";expires=60
  1417.   Allow: ACK,CANCEL,INVITE,MESSAGE,BYE,OPTIONS,INFO,NOTIFY,REFER
  1418.    Supported: path, gruu, outbound
  1419.    User-Agent: SIP.js/0.7.5
  1420.    Content-Length: 0
  1421.    
  1422.    ------------------------------------------------------------------------
  1423. 2016-09-01 18:12:39.652592 [WARNING] sofia_reg.c:1787 SIP auth challenge (REGISTER) on sofia profile 'internal' for [2183@3waytalk.com] from ip 46.159.161.140
  1424. send 603 bytes to wss/[46.159.161.140]:56849 at 18:12:39.656184:
  1425.    ------------------------------------------------------------------------
  1426.    SIP/2.0 401 Unauthorized
  1427.    Via: SIP/2.0/WSS bvhvt1dfbk4m.invalid;branch=z9hG4bK5077393;received=46.159.161.140;rport=56849
  1428.    From: <sip:2183@3waytalk.com>;tag=58kqj6906r
  1429.    To: <sip:2183@3waytalk.com>;tag=cmmQ01ytjBF6K
  1430.    Call-ID: tpvh45d1k0vvdqmpf5m86u
  1431.    CSeq: 85 REGISTER
  1432.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  1433.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1434.    Supported: timer, path, replaces
  1435.    WWW-Authenticate: Digest realm="3waytalk.com", nonce="5fe3e0b3-977b-4899-819a-d42ccb5d24fb", stale=true, algorithm=MD5, qop="auth"
  1436.    Content-Length: 0
  1437.    
  1438.    ------------------------------------------------------------------------
  1439. recv 765 bytes from wss/[46.159.161.140]:56849 at 18:12:39.755273:
  1440.    ------------------------------------------------------------------------
  1441.    REGISTER sip:3waytalk.com SIP/2.0
  1442.    Via: SIP/2.0/WSS bvhvt1dfbk4m.invalid;branch=z9hG4bK9356754
  1443.    Max-Forwards: 70
  1444.    To: <sip:2183@3waytalk.com>
  1445.    From: <sip:2183@3waytalk.com>;tag=58kqj6906r
  1446.    Call-ID: tpvh45d1k0vvdqmpf5m86u
  1447.    CSeq: 86 REGISTER
  1448.    Authorization: Digest algorithm=MD5, username="2183", realm="3waytalk.com", nonce="5fe3e0b3-977b-4899-819a-d42ccb5d24fb", uri="sip:3waytalk.com", response="4e3457b5b3a26693298600d4a866f67a", qop=auth, cnonce="7dov08ackk02", nc=00000001
  1449.    Contact: <sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws>;reg-id=1;+sip.instance="<urn:uuid:bc182ad2-20ca-4b4e-b5a1-cd1dfd718967>";expires=60
  1450.   Allow: ACK,CANCEL,INVITE,MESSAGE,BYE,OPTIONS,INFO,NOTIFY,REFER
  1451.    Supported: path, gruu, outbound
  1452.    User-Agent: SIP.js/0.7.5
  1453.    Content-Length: 0
  1454.    
  1455.    ------------------------------------------------------------------------
  1456. 2016-09-01 18:12:39.752589 [NOTICE] switch_cpp.cpp:1360 directory request
  1457. 2016-09-01 18:12:39.892592 [NOTICE] switch_cpp.cpp:1360 Authenticated user 2183
  1458. send 568 bytes to wss/[46.159.161.140]:56849 at 18:12:39.903212:
  1459.    ------------------------------------------------------------------------
  1460.    SIP/2.0 200 OK
  1461.    Via: SIP/2.0/WSS bvhvt1dfbk4m.invalid;branch=z9hG4bK9356754;received=46.159.161.140;rport=56849
  1462.    From: <sip:2183@3waytalk.com>;tag=58kqj6906r
  1463.    To: <sip:2183@3waytalk.com>;tag=DXDg2vFyFm5rF
  1464.    Call-ID: tpvh45d1k0vvdqmpf5m86u
  1465.    CSeq: 86 REGISTER
  1466.    Contact: <sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws>;expires=60
  1467.    Date: Thu, 01 Sep 2016 18:12:39 GMT
  1468.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  1469.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1470.    Supported: timer, path, replaces
  1471.    Content-Length: 0
  1472.    
  1473.    ------------------------------------------------------------------------
  1474. recv 687 bytes from udp/[54.171.127.193]:5060 at 18:12:40.236422:
  1475.    ------------------------------------------------------------------------
  1476.    BYE sip:gw+Twilio-outbound@134.213.206.157:5080;transport=udp;gw=Twilio-outbound SIP/2.0
  1477.    CSeq: 1 BYE
  1478.    From: <sip:+79001234567@3waytalk.pstn.ie1.twilio.com>;tag=17511146_6772d868_457731f5-5d71-4662-9b26-c2603858a736
  1479.    To: "" <sip:+441277420392@134.213.206.157>;tag=Ft6DDX0mX2mXc
  1480.    Call-ID: 766f85b5-eb12-1234-368a-bc764e08bec0
  1481.    Max-Forwards: 68
  1482.    Via: SIP/2.0/UDP 54.171.127.193:5060;branch=z9hG4bK54a8.126ea8b.0
  1483.    Via: SIP/2.0/UDP 172.18.197.204:5060;rport=5060;received=54.171.127.237;branch=z9hG4bK457731f5-5d71-4662-9b26-c2603858a736_6772d868_566126959637384
  1484.    Reason: Q.850;cause=16
  1485.    User-Agent: Twilio Gateway
  1486.    X-Twilio-CallSid: CAaa11f79f30a6d8af6d7bcb46422d636e
  1487.    Content-Length: 0
  1488.    
  1489.    ------------------------------------------------------------------------
  1490. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [NOTICE] sofia.c:988 Hangup sofia/external/+79001234567 [CS_EXECUTE] [NORMAL_CLEARING]
  1491. send 661 bytes to udp/[54.171.127.193]:5060 at 18:12:40.237598:
  1492.    ------------------------------------------------------------------------
  1493.    SIP/2.0 200 OK
  1494.    Via: SIP/2.0/UDP 54.171.127.193:5060;branch=z9hG4bK54a8.126ea8b.0
  1495.    Via: SIP/2.0/UDP 172.18.197.204:5060;rport=5060;received=54.171.127.237;branch=z9hG4bK457731f5-5d71-4662-9b26-c2603858a736_6772d868_566126959637384
  1496.    From: <sip:+79001234567@3waytalk.pstn.ie1.twilio.com>;tag=17511146_6772d868_457731f5-5d71-4662-9b26-c2603858a736
  1497.    To: "" <sip:+441277420392@134.213.206.157>;tag=Ft6DDX0mX2mXc
  1498.    Call-ID: 766f85b5-eb12-1234-368a-bc764e08bec0
  1499.    CSeq: 1 BYE
  1500.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  1501.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REFER, NOTIFY
  1502.    Supported: timer, path, replaces
  1503.    Content-Length: 0
  1504.    
  1505.    ------------------------------------------------------------------------
  1506. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [INFO] conference_loop.c:1400 Channel leaving conference, cause: NORMAL_CLEARING
  1507. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] mod_conference.c:2215 sofia/external/+79001234567 skip receive message [UNBRIDGE] (channel is hungup already)
  1508. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_media.c:9330 sofia/external/+79001234567 skip receive message [HARD_MUTE] (channel is hungup already)
  1509. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_codec.c:258 Restore original codec.
  1510. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_session.c:2797 sofia/external/+79001234567 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1511. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_ivr.c:666 sofia/external/+79001234567 skip receive message [AUDIO_SYNC] (channel is hungup already)
  1512. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_session.c:2797 sofia/external/+79001234567 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1513. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_state_machine.c:609 (sofia/external/+79001234567) State EXECUTE going to sleep
  1514. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_state_machine.c:543 (sofia/external/+79001234567) Running State Change CS_HANGUP
  1515. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_state_machine.c:809 (sofia/external/+79001234567) Callstate Change ACTIVE -> HANGUP
  1516. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_state_machine.c:811 (sofia/external/+79001234567) State HANGUP
  1517. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] mod_sofia.c:438 Channel sofia/external/+79001234567 hanging up, cause: NORMAL_CLEARING
  1518. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_state_machine.c:60 sofia/external/+79001234567 Standard HANGUP, cause: NORMAL_CLEARING
  1519. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_state_machine.c:811 (sofia/external/+79001234567) State HANGUP going to sleep
  1520. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_state_machine.c:578 (sofia/external/+79001234567) State Change CS_HANGUP -> CS_REPORTING
  1521. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_state_machine.c:543 (sofia/external/+79001234567) Running State Change CS_REPORTING
  1522. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_state_machine.c:897 (sofia/external/+79001234567) State REPORTING
  1523. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_state_machine.c:174 sofia/external/+79001234567 Standard REPORTING, cause: NORMAL_CLEARING
  1524. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_state_machine.c:897 (sofia/external/+79001234567) State REPORTING going to sleep
  1525. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_state_machine.c:569 (sofia/external/+79001234567) State Change CS_REPORTING -> CS_DESTROY
  1526. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.232589 [DEBUG] switch_core_session.c:1647 Session 212 (sofia/external/+79001234567) Locked, Waiting on external entities
  1527. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.252589 [DEBUG] mod_event_socket.c:1441 sofia/external/+79001234567 Socket Linger -1
  1528. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.452593 [NOTICE] switch_core_session.c:1665 Session 212 (sofia/external/+79001234567) Ended
  1529. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.452593 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/+79001234567 [CS_DESTROY]
  1530. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.452593 [DEBUG] switch_core_state_machine.c:700 (sofia/external/+79001234567) Running State Change CS_DESTROY
  1531. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.452593 [DEBUG] switch_core_state_machine.c:710 (sofia/external/+79001234567) State DESTROY
  1532. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.452593 [DEBUG] mod_sofia.c:343 sofia/external/+79001234567 SOFIA DESTROY
  1533. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.452593 [DEBUG] switch_core_state_machine.c:181 sofia/external/+79001234567 Standard DESTROY
  1534. 51d3d083-740d-4133-ab84-a6b169be9de0 2016-09-01 18:12:40.452593 [DEBUG] switch_core_state_machine.c:710 (sofia/external/+79001234567) State DESTROY going to sleep
  1535. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.512591 [DEBUG] switch_ivr.c:623 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Command Execute hangup(undefined)
  1536. 5b8b1c43-a010-4570-b282-6eb4a965a4db EXECUTE sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid hangup(undefined)
  1537. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.512591 [NOTICE] mod_dptools.c:1283 Hangup sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid [CS_EXECUTE] [NORMAL_CLEARING]
  1538. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.512591 [DEBUG] switch_core_session.c:2797 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1539. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.532589 [INFO] conference_loop.c:1400 Channel leaving conference, cause: NONE
  1540. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.532589 [DEBUG] switch_core_media.c:9697 sofia/internal/280@3waytalk.com RESUME Jitterbuffer
  1541. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.532589 [DEBUG] switch_core_codec.c:246 sofia/internal/280@3waytalk.com Restore previous codec opus:116.
  1542. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [INFO] conference_loop.c:1400 Channel leaving conference, cause: NORMAL_CLEARING
  1543. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] mod_conference.c:2215 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid skip receive message [UNBRIDGE] (channel is hungup already)
  1544. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_media.c:9330 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid skip receive message [HARD_MUTE] (channel is hungup already)
  1545. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_codec.c:246 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Restore previous codec opus:116.
  1546. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_session.c:2797 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1547. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_ivr.c:666 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid skip receive message [AUDIO_SYNC] (channel is hungup already)
  1548. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_session.c:2797 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1549. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State EXECUTE going to sleep
  1550. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Running State Change CS_HANGUP
  1551. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Callstate Change ACTIVE -> HANGUP
  1552. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State HANGUP
  1553. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] mod_sofia.c:438 Channel sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid hanging up, cause: NORMAL_CLEARING
  1554. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid
  1555. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_state_machine.c:60 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Standard HANGUP, cause: NORMAL_CLEARING
  1556. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State HANGUP going to sleep
  1557. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State Change CS_HANGUP -> CS_REPORTING
  1558. send 611 bytes to wss/[46.159.161.140]:56849 at 18:12:43.548930:
  1559.    ------------------------------------------------------------------------
  1560. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Running State Change CS_REPORTING
  1561.    BYE sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws SIP/2.0
  1562.    Via: SIP/2.0/WSS 134.213.206.157:7443;rport;branch=z9hG4bK72BeDeZtjK86j
  1563.    Max-Forwards: 70
  1564.    From: "incoming call (Russian)" <sip:280@3waytalk.com>;tag=a215vBXKrS20c
  1565.    To: <sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws>;tag=fm2qiedr5v
  1566.    Call-ID: 6d2f7341-eb12-1234-368a-bc764e08bec0
  1567.    CSeq: 96040698 BYE
  1568.    User-Agent: FreeSWITCH-mod_sofia/1.7.0-915-35ee4ee~64bit
  1569.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1570.    Supported: timer, path, replaces
  1571.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  1572.    Content-Length: 0
  1573.    
  1574.    ------------------------------------------------------------------------
  1575. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State REPORTING
  1576. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_state_machine.c:174 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Standard REPORTING, cause: NORMAL_CLEARING
  1577. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State REPORTING going to sleep
  1578. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State Change CS_REPORTING -> CS_DESTROY
  1579. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] switch_core_session.c:1647 Session 211 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Locked, Waiting on external entities
  1580. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.532589 [DEBUG] mod_event_socket.c:1441 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Socket Linger -1
  1581. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_ivr_bridge.c:706 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid ending bridge by request from write function
  1582. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_ivr_bridge.c:785 BRIDGE THREAD DONE [sofia/internal/280@3waytalk.com]
  1583. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_ivr.c:623 sofia/internal/280@3waytalk.com Command Execute hangup(undefined)
  1584. a39771e4-fbf1-4964-b499-e9843b7bfe59 EXECUTE sofia/internal/280@3waytalk.com hangup(undefined)
  1585. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [NOTICE] mod_dptools.c:1283 Hangup sofia/internal/280@3waytalk.com [CS_EXECUTE] [NORMAL_CLEARING]
  1586. 2016-09-01 18:12:43.552586 [DEBUG] mod_conference.c:782 Write Lock ON
  1587. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_session.c:2797 sofia/internal/280@3waytalk.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1588. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_ivr.c:666 sofia/internal/280@3waytalk.com skip receive message [AUDIO_SYNC] (channel is hungup already)
  1589. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_ivr.c:666 sofia/internal/280@3waytalk.com skip receive message [AUDIO_SYNC] (channel is hungup already)
  1590. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_session.c:2797 sofia/internal/280@3waytalk.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1591. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/280@3waytalk.com) State EXECUTE going to sleep
  1592. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/280@3waytalk.com) Running State Change CS_HANGUP
  1593. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/280@3waytalk.com) Callstate Change ACTIVE -> HANGUP
  1594. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/280@3waytalk.com) State HANGUP
  1595. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] mod_sofia.c:438 Channel sofia/internal/280@3waytalk.com hanging up, cause: NORMAL_CLEARING
  1596. 2016-09-01 18:12:43.552586 [INFO] conference_record.c:394 Recording of {channels=1,samplerate=48000,vw=0,vh=0,fps=0.00}/var/www/recordings/9d1e1675fa8a804e12512d65e0388421.wav Stopped
  1597. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/280@3waytalk.com
  1598. 2016-09-01 18:12:43.552586 [DEBUG] mod_conference.c:785 Write Lock OFF
  1599. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_state_machine.c:60 sofia/internal/280@3waytalk.com Standard HANGUP, cause: NORMAL_CLEARING
  1600. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/280@3waytalk.com) State HANGUP going to sleep
  1601. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/280@3waytalk.com) State Change CS_HANGUP -> CS_REPORTING
  1602. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/280@3waytalk.com) Running State Change CS_REPORTING
  1603. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/280@3waytalk.com) State REPORTING
  1604. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_state_machine.c:174 sofia/internal/280@3waytalk.com Standard REPORTING, cause: NORMAL_CLEARING
  1605. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/280@3waytalk.com) State REPORTING going to sleep
  1606. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/280@3waytalk.com) State Change CS_REPORTING -> CS_DESTROY
  1607. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] switch_core_session.c:1647 Session 210 (sofia/internal/280@3waytalk.com) Locked, Waiting on external entities
  1608. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.552586 [DEBUG] mod_event_socket.c:1441 sofia/internal/280@3waytalk.com Socket Linger -1
  1609. recv 367 bytes from wss/[46.159.161.140]:56849 at 18:12:43.645543:
  1610.    ------------------------------------------------------------------------
  1611.    SIP/2.0 200 OK
  1612.    Via: SIP/2.0/WSS 134.213.206.157:7443;rport;branch=z9hG4bK72BeDeZtjK86j
  1613.    To: <sip:qiin1j5e@bvhvt1dfbk4m.invalid;transport=ws>;tag=fm2qiedr5v
  1614.    From: "incoming call (Russian)" <sip:280@3waytalk.com>;tag=a215vBXKrS20c
  1615.    Call-ID: 6d2f7341-eb12-1234-368a-bc764e08bec0
  1616.    CSeq: 96040698 BYE
  1617.    Supported: outbound
  1618.    User-Agent: SIP.js/0.7.5
  1619.    Content-Length: 0
  1620.    
  1621.    ------------------------------------------------------------------------
  1622. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.932588 [NOTICE] switch_core_session.c:1665 Session 210 (sofia/internal/280@3waytalk.com) Ended
  1623. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.932588 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/280@3waytalk.com [CS_DESTROY]
  1624. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.932588 [NOTICE] switch_core_session.c:1665 Session 211 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Ended
  1625. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.932588 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid [CS_DESTROY]
  1626. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.932588 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/280@3waytalk.com) Running State Change CS_DESTROY
  1627. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.932588 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) Running State Change CS_DESTROY
  1628. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.932588 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State DESTROY
  1629. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.932588 [DEBUG] mod_sofia.c:343 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid SOFIA DESTROY
  1630. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.932588 [DEBUG] mod_opus.c:661 Opus decoder stats: Frames[1551] PLC[0] FEC[0]
  1631. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.932588 [DEBUG] mod_opus.c:661 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  1632. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.932588 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/280@3waytalk.com) State DESTROY
  1633. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.932588 [DEBUG] mod_sofia.c:343 sofia/internal/280@3waytalk.com SOFIA DESTROY
  1634. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.932588 [DEBUG] mod_opus.c:661 Opus decoder stats: Frames[1951] PLC[0] FEC[0]
  1635. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.932588 [DEBUG] mod_opus.c:661 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  1636. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.932588 [DEBUG] switch_core_state_machine.c:181 sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid Standard DESTROY
  1637. 5b8b1c43-a010-4570-b282-6eb4a965a4db 2016-09-01 18:12:43.932588 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/qiin1j5e@bvhvt1dfbk4m.invalid) State DESTROY going to sleep
  1638. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.932588 [DEBUG] switch_core_state_machine.c:181 sofia/internal/280@3waytalk.com Standard DESTROY
  1639. a39771e4-fbf1-4964-b499-e9843b7bfe59 2016-09-01 18:12:43.932588 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/280@3waytalk.com) State DESTROY going to sleep