From River Hume, 4 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. v2v call - no media in prod
  2.  
  3. FS_CLI:
  4.  
  5. +OK log level  [7]
  6. 2020-02-10 20:26:32.438738 [DEBUG] mod_verto.c:1415 READ 75.69.229.242:55707 [{
  7.         "jsonrpc":      "2.0",
  8.         "method":       "verto.invite",
  9.         "params":       {
  10.                 "sdp":  "v=0\r\no=- 5294435820167355833 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE audio\r\na=msid-semantic: WMS 73CZbHY19upuW80v0kyhdfNyDpN0ECx9MQY5\r\nm=audio 54872 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 75.69.229.242\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:2456471252 1 udp 2122260223 10.0.0.13 54872 typ host generation 0 network-id 1 network-cost 10\r\na=candidate:3504814172 1 udp 1686052607 75.69.229.242 54872 typ srflx raddr 10.0.0.13 rport 54872 generation 0 network-id 1 network-cost 10\r\na=candidate:3706272292 1 tcp 1518280447 10.0.0.13 9 typ host tcptype active generation 0 network-id 1 network-cost 10\r\na=ice-ufrag:7EfF\r\na=ice-pwd:Z8WH62o+Yhj+iqbOdIAapNpG\r\na=ice-options:trickle\r\na=fingerprint:sha-256 24:84:49:A3:18:F7:CC:BC:72:5D:72:3D:14:B4:79:B8:F4:70:76:51:86:9E:11:CD:A8:FE:CD:50:33:D5:BA:90\r\na=setup:actpass\r\na=mid:audio\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=sendrecv\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:3135906121 cname:4sygjsSsqgZzYttl\r\na=ssrc:3135906121 msid:73CZbHY19upuW80v0kyhdfNyDpN0ECx9MQY5 262eeec0-fe95-472a-b579-03389273d351\r\na=ssrc:3135906121 mslabel:73CZbHY19upuW80v0kyhdfNyDpN0ECx9MQY5\r\na=ssrc:3135906121 label:262eeec0-fe95-472a-b579-03389273d351\r\n",
  11.                 "dialogParams": {
  12.                         "useVideo":     false,
  13.                         "useStereo":    true,
  14.                         "screenShare":  false,
  15.                         "useCamera":    "f76590a55f1c1fd642aae28210b44ba4e366d3e0ec613d8860358fe0bb9ff58c",
  16.                         "useMic":       "default",
  17.                         "useSpeak":     "default",
  18.                         "tag":  "webcam",
  19.                         "localTag":     null,
  20.                         "login":        "2201@pbx.performityinctest.com",
  21.                         "videoParams":  {
  22.                                 "minWidth":     1280,
  23.                                 "minHeight":    720,
  24.                                 "maxWidth":     1280,
  25.                                 "maxHeight":    720,
  26.                                 "minFrameRate": 15
  27.                         },
  28.                         "useStream":    null,
  29.                         "destination_number":   "2001",
  30.                         "caller_id_name":       "Bennett Malbon",
  31.                         "caller_id_number":     "bennett.malbon@gmail.com",
  32.                         "outgoingBandwidth":    4377,
  33.                         "incomingBandwidth":    null,
  34.                         "dedEnc":       false,
  35.                         "mirrorInput":  false,
  36.                         "userVariables":        {
  37.                                 "email":        "bennett.malbon@gmail.com",
  38.                                 "avatar":       "http://gravatar.com/avatar/9264cd2d9ebc0d7d78c2c73b39a5c176.png?s=600"
  39.                         },
  40.                         "callID":       "3d4878b9-8f16-5a58-feee-a300d1e8e84c",
  41.                         "remote_caller_id_name":        "Outbound Call",
  42.                         "remote_caller_id_number":      "2001"
  43.                 },
  44.                 "sessid":       "cc7e3c7a-d037-28b6-6743-6775eeb505a8"
  45.         },
  46.         "id":   16
  47. }]
  48. 2020-02-10 20:26:32.438738 [DEBUG] mod_rtc.c:390 () State Change CS_NEW -> CS_INIT
  49. 2020-02-10 20:26:32.438738 [DEBUG] switch_core_session.c:641 N/A set UUID=3d4878b9-8f16-5a58-feee-a300d1e8e84c
  50. 2020-02-10 20:26:32.438738 [NOTICE] switch_channel.c:1118 New Channel verto.rtc/2001 [3d4878b9-8f16-5a58-feee-a300d1e8e84c]
  51. 2020-02-10 20:26:32.438738 [DEBUG] mod_verto.c:3797 Remote SDP verto.rtc/2001:
  52. v=0
  53. o=- 5294435820167355833 2 IN IP4 127.0.0.1
  54. s=-
  55. t=0 0
  56. a=group:BUNDLE audio
  57. a=msid-semantic: WMS 73CZbHY19upuW80v0kyhdfNyDpN0ECx9MQY5
  58. m=audio 54872 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
  59. c=IN IP4 75.69.229.242
  60. a=rtcp:9 IN IP4 0.0.0.0
  61. a=candidate:2456471252 1 udp 2122260223 10.0.0.13 54872 typ host generation 0 network-id 1 network-cost 10
  62. a=candidate:3504814172 1 udp 1686052607 75.69.229.242 54872 typ srflx raddr 10.0.0.13 rport 54872 generation 0 network-id 1 network-cost 10
  63. a=candidate:3706272292 1 tcp 1518280447 10.0.0.13 9 typ host tcptype active generation 0 network-id 1 network-cost 10
  64. a=ice-ufrag:7EfF
  65. a=ice-pwd:Z8WH62o+Yhj+iqbOdIAapNpG
  66. a=ice-options:trickle
  67. a=fingerprint:sha-256 24:84:49:A3:18:F7:CC:BC:72:5D:72:3D:14:B4:79:B8:F4:70:76:51:86:9E:11:CD:A8:FE:CD:50:33:D5:BA:90
  68. a=setup:actpass
  69. a=mid:audio
  70. a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
  71. a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
  72. a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
  73. a=sendrecv
  74. a=rtcp-mux
  75. a=rtpmap:111 opus/48000/2
  76. a=rtcp-fb:111 transport-cc
  77. a=fmtp:111 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1
  78. a=rtpmap:103 ISAC/16000
  79. a=rtpmap:104 ISAC/32000
  80. a=rtpmap:9 G722/8000
  81. a=rtpmap:0 PCMU/8000
  82. a=rtpmap:8 PCMA/8000
  83. a=rtpmap:106 CN/32000
  84. a=rtpmap:105 CN/16000
  85. a=rtpmap:13 CN/8000
  86. a=rtpmap:110 telephone-event/48000
  87. a=rtpmap:112 telephone-event/32000
  88. a=rtpmap:113 telephone-event/16000
  89. a=rtpmap:126 telephone-event/8000
  90. a=ssrc:3135906121 cname:4sygjsSsqgZzYttl
  91. a=ssrc:3135906121 msid:73CZbHY19upuW80v0kyhdfNyDpN0ECx9MQY5 262eeec0-fe95-472a-b579-03389273d351
  92. a=ssrc:3135906121 mslabel:73CZbHY19upuW80v0kyhdfNyDpN0ECx9MQY5
  93. a=ssrc:3135906121 label:262eeec0-fe95-472a-b579-03389273d351
  94.  
  95. 2020-02-10 20:26:32.438738 [DEBUG] mod_verto.c:607 WRITE 75.69.229.242:55707 [{
  96.         "jsonrpc":      "2.0",
  97.         "id":   16,
  98.         "result":       {
  99.                 "message":      "CALL CREATED",
  100.                 "callID":       "3d4878b9-8f16-5a58-feee-a300d1e8e84c",
  101.                 "sessid":       "cc7e3c7a-d037-28b6-6743-6775eeb505a8"
  102.         }
  103. }]
  104. 2020-02-10 20:26:32.438738 [DEBUG] switch_core_state_machine.c:585 (verto.rtc/2001) Running State Change CS_INIT (Cur 1 Tot 25)
  105. 2020-02-10 20:26:32.438738 [DEBUG] switch_core_state_machine.c:628 (verto.rtc/2001) State INIT
  106. 2020-02-10 20:26:32.438738 [DEBUG] switch_core_state_machine.c:40 verto.rtc/2001 Standard INIT
  107. 2020-02-10 20:26:32.438738 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/2001) State Change CS_INIT -> CS_ROUTING
  108. 2020-02-10 20:26:32.438738 [DEBUG] switch_core_state_machine.c:628 (verto.rtc/2001) State INIT going to sleep
  109. 2020-02-10 20:26:32.438738 [DEBUG] switch_core_state_machine.c:585 (verto.rtc/2001) Running State Change CS_ROUTING (Cur 1 Tot 25)
  110. 2020-02-10 20:26:32.438738 [DEBUG] switch_channel.c:2332 (verto.rtc/2001) Callstate Change DOWN -> RINGING
  111. 2020-02-10 20:26:32.438738 [DEBUG] switch_core_state_machine.c:644 (verto.rtc/2001) State ROUTING
  112. 2020-02-10 20:26:32.438738 [DEBUG] mod_rtc.c:89 verto.rtc/2001 RTC ROUTING
  113. 2020-02-10 20:26:32.438738 [DEBUG] switch_core_state_machine.c:236 verto.rtc/2001 Standard ROUTING
  114. 2020-02-10 20:26:32.438738 [INFO] mod_dialplan_xml.c:637 Processing Bennett Malbon <bennett.malbon@gmail.com>->2001 in context pbx.performityinctest.com
  115. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->user_exists] continue=true
  116. Dialplan: verto.rtc/2001 Regex (PASS) [user_exists] () =~ // break=on-false
  117. Dialplan: verto.rtc/2001 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
  118. EXECUTE [depth=0] verto.rtc/2001 set(user_exists=true)
  119. 2020-02-10 20:26:32.438738 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [user_exists]=[true]
  120. Dialplan: verto.rtc/2001 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
  121. EXECUTE [depth=0] verto.rtc/2001 set(from_user_exists=false)
  122. 2020-02-10 20:26:32.438738 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [from_user_exists]=[false]
  123. Dialplan: verto.rtc/2001 Regex (PASS) [user_exists] ${user_exists}(true) =~ /^true$/ break=on-false
  124. Dialplan: verto.rtc/2001 Action set(extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid}) INLINE
  125. EXECUTE [depth=0] verto.rtc/2001 set(extension_uuid=cb74a4f9-f557-4e2f-82e9-b822ea00185c)
  126. 2020-02-10 20:26:32.438738 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [extension_uuid]=[cb74a4f9-f557-4e2f-82e9-b822ea00185c]
  127. Dialplan: verto.rtc/2001 Action set(hold_music=${user_data ${destination_number}@${domain_name} var hold_music}) INLINE
  128. EXECUTE [depth=0] verto.rtc/2001 set(hold_music=)
  129. 2020-02-10 20:26:32.458742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [hold_music]=[UNDEF]
  130. Dialplan: verto.rtc/2001 Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled}) INLINE
  131. EXECUTE [depth=0] verto.rtc/2001 set(forward_all_enabled=)
  132. 2020-02-10 20:26:32.458742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [forward_all_enabled]=[UNDEF]
  133. Dialplan: verto.rtc/2001 Action set(forward_all_destination=${user_data ${destination_number}@${domain_name} var forward_all_destination}) INLINE
  134. EXECUTE [depth=0] verto.rtc/2001 set(forward_all_destination=)
  135. 2020-02-10 20:26:32.458742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [forward_all_destination]=[UNDEF]
  136. Dialplan: verto.rtc/2001 Action set(forward_busy_enabled=${user_data ${destination_number}@${domain_name} var forward_busy_enabled}) INLINE
  137. EXECUTE [depth=0] verto.rtc/2001 set(forward_busy_enabled=)
  138. 2020-02-10 20:26:32.458742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [forward_busy_enabled]=[UNDEF]
  139. Dialplan: verto.rtc/2001 Action set(forward_busy_destination=${user_data ${destination_number}@${domain_name} var forward_busy_destination}) INLINE
  140. EXECUTE [depth=0] verto.rtc/2001 set(forward_busy_destination=)
  141. 2020-02-10 20:26:32.458742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [forward_busy_destination]=[UNDEF]
  142. Dialplan: verto.rtc/2001 Action set(forward_no_answer_enabled=${user_data ${destination_number}@${domain_name} var forward_no_answer_enabled}) INLINE
  143. EXECUTE [depth=0] verto.rtc/2001 set(forward_no_answer_enabled=)
  144. 2020-02-10 20:26:32.458742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [forward_no_answer_enabled]=[UNDEF]
  145. Dialplan: verto.rtc/2001 Action set(forward_no_answer_destination=${user_data ${destination_number}@${domain_name} var forward_no_answer_destination}) INLINE
  146. EXECUTE [depth=0] verto.rtc/2001 set(forward_no_answer_destination=)
  147. 2020-02-10 20:26:32.458742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [forward_no_answer_destination]=[UNDEF]
  148. Dialplan: verto.rtc/2001 Action set(forward_user_not_registered_enabled=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_enabled}) INLINE
  149. EXECUTE [depth=0] verto.rtc/2001 set(forward_user_not_registered_enabled=)
  150. 2020-02-10 20:26:32.478742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [forward_user_not_registered_enabled]=[UNDEF]
  151. Dialplan: verto.rtc/2001 Action set(forward_user_not_registered_destination=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_destination}) INLINE
  152. EXECUTE [depth=0] verto.rtc/2001 set(forward_user_not_registered_destination=)
  153. 2020-02-10 20:26:32.478742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [forward_user_not_registered_destination]=[UNDEF]
  154. Dialplan: verto.rtc/2001 Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled}) INLINE
  155. EXECUTE [depth=0] verto.rtc/2001 set(forward_all_enabled=)
  156. 2020-02-10 20:26:32.478742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [forward_all_enabled]=[UNDEF]
  157. Dialplan: verto.rtc/2001 Action set(follow_me_enabled=${user_data ${destination_number}@${domain_name} var follow_me_enabled}) INLINE
  158. EXECUTE [depth=0] verto.rtc/2001 set(follow_me_enabled=)
  159. 2020-02-10 20:26:32.478742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [follow_me_enabled]=[UNDEF]
  160. Dialplan: verto.rtc/2001 Action set(do_not_disturb=${user_data ${destination_number}@${domain_name} var do_not_disturb}) INLINE
  161. EXECUTE [depth=0] verto.rtc/2001 set(do_not_disturb=)
  162. 2020-02-10 20:26:32.478742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [do_not_disturb]=[UNDEF]
  163. Dialplan: verto.rtc/2001 Action set(call_timeout=${user_data ${destination_number}@${domain_name} var call_timeout}) INLINE
  164. EXECUTE [depth=0] verto.rtc/2001 set(call_timeout=30)
  165. 2020-02-10 20:26:32.478742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [call_timeout]=[30]
  166. Dialplan: verto.rtc/2001 Action set(missed_call_app=${user_data ${destination_number}@${domain_name} var missed_call_app}) INLINE
  167. EXECUTE [depth=0] verto.rtc/2001 set(missed_call_app=)
  168. 2020-02-10 20:26:32.478742 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [missed_call_app]=[UNDEF]
  169. Dialplan: verto.rtc/2001 Action set(missed_call_data=${user_data ${destination_number}@${domain_name} var missed_call_data}) INLINE
  170. EXECUTE [depth=0] verto.rtc/2001 set(missed_call_data=)
  171. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [missed_call_data]=[UNDEF]
  172. Dialplan: verto.rtc/2001 Action set(toll_allow=${user_data ${destination_number}@${domain_name} var toll_allow}) INLINE
  173. EXECUTE [depth=0] verto.rtc/2001 set(toll_allow=)
  174. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [toll_allow]=[UNDEF]
  175. Dialplan: verto.rtc/2001 Action set(call_screen_enabled=${user_data ${destination_number}@${domain_name} var call_screen_enabled}) INLINE
  176. EXECUTE [depth=0] verto.rtc/2001 set(call_screen_enabled=false)
  177. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [call_screen_enabled]=[false]
  178. Dialplan: verto.rtc/2001 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
  179. EXECUTE [depth=0] verto.rtc/2001 set(user_record=)
  180. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [user_record]=[UNDEF]
  181. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->caller-details] continue=true
  182. Dialplan: verto.rtc/2001 Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
  183. Dialplan: verto.rtc/2001 Action set(caller_destination=${destination_number}) INLINE
  184. EXECUTE [depth=0] verto.rtc/2001 set(caller_destination=2001)
  185. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [caller_destination]=[2001]
  186. Dialplan: verto.rtc/2001 Action set(caller_id_name=${caller_id_name}) INLINE
  187. EXECUTE [depth=0] verto.rtc/2001 set(caller_id_name=2201)
  188. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [caller_id_name]=[2201]
  189. Dialplan: verto.rtc/2001 Action set(caller_id_number=${caller_id_number})
  190. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->call-direction] continue=true
  191. Dialplan: verto.rtc/2001 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
  192. Dialplan: verto.rtc/2001 ANTI-Action export(call_direction=local) INLINE
  193. EXECUTE [depth=0] verto.rtc/2001 export(call_direction=local)
  194. 2020-02-10 20:26:32.498741 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[local]
  195. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->variables] continue=true
  196. Dialplan: verto.rtc/2001 Regex (PASS) [variables] () =~ // break=on-false
  197. Dialplan: verto.rtc/2001 Action export(origination_callee_id_name=${destination_number})
  198. Dialplan: verto.rtc/2001 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  199. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->is_loopback] continue=true
  200. Dialplan: verto.rtc/2001 Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
  201. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->user_record] continue=true
  202. Dialplan: verto.rtc/2001 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
  203. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
  204. Dialplan: verto.rtc/2001 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
  205. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
  206. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
  207. Dialplan: verto.rtc/2001 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
  208. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
  209. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
  210. Dialplan: verto.rtc/2001 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
  211. Dialplan: verto.rtc/2001 Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
  212. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
  213. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  214. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  215. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
  216. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  217. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
  218. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
  219. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  220. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
  221. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
  222. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  223. Dialplan: verto.rtc/2001 Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
  224. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
  225. Dialplan: verto.rtc/2001 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
  226. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->redial] continue=true
  227. Dialplan: verto.rtc/2001 Regex (FAIL) [redial] destination_number(2001) =~ /^(redial|\*870)$/ break=on-true
  228. Dialplan: verto.rtc/2001 Regex (PASS) [redial] () =~ // break=never
  229. Dialplan: verto.rtc/2001 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  230. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->speed_dial] continue=false
  231. Dialplan: verto.rtc/2001 Regex (FAIL) [speed_dial] destination_number(2001) =~ /^\*0(.*)$/ break=on-false
  232. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->default_caller_id] continue=true
  233. Dialplan: verto.rtc/2001 Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
  234. Dialplan: verto.rtc/2001 Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE
  235. EXECUTE [depth=0] verto.rtc/2001 set(emergency_caller_id_name=)
  236. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [emergency_caller_id_name]=[UNDEF]
  237. Dialplan: verto.rtc/2001 Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE
  238. EXECUTE [depth=0] verto.rtc/2001 set(emergency_caller_id_number=)
  239. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [emergency_caller_id_number]=[UNDEF]
  240. Dialplan: verto.rtc/2001 Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never
  241. Dialplan: verto.rtc/2001 Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE
  242. EXECUTE [depth=0] verto.rtc/2001 set(outbound_caller_id_name=)
  243. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [outbound_caller_id_name]=[UNDEF]
  244. Dialplan: verto.rtc/2001 Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE
  245. EXECUTE [depth=0] verto.rtc/2001 set(outbound_caller_id_number=)
  246. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [outbound_caller_id_number]=[UNDEF]
  247. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->Cisco.PRI] continue=false
  248. Dialplan: verto.rtc/2001 Regex (FAIL) [Cisco.PRI] ${user_exists}(true) =~ /false/ break=on-false
  249. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->Cisco.d4] continue=false
  250. Dialplan: verto.rtc/2001 Regex (FAIL) [Cisco.d4] ${user_exists}(true) =~ /false/ break=on-false
  251. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->verto_screen_share] continue=false
  252. Dialplan: verto.rtc/2001 Regex (FAIL) [verto_screen_share] destination_number(2001) =~ /^(\d+)-screen$/ break=on-false
  253. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->agent_status] continue=false
  254. Dialplan: verto.rtc/2001 Regex (FAIL) [agent_status] destination_number(2001) =~ /^\*22$/ break=never
  255. Dialplan: verto.rtc/2001 Regex (FAIL) [agent_status] destination_number(2001) =~ /^(agent\+)(.*)$/ break=on-false
  256. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->agent_status_id] continue=false
  257. Dialplan: verto.rtc/2001 Regex (FAIL) [agent_status_id] destination_number(2001) =~ /^\*23$/ break=on-false
  258. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->TestQue] continue=
  259. Dialplan: verto.rtc/2001 Regex (FAIL) [TestQue] destination_number(2001) =~ /^([^#]+#)(.*)$/ break=never
  260. Dialplan: verto.rtc/2001 Regex (FAIL) [TestQue] destination_number(2001) =~ /^3003$/ break=on-false
  261. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->group-intercept] continue=false
  262. Dialplan: verto.rtc/2001 Regex (FAIL) [group-intercept] destination_number(2001) =~ /^\*8$/ break=on-false
  263. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->page-extension] continue=false
  264. Dialplan: verto.rtc/2001 Regex (FAIL) [page-extension] destination_number(2001) =~ /^\*8(\d{2,7})$/ break=on-false
  265. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->eavesdrop] continue=false
  266. Dialplan: verto.rtc/2001 Regex (FAIL) [eavesdrop] destination_number(2001) =~ /^\*33(\d{2,7})$/ break=on-false
  267. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->call_privacy] continue=false
  268. Dialplan: verto.rtc/2001 Regex (FAIL) [call_privacy] destination_number(2001) =~ /^\*67(\d+)$/ break=on-false
  269. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->call_return] continue=false
  270. Dialplan: verto.rtc/2001 Regex (FAIL) [call_return] destination_number(2001) =~ /^\*69$/ break=on-false
  271. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->intercept-ext-polycom] continue=false
  272. Dialplan: verto.rtc/2001 Regex (FAIL) [intercept-ext-polycom] destination_number(2001) =~ /^\*97(\d+)$/ break=on-false
  273. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->extension_queue] continue=false
  274. Dialplan: verto.rtc/2001 Regex (FAIL) [extension_queue] destination_number(2001) =~ /^\*800(.*)$/ break=on-false
  275. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->intercept-ext] continue=false
  276. Dialplan: verto.rtc/2001 Regex (FAIL) [intercept-ext] destination_number(2001) =~ /^\*\*(\d+)$/ break=on-true
  277. Dialplan: verto.rtc/2001 Regex (FAIL) [intercept-ext] destination_number(2001) =~ /^\*\*$/ break=on-false
  278. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->dx] continue=false
  279. Dialplan: verto.rtc/2001 Regex (FAIL) [dx] destination_number(2001) =~ /^dx$/ break=on-false
  280. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->send_to_voicemail] continue=false
  281. Dialplan: verto.rtc/2001 Regex (FAIL) [send_to_voicemail] destination_number(2001) =~ /^\*99(\d{2,10})$/ break=on-false
  282. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->extension-to-voicemail] continue=false
  283. Dialplan: verto.rtc/2001 Regex (PASS) [extension-to-voicemail] ${user_exists}(true) =~ /^true$/ break=on-false
  284. Dialplan: verto.rtc/2001 Regex (FAIL) [extension-to-voicemail] username(2201@pbx.performityinctest.com) =~ /^2201$/ break=on-false
  285. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->att_xfer] continue=false
  286. Dialplan: verto.rtc/2001 Regex (FAIL) [att_xfer] destination_number(2001) =~ /^att_xfer$/ break=on-false
  287. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->xfer_vm] continue=false
  288. Dialplan: verto.rtc/2001 Regex (FAIL) [xfer_vm] destination_number(2001) =~ /^xfer_vm$/ break=on-false
  289. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->vmain] continue=false
  290. Dialplan: verto.rtc/2001 Regex (FAIL) [vmain] destination_number(2001) =~ /^vmain$|^\*4000$|^\*98$/ break=never
  291. Dialplan: verto.rtc/2001 Regex (FAIL) [vmain] destination_number(2001) =~ /^(vmain$|^\*4000$|^\*98)(\d{2,12})$/ break=on-false
  292. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->vmain_user] continue=false
  293. Dialplan: verto.rtc/2001 Regex (FAIL) [vmain_user] destination_number(2001) =~ /^\*97$/ break=on-false
  294. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->is_transfer] continue=false
  295. Dialplan: verto.rtc/2001 Regex (FAIL) [is_transfer] destination_number(2001) =~ /^is_transfer$/ break=on-false
  296. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->delay_echo] continue=false
  297. Dialplan: verto.rtc/2001 Regex (FAIL) [delay_echo] destination_number(2001) =~ /^\*9195$/ break=on-false
  298. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->cf] continue=false
  299. Dialplan: verto.rtc/2001 Regex (FAIL) [cf] destination_number(2001) =~ /^cf$/ break=on-false
  300. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->echo] continue=false
  301. Dialplan: verto.rtc/2001 Regex (FAIL) [echo] destination_number(2001) =~ /^\*9196$/ break=on-false
  302. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->is_zrtp_secure] continue=true
  303. Dialplan: verto.rtc/2001 Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false
  304. Dialplan: verto.rtc/2001 ANTI-Action eval(not_secure)
  305. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->milliwatt] continue=false
  306. Dialplan: verto.rtc/2001 Regex (FAIL) [milliwatt] destination_number(2001) =~ /^\*9197$/ break=on-false
  307. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->tone_stream] continue=false
  308. Dialplan: verto.rtc/2001 Regex (FAIL) [tone_stream] destination_number(2001) =~ /^\*9198$/ break=on-false
  309. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->is_secure] continue=true
  310. Dialplan: verto.rtc/2001 Regex (FAIL) [is_secure] ${sip_via_protocol}() =~ /tls/ break=on-false
  311. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->hold_music] continue=false
  312. Dialplan: verto.rtc/2001 Regex (FAIL) [hold_music] destination_number(2001) =~ /^\*9664$/ break=on-false
  313. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->recordings] continue=false
  314. Dialplan: verto.rtc/2001 Regex (FAIL) [recordings] destination_number(2001) =~ /^\*(732)$/ break=on-false
  315. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->directory] continue=false
  316. Dialplan: verto.rtc/2001 Regex (FAIL) [directory] destination_number(2001) =~ /^\*411$/ break=on-false
  317. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->wake-up] continue=false
  318. Dialplan: verto.rtc/2001 Regex (FAIL) [wake-up] destination_number(2001) =~ /^\*(925)$/ break=on-false
  319. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->valet_park] continue=false
  320. Dialplan: verto.rtc/2001 Regex (FAIL) [valet_park] destination_number(2001) =~ /^(park\+)?\*(59[0-9][0-9])$/ break=never
  321. Dialplan: verto.rtc/2001 Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never
  322. Dialplan: verto.rtc/2001 Regex (FAIL) [valet_park] destination_number(2001) =~ /^(park\+)?\*(59[0-9][0-9])$/ break=never
  323. Dialplan: verto.rtc/2001 Regex (FAIL) [valet_park] destination_number(2001) =~ /^(park\+)?\*(59[0-9][0-9])$/ break=on-false
  324. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->operator] continue=false
  325. Dialplan: verto.rtc/2001 Regex (FAIL) [operator] destination_number(2001) =~ /^0$|^operator$/ break=on-false
  326. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->operator-forward] continue=false
  327. Dialplan: verto.rtc/2001 Regex (FAIL) [operator-forward] destination_number(2001) =~ /^\*000$/ break=on-false
  328. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->do-not-disturb] continue=false
  329. Dialplan: verto.rtc/2001 Regex (FAIL) [do-not-disturb] destination_number(2001) =~ /^\*77$/ break=on-true
  330. Dialplan: verto.rtc/2001 Regex (FAIL) [do-not-disturb] destination_number(2001) =~ /^\*78$|\*363$/ break=on-true
  331. Dialplan: verto.rtc/2001 Regex (FAIL) [do-not-disturb] destination_number(2001) =~ /^\*79$/ break=on-true
  332. Dialplan: verto.rtc/2001 Regex (FAIL) [do-not-disturb] destination_number(2001) =~ /^dnd\+2201$/ break=on-true
  333. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->call_screen] continue=true
  334. Dialplan: verto.rtc/2001 Regex (FAIL) [call_screen] ${call_screen_enabled}(false) =~ /^true$/ break=on-false
  335. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->follow-me-destinations] continue=false
  336. Dialplan: verto.rtc/2001 Regex (PASS) [follow-me-destinations] ${user_exists}(true) =~ /^true$/ break=on-false
  337. Dialplan: verto.rtc/2001 Regex (FAIL) [follow-me-destinations] ${follow_me_enabled}() =~ /^true$/ break=on-false
  338. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->call-forward] continue=false
  339. Dialplan: verto.rtc/2001 Regex (FAIL) [call-forward] destination_number(2001) =~ /^\*72(\d*)$/ break=on-true
  340. Dialplan: verto.rtc/2001 Regex (FAIL) [call-forward] destination_number(2001) =~ /^\*73$/ break=on-true
  341. Dialplan: verto.rtc/2001 Regex (FAIL) [call-forward] destination_number(2001) =~ /^\*74$/ break=on-true
  342. Dialplan: verto.rtc/2001 Regex (FAIL) [call-forward] destination_number(2001) =~ /^forward\+(\Q2201\E)(?:\/(\d+))?$/ break=on-true
  343. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->call forward all] continue=false
  344. Dialplan: verto.rtc/2001 Regex (PASS) [call forward all] ${user_exists}(true) =~ /true/ break=on-false
  345. Dialplan: verto.rtc/2001 Regex (FAIL) [call forward all] ${forward_all_enabled}() =~ /true/ break=on-false
  346. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->call forward not registered] continue=false
  347. Dialplan: verto.rtc/2001 Regex (PASS) [call forward not registered] ${user_exists}(true) =~ /true/ break=on-false
  348. Dialplan: verto.rtc/2001 Regex (FAIL) [call forward not registered] ${forward_user_not_registered_enabled}() =~ /true/ break=on-false
  349. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->follow-me] continue=false
  350. Dialplan: verto.rtc/2001 Regex (FAIL) [follow-me] destination_number(2001) =~ /^\*21$/ break=on-false
  351. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->talking clock date and time] continue=true
  352. Dialplan: verto.rtc/2001 Regex (FAIL) [talking clock date and time] destination_number(2001) =~ /^\*9172$/ break=on-false
  353. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->clear_sip_auto_answer] continue=true
  354. Dialplan: verto.rtc/2001 Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
  355. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->talking clock time] continue=true
  356. Dialplan: verto.rtc/2001 Regex (FAIL) [talking clock time] destination_number(2001) =~ /^\*9170$/ break=on-false
  357. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->talking clock date] continue=true
  358. Dialplan: verto.rtc/2001 Regex (FAIL) [talking clock date] destination_number(2001) =~ /^\*9171$/ break=on-false
  359. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->local_extension] continue=true
  360. Dialplan: verto.rtc/2001 Regex (PASS) [local_extension] ${user_exists}(true) =~ /true/ break=on-false
  361. Dialplan: verto.rtc/2001 Action export(dialed_extension=${destination_number}) INLINE
  362. EXECUTE [depth=0] verto.rtc/2001 export(dialed_extension=2001)
  363. 2020-02-10 20:26:32.498741 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [dialed_extension]=[2001]
  364. Dialplan: verto.rtc/2001 Action limit(hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination})
  365. Dialplan: verto.rtc/2001 Regex (PASS) [local_extension] () =~ // break=on-false
  366. Dialplan: verto.rtc/2001 Action set(hangup_after_bridge=true)
  367. Dialplan: verto.rtc/2001 Action set(continue_on_fail=true)
  368. Dialplan: verto.rtc/2001 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
  369. Dialplan: verto.rtc/2001 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
  370. Dialplan: verto.rtc/2001 Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)})
  371. Dialplan: verto.rtc/2001 Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid})
  372. Dialplan: verto.rtc/2001 Action set(api_hangup_hook=lua app.lua hangup)
  373. Dialplan: verto.rtc/2001 Action export(domain_name=${domain_name})
  374. Dialplan: verto.rtc/2001 Action bridge(user/${destination_number}@${domain_name})
  375. Dialplan: verto.rtc/2001 Action lua(app.lua failure_handler)
  376. Dialplan: verto.rtc/2001 parsing [pbx.performityinctest.com->voicemail] continue=false
  377. Dialplan: verto.rtc/2001 Regex (PASS) [voicemail] ${user_exists}(true) =~ /true/ break=on-false
  378. Dialplan: verto.rtc/2001 Action set(record_append=false)
  379. Dialplan: verto.rtc/2001 Action set(voicemail_action=save)
  380. Dialplan: verto.rtc/2001 Action set(voicemail_id=${destination_number})
  381. Dialplan: verto.rtc/2001 Action set(voicemail_profile=default)
  382. Dialplan: verto.rtc/2001 Action lua(app.lua voicemail)
  383. 2020-02-10 20:26:32.498741 [DEBUG] switch_core_state_machine.c:287 (verto.rtc/2001) State Change CS_ROUTING -> CS_EXECUTE
  384. 2020-02-10 20:26:32.498741 [DEBUG] switch_core_state_machine.c:644 (verto.rtc/2001) State ROUTING going to sleep
  385. 2020-02-10 20:26:32.498741 [DEBUG] switch_core_state_machine.c:585 (verto.rtc/2001) Running State Change CS_EXECUTE (Cur 1 Tot 25)
  386. 2020-02-10 20:26:32.498741 [DEBUG] switch_core_state_machine.c:651 (verto.rtc/2001) State EXECUTE
  387. 2020-02-10 20:26:32.498741 [DEBUG] mod_rtc.c:120 verto.rtc/2001 RTC EXECUTE
  388. 2020-02-10 20:26:32.498741 [DEBUG] switch_core_state_machine.c:329 verto.rtc/2001 Standard EXECUTE
  389. EXECUTE [depth=0] verto.rtc/2001 set(caller_id_number=2201)
  390. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [caller_id_number]=[2201]
  391. EXECUTE [depth=0] verto.rtc/2001 export(origination_callee_id_name=2001)
  392. 2020-02-10 20:26:32.498741 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [origination_callee_id_name]=[2001]
  393. EXECUTE [depth=0] verto.rtc/2001 set(RFC2822_DATE=Mon, 10 Feb 2020 20:26:32 +0000)
  394. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [RFC2822_DATE]=[Mon, 10 Feb 2020 20:26:32 +0000]
  395. EXECUTE [depth=0] verto.rtc/2001 hash(insert/pbx.performityinctest.com-last_dial/2201/2001)
  396. EXECUTE [depth=0] verto.rtc/2001 eval(not_secure)
  397.  
  398. EXECUTE [depth=0] verto.rtc/2001 limit(hash pbx.performityinctest.com 2001 5 error/user_busy)
  399.  
  400. 2020-02-10 20:26:32.498741 [DEBUG] switch_limit.c:124 incr called: pbx.performityinctest.com_2001 max:5, interval:0
  401. 2020-02-10 20:26:32.498741 [DEBUG] mod_hash.c:196 Usage for pbx.performityinctest.com_2001 is now 1/5
  402. EXECUTE [depth=0] verto.rtc/2001 set(hangup_after_bridge=true)
  403. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [hangup_after_bridge]=[true]
  404. EXECUTE [depth=0] verto.rtc/2001 set(continue_on_fail=true)
  405. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [continue_on_fail]=[true]
  406. EXECUTE [depth=0] verto.rtc/2001 hash(insert/pbx.performityinctest.com-call_return/2001/2201)
  407. EXECUTE [depth=0] verto.rtc/2001 hash(insert/pbx.performityinctest.com-last_dial_ext/2001/3d4878b9-8f16-5a58-feee-a300d1e8e84c)
  408. EXECUTE [depth=0] verto.rtc/2001 set(called_party_call_group=)
  409. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [called_party_call_group]=[UNDEF]
  410. EXECUTE [depth=0] verto.rtc/2001 hash(insert/pbx.performityinctest.com-last_dial//3d4878b9-8f16-5a58-feee-a300d1e8e84c)
  411. EXECUTE [depth=0] verto.rtc/2001 set(api_hangup_hook=lua app.lua hangup)
  412. 2020-02-10 20:26:32.498741 [DEBUG] mod_dptools.c:1672 SET verto.rtc/2001 [api_hangup_hook]=[lua app.lua hangup]
  413. EXECUTE [depth=0] verto.rtc/2001 export(domain_name=pbx.performityinctest.com)
  414. 2020-02-10 20:26:32.498741 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [domain_name]=[pbx.performityinctest.com]
  415. EXECUTE [depth=0] verto.rtc/2001 bridge(user/2001@pbx.performityinctest.com)
  416. 2020-02-10 20:26:32.498741 [DEBUG] switch_channel.c:1264 verto.rtc/2001 EXPORTING[export_vars] [domain_name]=[pbx.performityinctest.com] to event
  417. 2020-02-10 20:26:32.498741 [DEBUG] switch_channel.c:1264 verto.rtc/2001 EXPORTING[export_vars] [call_direction]=[local] to event
  418. 2020-02-10 20:26:32.498741 [DEBUG] switch_channel.c:1264 verto.rtc/2001 EXPORTING[export_vars] [dialed_extension]=[2001] to event
  419. 2020-02-10 20:26:32.498741 [DEBUG] switch_channel.c:1264 verto.rtc/2001 EXPORTING[export_vars] [origination_callee_id_name]=[2001] to event
  420. 2020-02-10 20:26:32.498741 [DEBUG] switch_channel.c:1264 verto.rtc/2001 EXPORTING[export_vars] [domain_name]=[pbx.performityinctest.com] to event
  421. 2020-02-10 20:26:32.498741 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables
  422. 2020-02-10 20:26:32.518742 [DEBUG] switch_channel.c:1264 verto.rtc/2001 EXPORTING[export_vars] [domain_name]=[pbx.performityinctest.com] to event
  423. 2020-02-10 20:26:32.518742 [DEBUG] switch_channel.c:1264 verto.rtc/2001 EXPORTING[export_vars] [call_direction]=[local] to event
  424. 2020-02-10 20:26:32.518742 [DEBUG] switch_channel.c:1264 verto.rtc/2001 EXPORTING[export_vars] [dialed_extension]=[2001] to event
  425. 2020-02-10 20:26:32.518742 [DEBUG] switch_channel.c:1264 verto.rtc/2001 EXPORTING[export_vars] [origination_callee_id_name]=[2001] to event
  426. 2020-02-10 20:26:32.518742 [DEBUG] switch_channel.c:1264 verto.rtc/2001 EXPORTING[export_vars] [domain_name]=[pbx.performityinctest.com] to event
  427. 2020-02-10 20:26:32.518742 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables
  428.  
  429. 2020-02-10 20:26:32.518742 [NOTICE] switch_ivr_originate.c:2952 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
  430.  
  431.  *** NOTE: no 'originate failed', and both clients show call connected, just without media ***
  432.  
  433. 2020-02-10 20:26:32.518742 [DEBUG] mod_rtc.c:390 () State Change CS_NEW -> CS_INIT
  434. 2020-02-10 20:26:32.518742 [NOTICE] switch_channel.c:1118 New Channel verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 [5d064648-18e8-4004-b4e6-bdda6cefa96f]
  435.  
  436. 2020-02-10 20:26:32.518742 [DEBUG] switch_ivr_originate.c:3064 verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 Setting leg timeout to 30
  437.  
  438. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_state_machine.c:585 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) Running State Change CS_INIT (Cur 2 Tot 26)
  439. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_state_machine.c:628 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State INIT
  440.  
  441.  
  442.  
  443. ***
  444. 2020-02-10 20:26:32.518742 [CRIT] switch_channel.c:1484 Invalid data (${advertised_media_ip} contains a variable)
  445. 2020-02-10 20:26:32.518742 [CRIT] switch_channel.c:1484 Invalid data (${local_video_ip} contains a variable)
  446. 2020-02-10 20:26:32.518742 [CRIT] switch_channel.c:1484 Invalid data (${rtp_local_sdp_str} contains a variable)
  447. ***
  448.  
  449.  
  450.  
  451. 2020-02-10 20:26:32.518742 [DEBUG] mod_verto.c:2272 Local verto.invite SDP verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2:
  452. v=0
  453. o=FreeSWITCH 1581346272 1581346273 IN IP4 ${external_rtp_ip}
  454. s=FreeSWITCH
  455. c=IN IP4 ${external_rtp_ip}
  456. t=0 0
  457. a=msid-semantic: WMS GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q
  458. m=audio 20120 RTP/SAVPF 102 9 0 8 103
  459. a=rtpmap:102 opus/48000/2
  460. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  461. a=rtpmap:9 G722/8000
  462. a=rtpmap:0 PCMU/8000
  463. a=rtpmap:8 PCMA/8000
  464. a=rtpmap:103 opus/48000/2
  465. a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  466. a=fingerprint:sha-256 96:E9:38:23:96:4C:07:4E:61:4F:80:B7:05:E6:BE:BA:04:CA:F3:69:B6:90:E0:43:59:AD:F1:A9:4B:D1:57:C3
  467. a=setup:actpass
  468. a=rtcp-mux
  469. a=rtcp:20120 IN IP4 ${external_rtp_ip}
  470. a=ssrc:1380421024 cname:qTfDHrzty7XiZScD
  471. a=ssrc:1380421024 msid:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q a0
  472. a=ssrc:1380421024 mslabel:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q
  473. a=ssrc:1380421024 label:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Qa0
  474. a=ice-ufrag:lO7nNTWvTVZ3KUK1
  475. a=ice-pwd:EQub7UvwRjFH2Ibdm8r0SdmC
  476. a=candidate:3536908466 1 udp 659136 ${external_rtp_ip} 20120 typ host generation 0
  477. a=candidate:3536908466 2 udp 659136 ${external_rtp_ip} 20120 typ host generation 0
  478. a=silenceSupp:off - - - -
  479. a=ptime:20
  480. a=sendrecv
  481. m=video 20122 RTP/SAVPF 104 105
  482. b=AS:1024
  483. a=rtpmap:104 VP8/90000
  484. a=rtpmap:105 VP9/90000
  485. a=sendrecv
  486. a=fingerprint:sha-256 96:E9:38:23:96:4C:07:4E:61:4F:80:B7:05:E6:BE:BA:04:CA:F3:69:B6:90:E0:43:59:AD:F1:A9:4B:D1:57:C3
  487. a=setup:actpass
  488. a=rtcp-mux
  489. a=rtcp:20122 IN IP4 ${external_rtp_ip}
  490. a=rtcp-fb:104 ccm fir
  491. a=rtcp-fb:104 ccm tmmbr
  492. a=rtcp-fb:104 nack
  493. a=rtcp-fb:104 nack pli
  494. a=rtcp-fb:105 ccm fir
  495. a=rtcp-fb:105 ccm tmmbr
  496. a=rtcp-fb:105 nack
  497. a=rtcp-fb:105 nack pli
  498. a=ssrc:589759212 cname:qTfDHrzty7XiZScD
  499. a=ssrc:589759212 msid:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q v0
  500. a=ssrc:589759212 mslabel:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q
  501. a=ssrc:589759212 label:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Qv0
  502. a=ice-ufrag:nOqJsoNhRoYS1I6C
  503. a=ice-pwd:KbWQrJLKQ7PUJaBXPSw76afN
  504. a=candidate:7693262838 1 udp 659136 ${external_rtp_ip} 20122 typ host generation 0
  505. a=candidate:7693262838 2 udp 659135 ${external_rtp_ip} 20122 typ host generation 0
  506. a=end-of-candidates
  507.  
  508. 2020-02-10 20:26:32.518742 [NOTICE] mod_verto.c:2372 Ring-Ready verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2!
  509. 2020-02-10 20:26:32.518742 [DEBUG] switch_channel.c:3437 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) Callstate Change DOWN -> RINGING
  510. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_state_machine.c:40 verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 Standard INIT
  511. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State Change CS_INIT -> CS_ROUTING
  512. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_state_machine.c:628 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State INIT going to sleep
  513. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_state_machine.c:585 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) Running State Change CS_ROUTING (Cur 2 Tot 26)
  514. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_state_machine.c:644 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State ROUTING
  515. 2020-02-10 20:26:32.518742 [DEBUG] mod_rtc.c:89 verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 RTC ROUTING
  516. 2020-02-10 20:26:32.518742 [DEBUG] switch_ivr_originate.c:67 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  517. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_state_machine.c:644 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State ROUTING going to sleep
  518. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_state_machine.c:585 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 26)
  519. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [opus:111:48000:20:0:2]/[opus:116:48000:20:0:1]
  520. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  521. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[opus:116:48000:20:0:1]
  522. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[opus:116:48000:20:0:1]
  523. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  524. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  525. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  526. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [CN:106:32000:20:0:1]/[opus:116:48000:20:0:1]
  527. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [CN:105:16000:20:0:1]/[opus:116:48000:20:0:1]
  528. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [CN:13:8000:20:0:1]/[opus:116:48000:20:0:1]
  529. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5506 Set telephone-event payload to 110@48000
  530. 2020-02-10 20:26:32.518742 [DEBUG] mod_opus.c:613 Opus encoder: set bitrate to local settings [120000bps]
  531. 2020-02-10 20:26:32.518742 [DEBUG] mod_opus.c:613 Opus encoder: set bitrate to local settings [120000bps]
  532. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:3834 Set Codec verto.rtc/2001 opus/48000 20 ms 960 samples 0 bits 2 channels
  533. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_codec.c:111 verto.rtc/2001 Original read codec set to opus:116
  534. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:4279 Save audio Candidate cid: 1 proto: udp type: host addr: 10.0.0.13:54872
  535. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:4279 Save audio Candidate cid: 1 proto: udp type: srflx addr: 75.69.229.242:54872
  536. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_state_machine.c:663 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State CONSUME_MEDIA
  537. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_state_machine.c:663 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State CONSUME_MEDIA going to sleep
  538. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:4324 Searching for rtp candidate.
  539. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:4333 Choose rtp candidate, index 1, 75.69.229.242:54872
  540. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:4099 verto.rtc/2001 choosing family v4
  541. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:4344 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 75.69.229.242:54872
  542. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:4396 setting remote audio ice addr to index 1 75.69.229.242:54872 based on candidate
  543. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:4431 Setting remote rtcp audio addr to 75.69.229.242:54872 based on candidate
  544. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5849 Set telephone-event payload to 110@48000
  545. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:5907 verto.rtc/2001 Set 2833 dtmf send payload to 110 recv payload to 110
  546. 2020-02-10 20:26:32.518742 [CRIT] switch_channel.c:1484 Invalid data (${advertised_media_ip} contains a variable)
  547. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:8658 AUDIO RTP [verto.rtc/2001] 172.20.253.46 port 31314 -> 75.69.229.242 port 54872 codec: 111 ms: 20
  548. 2020-02-10 20:26:32.518742 [CRIT] switch_channel.c:1484 Invalid data (${advertised_media_ip} contains a variable)
  549. 2020-02-10 20:26:32.518742 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 960 bytes per 20ms
  550. 2020-02-10 20:26:32.518742 [INFO] switch_core_media.c:8840 Activating Audio ICE
  551. 2020-02-10 20:26:32.518742 [NOTICE] switch_rtp.c:4910 Activating RTP audio ICE: 7EfF:euKNmM0JJcZQhDh2 75.69.229.242:54872
  552. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:8880 Activating RTCP PORT 54872
  553. 2020-02-10 20:26:32.518742 [DEBUG] switch_rtp.c:4806 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 54872
  554. 2020-02-10 20:26:32.518742 [INFO] switch_core_media.c:8891 Skipping RTCP ICE (Same as RTP)
  555. 2020-02-10 20:26:32.518742 [INFO] switch_rtp.c:3736 Activate RTP/RTCP audio DTLS client
  556. 2020-02-10 20:26:32.518742 [INFO] switch_rtp.c:3903 Changing audio DTLS state from OFF to HANDSHAKE
  557. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:2549 Setting Jitterbuffer to 20ms (1 frames) (50 max frames)
  558. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:8972 verto.rtc/2001 Set 2833 dtmf send payload to 110
  559. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:8979 verto.rtc/2001 Set 2833 dtmf receive payload to 110
  560. 2020-02-10 20:26:32.518742 [CRIT] switch_channel.c:1484 Invalid data (${rtp_local_sdp_str} contains a variable)
  561. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_media.c:8640 Audio params are unchanged for verto.rtc/2001.
  562. 2020-02-10 20:26:32.518742 [DEBUG] mod_verto.c:2519 Local SDP verto.rtc/2001:
  563. v=0
  564. o=FreeSWITCH 1581335078 1581335079 IN IP4 ${external_rtp_ip}
  565. s=FreeSWITCH
  566. c=IN IP4 ${external_rtp_ip}
  567. t=0 0
  568. a=msid-semantic: WMS tRdhIVXwVqToQK858NgKI2bmx7mpheOA
  569. m=audio 31314 UDP/TLS/RTP/SAVPF 111 110
  570. a=rtpmap:111 opus/48000/2
  571. a=fmtp:111 useinbandfec=1; minptime=10; stereo=1; sprop-stereo=1
  572. a=rtpmap:110 telephone-event/48000
  573. a=silenceSupp:off - - - -
  574. a=ptime:20
  575. a=sendrecv
  576. a=fingerprint:sha-256 96:E9:38:23:96:4C:07:4E:61:4F:80:B7:05:E6:BE:BA:04:CA:F3:69:B6:90:E0:43:59:AD:F1:A9:4B:D1:57:C3
  577. a=setup:active
  578. a=rtcp-mux
  579. a=rtcp:31314 IN IP4 ${external_rtp_ip}
  580. a=ice-ufrag:euKNmM0JJcZQhDh2
  581. a=ice-pwd:mPmPb34Nuw6vYkv3E5HRHxqh
  582. a=candidate:4653287215 1 udp 659136 ${external_rtp_ip} 31314 typ host generation 0
  583. a=end-of-candidates
  584. a=ssrc:1112046320 cname:VSIuDvRoMACsbBna
  585. a=ssrc:1112046320 msid:tRdhIVXwVqToQK858NgKI2bmx7mpheOA a0
  586. a=ssrc:1112046320 mslabel:tRdhIVXwVqToQK858NgKI2bmx7mpheOA
  587. a=ssrc:1112046320 label:tRdhIVXwVqToQK858NgKI2bmx7mpheOAa0
  588.  
  589. 2020-02-10 20:26:32.518742 [NOTICE] switch_ivr_originate.c:1246 Pre-Answer verto.rtc/2001!
  590. 2020-02-10 20:26:32.518742 [DEBUG] switch_channel.c:3565 (verto.rtc/2001) Callstate Change RINGING -> EARLY
  591. 2020-02-10 20:26:32.518742 [DEBUG] switch_ivr_originate.c:1304 Raw Codec Activation Success L16@48000hz 2 channel 20ms
  592. 2020-02-10 20:26:32.518742 [DEBUG] switch_core_codec.c:223 verto.rtc/2001 Push codec L16:100
  593. 2020-02-10 20:26:32.518742 [DEBUG] switch_ivr_originate.c:1378 Play Ringback Tone [%(2000,4000,440,480)]
  594. 2020-02-10 20:26:32.538739 [DEBUG] mod_verto.c:607 WRITE 75.69.229.242:55707 [{
  595.         "jsonrpc":      "2.0",
  596.         "id":   39,
  597.         "method":       "verto.media",
  598.         "params":       {
  599.                 "callID":       "3d4878b9-8f16-5a58-feee-a300d1e8e84c",
  600.                 "sdp":  "v=0\r\no=FreeSWITCH 1581335078 1581335079 IN IP4 ${external_rtp_ip}\r\ns=FreeSWITCH\r\nc=IN IP4 ${external_rtp_ip}\r\nt=0 0\r\na=msid-semantic: WMS tRdhIVXwVqToQK858NgKI2bmx7mpheOA\r\nm=audio 31314 UDP/TLS/RTP/SAVPF 111 110\r\na=rtpmap:111 opus/48000/2\r\na=fmtp:111 useinbandfec=1; minptime=10; stereo=1; sprop-stereo=1\r\na=rtpmap:110 telephone-event/48000\r\na=silenceSupp:off - - - -\r\na=ptime:20\r\na=sendrecv\r\na=fingerprint:sha-256 96:E9:38:23:96:4C:07:4E:61:4F:80:B7:05:E6:BE:BA:04:CA:F3:69:B6:90:E0:43:59:AD:F1:A9:4B:D1:57:C3\r\na=setup:active\r\na=rtcp-mux\r\na=rtcp:31314 IN IP4 ${external_rtp_ip}\r\na=ice-ufrag:euKNmM0JJcZQhDh2\r\na=ice-pwd:mPmPb34Nuw6vYkv3E5HRHxqh\r\na=candidate:4653287215 1 udp 659136 ${external_rtp_ip} 31314 typ host generation 0\r\na=end-of-candidates\r\na=ssrc:1112046320 cname:VSIuDvRoMACsbBna\r\na=ssrc:1112046320 msid:tRdhIVXwVqToQK858NgKI2bmx7mpheOA a0\r\na=ssrc:1112046320 mslabel:tRdhIVXwVqToQK858NgKI2bmx7mpheOA\r\na=ssrc:1112046320 label:tRdhIVXwVqToQK858NgKI2bmx7mpheOAa0\r\n"
  601.         }
  602. }]
  603. 2020-02-10 20:26:32.538739 [DEBUG] mod_verto.c:607 WRITE 47.208.143.73:53106 [{
  604.         "jsonrpc":      "2.0",
  605.         "id":   38,
  606.         "method":       "verto.invite",
  607.         "params":       {
  608.                 "callID":       "5d064648-18e8-4004-b4e6-bdda6cefa96f",
  609.                 "sdp":  "v=0\r\no=FreeSWITCH 1581346272 1581346273 IN IP4 ${external_rtp_ip}\r\ns=FreeSWITCH\r\nc=IN IP4 ${external_rtp_ip}\r\nt=0 0\r\na=msid-semantic: WMS GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q\r\nm=audio 20120 RTP/SAVPF 102 9 0 8 103\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:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/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 96:E9:38:23:96:4C:07:4E:61:4F:80:B7:05:E6:BE:BA:04:CA:F3:69:B6:90:E0:43:59:AD:F1:A9:4B:D1:57:C3\r\na=setup:actpass\r\na=rtcp-mux\r\na=rtcp:20120 IN IP4 ${external_rtp_ip}\r\na=ssrc:1380421024 cname:qTfDHrzty7XiZScD\r\na=ssrc:1380421024 msid:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q a0\r\na=ssrc:1380421024 mslabel:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q\r\na=ssrc:1380421024 label:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Qa0\r\na=ice-ufrag:lO7nNTWvTVZ3KUK1\r\na=ice-pwd:EQub7UvwRjFH2Ibdm8r0SdmC\r\na=candidate:3536908466 1 udp 659136 ${external_rtp_ip} 20120 typ host generation 0\r\na=candidate:3536908466 2 udp 659136 ${external_rtp_ip} 20120 typ host generation 0\r\na=silenceSupp:off - - - -\r\na=ptime:20\r\na=sendrecv\r\nm=video 20122 RTP/SAVPF 104 105\r\nb=AS:1024\r\na=rtpmap:104 VP8/90000\r\na=rtpmap:105 VP9/90000\r\na=sendrecv\r\na=fingerprint:sha-256 96:E9:38:23:96:4C:07:4E:61:4F:80:B7:05:E6:BE:BA:04:CA:F3:69:B6:90:E0:43:59:AD:F1:A9:4B:D1:57:C3\r\na=setup:actpass\r\na=rtcp-mux\r\na=rtcp:20122 IN IP4 ${external_rtp_ip}\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=rtcp-fb:105 ccm fir\r\na=rtcp-fb:105 ccm tmmbr\r\na=rtcp-fb:105 nack\r\na=rtcp-fb:105 nack pli\r\na=ssrc:589759212 cname:qTfDHrzty7XiZScD\r\na=ssrc:589759212 msid:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q v0\r\na=ssrc:589759212 mslabel:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q\r\na=ssrc:589759212 label:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Qv0\r\na=ice-ufrag:nOqJsoNhRoYS1I6C\r\na=ice-pwd:KbWQrJLKQ7PUJaBXPSw76afN\r\na=candidate:7693262838 1 udp 659136 ${external_rtp_ip} 20122 typ host generation 0\r\na=candidate:7693262838 2 udp 659135 ${external_rtp_ip} 20122 typ host generation 0\r\na=end-of-candidates\r\n",
  610.                 "caller_id_name":       "Bennett Malbon",
  611.                 "caller_id_number":     "bennett.malbon@gmail.com",
  612.                 "callee_id_name":       "River Hume",
  613.                 "callee_id_number":     "2001",
  614.                 "display_direction":    "outbound"
  615.         }
  616. }]
  617. 2020-02-10 20:26:32.638740 [DEBUG] mod_verto.c:1415 READ 47.208.143.73:53106 [{
  618.         "jsonrpc":      "2.0",
  619.         "id":   38,
  620.         "result":       {
  621.                 "method":       "verto.invite"
  622.         }
  623. }]
  624. 2020-02-10 20:26:32.638740 [DEBUG] mod_verto.c:1415 READ 75.69.229.242:55707 [{
  625.         "jsonrpc":      "2.0",
  626.         "id":   39,
  627.         "result":       {
  628.                 "method":       "verto.media"
  629.         }
  630. }]
  631. 2020-02-10 20:26:35.298739 [DEBUG] mod_verto.c:1415 READ 47.208.143.73:53106 [{
  632.         "jsonrpc":      "2.0",
  633.         "method":       "verto.answer",
  634.         "params":       {
  635.                 "sdp":  "v=0\r\no=- 2086842449089840571 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=msid-semantic: WMS L0aEOuZ3LaFUV6AIRKXnRO3LiVXme4Lo9e2v\r\nm=audio 61518 RTP/SAVPF 102 9 0 8\r\nc=IN IP4 47.208.143.73\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:544163749 1 udp 2122260223 192.168.203.1 61517 typ host generation 0 network-id 1\r\na=candidate:1817745826 1 udp 2122194687 192.168.11.4 61518 typ host generation 0 network-id 2 network-cost 10\r\na=candidate:1861008213 1 tcp 1518280447 192.168.203.1 9 typ host tcptype active generation 0 network-id 1\r\na=candidate:584803666 1 tcp 1518214911 192.168.11.4 9 typ host tcptype active generation 0 network-id 2 network-cost 10\r\na=candidate:2588467318 1 udp 1685987071 47.208.143.73 61518 typ srflx raddr 192.168.11.4 rport 61518 generation 0 network-id 2 network-cost 10\r\na=ice-ufrag:H55r\r\na=ice-pwd:rzNXN8nz6FBo5Nf3QZz32aNL\r\na=ice-options:trickle\r\na=fingerprint:sha-256 5A:28:05:08:10:7A:E4:87:34:EF:3B:42:64:2E:D4:FC:F4:AB:95:2C:76:3B:8B:F1:E7:02:45:44:15:C7:33:2D\r\na=setup:active\r\na=mid:audio\r\na=sendrecv\r\na=rtcp-mux\r\na=rtpmap:102 opus/48000/2\r\na=fmtp:102 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=ssrc:2328629703 cname:TpichWJD8vJa7eUl\r\na=ssrc:2328629703 msid:L0aEOuZ3LaFUV6AIRKXnRO3LiVXme4Lo9e2v b89f0657-9a2d-4e86-b094-a52701603622\r\na=ssrc:2328629703 mslabel:L0aEOuZ3LaFUV6AIRKXnRO3LiVXme4Lo9e2v\r\na=ssrc:2328629703 label:b89f0657-9a2d-4e86-b094-a52701603622\r\nm=video 61520 RTP/SAVPF 104 105\r\nc=IN IP4 47.208.143.73\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:544163749 1 udp 2122260223 192.168.203.1 61519 typ host generation 0 network-id 1\r\na=candidate:1817745826 1 udp 2122194687 192.168.11.4 61520 typ host generation 0 network-id 2 network-cost 10\r\na=candidate:1861008213 1 tcp 1518280447 192.168.203.1 9 typ host tcptype active generation 0 network-id 1\r\na=candidate:584803666 1 tcp 1518214911 192.168.11.4 9 typ host tcptype active generation 0 network-id 2 network-cost 10\r\na=candidate:2588467318 1 udp 1685987071 47.208.143.73 61520 typ srflx raddr 192.168.11.4 rport 61520 generation 0 network-id 2 network-cost 10\r\na=ice-ufrag:/xeC\r\na=ice-pwd:w2ArNzyEcxKMPQ5YpFb7IGBO\r\na=ice-options:trickle\r\na=fingerprint:sha-256 5A:28:05:08:10:7A:E4:87:34:EF:3B:42:64:2E:D4:FC:F4:AB:95:2C:76:3B:8B:F1:E7:02:45:44:15:C7:33:2D\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\na=rtpmap:105 VP9/90000\r\na=rtcp-fb:105 ccm fir\r\na=rtcp-fb:105 nack\r\na=rtcp-fb:105 nack pli\r\na=fmtp:105 profile-id=0\r\n",
  636.                 "dialogParams": {
  637.                         "useStereo":    true,
  638.                         "screenShare":  false,
  639.                         "useCamera":    "b17ca3d0e160db0e8ba5bcdc4d2888451133c8e3ab8cb486618b7a0fb11fed25",
  640.                         "useMic":       "default",
  641.                         "useSpeak":     "default",
  642.                         "tag":  "webcam",
  643.                         "localTag":     null,
  644.                         "login":        "2001@pbx.performityinctest.com",
  645.                         "videoParams":  {
  646.                                 "minWidth":     1280,
  647.                                 "minHeight":    720,
  648.                                 "maxWidth":     1280,
  649.                                 "maxHeight":    720,
  650.                                 "minFrameRate": 15
  651.                         },
  652.                         "useStream":    null,
  653.                         "callID":       "5d064648-18e8-4004-b4e6-bdda6cefa96f",
  654.                         "caller_id_name":       "Bennett Malbon",
  655.                         "caller_id_number":     "bennett.malbon@gmail.com",
  656.                         "callee_id_name":       "River Hume",
  657.                         "callee_id_number":     "2001",
  658.                         "display_direction":    "outbound",
  659.                         "wantVideo":    true,
  660.                         "remote_caller_id_name":        "Bennett Malbon",
  661.                         "remote_caller_id_number":      "bennett.malbon@gmail.com"
  662.                 },
  663.                 "sessid":       "7374663b-9d73-f499-a2a6-05d579db6fa2"
  664.         },
  665.         "id":   36
  666. }]
  667. 2020-02-10 20:26:35.298739 [DEBUG] mod_verto.c:2753 Remote SDP verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2:
  668. v=0
  669. o=- 2086842449089840571 2 IN IP4 127.0.0.1
  670. s=-
  671. t=0 0
  672. a=msid-semantic: WMS L0aEOuZ3LaFUV6AIRKXnRO3LiVXme4Lo9e2v
  673. m=audio 61518 RTP/SAVPF 102 9 0 8
  674. c=IN IP4 47.208.143.73
  675. a=rtcp:9 IN IP4 0.0.0.0
  676. a=candidate:544163749 1 udp 2122260223 192.168.203.1 61517 typ host generation 0 network-id 1
  677. a=candidate:1817745826 1 udp 2122194687 192.168.11.4 61518 typ host generation 0 network-id 2 network-cost 10
  678. a=candidate:1861008213 1 tcp 1518280447 192.168.203.1 9 typ host tcptype active generation 0 network-id 1
  679. a=candidate:584803666 1 tcp 1518214911 192.168.11.4 9 typ host tcptype active generation 0 network-id 2 network-cost 10
  680. a=candidate:2588467318 1 udp 1685987071 47.208.143.73 61518 typ srflx raddr 192.168.11.4 rport 61518 generation 0 network-id 2 network-cost 10
  681. a=ice-ufrag:H55r
  682. a=ice-pwd:rzNXN8nz6FBo5Nf3QZz32aNL
  683. a=ice-options:trickle
  684. a=fingerprint:sha-256 5A:28:05:08:10:7A:E4:87:34:EF:3B:42:64:2E:D4:FC:F4:AB:95:2C:76:3B:8B:F1:E7:02:45:44:15:C7:33:2D
  685. a=setup:active
  686. a=mid:audio
  687. a=sendrecv
  688. a=rtcp-mux
  689. a=rtpmap:102 opus/48000/2
  690. a=fmtp:102 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1
  691. a=rtpmap:9 G722/8000
  692. a=rtpmap:0 PCMU/8000
  693. a=rtpmap:8 PCMA/8000
  694. a=ssrc:2328629703 cname:TpichWJD8vJa7eUl
  695. a=ssrc:2328629703 msid:L0aEOuZ3LaFUV6AIRKXnRO3LiVXme4Lo9e2v b89f0657-9a2d-4e86-b094-a52701603622
  696. a=ssrc:2328629703 mslabel:L0aEOuZ3LaFUV6AIRKXnRO3LiVXme4Lo9e2v
  697. a=ssrc:2328629703 label:b89f0657-9a2d-4e86-b094-a52701603622
  698. m=video 61520 RTP/SAVPF 104 105
  699. c=IN IP4 47.208.143.73
  700. a=rtcp:9 IN IP4 0.0.0.0
  701. a=candidate:544163749 1 udp 2122260223 192.168.203.1 61519 typ host generation 0 network-id 1
  702. a=candidate:1817745826 1 udp 2122194687 192.168.11.4 61520 typ host generation 0 network-id 2 network-cost 10
  703. a=candidate:1861008213 1 tcp 1518280447 192.168.203.1 9 typ host tcptype active generation 0 network-id 1
  704. a=candidate:584803666 1 tcp 1518214911 192.168.11.4 9 typ host tcptype active generation 0 network-id 2 network-cost 10
  705. a=candidate:2588467318 1 udp 1685987071 47.208.143.73 61520 typ srflx raddr 192.168.11.4 rport 61520 generation 0 network-id 2 network-cost 10
  706. a=ice-ufrag:/xeC
  707. a=ice-pwd:w2ArNzyEcxKMPQ5YpFb7IGBO
  708. a=ice-options:trickle
  709. a=fingerprint:sha-256 5A:28:05:08:10:7A:E4:87:34:EF:3B:42:64:2E:D4:FC:F4:AB:95:2C:76:3B:8B:F1:E7:02:45:44:15:C7:33:2D
  710. a=setup:active
  711. a=mid:video
  712. a=recvonly
  713. a=rtcp-mux
  714. a=rtpmap:104 VP8/90000
  715. a=rtcp-fb:104 ccm fir
  716. a=rtcp-fb:104 nack
  717. a=rtcp-fb:104 nack pli
  718. a=rtpmap:105 VP9/90000
  719. a=rtcp-fb:105 ccm fir
  720. a=rtcp-fb:105 nack
  721. a=rtcp-fb:105 nack pli
  722. a=fmtp:105 profile-id=0
  723.  
  724. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [opus:102:48000:20:0:2]/[opus:116:48000:20:0:2]
  725. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match
  726. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [opus:102:48000:20:0:2]/[G722:9:8000:20:64000:1]
  727. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [opus:102:48000:20:0:2]/[PCMU:0:8000:20:64000:1]
  728. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [opus:102:48000:20:0:2]/[PCMA:8:8000:20:64000:1]
  729. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [opus:102:48000:20:0:2]/[opus:116:48000:20:0:1]
  730. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  731. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:2]
  732. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  733. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  734. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  735. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  736. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  737. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:2]
  738. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  739. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  740. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  741. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  742. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  743. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:2]
  744. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  745. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  746. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  747. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  748. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  749. 2020-02-10 20:26:35.298739 [DEBUG] mod_opus.c:613 Opus encoder: set bitrate to local settings [120000bps]
  750. 2020-02-10 20:26:35.298739 [DEBUG] mod_opus.c:613 Opus encoder: set bitrate to local settings [120000bps]
  751. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:3834 Set Codec verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 opus/48000 20 ms 960 samples 0 bits 2 channels
  752. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_codec.c:111 verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 Original read codec set to opus:116
  753. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4279 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.203.1:61517
  754. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4279 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.11.4:61518
  755. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4279 Save audio Candidate cid: 1 proto: udp type: srflx addr: 47.208.143.73:61518
  756. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4324 Searching for rtp candidate.
  757. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4333 Choose rtp candidate, index 2, 47.208.143.73:61518
  758. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4099 verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 choosing family v4
  759. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4344 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 47.208.143.73:61518
  760. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4396 setting remote audio ice addr to index 2 47.208.143.73:61518 based on candidate
  761. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4431 Setting remote rtcp audio addr to 47.208.143.73:61518 based on candidate
  762. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:5914 No 2833 in SDP.  Disable 2833 dtmf and switch to INFO
  763. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:6191 Video Codec Compare [VP8:104]/[VP8:99]
  764. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:6223 Video Codec Compare [VP8:104] +++ is saved as a match
  765. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:6191 Video Codec Compare [VP8:104]/[VP9:99]
  766. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:6191 Video Codec Compare [VP9:105]/[VP8:99]
  767. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:6191 Video Codec Compare [VP9:105]/[VP9:99]
  768. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:6223 Video Codec Compare [VP9:105] +++ is saved as a match
  769. 2020-02-10 20:26:35.298739 [DEBUG] switch_vpx.c:703 VPX VER:v1.7.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:8
  770. 2020-02-10 20:26:35.298739 [DEBUG] switch_vpx.c:703 VPX VER:v1.7.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:8
  771. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:3644 Set VIDEO Codec verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 VP8/90000 0 ms
  772. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4279 Save video Candidate cid: 1 proto: udp type: host addr: 192.168.203.1:61519
  773. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4279 Save video Candidate cid: 1 proto: udp type: host addr: 192.168.11.4:61520
  774. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4279 Save video Candidate cid: 1 proto: udp type: srflx addr: 47.208.143.73:61520
  775. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4324 Searching for rtp candidate.
  776. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4333 Choose rtp candidate, index 2, 47.208.143.73:61520
  777. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4099 verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 choosing family v4
  778. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4344 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 47.208.143.73:61520
  779. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4396 setting remote video ice addr to index 2 47.208.143.73:61520 based on candidate
  780. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:4431 Setting remote rtcp video addr to 47.208.143.73:61520 based on candidate
  781. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:8658 AUDIO RTP [verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2] 172.20.253.46 port 20120 -> 47.208.143.73 port 61518 codec: 102 ms: 20
  782. 2020-02-10 20:26:35.298739 [CRIT] switch_channel.c:1484 Invalid data (${advertised_media_ip} contains a variable)
  783. 2020-02-10 20:26:35.298739 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 960 bytes per 20ms
  784. 2020-02-10 20:26:35.298739 [INFO] switch_core_media.c:8840 Activating Audio ICE
  785. 2020-02-10 20:26:35.298739 [NOTICE] switch_rtp.c:4910 Activating RTP audio ICE: H55r:lO7nNTWvTVZ3KUK1 47.208.143.73:61518
  786. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:8880 Activating RTCP PORT 61518
  787. 2020-02-10 20:26:35.298739 [DEBUG] switch_rtp.c:4806 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 61518
  788. 2020-02-10 20:26:35.298739 [INFO] switch_core_media.c:8891 Skipping RTCP ICE (Same as RTP)
  789. 2020-02-10 20:26:35.298739 [INFO] switch_rtp.c:3736 Activate RTP/RTCP audio DTLS server
  790. 2020-02-10 20:26:35.298739 [INFO] switch_rtp.c:3903 Changing audio DTLS state from OFF to HANDSHAKE
  791. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:2549 Setting Jitterbuffer to 20ms (1 frames) (50 max frames)
  792.  
  793.  
  794. 2020-02-10 20:26:35.298739 [CRIT] switch_channel.c:1484 Invalid data (${local_video_ip} contains a variable)
  795.  
  796.  
  797. 2020-02-10 20:26:35.298739 [DEBUG] switch_rtp.c:4423 Starting video timer.
  798. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:9504 VIDEO RTP [verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2] 172.20.253.46:20122->47.208.143.73:61520 codec: 104 ms: 0 [SUCCESS]
  799. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:7625 verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 Starting Video thread
  800. 2020-02-10 20:26:35.298739 [DEBUG] switch_core_media.c:7491 verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 Video thread started. Echo is off
  801. 2020-02-10 20:26:35.298739 [INFO] switch_core_media.c:9548 Activating Video ICE
  802. 2020-02-10 20:26:35.298739 [NOTICE] switch_rtp.c:4910 Activating RTP video ICE: /xeC:nOqJsoNhRoYS1I6C 47.208.143.73:61520
  803. 2020-02-10 20:26:35.298739 [INFO] switch_core_media.c:9585 Activating VIDEO RTCP PORT 61520 interval 1000 mux 1
  804. 2020-02-10 20:26:35.298739 [DEBUG] switch_rtp.c:4806 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 61520
  805. 2020-02-10 20:26:35.298739 [INFO] switch_core_media.c:9598 Skipping VIDEO RTCP ICE (Same as VIDEO RTP)
  806. 2020-02-10 20:26:35.298739 [INFO] switch_rtp.c:3736 Activate RTP/RTCP video DTLS server
  807. 2020-02-10 20:26:35.318744 [INFO] switch_rtp.c:3903 Changing video DTLS state from OFF to HANDSHAKE
  808. 2020-02-10 20:26:35.318744 [DEBUG] switch_core_media.c:8640 Audio params are unchanged for verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2.
  809. 2020-02-10 20:26:35.318744 [DEBUG] switch_core_media.c:9361 Video params are unchanged for verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2.
  810. 2020-02-10 20:26:35.318744 [NOTICE] mod_verto.c:2787 Channel [verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2] has been answered
  811. 2020-02-10 20:26:35.318744 [DEBUG] switch_channel.c:3865 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) Callstate Change RINGING -> ACTIVE
  812. 2020-02-10 20:26:35.318744 [DEBUG] mod_verto.c:607 WRITE 47.208.143.73:53106 [{
  813.         "jsonrpc":      "2.0",
  814.         "id":   36,
  815.         "result":       {
  816.                 "sessid":       "7374663b-9d73-f499-a2a6-05d579db6fa2"
  817.         }
  818. }]
  819. 2020-02-10 20:26:35.318744 [DEBUG] switch_core_codec.c:248 verto.rtc/2001 Restore previous codec opus:116.
  820. 2020-02-10 20:26:35.338741 [DEBUG] mod_verto.c:607 WRITE 75.69.229.242:55707 [{
  821.         "jsonrpc":      "2.0",
  822.         "id":   40,
  823.         "method":       "verto.answer",
  824.         "params":       {
  825.                 "callID":       "3d4878b9-8f16-5a58-feee-a300d1e8e84c"
  826.         }
  827. }]
  828. 2020-02-10 20:26:35.418737 [INFO] switch_vpx.c:564 config: vp8
  829. 2020-02-10 20:26:35.418737 [NOTICE] switch_vpx.c:599 VPX encoder reset (WxH/BW) from 0x0/0 to 352x288/1024
  830. 2020-02-10 20:26:35.478737 [DEBUG] mod_verto.c:1415 READ 75.69.229.242:55707 [{
  831.         "jsonrpc":      "2.0",
  832.         "id":   40,
  833.         "result":       {
  834.                 "method":       "verto.answer"
  835.         }
  836. }]
  837. 2020-02-10 20:26:42.998738 [DEBUG] mod_verto.c:1415 READ 47.208.143.73:53106 [{
  838.         "jsonrpc":      "2.0",
  839.         "method":       "verto.bye",
  840.         "params":       {
  841.                 "dialogParams": {
  842.                         "useStereo":    true,
  843.                         "screenShare":  false,
  844.                         "useCamera":    "b17ca3d0e160db0e8ba5bcdc4d2888451133c8e3ab8cb486618b7a0fb11fed25",
  845.                         "useMic":       "default",
  846.                         "useSpeak":     "default",
  847.                         "tag":  "webcam",
  848.                         "localTag":     null,
  849.                         "login":        "2001@pbx.performityinctest.com",
  850.                         "videoParams":  {
  851.                                 "minWidth":     1280,
  852.                                 "minHeight":    720,
  853.                                 "maxWidth":     1280,
  854.                                 "maxHeight":    720,
  855.                                 "minFrameRate": 15
  856.                         },
  857.                         "useStream":    null,
  858.                         "callID":       "5d064648-18e8-4004-b4e6-bdda6cefa96f",
  859.                         "caller_id_name":       "Bennett Malbon",
  860.                         "caller_id_number":     "bennett.malbon@gmail.com",
  861.                         "callee_id_name":       "River Hume",
  862.                         "callee_id_number":     "2001",
  863.                         "display_direction":    "outbound",
  864.                         "wantVideo":    true,
  865.                         "remote_caller_id_name":        "Bennett Malbon",
  866.                         "remote_caller_id_number":      "bennett.malbon@gmail.com"
  867.                 },
  868.                 "sessid":       "7374663b-9d73-f499-a2a6-05d579db6fa2"
  869.         },
  870.         "id":   37
  871. }]
  872. 2020-02-10 20:26:42.998738 [NOTICE] mod_verto.c:2867 Hangup verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 [CS_CONSUME_MEDIA] [NORMAL_CLEARING]
  873. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_state_machine.c:585 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) Running State Change CS_HANGUP (Cur 2 Tot 26)
  874. 2020-02-10 20:26:42.998738 [DEBUG] mod_verto.c:607 WRITE 47.208.143.73:53106 [{
  875.         "jsonrpc":      "2.0",
  876.         "id":   37,
  877.         "result":       {
  878.                 "callID":       "5d064648-18e8-4004-b4e6-bdda6cefa96f",
  879.                 "message":      "CALL ENDED",
  880.                 "causeCode":    16,
  881.                 "cause":        "NORMAL_CLEARING",
  882.                 "sessid":       "7374663b-9d73-f499-a2a6-05d579db6fa2"
  883.         }
  884. }]
  885. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_state_machine.c:848 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) Callstate Change ACTIVE -> HANGUP
  886. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State HANGUP
  887. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_state_machine.c:850 verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 skip receive message [VIDEO_REFRESH_REQ] (channel is hungup already)
  888. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_state_machine.c:60 verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 Standard HANGUP, cause: NORMAL_CLEARING
  889. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State HANGUP going to sleep
  890. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_state_machine.c:620 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State Change CS_HANGUP -> CS_REPORTING
  891. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_state_machine.c:585 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) Running State Change CS_REPORTING (Cur 2 Tot 26)
  892. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_state_machine.c:936 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State REPORTING
  893. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_state_machine.c:174 verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 Standard REPORTING, cause: NORMAL_CLEARING
  894. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_state_machine.c:936 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State REPORTING going to sleep
  895. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_state_machine.c:611 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) State Change CS_REPORTING -> CS_DESTROY
  896. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_session.c:1726 Session 26 (verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2) Locked, Waiting on external entities
  897. 2020-02-10 20:26:42.998738 [DEBUG] switch_core_media.c:7592 verto.rtc/7374663b-9d73-f499-a2a6-05d579db6fa2 Video thread ended
  898.  
  899.  
  900.  
  901.  
  902.  
  903.  
  904.  
  905.  
  906.  
  907.  
  908.  
  909. -------------------------
  910.  
  911. WEB CONSOLE OUTPUT:
  912.  
  913. Dialog 5d064648-18e8-4004-b4e6-bdda6cefa96f: state change from new to ringing
  914. scripts.js:13339 onDialogState: a.verto.dialog {params: {}, verto: a.verto, direction: {}, lastState: {}, state: {}, …}params: {useVideo: undefined, useStereo: true, screenShare: false, useCamera: "b17ca3d0e160db0e8ba5bcdc4d2888451133c8e3ab8cb486618b7a0fb11fed25", useMic: "default", …}verto: a.verto {options: {}, sessid: "7374663b-9d73-f499-a2a6-05d579db6fa2", dialogs: {}, callbacks: {}, eventSUBS: {}, …}direction: {name: "inbound", val: 0}lastState: {name: "hangup", val: 9}state: {name: "destroy", val: 10}callbacks: {onWSLogin: ƒ, onMessage: ƒ, onDialogState: ƒ, onWSClose: ƒ, onEvent: ƒ}answered: trueattach: falsescreenShare: falseuseCamera: "b17ca3d0e160db0e8ba5bcdc4d2888451133c8e3ab8cb486618b7a0fb11fed25"useCameraLabel: undefineduseMic: "default"useMicLabel: undefineduseSpeak: "default"callID: "5d064648-18e8-4004-b4e6-bdda6cefa96f"audioStream: video#webcam.hidertc: a.FSRTC {options: {…}, audioEnabled: true, videoEnabled: true, mediaData: {…}, constraints: {…}, …}cause: "NORMAL_CLEARING"__proto__: Object
  915. scripts.js:11875 Incoming call from: bennett.malbon@gmail.com
  916. scripts.js:11889 Mon Feb 10 2020 12:25:13 GMT-0800 (Pacific Standard Time)
  917. scripts.js:1122 inbound sdp:  v=0
  918. o=FreeSWITCH 1581346272 1581346273 IN IP4 ${external_rtp_ip}
  919. s=FreeSWITCH
  920. c=IN IP4 ${external_rtp_ip}
  921. t=0 0
  922. a=msid-semantic: WMS GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q
  923. m=audio 20120 RTP/SAVPF 102 9 0 8 103
  924. a=rtpmap:102 opus/48000/2
  925. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  926. a=rtpmap:9 G722/8000
  927. a=rtpmap:0 PCMU/8000
  928. a=rtpmap:8 PCMA/8000
  929. a=rtpmap:103 opus/48000/2
  930. a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  931. a=fingerprint:sha-256 96:E9:38:23:96:4C:07:4E:61:4F:80:B7:05:E6:BE:BA:04:CA:F3:69:B6:90:E0:43:59:AD:F1:A9:4B:D1:57:C3
  932. a=setup:actpass
  933. a=rtcp-mux
  934. a=rtcp:20120 IN IP4 ${external_rtp_ip}
  935. a=ssrc:1380421024 cname:qTfDHrzty7XiZScD
  936. a=ssrc:1380421024 msid:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q a0
  937. a=ssrc:1380421024 mslabel:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q
  938. a=ssrc:1380421024 label:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Qa0
  939. a=ice-ufrag:lO7nNTWvTVZ3KUK1
  940. a=ice-pwd:EQub7UvwRjFH2Ibdm8r0SdmC
  941. a=candidate:3536908466 1 udp 659136 ${external_rtp_ip} 20120 typ host generation 0
  942. a=candidate:3536908466 2 udp 659136 ${external_rtp_ip} 20120 typ host generation 0
  943. a=silenceSupp:off - - - -
  944. a=ptime:20
  945. a=sendrecv
  946. m=video 20122 RTP/SAVPF 104 105
  947. b=AS:1024
  948. a=rtpmap:104 VP8/90000
  949. a=rtpmap:105 VP9/90000
  950. a=sendrecv
  951. a=fingerprint:sha-256 96:E9:38:23:96:4C:07:4E:61:4F:80:B7:05:E6:BE:BA:04:CA:F3:69:B6:90:E0:43:59:AD:F1:A9:4B:D1:57:C3
  952. a=setup:actpass
  953. a=rtcp-mux
  954. a=rtcp:20122 IN IP4 ${external_rtp_ip}
  955. a=rtcp-fb:104 ccm fir
  956. a=rtcp-fb:104 ccm tmmbr
  957. a=rtcp-fb:104 nack
  958. a=rtcp-fb:104 nack pli
  959. a=rtcp-fb:105 ccm fir
  960. a=rtcp-fb:105 ccm tmmbr
  961. a=rtcp-fb:105 nack
  962. a=rtcp-fb:105 nack pli
  963. a=ssrc:589759212 cname:qTfDHrzty7XiZScD
  964. a=ssrc:589759212 msid:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q v0
  965. a=ssrc:589759212 mslabel:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q
  966. a=ssrc:589759212 label:GBYymXl6WhHDT244NCLEc9WJS98DkZ4Qv0
  967. a=ice-ufrag:nOqJsoNhRoYS1I6C
  968. a=ice-pwd:KbWQrJLKQ7PUJaBXPSw76afN
  969. a=candidate:7693262838 1 udp 659136 ${external_rtp_ip} 20122 typ host generation 0
  970. a=candidate:7693262838 2 udp 659135 ${external_rtp_ip} 20122 typ host generation 0
  971. a=end-of-candidates
  972.  
  973. scripts.js:1304 Camera Disabled
  974. scripts.js:1163 Audio constraints {googEchoCancellation: true, googNoiseSuppression: true, googHighpassFilter: true, googAutoGainControl: true, googAutoGainControl2: true, …}googEchoCancellation: truegoogNoiseSuppression: truegoogHighpassFilter: truegoogAutoGainControl: truegoogAutoGainControl2: truedeviceId: {deviceId: {}}__proto__: Object
  975.  
  976. scripts.js:1164 Video constraints false
  977.  
  978. scripts.js:1707 Tried to use constraint fallbacks but did not found vendor function `assureMediaInputId` on window scope. Did you forget to import `vendor/media-device-id.js` before Verto?
  979.  
  980.  
  981. v @ scripts.js:1707
  982. z @ scripts.js:1765
  983. a.FSRTC.createAnswer @ scripts.js:1190
  984. a.verto.dialog.answer @ scripts.js:4460
  985. a.answerCall @ scripts.js:11943
  986. (anonymous) @ scripts.js:11891
  987. i @ vendor.js:22848
  988. (anonymous) @ vendor.js:22864
  989. $eval @ vendor.js:24100
  990. $digest @ vendor.js:23916
  991. $apply @ vendor.js:24205
  992. (anonymous) @ vendor.js:31235
  993. dispatch @ vendor.js:4435
  994. q.handle @ vendor.js:4121
  995.  
  996.  
  997. scripts.js:1707 Tried to use constraint fallbacks but did not found vendor function `assureMediaInputId` on window scope. Did you forget to import `vendor/media-device-id.js` before Verto?
  998.  
  999. v @ scripts.js:1707
  1000. z @ scripts.js:1766
  1001. a.FSRTC.createAnswer @ scripts.js:1190
  1002. a.verto.dialog.answer @ scripts.js:4460
  1003. a.answerCall @ scripts.js:11943
  1004. (anonymous) @ scripts.js:11891
  1005. i @ vendor.js:22848
  1006. (anonymous) @ vendor.js:22864
  1007. $eval @ vendor.js:24100
  1008. $digest @ vendor.js:23916
  1009. $apply @ vendor.js:24205
  1010. (anonymous) @ vendor.js:31235
  1011. dispatch @ vendor.js:4435
  1012. q.handle @ vendor.js:4121
  1013. scripts.js:14061 0
  1014. scripts.js:11251 Executing InCallController.
  1015. scripts.js:921 Stream Success
  1016. scripts.js:3958 stream started
  1017. scripts.js:1504 Peer Track RTCTrackEvent {isTrusted: true, transceiver: {}, receiver: RTCRtpReceiver, track: MediaStreamTrack, streams: Array(1), …}
  1018. scripts.js:991 REMOTE STREAM MediaStream {id: "GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q", active: true, onaddtrack: null, onremovetrack: null, onactive: null, …} <video class=​"hide" id=​"webcam" autoplay=​"autoplay" style=​"object-fit:​ inherit;​" src=​"sounds/​bell_ring2.wav">​</video>​
  1019. scripts.js:926 Remote Stream Success
  1020. scripts.js:3965 remote stream started
  1021. scripts.js:1504 Peer Track RTCTrackEvent {isTrusted: true, transceiver: {}, receiver: RTCRtpReceiver, track: MediaStreamTrack, streams: Array(1), …}
  1022. scripts.js:991 REMOTE STREAM MediaStream {id: "GBYymXl6WhHDT244NCLEc9WJS98DkZ4Q", active: true, onaddtrack: null, onremovetrack: null, onactive: null, …} <video class=​"hide" id=​"webcam" autoplay=​"autoplay" style=​"object-fit:​ inherit;​" src=​"sounds/​bell_ring2.wav">​</video>​
  1023. scripts.js:926 Remote Stream Success
  1024. scripts.js:3965 remote stream started
  1025. scripts.js:11385 broadcast time-start incall
  1026. scripts.js:944 ICE Complete
  1027. scripts.js:955 ICE SDP
  1028. scripts.js:3912 RECV answer SDP v=0
  1029. o=- 2086842449089840571 2 IN IP4 127.0.0.1
  1030. s=-
  1031. t=0 0
  1032. a=msid-semantic: WMS L0aEOuZ3LaFUV6AIRKXnRO3LiVXme4Lo9e2v
  1033. m=audio 61518 RTP/SAVPF 102 9 0 8
  1034. c=IN IP4 47.208.143.73
  1035. a=rtcp:9 IN IP4 0.0.0.0
  1036. a=candidate:544163749 1 udp 2122260223 192.168.203.1 61517 typ host generation 0 network-id 1
  1037. a=candidate:1817745826 1 udp 2122194687 192.168.11.4 61518 typ host generation 0 network-id 2 network-cost 10
  1038. a=candidate:1861008213 1 tcp 1518280447 192.168.203.1 9 typ host tcptype active generation 0 network-id 1
  1039. a=candidate:584803666 1 tcp 1518214911 192.168.11.4 9 typ host tcptype active generation 0 network-id 2 network-cost 10
  1040. a=candidate:2588467318 1 udp 1685987071 47.208.143.73 61518 typ srflx raddr 192.168.11.4 rport 61518 generation 0 network-id 2 network-cost 10
  1041. a=ice-ufrag:H55r
  1042. a=ice-pwd:rzNXN8nz6FBo5Nf3QZz32aNL
  1043. a=ice-options:trickle
  1044. a=fingerprint:sha-256 5A:28:05:08:10:7A:E4:87:34:EF:3B:42:64:2E:D4:FC:F4:AB:95:2C:76:3B:8B:F1:E7:02:45:44:15:C7:33:2D
  1045. a=setup:active
  1046. a=mid:audio
  1047. a=sendrecv
  1048. a=rtcp-mux
  1049. a=rtpmap:102 opus/48000/2
  1050. a=fmtp:102 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1
  1051. a=rtpmap:9 G722/8000
  1052. a=rtpmap:0 PCMU/8000
  1053. a=rtpmap:8 PCMA/8000
  1054. a=ssrc:2328629703 cname:TpichWJD8vJa7eUl
  1055. a=ssrc:2328629703 msid:L0aEOuZ3LaFUV6AIRKXnRO3LiVXme4Lo9e2v b89f0657-9a2d-4e86-b094-a52701603622
  1056. a=ssrc:2328629703 mslabel:L0aEOuZ3LaFUV6AIRKXnRO3LiVXme4Lo9e2v
  1057. a=ssrc:2328629703 label:b89f0657-9a2d-4e86-b094-a52701603622
  1058. m=video 61520 RTP/SAVPF 104 105
  1059. c=IN IP4 47.208.143.73
  1060. a=rtcp:9 IN IP4 0.0.0.0
  1061. a=candidate:544163749 1 udp 2122260223 192.168.203.1 61519 typ host generation 0 network-id 1
  1062. a=candidate:1817745826 1 udp 2122194687 192.168.11.4 61520 typ host generation 0 network-id 2 network-cost 10
  1063. a=candidate:1861008213 1 tcp 1518280447 192.168.203.1 9 typ host tcptype active generation 0 network-id 1
  1064. a=candidate:584803666 1 tcp 1518214911 192.168.11.4 9 typ host tcptype active generation 0 network-id 2 network-cost 10
  1065. a=candidate:2588467318 1 udp 1685987071 47.208.143.73 61520 typ srflx raddr 192.168.11.4 rport 61520 generation 0 network-id 2 network-cost 10
  1066. a=ice-ufrag:/xeC
  1067. a=ice-pwd:w2ArNzyEcxKMPQ5YpFb7IGBO
  1068. a=ice-options:trickle
  1069. a=fingerprint:sha-256 5A:28:05:08:10:7A:E4:87:34:EF:3B:42:64:2E:D4:FC:F4:AB:95:2C:76:3B:8B:F1:E7:02:45:44:15:C7:33:2D
  1070. a=setup:active
  1071. a=mid:video
  1072. a=recvonly
  1073. a=rtcp-mux
  1074. a=rtpmap:104 VP8/90000
  1075. a=rtcp-fb:104 ccm fir
  1076. a=rtcp-fb:104 nack
  1077. a=rtcp-fb:104 nack pli
  1078. a=rtpmap:105 VP9/90000
  1079. a=rtcp-fb:105 ccm fir
  1080. a=rtcp-fb:105 nack
  1081. a=rtcp-fb:105 nack pli
  1082. a=fmtp:105 profile-id=0
  1083.  
  1084. scripts.js:4116 Dialog 5d064648-18e8-4004-b4e6-bdda6cefa96f: state change from ringing to answering
  1085. scripts.js:13339 onDialogState: a.verto.dialog {params: {}, verto: a.verto, direction: {}, lastState: {}, state: {}, …}
  1086.  
  1087.  
  1088. *** not implemented state: destroy ??? ***
  1089. scripts.js:13375 Got a not implemented state: a.verto.dialog {params: {}, verto: a.verto, direction: {}, lastState: {}, state: {}, …}params: {useVideo: undefined, useStereo: true, screenShare: false, useCamera: "b17ca3d0e160db0e8ba5bcdc4d2888451133c8e3ab8cb486618b7a0fb11fed25", useMic: "default", …}verto: a.verto {options: {}, sessid: "7374663b-9d73-f499-a2a6-05d579db6fa2", dialogs: {}, callbacks: {}, eventSUBS: {}, …}direction: {name: "inbound", val: 0}lastState: {name: "hangup", val: 9}
  1090.  
  1091. state: name: "destroy"val: 10__proto__: Objectcallbacks: {onWSLogin: ƒ, onMessage: ƒ, onDialogState: ƒ, onWSClose: ƒ, onEvent: ƒ}answered: trueattach: falsescreenShare: falseuseCamera: "b17ca3d0e160db0e8ba5bcdc4d2888451133c8e3ab8cb486618b7a0fb11fed25"useCameraLabel: undefineduseMic: "default"useMicLabel: undefineduseSpeak: "default"callID: "5d064648-18e8-4004-b4e6-bdda6cefa96f"audioStream: video#webcam.hidertc: a.FSRTC {options: {…}, audioEnabled: true, videoEnabled: true, mediaData: {…}, constraints: {…}, …}cause: "NORMAL_CLEARING"__proto__: Object
  1092.  
  1093.  
  1094.  
  1095.  
  1096. onDialogState @ scripts.js:13375
  1097. a.verto.dialog.setState @ scripts.js:4122
  1098. f.onICESDP @ scripts.js:3933
  1099. l @ scripts.js:939
  1100. o @ scripts.js:956
  1101. onICESDP @ scripts.js:1140
  1102. b @ scripts.js:1452
  1103. setTimeout (async)
  1104. o.onicecandidate @ scripts.js:1464
  1105. scripts.js:4116 Dialog 5d064648-18e8-4004-b4e6-bdda6cefa96f: state change from answering to active
  1106. scripts.js:13339 onDialogState: a.verto.dialog {params: {}, verto: a.verto, direction: {}, lastState: {}, state: {}, …}
  1107. scripts.js:13354 Talking to: Bennett Malbon <bennett.malbon@gmail.com>
  1108. scripts.js:4131 Using Speaker:  default
  1109. scripts.js:12812 Setting video size to NaN/null
  1110. scripts.js:4075 Dialog: 5d064648-18e8-4004-b4e6-bdda6cefa96f Setting speaker: <video class=​"hide" id=​"webcam" autoplay=​"autoplay" style=​"object-fit:​ inherit;​" src=​"sounds/​bell_ring2.wav">​</video>​ Default - Speakers (Dell USB Audio) (17e9:436e)
  1111. scripts.js:4079 Dialog: 5d064648-18e8-4004-b4e6-bdda6cefa96f Success, audio output device attached: default
  1112. scripts.js:13659 Attempting to hangup the current call.
  1113. scripts.js:4116 Dialog 5d064648-18e8-4004-b4e6-bdda6cefa96f: state change from active to hangup
  1114. scripts.js:13339 onDialogState: a.verto.dialog {params: {}, verto: a.verto, direction: {}, lastState: {}, state: {}, …}
  1115. scripts.js:13360 Call ended with cause: NORMAL_CLEARING
  1116. scripts.js:4116 Dialog 5d064648-18e8-4004-b4e6-bdda6cefa96f: state change from hangup to destroy
  1117. scripts.js:13339 onDialogState: a.verto.dialog {params: {}, verto: a.verto, direction: {}, lastState: {}, state: {}, …}
  1118. scripts.js:13364 Destroying: NORMAL_CLEARING
  1119. scripts.js:13251 stopConference()
  1120. scripts.js:13258 Doesn't found data.liveArray.
  1121. scripts.js:11772 Redirecting to dialpad page.
  1122. scripts.js:11778 not digest
  1123. scripts.js:1033 Stopping localStream tracks: [MediaStreamTrack]
  1124. scripts.js:1049 stopping peer
  1125. scripts.js:13673 The call was hangup.
  1126. scripts.js:11102 Executing DialPadController.