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