1. [m[33m[44m.=======================================================.
2. |            _____ ____     ____ _     ___              |
3. |           |  ___/ ___|   / ___| |   |_ _|             |
4. |           | |_  \___ \  | |   | |    | |              |
5. |           |  _|  ___) | | |___| |___ | |              |
6. |           |_|   |____/   \____|_____|___|             |
7. |                                                       |
8. .=======================================================.
9. | Anthony Minessale II, Ken Rice,                       |
10. | Michael Jerris, Travis Cross                          |
11. | FreeSWITCH (http://www.freeswitch.org)                |
12. | Paypal Donations Appreciated: paypal@freeswitch.org   |
13. | Brought to you by ClueCon http://www.cluecon.com/     |
14. .=======================================================.
15.  
16. .===============================================================.
17. |       _                                                       |
18. |   ___| |_   _  ___  ___ ___  _ __         ___ ___  _ __ ___   |
19. |  / __| | | | |/ _ \/ __/ _ \| '_ \       / __/ _ \| '_ ` _ \  |
20. | | (__| | |_| |  __/ (_| (_) | | | |  _  | (_| (_) | | | | | | |
21. |  \___|_|\__,_|\___|\___\___/|_| |_| (_)  \___\___/|_| |_| |_| |
22. |                                                               |
23. .===============================================================.
24. [m
25. Type /help <enter> to see a list of commands
26.  
27.  
28.  
29. [m
30. [This app Best viewed at 160x60 or more..]
31. +OK log level 7 [7]
32. freeswitch@internal> Enabled sip debugging on internal
33. freeswitch@internal> recv 1387 bytes from udp/[118.70.126.89]:44414 at 02:35:50.080629:
34.    ------------------------------------------------------------------------
35.    INVITE sip:12345@52.193.89.3 SIP/2.0
36.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.LHIuw7F5A;rport
37.    From: <sip:100005@52.193.89.3>;tag=aVBTEb6Xj
38.    To: sip:12345@52.193.89.3
39.    CSeq: 20 INVITE
40.    Call-ID: p8QPFQE58e
41.    Max-Forwards: 70
42.    Supported: replaces, outbound
43.    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
44.    Content-Type: application/sdp
45.    Content-Length: 802
46.    Contact: <sip:100005@118.70.128.64:44414;transport=udp>;+sip.instance="<urn:uuid:25e7cde4-a0bc-432f-a890-120ff985697b>"
47.    User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1)
48.    
49.    v=0
50.    o=100005 1658 3942 IN IP4 10.22.30.124
51.    s=Talk
52.    c=IN IP4 10.22.30.124
53.    b=AS:660
54.    t=0 0
55.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
56.    m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102
57.    a=rtpmap:96 opus/48000/2
58.    a=fmtp:96 useinbandfec=1
59.    a=rtpmap:97 SILK/16000
60.    a=rtpmap:98 speex/16000
61.    a=fmtp:98 vbr=on
62.    a=rtpmap:99 speex/8000
63.    a=fmtp:99 vbr=on
64.    a=fmtp:18 annexb=yes
65.    a=rtpmap:101 telephone-event/48000
66.    a=rtpmap:100 telephone-event/16000
67.    a=rtpmap:102 telephone-event/8000
68.    a=rtcp-fb:* ccm tmmbr
69.    m=video 9078 RTP/AVP 96 97
70.    a=rtpmap:96 VP8/90000
71.    a=rtpmap:97 H264/90000
72.    a=fmtp:97 profile-level-id=42801F
73.    a=rtcp-fb:* ccm tmmbr
74.    a=rtcp-fb:96 nack pli
75.    a=rtcp-fb:96 nack sli
76.    a=rtcp-fb:96 ack rpsi
77.    a=rtcp-fb:96 ccm fir
78.    a=rtcp-fb:97 nack pli
79.    a=rtcp-fb:97 ccm fir
80.    ------------------------------------------------------------------------
81. send 296 bytes to udp/[118.70.126.89]:44414 at 02:35:50.080973:
82.    ------------------------------------------------------------------------
83.    SIP/2.0 100 Trying
84.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.LHIuw7F5A;rport=44414;received=118.70.126.89
85.    From: <sip:100005@52.193.89.3>;tag=aVBTEb6Xj
86.    To: sip:12345@52.193.89.3
87.    Call-ID: p8QPFQE58e
88.    CSeq: 20 INVITE
89.    User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit
90.    Content-Length: 0
91.    
92.    ------------------------------------------------------------------------
93. 2017-08-07 02:35:50.073459 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/100005@52.193.89.3 [201628ce-7b19-11e7-a73a-c76caa719387]
94. 2017-08-07 02:35:50.073459 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_NEW (Cur 1 Tot 1)
95. 2017-08-07 02:35:50.073459 [DEBUG] sofia.c:9873 sofia/internal/100005@52.193.89.3 receiving invite from 118.70.126.89:44414 version: 1.6.19  64bit
96. 2017-08-07 02:35:50.073459 [DEBUG] sofia.c:10044 IP 118.70.126.89 Rejected by acl "domains". Falling back to Digest auth.
97. 2017-08-07 02:35:50.073459 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/100005@52.193.89.3) State NEW
98. send 800 bytes to udp/[118.70.126.89]:44414 at 02:35:50.081965:
99.    ------------------------------------------------------------------------
100.    SIP/2.0 407 Proxy Authentication Required
101.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.LHIuw7F5A;rport=44414;received=118.70.126.89
102.    From: <sip:100005@52.193.89.3>;tag=aVBTEb6Xj
103.    To: <sip:12345@52.193.89.3>;tag=Z76mFj4jeBgDQ
104.    Call-ID: p8QPFQE58e
105.    CSeq: 20 INVITE
106.    User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit
107.    Accept: application/sdp
108.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
109.    Supported: timer, path, replaces
110.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
111.    Proxy-Authenticate: Digest realm="52.193.89.3", nonce="201639d6-7b19-11e7-a73b-c76caa719387", algorithm=MD5, qop="auth"
112.    Content-Length: 0
113.    
114.    ------------------------------------------------------------------------
115. 2017-08-07 02:35:50.073459 [DEBUG] sofia.c:2334 detaching session 201628ce-7b19-11e7-a73a-c76caa719387
116. recv 372 bytes from udp/[118.70.126.89]:44414 at 02:35:50.357036:
117.    ------------------------------------------------------------------------
118.    ACK sip:12345@52.193.89.3 SIP/2.0
119.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.LHIuw7F5A;rport
120.    Call-ID: p8QPFQE58e
121.    From: <sip:100005@52.193.89.3>;tag=aVBTEb6Xj
122.    To: <sip:12345@52.193.89.3>;tag=Z76mFj4jeBgDQ
123.    Contact: <sip:100005@118.70.126.89:44414;transport=udp>;+sip.instance="<urn:uuid:25e7cde4-a0bc-432f-a890-120ff985697b>"
124.    Max-Forwards: 70
125.    CSeq: 20 ACK
126.    
127.    ------------------------------------------------------------------------
128. recv 1642 bytes from udp/[118.70.126.89]:44414 at 02:35:50.360466:
129.    ------------------------------------------------------------------------
130.    INVITE sip:12345@52.193.89.3 SIP/2.0
131.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.juTJlWzmD;rport
132.    From: <sip:100005@52.193.89.3>;tag=aVBTEb6Xj
133.    To: sip:12345@52.193.89.3
134.    CSeq: 21 INVITE
135.    Call-ID: p8QPFQE58e
136.    Max-Forwards: 70
137.    Supported: replaces, outbound
138.    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
139.    Content-Type: application/sdp
140.    Content-Length: 802
141.    Contact: <sip:100005@118.70.126.89:44414;transport=udp>;+sip.instance="<urn:uuid:25e7cde4-a0bc-432f-a890-120ff985697b>"
142.    User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1)
143.    Proxy-Authorization:  Digest realm="52.193.89.3", nonce="201639d6-7b19-11e7-a73b-c76caa719387", algorithm=MD5, username="100005",  uri="sip:12345@52.193.89.3", response="395a4c96831c94b4811be24eba26ddb7", cnonce="XXnpmhu1sw96ruLT", nc=00000001, qop=auth
144.    
145.    v=0
146.    o=100005 1658 3942 IN IP4 10.22.30.124
147.    s=Talk
148.    c=IN IP4 10.22.30.124
149.    b=AS:660
150.    t=0 0
151.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
152.    m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102
153.    a=rtpmap:96 opus/48000/2
154.    a=fmtp:96 useinbandfec=1
155.    a=rtpmap:97 SILK/16000
156.    a=rtpmap:98 speex/16000
157.    a=fmtp:98 vbr=on
158.    a=rtpmap:99 speex/8000
159.    a=fmtp:99 vbr=on
160.    a=fmtp:18 annexb=yes
161.    a=rtpmap:101 telephone-event/48000
162.    a=rtpmap:100 telephone-event/16000
163.    a=rtpmap:102 telephone-event/8000
164.    a=rtcp-fb:* ccm tmmbr
165.    m=video 9078 RTP/AVP 96 97
166.    a=rtpmap:96 VP8/90000
167.    a=rtpmap:97 H264/90000
168.    a=fmtp:97 profile-level-id=42801F
169.    a=rtcp-fb:* ccm tmmbr
170.    a=rtcp-fb:96 nack pli
171.    a=rtcp-fb:96 nack sli
172.    a=rtcp-fb:96 ack rpsi
173.    a=rtcp-fb:96 ccm fir
174.    a=rtcp-fb:97 nack pli
175.    a=rtcp-fb:97 ccm fir
176.    ------------------------------------------------------------------------
177. send 296 bytes to udp/[118.70.126.89]:44414 at 02:35:50.360718:
178.    ------------------------------------------------------------------------
179.    SIP/2.0 100 Trying
180.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.juTJlWzmD;rport=44414;received=118.70.126.89
181.    From: <sip:100005@52.193.89.3>;tag=aVBTEb6Xj
182.    To: sip:12345@52.193.89.3
183.    Call-ID: p8QPFQE58e
184.    CSeq: 21 INVITE
185.    User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit
186.    Content-Length: 0
187.    
188.    ------------------------------------------------------------------------
189. 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:2442 Re-attaching to session 201628ce-7b19-11e7-a73a-c76caa719387
190. 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:9873 sofia/internal/100005@52.193.89.3 receiving invite from 118.70.126.89:44414 version: 1.6.19  64bit
191. 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:10044 IP 118.70.126.89 Rejected by acl "domains". Falling back to Digest auth.
192. 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:11168 Setting NAT mode based on nat.auto
193. 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:7084 Channel sofia/internal/100005@52.193.89.3 entering state [received][100]
194. 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:7094 Remote SDP:
195. v=0
196. o=100005 1658 3942 IN IP4 10.22.30.124
197. s=Talk
198. c=IN IP4 10.22.30.124
199. b=AS:660
200. t=0 0
201. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
202. m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102
203. a=rtpmap:96 opus/48000/2
204. a=fmtp:96 useinbandfec=1
205. a=rtpmap:97 SILK/16000
206. a=rtpmap:98 speex/16000
207. a=fmtp:98 vbr=on
208. a=rtpmap:99 speex/8000
209. a=fmtp:99 vbr=on
210. a=fmtp:18 annexb=yes
211. a=rtpmap:101 telephone-event/48000
212. a=rtpmap:100 telephone-event/16000
213. a=rtpmap:102 telephone-event/8000
214. a=rtcp-fb:* ccm tmmbr
215. m=video 9078 RTP/AVP 96 97
216. a=rtpmap:96 VP8/90000
217. a=rtpmap:97 H264/90000
218. a=fmtp:97 profile-level-id=42801F
219. a=rtcp-fb:* ccm tmmbr
220. a=rtcp-fb:96 nack pli
221. a=rtcp-fb:96 nack sli
222. a=rtcp-fb:96 ack rpsi
223. a=rtcp-fb:96 ccm fir
224. a=rtcp-fb:97 nack pli
225. a=rtcp-fb:97 ccm fir
226.  
227. 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:7486 (sofia/internal/100005@52.193.89.3) State Change CS_NEW -> CS_INIT
228. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_INIT (Cur 1 Tot 1)
229. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/100005@52.193.89.3) State INIT
230. 2017-08-07 02:35:50.353458 [DEBUG] mod_sofia.c:90 sofia/internal/100005@52.193.89.3 SOFIA INIT
231. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:40 sofia/internal/100005@52.193.89.3 Standard INIT
232. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/100005@52.193.89.3) State Change CS_INIT -> CS_ROUTING
233. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/100005@52.193.89.3) State INIT going to sleep
234. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_ROUTING (Cur 1 Tot 1)
235. 2017-08-07 02:35:50.353458 [DEBUG] switch_channel.c:2249 (sofia/internal/100005@52.193.89.3) Callstate Change DOWN -> RINGING
236. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/100005@52.193.89.3) State ROUTING
237. 2017-08-07 02:35:50.353458 [DEBUG] mod_sofia.c:143 sofia/internal/100005@52.193.89.3 SOFIA ROUTING
238. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:236 sofia/internal/100005@52.193.89.3 Standard ROUTING
239. 2017-08-07 02:35:50.353458 [INFO] mod_dialplan_xml.c:637 Processing 100005 <100005>->12345 in context default
240. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->unloop] continue=false
241. Dialplan: sofia/internal/100005@52.193.89.3 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
242. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
243. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->tod_example] continue=true
244. Dialplan: sofia/internal/100005@52.193.89.3 Date/TimeMatch (FAIL) [tod_example] break=on-false
245. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->holiday_example] continue=true
246. Dialplan: sofia/internal/100005@52.193.89.3 Date/TimeMatch (FAIL) [holiday_example] break=on-false
247. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->global-intercept] continue=false
248. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global-intercept] destination_number(12345) =~ /^886$/ break=on-false
249. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group-intercept] continue=false
250. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group-intercept] destination_number(12345) =~ /^\*8$/ break=on-false
251. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->intercept-ext] continue=false
252. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [intercept-ext] destination_number(12345) =~ /^\*\*(\d+)$/ break=on-false
253. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->redial] continue=false
254. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [redial] destination_number(12345) =~ /^(redial|870)$/ break=on-false
255. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->global] continue=true
256. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
257. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${default_password}(123456) =~ /^1234$/ break=never
258. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
259. Dialplan: sofia/internal/100005@52.193.89.3 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
260. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${switch_r_sdp}(v=0
261. o=100005 1658 3942 IN IP4 10.22.30.124
262. s=Talk
263. c=IN IP4 10.22.30.124
264. b=AS:660
265. t=0 0
266. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
267. m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102
268. a=rtpmap:96 opus/48000/2
269. a=fmtp:96 useinbandfec=1
270. a=rtpmap:97 SILK/16000
271. a=rtpmap:98 speex/16000
272. a=fmtp:98 vbr=on
273. a=rtpmap:99 speex/8000
274. a=fmtp:99 vbr=on
275. a=fmtp:18 annexb=yes
276. a=rtpmap:101 telephone-event/48000
277. a=rtpmap:100 telephone-event/16000
278. a=rtpmap:102 telephone-event/8000
279. a=rtcp-fb:* ccm tmmbr
280. m=video 9078 RTP/AVP 96 97
281. a=rtpmap:96 VP8/90000
282. a=rtpmap:97 H264/90000
283. a=fmtp:97 profile-level-id=42801F
284. a=rtcp-fb:* ccm tmmbr
285. a=rtcp-fb:96 nack pli
286. a=rtcp-fb:96 nack sli
287. a=rtcp-fb:96 ack rpsi
288. a=rtcp-fb:96 ccm fir
289. a=rtcp-fb:97 nack pli
290. a=rtcp-fb:97 ccm fir
291. ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
292. Dialplan: sofia/internal/100005@52.193.89.3 Absolute Condition [global]
293. Dialplan: sofia/internal/100005@52.193.89.3 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
294. Dialplan: sofia/internal/100005@52.193.89.3 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
295. Dialplan: sofia/internal/100005@52.193.89.3 Action hash(insert/${domain_name}-last_dial/global/${uuid})
296. Dialplan: sofia/internal/100005@52.193.89.3 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
297. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->snom-demo-2] continue=false
298. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [snom-demo-2] destination_number(12345) =~ /^9001$/ break=on-false
299. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->snom-demo-1] continue=false
300. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [snom-demo-1] destination_number(12345) =~ /^9000$/ break=on-false
301. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->eavesdrop] continue=false
302. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [eavesdrop] destination_number(12345) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
303. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->eavesdrop] continue=false
304. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [eavesdrop] destination_number(12345) =~ /^779$/ break=on-false
305. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->call_return] continue=false
306. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [call_return] destination_number(12345) =~ /^\*69$|^869$|^lcr$/ break=on-false
307. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->del-group] continue=false
308. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [del-group] destination_number(12345) =~ /^80(\d{2})$/ break=on-false
309. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->add-group] continue=false
310. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [add-group] destination_number(12345) =~ /^81(\d{2})$/ break=on-false
311. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->call-group-simo] continue=false
312. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [call-group-simo] destination_number(12345) =~ /^82(\d{2})$/ break=on-false
313. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->call-group-order] continue=false
314. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [call-group-order] destination_number(12345) =~ /^83(\d{2})$/ break=on-false
315. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->extension-intercom] continue=false
316. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [extension-intercom] destination_number(12345) =~ /^8(10[01][0-9])$/ break=on-false
317. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Local_Extension] continue=false
318. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Local_Extension] destination_number(12345) =~ /^(10[01][0-9])$/ break=on-false
319. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Nal_Peer_Extension] continue=false
320. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Nal_Peer_Extension] destination_number(12345) =~ /^(\d{6})$/ break=on-false
321. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Nal_Peer_Extension_Admin] continue=false
322. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Nal_Peer_Extension_Admin] destination_number(12345) =~ /^(\d{4})$/ break=on-false
323. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Local_Extension_Skinny] continue=false
324. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Local_Extension_Skinny] destination_number(12345) =~ /^(11[01][0-9])$/ break=on-false
325. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group_dial_sales] continue=false
326. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group_dial_sales] destination_number(12345) =~ /^2000$/ break=on-false
327. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group_dial_support] continue=false
328. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group_dial_support] destination_number(12345) =~ /^2001$/ break=on-false
329. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group_dial_billing] continue=false
330. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group_dial_billing] destination_number(12345) =~ /^2002$/ break=on-false
331. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->operator] continue=false
332. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [operator] destination_number(12345) =~ /^(operator|0)$/ break=on-false
333. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->vmain] continue=false
334. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [vmain] destination_number(12345) =~ /^vmain$|^4000$|^\*98$/ break=on-false
335. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->sip_uri] continue=false
336. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [sip_uri] destination_number(12345) =~ /^sip:(.*)$/ break=on-false
337. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->nb_conferences] continue=false
338. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [nb_conferences] destination_number(12345) =~ /^(30\d{2})$/ break=on-false
339. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->nal_conferences] continue=false
340. Dialplan: sofia/internal/100005@52.193.89.3 Regex (PASS) [nal_conferences] destination_number(12345) =~ /^(\d{5})$/ break=on-false
341. Dialplan: sofia/internal/100005@52.193.89.3 Action answer()
342. Dialplan: sofia/internal/100005@52.193.89.3 Action conference(12345)
343. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/100005@52.193.89.3) State Change CS_ROUTING -> CS_EXECUTE
344. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/100005@52.193.89.3) State ROUTING going to sleep
345. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_EXECUTE (Cur 1 Tot 1)
346. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/100005@52.193.89.3) State EXECUTE
347. 2017-08-07 02:35:50.353458 [DEBUG] mod_sofia.c:198 sofia/internal/100005@52.193.89.3 SOFIA EXECUTE
348. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:328 sofia/internal/100005@52.193.89.3 Standard EXECUTE
349. EXECUTE sofia/internal/100005@52.193.89.3 hash(insert/172.31.26.52-spymap/100005/201628ce-7b19-11e7-a73a-c76caa719387)
350. EXECUTE sofia/internal/100005@52.193.89.3 hash(insert/172.31.26.52-last_dial/100005/12345)
351. EXECUTE sofia/internal/100005@52.193.89.3 hash(insert/172.31.26.52-last_dial/global/201628ce-7b19-11e7-a73a-c76caa719387)
352. EXECUTE sofia/internal/100005@52.193.89.3 export(RFC2822_DATE=Mon, 07 Aug 2017 02:35:50 +0000)
353. 2017-08-07 02:35:50.353458 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 07 Aug 2017 02:35:50 +0000]
354. EXECUTE sofia/internal/100005@52.193.89.3 answer()
355. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:96:48000:20:0:2]/[G722:9:8000:20:64000:1]
356. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:96:48000:20:0:2]/[PCMU:0:8000:20:64000:1]
357. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:96:48000:20:0:2]/[PCMA:8:8000:20:64000:1]
358. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:97:16000:20:0:1]/[G722:9:8000:20:64000:1]
359. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:97:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
360. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:97:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
361. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:98:16000:20:0:1]/[G722:9:8000:20:64000:1]
362. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:98:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
363. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:98:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
364. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:99:8000:20:0:1]/[G722:9:8000:20:64000:1]
365. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:99:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
366. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:99:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
367. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
368. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
369. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
370. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
371. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
372. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
373. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
374. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
375. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
376. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
377. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
378. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@48000
379. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/100005@52.193.89.3 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
380. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_codec.c:111 sofia/internal/100005@52.193.89.3 Original read codec set to PCMU:0
381. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 102@8000
382. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4767 sofia/internal/100005@52.193.89.3 Set 2833 dtmf send payload to 102 recv payload to 102
383. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4950 Video Codec Compare [VP8:96]/[VP8:99]
384. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4982 Video Codec Compare [VP8:96] +++ is saved as a match
385. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4950 Video Codec Compare [H264:97]/[VP8:99]
386. 2017-08-07 02:35:50.353458 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
387. 2017-08-07 02:35:50.353458 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
388. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:2871 Set VIDEO Codec sofia/internal/100005@52.193.89.3 VP8/90000 0 ms
389. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/100005@52.193.89.3] 172.31.26.52 port 21390 -> 10.22.30.124 port 7076 codec: 0 ms: 20
390. 2017-08-07 02:35:50.353458 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
391. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:7179 sofia/internal/100005@52.193.89.3 Set 2833 dtmf send payload to 102
392. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:7186 sofia/internal/100005@52.193.89.3 Set 2833 dtmf receive payload to 102
393. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:7209 sofia/internal/100005@52.193.89.3 Set rtp dtmf delay to 40
394. 2017-08-07 02:35:50.353458 [DEBUG] switch_rtp.c:4126 Starting video timer.
395. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:7398 VIDEO RTP [sofia/internal/100005@52.193.89.3] 172.31.26.52:21692->10.22.30.124:9078 codec: 96 ms: 0 [SUCCESS]
396. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:6003 sofia/internal/100005@52.193.89.3 Starting Video thread
397. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:5869 sofia/internal/100005@52.193.89.3 Video thread started. Echo is off
398. 2017-08-07 02:35:50.353458 [INFO] switch_core_media.c:7478 Activating VIDEO RTCP PORT 9079 interval 1000 mux -1
399. 2017-08-07 02:35:50.353458 [DEBUG] switch_rtp.c:4507 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 9079
400. 2017-08-07 02:35:50.353458 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 10.22.30.124:9079 2
401. 2017-08-07 02:35:50.353458 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/100005@52.193.89.3!
402. 2017-08-07 02:35:50.353458 [DEBUG] switch_channel.c:3474 (sofia/internal/100005@52.193.89.3) Callstate Change RINGING -> EARLY
403. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/100005@52.193.89.3.
404. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:7256 Video params are unchanged for sofia/internal/100005@52.193.89.3.
405. 2017-08-07 02:35:50.353458 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/100005@52.193.89.3:
406. v=0
407. o=FreeSWITCH 1502051960 1502051961 IN IP4 52.193.89.3
408. s=FreeSWITCH
409. c=IN IP4 52.193.89.3
410. t=0 0
411. m=audio 21390 RTP/AVP 0 102
412. a=rtpmap:0 PCMU/8000
413. a=rtpmap:102 telephone-event/8000
414. a=fmtp:102 0-16
415. a=ptime:20
416. a=sendrecv
417. m=video 21692 RTP/AVP 96
418. b=AS:1024
419. a=rtpmap:96 VP8/90000
420. a=rtcp:21693 IN IP4 52.193.89.3
421. a=rtcp-fb:96 ccm fir
422. a=rtcp-fb:96 ccm tmmbr
423. a=rtcp-fb:96 nack
424. a=rtcp-fb:96 nack pli
425.  
426. 2017-08-07 02:35:50.353458 [NOTICE] mod_dptools.c:1312 Channel [sofia/internal/100005@52.193.89.3] has been answered
427. send 1289 bytes to udp/[118.70.126.89]:44414 at 02:35:50.372877:
428.    ------------------------------------------------------------------------
429.    SIP/2.0 200 OK
430.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.juTJlWzmD;rport=44414;received=118.70.126.89
431.    From: <sip:100005@52.193.89.3>;tag=aVBTEb6Xj
432.    To: <sip:12345@52.193.89.3>;tag=0g0DHDNpBm6Zj
433.    Call-ID: p8QPFQE58e
434.    CSeq: 21 INVITE
435.    Contact: <sip:12345@52.193.89.3:5060;transport=udp>
436.    User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit
437.    Accept: application/sdp
438.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
439.    Supported: timer, path, replaces
440.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
441.    Session-Expires: 120;refresher=uas
442.    Content-Type: application/sdp
443.    Content-Disposition: session
444.    Content-Length: 399
445.    Remote-Party-ID: "12345" <sip:12345@52.193.89.3>;party=calling;privacy=off;screen=no
446.    
447.    v=0
448.    o=FreeSWITCH 1502051960 1502051961 IN IP4 52.193.89.3
449.    s=FreeSWITCH
450.    c=IN IP4 52.193.89.3
451.    t=0 0
452.    m=audio 21390 RTP/AVP 0 102
453.    a=rtpmap:0 PCMU/8000
454.    a=rtpmap:102 telephone-event/8000
455.    a=fmtp:102 0-16
456.    a=ptime:20
457.    m=video 21692 RTP/AVP 96
458.    b=AS:1024
459.    a=rtpmap:96 VP8/90000
460.    a=rtcp:21693 IN IP4 52.193.89.3
461.    a=rtcp-fb:96 ccm fir
462.    a=rtcp-fb:96 ccm tmmbr
463.    a=rtcp-fb:96 nack
464.    a=rtcp-fb:96 nack pli
465.    ------------------------------------------------------------------------
466. 2017-08-07 02:35:50.353458 [DEBUG] switch_channel.c:3773 (sofia/internal/100005@52.193.89.3) Callstate Change EARLY -> ACTIVE
467. 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:7084 Channel sofia/internal/100005@52.193.89.3 entering state [completed][200]
468. EXECUTE sofia/internal/100005@52.193.89.3 conference(12345)
469. 2017-08-07 02:35:50.374028 [WARNING] mod_conference.c:2915 Unspecified video-canvas-size, falling back to 1280x720
470. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:275 Adding layout group grid
471. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 1x1 to layout group grid
472. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 2x1 to layout group grid
473. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 1x1+2x1 to layout group grid
474. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 2x2 to layout group grid
475. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 3x3 to layout group grid
476. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 4x4 to layout group grid
477. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 5x5 to layout group grid
478. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 6x6 to layout group grid
479. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 8x8 to layout group grid
480. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:275 Adding layout group grid-zoom
481. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 1x1 to layout group grid-zoom
482. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 2x1-zoom to layout group grid-zoom
483. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 3x1-zoom to layout group grid-zoom
484. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 2x2 to layout group grid-zoom
485. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 5-grid-zoom to layout group grid-zoom
486. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 3x2-zoom to layout group grid-zoom
487. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 7-grid-zoom to layout group grid-zoom
488. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 4x2-zoom to layout group grid-zoom
489. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 3x3 to layout group grid-zoom
490. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:275 Adding layout group 1up_top_left_plus
491. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 1up_top_left+5 to layout group 1up_top_left_plus
492. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 1up_top_left+7 to layout group 1up_top_left_plus
493. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 1up_top_left+9 to layout group 1up_top_left_plus
494. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:275 Adding layout group 3up_plus
495. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 3up+4 to layout group 3up_plus
496. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 3up+9 to layout group 3up_plus
497. 2017-08-07 02:35:50.374028 [DEBUG] mod_conference.c:3076 using channel sound prefix: /usr/share/freeswitch/sounds/en/us/callie
498. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:1132 Canvas position 1 applied layout 3x3
499. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:1222 Layout set to 3x3
500. 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:1182 Canvas attached to position 0
501. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:1691 Raw Codec Activation Success L16@8000hz 1 channel 20ms
502. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:1738 Raw Codec Activation Success L16@8000hz 1 channel 20ms
503. 2017-08-07 02:35:50.374028 [DEBUG] switch_core_codec.c:223 sofia/internal/100005@52.193.89.3 Push codec L16:100
504. 2017-08-07 02:35:50.374028 [DEBUG] mod_conference.c:220 Setup timer success interval: 20  samples: 160
505. 2017-08-07 02:35:50.374028 [ERR] switch_core_video.c:2267 This function is not available, libpng not installed
506. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '0' to 'mute'
507. 2017-08-07 02:35:50.374028 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
508. 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092b70
509. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '*' to 'deaf mute'
510. 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092ba0
511. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '9' to 'energy up'
512. 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092bd0
513. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '8' to 'energy equ'
514. 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092c00
515. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '7' to 'energy dn'
516. 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092c30
517. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '3' to 'vol talk up'
518. 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092c60
519. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '2' to 'vol talk zero'
520. 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092c90
521. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '1' to 'vol talk dn'
522. 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092cc0
523. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '6' to 'vol listen up'
524. 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092cf0
525. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '5' to 'vol listen zero'
526. 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092d20
527. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '4' to 'vol listen dn'
528. 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092d50
529. 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '#' to 'hangup'
530. 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092d80
531. 2017-08-07 02:35:50.374028 [DEBUG] conference_loop.c:1140 Setup timer soft success interval: 20  samples: 160 from codec PCMU
532. 2017-08-07 02:35:50.404187 [NOTICE] switch_vpx.c:406 VPX reset encoder picture from 320x240 to 1280x720 1024 BW
533. 2017-08-07 02:35:50.414205 [NOTICE] switch_vpx.c:406 VPX reset encoder picture from 1280x720 to 1280x720 1024 BW
534. 2017-08-07 02:35:50.414205 [INFO] switch_vpx.c:502 VPX ENCODER RESET
535. recv 578 bytes from udp/[118.70.126.89]:44414 at 02:35:50.492502:
536.    ------------------------------------------------------------------------
537.    ACK sip:12345@52.193.89.3:5060;transport=udp SIP/2.0
538.    Via: SIP/2.0/UDP 10.22.30.124:44414;rport;branch=z9hG4bK.YkHM7cnfV
539.    From: <sip:100005@52.193.89.3>;tag=aVBTEb6Xj
540.    To: <sip:12345@52.193.89.3>;tag=0g0DHDNpBm6Zj
541.    CSeq: 21 ACK
542.    Call-ID: p8QPFQE58e
543.    Max-Forwards: 70
544.    Proxy-Authorization:  Digest realm="52.193.89.3", nonce="201639d6-7b19-11e7-a73b-c76caa719387", algorithm=MD5, username="100005",  uri="sip:12345@52.193.89.3", response="395a4c96831c94b4811be24eba26ddb7", cnonce="XXnpmhu1sw96ruLT", nc=00000001, qop=auth
545.    User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1)
546.    
547.    ------------------------------------------------------------------------
548. 2017-08-07 02:35:50.494186 [DEBUG] sofia.c:7084 Channel sofia/internal/100005@52.193.89.3 entering state [ready][200]
549. 2017-08-07 02:35:50.794192 [INFO] switch_rtp.c:6700 Auto Changing video RTCP port from 10.22.30.124:9079 to 123.25.21.255:9079
550. 2017-08-07 02:35:50.794192 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 123.25.21.255:9079 2
551. 2017-08-07 02:35:50.924189 [INFO] switch_rtp.c:7231 Auto Changing audio port from 10.22.30.124:7076 to 118.70.128.64:7076
552. 2017-08-07 02:35:53.134186 [DEBUG] mod_local_stream.c:871 Opening Stream [moh/8000] 8000hz
553. recv 597 bytes from udp/[118.70.126.89]:44414 at 02:36:04.793052:
554.    ------------------------------------------------------------------------
555.    BYE sip:12345@52.193.89.3:5060;transport=udp SIP/2.0
556.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.aCnpbEUwp;rport
557.    From: <sip:100005@52.193.89.3>;tag=aVBTEb6Xj
558.    To: <sip:12345@52.193.89.3>;tag=0g0DHDNpBm6Zj
559.    CSeq: 22 BYE
560.    Call-ID: p8QPFQE58e
561.    Max-Forwards: 70
562.    User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1)
563.    Proxy-Authorization:  Digest realm="52.193.89.3", nonce="201639d6-7b19-11e7-a73b-c76caa719387", algorithm=MD5, username="100005",  uri="sip:12345@52.193.89.3:5060;transport=udp", response="34ee3921d1356d0b02b05e92ef7793be", cnonce="CW0y01IEvzrHTN0n", nc=00000002, qop=auth
564.    
565.    ------------------------------------------------------------------------
566. 2017-08-07 02:36:04.794188 [NOTICE] sofia.c:1012 Hangup sofia/internal/100005@52.193.89.3 [CS_EXECUTE] [NORMAL_CLEARING]
567. send 453 bytes to udp/[118.70.126.89]:44414 at 02:36:04.798633:
568.    ------------------------------------------------------------------------
569.    SIP/2.0 200 OK
570.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.aCnpbEUwp;rport=44414;received=118.70.126.89
571.    From: <sip:100005@52.193.89.3>;tag=aVBTEb6Xj
572.    To: <sip:12345@52.193.89.3>;tag=0g0DHDNpBm6Zj
573.    Call-ID: p8QPFQE58e
574.    CSeq: 22 BYE
575.    User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit
576.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
577.    Supported: timer, path, replaces
578.    Content-Length: 0
579.    
580.    ------------------------------------------------------------------------
581. 2017-08-07 02:36:04.794188 [DEBUG] switch_core_media.c:5970 sofia/internal/100005@52.193.89.3 Video thread ended
582. 2017-08-07 02:36:04.804185 [INFO] conference_loop.c:1469 Channel leaving conference, cause: NORMAL_CLEARING
583. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_session.c:2577 sofia/internal/100005@52.193.89.3 skip receive message [VIDEO_REFRESH_REQ] (channel is hungup already)
584. 2017-08-07 02:36:04.804185 [DEBUG] mod_conference.c:2247 sofia/internal/100005@52.193.89.3 skip receive message [UNBRIDGE] (channel is hungup already)
585. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_media.c:9779 sofia/internal/100005@52.193.89.3 skip receive message [HARD_MUTE] (channel is hungup already)
586. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_codec.c:248 sofia/internal/100005@52.193.89.3 Restore previous codec PCMU:0.
587. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_session.c:2815 sofia/internal/100005@52.193.89.3 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
588. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/100005@52.193.89.3) State EXECUTE going to sleep
589. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_HANGUP (Cur 1 Tot 1)
590. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/100005@52.193.89.3) Callstate Change ACTIVE -> HANGUP
591. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/100005@52.193.89.3) State HANGUP
592. 2017-08-07 02:36:04.804185 [DEBUG] mod_sofia.c:438 Channel sofia/internal/100005@52.193.89.3 hanging up, cause: NORMAL_CLEARING
593. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:60 sofia/internal/100005@52.193.89.3 Standard HANGUP, cause: NORMAL_CLEARING
594. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/100005@52.193.89.3) State HANGUP going to sleep
595. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/100005@52.193.89.3) State Change CS_HANGUP -> CS_REPORTING
596. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_REPORTING (Cur 1 Tot 1)
597. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/100005@52.193.89.3) State REPORTING
598. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:174 sofia/internal/100005@52.193.89.3 Standard REPORTING, cause: NORMAL_CLEARING
599. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/100005@52.193.89.3) State REPORTING going to sleep
600. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/100005@52.193.89.3) State Change CS_REPORTING -> CS_DESTROY
601. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_session.c:1665 Session 1 (sofia/internal/100005@52.193.89.3) Locked, Waiting on external entities
602. 2017-08-07 02:36:04.804185 [NOTICE] switch_core_session.c:1683 Session 1 (sofia/internal/100005@52.193.89.3) Ended
603. 2017-08-07 02:36:04.804185 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/100005@52.193.89.3 [CS_DESTROY]
604. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/100005@52.193.89.3) Running State Change CS_DESTROY (Cur 0 Tot 1)
605. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/100005@52.193.89.3) State DESTROY
606. 2017-08-07 02:36:04.804185 [DEBUG] mod_sofia.c:343 sofia/internal/100005@52.193.89.3 SOFIA DESTROY
607. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:181 sofia/internal/100005@52.193.89.3 Standard DESTROY
608. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/100005@52.193.89.3) State DESTROY going to sleep
609. 2017-08-07 02:36:04.824252 [DEBUG] mod_conference.c:788 Write Lock ON
610. 2017-08-07 02:36:04.824252 [DEBUG] mod_conference.c:791 Write Lock OFF
611. recv 597 bytes from udp/[118.70.126.89]:44414 at 02:36:05.612650:
612.    ------------------------------------------------------------------------
613.    BYE sip:12345@52.193.89.3:5060;transport=udp SIP/2.0
614.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.aCnpbEUwp;rport
615.    From: <sip:100005@52.193.89.3>;tag=aVBTEb6Xj
616.    To: <sip:12345@52.193.89.3>;tag=0g0DHDNpBm6Zj
617.    CSeq: 22 BYE
618.    Call-ID: p8QPFQE58e
619.    Max-Forwards: 70
620.    User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1)
621.    Proxy-Authorization:  Digest realm="52.193.89.3", nonce="201639d6-7b19-11e7-a73b-c76caa719387", algorithm=MD5, username="100005",  uri="sip:12345@52.193.89.3:5060;transport=udp", response="34ee3921d1356d0b02b05e92ef7793be", cnonce="CW0y01IEvzrHTN0n", nc=00000002, qop=auth
622.    
623.    ------------------------------------------------------------------------
624. send 453 bytes to udp/[118.70.126.89]:44414 at 02:36:05.612752:
625.    ------------------------------------------------------------------------
626.    SIP/2.0 200 OK
627.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.aCnpbEUwp;rport=44414;received=118.70.126.89
628.    From: <sip:100005@52.193.89.3>;tag=aVBTEb6Xj
629.    To: <sip:12345@52.193.89.3>;tag=0g0DHDNpBm6Zj
630.    Call-ID: p8QPFQE58e
631.    CSeq: 22 BYE
632.    User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit
633.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
634.    Supported: timer, path, replaces
635.    Content-Length: 0
636.    
637.    ------------------------------------------------------------------------
638. freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> recv 1387 bytes from udp/[118.70.126.89]:44414 at 02:36:50.680148:
639.    ------------------------------------------------------------------------
640.    INVITE sip:12345@52.193.89.3 SIP/2.0
641.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.9-wqPGP~v;rport
642.    From: <sip:100005@52.193.89.3>;tag=tH-SJK~Z-
643.    To: sip:12345@52.193.89.3
644.    CSeq: 20 INVITE
645.    Call-ID: NNFynTe-jL
646.    Max-Forwards: 70
647.    Supported: replaces, outbound
648.    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
649.    Content-Type: application/sdp
650.    Content-Length: 802
651.    Contact: <sip:100005@118.70.126.89:44414;transport=udp>;+sip.instance="<urn:uuid:25e7cde4-a0bc-432f-a890-120ff985697b>"
652.    User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1)
653.    
654.    v=0
655.    o=100005 2816 4029 IN IP4 10.22.30.124
656.    s=Talk
657.    c=IN IP4 10.22.30.124
658.    b=AS:660
659.    t=0 0
660.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
661.    m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102
662.    a=rtpmap:96 opus/48000/2
663.    a=fmtp:96 useinbandfec=1
664.    a=rtpmap:97 SILK/16000
665.    a=rtpmap:98 speex/16000
666.    a=fmtp:98 vbr=on
667.    a=rtpmap:99 speex/8000
668.    a=fmtp:99 vbr=on
669.    a=fmtp:18 annexb=yes
670.    a=rtpmap:101 telephone-event/48000
671.    a=rtpmap:100 telephone-event/16000
672.    a=rtpmap:102 telephone-event/8000
673.    a=rtcp-fb:* ccm tmmbr
674.    m=video 9078 RTP/AVP 96 97
675.    a=rtpmap:96 VP8/90000
676.    a=rtpmap:97 H264/90000
677.    a=fmtp:97 profile-level-id=42801F
678.    a=rtcp-fb:* ccm tmmbr
679.    a=rtcp-fb:96 nack pli
680.    a=rtcp-fb:96 nack sli
681.    a=rtcp-fb:96 ack rpsi
682.    a=rtcp-fb:96 ccm fir
683.    a=rtcp-fb:97 nack pli
684.    a=rtcp-fb:97 ccm fir
685.    ------------------------------------------------------------------------
686. send 296 bytes to udp/[118.70.126.89]:44414 at 02:36:50.680528:
687.    ------------------------------------------------------------------------
688.    SIP/2.0 100 Trying
689.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.9-wqPGP~v;rport=44414;received=118.70.126.89
690.    From: <sip:100005@52.193.89.3>;tag=tH-SJK~Z-
691.    To: sip:12345@52.193.89.3
692.    Call-ID: NNFynTe-jL
693.    CSeq: 20 INVITE
694.    User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit
695.    Content-Length: 0
696.    
697.    ------------------------------------------------------------------------
698. 2017-08-07 02:36:50.674186 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/100005@52.193.89.3 [4434e9e8-7b19-11e7-a744-c76caa719387]
699. 2017-08-07 02:36:50.674186 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_NEW (Cur 1 Tot 2)
700. 2017-08-07 02:36:50.674186 [DEBUG] sofia.c:9873 sofia/internal/100005@52.193.89.3 receiving invite from 118.70.126.89:44414 version: 1.6.19  64bit
701. 2017-08-07 02:36:50.674186 [DEBUG] sofia.c:10044 IP 118.70.126.89 Rejected by acl "domains". Falling back to Digest auth.
702. 2017-08-07 02:36:50.674186 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/100005@52.193.89.3) State NEW
703. send 800 bytes to udp/[118.70.126.89]:44414 at 02:36:50.681300:
704.    ------------------------------------------------------------------------
705.    SIP/2.0 407 Proxy Authentication Required
706.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.9-wqPGP~v;rport=44414;received=118.70.126.89
707.    From: <sip:100005@52.193.89.3>;tag=tH-SJK~Z-
708.    To: <sip:12345@52.193.89.3>;tag=1SS6j85S8vvje
709.    Call-ID: NNFynTe-jL
710.    CSeq: 20 INVITE
711.    User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit
712.    Accept: application/sdp
713.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
714.    Supported: timer, path, replaces
715.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
716.    Proxy-Authenticate: Digest realm="52.193.89.3", nonce="4434f5b4-7b19-11e7-a745-c76caa719387", algorithm=MD5, qop="auth"
717.    Content-Length: 0
718.    
719.    ------------------------------------------------------------------------
720. 2017-08-07 02:36:50.674186 [DEBUG] sofia.c:2334 detaching session 4434e9e8-7b19-11e7-a744-c76caa719387
721. recv 372 bytes from udp/[118.70.126.89]:44414 at 02:36:50.831021:
722.    ------------------------------------------------------------------------
723.    ACK sip:12345@52.193.89.3 SIP/2.0
724.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.9-wqPGP~v;rport
725.    Call-ID: NNFynTe-jL
726.    From: <sip:100005@52.193.89.3>;tag=tH-SJK~Z-
727.    To: <sip:12345@52.193.89.3>;tag=1SS6j85S8vvje
728.    Contact: <sip:100005@118.70.126.89:44414;transport=udp>;+sip.instance="<urn:uuid:25e7cde4-a0bc-432f-a890-120ff985697b>"
729.    Max-Forwards: 70
730.    CSeq: 20 ACK
731.    
732.    ------------------------------------------------------------------------
733. recv 1642 bytes from udp/[118.70.126.89]:44414 at 02:36:50.832117:
734.    ------------------------------------------------------------------------
735.    INVITE sip:12345@52.193.89.3 SIP/2.0
736.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.PotJzfLZa;rport
737.    From: <sip:100005@52.193.89.3>;tag=tH-SJK~Z-
738.    To: sip:12345@52.193.89.3
739.    CSeq: 21 INVITE
740.    Call-ID: NNFynTe-jL
741.    Max-Forwards: 70
742.    Supported: replaces, outbound
743.    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
744.    Content-Type: application/sdp
745.    Content-Length: 802
746.    Contact: <sip:100005@118.70.126.89:44414;transport=udp>;+sip.instance="<urn:uuid:25e7cde4-a0bc-432f-a890-120ff985697b>"
747.    User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1)
748.    Proxy-Authorization:  Digest realm="52.193.89.3", nonce="4434f5b4-7b19-11e7-a745-c76caa719387", algorithm=MD5, username="100005",  uri="sip:12345@52.193.89.3", response="7b5ab9d9d7bb97534957ad7c97693882", cnonce="KsoDY2XgxVohOJ0P", nc=00000001, qop=auth
749.    
750.    v=0
751.    o=100005 2816 4029 IN IP4 10.22.30.124
752.    s=Talk
753.    c=IN IP4 10.22.30.124
754.    b=AS:660
755.    t=0 0
756.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
757.    m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102
758.    a=rtpmap:96 opus/48000/2
759.    a=fmtp:96 useinbandfec=1
760.    a=rtpmap:97 SILK/16000
761.    a=rtpmap:98 speex/16000
762.    a=fmtp:98 vbr=on
763.    a=rtpmap:99 speex/8000
764.    a=fmtp:99 vbr=on
765.    a=fmtp:18 annexb=yes
766.    a=rtpmap:101 telephone-event/48000
767.    a=rtpmap:100 telephone-event/16000
768.    a=rtpmap:102 telephone-event/8000
769.    a=rtcp-fb:* ccm tmmbr
770.    m=video 9078 RTP/AVP 96 97
771.    a=rtpmap:96 VP8/90000
772.    a=rtpmap:97 H264/90000
773.    a=fmtp:97 profile-level-id=42801F
774.    a=rtcp-fb:* ccm tmmbr
775.    a=rtcp-fb:96 nack pli
776.    a=rtcp-fb:96 nack sli
777.    a=rtcp-fb:96 ack rpsi
778.    a=rtcp-fb:96 ccm fir
779.    a=rtcp-fb:97 nack pli
780.    a=rtcp-fb:97 ccm fir
781.    ------------------------------------------------------------------------
782. send 296 bytes to udp/[118.70.126.89]:44414 at 02:36:50.832383:
783.    ------------------------------------------------------------------------
784.    SIP/2.0 100 Trying
785.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.PotJzfLZa;rport=44414;received=118.70.126.89
786.    From: <sip:100005@52.193.89.3>;tag=tH-SJK~Z-
787.    To: sip:12345@52.193.89.3
788.    Call-ID: NNFynTe-jL
789.    CSeq: 21 INVITE
790.    User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit
791.    Content-Length: 0
792.    
793.    ------------------------------------------------------------------------
794. 2017-08-07 02:36:50.824213 [DEBUG] sofia.c:2442 Re-attaching to session 4434e9e8-7b19-11e7-a744-c76caa719387
795. 2017-08-07 02:36:50.835794 [DEBUG] sofia.c:9873 sofia/internal/100005@52.193.89.3 receiving invite from 118.70.126.89:44414 version: 1.6.19  64bit
796. 2017-08-07 02:36:50.835794 [DEBUG] sofia.c:10044 IP 118.70.126.89 Rejected by acl "domains". Falling back to Digest auth.
797. 2017-08-07 02:36:50.835794 [DEBUG] sofia.c:11168 Setting NAT mode based on nat.auto
798. 2017-08-07 02:36:50.835794 [DEBUG] sofia.c:7084 Channel sofia/internal/100005@52.193.89.3 entering state [received][100]
799. 2017-08-07 02:36:50.835794 [DEBUG] sofia.c:7094 Remote SDP:
800. v=0
801. o=100005 2816 4029 IN IP4 10.22.30.124
802. s=Talk
803. c=IN IP4 10.22.30.124
804. b=AS:660
805. t=0 0
806. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
807. m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102
808. a=rtpmap:96 opus/48000/2
809. a=fmtp:96 useinbandfec=1
810. a=rtpmap:97 SILK/16000
811. a=rtpmap:98 speex/16000
812. a=fmtp:98 vbr=on
813. a=rtpmap:99 speex/8000
814. a=fmtp:99 vbr=on
815. a=fmtp:18 annexb=yes
816. a=rtpmap:101 telephone-event/48000
817. a=rtpmap:100 telephone-event/16000
818. a=rtpmap:102 telephone-event/8000
819. a=rtcp-fb:* ccm tmmbr
820. m=video 9078 RTP/AVP 96 97
821. a=rtpmap:96 VP8/90000
822. a=rtpmap:97 H264/90000
823. a=fmtp:97 profile-level-id=42801F
824. a=rtcp-fb:* ccm tmmbr
825. a=rtcp-fb:96 nack pli
826. a=rtcp-fb:96 nack sli
827. a=rtcp-fb:96 ack rpsi
828. a=rtcp-fb:96 ccm fir
829. a=rtcp-fb:97 nack pli
830. a=rtcp-fb:97 ccm fir
831.  
832. 2017-08-07 02:36:50.835794 [DEBUG] sofia.c:7486 (sofia/internal/100005@52.193.89.3) State Change CS_NEW -> CS_INIT
833. 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_INIT (Cur 1 Tot 2)
834. 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/100005@52.193.89.3) State INIT
835. 2017-08-07 02:36:50.835794 [DEBUG] mod_sofia.c:90 sofia/internal/100005@52.193.89.3 SOFIA INIT
836. 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:40 sofia/internal/100005@52.193.89.3 Standard INIT
837. 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/100005@52.193.89.3) State Change CS_INIT -> CS_ROUTING
838. 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/100005@52.193.89.3) State INIT going to sleep
839. 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_ROUTING (Cur 1 Tot 2)
840. 2017-08-07 02:36:50.835794 [DEBUG] switch_channel.c:2249 (sofia/internal/100005@52.193.89.3) Callstate Change DOWN -> RINGING
841. 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/100005@52.193.89.3) State ROUTING
842. 2017-08-07 02:36:50.835794 [DEBUG] mod_sofia.c:143 sofia/internal/100005@52.193.89.3 SOFIA ROUTING
843. 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:236 sofia/internal/100005@52.193.89.3 Standard ROUTING
844. 2017-08-07 02:36:50.835794 [INFO] mod_dialplan_xml.c:637 Processing 100005 <100005>->12345 in context default
845. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->unloop] continue=false
846. Dialplan: sofia/internal/100005@52.193.89.3 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
847. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
848. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->tod_example] continue=true
849. Dialplan: sofia/internal/100005@52.193.89.3 Date/TimeMatch (FAIL) [tod_example] break=on-false
850. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->holiday_example] continue=true
851. Dialplan: sofia/internal/100005@52.193.89.3 Date/TimeMatch (FAIL) [holiday_example] break=on-false
852. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->global-intercept] continue=false
853. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global-intercept] destination_number(12345) =~ /^886$/ break=on-false
854. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group-intercept] continue=false
855. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group-intercept] destination_number(12345) =~ /^\*8$/ break=on-false
856. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->intercept-ext] continue=false
857. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [intercept-ext] destination_number(12345) =~ /^\*\*(\d+)$/ break=on-false
858. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->redial] continue=false
859. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [redial] destination_number(12345) =~ /^(redial|870)$/ break=on-false
860. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->global] continue=true
861. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
862. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${default_password}(123456) =~ /^1234$/ break=never
863. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
864. Dialplan: sofia/internal/100005@52.193.89.3 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
865. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${switch_r_sdp}(v=0
866. o=100005 2816 4029 IN IP4 10.22.30.124
867. s=Talk
868. c=IN IP4 10.22.30.124
869. b=AS:660
870. t=0 0
871. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
872. m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102
873. a=rtpmap:96 opus/48000/2
874. a=fmtp:96 useinbandfec=1
875. a=rtpmap:97 SILK/16000
876. a=rtpmap:98 speex/16000
877. a=fmtp:98 vbr=on
878. a=rtpmap:99 speex/8000
879. a=fmtp:99 vbr=on
880. a=fmtp:18 annexb=yes
881. a=rtpmap:101 telephone-event/48000
882. a=rtpmap:100 telephone-event/16000
883. a=rtpmap:102 telephone-event/8000
884. a=rtcp-fb:* ccm tmmbr
885. m=video 9078 RTP/AVP 96 97
886. a=rtpmap:96 VP8/90000
887. a=rtpmap:97 H264/90000
888. a=fmtp:97 profile-level-id=42801F
889. a=rtcp-fb:* ccm tmmbr
890. a=rtcp-fb:96 nack pli
891. a=rtcp-fb:96 nack sli
892. a=rtcp-fb:96 ack rpsi
893. a=rtcp-fb:96 ccm fir
894. a=rtcp-fb:97 nack pli
895. a=rtcp-fb:97 ccm fir
896. ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
897. Dialplan: sofia/internal/100005@52.193.89.3 Absolute Condition [global]
898. Dialplan: sofia/internal/100005@52.193.89.3 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
899. Dialplan: sofia/internal/100005@52.193.89.3 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
900. Dialplan: sofia/internal/100005@52.193.89.3 Action hash(insert/${domain_name}-last_dial/global/${uuid})
901. Dialplan: sofia/internal/100005@52.193.89.3 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
902. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->snom-demo-2] continue=false
903. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [snom-demo-2] destination_number(12345) =~ /^9001$/ break=on-false
904. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->snom-demo-1] continue=false
905. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [snom-demo-1] destination_number(12345) =~ /^9000$/ break=on-false
906. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->eavesdrop] continue=false
907. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [eavesdrop] destination_number(12345) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
908. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->eavesdrop] continue=false
909. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [eavesdrop] destination_number(12345) =~ /^779$/ break=on-false
910. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->call_return] continue=false
911. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [call_return] destination_number(12345) =~ /^\*69$|^869$|^lcr$/ break=on-false
912. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->del-group] continue=false
913. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [del-group] destination_number(12345) =~ /^80(\d{2})$/ break=on-false
914. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->add-group] continue=false
915. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [add-group] destination_number(12345) =~ /^81(\d{2})$/ break=on-false
916. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->call-group-simo] continue=false
917. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [call-group-simo] destination_number(12345) =~ /^82(\d{2})$/ break=on-false
918. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->call-group-order] continue=false
919. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [call-group-order] destination_number(12345) =~ /^83(\d{2})$/ break=on-false
920. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->extension-intercom] continue=false
921. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [extension-intercom] destination_number(12345) =~ /^8(10[01][0-9])$/ break=on-false
922. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Local_Extension] continue=false
923. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Local_Extension] destination_number(12345) =~ /^(10[01][0-9])$/ break=on-false
924. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Nal_Peer_Extension] continue=false
925. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Nal_Peer_Extension] destination_number(12345) =~ /^(\d{6})$/ break=on-false
926. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Nal_Peer_Extension_Admin] continue=false
927. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Nal_Peer_Extension_Admin] destination_number(12345) =~ /^(\d{4})$/ break=on-false
928. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Local_Extension_Skinny] continue=false
929. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Local_Extension_Skinny] destination_number(12345) =~ /^(11[01][0-9])$/ break=on-false
930. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group_dial_sales] continue=false
931. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group_dial_sales] destination_number(12345) =~ /^2000$/ break=on-false
932. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group_dial_support] continue=false
933. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group_dial_support] destination_number(12345) =~ /^2001$/ break=on-false
934. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group_dial_billing] continue=false
935. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group_dial_billing] destination_number(12345) =~ /^2002$/ break=on-false
936. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->operator] continue=false
937. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [operator] destination_number(12345) =~ /^(operator|0)$/ break=on-false
938. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->vmain] continue=false
939. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [vmain] destination_number(12345) =~ /^vmain$|^4000$|^\*98$/ break=on-false
940. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->sip_uri] continue=false
941. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [sip_uri] destination_number(12345) =~ /^sip:(.*)$/ break=on-false
942. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->nb_conferences] continue=false
943. Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [nb_conferences] destination_number(12345) =~ /^(30\d{2})$/ break=on-false
944. Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->nal_conferences] continue=false
945. Dialplan: sofia/internal/100005@52.193.89.3 Regex (PASS) [nal_conferences] destination_number(12345) =~ /^(\d{5})$/ break=on-false
946. Dialplan: sofia/internal/100005@52.193.89.3 Action answer()
947. Dialplan: sofia/internal/100005@52.193.89.3 Action conference(12345)
948. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/100005@52.193.89.3) State Change CS_ROUTING -> CS_EXECUTE
949. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/100005@52.193.89.3) State ROUTING going to sleep
950. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_EXECUTE (Cur 1 Tot 2)
951. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/100005@52.193.89.3) State EXECUTE
952. 2017-08-07 02:36:50.844249 [DEBUG] mod_sofia.c:198 sofia/internal/100005@52.193.89.3 SOFIA EXECUTE
953. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_state_machine.c:328 sofia/internal/100005@52.193.89.3 Standard EXECUTE
954. EXECUTE sofia/internal/100005@52.193.89.3 hash(insert/172.31.26.52-spymap/100005/4434e9e8-7b19-11e7-a744-c76caa719387)
955. EXECUTE sofia/internal/100005@52.193.89.3 hash(insert/172.31.26.52-last_dial/100005/12345)
956. EXECUTE sofia/internal/100005@52.193.89.3 hash(insert/172.31.26.52-last_dial/global/4434e9e8-7b19-11e7-a744-c76caa719387)
957. EXECUTE sofia/internal/100005@52.193.89.3 export(RFC2822_DATE=Mon, 07 Aug 2017 02:36:50 +0000)
958. 2017-08-07 02:36:50.844249 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 07 Aug 2017 02:36:50 +0000]
959. EXECUTE sofia/internal/100005@52.193.89.3 answer()
960. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:96:48000:20:0:2]/[G722:9:8000:20:64000:1]
961. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:96:48000:20:0:2]/[PCMU:0:8000:20:64000:1]
962. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:96:48000:20:0:2]/[PCMA:8:8000:20:64000:1]
963. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:97:16000:20:0:1]/[G722:9:8000:20:64000:1]
964. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:97:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
965. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:97:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
966. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:98:16000:20:0:1]/[G722:9:8000:20:64000:1]
967. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:98:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
968. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:98:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
969. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:99:8000:20:0:1]/[G722:9:8000:20:64000:1]
970. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:99:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
971. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:99:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
972. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
973. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
974. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
975. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
976. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
977. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
978. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
979. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
980. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
981. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
982. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
983. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@48000
984. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/100005@52.193.89.3 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
985. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_codec.c:111 sofia/internal/100005@52.193.89.3 Original read codec set to PCMU:0
986. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 102@8000
987. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4767 sofia/internal/100005@52.193.89.3 Set 2833 dtmf send payload to 102 recv payload to 102
988. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4950 Video Codec Compare [VP8:96]/[VP8:99]
989. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4982 Video Codec Compare [VP8:96] +++ is saved as a match
990. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4950 Video Codec Compare [H264:97]/[VP8:99]
991. 2017-08-07 02:36:50.844249 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
992. 2017-08-07 02:36:50.844249 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
993. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:2871 Set VIDEO Codec sofia/internal/100005@52.193.89.3 VP8/90000 0 ms
994. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/100005@52.193.89.3] 172.31.26.52 port 26554 -> 10.22.30.124 port 7076 codec: 0 ms: 20
995. 2017-08-07 02:36:50.844249 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
996. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:7179 sofia/internal/100005@52.193.89.3 Set 2833 dtmf send payload to 102
997. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:7186 sofia/internal/100005@52.193.89.3 Set 2833 dtmf receive payload to 102
998. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:7209 sofia/internal/100005@52.193.89.3 Set rtp dtmf delay to 40
999. 2017-08-07 02:36:50.844249 [DEBUG] switch_rtp.c:4126 Starting video timer.
1000. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:7398 VIDEO RTP [sofia/internal/100005@52.193.89.3] 172.31.26.52:29328->10.22.30.124:9078 codec: 96 ms: 0 [SUCCESS]
1001. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:6003 sofia/internal/100005@52.193.89.3 Starting Video thread
1002. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:5869 sofia/internal/100005@52.193.89.3 Video thread started. Echo is off
1003. 2017-08-07 02:36:50.844249 [INFO] switch_core_media.c:7478 Activating VIDEO RTCP PORT 9079 interval 1000 mux -1
1004. 2017-08-07 02:36:50.844249 [DEBUG] switch_rtp.c:4507 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 9079
1005. 2017-08-07 02:36:50.844249 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 10.22.30.124:9079 2
1006. 2017-08-07 02:36:50.844249 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/100005@52.193.89.3!
1007. 2017-08-07 02:36:50.844249 [DEBUG] switch_channel.c:3474 (sofia/internal/100005@52.193.89.3) Callstate Change RINGING -> EARLY
1008. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/100005@52.193.89.3.
1009. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:7256 Video params are unchanged for sofia/internal/100005@52.193.89.3.
1010. 2017-08-07 02:36:50.844249 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/100005@52.193.89.3:
1011. v=0
1012. o=FreeSWITCH 1502046856 1502046857 IN IP4 52.193.89.3
1013. s=FreeSWITCH
1014. c=IN IP4 52.193.89.3
1015. t=0 0
1016. m=audio 26554 RTP/AVP 0 102
1017. a=rtpmap:0 PCMU/8000
1018. a=rtpmap:102 telephone-event/8000
1019. a=fmtp:102 0-16
1020. a=ptime:20
1021. a=sendrecv
1022. m=video 29328 RTP/AVP 96
1023. b=AS:1024
1024. a=rtpmap:96 VP8/90000
1025. a=rtcp:29329 IN IP4 52.193.89.3
1026. a=rtcp-fb:96 ccm fir
1027. a=rtcp-fb:96 ccm tmmbr
1028. a=rtcp-fb:96 nack
1029. a=rtcp-fb:96 nack pli
1030.  
1031. 2017-08-07 02:36:50.844249 [NOTICE] mod_dptools.c:1312 Channel [sofia/internal/100005@52.193.89.3] has been answered
1032. 2017-08-07 02:36:50.844249 [DEBUG] switch_channel.c:3773 (sofia/internal/100005@52.193.89.3) Callstate Change EARLY -> ACTIVE
1033. EXECUTE sofia/internal/100005@52.193.89.3 conference(12345)
1034. 2017-08-07 02:36:50.844249 [WARNING] mod_conference.c:2915 Unspecified video-canvas-size, falling back to 1280x720
1035. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:275 Adding layout group grid
1036. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 1x1 to layout group grid
1037. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 2x1 to layout group grid
1038. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 1x1+2x1 to layout group grid
1039. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 2x2 to layout group grid
1040. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 3x3 to layout group grid
1041. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 4x4 to layout group grid
1042. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 5x5 to layout group grid
1043. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 6x6 to layout group grid
1044. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 8x8 to layout group grid
1045. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:275 Adding layout group grid-zoom
1046. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 1x1 to layout group grid-zoom
1047. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 2x1-zoom to layout group grid-zoom
1048. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 3x1-zoom to layout group grid-zoom
1049. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 2x2 to layout group grid-zoom
1050. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 5-grid-zoom to layout group grid-zoom
1051. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 3x2-zoom to layout group grid-zoom
1052. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 7-grid-zoom to layout group grid-zoom
1053. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 4x2-zoom to layout group grid-zoom
1054. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 3x3 to layout group grid-zoom
1055. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:275 Adding layout group 1up_top_left_plus
1056. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 1up_top_left+5 to layout group 1up_top_left_plus
1057. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 1up_top_left+7 to layout group 1up_top_left_plus
1058. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 1up_top_left+9 to layout group 1up_top_left_plus
1059. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:275 Adding layout group 3up_plus
1060. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 3up+4 to layout group 3up_plus
1061. 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 3up+9 to layout group 3up_plus
1062. 2017-08-07 02:36:50.844249 [DEBUG] mod_conference.c:3076 using channel sound prefix: /usr/share/freeswitch/sounds/en/us/callie
1063. send 1289 bytes to udp/[118.70.126.89]:44414 at 02:36:50.853246:
1064.    ------------------------------------------------------------------------
1065.    SIP/2.0 200 OK
1066.    Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.PotJzfLZa;rport=44414;received=118.70.126.89
1067.    From: <sip:100005@52.193.89.3>;tag=tH-SJK~Z-
1068.    To: <sip:12345@52.193.89.3>;tag=22jZm3pX55j5S
1069.    Call-ID: NNFynTe-jL
1070.    CSeq: 21 INVITE
1071.    Contact: <sip:12345@52.193.89.3:5060;transport=udp>
1072.    User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit
1073.    Accept: application/sdp
1074.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
1075.    Supported: timer, path, replaces
1076.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
1077.    Session-Expires: 120;refresher=uas
1078.    Content-Type: application/sdp
1079.    Content-Disposition: session
1080.    Content-Length: 399
1081.    Remote-Party-ID: "12345" <sip:12345@52.193.89.3>;party=calling;privacy=off;screen=no
1082.    
1083.    v=0
1084.    o=FreeSWITCH 1502046856 1502046857 IN IP4 52.193.89.3
1085.    s=FreeSWITCH
1086.    c=IN IP4 52.193.89.3
1087.    t=0 0
1088.    m=audio 26554 RTP/AVP 0 102
1089.    a=rtpmap:0 PCMU/8000
1090.    a=rtpmap:102 telephone-event/8000
1091.    a=fmtp:102 0-16
1092.    a=ptime:20
1093.    m=video 29328 RTP/AVP 96
1094.    b=AS:1024
1095.    a=rtpmap:96 VP8/90000
1096.    a=rtcp:29329 IN IP4 52.193.89.3
1097.    a=rtcp-fb:96 ccm fir
1098.    a=rtcp-fb:96 ccm tmmbr
1099.    a=rtcp-fb:96 nack
1100.    a=rtcp-fb:96 nack pli
1101.    ------------------------------------------------------------------------
1102. 2017-08-07 02:36:50.854191 [DEBUG] conference_video.c:1132 Canvas position 1 applied layout 3x3
1103. 2017-08-07 02:36:50.854191 [DEBUG] conference_video.c:1222 Layout set to 3x3
1104. 2017-08-07 02:36:50.854191 [DEBUG] conference_video.c:1182 Canvas attached to position 0
1105. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:1691 Raw Codec Activation Success L16@8000hz 1 channel 20ms
1106. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:1738 Raw Codec Activation Success L16@8000hz 1 channel 20ms
1107. 2017-08-07 02:36:50.854191 [DEBUG] switch_core_codec.c:223 sofia/internal/100005@52.193.89.3 Push codec L16:100
1108. 2017-08-07 02:36:50.854191 [ERR] switch_core_video.c:2267 This function is not available, libpng not installed
1109. 2017-08-07 02:36:50.854191 [DEBUG] sofia.c:7084 Channel sofia/internal/100005@52.193.89.3 entering state [completed][200]
1110. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '0' to 'mute'
1111. 2017-08-07 02:36:50.854191 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
1112. 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cd80
1113. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '*' to 'deaf mute'
1114. 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cdb0
1115. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '9' to 'energy up'
1116. 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cde0
1117. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '8' to 'energy equ'
1118. 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08ce10
1119. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '7' to 'energy dn'
1120. 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08ce40
1121. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '3' to 'vol talk up'
1122. 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08ce70
1123. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '2' to 'vol talk zero'
1124. 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cea0
1125. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '1' to 'vol talk dn'
1126. 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08ced0
1127. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '6' to 'vol listen up'
1128. 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cf00
1129. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '5' to 'vol listen zero'
1130. 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cf30
1131. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '4' to 'vol listen dn'
1132. 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cf60
1133. 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '#' to 'hangup'
1134. 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cf90
1135. 2017-08-07 02:36:50.854191 [DEBUG] conference_loop.c:1140 Setup timer soft success interval: 20  samples: 160 from codec PCMU
1136. 2017-08-07 02:36:50.854191 [DEBUG] mod_conference.c:220 Setup timer success interval: 20  samples: 160
1137. 2017-08-07 02:36:50.884208 [NOTICE] switch_vpx.c:406 VPX reset encoder picture from 320x240 to 1280x720 1024 BW
1138. 2017-08-07 02:36:50.894197 [NOTICE] switch_vpx.c:406 VPX reset encoder picture from 1280x720 to 1280x720 1024 BW
1139. 2017-08-07 02:36:50.894197 [INFO] switch_vpx.c:502 VPX ENCODER RESET
1140. recv 578 bytes from udp/[118.70.126.89]:44414 at 02:36:51.003211:
1141.    ------------------------------------------------------------------------
1142.    ACK sip:12345@52.193.89.3:5060;transport=udp SIP/2.0
1143.    Via: SIP/2.0/UDP 10.22.30.124:44414;rport;branch=z9hG4bK.oK4jaG2wi
1144.    From: <sip:100005@52.193.89.3>;tag=tH-SJK~Z-
1145.    To: <sip:12345@52.193.89.3>;tag=22jZm3pX55j5S
1146.    CSeq: 21 ACK
1147.    Call-ID: NNFynTe-jL
1148.    Max-Forwards: 70
1149.    Proxy-Authorization:  Digest realm="52.193.89.3", nonce="4434f5b4-7b19-11e7-a745-c76caa719387", algorithm=MD5, username="100005",  uri="sip:12345@52.193.89.3", response="7b5ab9d9d7bb97534957ad7c97693882", cnonce="KsoDY2XgxVohOJ0P", nc=00000001, qop=auth
1150.    User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1)
1151.    
1152.    ------------------------------------------------------------------------
1153. 2017-08-07 02:36:51.014202 [DEBUG] sofia.c:7084 Channel sofia/internal/100005@52.193.89.3 entering state [ready][200]
1154. 2017-08-07 02:36:51.244208 [INFO] switch_rtp.c:6700 Auto Changing video RTCP port from 10.22.30.124:9079 to 123.25.21.255:9079
1155. 2017-08-07 02:36:51.244208 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 123.25.21.255:9079 2
1156. 2017-08-07 02:36:51.384198 [INFO] switch_rtp.c:7231 Auto Changing audio port from 10.22.30.124:7076 to 118.70.126.89:7076
1157. 2017-08-07 02:36:53.614186 [DEBUG] mod_local_stream.c:871 Opening Stream [moh/8000] 8000hz
1158.