FreeSWITCH Pastebin

log

From Dayo, 6 Years ago, written in Plain Text.
- view diff
URL https://pastebin.freeswitch.org/view/b33b891d
Embed
Download Paste or View Raw
  1. 2018-08-30 10:00:27.294099 [DEBUG] sofia.c:11168 Setting NAT mode based on nat.auto
  2. 2018-08-30 10:00:27.294099 [DEBUG] sofia.c:7084 Channel sofia/internal/1000@tekart.dayo.com.ng entering state [received][100]
  3. 2018-08-30 10:00:27.294099 [DEBUG] sofia.c:7094 Remote SDP:
  4. v=0
  5. o=- 13180096828185127 1 IN IP4 192.168.43.205
  6. s=X-Lite release 5.3.1 stamp 92361
  7. c=IN IP4 192.168.43.205
  8. t=0 0
  9. m=audio 50648 RTP/AVP 9 8 120 0
  10. a=rtpmap:120 opus/48000/2
  11. a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
  12.  
  13. 2018-08-30 10:00:27.294099 [DEBUG] sofia.c:7486 (sofia/internal/1000@tekart.dayo.com.ng) State Change CS_NEW -> CS_INIT
  14. 2018-08-30 10:00:27.294099 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@tekart.dayo.com.ng) Running State Change CS_INIT (Cur 2 Tot 1932)
  15. 2018-08-30 10:00:27.294099 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1000@tekart.dayo.com.ng) State INIT
  16. 2018-08-30 10:00:27.294099 [DEBUG] mod_sofia.c:90 sofia/internal/1000@tekart.dayo.com.ng SOFIA INIT
  17. 2018-08-30 10:00:27.294099 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1000@tekart.dayo.com.ng Standard INIT
  18. 2018-08-30 10:00:27.294099 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1000@tekart.dayo.com.ng) State Change CS_INIT -> CS_ROUTING
  19. 2018-08-30 10:00:27.294099 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1000@tekart.dayo.com.ng) State INIT going to sleep
  20. 2018-08-30 10:00:27.294099 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@tekart.dayo.com.ng) Running State Change CS_ROUTING (Cur 2 Tot 1932)
  21. 2018-08-30 10:00:27.294099 [DEBUG] switch_channel.c:2249 (sofia/internal/1000@tekart.dayo.com.ng) Callstate Change DOWN -> RINGING
  22. 2018-08-30 10:00:27.294099 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1000@tekart.dayo.com.ng) State ROUTING
  23. 2018-08-30 10:00:27.294099 [DEBUG] mod_sofia.c:143 sofia/internal/1000@tekart.dayo.com.ng SOFIA ROUTING
  24. 2018-08-30 10:00:27.294099 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1000@tekart.dayo.com.ng Standard ROUTING
  25. 2018-08-30 10:00:27.294099 [INFO] mod_dialplan_xml.c:637 Processing 1000 <1000>->1010 in context tekart.dayo.com.ng
  26. 2018-08-30 10:00:27.294099 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Params:
  27. 'Event-Name: REQUEST_PARAMS
  28. Core-UUID: f339f992-3aa5-448d-8aca-5fd7e6c3b677
  29. FreeSWITCH-Hostname: test-fs-001
  30. FreeSWITCH-Switchname: test-fs-001
  31. FreeSWITCH-IPv4: 139.162.188.90
  32. FreeSWITCH-IPv6: %3A%3A1
  33. Event-Date-Local: 2018-08-30%2010%3A00%3A27
  34. Event-Date-GMT: Thu,%2030%20Aug%202018%2010%3A00%3A27%20GMT
  35. Event-Date-Timestamp: 1535623227294099
  36. Event-Calling-File: mod_dialplan_xml.c
  37. Event-Calling-Function: dialplan_xml_locate
  38. Event-Calling-Line-Number: 608
  39. Event-Sequence: 28349
  40. Channel-State: CS_ROUTING
  41. Channel-Call-State: RINGING
  42. Channel-State-Number: 2
  43. Channel-Name: sofia/internal/1000%40tekart.dayo.com.ng
  44. Unique-ID: cd187882-801d-45e0-b9b6-6065e09debb8
  45. Call-Direction: inbound
  46. Presence-Call-Direction: inbound
  47. Channel-HIT-Dialplan: true
  48. Channel-Presence-ID: 1000%40tekart.dayo.com.ng
  49. Channel-Call-UUID: cd187882-801d-45e0-b9b6-6065e09debb8
  50. Answer-State: ringing
  51. Caller-Direction: inbound
  52. Caller-Logical-Direction: inbound
  53. Caller-Username: 1000
  54. Caller-Dialplan: XML
  55. Caller-Caller-ID-Name: 1000
  56. Caller-Caller-ID-Number: 1000
  57. Caller-Orig-Caller-ID-Name: 1000
  58. Caller-Orig-Caller-ID-Number: 1000
  59. Caller-Network-Addr: 197.210.173.34
  60. Caller-ANI: 1000
  61. Caller-Destination-Number: 1010
  62. Caller-Unique-ID: cd187882-801d-45e0-b9b6-6065e09debb8
  63. Caller-Source: mod_sofia
  64. Caller-Context: tekart.dayo.com.ng
  65. Caller-Channel-Name: sofia/internal/1000%40tekart.dayo.com.ng
  66. Caller-Profile-Index: 1
  67. Caller-Profile-Created-Time: 1535623227294099
  68. Caller-Channel-Created-Time: 1535623227294099
  69. Caller-Channel-Answered-Time: 0
  70. Caller-Channel-Progress-Time: 0
  71. Caller-Channel-Progress-Media-Time: 0
  72. Caller-Channel-Hangup-Time: 0
  73. Caller-Channel-Transfer-Time: 0
  74. Caller-Channel-Resurrect-Time: 0
  75. Caller-Channel-Bridged-Time: 0
  76. Caller-Channel-Last-Hold: 0
  77. Caller-Channel-Hold-Accum: 0
  78. Caller-Screen-Bit: true
  79. Caller-Privacy-Hide-Name: false
  80. Caller-Privacy-Hide-Number: false
  81. variable_direction: inbound
  82. variable_uuid: cd187882-801d-45e0-b9b6-6065e09debb8
  83. variable_session_id: 1932
  84. variable_sip_from_user: 1000
  85. variable_sip_from_uri: 1000%40tekart.dayo.com.ng
  86. variable_sip_from_host: tekart.dayo.com.ng
  87. variable_video_media_flow: sendrecv
  88. variable_audio_media_flow: sendrecv
  89. variable_channel_name: sofia/internal/1000%40tekart.dayo.com.ng
  90. variable_sip_call_id: 92361NTUyMzI1ZWE0YWJiMDM2NzI3YTZlNjZmOGMyZDhiNGM
  91. variable_ep_codec_string: mod_spandsp.G722%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_opus.opus%4048000h%4020i%402c,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b
  92. variable_sip_local_network_addr: 139.162.188.90
  93. variable_sip_network_ip: 197.210.173.34
  94. variable_sip_network_port: 63691
  95. variable_sip_invite_stamp: 1535623226724129
  96. variable_sip_received_ip: 197.210.173.34
  97. variable_sip_received_port: 63691
  98. variable_sip_via_protocol: udp
  99. variable_sip_authorized: true
  100. variable_Event-Name: REQUEST_PARAMS
  101. variable_Core-UUID: f339f992-3aa5-448d-8aca-5fd7e6c3b677
  102. variable_FreeSWITCH-Hostname: test-fs-001
  103. variable_FreeSWITCH-Switchname: test-fs-001
  104. variable_FreeSWITCH-IPv4: 139.162.188.90
  105. variable_FreeSWITCH-IPv6: %3A%3A1
  106. variable_Event-Date-Local: 2018-08-30%2010%3A00%3A26
  107. variable_Event-Date-GMT: Thu,%2030%20Aug%202018%2010%3A00%3A26%20GMT
  108. variable_Event-Date-Timestamp: 1535623226724129
  109. variable_Event-Calling-File: sofia.c
  110. variable_Event-Calling-Function: sofia_handle_sip_i_invite
  111. variable_Event-Calling-Line-Number: 10096
  112. variable_Event-Sequence: 28341
  113. variable_sip_number_alias: 1000
  114. variable_sip_auth_username: 1000
  115. variable_sip_auth_realm: tekart.dayo.com.ng
  116. variable_number_alias: 1000
  117. variable_requested_user_name: 1000
  118. variable_requested_domain_name: tekart.dayo.com.ng
  119. variable_domain_uuid: None
  120. variable_user_id: 3
  121. variable_extension_uuid: d5bd2432-d3bc-413a-8dac-032df74a6f06
  122. variable_call_timeout: 30
  123. variable_caller_id_name: 1000
  124. variable_caller_id_number: 1000
  125. variable_presence_id: 1000%40tekart.dayo.com.ng
  126. variable_user_context: tekart.dayo.com.ng
  127. variable_bypass_media: false
  128. variable_export_vars: domain_name
  129. variable_user_name: 1000
  130. variable_domain_name: tekart.dayo.com.ng
  131. variable_sip_from_user_stripped: 1000
  132. variable_sip_from_tag: f3994a54
  133. variable_sofia_profile_name: internal
  134. variable_recovery_profile_name: internal
  135. variable_sip_full_via: SIP/2.0/UDP%20192.168.43.205%3A63691%3Bbranch%3Dz9hG4bK-524287-1---bf52ae2515eda022%3Brport%3D63691%3Breceived%3D197.210.173.34
  136. variable_sip_from_display: 1000
  137. variable_sip_full_from: %221000%22%20%3Csip%3A1000%40tekart.dayo.com.ng%3E%3Btag%3Df3994a54
  138. variable_sip_full_to: %3Csip%3A1010%40tekart.dayo.com.ng%3E
  139. variable_sip_allow: SUBSCRIBE,%20NOTIFY,%20INVITE,%20ACK,%20CANCEL,%20BYE,%20REFER,%20INFO,%20OPTIONS,%20MESSAGE
  140. variable_sip_req_user: 1010
  141. variable_sip_req_uri: 1010%40tekart.dayo.com.ng
  142. variable_sip_req_host: tekart.dayo.com.ng
  143. variable_sip_to_user: 1010
  144. variable_sip_to_uri: 1010%40tekart.dayo.com.ng
  145. variable_sip_to_host: tekart.dayo.com.ng
  146. variable_sip_contact_user: 1000
  147. variable_sip_contact_port: 63691
  148. variable_sip_contact_uri: 1000%40192.168.43.205%3A63691
  149. variable_sip_contact_host: 192.168.43.205
  150. variable_rtp_use_codec_string: OPUS,G722,PCMU,PCMA,VP8
  151. variable_sip_user_agent: X-Lite%20release%205.3.1%20stamp%2092361
  152. variable_sip_via_host: 192.168.43.205
  153. variable_sip_via_port: 63691
  154. variable_sip_via_rport: 63691
  155. variable_max_forwards: 70
  156. variable_sip_nat_detected: true
  157. variable_switch_r_sdp: v%3D0%0D%0Ao%3D-%2013180096828185127%201%20IN%20IP4%20192.168.43.205%0D%0As%3DX-Lite%20release%205.3.1%20stamp%2092361%0D%0Ac%3DIN%20IP4%20192.168.43.205%0D%0At%3D0%200%0D%0Am%3Daudio%2050648%20RTP/AVP%209%208%20120%200%0D%0Aa%3Drtpmap%3A120%20opus/48000/2%0D%0Aa%3Dfmtp%3A120%20useinbandfec%3D1%3B%20usedtx%3D1%3B%20maxaveragebitrate%3D64000%0D%0A
  158. variable_endpoint_disposition: DELAYED%20NEGOTIATION
  159. variable_call_uuid: cd187882-801d-45e0-b9b6-6065e09debb8
  160. Hunt-Direction: inbound
  161. Hunt-Logical-Direction: inbound
  162. Hunt-Username: 1000
  163. Hunt-Dialplan: XML
  164. Hunt-Caller-ID-Name: 1000
  165. Hunt-Caller-ID-Number: 1000
  166. Hunt-Orig-Caller-ID-Name: 1000
  167. Hunt-Orig-Caller-ID-Number: 1000
  168. Hunt-Network-Addr: 197.210.173.34
  169. Hunt-ANI: 1000
  170. Hunt-Destination-Number: 1010
  171. Hunt-Unique-ID: cd187882-801d-45e0-b9b6-6065e09debb8
  172. Hunt-Source: mod_sofia
  173. Hunt-Context: tekart.dayo.com.ng
  174. Hunt-Channel-Name: sofia/internal/1000%40tekart.dayo.com.ng
  175. Hunt-Profile-Index: 1
  176. Hunt-Profile-Created-Time: 1535623227294099
  177. Hunt-Channel-Created-Time: 1535623227294099
  178. Hunt-Channel-Answered-Time: 0
  179. Hunt-Channel-Progress-Time: 0
  180. Hunt-Channel-Progress-Media-Time: 0
  181. Hunt-Channel-Hangup-Time: 0
  182. Hunt-Channel-Transfer-Time: 0
  183. Hunt-Channel-Resurrect-Time: 0
  184. Hunt-Channel-Bridged-Time: 0
  185. Hunt-Channel-Last-Hold: 0
  186. Hunt-Channel-Hold-Accum: 0
  187. Hunt-Screen-Bit: true
  188. Hunt-Privacy-Hide-Name: false
  189. Hunt-Privacy-Hide-Number: false
  190.  
  191. '
  192. 2018-08-30 10:00:27.294099 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Section: dialplan
  193. 2018-08-30 10:00:27.294099 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Tag Name:
  194. 2018-08-30 10:00:27.294099 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Key Name:
  195. 2018-08-30 10:00:27.294099 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Key Value:
  196. 2018-08-30 10:00:27.324093 [ALERT] mod_verto.c:5329 EVENT BROADCAST presence.1000@tekart.dayo.com.ng {
  197.         "data": {
  198.                 "channelCallState":     "RINGING",
  199.                 "originalChannelCallState":     "DOWN",
  200.                 "channelState": "CS_ROUTING",
  201.                 "callerUserName":       "1000",
  202.                 "callerIDName": "1000",
  203.                 "callerIDNumber":       "1000",
  204.                 "channelUUID":  "cd187882-801d-45e0-b9b6-6065e09debb8",
  205.                 "presenceCallDirection":        "inbound",
  206.                 "channelPresenceID":    "1000@tekart.dayo.com.ng"
  207.         },
  208.         "eventChannel": "presence.1000@tekart.dayo.com.ng"
  209. }
  210. 2018-08-30 10:00:27.864114 [NOTICE] switch_cpp.cpp:1365 [xml]: <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  211. <document type="freeswitch/xml">
  212.  
  213. <section name="dialplan" description="">
  214.  
  215.   <context name="tekart.dayo.com.ng">
  216.  
  217.     <extension name="dayo" continue="true" uuid="0b9e37ca-b9ad-4f8a-9590-bb2ae00eec8c"><condition field="destination_number" expression="^\d{4}$"><action application="export" data="domain_name=${context}"/><action application="bridge" data="user/${destination_number}@${domain_name}"/></condition></extension>
  218.  
  219.     <extension name="user_exists" continue="true" uuid="d22d4d07-66a3-430a-9af3-7ed16c9ec5e2"><condition field="" expression=""><action application="set" data="user_exists=${user_exists id ${destination_number} ${domain_name}}"/></condition><condition field="${user_exists}" expression="^true$"><action application="set" data="extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid}"/><action application="set" data="hold_music=${user_data ${destination_number}@${domain_name} var hold_music}"/></condition></extension>
  220.  
  221.     <extension name="call-direction" continue="true" uuid="963f09de-2f51-49c4-907c-92e7036977a1"><condition field="${call_direction}" expression="^(inbound|outbound|local)$" break="never"><anti-action application="set" data="call_direction=local"/></condition></extension>
  222.  
  223.     <extension name="hold_music" continue="false" uuid="186cc900-157d-4d87-aaa5-117f2f4f1172"><condition field="destination_number" expression="^\*9664$"/><condition field="${sip_has_crypto}" expression="^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$"><action application="answer" data=""/><action application="execute_extension" data="is_secure XML ${context}"/><action application="playback" data="$${hold_music}"/><anti-action application="set" data="zrtp_secure_media=true"/><anti-action application="answer" data=""/><anti-action application="playback" data="silence_stream://2000"/><anti-action application="execute_extension" data="is_zrtp_secure XML ${context}"/><anti-action application="playback" data="$${hold_music}"/></condition></extension>
  224.  
  225.     <extension name="freeswitch_conference" continue="false" uuid="6e6e9019-4813-42a7-ad06-b06853da531b"><condition field="destination_number" expression="^\*9(888|8888|1616|3232)$"><action application="export" data="hold_music=silence"/><action application="bridge" data="sofia/${use_profile}/$1@conference.freeswitch.org"/></condition></extension>
  226.  
  227.     <extension name="local_extension" continue="true" uuid="7dd7469e-8207-454a-a6c9-6f1c28577323"><condition field="${user_exists}" expression="true"><action application="export" data="dialed_extension=${destination_number}" inline="true"/><action application="limit" data="hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination}" inline="false"/></condition><condition field="${sip_authorized}" expression="true" break="never"><action application="set" data="bind_target=both" inline="true"/><anti-action application="set" data="bind_target=peer" inline="true"/></condition><condition field="" expression=""><action application="set" data="hangup_after_bridge=true"/><action application="set" data="continue_on_fail=true"/><action application="export" data="domain_name=${context}"/><action application="bridge" data="user/${destination_number}@${domain_name}"/></condition></extension>
  228.  
  229.     <extension name="12005" continue="true" uuid="17fa5ad7-3774-48c2-99b8-3ec01e5ee519"><condition field="destination_number" expression="^(12005)$"/><condition field="${sofia_presence_data status */2005@${domain}}" expression="^(Busy)$"><action application="playback" data="ivr/8000/ivr-user_busy.wav"/><action application="hangup" data=""/><action application="bridge" data="user/2005@${domain_name}"/></condition></extension>
  230.  
  231.   </context>
  232.  
  233. </section>
  234.  
  235. </document>
  236. Dialplan: sofia/internal/1000@tekart.dayo.com.ng parsing [tekart.dayo.com.ng->dayo] continue=true
  237. Dialplan: sofia/internal/1000@tekart.dayo.com.ng Regex (PASS) [dayo] destination_number(1010) =~ /^\d{4}$/ break=on-false
  238. Dialplan: sofia/internal/1000@tekart.dayo.com.ng Action export(domain_name=${context})
  239. Dialplan: sofia/internal/1000@tekart.dayo.com.ng Action bridge(user/${destination_number}@${domain_name})
  240. Dialplan: sofia/internal/1000@tekart.dayo.com.ng parsing [tekart.dayo.com.ng->user_exists] continue=true
  241. Dialplan: sofia/internal/1000@tekart.dayo.com.ng Regex (PASS) [user_exists] () =~ // break=on-false
  242. Dialplan: sofia/internal/1000@tekart.dayo.com.ng Action set(user_exists=${user_exists id ${destination_number} ${domain_name}})
  243. Dialplan: sofia/internal/1000@tekart.dayo.com.ng Regex (FAIL) [user_exists] ${user_exists}() =~ /^true$/ break=on-false
  244. Dialplan: sofia/internal/1000@tekart.dayo.com.ng parsing [tekart.dayo.com.ng->call-direction] continue=true
  245. Dialplan: sofia/internal/1000@tekart.dayo.com.ng Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
  246. Dialplan: sofia/internal/1000@tekart.dayo.com.ng ANTI-Action set(call_direction=local)
  247. Dialplan: sofia/internal/1000@tekart.dayo.com.ng parsing [tekart.dayo.com.ng->hold_music] continue=false
  248. Dialplan: sofia/internal/1000@tekart.dayo.com.ng Regex (FAIL) [hold_music] destination_number(1010) =~ /^\*9664$/ break=on-false
  249. Dialplan: sofia/internal/1000@tekart.dayo.com.ng parsing [tekart.dayo.com.ng->freeswitch_conference] continue=false
  250. Dialplan: sofia/internal/1000@tekart.dayo.com.ng Regex (FAIL) [freeswitch_conference] destination_number(1010) =~ /^\*9(888|8888|1616|3232)$/ break=on-false
  251. Dialplan: sofia/internal/1000@tekart.dayo.com.ng parsing [tekart.dayo.com.ng->local_extension] continue=true
  252. Dialplan: sofia/internal/1000@tekart.dayo.com.ng Regex (FAIL) [local_extension] ${user_exists}() =~ /true/ break=on-false
  253. Dialplan: sofia/internal/1000@tekart.dayo.com.ng parsing [tekart.dayo.com.ng->12005] continue=true
  254. Dialplan: sofia/internal/1000@tekart.dayo.com.ng Regex (FAIL) [12005] destination_number(1010) =~ /^(12005)$/ break=on-false
  255. 2018-08-30 10:00:27.874099 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1000@tekart.dayo.com.ng) State Change CS_ROUTING -> CS_EXECUTE
  256. 2018-08-30 10:00:27.874099 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1000@tekart.dayo.com.ng) State ROUTING going to sleep
  257. 2018-08-30 10:00:27.874099 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@tekart.dayo.com.ng) Running State Change CS_EXECUTE (Cur 2 Tot 1932)
  258. 2018-08-30 10:00:27.874099 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1000@tekart.dayo.com.ng) State EXECUTE
  259. 2018-08-30 10:00:27.874099 [DEBUG] mod_sofia.c:198 sofia/internal/1000@tekart.dayo.com.ng SOFIA EXECUTE
  260. 2018-08-30 10:00:27.874099 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1000@tekart.dayo.com.ng Standard EXECUTE
  261. EXECUTE sofia/internal/1000@tekart.dayo.com.ng export(domain_name=tekart.dayo.com.ng)
  262. 2018-08-30 10:00:27.874099 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [domain_name]=[tekart.dayo.com.ng]
  263. EXECUTE sofia/internal/1000@tekart.dayo.com.ng bridge(user/1010@tekart.dayo.com.ng)
  264. 2018-08-30 10:00:27.874099 [DEBUG] switch_channel.c:1250 sofia/internal/1000@tekart.dayo.com.ng EXPORTING[export_vars] [domain_name]=[tekart.dayo.com.ng] to event
  265. 2018-08-30 10:00:27.874099 [DEBUG] switch_channel.c:1250 sofia/internal/1000@tekart.dayo.com.ng EXPORTING[export_vars] [domain_name]=[tekart.dayo.com.ng] to event
  266. 2018-08-30 10:00:27.874099 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  267. 2018-08-30 10:00:27.874099 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Params:
  268. 'Event-Name: REQUEST_PARAMS
  269. Core-UUID: f339f992-3aa5-448d-8aca-5fd7e6c3b677
  270. FreeSWITCH-Hostname: test-fs-001
  271. FreeSWITCH-Switchname: test-fs-001
  272. FreeSWITCH-IPv4: 139.162.188.90
  273. FreeSWITCH-IPv6: %3A%3A1
  274. Event-Date-Local: 2018-08-30%2010%3A00%3A27
  275. Event-Date-GMT: Thu,%2030%20Aug%202018%2010%3A00%3A27%20GMT
  276. Event-Date-Timestamp: 1535623227874099
  277. Event-Calling-File: mod_dptools.c
  278. Event-Calling-Function: user_outgoing_channel
  279. Event-Calling-Line-Number: 4167
  280. Event-Sequence: 28356
  281. as_channel: true
  282. action: user_call
  283. export_vars: domain_name,domain_name
  284. domain_name: tekart.dayo.com.ng
  285. key: id
  286. user: 1010
  287. domain: tekart.dayo.com.ng
  288.  
  289. '
  290. 2018-08-30 10:00:27.874099 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Section: directory
  291. 2018-08-30 10:00:27.874099 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Tag Name: domain
  292. 2018-08-30 10:00:27.874099 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Key Name: name
  293. 2018-08-30 10:00:27.874099 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Key Value: tekart.dayo.com.ng
  294. 2018-08-30 10:00:28.434104 [NOTICE] switch_cpp.cpp:1365 [xml]: <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  295. <document type="freeswitch/xml">
  296.  
  297. <section name="directory">
  298.  
  299.   <domain name="tekart.dayo.com.ng" alias="true">
  300.     <params>
  301.       <param name="jsonrpc-allowed-methods" value="verto"/>
  302.       <param name="jsonrpc-allowed-event-channels" value="demo,conference,presence"/>
  303.     </params>
  304.     <groups>
  305.       <group name="default">
  306.         <users>
  307.  
  308.           <user id="1010">
  309.  
  310.           <params>
  311.             <param name="password" value=""/>
  312.             <param name="vm-enabled" value="false"/>
  313.  
  314.  
  315.             <param name="dial-string" value="{sip_invite_domain=tekart.dayo.com.ng,
  316.              presence_id=1010@tekart.dayo.com.ng}${sofia_contact(1010@tekart.dayo.com.ng)},
  317.              ${verto_contact 1010@tekart.dayo.com.ng}"/>
  318.  
  319.             <param name="verto-context" value="tekart.dayo.com.ng"/>
  320.             <param name="verto-dialplan" value="XML"/>
  321.             <param name="jsonrpc-allowed-methods" value="verto"/>
  322.             <param name="jsonrpc-allowed-event-channels" value="demo,conference,presence"/>
  323.           </params>
  324.           <variables>
  325.             <variable name="domain_uuid" value="None"/>
  326.             <varibale name="domain_name" value="tekart.dayo.com.ng"/>
  327.             <variable name="user_id" value="3"/>
  328.             <variable name="extension_uuid" value="a26ada20-9c0a-4218-9c3e-96bac8efe702"/>
  329.             <variable name="call_timeout" value="30"/>
  330.             <variable name="caller_id_name" value="1010"/>
  331.             <variable name="caller_id_number" value="1010"/>
  332.             <variable name="presence_id" value="1010@tekart.dayo.com.ng"/>
  333.  
  334.             <variable name="user_context" value="tekart.dayo.com.ng"/>
  335.  
  336.  
  337.             <variable name="bypass_media" value="false"/>
  338.             <variable name="export_vars" value="domain_name"/>
  339.           </variables>
  340.           </user>
  341.         </users>
  342.       </group>
  343.     </groups>
  344.   </domain>
  345.  
  346. </section>
  347.  
  348. </document>
  349. 2018-08-30 10:00:28.444092 [DEBUG] switch_channel.c:1250 sofia/internal/1000@tekart.dayo.com.ng EXPORTING[export_vars] [domain_name]=[tekart.dayo.com.ng] to event
  350. 2018-08-30 10:00:28.444092 [DEBUG] switch_channel.c:1250 sofia/internal/1000@tekart.dayo.com.ng EXPORTING[export_vars] [domain_name]=[tekart.dayo.com.ng] to event
  351. 2018-08-30 10:00:28.444092 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  352. 2018-08-30 10:00:28.444092 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
  353. 2018-08-30 10:00:28.444092 [DEBUG] mod_rtc.c:389 () State Change CS_NEW -> CS_INIT
  354. 2018-08-30 10:00:28.444092 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 [41f39aee-4cfc-4782-be62-546edc3cef7c]
  355. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) Running State Change CS_INIT (Cur 3 Tot 1933)
  356. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State INIT
  357. 2018-08-30 10:00:28.444092 [CRIT] switch_channel.c:1436 Invalid data (${local_media_ip} contains a variable)
  358. 2018-08-30 10:00:28.444092 [CRIT] switch_channel.c:1436 Invalid data (${advertised_media_ip} contains a variable)
  359. 2018-08-30 10:00:28.444092 [CRIT] switch_channel.c:1436 Invalid data (${local_video_ip} contains a variable)
  360. 2018-08-30 10:00:28.444092 [CRIT] switch_channel.c:1436 Invalid data (${rtp_local_sdp_str} contains a variable)
  361. 2018-08-30 10:00:28.444092 [DEBUG] mod_verto.c:2229 Local verto.invite SDP verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071:
  362. v=0
  363. o=FreeSWITCH 1535593920 1535593921 IN IP4 $${local_ip_v4}
  364. s=FreeSWITCH
  365. c=IN IP4 $${local_ip_v4}
  366. t=0 0
  367. a=msid-semantic: WMS ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrN
  368. m=audio 29308 RTP/SAVPF 9 8 102 0 103
  369. a=rtpmap:9 G722/8000
  370. a=rtpmap:8 PCMA/8000
  371. a=rtpmap:102 opus/48000/2
  372. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  373. a=rtpmap:0 PCMU/8000
  374. a=rtpmap:103 opus/48000/2
  375. a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  376. a=fingerprint:sha-256 6F:3C:B0:AA:DD:12:17:A7:F0:26:56:8C:5F:B2:F5:A1:DF:2A:0B:87:EF:F6:07:14:53:DB:A4:F8:81:01:D2:5F
  377. a=setup:actpass
  378. a=rtcp-mux
  379. a=rtcp:29308 IN IP4 $${local_ip_v4}
  380. a=ssrc:463787340 cname:7dtvIsRBJb2AlD8p
  381. a=ssrc:463787340 msid:ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrN a0
  382. a=ssrc:463787340 mslabel:ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrN
  383. a=ssrc:463787340 label:ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrNa0
  384. a=ice-ufrag:PnR7zzOrg4NFG4iE
  385. a=ice-pwd:8C0HUH8uJ1UVuTaa6S8whMOo
  386. a=candidate:7096455128 1 udp 659136 $${local_ip_v4} 29308 typ host generation 0
  387. a=candidate:7096455128 2 udp 659136 $${local_ip_v4} 29308 typ host generation 0
  388. a=silenceSupp:off - - - -
  389. a=ptime:20
  390. a=sendrecv
  391. m=video 29310 RTP/SAVPF 104
  392. b=AS:1024
  393. a=rtpmap:104 VP8/90000
  394. a=fingerprint:sha-256 6F:3C:B0:AA:DD:12:17:A7:F0:26:56:8C:5F:B2:F5:A1:DF:2A:0B:87:EF:F6:07:14:53:DB:A4:F8:81:01:D2:5F
  395. a=setup:actpass
  396. a=rtcp-mux
  397. a=rtcp:29310 IN IP4 $${local_ip_v4}
  398. a=rtcp-fb:104 ccm fir
  399. a=rtcp-fb:104 ccm tmmbr
  400. a=rtcp-fb:104 nack
  401. a=rtcp-fb:104 nack pli
  402. a=ssrc:3990963918 cname:7dtvIsRBJb2AlD8p
  403. a=ssrc:3990963918 msid:ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrN v0
  404. a=ssrc:3990963918 mslabel:ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrN
  405. a=ssrc:3990963918 label:ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrNv0
  406. a=ice-ufrag:xbhifuaNFvcnA94Z
  407. a=ice-pwd:GD9Yw1gpQhh2qLSON0WSkXwP
  408. a=candidate:7628899082 1 udp 659136 $${local_ip_v4} 29310 typ host generation 0
  409. a=candidate:7628899082 2 udp 659135 $${local_ip_v4} 29310 typ host generation 0
  410. a=end-of-candidates
  411.  
  412. 2018-08-30 10:00:28.444092 [NOTICE] mod_verto.c:2329 Ring-Ready verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071!
  413. 2018-08-30 10:00:28.444092 [DEBUG] switch_channel.c:3346 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) Callstate Change DOWN -> RINGING
  414. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_state_machine.c:40 verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 Standard INIT
  415. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State Change CS_INIT -> CS_ROUTING
  416. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State INIT going to sleep
  417. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) Running State Change CS_ROUTING (Cur 3 Tot 1933)
  418. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State ROUTING
  419. 2018-08-30 10:00:28.444092 [DEBUG] mod_rtc.c:89 verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 RTC ROUTING
  420. 2018-08-30 10:00:28.444092 [DEBUG] switch_ivr_originate.c:67 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  421. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State ROUTING going to sleep
  422. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 1933)
  423. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State CONSUME_MEDIA
  424. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State CONSUME_MEDIA going to sleep
  425. 2018-08-30 10:00:28.444092 [ALERT] mod_verto.c:5329 EVENT BROADCAST presence.1010@tekart.dayo.com.ng {
  426.         "data": {
  427.                 "channelCallState":     "RINGING",
  428.                 "originalChannelCallState":     "DOWN",
  429.                 "channelState": "CS_INIT",
  430.                 "callerUserName":       "1000",
  431.                 "callerIDName": "1000",
  432.                 "callerIDNumber":       "1000",
  433.                 "calleeIDName": "Dayo",
  434.                 "calleeIDNumber":       "1010",
  435.                 "channelUUID":  "41f39aee-4cfc-4782-be62-546edc3cef7c",
  436.                 "presenceCallDirection":        "outbound",
  437.                 "channelPresenceID":    "1010@tekart.dayo.com.ng"
  438.         },
  439.         "eventChannel": "presence.1010@tekart.dayo.com.ng"
  440. }
  441. 2018-08-30 10:00:28.444092 [INFO] switch_ivr_originate.c:1215 Sending early media
  442. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  443. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  444. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  445. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  446. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  447. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  448. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  449. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  450. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  451. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  452. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:120:48000:20:0:1]/[opus:116:48000:20:0:1]
  453. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  454. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:120:48000:20:0:1]/[G722:9:8000:20:64000:1]
  455. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:120:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
  456. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:120:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
  457. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  458. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  459. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  460. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  461. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  462. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/1000@tekart.dayo.com.ng G722/8000 20 ms 160 samples 64000 bits 1 channels
  463. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_codec.c:111 sofia/internal/1000@tekart.dayo.com.ng Original read codec set to G722:9
  464. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:4774 No 2833 in SDP.  Disable 2833 dtmf and switch to INFO
  465. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/1000@tekart.dayo.com.ng] 139.162.188.90 port 25702 -> 192.168.43.205 port 50648 codec: 9 ms: 20
  466. 2018-08-30 10:00:28.444092 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
  467. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:7210 sofia/internal/1000@tekart.dayo.com.ng Set rtp dtmf delay to 40
  468. 2018-08-30 10:00:28.444092 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1000@tekart.dayo.com.ng!
  469. 2018-08-30 10:00:28.444092 [DEBUG] switch_channel.c:3474 (sofia/internal/1000@tekart.dayo.com.ng) Callstate Change RINGING -> EARLY
  470. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/1000@tekart.dayo.com.ng.
  471. 2018-08-30 10:00:28.444092 [DEBUG] mod_sofia.c:2364 Ring SDP:
  472. v=0
  473. o=FreeSWITCH 1535597526 1535597527 IN IP4 139.162.188.90
  474. s=FreeSWITCH
  475. c=IN IP4 139.162.188.90
  476. t=0 0
  477. m=audio 25702 RTP/AVP 9
  478. a=rtpmap:9 G722/8000
  479. a=ptime:20
  480. a=sendrecv
  481.  
  482. 2018-08-30 10:00:28.444092 [DEBUG] sofia.c:7084 Channel sofia/internal/1000@tekart.dayo.com.ng entering state [early][183]
  483. 2018-08-30 10:00:28.444092 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@16000hz 1 channel 20ms
  484. 2018-08-30 10:00:28.444092 [DEBUG] switch_core_codec.c:223 sofia/internal/1000@tekart.dayo.com.ng Push codec L16:100
  485. 2018-08-30 10:00:28.444092 [DEBUG] switch_ivr_originate.c:1342 Play Ringback Tone [%(2000,4000,440,480)]
  486. 2018-08-30 10:00:28.444092 [ALERT] mod_verto.c:5329 EVENT BROADCAST presence.1000@tekart.dayo.com.ng {
  487.         "data": {
  488.                 "channelCallState":     "EARLY",
  489.                 "originalChannelCallState":     "RINGING",
  490.                 "channelState": "CS_EXECUTE",
  491.                 "callerUserName":       "1000",
  492.                 "callerIDName": "1000",
  493.                 "callerIDNumber":       "1000",
  494.                 "channelUUID":  "cd187882-801d-45e0-b9b6-6065e09debb8",
  495.                 "presenceCallDirection":        "inbound",
  496.                 "channelPresenceID":    "1000@tekart.dayo.com.ng"
  497.         },
  498.         "eventChannel": "presence.1000@tekart.dayo.com.ng"
  499. }
  500. 2018-08-30 10:00:28.454089 [ALERT] mod_verto.c:604 WRITE 197.210.65.10:63551 [{
  501.         "jsonrpc":      "2.0",
  502.         "id":   13,
  503.         "method":       "verto.invite",
  504.         "params":       {
  505.                 "callID":       "41f39aee-4cfc-4782-be62-546edc3cef7c",
  506.                 "sdp":  "v=0\r\no=FreeSWITCH 1535593920 1535593921 IN IP4 $${local_ip_v4}\r\ns=FreeSWITCH\r\nc=IN IP4 $${local_ip_v4}\r\nt=0 0\r\na=msid-semantic: WMS ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrN\r\nm=audio 29308 RTP/SAVPF 9 8 102 0 103\r\na=rtpmap:9 G722/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:102 opus/48000/2\r\na=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:103 opus/48000/2\r\na=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40\r\na=fingerprint:sha-256 6F:3C:B0:AA:DD:12:17:A7:F0:26:56:8C:5F:B2:F5:A1:DF:2A:0B:87:EF:F6:07:14:53:DB:A4:F8:81:01:D2:5F\r\na=setup:actpass\r\na=rtcp-mux\r\na=rtcp:29308 IN IP4 $${local_ip_v4}\r\na=ssrc:463787340 cname:7dtvIsRBJb2AlD8p\r\na=ssrc:463787340 msid:ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrN a0\r\na=ssrc:463787340 mslabel:ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrN\r\na=ssrc:463787340 label:ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrNa0\r\na=ice-ufrag:PnR7zzOrg4NFG4iE\r\na=ice-pwd:8C0HUH8uJ1UVuTaa6S8whMOo\r\na=candidate:7096455128 1 udp 659136 $${local_ip_v4} 29308 typ host generation 0\r\na=candidate:7096455128 2 udp 659136 $${local_ip_v4} 29308 typ host generation 0\r\na=silenceSupp:off - - - -\r\na=ptime:20\r\na=sendrecv\r\nm=video 29310 RTP/SAVPF 104\r\nb=AS:1024\r\na=rtpmap:104 VP8/90000\r\na=fingerprint:sha-256 6F:3C:B0:AA:DD:12:17:A7:F0:26:56:8C:5F:B2:F5:A1:DF:2A:0B:87:EF:F6:07:14:53:DB:A4:F8:81:01:D2:5F\r\na=setup:actpass\r\na=rtcp-mux\r\na=rtcp:29310 IN IP4 $${local_ip_v4}\r\na=rtcp-fb:104 ccm fir\r\na=rtcp-fb:104 ccm tmmbr\r\na=rtcp-fb:104 nack\r\na=rtcp-fb:104 nack pli\r\na=ssrc:3990963918 cname:7dtvIsRBJb2AlD8p\r\na=ssrc:3990963918 msid:ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrN v0\r\na=ssrc:3990963918 mslabel:ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrN\r\na=ssrc:3990963918 label:ovVQxF8w2ruB0jKIdmpCslRQ2RpvyMrNv0\r\na=ice-ufrag:xbhifuaNFvcnA94Z\r\na=ice-pwd:GD9Yw1gpQhh2qLSON0WSkXwP\r\na=candidate:7628899082 1 udp 659136 $${local_ip_v4} 29310 typ host generation 0\r\na=candidate:7628899082 2 udp 659135 $${local_ip_v4} 29310 typ host generation 0\r\na=end-of-candidates\r\n",
  507.                 "caller_id_name":       "1000",
  508.                 "caller_id_number":     "1000",
  509.                 "callee_id_name":       "Dayo",
  510.                 "callee_id_number":     "1010",
  511.                 "display_direction":    "outbound"
  512.         }
  513. }]
  514. 2018-08-30 10:00:28.664107 [ALERT] mod_verto.c:1384 READ 197.210.65.10:63551 [{
  515.         "jsonrpc":      "2.0",
  516.         "id":   13,
  517.         "result":       {
  518.                 "method":       "verto.invite"
  519.         }
  520. }]
  521. 2018-08-30 10:00:28.944151 [INFO] switch_rtp.c:7268 Auto Changing audio port from 192.168.43.205:50648 to 197.210.173.34:50648
  522. 2018-08-30 10:00:34.464282 [WARNING] switch_core_state_machine.c:687 93011244-be51-4d46-9bb4-5360f3d7c251 sofia/internal/301@139.162.188.90 Abandoned
  523. 2018-08-30 10:00:34.464282 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/internal/301@139.162.188.90 [CS_NEW] [WRONG_CALL_STATE]
  524. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/301@139.162.188.90) Running State Change CS_HANGUP (Cur 3 Tot 1933)
  525. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/301@139.162.188.90) Callstate Change DOWN -> HANGUP
  526. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/301@139.162.188.90) State HANGUP
  527. 2018-08-30 10:00:34.464282 [DEBUG] mod_sofia.c:438 Channel sofia/internal/301@139.162.188.90 hanging up, cause: WRONG_CALL_STATE
  528. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:60 sofia/internal/301@139.162.188.90 Standard HANGUP, cause: WRONG_CALL_STATE
  529. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/301@139.162.188.90) State HANGUP going to sleep
  530. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/301@139.162.188.90) State Change CS_HANGUP -> CS_REPORTING
  531. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/301@139.162.188.90) Running State Change CS_REPORTING (Cur 3 Tot 1933)
  532. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/301@139.162.188.90) State REPORTING
  533. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:174 sofia/internal/301@139.162.188.90 Standard REPORTING, cause: WRONG_CALL_STATE
  534. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/301@139.162.188.90) State REPORTING going to sleep
  535. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/301@139.162.188.90) State Change CS_REPORTING -> CS_DESTROY
  536. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_session.c:1665 Session 1931 (sofia/internal/301@139.162.188.90) Locked, Waiting on external entities
  537. 2018-08-30 10:00:34.464282 [NOTICE] switch_core_session.c:1683 Session 1931 (sofia/internal/301@139.162.188.90) Ended
  538. 2018-08-30 10:00:34.464282 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/301@139.162.188.90 [CS_DESTROY]
  539. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/301@139.162.188.90) Running State Change CS_DESTROY (Cur 2 Tot 1933)
  540. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/301@139.162.188.90) State DESTROY
  541. 2018-08-30 10:00:34.464282 [DEBUG] mod_sofia.c:343 sofia/internal/301@139.162.188.90 SOFIA DESTROY
  542. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:181 sofia/internal/301@139.162.188.90 Standard DESTROY
  543. 2018-08-30 10:00:34.464282 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/301@139.162.188.90) State DESTROY going to sleep
  544. 2018-08-30 10:00:36.974158 [ALERT] mod_verto.c:1384 READ 197.210.65.10:63551 [{
  545.         "jsonrpc":      "2.0",
  546.         "method":       "verto.answer",
  547.         "params":       {
  548.                 "sdp":  "v=0\r\no=- 6623213443728101624 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=msid-semantic: WMS agPMoxGKUmKC2r1CWvzGeIMtHLwWNWPBklXo\r\nm=audio 9 RTP/SAVPF 9 8 102 0\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:8Kjv\r\na=ice-pwd:HX0GfWf+boz0NiBE3bLyH5fb\r\na=ice-options:trickle\r\na=fingerprint:sha-256 AC:E9:80:1B:C6:F6:22:4B:08:FE:0D:7C:49:4E:DB:56:C0:FF:48:3B:15:32:3C:73:D7:B7:89:19:A9:12:15:E5\r\na=setup:active\r\na=mid:audio\r\na=sendrecv\r\na=rtcp-mux\r\na=rtpmap:9 G722/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:102 opus/48000/2\r\na=fmtp:102 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1\r\na=rtpmap:0 PCMU/8000\r\na=ssrc:2262968536 cname:Yiz8JWeDWN9phn7e\r\na=ssrc:2262968536 msid:agPMoxGKUmKC2r1CWvzGeIMtHLwWNWPBklXo af3cca82-c6a5-45ce-b586-090f10d11034\r\na=ssrc:2262968536 mslabel:agPMoxGKUmKC2r1CWvzGeIMtHLwWNWPBklXo\r\na=ssrc:2262968536 label:af3cca82-c6a5-45ce-b586-090f10d11034\r\nm=video 9 RTP/SAVPF 104\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:csHP\r\na=ice-pwd:cURMSx0Q/1hMZFs6vZcCGqnU\r\na=ice-options:trickle\r\na=fingerprint:sha-256 AC:E9:80:1B:C6:F6:22:4B:08:FE:0D:7C:49:4E:DB:56:C0:FF:48:3B:15:32:3C:73:D7:B7:89:19:A9:12:15:E5\r\na=setup:active\r\na=mid:video\r\na=recvonly\r\na=rtcp-mux\r\na=rtpmap:104 VP8/90000\r\na=rtcp-fb:104 ccm fir\r\na=rtcp-fb:104 nack\r\na=rtcp-fb:104 nack pli\r\n",
  549.                 "dialogParams": {
  550.                         "useStereo":    true,
  551.                         "screenShare":  false,
  552.                         "useCamera":    "none",
  553.                         "useMic":       "default",
  554.                         "useSpeak":     "default",
  555.                         "tag":  "webcam",
  556.                         "localTag":     null,
  557.                         "login":        "1010@tekart.dayo.com.ng",
  558.                         "videoParams":  {
  559.                                 "minWidth":     160,
  560.                                 "minHeight":    120,
  561.                                 "maxWidth":     160,
  562.                                 "maxHeight":    120,
  563.                                 "minFrameRate": 15
  564.                         },
  565.                         "callID":       "41f39aee-4cfc-4782-be62-546edc3cef7c",
  566.                         "caller_id_name":       "1000",
  567.                         "caller_id_number":     "1000",
  568.                         "callee_id_name":       "Dayo",
  569.                         "callee_id_number":     "1010",
  570.                         "display_direction":    "outbound",
  571.                         "wantVideo":    true,
  572.                         "remote_caller_id_name":        "1000",
  573.                         "remote_caller_id_number":      "1000"
  574.                 },
  575.                 "sessid":       "d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071"
  576.         },
  577.         "id":   29
  578. }]
  579. 2018-08-30 10:00:36.974158 [DEBUG] mod_verto.c:2689 Remote SDP verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071:
  580. v=0
  581. o=- 6623213443728101624 2 IN IP4 127.0.0.1
  582. s=-
  583. t=0 0
  584. a=msid-semantic: WMS agPMoxGKUmKC2r1CWvzGeIMtHLwWNWPBklXo
  585. m=audio 9 RTP/SAVPF 9 8 102 0
  586. c=IN IP4 0.0.0.0
  587. a=rtcp:9 IN IP4 0.0.0.0
  588. a=ice-ufrag:8Kjv
  589. a=ice-pwd:HX0GfWf+boz0NiBE3bLyH5fb
  590. a=ice-options:trickle
  591. a=fingerprint:sha-256 AC:E9:80:1B:C6:F6:22:4B:08:FE:0D:7C:49:4E:DB:56:C0:FF:48:3B:15:32:3C:73:D7:B7:89:19:A9:12:15:E5
  592. a=setup:active
  593. a=mid:audio
  594. a=sendrecv
  595. a=rtcp-mux
  596. a=rtpmap:9 G722/8000
  597. a=rtpmap:8 PCMA/8000
  598. a=rtpmap:102 opus/48000/2
  599. a=fmtp:102 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1
  600. a=rtpmap:0 PCMU/8000
  601. a=ssrc:2262968536 cname:Yiz8JWeDWN9phn7e
  602. a=ssrc:2262968536 msid:agPMoxGKUmKC2r1CWvzGeIMtHLwWNWPBklXo af3cca82-c6a5-45ce-b586-090f10d11034
  603. a=ssrc:2262968536 mslabel:agPMoxGKUmKC2r1CWvzGeIMtHLwWNWPBklXo
  604. a=ssrc:2262968536 label:af3cca82-c6a5-45ce-b586-090f10d11034
  605. m=video 9 RTP/SAVPF 104
  606. c=IN IP4 0.0.0.0
  607. a=rtcp:9 IN IP4 0.0.0.0
  608. a=ice-ufrag:csHP
  609. a=ice-pwd:cURMSx0Q/1hMZFs6vZcCGqnU
  610. a=ice-options:trickle
  611. a=fingerprint:sha-256 AC:E9:80:1B:C6:F6:22:4B:08:FE:0D:7C:49:4E:DB:56:C0:FF:48:3B:15:32:3C:73:D7:B7:89:19:A9:12:15:E5
  612. a=setup:active
  613. a=mid:video
  614. a=recvonly
  615. a=rtcp-mux
  616. a=rtpmap:104 VP8/90000
  617. a=rtcp-fb:104 ccm fir
  618. a=rtcp-fb:104 nack
  619. a=rtcp-fb:104 nack pli
  620.  
  621. 2018-08-30 10:00:36.974158 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Params:
  622. 'Event-Name: REQUEST_PARAMS
  623. Core-UUID: f339f992-3aa5-448d-8aca-5fd7e6c3b677
  624. FreeSWITCH-Hostname: test-fs-001
  625. FreeSWITCH-Switchname: test-fs-001
  626. FreeSWITCH-IPv4: 139.162.188.90
  627. FreeSWITCH-IPv6: %3A%3A1
  628. Event-Date-Local: 2018-08-30%2010%3A00%3A36
  629. Event-Date-GMT: Thu,%2030%20Aug%202018%2010%3A00%3A36%20GMT
  630. Event-Date-Timestamp: 1535623236974158
  631. Event-Calling-File: switch_xml.c
  632. Event-Calling-Function: switch_xml_locate_user
  633. Event-Calling-Line-Number: 2109
  634. Event-Sequence: 28383
  635. key: id
  636. user: 1010
  637. domain: tekart.dayo.com.ng
  638.  
  639. '
  640. 2018-08-30 10:00:36.974158 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Section: directory
  641. 2018-08-30 10:00:36.974158 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Tag Name: domain
  642. 2018-08-30 10:00:36.974158 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Key Name: name
  643. 2018-08-30 10:00:36.974158 [NOTICE] switch_cpp.cpp:1365 [xml_handler] Key Value: tekart.dayo.com.ng
  644. 2018-08-30 10:00:37.594155 [NOTICE] switch_cpp.cpp:1365 [xml]: <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  645. <document type="freeswitch/xml">
  646.  
  647. <section name="directory">
  648.  
  649.   <domain name="tekart.dayo.com.ng" alias="true">
  650.     <params>
  651.       <param name="jsonrpc-allowed-methods" value="verto"/>
  652.       <param name="jsonrpc-allowed-event-channels" value="demo,conference,presence"/>
  653.     </params>
  654.     <groups>
  655.       <group name="default">
  656.         <users>
  657.  
  658.           <user id="1010">
  659.  
  660.           <params>
  661.             <param name="password" value=""/>
  662.             <param name="vm-enabled" value="false"/>
  663.  
  664.  
  665.             <param name="dial-string" value="{sip_invite_domain=tekart.dayo.com.ng,
  666.              presence_id=1010@tekart.dayo.com.ng}${sofia_contact(1010@tekart.dayo.com.ng)},
  667.              ${verto_contact 1010@tekart.dayo.com.ng}"/>
  668.  
  669.             <param name="verto-context" value="tekart.dayo.com.ng"/>
  670.             <param name="verto-dialplan" value="XML"/>
  671.             <param name="jsonrpc-allowed-methods" value="verto"/>
  672.             <param name="jsonrpc-allowed-event-channels" value="demo,conference,presence"/>
  673.           </params>
  674.           <variables>
  675.             <variable name="domain_uuid" value="None"/>
  676.             <varibale name="domain_name" value="tekart.dayo.com.ng"/>
  677.             <variable name="user_id" value="3"/>
  678.             <variable name="extension_uuid" value="a26ada20-9c0a-4218-9c3e-96bac8efe702"/>
  679.             <variable name="call_timeout" value="30"/>
  680.             <variable name="caller_id_name" value="1010"/>
  681.             <variable name="caller_id_number" value="1010"/>
  682.             <variable name="presence_id" value="1010@tekart.dayo.com.ng"/>
  683.  
  684.             <variable name="user_context" value="tekart.dayo.com.ng"/>
  685.  
  686.  
  687.             <variable name="bypass_media" value="false"/>
  688.             <variable name="export_vars" value="domain_name"/>
  689.           </variables>
  690.           </user>
  691.         </users>
  692.       </group>
  693.     </groups>
  694.   </domain>
  695.  
  696. </section>
  697.  
  698. </document>
  699. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  700. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  701. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  702. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:2]
  703. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  704. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  705. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  706. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  707. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  708. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:2]
  709. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  710. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  711. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:2]/[G722:9:8000:20:64000:1]
  712. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:2]/[PCMA:8:8000:20:64000:1]
  713. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:2]/[opus:116:48000:20:0:2]
  714. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match
  715. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:2]/[PCMU:0:8000:20:64000:1]
  716. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:102:48000:20:0:2]/[opus:116:48000:20:0:1]
  717. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  718. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  719. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  720. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:2]
  721. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  722. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  723. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  724. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:3061 Set Codec verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 G722/8000 20 ms 160 samples 64000 bits 1 channels
  725. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_codec.c:111 verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 Original read codec set to G722:9
  726. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:3523 Searching for rtp candidate.
  727. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:3523 Searching for rtcp candidate.
  728. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:3567 verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 no suitable candidates found.
  729. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4774 No 2833 in SDP.  Disable 2833 dtmf and switch to INFO
  730. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4950 Video Codec Compare [VP8:104]/[VP8:99]
  731. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:4982 Video Codec Compare [VP8:104] +++ is saved as a match
  732. 2018-08-30 10:00:37.594155 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  733. 2018-08-30 10:00:37.594155 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  734. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:2871 Set VIDEO Codec verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 VP8/90000 0 ms
  735. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:3523 Searching for rtp candidate.
  736. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:3523 Searching for rtcp candidate.
  737. 2018-08-30 10:00:37.594155 [DEBUG] switch_core_media.c:3567 verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 no suitable candidates found.
  738. 2018-08-30 10:00:37.594155 [ALERT] mod_verto.c:604 WRITE 197.210.65.10:63551 [{
  739.         "jsonrpc":      "2.0",
  740.         "id":   29,
  741.         "error":        {
  742.                 "message":      "CODEC ERROR",
  743.                 "code": -32002
  744.         }
  745. }]
  746. 2018-08-30 10:00:37.784119 [ALERT] mod_verto.c:1384 READ 197.210.65.10:63551 [{
  747.         "jsonrpc":      "2.0",
  748.         "method":       "verto.bye",
  749.         "params":       {
  750.                 "dialogParams": {
  751.                         "useStereo":    true,
  752.                         "screenShare":  false,
  753.                         "useCamera":    "none",
  754.                         "useMic":       "default",
  755.                         "useSpeak":     "default",
  756.                         "tag":  "webcam",
  757.                         "localTag":     null,
  758.                         "login":        "1010@tekart.dayo.com.ng",
  759.                         "videoParams":  {
  760.                                 "minWidth":     160,
  761.                                 "minHeight":    120,
  762.                                 "maxWidth":     160,
  763.                                 "maxHeight":    120,
  764.                                 "minFrameRate": 15
  765.                         },
  766.                         "callID":       "41f39aee-4cfc-4782-be62-546edc3cef7c",
  767.                         "caller_id_name":       "1000",
  768.                         "caller_id_number":     "1000",
  769.                         "callee_id_name":       "Dayo",
  770.                         "callee_id_number":     "1010",
  771.                         "display_direction":    "outbound",
  772.                         "wantVideo":    true,
  773.                         "remote_caller_id_name":        "1000",
  774.                         "remote_caller_id_number":      "1000"
  775.                 },
  776.                 "sessid":       "d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071"
  777.         },
  778.         "id":   30
  779. }]
  780. 2018-08-30 10:00:37.784119 [NOTICE] mod_verto.c:2785 Hangup verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 [CS_CONSUME_MEDIA] [NORMAL_CLEARING]
  781. 2018-08-30 10:00:37.794091 [ALERT] mod_verto.c:604 WRITE 197.210.65.10:63551 [{
  782.         "jsonrpc":      "2.0",
  783.         "id":   30,
  784.         "result":       {
  785.                 "callID":       "41f39aee-4cfc-4782-be62-546edc3cef7c",
  786.                 "message":      "CALL ENDED",
  787.                 "causeCode":    16,
  788.                 "cause":        "NORMAL_CLEARING",
  789.                 "sessid":       "d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071"
  790.         }
  791. }]
  792. 2018-08-30 10:00:37.794091 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) Running State Change CS_HANGUP (Cur 2 Tot 1933)
  793. 2018-08-30 10:00:37.794091 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) Callstate Change RINGING -> HANGUP
  794. 2018-08-30 10:00:37.794091 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State HANGUP
  795. 2018-08-30 10:00:37.794091 [DEBUG] switch_core_state_machine.c:60 verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 Standard HANGUP, cause: NORMAL_CLEARING
  796. 2018-08-30 10:00:37.794091 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State HANGUP going to sleep
  797. 2018-08-30 10:00:37.794091 [DEBUG] switch_core_state_machine.c:619 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State Change CS_HANGUP -> CS_REPORTING
  798. 2018-08-30 10:00:37.794091 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) Running State Change CS_REPORTING (Cur 2 Tot 1933)
  799. 2018-08-30 10:00:37.794091 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State REPORTING
  800. 2018-08-30 10:00:37.794091 [DEBUG] switch_core_state_machine.c:174 verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 Standard REPORTING, cause: NORMAL_CLEARING
  801. 2018-08-30 10:00:37.794091 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State REPORTING going to sleep
  802. 2018-08-30 10:00:37.794091 [DEBUG] switch_core_state_machine.c:610 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State Change CS_REPORTING -> CS_DESTROY
  803. 2018-08-30 10:00:37.794091 [DEBUG] switch_core_session.c:1665 Session 1933 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) Locked, Waiting on external entities
  804. 2018-08-30 10:00:37.794091 [ALERT] mod_verto.c:5329 EVENT BROADCAST presence.1010@tekart.dayo.com.ng {
  805.         "data": {
  806.                 "channelCallState":     "HANGUP",
  807.                 "originalChannelCallState":     "RINGING",
  808.                 "channelState": "CS_HANGUP",
  809.                 "callerUserName":       "1000",
  810.                 "callerIDName": "1000",
  811.                 "callerIDNumber":       "1000",
  812.                 "calleeIDName": "Dayo",
  813.                 "calleeIDNumber":       "1010",
  814.                 "channelUUID":  "41f39aee-4cfc-4782-be62-546edc3cef7c",
  815.                 "presenceCallDirection":        "outbound",
  816.                 "channelPresenceID":    "1010@tekart.dayo.com.ng"
  817.         },
  818.         "eventChannel": "presence.1010@tekart.dayo.com.ng"
  819. }
  820. 2018-08-30 10:00:37.804098 [DEBUG] switch_core_codec.c:248 sofia/internal/1000@tekart.dayo.com.ng Restore previous codec G722:9.
  821. 2018-08-30 10:00:37.804098 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 16 [NORMAL_CLEARING]
  822. 2018-08-30 10:00:37.804098 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [user] cause: [NORMAL_CLEARING]
  823. 2018-08-30 10:00:37.804098 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 16 [NORMAL_CLEARING]
  824. 2018-08-30 10:00:37.804098 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_CLEARING
  825. 2018-08-30 10:00:37.804098 [NOTICE] switch_channel.c:4844 Hangup sofia/internal/1000@tekart.dayo.com.ng [CS_EXECUTE] [NORMAL_CLEARING]
  826. 2018-08-30 10:00:37.804098 [DEBUG] switch_core_session.c:2815 sofia/internal/1000@tekart.dayo.com.ng skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  827. 2018-08-30 10:00:37.804098 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1000@tekart.dayo.com.ng) State EXECUTE going to sleep
  828. 2018-08-30 10:00:37.804098 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@tekart.dayo.com.ng) Running State Change CS_HANGUP (Cur 2 Tot 1933)
  829. 2018-08-30 10:00:37.804098 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1000@tekart.dayo.com.ng) Callstate Change EARLY -> HANGUP
  830. 2018-08-30 10:00:37.804098 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1000@tekart.dayo.com.ng) State HANGUP
  831. 2018-08-30 10:00:37.804098 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1000@tekart.dayo.com.ng hanging up, cause: NORMAL_CLEARING
  832. 2018-08-30 10:00:37.804098 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 480
  833. 2018-08-30 10:00:37.804098 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1000@tekart.dayo.com.ng Standard HANGUP, cause: NORMAL_CLEARING
  834. 2018-08-30 10:00:37.804098 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1000@tekart.dayo.com.ng) State HANGUP going to sleep
  835. 2018-08-30 10:00:37.804098 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1000@tekart.dayo.com.ng) State Change CS_HANGUP -> CS_REPORTING
  836. 2018-08-30 10:00:37.804098 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@tekart.dayo.com.ng) Running State Change CS_REPORTING (Cur 2 Tot 1933)
  837. 2018-08-30 10:00:37.804098 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1000@tekart.dayo.com.ng) State REPORTING
  838. 2018-08-30 10:00:37.814097 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1000@tekart.dayo.com.ng Standard REPORTING, cause: NORMAL_CLEARING
  839. 2018-08-30 10:00:37.814097 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1000@tekart.dayo.com.ng) State REPORTING going to sleep
  840. 2018-08-30 10:00:37.814097 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1000@tekart.dayo.com.ng) State Change CS_REPORTING -> CS_DESTROY
  841. 2018-08-30 10:00:37.814097 [DEBUG] switch_core_session.c:1665 Session 1932 (sofia/internal/1000@tekart.dayo.com.ng) Locked, Waiting on external entities
  842. 2018-08-30 10:00:37.814097 [NOTICE] switch_core_session.c:1683 Session 1932 (sofia/internal/1000@tekart.dayo.com.ng) Ended
  843. 2018-08-30 10:00:37.814097 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/1000@tekart.dayo.com.ng [CS_DESTROY]
  844. 2018-08-30 10:00:37.814097 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@tekart.dayo.com.ng) Running State Change CS_DESTROY (Cur 1 Tot 1933)
  845. 2018-08-30 10:00:37.814097 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1000@tekart.dayo.com.ng) State DESTROY
  846. 2018-08-30 10:00:37.814097 [DEBUG] mod_sofia.c:343 sofia/internal/1000@tekart.dayo.com.ng SOFIA DESTROY
  847. 2018-08-30 10:00:37.814097 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1000@tekart.dayo.com.ng Standard DESTROY
  848. 2018-08-30 10:00:37.814097 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1000@tekart.dayo.com.ng) State DESTROY going to sleep
  849. 2018-08-30 10:00:37.814097 [NOTICE] switch_core_session.c:1683 Session 1933 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) Ended
  850. 2018-08-30 10:00:37.814097 [NOTICE] switch_core_session.c:1687 Close Channel verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 [CS_DESTROY]
  851. 2018-08-30 10:00:37.814097 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) Running State Change CS_DESTROY (Cur 0 Tot 1933)
  852. 2018-08-30 10:00:37.814097 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State DESTROY
  853. 2018-08-30 10:00:37.814097 [DEBUG] mod_rtc.c:132 verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 RTC DESTROY
  854. 2018-08-30 10:00:37.814097 [DEBUG] switch_core_state_machine.c:181 verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071 Standard DESTROY
  855. 2018-08-30 10:00:37.814097 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/d620b6c4-56d7-6dfd-b19f-f6d3dbd9f071) State DESTROY going to sleep
  856. 2018-08-30 10:00:37.814097 [ALERT] mod_verto.c:5329 EVENT BROADCAST presence.1000@tekart.dayo.com.ng {
  857.         "data": {
  858.                 "channelCallState":     "HANGUP",
  859.                 "originalChannelCallState":     "EARLY",
  860.                 "channelState": "CS_HANGUP",
  861.                 "callerUserName":       "1000",
  862.                 "callerIDName": "1000",
  863.                 "callerIDNumber":       "1000",
  864.                 "channelUUID":  "cd187882-801d-45e0-b9b6-6065e09debb8",
  865.                 "presenceCallDirection":        "inbound",
  866.                 "channelPresenceID":    "1000@tekart.dayo.com.ng"
  867.         },
  868.         "eventChannel": "presence.1000@tekart.dayo.com.ng"
  869. }
  870.  

Reply to "log"