From shashi kumar, 6 Years ago, written in FreeSWITCH.
- go back
Embed
Viewing differences between and Bria call log Audio to VIdeo Upgrade
  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.