FreeSWITCH Pastebin

Bria call log

From shashi kumar, 6 Years ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/41c2a158
Embed
Download Paste or View Raw
  1. span style="color: cornflowerblue; font-weight: bold;"> 2018-05-10 17:33:47.448060 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1003@icc-tech.com:5020 [8c14b729-d7af-4023-b6d8-3c459d8c2bee]
  2. 2018-05-10 17:33:47.448060 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_NEW (Cur 1 Tot 26)
  3. 2018-05-10 17:33:47.448060 [DEBUG] sofia.c:10080 sofia/internal/1003@icc-tech.com:5020 receiving invite from 106.76.208.201:61794 version: 1.9.0 git a139bbd 2018-05-09 16:08:24Z 64bit
  4. 2018-05-10 17:33:47.448060 [DEBUG] sofia.c:10251 IP 106.76.208.201 Rejected by acl "domains". Falling back to Digest auth.
  5. 2018-05-10 17:33:47.448060 [DEBUG] sofia.c:2413 detaching session 8c14b729-d7af-4023-b6d8-3c459d8c2bee
  6. 2018-05-10 17:33:47.448060 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1003@icc-tech.com:5020) State NEW
  7. 2018-05-10 17:33:47.608050 [DEBUG] sofia.c:2522 Re-attaching to session 8c14b729-d7af-4023-b6d8-3c459d8c2bee
  8. 2018-05-10 17:33:47.608050 [DEBUG] sofia.c:10080 sofia/internal/1003@icc-tech.com:5020 receiving invite from 106.76.208.201:61794 version: 1.9.0 git a139bbd 2018-05-09 16:08:24Z 64bit
  9. 2018-05-10 17:33:47.608050 [DEBUG] sofia.c:10251 IP 106.76.208.201 Rejected by acl "domains". Falling back to Digest auth.
  10. 2018-05-10 17:33:47.608050 [DEBUG] sofia.c:11388 Setting NAT mode based on nat.auto
  11. 2018-05-10 17:33:47.608050 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [received][100]
  12. 2018-05-10 17:33:47.608050 [DEBUG] sofia.c:7301 Remote SDP:
  13. v=0
  14. o=- 1525973624044321 1 IN IP4 100.67.7.149
  15. s=Cpc session
  16. c=IN IP4 100.67.7.149
  17. t=0 0
  18. m=audio 53836 RTP/AVP 120 18 0 8 101
  19. a=rtpmap:120 opus/48000/2
  20. a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
  21. a=rtpmap:18 G729/8000
  22. a=fmtp:18 annexb=no
  23. a=rtpmap:101 telephone-event/8000
  24. a=fmtp:101 0-15
  25.  
  26. 2018-05-10 17:33:47.608050 [DEBUG] sofia.c:7695 (sofia/internal/1003@icc-tech.com:5020) State Change CS_NEW -> CS_INIT
  27. 2018-05-10 17:33:47.608050 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_INIT (Cur 1 Tot 26)
  28. 2018-05-10 17:33:47.608050 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1003@icc-tech.com:5020) State INIT
  29. 2018-05-10 17:33:47.608050 [DEBUG] mod_sofia.c:93 sofia/internal/1003@icc-tech.com:5020 SOFIA INIT
  30. 2018-05-10 17:33:47.608050 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1003@icc-tech.com:5020 Standard INIT
  31. 2018-05-10 17:33:47.608050 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1003@icc-tech.com:5020) State Change CS_INIT -> CS_ROUTING
  32. 2018-05-10 17:33:47.608050 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1003@icc-tech.com:5020) State INIT going to sleep
  33. 2018-05-10 17:33:47.608050 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_ROUTING (Cur 1 Tot 26)
  34. 2018-05-10 17:33:47.608050 [DEBUG] switch_channel.c:2249 (sofia/internal/1003@icc-tech.com:5020) Callstate Change DOWN -> RINGING
  35. 2018-05-10 17:33:47.608050 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1003@icc-tech.com:5020) State ROUTING
  36. 2018-05-10 17:33:47.608050 [DEBUG] mod_sofia.c:154 sofia/internal/1003@icc-tech.com:5020 SOFIA ROUTING
  37. 2018-05-10 17:33:47.608050 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1003@icc-tech.com:5020 Standard ROUTING
  38. 2018-05-10 17:33:47.608050 [INFO] mod_dialplan_xml.c:637 Processing Brian West <1003>->1000 in context default
  39. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->unloop] continue=false
  40. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  41. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  42. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->tod_example] continue=true
  43. Dialplan: sofia/internal/1003@icc-tech.com:5020 Date/Time Match (PASS) [tod_example] break=on-false
  44. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(open=true)
  45. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->holiday_example] continue=true
  46. Dialplan: sofia/internal/1003@icc-tech.com:5020 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  47. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->global-intercept] continue=false
  48. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [global-intercept] destination_number(1000) =~ /^886$/ break=on-false
  49. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->group-intercept] continue=false
  50. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [group-intercept] destination_number(1000) =~ /^\*8$/ break=on-false
  51. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->intercept-ext] continue=false
  52. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [intercept-ext] destination_number(1000) =~ /^\*\*(\d+)$/ break=on-false
  53. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->redial] continue=false
  54. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [redial] destination_number(1000) =~ /^(redial|870)$/ break=on-false
  55. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->global] continue=true
  56. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  57. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [global] ${default_password}(12345) =~ /^1234$/ break=never
  58. 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
  59. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  60. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [global] ${switch_r_sdp}(v=0
  61. o=- 1525973624044321 1 IN IP4 100.67.7.149
  62. s=Cpc session
  63. c=IN IP4 100.67.7.149
  64. t=0 0
  65. m=audio 53836 RTP/AVP 120 18 0 8 101
  66. a=rtpmap:120 opus/48000/2
  67. a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
  68. a=rtpmap:18 G729/8000
  69. a=fmtp:18 annexb=no
  70. a=rtpmap:101 telephone-event/8000
  71. a=fmtp:101 0-15
  72. ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
  73. Dialplan: sofia/internal/1003@icc-tech.com:5020 Absolute Condition [global]
  74. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
  75. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  76. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-last_dial/global/${uuid})
  77. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  78. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->snom-demo-2] continue=false
  79. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [snom-demo-2] destination_number(1000) =~ /^9001$/ break=on-false
  80. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->snom-demo-1] continue=false
  81. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [snom-demo-1] destination_number(1000) =~ /^9000$/ break=on-false
  82. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->eavesdrop] continue=false
  83. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [eavesdrop] destination_number(1000) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  84. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->eavesdrop] continue=false
  85. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [eavesdrop] destination_number(1000) =~ /^779$/ break=on-false
  86. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->call_return] continue=false
  87. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [call_return] destination_number(1000) =~ /^\*69$|^869$|^lcr$/ break=on-false
  88. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->del-group] continue=false
  89. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [del-group] destination_number(1000) =~ /^80(\d{2})$/ break=on-false
  90. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->add-group] continue=false
  91. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [add-group] destination_number(1000) =~ /^81(\d{2})$/ break=on-false
  92. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->call-group-simo] continue=false
  93. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [call-group-simo] destination_number(1000) =~ /^82(\d{2})$/ break=on-false
  94. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->call-group-order] continue=false
  95. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [call-group-order] destination_number(1000) =~ /^83(\d{2})$/ break=on-false
  96. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->extension-intercom] continue=false
  97. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (FAIL) [extension-intercom] destination_number(1000) =~ /^8(10[01][0-9])$/ break=on-false
  98. Dialplan: sofia/internal/1003@icc-tech.com:5020 parsing [default->Local_Extension] continue=false
  99. Dialplan: sofia/internal/1003@icc-tech.com:5020 Regex (PASS) [Local_Extension] destination_number(1000) =~ /^(10[01][0-9])$/ break=on-false
  100. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action export(dialed_extension=1000)
  101. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action bind_meta_app(1 b s execute_extension::dx XML features)
  102. 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)
  103. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action bind_meta_app(3 b s execute_extension::cf XML features)
  104. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action bind_meta_app(4 b s execute_extension::att_xfer XML features)
  105. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(ringback=${us-ring})
  106. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(transfer_ringback=local_stream://moh)
  107. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(call_timeout=30)
  108. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(hangup_after_bridge=true)
  109. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(continue_on_fail=true)
  110. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
  111. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
  112. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)})
  113. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid})
  114. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid})
  115. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})
  116. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action bridge(user/${dialed_extension}@${domain_name})
  117. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action answer()
  118. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action sleep(1000)
  119. Dialplan: sofia/internal/1003@icc-tech.com:5020 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension})
  120. 2018-05-10 17:33:47.608050 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1003@icc-tech.com:5020) State Change CS_ROUTING -> CS_EXECUTE
  121. 2018-05-10 17:33:47.608050 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1003@icc-tech.com:5020) State ROUTING going to sleep
  122. 2018-05-10 17:33:47.618022 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_EXECUTE (Cur 1 Tot 26)
  123. 2018-05-10 17:33:47.618022 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1003@icc-tech.com:5020) State EXECUTE
  124. 2018-05-10 17:33:47.618022 [DEBUG] mod_sofia.c:209 sofia/internal/1003@icc-tech.com:5020 SOFIA EXECUTE
  125. 2018-05-10 17:33:47.618022 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1003@icc-tech.com:5020 Standard EXECUTE
  126. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(open=true)
  127. 2018-05-10 17:33:47.618022 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [open]=[true]
  128. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-spymap/1003/8c14b729-d7af-4023-b6d8-3c459d8c2bee)
  129. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-last_dial/1003/1000)
  130. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-last_dial/global/8c14b729-d7af-4023-b6d8-3c459d8c2bee)
  131. EXECUTE sofia/internal/1003@icc-tech.com:5020 export(RFC2822_DATE=Thu, 10 May 2018 17:33:47 +0000)
  132. 2018-05-10 17:33:47.618022 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 10 May 2018 17:33:47 +0000]
  133. EXECUTE sofia/internal/1003@icc-tech.com:5020 export(dialed_extension=1000)
  134. 2018-05-10 17:33:47.618022 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[1000]
  135. EXECUTE sofia/internal/1003@icc-tech.com:5020 bind_meta_app(1 b s execute_extension::dx XML features)
  136. 2018-05-10 17:33:47.618022 [INFO] switch_ivr_async.c:4273 Bound B-Leg: *1 execute_extension::dx XML features
  137. 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-33-47.wav)
  138. 2018-05-10 17:33:47.618022 [INFO] switch_ivr_async.c:4273 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/1003.2018-05-10-17-33-47.wav
  139. EXECUTE sofia/internal/1003@icc-tech.com:5020 bind_meta_app(3 b s execute_extension::cf XML features)
  140. 2018-05-10 17:33:47.618022 [INFO] switch_ivr_async.c:4273 Bound B-Leg: *3 execute_extension::cf XML features
  141. EXECUTE sofia/internal/1003@icc-tech.com:5020 bind_meta_app(4 b s execute_extension::att_xfer XML features)
  142. 2018-05-10 17:33:47.618022 [INFO] switch_ivr_async.c:4273 Bound B-Leg: *4 execute_extension::att_xfer XML features
  143. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(ringback=%(2000,4000,440,480))
  144. 2018-05-10 17:33:47.618022 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [ringback]=[%(2000,4000,440,480)]
  145. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(transfer_ringback=local_stream://moh)
  146. 2018-05-10 17:33:47.618022 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [transfer_ringback]=[local_stream://moh]
  147. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(call_timeout=30)
  148. 2018-05-10 17:33:47.618022 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [call_timeout]=[30]
  149. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(hangup_after_bridge=true)
  150. 2018-05-10 17:33:47.618022 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [hangup_after_bridge]=[true]
  151. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(continue_on_fail=true)
  152. 2018-05-10 17:33:47.618022 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [continue_on_fail]=[true]
  153. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-call_return/1000/1003)
  154. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-last_dial_ext/1000/8c14b729-d7af-4023-b6d8-3c459d8c2bee)
  155. EXECUTE sofia/internal/1003@icc-tech.com:5020 set(called_party_callgroup=techsupport)
  156. 2018-05-10 17:33:47.618022 [DEBUG] mod_dptools.c:1593 SET sofia/internal/1003@icc-tech.com:5020 [called_party_callgroup]=[techsupport]
  157. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-last_dial_ext/techsupport/8c14b729-d7af-4023-b6d8-3c459d8c2bee)
  158. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-last_dial_ext/global/8c14b729-d7af-4023-b6d8-3c459d8c2bee)
  159. EXECUTE sofia/internal/1003@icc-tech.com:5020 hash(insert/159.89.163.195-last_dial/techsupport/8c14b729-d7af-4023-b6d8-3c459d8c2bee)
  160. EXECUTE sofia/internal/1003@icc-tech.com:5020 bridge(user/1000@159.89.163.195)
  161. 2018-05-10 17:33:47.618022 [DEBUG] switch_channel.c:1250 sofia/internal/1003@icc-tech.com:5020 EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 10 May 2018 17:33:47 +0000] to event
  162. 2018-05-10 17:33:47.618022 [DEBUG] switch_channel.c:1250 sofia/internal/1003@icc-tech.com:5020 EXPORTING[export_vars] [dialed_extension]=[1000] to event
  163. 2018-05-10 17:33:47.618022 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables
  164. 2018-05-10 17:33:47.618022 [DEBUG] switch_channel.c:1250 sofia/internal/1003@icc-tech.com:5020 EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 10 May 2018 17:33:47 +0000] to event
  165. 2018-05-10 17:33:47.618022 [DEBUG] switch_channel.c:1250 sofia/internal/1003@icc-tech.com:5020 EXPORTING[export_vars] [dialed_extension]=[1000] to event
  166. 2018-05-10 17:33:47.618022 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables
  167. 2018-05-10 17:33:47.618022 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1000@124.123.68.233:21102 [83108376-bd36-4d24-97fa-d97b4e46d163]
  168. 2018-05-10 17:33:47.618022 [DEBUG] mod_sofia.c:5028 (sofia/internal/1000@124.123.68.233:21102) State Change CS_NEW -> CS_INIT
  169. 2018-05-10 17:33:47.628017 [NOTICE] switch_ivr_originate.c:2868 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
  170. 2018-05-10 17:33:47.628017 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_INIT (Cur 2 Tot 27)
  171. 2018-05-10 17:33:47.628017 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1000@124.123.68.233:21102) State INIT
  172. 2018-05-10 17:33:47.628017 [DEBUG] mod_sofia.c:93 sofia/internal/1000@124.123.68.233:21102 SOFIA INIT
  173. 2018-05-10 17:33:47.628017 [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
  174. Local SDP:
  175. v=0
  176. o=FreeSWITCH 1525947109 1525947110 IN IP4 159.89.163.195
  177. s=FreeSWITCH
  178. c=IN IP4 159.89.163.195
  179. t=0 0
  180. m=audio 26518 RTP/AVP 102 0 8 103 101
  181. a=rtpmap:102 opus/48000/2
  182. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  183. a=rtpmap:0 PCMU/8000
  184. a=rtpmap:8 PCMA/8000
  185. a=rtpmap:103 telephone-event/48000
  186. a=fmtp:103 0-16
  187. a=rtpmap:101 telephone-event/8000
  188. a=fmtp:101 0-16
  189. a=ptime:20
  190. a=sendrecv
  191.  
  192. 2018-05-10 17:33:47.628017 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1000@124.123.68.233:21102 Standard INIT
  193. 2018-05-10 17:33:47.628017 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1000@124.123.68.233:21102) State Change CS_INIT -> CS_ROUTING
  194. 2018-05-10 17:33:47.628017 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1000@124.123.68.233:21102) State INIT going to sleep
  195. 2018-05-10 17:33:47.628017 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_ROUTING (Cur 2 Tot 27)
  196. 2018-05-10 17:33:47.628017 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [calling][0]
  197. 2018-05-10 17:33:47.628017 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1000@124.123.68.233:21102) State ROUTING
  198. 2018-05-10 17:33:47.628017 [DEBUG] mod_sofia.c:154 sofia/internal/1000@124.123.68.233:21102 SOFIA ROUTING
  199. 2018-05-10 17:33:47.628017 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1000@124.123.68.233:21102) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  200. 2018-05-10 17:33:47.628017 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1000@124.123.68.233:21102) State ROUTING going to sleep
  201. 2018-05-10 17:33:47.628017 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 27)
  202. 2018-05-10 17:33:47.628017 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1000@124.123.68.233:21102) State CONSUME_MEDIA
  203. 2018-05-10 17:33:47.628017 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1000@124.123.68.233:21102) State CONSUME_MEDIA going to sleep
  204. 2018-05-10 17:33:48.088071 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [proceeding][180]
  205. 2018-05-10 17:33:48.088071 [NOTICE] sofia.c:7401 Ring-Ready sofia/internal/1000@124.123.68.233:21102!
  206. 2018-05-10 17:33:48.088071 [DEBUG] switch_channel.c:3354 (sofia/internal/1000@124.123.68.233:21102) Callstate Change DOWN -> RINGING
  207. 2018-05-10 17:33:48.088071 [INFO] switch_ivr_originate.c:1220 Sending early media
  208. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:120:48000:20:0:1]/[opus:116:48000:20:0:1]
  209. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  210. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:120:48000:20:0:1]/[G722:9:8000:20:64000:1]
  211. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:120:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
  212. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:120:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
  213. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
  214. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  215. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  216. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  217. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  218. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  219. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  220. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  221. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  222. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  223. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  224. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  225. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  226. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  227. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5374 Set telephone-event payload to 101@8000
  228. 2018-05-10 17:33:48.088071 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps]
  229. 2018-05-10 17:33:48.088071 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps]
  230. 2018-05-10 17:33:48.088071 [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
  231. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_codec.c:111 sofia/internal/1003@icc-tech.com:5020 Original read codec set to opus:116
  232. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:5775 sofia/internal/1003@icc-tech.com:5020 Set 2833 dtmf send payload to 101 recv payload to 101
  233. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:8491 AUDIO RTP [sofia/internal/1003@icc-tech.com:5020] 159.89.163.195 port 27578 -> 100.67.7.149 port 53836 codec: 120 ms: 20
  234. 2018-05-10 17:33:48.088071 [DEBUG] switch_rtp.c:4277 Starting timer [soft] 960 bytes per 20ms
  235. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:8795 sofia/internal/1003@icc-tech.com:5020 Set 2833 dtmf send payload to 101
  236. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:8802 sofia/internal/1003@icc-tech.com:5020 Set 2833 dtmf receive payload to 101
  237. 2018-05-10 17:33:48.088071 [DEBUG] switch_core_media.c:8825 sofia/internal/1003@icc-tech.com:5020 Set rtp dtmf delay to 40
  238. 2018-05-10 17:33:48.088071 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1003@icc-tech.com:5020!
  239. 2018-05-10 17:33:48.098017 [DEBUG] switch_channel.c:3482 (sofia/internal/1003@icc-tech.com:5020) Callstate Change RINGING -> EARLY
  240. 2018-05-10 17:33:48.098017 [DEBUG] switch_core_media.c:8474 Audio params are unchanged for sofia/internal/1003@icc-tech.com:5020.
  241. 2018-05-10 17:33:48.098017 [DEBUG] mod_sofia.c:2514 Ring SDP:
  242. v=0
  243. o=FreeSWITCH 1525946050 1525946051 IN IP4 159.89.163.195
  244. s=FreeSWITCH
  245. c=IN IP4 159.89.163.195
  246. t=0 0
  247. m=audio 27578 RTP/AVP 120 101
  248. a=rtpmap:120 opus/48000/2
  249. a=fmtp:120 useinbandfec=1
  250. a=rtpmap:101 telephone-event/8000
  251. a=fmtp:101 0-16
  252. a=ptime:20
  253. a=sendrecv
  254.  
  255. 2018-05-10 17:33:48.098017 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [early][183]
  256. 2018-05-10 17:33:48.098017 [DEBUG] switch_ivr_originate.c:1278 Raw Codec Activation Success L16@48000hz 1 channel 20ms
  257. 2018-05-10 17:33:48.098017 [DEBUG] switch_core_codec.c:223 sofia/internal/1003@icc-tech.com:5020 Push codec L16:100
  258. 2018-05-10 17:33:48.098017 [DEBUG] switch_ivr_originate.c:1347 Play Ringback Tone [%(2000,4000,440,480)]
  259. 2018-05-10 17:33:48.648051 [INFO] switch_rtp.c:7450 Auto Changing audio port from 100.67.7.149:53836 to 106.76.208.201:53836
  260. 2018-05-10 17:33:52.048048 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [completing][200]
  261. 2018-05-10 17:33:52.048048 [DEBUG] sofia.c:7301 Remote SDP:
  262. v=0
  263. o=- 2761335767 3 IN IP4 10.144.160.39
  264. s=Bria Stretto release 5.2.0 stamp 90726
  265. c=IN IP4 124.123.68.233
  266. t=0 0
  267. m=audio 21142 RTP/AVP 102 0 8 101
  268. a=rtpmap:102 opus/48000/2
  269. a=fmtp:102 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
  270. a=rtpmap:101 telephone-event/8000
  271. a=fmtp:101 0-15
  272.  
  273. 2018-05-10 17:33:52.048048 [NOTICE] sofia.c:7304 Pre-Answer sofia/internal/1000@124.123.68.233:21102!
  274. 2018-05-10 17:33:52.048048 [DEBUG] switch_channel.c:3482 (sofia/internal/1000@124.123.68.233:21102) Callstate Change RINGING -> EARLY
  275. 2018-05-10 17:33:52.048048 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [ready][200]
  276. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:2]
  277. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match
  278. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
  279. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
  280. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:2]
  281. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  282. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  283. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  284. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:2]
  285. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  286. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  287. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  288. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:5374 Set telephone-event payload to 101@8000
  289. 2018-05-10 17:33:52.048048 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps]
  290. 2018-05-10 17:33:52.048048 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps]
  291. 2018-05-10 17:33:52.048048 [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
  292. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_codec.c:111 sofia/internal/1000@124.123.68.233:21102 Original read codec set to opus:116
  293. 2018-05-10 17:33:52.048048 [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
  294. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_media.c:8491 AUDIO RTP [sofia/internal/1000@124.123.68.233:21102] 159.89.163.195 port 26518 -> 124.123.68.233 port 21142 codec: 102 ms: 20
  295. 2018-05-10 17:33:52.048048 [DEBUG] switch_core_codec.c:248 sofia/internal/1003@icc-tech.com:5020 Restore previous codec opus:116.
  296. 2018-05-10 17:33:52.048048 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [sofia/internal/1000@124.123.68.233:21102]
  297. 2018-05-10 17:33:52.058015 [DEBUG] switch_rtp.c:4277 Starting timer [soft] 960 bytes per 20ms
  298. 2018-05-10 17:33:52.058015 [DEBUG] switch_core_media.c:8795 sofia/internal/1000@124.123.68.233:21102 Set 2833 dtmf send payload to 101
  299. 2018-05-10 17:33:52.058015 [DEBUG] switch_core_media.c:8802 sofia/internal/1000@124.123.68.233:21102 Set 2833 dtmf receive payload to 101
  300. 2018-05-10 17:33:52.058015 [DEBUG] switch_core_media.c:8825 sofia/internal/1000@124.123.68.233:21102 Set rtp dtmf delay to 40
  301. 2018-05-10 17:33:52.058015 [NOTICE] sofia.c:8429 Channel [sofia/internal/1000@124.123.68.233:21102] has been answered
  302. 2018-05-10 17:33:52.058015 [DEBUG] switch_channel.c:3781 (sofia/internal/1000@124.123.68.233:21102) Callstate Change EARLY -> ACTIVE
  303. 2018-05-10 17:33:52.058015 [DEBUG] switch_core_media.c:8474 Audio params are unchanged for sofia/internal/1003@icc-tech.com:5020.
  304. 2018-05-10 17:33:52.058015 [DEBUG] mod_sofia.c:881 Local SDP sofia/internal/1003@icc-tech.com:5020:
  305. v=0
  306. o=FreeSWITCH 1525946050 1525946052 IN IP4 159.89.163.195
  307. s=FreeSWITCH
  308. c=IN IP4 159.89.163.195
  309. t=0 0
  310. m=audio 27578 RTP/AVP 120 101
  311. a=rtpmap:120 opus/48000/2
  312. a=fmtp:120 useinbandfec=1
  313. a=rtpmap:101 telephone-event/8000
  314. a=fmtp:101 0-16
  315. a=ptime:20
  316. a=sendrecv
  317.  
  318. 2018-05-10 17:33:52.058015 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [completed][200]
  319. 2018-05-10 17:33:52.058015 [NOTICE] switch_ivr_originate.c:3664 Channel [sofia/internal/1003@icc-tech.com:5020] has been answered
  320. 2018-05-10 17:33:52.058015 [DEBUG] switch_channel.c:3781 (sofia/internal/1003@icc-tech.com:5020) Callstate Change EARLY -> ACTIVE
  321. 2018-05-10 17:33:52.058015 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [sofia/internal/1000@124.123.68.233:21102]
  322. 2018-05-10 17:33:52.058015 [DEBUG] switch_ivr_bridge.c:1744 (sofia/internal/1000@124.123.68.233:21102) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  323. 2018-05-10 17:33:52.058015 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 27)
  324. 2018-05-10 17:33:52.058015 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1000@124.123.68.233:21102) State EXCHANGE_MEDIA
  325. 2018-05-10 17:33:52.058015 [DEBUG] mod_sofia.c:645 SOFIA EXCHANGE_MEDIA
  326. 2018-05-10 17:33:52.088035 [DEBUG] switch_rtp.c:7490 Correct audio ip/port confirmed.
  327. 2018-05-10 17:33:52.088035 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  328. 2018-05-10 17:33:52.088035 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
  329. 2018-05-10 17:33:52.088035 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps]
  330. 2018-05-10 17:33:52.088035 [DEBUG] switch_rtp.c:7490 Correct audio ip/port confirmed.
  331. 2018-05-10 17:33:52.088035 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  332. 2018-05-10 17:33:52.088035 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
  333. 2018-05-10 17:33:52.088035 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps]
  334. 2018-05-10 17:33:52.268043 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [ready][200]
  335. 2018-05-10 17:34:02.408125 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [received][100]
  336. 2018-05-10 17:34:02.408125 [DEBUG] sofia.c:7301 Remote SDP:
  337. v=0
  338. o=- 2761335767 4 IN IP4 10.144.160.39
  339. s=Bria Stretto release 5.2.0 stamp 90726
  340. c=IN IP4 124.123.68.233
  341. t=0 0
  342. m=audio 21142 RTP/AVP 102 0 8 101 9 18
  343. a=rtpmap:102 opus/48000/2
  344. a=fmtp:102 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
  345. a=rtpmap:101 telephone-event/8000
  346. a=fmtp:101 0-15
  347. a=rtpmap:18 G729/8000
  348. a=fmtp:18 annexb=yes
  349. m=video 21144 RTP/AVP 127 126 100
  350. a=rtpmap:127 H264/90000
  351. a=fmtp:127 profile-level-id=428016;packetization-mode=0
  352. a=rtpmap:126 H264/90000
  353. a=fmtp:126 profile-level-id=428016;packetization-mode=1
  354. a=rtpmap:100 VP8/90000
  355. a=rtcp-fb:* nack
  356. a=rtcp-fb:* nack pli
  357.  
  358. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
  359. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  360. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[G722:9:8000:20:64000:1]
  361. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
  362. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
  363. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  364. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  365. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  366. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  367. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  368. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  369. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  370. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  371. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  372. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  373. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5374 Set telephone-event payload to 101@8000
  374. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  375. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  376. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  377. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  378. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  379. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
  380. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  381. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  382. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  383. 2018-05-10 17:34:02.408125 [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
  384. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:6058 Video Codec Compare [H264:127]/[VP8:99]
  385. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:6058 Video Codec Compare [H264:126]/[VP8:99]
  386. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:6058 Video Codec Compare [VP8:100]/[VP8:99]
  387. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:6090 Video Codec Compare [VP8:100] +++ is saved as a match
  388. 2018-05-10 17:34:02.408125 [DEBUG] switch_vpx.c:636 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  389. 2018-05-10 17:34:02.408125 [DEBUG] switch_vpx.c:636 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  390. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:3583 Set VIDEO Codec sofia/internal/1000@124.123.68.233:21102 VP8/90000 0 ms
  391. 2018-05-10 17:34:02.408125 [DEBUG] switch_core_media.c:8474 Audio params are unchanged for sofia/internal/1000@124.123.68.233:21102.
  392. 2018-05-10 17:34:02.408125 [DEBUG] switch_rtp.c:4292 Starting video timer.
  393. 2018-05-10 17:34:02.418032 [DEBUG] switch_core_media.c:9320 VIDEO RTP [sofia/internal/1000@124.123.68.233:21102] 159.89.163.195:19304->124.123.68.233:21144 codec: 100 ms: 0 [SUCCESS]
  394. 2018-05-10 17:34:02.418032 [DEBUG] switch_core_media.c:7483 sofia/internal/1000@124.123.68.233:21102 Starting Video thread
  395. 2018-05-10 17:34:02.418032 [DEBUG] switch_core_media.c:7349 sofia/internal/1000@124.123.68.233:21102 Video thread started. Echo is off
  396. 2018-05-10 17:34:02.418032 [INFO] switch_core_media.c:9400 Activating VIDEO RTCP PORT 21145 interval 1000 mux -1
  397. 2018-05-10 17:34:02.418032 [DEBUG] switch_rtp.c:4673 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 21145
  398. 2018-05-10 17:34:02.418032 [DEBUG] switch_rtp.c:2568 Setting RTCP remote addr to 124.123.68.233:21145 2
  399. 2018-05-10 17:34:02.418032 [DEBUG] sofia.c:8208 Processing updated SDP
  400. 2018-05-10 17:34:02.428022 [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
  401. Local SDP:
  402. v=0
  403. o=FreeSWITCH 1525946050 1525946054 IN IP4 159.89.163.195
  404. s=FreeSWITCH
  405. c=IN IP4 159.89.163.195
  406. t=0 0
  407. m=audio 27578 RTP/AVP 102 9 0 8 103 101
  408. a=rtpmap:102 opus/48000/2
  409. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  410. a=rtpmap:9 G722/8000
  411. a=rtpmap:0 PCMU/8000
  412. a=rtpmap:8 PCMA/8000
  413. a=rtpmap:103 telephone-event/48000
  414. a=fmtp:103 0-16
  415. a=rtpmap:101 telephone-event/8000
  416. a=fmtp:101 0-16
  417. a=ptime:20
  418. a=sendrecv
  419. m=video 17616 RTP/AVP 100
  420. b=AS:1024
  421. a=rtpmap:100 VP8/90000
  422. a=sendrecv
  423. a=rtcp-fb:100 ccm fir
  424. a=rtcp-fb:100 ccm tmmbr
  425. a=rtcp-fb:100 nack
  426. a=rtcp-fb:100 nack pli
  427.  
  428. 2018-05-10 17:34:02.428022 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [calling][0]
  429. 2018-05-10 17:34:02.528049 [NOTICE] switch_vpx.c:410 VPX reset encoder picture from 320x240 to 352x288 1024 BW
  430. 2018-05-10 17:34:02.928064 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [completing][200]
  431. 2018-05-10 17:34:02.928064 [DEBUG] sofia.c:7301 Remote SDP:
  432. v=0
  433. o=- 1525973624044321 2 IN IP4 100.67.7.149
  434. s=Cpc session
  435. c=IN IP4 100.67.7.149
  436. t=0 0
  437. m=audio 53836 RTP/AVP 102 0 8 101
  438. a=rtpmap:102 opus/48000/2
  439. a=fmtp:102 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
  440. a=rtpmap:101 telephone-event/8000
  441. a=fmtp:101 0-15
  442. a=sendrecv
  443. m=video 65292 RTP/AVP 100
  444. a=rtpmap:100 VP8/90000
  445. a=sendrecv
  446. a=rtcp-fb:* nack
  447. a=rtcp-fb:* nack pli
  448.  
  449. 2018-05-10 17:34:02.928064 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@icc-tech.com:5020 entering state [ready][200]
  450. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:2]
  451. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match
  452. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[G722:9:8000:20:64000:1]
  453. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
  454. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
  455. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:2]
  456. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  457. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  458. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  459. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  460. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:2]
  461. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  462. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  463. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5458 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  464. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5513 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  465. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5374 Set telephone-event payload to 101@8000
  466. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:5775 sofia/internal/1003@icc-tech.com:5020 Set 2833 dtmf send payload to 101 recv payload to 101
  467. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:6058 Video Codec Compare [VP8:100]/[VP8:99]
  468. 2018-05-10 17:34:02.928064 [DEBUG] switch_core_media.c:6090 Video Codec Compare [VP8:100] +++ is saved as a match
  469. 2018-05-10 17:34:02.928064 [DEBUG] switch_vpx.c:636 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  470. 2018-05-10 17:34:02.938033 [DEBUG] switch_vpx.c:636 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  471. 2018-05-10 17:34:02.938033 [DEBUG] switch_core_media.c:3583 Set VIDEO Codec sofia/internal/1003@icc-tech.com:5020 VP8/90000 0 ms
  472. 2018-05-10 17:34:02.938033 [DEBUG] switch_core_media.c:8474 Audio params are unchanged for sofia/internal/1003@icc-tech.com:5020.
  473. 2018-05-10 17:34:02.938033 [DEBUG] switch_rtp.c:4292 Starting video timer.
  474. 2018-05-10 17:34:02.938033 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [completed][200]
  475. 2018-05-10 17:34:02.938033 [DEBUG] switch_core_media.c:9320 VIDEO RTP [sofia/internal/1003@icc-tech.com:5020] 159.89.163.195:17616->100.67.7.149:65292 codec: 100 ms: 0 [SUCCESS]
  476. 2018-05-10 17:34:02.938033 [DEBUG] switch_core_media.c:7483 sofia/internal/1003@icc-tech.com:5020 Starting Video thread
  477. 2018-05-10 17:34:02.938033 [DEBUG] switch_core_media.c:7349 sofia/internal/1003@icc-tech.com:5020 Video thread started. Echo is off
  478. 2018-05-10 17:34:02.938033 [INFO] switch_core_media.c:9400 Activating VIDEO RTCP PORT 65293 interval 1000 mux -1
  479. 2018-05-10 17:34:02.938033 [DEBUG] switch_rtp.c:4673 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 65293
  480. 2018-05-10 17:34:02.938033 [DEBUG] switch_rtp.c:2568 Setting RTCP remote addr to 100.67.7.149:65293 2
  481. 2018-05-10 17:34:02.988027 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@124.123.68.233:21102 entering state [ready][200]
  482. 2018-05-10 17:34:03.058039 [DEBUG] switch_rtp.c:6930 Correct video RTCP ip/port confirmed.
  483. 2018-05-10 17:34:03.058039 [DEBUG] switch_rtp.c:1886 rtcp_stats_init: video ssrc[1767057113] base_seq[11366]
  484. 2018-05-10 17:34:03.098032 [DEBUG] switch_rtp.c:7490 Correct video ip/port confirmed.
  485. 2018-05-10 17:34:04.338078 [INFO] switch_rtp.c:6909 Auto Changing video RTCP port from 100.67.7.149:65293 to 106.76.208.201:65293
  486. 2018-05-10 17:34:04.338078 [DEBUG] switch_rtp.c:2568 Setting RTCP remote addr to 106.76.208.201:65293 2
  487. 2018-05-10 17:34:18.648044 [NOTICE] sofia.c:1079 Hangup sofia/internal/1003@icc-tech.com:5020 [CS_EXECUTE] [NORMAL_CLEARING]
  488. 2018-05-10 17:34:18.648044 [DEBUG] switch_ivr_bridge.c:848 Ending video thread.
  489. 2018-05-10 17:34:18.658017 [DEBUG] switch_ivr_bridge.c:906 Ending video thread.
  490. 2018-05-10 17:34:18.658017 [DEBUG] switch_ivr_bridge.c:825 sofia/internal/1003@icc-tech.com:5020 ending bridge by request from write function
  491. 2018-05-10 17:34:18.658017 [DEBUG] switch_ivr_bridge.c:848 Ending video thread.
  492. 2018-05-10 17:34:18.658017 [DEBUG] switch_ivr_bridge.c:906 Ending video thread.
  493. 2018-05-10 17:34:18.658017 [DEBUG] switch_ivr_bridge.c:255 sofia/internal/1000@124.123.68.233:21102 video thread ended.
  494. 2018-05-10 17:34:18.658017 [DEBUG] switch_ivr_bridge.c:258 sofia/internal/1003@icc-tech.com:5020 skip receive message [DISPLAY] (channel is hungup already)
  495. 2018-05-10 17:34:18.658017 [DEBUG] switch_ivr_bridge.c:255 sofia/internal/1003@icc-tech.com:5020 video thread ended.
  496. 2018-05-10 17:34:18.658017 [DEBUG] switch_core_media.c:7450 sofia/internal/1003@icc-tech.com:5020 Video thread ended
  497. 2018-05-10 17:34:18.668038 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/internal/1003@icc-tech.com:5020]
  498. 2018-05-10 17:34:18.668038 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/internal/1000@124.123.68.233:21102]
  499. 2018-05-10 17:34:18.668038 [NOTICE] switch_ivr_bridge.c:1034 Hangup sofia/internal/1000@124.123.68.233:21102 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  500. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1000@124.123.68.233:21102) State EXCHANGE_MEDIA going to sleep
  501. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_HANGUP (Cur 2 Tot 27)
  502. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1000@124.123.68.233:21102) Callstate Change ACTIVE -> HANGUP
  503. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1000@124.123.68.233:21102) State HANGUP
  504. 2018-05-10 17:34:18.668038 [DEBUG] mod_sofia.c:443 sofia/internal/1000@124.123.68.233:21102 Overriding SIP cause 480 with 200 from the other leg
  505. 2018-05-10 17:34:18.668038 [DEBUG] mod_sofia.c:449 Channel sofia/internal/1000@124.123.68.233:21102 hanging up, cause: NORMAL_CLEARING
  506. 2018-05-10 17:34:18.668038 [DEBUG] mod_sofia.c:502 Sending BYE to sofia/internal/1000@124.123.68.233:21102
  507. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1000@124.123.68.233:21102 Standard HANGUP, cause: NORMAL_CLEARING
  508. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1000@124.123.68.233:21102) State HANGUP going to sleep
  509. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1000@124.123.68.233:21102) State Change CS_HANGUP -> CS_REPORTING
  510. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_REPORTING (Cur 2 Tot 27)
  511. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1000@124.123.68.233:21102) State REPORTING
  512. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1000@124.123.68.233:21102 Standard REPORTING, cause: NORMAL_CLEARING
  513. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1000@124.123.68.233:21102) State REPORTING going to sleep
  514. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1000@124.123.68.233:21102) State Change CS_REPORTING -> CS_DESTROY
  515. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_session.c:1713 Session 27 (sofia/internal/1000@124.123.68.233:21102) Locked, Waiting on external entities
  516. 2018-05-10 17:34:18.668038 [DEBUG] switch_ivr_bridge.c:1846 sofia/internal/1003@icc-tech.com:5020 skip receive message [TRANSFER] (channel is hungup already)
  517. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_session.c:2885 sofia/internal/1003@icc-tech.com:5020 skip receive message [PHONE_EVENT] (channel is hungup already)
  518. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1003@icc-tech.com:5020) State EXECUTE going to sleep
  519. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_HANGUP (Cur 2 Tot 27)
  520. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1003@icc-tech.com:5020) Callstate Change ACTIVE -> HANGUP
  521. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1003@icc-tech.com:5020) State HANGUP
  522. 2018-05-10 17:34:18.668038 [DEBUG] mod_sofia.c:449 Channel sofia/internal/1003@icc-tech.com:5020 hanging up, cause: NORMAL_CLEARING
  523. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1003@icc-tech.com:5020 Standard HANGUP, cause: NORMAL_CLEARING
  524. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1003@icc-tech.com:5020) State HANGUP going to sleep
  525. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1003@icc-tech.com:5020) State Change CS_HANGUP -> CS_REPORTING
  526. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_REPORTING (Cur 2 Tot 27)
  527. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1003@icc-tech.com:5020) State REPORTING
  528. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1003@icc-tech.com:5020 Standard REPORTING, cause: NORMAL_CLEARING
  529. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1003@icc-tech.com:5020) State REPORTING going to sleep
  530. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1003@icc-tech.com:5020) State Change CS_REPORTING -> CS_DESTROY
  531. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_session.c:1713 Session 26 (sofia/internal/1003@icc-tech.com:5020) Locked, Waiting on external entities
  532. 2018-05-10 17:34:18.668038 [NOTICE] switch_core_session.c:1731 Session 26 (sofia/internal/1003@icc-tech.com:5020) Ended
  533. 2018-05-10 17:34:18.668038 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/1003@icc-tech.com:5020 [CS_DESTROY]
  534. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1003@icc-tech.com:5020) Running State Change CS_DESTROY (Cur 1 Tot 27)
  535. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1003@icc-tech.com:5020) State DESTROY
  536. 2018-05-10 17:34:18.668038 [DEBUG] mod_sofia.c:354 sofia/internal/1003@icc-tech.com:5020 SOFIA DESTROY
  537. 2018-05-10 17:34:18.668038 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[176] PLC[0] FEC[0]
  538. 2018-05-10 17:34:18.668038 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
  539. 2018-05-10 17:34:18.668038 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  540. 2018-05-10 17:34:18.668038 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[198] Bytes encoded[15445] Encoded length ms[3960] Average encoded bitrate bps[41186]
  541. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1003@icc-tech.com:5020 Standard DESTROY
  542. 2018-05-10 17:34:18.668038 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1003@icc-tech.com:5020) State DESTROY going to sleep
  543. 2018-05-10 17:34:18.708031 [DEBUG] switch_core_media.c:7450 sofia/internal/1000@124.123.68.233:21102 Video thread ended
  544. 2018-05-10 17:34:18.708031 [NOTICE] switch_core_session.c:1731 Session 27 (sofia/internal/1000@124.123.68.233:21102) Ended
  545. 2018-05-10 17:34:18.708031 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/1000@124.123.68.233:21102 [CS_DESTROY]
  546. 2018-05-10 17:34:18.708031 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@124.123.68.233:21102) Running State Change CS_DESTROY (Cur 0 Tot 27)
  547. 2018-05-10 17:34:18.708031 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1000@124.123.68.233:21102) State DESTROY
  548. 2018-05-10 17:34:18.708031 [DEBUG] mod_sofia.c:354 sofia/internal/1000@124.123.68.233:21102 SOFIA DESTROY
  549. 2018-05-10 17:34:18.708031 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  550. 2018-05-10 17:34:18.708031 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
  551. 2018-05-10 17:34:18.708031 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  552. 2018-05-10 17:34:18.708031 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
  553. 2018-05-10 17:34:18.708031 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1000@124.123.68.233:21102 Standard DESTROY
  554. 2018-05-10 17:34:18.708031 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1000@124.123.68.233:21102) State DESTROY going to sleep
  555.  

Reply to "Bria call log"