1. recv 956 bytes from udp/[106.76.208.201]:62757 at 17:43:42.501949:
2.    ------------------------------------------------------------------------
3.    INVITE sip:1000@icc-tech.com:5020 SIP/2.0
4.    Via: SIP/2.0/UDP 100.67.7.149:62757;branch=z9hG4bK-524287-1---f614fd66ba4d9222;rport
5.    Max-Forwards: 70
6.    Contact: <sip:1003@106.76.208.201:62757;rinstance=188e82c61b12fc56>
7.    To: <sip:1000@icc-tech.com:5020>
8.    From: "Brian West"<sip:1003@icc-tech.com:5020>;tag=597fc44b
9.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
10.    CSeq: 1 INVITE
11.   Allow: INVITE, ACK, CANCEL, BYE, REFER, INFO, NOTIFY, OPTIONS, UPDATE, PRACK, MESSAGE, SUBSCRIBE
12.    Content-Type: application/sdp
13.    Supported: replaces, 100rel
14.    User-Agent: Bria Stretto iOS release 5.2.4001 stamp 41079.41081
15.    Content-Length: 329
16.  
17.    v=0
18.    o=- 1525974219987473 1 IN IP4 100.67.7.149
19.    s=Cpc session
20.    c=IN IP4 100.67.7.149
21.    t=0 0
22.    m=audio 58088 RTP/AVP 120 18 0 8 101
23.    a=rtpmap:120 opus/48000/2
24.    a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
25.    a=rtpmap:18 G729/8000
26.    a=fmtp:18 annexb=no
27.    a=rtpmap:101 telephone-event/8000
28.    a=fmtp:101 0-15
29.    a=sendrecv
30.    ------------------------------------------------------------------------
31. 2018-05-10 17:43:42.498051 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1003@icc-tech.com:5020 [a5a22fa7-c802-422a-9819-c7948fb534cf]
32. 2018-05-10 17:43:42.498051 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_NEW (Cur 1 Tot 28)
33. 2018-05-10 17:43:42.498051 [DEBUG] sofia.c:10080 sofia/internal/1003@icc-tech.com:5020 receiving invite from 106.76.208.201:62757 version: 1.9.0 git a139bbd 2018-05-09 16:08:24Z 64bit
34. 2018-05-10 17:43:42.498051 [DEBUG] sofia.c:10251 IP 106.76.208.201 Rejected by acl "domains". Falling back to Digest auth.
35. send 903 bytes to udp/[106.76.208.201]:62757 at 17:43:42.503443:
36.    ------------------------------------------------------------------------
37.    SIP/2.0 407 Proxy Authentication Required
38.    Via: SIP/2.0/UDP 100.67.7.149:62757;branch=z9hG4bK-524287-1---f614fd66ba4d9222;rport=62757;received=106.76.208.201
39.    From: "Brian West"<sip:1003@icc-tech.com:5020>;tag=597fc44b
40.    To: <sip:1000@icc-tech.com:5020>;tag=F2jjNmeX167eK
41.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
42.    CSeq: 1 INVITE
43.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20180509T160824Z~a139bbd195~64bit
44.    Accept: application/sdp
45.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
46.    Supported: timer, path, replaces
47.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
48.    Proxy-Authenticate: Digest realm="icc-tech.com", nonce="a5fab0b0-0c50-4a88-a052-076925eb7c54", algorithm=MD5, qop="auth"
49.    Content-Length: 0
50.  
51.    ------------------------------------------------------------------------
52. 2018-05-10 17:43:42.498051 [DEBUG] sofia.c:2413 detaching session a5a22fa7-c802-422a-9819-c7948fb534cf
53. 2018-05-10 17:43:42.498051 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1003@icc-tech.com:5020) State NEW
54. recv 345 bytes from udp/[106.76.208.201]:62757 at 17:43:42.621735:
55.    ------------------------------------------------------------------------
56.    ACK sip:1000@icc-tech.com:5020 SIP/2.0
57.    Via: SIP/2.0/UDP 100.67.7.149:62757;branch=z9hG4bK-524287-1---f614fd66ba4d9222;rport
58.    Max-Forwards: 70
59.    To: <sip:1000@icc-tech.com:5020>;tag=F2jjNmeX167eK
60.    From: "Brian West"<sip:1003@icc-tech.com:5020>;tag=597fc44b
61.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
62.    CSeq: 1 ACK
63.    Content-Length: 0
64.  
65.    ------------------------------------------------------------------------
66. recv 1221 bytes from udp/[106.76.208.201]:62757 at 17:43:43.141938:
67.    ------------------------------------------------------------------------
68.    INVITE sip:1000@icc-tech.com:5020 SIP/2.0
69.    Via: SIP/2.0/UDP 100.67.7.149:62757;branch=z9hG4bK-524287-1---0deb4f2ceb9d585e;rport
70.    Max-Forwards: 70
71.    Contact: <sip:1003@106.76.208.201:62757;rinstance=188e82c61b12fc56>
72.    To: <sip:1000@icc-tech.com:5020>
73.    From: "Brian West"<sip:1003@icc-tech.com:5020>;tag=597fc44b
74.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
75.    CSeq: 2 INVITE
76.   Allow: INVITE, ACK, CANCEL, BYE, REFER, INFO, NOTIFY, OPTIONS, UPDATE, PRACK, MESSAGE, SUBSCRIBE
77.    Content-Type: application/sdp
78.    Proxy-Authorization: Digest username="1003",realm="icc-tech.com",nonce="a5fab0b0-0c50-4a88-a052-076925eb7c54",uri="sip:1000@icc-tech.com:5020",response="931477ec0e0fa7aad704b58746810c96",cnonce="7ef24382b93267f3cbae8de09138553f",nc=00000001,qop=auth,algorithm=MD5
79.    Supported: replaces, 100rel
80.    User-Agent: Bria Stretto iOS release 5.2.4001 stamp 41079.41081
81.    Content-Length: 329
82.  
83.    v=0
84.    o=- 1525974219987473 1 IN IP4 100.67.7.149
85.    s=Cpc session
86.    c=IN IP4 100.67.7.149
87.    t=0 0
88.    m=audio 58088 RTP/AVP 120 18 0 8 101
89.    a=rtpmap:120 opus/48000/2
90.    a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
91.    a=rtpmap:18 G729/8000
92.    a=fmtp:18 annexb=no
93.    a=rtpmap:101 telephone-event/8000
94.    a=fmtp:101 0-15
95.    a=sendrecv
96.    ------------------------------------------------------------------------
97. 2018-05-10 17:43:43.138055 [DEBUG] sofia.c:2522 Re-attaching to session a5a22fa7-c802-422a-9819-c7948fb534cf
98. 2018-05-10 17:43:43.138055 [DEBUG] sofia.c:10080 sofia/internal/1003@icc-tech.com:5020 receiving invite from 106.76.208.201:62757 version: 1.9.0 git a139bbd 2018-05-09 16:08:24Z 64bit
99. 2018-05-10 17:43:43.138055 [DEBUG] sofia.c:10251 IP 106.76.208.201 Rejected by acl "domains". Falling back to Digest auth.
100. 2018-05-10 17:43:43.138055 [DEBUG] sofia.c:11388 Setting NAT mode based on nat.auto
101. 2018-05-10 17:43:43.138055 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [received][100]
102. 2018-05-10 17:43:43.138055 [DEBUG] sofia.c:7301 Remote SDP:
103. v=0
104. o=- 1525974219987473 1 IN IP4 100.67.7.149
105. s=Cpc session
106. c=IN IP4 100.67.7.149
107. t=0 0
108. m=audio 58088 RTP/AVP 120 18 0 8 101
109. a=rtpmap:120 opus/48000/2
110. a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
111. a=rtpmap:18 G729/8000
112. a=fmtp:18 annexb=no
113. a=rtpmap:101 telephone-event/8000
114. a=fmtp:101 0-15
115.  
116. 2018-05-10 17:43:43.138055 [DEBUG] sofia.c:7695 (sofia/internal/1003@icc-tech.com:5020) State Change CS_NEW -> CS_INIT
117. 2018-05-10 17:43:43.138055 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_INIT (Cur 1 Tot 28)
118. 2018-05-10 17:43:43.138055 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1003@icc-tech.com:5020) State INIT
119. 2018-05-10 17:43:43.138055 [DEBUG] mod_sofia.c:93 sofia/internal/1003@icc-tech.com:5020 SOFIA INIT
120. 2018-05-10 17:43:43.138055 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1003@icc-tech.com:5020 Standard INIT
121. 2018-05-10 17:43:43.138055 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1003@icc-tech.com:5020) State Change CS_INIT -> CS_ROUTING
122. 2018-05-10 17:43:43.138055 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1003@icc-tech.com:5020) State INIT going to sleep
123. 2018-05-10 17:43:43.148019 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_ROUTING (Cur 1 Tot 28)
124. 2018-05-10 17:43:43.148019 [DEBUG] switch_channel.c:2249 (sofia/internal/1003@icc-tech.com:5020) Callstate Change DOWN -> RINGING
125. 2018-05-10 17:43:43.148019 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1003@icc-tech.com:5020) State ROUTING
126. send 400 bytes to udp/[106.76.208.201]:62757 at 17:43:43.148551:
127.    ------------------------------------------------------------------------
128.    SIP/2.0 100 Trying
129.    Via: SIP/2.0/UDP 100.67.7.149:62757;branch=z9hG4bK-524287-1---0deb4f2ceb9d585e;rport=62757;received=106.76.208.201
130.    From: "Brian West"<sip:1003@icc-tech.com:5020>;tag=597fc44b
131.    To: <sip:1000@icc-tech.com:5020>
132.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
133.    CSeq: 2 INVITE
134.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20180509T160824Z~a139bbd195~64bit
135.    Content-Length: 0
136.  
137.    ------------------------------------------------------------------------
138. 2018-05-10 17:43:43.148019 [DEBUG] mod_sofia.c:154 sofia/internal/1003@icc-tech.com:5020 SOFIA ROUTING
139. 2018-05-10 17:43:43.148019 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1003@icc-tech.com:5020 Standard ROUTING
140. 2018-05-10 17:43:43.148019 [INFO] mod_dialplan_xml.c:637 Processing Brian West <1003>->1000 in context default
141. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->unloop] continue=false
142. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
143. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
144. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->tod_example] continue=true
145. Dialplan: sofia/internal/1003@icc-tech.com:5020 Date/Time Match (PASS) [tod_example] break=on-false
146. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(open=true)
147. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->holiday_example] continue=true
148. Dialplan: sofia/internal/1003@icc-tech.com:5020 Date/TimeMatch (FAIL) [holiday_example] break=on-false
149. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->global-intercept] continue=false
150. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [global-intercept] destination_number(1000) =~ /^886$/ break=on-false
151. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->group-intercept] continue=false
152. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [group-intercept] destination_number(1000) =~ /^\*8$/ break=on-false
153. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->intercept-ext] continue=false
154. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [intercept-ext] destination_number(1000) =~ /^\*\*(\d+)$/ break=on-false
155. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->redial] continue=false
156. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [redial] destination_number(1000) =~ /^(redial|870)$/ break=on-false
157. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->global] continue=true
158. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
159. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [global] ${default_password}(12345) =~ /^1234$/ break=never
160. Dialplan: sofia/internal/1003@icc-tech.com:5020 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
161. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
162. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [global] ${switch_r_sdp}(v=0
163. o=- 1525974219987473 1 IN IP4 100.67.7.149
164. s=Cpc session
165. c=IN IP4 100.67.7.149
166. t=0 0
167. m=audio 58088 RTP/AVP 120 18 0 8 101
168. a=rtpmap:120 opus/48000/2
169. a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
170. a=rtpmap:18 G729/8000
171. a=fmtp:18 annexb=no
172. a=rtpmap:101 telephone-event/8000
173. a=fmtp:101 0-15
174. ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
175. Dialplan: sofia/internal/1003@icc-tech.com:5020 Absolute Condition [global]
176. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
177. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
178. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-last_dial/global/${uuid})
179. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
180. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->snom-demo-2] continue=false
181. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [snom-demo-2] destination_number(1000) =~ /^9001$/ break=on-false
182. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->snom-demo-1] continue=false
183. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [snom-demo-1] destination_number(1000) =~ /^9000$/ break=on-false
184. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->eavesdrop] continue=false
185. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [eavesdrop] destination_number(1000) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
186. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->eavesdrop] continue=false
187. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [eavesdrop] destination_number(1000) =~ /^779$/ break=on-false
188. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->call_return] continue=false
189. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [call_return] destination_number(1000) =~ /^\*69$|^869$|^lcr$/ break=on-false
190. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->del-group] continue=false
191. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [del-group] destination_number(1000) =~ /^80(\d{2})$/ break=on-false
192. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->add-group] continue=false
193. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [add-group] destination_number(1000) =~ /^81(\d{2})$/ break=on-false
194. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->call-group-simo] continue=false
195. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [call-group-simo] destination_number(1000) =~ /^82(\d{2})$/ break=on-false
196. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->call-group-order] continue=false
197. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [call-group-order] destination_number(1000) =~ /^83(\d{2})$/ break=on-false
198. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->extension-intercom] continue=false
199. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [extension-intercom] destination_number(1000) =~ /^8(10[01][0-9])$/ break=on-false
200. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->Local_Extension] continue=false
201. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (PASS) [Local_Extension] destination_number(1000) =~ /^(10[01][0-9])$/ break=on-false
202. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action export(dialed_extension=1000)
203. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action bind_meta_app(1 b s execute_extension::dx XML features)
204. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav)
205. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action bind_meta_app(3 b s execute_extension::cf XML features)
206. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action bind_meta_app(4 b s execute_extension::att_xfer XML features)
207. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(ringback=${us-ring})
208. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(transfer_ringback=local_stream://moh)
209. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(call_timeout=30)
210. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(hangup_after_bridge=true)
211. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(continue_on_fail=true)
212. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
213. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
214. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)})
215. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid})
216. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid})
217. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})
218. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action bridge(user/${dialed_extension}@${domain_name})
219. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action answer()
220. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action sleep(1000)
221. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension})
222. 2018-05-10 17:43:43.148019 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1003@icc-tech.com:5020) State Change CS_ROUTING -> CS_EXECUTE
223. 2018-05-10 17:43:43.148019 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1003@icc-tech.com:5020) State ROUTING going to sleep
224. 2018-05-10 17:43:43.148019 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_EXECUTE (Cur 1 Tot 28)
225. 2018-05-10 17:43:43.148019 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1003@icc-tech.com:5020) State EXECUTE
226. 2018-05-10 17:43:43.148019 [DEBUG] mod_sofia.c:209 sofia/internal/1003@icc-tech.com:5020 SOFIA EXECUTE
227. 2018-05-10 17:43:43.148019 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1003@icc-tech.com:5020 Standard EXECUTE
228. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(open=true)
229. 2018-05-10 17:43:43.148019 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [open]=[true]
230. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-spymap/1003/a5a22fa7-c802-422a-9819-c7948fb534cf)
231. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-last_dial/1003/1000)
232. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-last_dial/global/a5a22fa7-c802-422a-9819-c7948fb534cf)
233. EXECUTE sofia/internal/1003@icc-tech.com:5020 export(RFC2822_DATE=Thu, 10 May 2018 17:43:43 +0000)
234. 2018-05-10 17:43:43.148019 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 10 May 2018 17:43:43 +0000]
235. EXECUTE sofia/internal/1003@icc-tech.com:5020 export(dialed_extension=1000)
236. 2018-05-10 17:43:43.148019 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[1000]
237. EXECUTE sofia/internal/1003@icc-tech.com:5020 bind_meta_app(1 b s execute_extension::dx XML features)
238. 2018-05-10 17:43:43.148019 [INFO] switch_ivr_async.c:4273 Bound B-Leg: *1 execute_extension::dx XML features
239. EXECUTE sofia/internal/1003@icc-tech.com:5020 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/1003.2018-05-10-17-43-43.wav)
240. 2018-05-10 17:43:43.148019 [INFO] switch_ivr_async.c:4273 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/1003.2018-05-10-17-43-43.wav
241. EXECUTE sofia/internal/1003@icc-tech.com:5020 bind_meta_app(3 b s execute_extension::cf XML features)
242. 2018-05-10 17:43:43.148019 [INFO] switch_ivr_async.c:4273 Bound B-Leg: *3 execute_extension::cf XML features
243. EXECUTE sofia/internal/1003@icc-tech.com:5020 bind_meta_app(4 b s execute_extension::att_xfer XML features)
244. 2018-05-10 17:43:43.148019 [INFO] switch_ivr_async.c:4273 Bound B-Leg: *4 execute_extension::att_xfer XML features
245. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(ringback=%(2000,4000,440,480))
246. 2018-05-10 17:43:43.148019 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [ringback]=[%(2000,4000,440,480)]
247. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(transfer_ringback=local_stream://moh)
248. 2018-05-10 17:43:43.148019 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [transfer_ringback]=[local_stream://moh]
249. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(call_timeout=30)
250. 2018-05-10 17:43:43.148019 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [call_timeout]=[30]
251. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(hangup_after_bridge=true)
252. 2018-05-10 17:43:43.148019 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [hangup_after_bridge]=[true]
253. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(continue_on_fail=true)
254. 2018-05-10 17:43:43.148019 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [continue_on_fail]=[true]
255. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-call_return/1000/1003)
256. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-last_dial_ext/1000/a5a22fa7-c802-422a-9819-c7948fb534cf)
257. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(called_party_callgroup=techsupport)
258. 2018-05-10 17:43:43.148019 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [called_party_callgroup]=[techsupport]
259. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-last_dial_ext/techsupport/a5a22fa7-c802-422a-9819-c7948fb534cf)
260. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-last_dial_ext/global/a5a22fa7-c802-422a-9819-c7948fb534cf)
261. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-last_dial/techsupport/a5a22fa7-c802-422a-9819-c7948fb534cf)
262. EXECUTE sofia/internal/1003@icc-tech.com:5020 bridge(user/1000@159.89.163.195)
263. 2018-05-10 17:43:43.158136 [DEBUG] switch_channel.c:1250 sofia/internal/1003@icc-tech.com:5020 EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 10 May 2018 17:43:43 +0000] to event
264. 2018-05-10 17:43:43.158136 [DEBUG] switch_channel.c:1250 sofia/internal/1003@icc-tech.com:5020 EXPORTING[export_vars] [dialed_extension]=[1000] to event
265. 2018-05-10 17:43:43.158136 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables
266. 2018-05-10 17:43:43.158136 [DEBUG] switch_channel.c:1250 sofia/internal/1003@icc-tech.com:5020 EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 10 May 2018 17:43:43 +0000] to event
267. 2018-05-10 17:43:43.158136 [DEBUG] switch_channel.c:1250 sofia/internal/1003@icc-tech.com:5020 EXPORTING[export_vars] [dialed_extension]=[1000] to event
268. 2018-05-10 17:43:43.158136 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables
269. 2018-05-10 17:43:43.158136 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1000@124.123.68.233:21102 [5698874b-081f-4571-8b75-7990b1cf2661]
270. 2018-05-10 17:43:43.158136 [DEBUG] mod_sofia.c:5028 (sofia/internal/1000@124.123.68.233:21102) State Change CS_NEW -> CS_INIT
271. 2018-05-10 17:43:43.158136 [NOTICE] switch_ivr_originate.c:2868 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
272. 2018-05-10 17:43:43.158136 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_INIT (Cur 2 Tot 29)
273. 2018-05-10 17:43:43.158136 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1000@124.123.68.233:21102) State INIT
274. 2018-05-10 17:43:43.158136 [DEBUG] mod_sofia.c:93 sofia/internal/1000@124.123.68.233:21102 SOFIA INIT
275. 2018-05-10 17:43:43.158136 [DEBUG] sofia_glue.c:1299 sofia/internal/1000@124.123.68.233:21102 sending invite version: 1.9.0 git a139bbd 2018-05-09 16:08:24Z 64bit
276. Local SDP:
277. v=0
278. o=FreeSWITCH 1525954793 1525954794 IN IP4 159.89.163.195
279. s=FreeSWITCH
280. c=IN IP4 159.89.163.195
281. t=0 0
282. m=audio 19430 RTP/AVP 102 0 8 103 101
283. a=rtpmap:102 opus/48000/2
284. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
285. a=rtpmap:0 PCMU/8000
286. a=rtpmap:8 PCMA/8000
287. a=rtpmap:103 telephone-event/48000
288. a=fmtp:103 0-16
289. a=rtpmap:101 telephone-event/8000
290. a=fmtp:101 0-16
291. a=ptime:20
292. a=sendrecv
293.  
294. send 1484 bytes to udp/[124.123.68.233]:21102 at 17:43:43.165470:
295.    ------------------------------------------------------------------------
296.    INVITE sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f SIP/2.0
297.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport;branch=z9hG4bKtj3KDHQDNFjFm
298.    Max-Forwards: 69
299.    From: "Extension 1003" <sip:1003@159.89.163.195>;tag=Hm53rag4Urmma
300.    To: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>
301.    Call-ID: 85f46c7c-cf1c-1236-a889-528373432f88
302.    CSeq: 122651047 INVITE
303.    Contact: <sip:mod_sofia@159.89.163.195:5020>
304.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20180509T160824Z~a139bbd195~64bit
305.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
306.    Supported: timer, path, replaces
307.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
308.    Content-Type: application/sdp
309.    Content-Disposition: session
310.    Content-Length: 457
311.    X-FS-Support: update_display,send_info
312.    Remote-Party-ID: "Extension 1003" <sip:1003@159.89.163.195>;party=calling;screen=yes;privacy=off
313.  
314.    v=0
315.    o=FreeSWITCH 1525954793 1525954794 IN IP4 159.89.163.195
316.    s=FreeSWITCH
317.    c=IN IP4 159.89.163.195
318.    t=0 0
319.    m=audio 19430 RTP/AVP 102 0 8 103 101
320.    a=rtpmap:102 opus/48000/2
321.    a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
322.    a=rtpmap:0 PCMU/8000
323.    a=rtpmap:8 PCMA/8000
324.    a=rtpmap:103 telephone-event/48000
325.    a=fmtp:103 0-16
326.    a=rtpmap:101 telephone-event/8000
327.    a=fmtp:101 0-16
328.    a=ptime:20
329.    ------------------------------------------------------------------------
330. 2018-05-10 17:43:43.158136 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1000@124.123.68.233:21102 Standard INIT
331. 2018-05-10 17:43:43.158136 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1000@124.123.68.233:21102) State Change CS_INIT -> CS_ROUTING
332. 2018-05-10 17:43:43.158136 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1000@124.123.68.233:21102) State INIT going to sleep
333. 2018-05-10 17:43:43.158136 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_ROUTING (Cur 2 Tot 29)
334. 2018-05-10 17:43:43.158136 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [calling][0]
335. 2018-05-10 17:43:43.158136 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1000@124.123.68.233:21102) State ROUTING
336. 2018-05-10 17:43:43.158136 [DEBUG] mod_sofia.c:154 sofia/internal/1000@124.123.68.233:21102 SOFIA ROUTING
337. 2018-05-10 17:43:43.158136 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1000@124.123.68.233:21102) State Change CS_ROUTING -> CS_CONSUME_MEDIA
338. 2018-05-10 17:43:43.158136 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1000@124.123.68.233:21102) State ROUTING going to sleep
339. 2018-05-10 17:43:43.158136 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 29)
340. 2018-05-10 17:43:43.158136 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1000@124.123.68.233:21102) State CONSUME_MEDIA
341. 2018-05-10 17:43:43.158136 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1000@124.123.68.233:21102) State CONSUME_MEDIA going to sleep
342. recv 321 bytes from udp/[124.123.68.233]:21102 at 17:43:43.285396:
343.    ------------------------------------------------------------------------
344.    SIP/2.0 100 Trying
345.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport=5020;branch=z9hG4bKtj3KDHQDNFjFm
346.    To: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>
347.    From: "Extension 1003" <sip:1003@159.89.163.195>;tag=Hm53rag4Urmma
348.    Call-ID: 85f46c7c-cf1c-1236-a889-528373432f88
349.    CSeq: 122651047 INVITE
350.    Content-Length: 0
351.  
352.    ------------------------------------------------------------------------
353. recv 490 bytes from udp/[124.123.68.233]:21102 at 17:43:43.389124:
354.    ------------------------------------------------------------------------
355.    SIP/2.0 180 Ringing
356.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport=5020;branch=z9hG4bKtj3KDHQDNFjFm
357.    Contact: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>
358.    To: "shashi"<sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>;tag=6d2ca268
359.    From: "Extension 1003" <sip:1003@159.89.163.195>;tag=Hm53rag4Urmma
360.    Call-ID: 85f46c7c-cf1c-1236-a889-528373432f88
361.    CSeq: 122651047 INVITE
362.    User-Agent: Bria Stretto release 5.2.0 stamp 90726
363.    Allow-Events: talk, hold
364.    Content-Length: 0
365.  
366.    ------------------------------------------------------------------------
367. 2018-05-10 17:43:43.388054 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [proceeding][180]
368. 2018-05-10 17:43:43.388054 [NOTICE] sofia.c:7401 Ring-Ready sofia/internal/1000@124.123.68.233:21102!
369. 2018-05-10 17:43:43.388054 [DEBUG] switch_channel.c:3354 (sofia/internal/1000@124.123.68.233:21102) Callstate Change DOWN -> RINGING
370. 2018-05-10 17:43:43.408033 [INFO] switch_ivr_originate.c:1220 Sending early media
371. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:120:48000:20:0:1]/[opus:116:48000:20:0:1]
372. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
373. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:120:48000:20:0:1]/[G722:9:8000:20:64000:1]
374. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:120:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
375. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:120:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
376. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
377. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
378. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
379. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
380. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
381. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
382. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
383. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
384. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
385. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
386. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
387. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
388. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
389. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
390. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5374 Set telephone-event payload to 101@8000
391. 2018-05-10 17:43:43.408033 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps]
392. 2018-05-10 17:43:43.408033 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps]
393. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:3773 Set Codec sofia/internal/1003@icc-tech.com:5020 opus/48000 20 ms 960 samples 0 bits 1 channels
394. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_codec.c:111 sofia/internal/1003@icc-tech.com:5020 Original read codec set to opus:116
395. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:5775 sofia/internal/1003@icc-tech.com:5020 Set 2833 dtmf send payload to 101 recv payload to 101
396. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:8491 AUDIO RTP [sofia/internal/1003@icc-tech.com:5020] 159.89.163.195 port 18206 -> 100.67.7.149 port 58088 codec: 120 ms: 20
397. 2018-05-10 17:43:43.408033 [DEBUG] switch_rtp.c:4277 Starting timer [soft] 960 bytes per 20ms
398. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:8795 sofia/internal/1003@icc-tech.com:5020 Set 2833 dtmf send payload to 101
399. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:8802 sofia/internal/1003@icc-tech.com:5020 Set 2833 dtmf receive payload to 101
400. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:8825 sofia/internal/1003@icc-tech.com:5020 Set rtp dtmf delay to 40
401. 2018-05-10 17:43:43.408033 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1003@icc-tech.com:5020!
402. 2018-05-10 17:43:43.408033 [DEBUG] switch_channel.c:3482 (sofia/internal/1003@icc-tech.com:5020) Callstate Change RINGING -> EARLY
403. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_media.c:8474 Audio params are unchanged for sofia/internal/1003@icc-tech.com:5020.
404. 2018-05-10 17:43:43.408033 [DEBUG] mod_sofia.c:2514 Ring SDP:
405. v=0
406. o=FreeSWITCH 1525956017 1525956018 IN IP4 159.89.163.195
407. s=FreeSWITCH
408. c=IN IP4 159.89.163.195
409. t=0 0
410. m=audio 18206 RTP/AVP 120 101
411. a=rtpmap:120 opus/48000/2
412. a=fmtp:120 useinbandfec=1
413. a=rtpmap:101 telephone-event/8000
414. a=fmtp:101 0-16
415. a=ptime:20
416. a=sendrecv
417.  
418. send 1229 bytes to udp/[106.76.208.201]:62757 at 17:43:43.413091:
419.    ------------------------------------------------------------------------
420.    SIP/2.0 183 Session Progress
421.    Via: SIP/2.0/UDP 100.67.7.149:62757;branch=z9hG4bK-524287-1---0deb4f2ceb9d585e;rport=62757;received=106.76.208.201
422.    From: "Brian West"<sip:1003@icc-tech.com:5020>;tag=597fc44b
423.    To: <sip:1000@icc-tech.com:5020>;tag=gBcBQFZ0yFy1e
424.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
425.    CSeq: 2 INVITE
426.    Contact: <sip:1000@159.89.163.195:5020;transport=udp>
427.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20180509T160824Z~a139bbd195~64bit
428.    Accept: application/sdp
429.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
430.    Supported: timer, path, replaces
431.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
432.    Content-Type: application/sdp
433.    Content-Disposition: session
434.    Content-Length: 258
435.    Remote-Party-ID: "1000" <sip:1000@icc-tech.com>;party=calling;privacy=off;screen=no
436.  
437.    v=0
438.    o=FreeSWITCH 1525956017 1525956018 IN IP4 159.89.163.195
439.    s=FreeSWITCH
440.    c=IN IP4 159.89.163.195
441.    t=0 0
442.    m=audio 18206 RTP/AVP 120 101
443.    a=rtpmap:120 opus/48000/2
444.    a=fmtp:120 useinbandfec=1
445.    a=rtpmap:101 telephone-event/8000
446.    a=fmtp:101 0-16
447.    a=ptime:20
448.    ------------------------------------------------------------------------
449. 2018-05-10 17:43:43.408033 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [early][183]
450. 2018-05-10 17:43:43.408033 [DEBUG] switch_ivr_originate.c:1278 Raw Codec Activation Success L16@48000hz 1 channel 20ms
451. 2018-05-10 17:43:43.408033 [DEBUG] switch_core_codec.c:223 sofia/internal/1003@icc-tech.com:5020 Push codec L16:100
452. 2018-05-10 17:43:43.408033 [DEBUG] switch_ivr_originate.c:1347 Play Ringback Tone [%(2000,4000,440,480)]
453. 2018-05-10 17:43:43.808066 [INFO] switch_rtp.c:7450 Auto Changing audio port from 100.67.7.149:58088 to 106.76.208.201:58088
454. recv 894 bytes from udp/[124.123.68.233]:21102 at 17:43:46.268107:
455.    ------------------------------------------------------------------------
456.    SIP/2.0 200 OK
457.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport=5020;branch=z9hG4bKtj3KDHQDNFjFm
458.    Contact: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>
459.    To: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>;tag=6d2ca268
460.    From: "Extension 1003" <sip:1003@159.89.163.195>;tag=Hm53rag4Urmma
461.    Call-ID: 85f46c7c-cf1c-1236-a889-528373432f88
462.    CSeq: 122651047 INVITE
463.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
464.    Content-Type: application/sdp
465.    Supported: replaces
466.    User-Agent: Bria Stretto release 5.2.0 stamp 90726
467.    Content-Length: 306
468.  
469.    v=0
470.    o=- 3355508437 3 IN IP4 10.144.160.39
471.    s=Bria Stretto release 5.2.0 stamp 90726
472.    c=IN IP4 124.123.68.233
473.    t=0 0
474.    m=audio 21168 RTP/AVP 102 0 8 101
475.    a=rtpmap:102 opus/48000/2
476.    a=fmtp:102 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
477.    a=rtpmap:101 telephone-event/8000
478.    a=fmtp:101 0-15
479.    a=sendrecv
480.    ------------------------------------------------------------------------
481. 2018-05-10 17:43:46.268026 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [completing][200]
482. 2018-05-10 17:43:46.268026 [DEBUG] sofia.c:7301 Remote SDP:
483. v=0
484. o=- 3355508437 3 IN IP4 10.144.160.39
485. s=Bria Stretto release 5.2.0 stamp 90726
486. c=IN IP4 124.123.68.233
487. t=0 0
488. m=audio 21168 RTP/AVP 102 0 8 101
489. a=rtpmap:102 opus/48000/2
490. a=fmtp:102 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
491. a=rtpmap:101 telephone-event/8000
492. a=fmtp:101 0-15
493.  
494. 2018-05-10 17:43:46.268026 [NOTICE] sofia.c:7304 Pre-Answer sofia/internal/1000@124.123.68.233:21102!
495. 2018-05-10 17:43:46.268026 [DEBUG] switch_channel.c:3482 (sofia/internal/1000@124.123.68.233:21102) Callstate Change RINGING -> EARLY
496. send 440 bytes to udp/[124.123.68.233]:21102 at 17:43:46.270253:
497.    ------------------------------------------------------------------------
498.    ACK sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f SIP/2.0
499.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport;branch=z9hG4bKUUvcFc8gjr81F
500.    Max-Forwards: 70
501.    From: "Extension 1003" <sip:1003@159.89.163.195>;tag=Hm53rag4Urmma
502.    To: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>;tag=6d2ca268
503.    Call-ID: 85f46c7c-cf1c-1236-a889-528373432f88
504.    CSeq: 122651047 ACK
505.    Contact: <sip:mod_sofia@159.89.163.195:5020>
506.    Content-Length: 0
507.  
508.    ------------------------------------------------------------------------
509. 2018-05-10 17:43:46.268026 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [ready][200]
510. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:2]
511. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match
512. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
513. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
514. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:2]
515. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
516. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
517. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
518. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:2]
519. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
520. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
521. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
522. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5374 Set telephone-event payload to 101@8000
523. 2018-05-10 17:43:46.268026 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps]
524. 2018-05-10 17:43:46.268026 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps]
525. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:3773 Set Codec sofia/internal/1000@124.123.68.233:21102 opus/48000 20 ms 960 samples 0 bits 1 channels
526. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_codec.c:111 sofia/internal/1000@124.123.68.233:21102 Original read codec set to opus:116
527. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:5775 sofia/internal/1000@124.123.68.233:21102 Set 2833 dtmf send payload to 101 recv payload to 101
528. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:8491 AUDIO RTP [sofia/internal/1000@124.123.68.233:21102] 159.89.163.195 port 19430 -> 124.123.68.233 port 21168 codec: 102 ms: 20
529. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_codec.c:248 sofia/internal/1003@icc-tech.com:5020 Restore previous codec opus:116.
530. 2018-05-10 17:43:46.268026 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [sofia/internal/1000@124.123.68.233:21102]
531. 2018-05-10 17:43:46.268026 [DEBUG] switch_rtp.c:4277 Starting timer [soft] 960 bytes per 20ms
532. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:8795 sofia/internal/1000@124.123.68.233:21102 Set 2833 dtmf send payload to 101
533. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:8802 sofia/internal/1000@124.123.68.233:21102 Set 2833 dtmf receive payload to 101
534. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:8825 sofia/internal/1000@124.123.68.233:21102 Set rtp dtmf delay to 40
535. 2018-05-10 17:43:46.268026 [NOTICE] sofia.c:8429 Channel [sofia/internal/1000@124.123.68.233:21102] has been answered
536. 2018-05-10 17:43:46.268026 [DEBUG] switch_channel.c:3781 (sofia/internal/1000@124.123.68.233:21102) Callstate Change EARLY -> ACTIVE
537. 2018-05-10 17:43:46.268026 [DEBUG] switch_core_media.c:8474 Audio params are unchanged for sofia/internal/1003@icc-tech.com:5020.
538. 2018-05-10 17:43:46.268026 [DEBUG] mod_sofia.c:881 Local SDP sofia/internal/1003@icc-tech.com:5020:
539. v=0
540. o=FreeSWITCH 1525956017 1525956019 IN IP4 159.89.163.195
541. s=FreeSWITCH
542. c=IN IP4 159.89.163.195
543. t=0 0
544. m=audio 18206 RTP/AVP 120 101
545. a=rtpmap:120 opus/48000/2
546. a=fmtp:120 useinbandfec=1
547. a=rtpmap:101 telephone-event/8000
548. a=fmtp:101 0-16
549. a=ptime:20
550. a=sendrecv
551.  
552. send 1235 bytes to udp/[106.76.208.201]:62757 at 17:43:46.277814:
553.    ------------------------------------------------------------------------
554.    SIP/2.0 200 OK
555.    Via: SIP/2.0/UDP 100.67.7.149:62757;branch=z9hG4bK-524287-1---0deb4f2ceb9d585e;rport=62757;received=106.76.208.201
556.    From: "Brian West"<sip:1003@icc-tech.com:5020>;tag=597fc44b
557.    To: <sip:1000@icc-tech.com:5020>;tag=gBcBQFZ0yFy1e
558.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
559.    CSeq: 2 INVITE
560.    Contact: <sip:1000@159.89.163.195:5020;transport=udp>
561.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20180509T160824Z~a139bbd195~64bit
562.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
563.    Supported: timer, path, replaces
564.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
565.    Session-Expires: 120;refresher=uas
566.    Content-Type: application/sdp
567.    Content-Disposition: session
568.    Content-Length: 258
569.    Remote-Party-ID: "Outbound Call" <sip:1000@icc-tech.com>;party=calling;privacy=off;screen=no
570.  
571.    v=0
572.    o=FreeSWITCH 1525956017 1525956018 IN IP4 159.89.163.195
573.    s=FreeSWITCH
574.    c=IN IP4 159.89.163.195
575.    t=0 0
576.    m=audio 18206 RTP/AVP 120 101
577.    a=rtpmap:120 opus/48000/2
578.    a=fmtp:120 useinbandfec=1
579.    a=rtpmap:101 telephone-event/8000
580.    a=fmtp:101 0-16
581.    a=ptime:20
582.    ------------------------------------------------------------------------
583. 2018-05-10 17:43:46.278040 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [completed][200]
584. 2018-05-10 17:43:46.278040 [NOTICE] switch_ivr_originate.c:3664 Channel [sofia/internal/1003@icc-tech.com:5020] has been answered
585. 2018-05-10 17:43:46.278040 [DEBUG] switch_channel.c:3781 (sofia/internal/1003@icc-tech.com:5020) Callstate Change EARLY -> ACTIVE
586. 2018-05-10 17:43:46.278040 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [sofia/internal/1000@124.123.68.233:21102]
587. 2018-05-10 17:43:46.278040 [DEBUG] switch_ivr_bridge.c:1744 (sofia/internal/1000@124.123.68.233:21102) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
588. 2018-05-10 17:43:46.278040 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 29)
589. 2018-05-10 17:43:46.278040 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1000@124.123.68.233:21102) State EXCHANGE_MEDIA
590. 2018-05-10 17:43:46.278040 [DEBUG] mod_sofia.c:645 SOFIA EXCHANGE_MEDIA
591. 2018-05-10 17:43:46.308020 [DEBUG] switch_rtp.c:7490 Correct audio ip/port confirmed.
592. 2018-05-10 17:43:46.308020 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
593. 2018-05-10 17:43:46.308020 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
594. 2018-05-10 17:43:46.308020 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps]
595. 2018-05-10 17:43:46.328046 [DEBUG] switch_rtp.c:7490 Correct audio ip/port confirmed.
596. 2018-05-10 17:43:46.328046 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
597. 2018-05-10 17:43:46.328046 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
598. 2018-05-10 17:43:46.328046 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps]
599. recv 495 bytes from udp/[106.76.208.201]:62757 at 17:43:46.441746:
600.    ------------------------------------------------------------------------
601.    ACK sip:1000@159.89.163.195:5020;transport=udp SIP/2.0
602.    Via: SIP/2.0/UDP 100.67.7.149:62757;branch=z9hG4bK-524287-1---be56da37433b0861;rport
603.    Max-Forwards: 70
604.    Contact: <sip:1003@106.76.208.201:62757;rinstance=188e82c61b12fc56>
605.    To: <sip:1000@icc-tech.com:5020>;tag=gBcBQFZ0yFy1e
606.    From: "Brian West"<sip:1003@icc-tech.com:5020>;tag=597fc44b
607.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
608.    CSeq: 2 ACK
609.    User-Agent: Bria Stretto iOS release 5.2.4001 stamp 41079.41081
610.    Content-Length: 0
611.  
612.    ------------------------------------------------------------------------
613. 2018-05-10 17:43:46.448036 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [ready][200]
614.  
615.  
616. ****************************************Video Activation in Bria App at this movement **********************************************************************************
617.  
618.  
619. recv 1273 bytes from udp/[124.123.68.233]:21102 at 17:44:00.303628:
620.    ------------------------------------------------------------------------
621.    INVITE sip:mod_sofia@159.89.163.195:5020 SIP/2.0
622.    Via: SIP/2.0/UDP 10.144.160.39:49933;branch=z9hG4bK-524287-1---9b22d163c92c4a3f;rport
623.    Max-Forwards: 70
624.    Contact: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>
625.    To: "Extension 1003" <sip:1003@159.89.163.195>;tag=Hm53rag4Urmma
626.    From: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>;tag=6d2ca268
627.    Call-ID: 85f46c7c-cf1c-1236-a889-528373432f88
628.    CSeq: 2 INVITE
629.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
630.    Content-Type: application/sdp
631.    Supported: replaces
632.    User-Agent: Bria Stretto release 5.2.0 stamp 90726
633.    Content-Length: 631
634.  
635.    v=0
636.    o=- 3355508437 4 IN IP4 10.144.160.39
637.    s=Bria Stretto release 5.2.0 stamp 90726
638.    c=IN IP4 124.123.68.233
639.    t=0 0
640.    m=audio 21168 RTP/AVP 102 0 8 101 9 18
641.    a=rtpmap:102 opus/48000/2
642.    a=fmtp:102 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
643.    a=rtpmap:101 telephone-event/8000
644.    a=fmtp:101 0-15
645.    a=rtpmap:18 G729/8000
646.    a=fmtp:18 annexb=yes
647.    a=sendrecv
648.    m=video 21170 RTP/AVP 127 126 100
649.    a=rtpmap:127 H264/90000
650.    a=fmtp:127 profile-level-id=428016;packetization-mode=0
651.    a=rtpmap:126 H264/90000
652.    a=fmtp:126 profile-level-id=428016;packetization-mode=1
653.    a=rtpmap:100 VP8/90000
654.    a=rtcp-fb:* nack
655.    a=rtcp-fb:* nack pli
656.    a=sendrecv
657.    ------------------------------------------------------------------------
658. send 444 bytes to udp/[124.123.68.233]:21102 at 17:44:00.311182:
659.    ------------------------------------------------------------------------
660.    SIP/2.0 100 Trying
661.    Via: SIP/2.0/UDP 10.144.160.39:49933;branch=z9hG4bK-524287-1---9b22d163c92c4a3f;rport=21102;received=124.123.68.233
662.    From: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>;tag=6d2ca268
663.    To: "Extension 1003" <sip:1003@159.89.163.195>;tag=Hm53rag4Urmma
664.    Call-ID: 85f46c7c-cf1c-1236-a889-528373432f88
665.    CSeq: 2 INVITE
666.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20180509T160824Z~a139bbd195~64bit
667.    Content-Length: 0
668.  
669.    ------------------------------------------------------------------------
670. 2018-05-10 17:44:00.308040 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [received][100]
671. 2018-05-10 17:44:00.308040 [DEBUG] sofia.c:7301 Remote SDP:
672. v=0
673. o=- 3355508437 4 IN IP4 10.144.160.39
674. s=Bria Stretto release 5.2.0 stamp 90726
675. c=IN IP4 124.123.68.233
676. t=0 0
677. m=audio 21168 RTP/AVP 102 0 8 101 9 18
678. a=rtpmap:102 opus/48000/2
679. a=fmtp:102 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
680. a=rtpmap:101 telephone-event/8000
681. a=fmtp:101 0-15
682. a=rtpmap:18 G729/8000
683. a=fmtp:18 annexb=yes
684. m=video 21170 RTP/AVP 127 126 100
685. a=rtpmap:127 H264/90000
686. a=fmtp:127 profile-level-id=428016;packetization-mode=0
687. a=rtpmap:126 H264/90000
688. a=fmtp:126 profile-level-id=428016;packetization-mode=1
689. a=rtpmap:100 VP8/90000
690. a=rtcp-fb:* nack
691. a=rtcp-fb:* nack pli
692.  
693. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
694. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
695. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[G722:9:8000:20:64000:1]
696. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
697. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
698. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
699. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
700. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
701. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
702. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
703. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
704. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
705. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
706. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
707. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
708. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5374 Set telephone-event payload to 101@8000
709. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
710. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
711. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
712. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
713. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
714. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
715. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
716. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
717. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
718. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:5775 sofia/internal/1000@124.123.68.233:21102 Set 2833 dtmf send payload to 101 recv payload to 101
719. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:6058 Video Codec Compare [H264:127]/[VP8:99]
720. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:6058 Video Codec Compare [H264:126]/[VP8:99]
721. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:6058 Video Codec Compare [VP8:100]/[VP8:99]
722. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:6090 Video Codec Compare [VP8:100] +++ is saved as a match
723. 2018-05-10 17:44:00.308040 [DEBUG] switch_vpx.c:636 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
724. 2018-05-10 17:44:00.308040 [DEBUG] switch_vpx.c:636 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
725. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:3583 Set VIDEO Codec sofia/internal/1000@124.123.68.233:21102 VP8/90000 0 ms
726. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:8474 Audio params are unchanged for sofia/internal/1000@124.123.68.233:21102.
727. 2018-05-10 17:44:00.308040 [DEBUG] switch_rtp.c:4292 Starting video timer.
728. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:9320 VIDEO RTP [sofia/internal/1000@124.123.68.233:21102] 159.89.163.195:26198->124.123.68.233:21170 codec: 100 ms: 0 [SUCCESS]
729. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:7483 sofia/internal/1000@124.123.68.233:21102 Starting Video thread
730. 2018-05-10 17:44:00.308040 [DEBUG] switch_core_media.c:7349 sofia/internal/1000@124.123.68.233:21102 Video thread started. Echo is off
731. 2018-05-10 17:44:00.308040 [INFO] switch_core_media.c:9400 Activating VIDEO RTCP PORT 21171 interval 1000 mux -1
732. 2018-05-10 17:44:00.308040 [DEBUG] switch_rtp.c:4673 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 21171
733. 2018-05-10 17:44:00.308040 [DEBUG] switch_rtp.c:2568 Setting RTCP remote addr to 124.123.68.233:21171 2
734. 2018-05-10 17:44:00.308040 [DEBUG] sofia.c:8208 Processing updated SDP
735. 2018-05-10 17:44:00.328017 [DEBUG] sofia_glue.c:1299 sofia/internal/1003@icc-tech.com:5020 sending invite version: 1.9.0 git a139bbd 2018-05-09 16:08:24Z 64bit
736. Local SDP:
737. v=0
738. o=FreeSWITCH 1525956017 1525956021 IN IP4 159.89.163.195
739. s=FreeSWITCH
740. c=IN IP4 159.89.163.195
741. t=0 0
742. m=audio 18206 RTP/AVP 102 9 0 8 103 101
743. a=rtpmap:102 opus/48000/2
744. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
745. a=rtpmap:9 G722/8000
746. a=rtpmap:0 PCMU/8000
747. a=rtpmap:8 PCMA/8000
748. a=rtpmap:103 telephone-event/48000
749. a=fmtp:103 0-16
750. a=rtpmap:101 telephone-event/8000
751. a=fmtp:101 0-16
752. a=ptime:20
753. a=sendrecv
754. m=video 26806 RTP/AVP 100
755. b=AS:1024
756. a=rtpmap:100 VP8/90000
757. a=sendrecv
758. a=rtcp-fb:100 ccm fir
759. a=rtcp-fb:100 ccm tmmbr
760. a=rtcp-fb:100 nack
761. a=rtcp-fb:100 nack pli
762.  
763. send 1434 bytes to udp/[106.76.208.201]:62757 at 17:44:00.330585:
764.    ------------------------------------------------------------------------
765.    INVITE sip:1003@106.76.208.201:62757;rinstance=188e82c61b12fc56 SIP/2.0
766.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport;branch=z9hG4bKv4N5g7rmF1ymB
767.    Max-Forwards: 70
768.    From: <sip:1000@icc-tech.com:5020>;tag=gBcBQFZ0yFy1e
769.    To: "Brian West" <sip:1003@icc-tech.com:5020>;tag=597fc44b
770.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
771.    CSeq: 122651056 INVITE
772.    Contact: <sip:1000@159.89.163.195:5020;transport=udp>
773.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20180509T160824Z~a139bbd195~64bit
774.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
775.    Supported: timer, path, replaces
776.    Session-Expires: 120;refresher=uac
777.    Min-SE: 120
778.    Content-Type: application/sdp
779.    Content-Length: 659
780.    X-FS-Support: update_display,send_info
781.  
782.    v=0
783.    o=FreeSWITCH 1525956017 1525956021 IN IP4 159.89.163.195
784.    s=FreeSWITCH
785.    c=IN IP4 159.89.163.195
786.    t=0 0
787.    m=audio 18206 RTP/AVP 102 9 0 8 103 101
788.    a=rtpmap:102 opus/48000/2
789.    a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
790.    a=rtpmap:9 G722/8000
791.    a=rtpmap:0 PCMU/8000
792.    a=rtpmap:8 PCMA/8000
793.    a=rtpmap:103 telephone-event/48000
794.    a=fmtp:103 0-16
795.    a=rtpmap:101 telephone-event/8000
796.    a=fmtp:101 0-16
797.    a=ptime:20
798.    a=sendrecv
799.    m=video 26806 RTP/AVP 100
800.    b=AS:1024
801.    a=rtpmap:100 VP8/90000
802.    a=sendrecv
803.    a=rtcp-fb:100 ccm fir
804.    a=rtcp-fb:100 ccm tmmbr
805.    a=rtcp-fb:100 nack
806.    a=rtcp-fb:100 nack pli
807.    ------------------------------------------------------------------------
808. 2018-05-10 17:44:00.328017 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [calling][0]
809. 2018-05-10 17:44:00.418041 [NOTICE] switch_vpx.c:410 VPX reset encoder picture from 320x240 to 352x288 1024 BW
810. recv 976 bytes from udp/[106.76.208.201]:62757 at 17:44:00.526214:
811.    ------------------------------------------------------------------------
812.    SIP/2.0 200 OK
813.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport=5020;branch=z9hG4bKv4N5g7rmF1ymB
814.    Contact: <sip:1003@106.76.208.201:62757;rinstance=188e82c61b12fc56>
815.    To: "Brian West"<sip:1003@icc-tech.com:5020>;tag=597fc44b
816.    From: <sip:1000@icc-tech.com:5020>;tag=gBcBQFZ0yFy1e
817.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
818.    CSeq: 122651056 INVITE
819.   Allow: INVITE, ACK, CANCEL, BYE, REFER, INFO, NOTIFY, OPTIONS, UPDATE, PRACK, MESSAGE, SUBSCRIBE
820.    Content-Type: application/sdp
821.    Supported: replaces
822.    User-Agent: Bria Stretto iOS release 5.2.4001 stamp 41079.41081
823.    Content-Length: 385
824.  
825.    v=0
826.    o=- 1525974219987473 2 IN IP4 100.67.7.149
827.    s=Cpc session
828.    c=IN IP4 100.67.7.149
829.    t=0 0
830.    m=audio 58088 RTP/AVP 102 0 8 101
831.    a=rtpmap:102 opus/48000/2
832.    a=fmtp:102 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
833.    a=rtpmap:101 telephone-event/8000
834.    a=fmtp:101 0-15
835.    a=sendrecv
836.    m=video 61108 RTP/AVP 100
837.    a=rtpmap:100 VP8/90000
838.    a=sendrecv
839.    a=rtcp-fb:* nack
840.    a=rtcp-fb:* nack pli
841.    ------------------------------------------------------------------------
842. 2018-05-10 17:44:00.528040 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [completing][200]
843. 2018-05-10 17:44:00.528040 [DEBUG] sofia.c:7301 Remote SDP:
844. v=0
845. o=- 1525974219987473 2 IN IP4 100.67.7.149
846. s=Cpc session
847. c=IN IP4 100.67.7.149
848. t=0 0
849. m=audio 58088 RTP/AVP 102 0 8 101
850. a=rtpmap:102 opus/48000/2
851. a=fmtp:102 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
852. a=rtpmap:101 telephone-event/8000
853. a=fmtp:101 0-15
854. a=sendrecv
855. m=video 61108 RTP/AVP 100
856. a=rtpmap:100 VP8/90000
857. a=sendrecv
858. a=rtcp-fb:* nack
859. a=rtcp-fb:* nack pli
860.  
861. send 370 bytes to udp/[106.76.208.201]:62757 at 17:44:00.532424:
862.    ------------------------------------------------------------------------
863.    ACK sip:1003@106.76.208.201:62757;rinstance=188e82c61b12fc56 SIP/2.0
864.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport;branch=z9hG4bKXDFyj29QcaN7p
865.    Max-Forwards: 70
866.    From: <sip:1000@icc-tech.com:5020>;tag=gBcBQFZ0yFy1e
867.    To: "Brian West" <sip:1003@icc-tech.com:5020>;tag=597fc44b
868.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
869.    CSeq: 122651056 ACK
870.    Content-Length: 0
871.  
872.    ------------------------------------------------------------------------
873. 2018-05-10 17:44:00.528040 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [ready][200]
874. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:2]
875. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match
876. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[G722:9:8000:20:64000:1]
877. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
878. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
879. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:2]
880. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
881. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
882. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
883. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
884. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:2]
885. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
886. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
887. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
888. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
889. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5374 Set telephone-event payload to 101@8000
890. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:5775 sofia/internal/1003@icc-tech.com:5020 Set 2833 dtmf send payload to 101 recv payload to 101
891. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:6058 Video Codec Compare [VP8:100]/[VP8:99]
892. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:6090 Video Codec Compare [VP8:100] +++ is saved as a match
893. 2018-05-10 17:44:00.528040 [DEBUG] switch_vpx.c:636 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
894. 2018-05-10 17:44:00.528040 [DEBUG] switch_vpx.c:636 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
895. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:3583 Set VIDEO Codec sofia/internal/1003@icc-tech.com:5020 VP8/90000 0 ms
896. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:8474 Audio params are unchanged for sofia/internal/1003@icc-tech.com:5020.
897. 2018-05-10 17:44:00.528040 [DEBUG] switch_rtp.c:4292 Starting video timer.
898. send 1118 bytes to udp/[124.123.68.233]:21102 at 17:44:00.534056:
899.    ------------------------------------------------------------------------
900.    SIP/2.0 200 OK
901.    Via: SIP/2.0/UDP 10.144.160.39:49933;branch=z9hG4bK-524287-1---9b22d163c92c4a3f;rport=21102;received=124.123.68.233
902.    From: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>;tag=6d2ca268
903.    To: "Extension 1003" <sip:1003@159.89.163.195>;tag=Hm53rag4Urmma
904.    Call-ID: 85f46c7c-cf1c-1236-a889-528373432f88
905.    CSeq: 2 INVITE
906.    Contact: <sip:mod_sofia@159.89.163.195:5020>
907.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20180509T160824Z~a139bbd195~64bit
908.    Accept: application/sdp
909.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
910.    Supported: timer, path, replaces
911.    Content-Type: application/sdp
912.    Content-Disposition: session
913.    Content-Length: 400
914.  
915.    v=0
916.    o=FreeSWITCH 1525954793 1525954795 IN IP4 159.89.163.195
917.    s=FreeSWITCH
918.    c=IN IP4 159.89.163.195
919.    t=0 0
920.    m=audio 19430 RTP/AVP 102 101
921.    a=rtpmap:102 opus/48000/2
922.    a=fmtp:102 useinbandfec=1
923.    a=rtpmap:101 telephone-event/8000
924.    a=fmtp:101 0-16
925.    a=ptime:20
926.    m=video 26198 RTP/AVP 100
927.    b=AS:1024
928.    a=rtpmap:100 VP8/90000
929.    a=rtcp:26199 IN IP4 159.89.163.195
930.    a=rtcp-fb:100 nack
931.    a=rtcp-fb:100 nack pli
932.    ------------------------------------------------------------------------
933. 2018-05-10 17:44:00.528040 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [completed][200]
934. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:9320 VIDEO RTP [sofia/internal/1003@icc-tech.com:5020] 159.89.163.195:26806->100.67.7.149:61108 codec: 100 ms: 0 [SUCCESS]
935. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:7483 sofia/internal/1003@icc-tech.com:5020 Starting Video thread
936. 2018-05-10 17:44:00.528040 [DEBUG] switch_core_media.c:7349 sofia/internal/1003@icc-tech.com:5020 Video thread started. Echo is off
937. 2018-05-10 17:44:00.528040 [INFO] switch_core_media.c:9400 Activating VIDEO RTCP PORT 61109 interval 1000 mux -1
938. 2018-05-10 17:44:00.528040 [DEBUG] switch_rtp.c:4673 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 61109
939. 2018-05-10 17:44:00.528040 [DEBUG] switch_rtp.c:2568 Setting RTCP remote addr to 100.67.7.149:61109 2
940. recv 499 bytes from udp/[124.123.68.233]:21102 at 17:44:00.570076:
941.    ------------------------------------------------------------------------
942.    ACK sip:mod_sofia@159.89.163.195:5020 SIP/2.0
943.    Via: SIP/2.0/UDP 10.144.160.39:49933;branch=z9hG4bK-524287-1---e50b9f1f30c4ad1f;rport
944.    Max-Forwards: 70
945.    Contact: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>
946.    To: "Extension 1003" <sip:1003@159.89.163.195>;tag=Hm53rag4Urmma
947.    From: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>;tag=6d2ca268
948.    Call-ID: 85f46c7c-cf1c-1236-a889-528373432f88
949.    CSeq: 2 ACK
950.    User-Agent: Bria Stretto release 5.2.0 stamp 90726
951.    Content-Length: 0
952.  
953.    ------------------------------------------------------------------------
954. 2018-05-10 17:44:00.568015 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [ready][200]
955. 2018-05-10 17:44:00.588043 [INFO] switch_rtp.c:6909 Auto Changing video RTCP port from 100.67.7.149:61109 to 106.76.208.201:61109
956. 2018-05-10 17:44:00.588043 [DEBUG] switch_rtp.c:2568 Setting RTCP remote addr to 106.76.208.201:61109 2
957. 2018-05-10 17:44:00.668020 [DEBUG] switch_rtp.c:6930 Correct video RTCP ip/port confirmed.
958. 2018-05-10 17:44:01.028046 [DEBUG] switch_rtp.c:1886 rtcp_stats_init: video ssrc[877744567] base_seq[32287]
959. 2018-05-10 17:44:01.038036 [DEBUG] switch_rtp.c:7490 Correct video ip/port confirmed.
960. recv 499 bytes from udp/[124.123.68.233]:21102 at 17:44:11.289404:
961.    ------------------------------------------------------------------------
962.    BYE sip:mod_sofia@159.89.163.195:5020 SIP/2.0
963.    Via: SIP/2.0/UDP 10.144.160.39:49933;branch=z9hG4bK-524287-1---93b386410cc00931;rport
964.    Max-Forwards: 70
965.    Contact: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>
966.    To: "Extension 1003" <sip:1003@159.89.163.195>;tag=Hm53rag4Urmma
967.    From: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>;tag=6d2ca268
968.    Call-ID: 85f46c7c-cf1c-1236-a889-528373432f88
969.    CSeq: 3 BYE
970.    User-Agent: Bria Stretto release 5.2.0 stamp 90726
971.    Content-Length: 0
972.  
973.    ------------------------------------------------------------------------
974. 2018-05-10 17:44:11.288045 [NOTICE] sofia.c:1079 Hangup sofia/internal/1000@124.123.68.233:21102 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
975. send 581 bytes to udp/[124.123.68.233]:21102 at 17:44:11.292677:
976.    ------------------------------------------------------------------------
977.    SIP/2.0 200 OK
978.    Via: SIP/2.0/UDP 10.144.160.39:49933;branch=z9hG4bK-524287-1---93b386410cc00931;rport=21102;received=124.123.68.233
979.    From: <sip:1000@124.123.68.233:21102;rinstance=f72e49e8403a7e5f>;tag=6d2ca268
980.    To: "Extension 1003" <sip:1003@159.89.163.195>;tag=Hm53rag4Urmma
981.    Call-ID: 85f46c7c-cf1c-1236-a889-528373432f88
982.    CSeq: 3 BYE
983.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20180509T160824Z~a139bbd195~64bit
984.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
985.    Supported: timer, path, replaces
986.    Content-Length: 0
987.  
988.    ------------------------------------------------------------------------
989. 2018-05-10 17:44:11.288045 [DEBUG] switch_ivr_bridge.c:848 Ending video thread.
990. 2018-05-10 17:44:11.288045 [DEBUG] switch_ivr_bridge.c:906 Ending video thread.
991. 2018-05-10 17:44:11.288045 [DEBUG] switch_ivr_bridge.c:255 sofia/internal/1000@124.123.68.233:21102 video thread ended.
992. 2018-05-10 17:44:11.288045 [DEBUG] switch_core_media.c:7450 sofia/internal/1000@124.123.68.233:21102 Video thread ended
993. 2018-05-10 17:44:11.298020 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/internal/1000@124.123.68.233:21102]
994. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1000@124.123.68.233:21102) State EXCHANGE_MEDIA going to sleep
995. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_HANGUP (Cur 2 Tot 29)
996. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1000@124.123.68.233:21102) Callstate Change ACTIVE -> HANGUP
997. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1000@124.123.68.233:21102) State HANGUP
998. 2018-05-10 17:44:11.298020 [DEBUG] mod_sofia.c:449 Channel sofia/internal/1000@124.123.68.233:21102 hanging up, cause: NORMAL_CLEARING
999. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1000@124.123.68.233:21102 Standard HANGUP, cause: NORMAL_CLEARING
1000. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1000@124.123.68.233:21102) State HANGUP going to sleep
1001. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1000@124.123.68.233:21102) State Change CS_HANGUP -> CS_REPORTING
1002. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_REPORTING (Cur 2 Tot 29)
1003. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1000@124.123.68.233:21102) State REPORTING
1004. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1000@124.123.68.233:21102 Standard REPORTING, cause: NORMAL_CLEARING
1005. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1000@124.123.68.233:21102) State REPORTING going to sleep
1006. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1000@124.123.68.233:21102) State Change CS_REPORTING -> CS_DESTROY
1007. 2018-05-10 17:44:11.298020 [DEBUG] switch_core_session.c:1713 Session 29 (sofia/internal/1000@124.123.68.233:21102) Locked, Waiting on external entities
1008. 2018-05-10 17:44:11.308030 [DEBUG] switch_ivr_bridge.c:825 sofia/internal/1000@124.123.68.233:21102 ending bridge by request from write function
1009. 2018-05-10 17:44:11.308030 [DEBUG] switch_ivr_bridge.c:848 Ending video thread.
1010. 2018-05-10 17:44:11.308030 [DEBUG] switch_ivr_bridge.c:906 Ending video thread.
1011. 2018-05-10 17:44:11.318063 [DEBUG] switch_ivr_bridge.c:255 sofia/internal/1003@icc-tech.com:5020 video thread ended.
1012. 2018-05-10 17:44:11.318063 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/internal/1003@icc-tech.com:5020]
1013. 2018-05-10 17:44:11.318063 [NOTICE] switch_ivr_bridge.c:1895 Hangup sofia/internal/1003@icc-tech.com:5020 [CS_EXECUTE] [NORMAL_CLEARING]
1014. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_session.c:2885 sofia/internal/1003@icc-tech.com:5020 skip receive message [PHONE_EVENT] (channel is hungup already)
1015. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1003@icc-tech.com:5020) State EXECUTE going to sleep
1016. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_HANGUP (Cur 2 Tot 29)
1017. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1003@icc-tech.com:5020) Callstate Change ACTIVE -> HANGUP
1018. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1003@icc-tech.com:5020) State HANGUP
1019. 2018-05-10 17:44:11.318063 [DEBUG] mod_sofia.c:443 sofia/internal/1003@icc-tech.com:5020 Overriding SIP cause 480 with 200 from the other leg
1020. 2018-05-10 17:44:11.318063 [DEBUG] mod_sofia.c:449 Channel sofia/internal/1003@icc-tech.com:5020 hanging up, cause: NORMAL_CLEARING
1021. 2018-05-10 17:44:11.318063 [DEBUG] mod_sofia.c:502 Sending BYE to sofia/internal/1003@icc-tech.com:5020
1022. send 639 bytes to udp/[106.76.208.201]:62757 at 17:44:11.322734:
1023.    ------------------------------------------------------------------------
1024.    BYE sip:1003@106.76.208.201:62757;rinstance=188e82c61b12fc56 SIP/2.0
1025.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport;branch=z9hG4bKyp8pmXtU9jBtj
1026.    Max-Forwards: 70
1027.    From: <sip:1000@icc-tech.com:5020>;tag=gBcBQFZ0yFy1e
1028.    To: "Brian West" <sip:1003@icc-tech.com:5020>;tag=597fc44b
1029.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
1030.    CSeq: 122651057 BYE
1031.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20180509T160824Z~a139bbd195~64bit
1032.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
1033.    Supported: timer, path, replaces
1034.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
1035.    Content-Length: 0
1036.  
1037.    ------------------------------------------------------------------------
1038. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1003@icc-tech.com:5020 Standard HANGUP, cause: NORMAL_CLEARING
1039. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1003@icc-tech.com:5020) State HANGUP going to sleep
1040. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1003@icc-tech.com:5020) State Change CS_HANGUP -> CS_REPORTING
1041. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_REPORTING (Cur 2 Tot 29)
1042. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1003@icc-tech.com:5020) State REPORTING
1043. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1003@icc-tech.com:5020 Standard REPORTING, cause: NORMAL_CLEARING
1044. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1003@icc-tech.com:5020) State REPORTING going to sleep
1045. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1003@icc-tech.com:5020) State Change CS_REPORTING -> CS_DESTROY
1046. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_session.c:1713 Session 28 (sofia/internal/1003@icc-tech.com:5020) Locked, Waiting on external entities
1047. 2018-05-10 17:44:11.318063 [NOTICE] switch_core_session.c:1731 Session 29 (sofia/internal/1000@124.123.68.233:21102) Ended
1048. 2018-05-10 17:44:11.318063 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/1000@124.123.68.233:21102 [CS_DESTROY]
1049. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_DESTROY (Cur 1 Tot 29)
1050. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1000@124.123.68.233:21102) State DESTROY
1051. 2018-05-10 17:44:11.318063 [DEBUG] mod_sofia.c:354 sofia/internal/1000@124.123.68.233:21102 SOFIA DESTROY
1052. 2018-05-10 17:44:11.318063 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
1053. 2018-05-10 17:44:11.318063 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
1054. 2018-05-10 17:44:11.318063 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
1055. 2018-05-10 17:44:11.318063 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
1056. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1000@124.123.68.233:21102 Standard DESTROY
1057. 2018-05-10 17:44:11.318063 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1000@124.123.68.233:21102) State DESTROY going to sleep
1058. 2018-05-10 17:44:11.368034 [DEBUG] switch_core_media.c:7450 sofia/internal/1003@icc-tech.com:5020 Video thread ended
1059. 2018-05-10 17:44:11.368034 [NOTICE] switch_core_session.c:1731 Session 28 (sofia/internal/1003@icc-tech.com:5020) Ended
1060. 2018-05-10 17:44:11.368034 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/1003@icc-tech.com:5020 [CS_DESTROY]
1061. 2018-05-10 17:44:11.368034 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_DESTROY (Cur 0 Tot 29)
1062. 2018-05-10 17:44:11.368034 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1003@icc-tech.com:5020) State DESTROY
1063. 2018-05-10 17:44:11.368034 [DEBUG] mod_sofia.c:354 sofia/internal/1003@icc-tech.com:5020 SOFIA DESTROY
1064. 2018-05-10 17:44:11.368034 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[131] PLC[0] FEC[0]
1065. 2018-05-10 17:44:11.368034 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
1066. 2018-05-10 17:44:11.368034 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
1067. 2018-05-10 17:44:11.368034 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[143] Bytes encoded[13465] Encoded length ms[2860] Average encoded bitrate bps[53860]
1068. 2018-05-10 17:44:11.368034 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1003@icc-tech.com:5020 Standard DESTROY
1069. 2018-05-10 17:44:11.368034 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1003@icc-tech.com:5020) State DESTROY going to sleep
1070. recv 436 bytes from udp/[106.76.208.201]:62757 at 17:44:12.017774:
1071.    ------------------------------------------------------------------------
1072.    SIP/2.0 200 OK
1073.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport=5020;branch=z9hG4bKyp8pmXtU9jBtj
1074.    Contact: <sip:1003@106.76.208.201:62757;rinstance=188e82c61b12fc56>
1075.    To: "Brian West"<sip:1003@icc-tech.com:5020>;tag=597fc44b
1076.    From: <sip:1000@icc-tech.com:5020>;tag=gBcBQFZ0yFy1e
1077.    Call-ID: YjBiNzI0N2M5MzFhMjg1OWY5YzJiYjkzOGE2YmRhMDg
1078.    CSeq: 122651057 BYE
1079.    User-Agent: Bria Stretto iOS release 5.2.4001 stamp 41079.41081
1080.    Content-Length: 0
1081.  
1082.    ------------------------------------------------------------------------
1083.