From ft, 3 Years ago, written in Plain Text.
- go back
Embed
Viewing differences between and reinvideo
  1. +OK log level  [7]
  2. recv 765 bytes from udp/[192.168.1.166]:61762 at 20:43:04.119491:
  3. ------------------------------------------------------------------------
  4. INVITE sip:98765432100@192.168.1.166;transport=UDP SIP/2.0
  5. Via: SIP/2.0/UDP 192.168.1.166:61762;branch=z9hG4bK-524287-1---5534b8e0c41892ea;rport
  6. Max-Forwards: 70
  7. Contact: <sip:1000@192.168.1.166:61762;transport=UDP>
  8. To: <sip:98765432100@192.168.1.166>
  9. From: <sip:1000@192.168.1.166;transport=UDP>;tag=82406d28
  10. Call-ID: H66EMVsAFFGB4DzX2Fk4IA..
  11. CSeq: 1 INVITE
  12. Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  13. Content-Type: application/sdp
  14. User-Agent: Z 5.4.12 v2.10.13.2-mod
  15. Allow-Events: presence, kpml, talk
  16. Content-Length: 188
  17.  
  18. v=0
  19. o=Z 1639600984077 1 IN IP4 192.168.1.166
  20. s=Z
  21. c=IN IP4 192.168.1.166
  22. t=0 0
  23. m=audio 8000 RTP/AVP 106
  24. a=rtpmap:106 opus/48000/2
  25. a=fmtp:106 minptime=20; useinbandfec=1
  26. a=sendrecv
  27. 2021-12-15 20:43:04.107172 96.50% [NOTICE] switch_channel.c:1123 New Channel sofia/internal/1000@192.168.1.166 [38bfa394-ca3e-468f-9a1e-9d048a913edc]
  28. 2021-12-15 20:43:04.107172 96.50% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1000@192.168.1.166) Running State Change CS_NEW (Cur 1 Tot 5)
  29. 2021-12-15 20:43:04.107172 96.50% [INFO] sofia.c:10462 sofia/internal/1000@192.168.1.166 receiving invite from 192.168.1.166:61762 version: 1.10.8-dev git e428be3 2021-11-17 19:23:28Z 64bit call-id: H66EMVsAFFGB4DzX2Fk4IA..
  30. 2021-12-15 20:43:04.107172 96.50% [DEBUG] sofia.c:10556 verifying acl "domains" for ip/port 192.168.1.166:0.
  31. 2021-12-15 20:43:04.107172 96.50% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/1000@192.168.1.166) State NEW
  32. send 868 bytes to udp/[192.168.1.166]:61762 at 20:43:04.120211:
  33. ------------------------------------------------------------------------
  34. SIP/2.0 407 Proxy Authentication Required
  35. Via: SIP/2.0/UDP 192.168.1.166:61762;branch=z9hG4bK-524287-1---5534b8e0c41892ea;rport=61762
  36. From: <sip:1000@192.168.1.166;transport=UDP>;tag=82406d28
  37. To: <sip:98765432100@192.168.1.166>;tag=p3HtQg3cUFBca
  38. Call-ID: H66EMVsAFFGB4DzX2Fk4IA..
  39. CSeq: 1 INVITE
  40. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  41. Accept: application/sdp
  42. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  43. Supported: timer, path, replaces
  44. Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  45. Proxy-Authenticate: Digest realm="192.168.1.166", nonce="ab5b00e1-b42f-47b7-8c6c-659644f6164b", algorithm=MD5, qop="auth"
  46. Content-Length: 0
  47.  
  48. 2021-12-15 20:43:04.107172 96.50% [DEBUG] sofia.c:2419 detaching session 38bfa394-ca3e-468f-9a1e-9d048a913edc
  49. recv 345 bytes from udp/[192.168.1.166]:61762 at 20:43:04.120442:
  50. ------------------------------------------------------------------------
  51. ACK sip:98765432100@192.168.1.166;transport=UDP SIP/2.0
  52. Via: SIP/2.0/UDP 192.168.1.166:61762;branch=z9hG4bK-524287-1---5534b8e0c41892ea;rport
  53. Max-Forwards: 70
  54. To: <sip:98765432100@192.168.1.166>;tag=p3HtQg3cUFBca
  55. From: <sip:1000@192.168.1.166;transport=UDP>;tag=82406d28
  56. Call-ID: H66EMVsAFFGB4DzX2Fk4IA..
  57. CSeq: 1 ACK
  58. Content-Length: 0
  59.  
  60. recv 1048 bytes from udp/[192.168.1.166]:61762 at 20:43:04.120473:
  61. ------------------------------------------------------------------------
  62. INVITE sip:98765432100@192.168.1.166;transport=UDP SIP/2.0
  63. Via: SIP/2.0/UDP 192.168.1.166:61762;branch=z9hG4bK-524287-1---208a27208340423a;rport
  64. Max-Forwards: 70
  65. Contact: <sip:1000@192.168.1.166:61762;transport=UDP>
  66. To: <sip:98765432100@192.168.1.166>
  67. From: <sip:1000@192.168.1.166;transport=UDP>;tag=82406d28
  68. Call-ID: H66EMVsAFFGB4DzX2Fk4IA..
  69. CSeq: 2 INVITE
  70. Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  71. Content-Type: application/sdp
  72. Proxy-Authorization: Digest username="1000",realm="192.168.1.166",nonce="ab5b00e1-b42f-47b7-8c6c-659644f6164b",uri="sip:98765432100@192.168.1.166;transport=UDP",response="b23dca771172fe4901fed36e4423f68a",cnonce="d5b9f3feefdaab8cb51002477c96aade",nc=00000001,qop=auth,algorithm=MD5
  73. User-Agent: Z 5.4.12 v2.10.13.2-mod
  74. Allow-Events: presence, kpml, talk
  75. Content-Length: 188
  76.  
  77. v=0
  78. o=Z 1639600984077 1 IN IP4 192.168.1.166
  79. s=Z
  80. c=IN IP4 192.168.1.166
  81. t=0 0
  82. m=audio 8000 RTP/AVP 106
  83. a=rtpmap:106 opus/48000/2
  84. a=fmtp:106 minptime=20; useinbandfec=1
  85. a=sendrecv
  86. 2021-12-15 20:43:04.107172 96.50% [DEBUG] sofia.c:2532 Re-attaching to session 38bfa394-ca3e-468f-9a1e-9d048a913edc
  87. 2021-12-15 20:43:04.127165 96.50% [INFO] sofia.c:10462 sofia/internal/1000@192.168.1.166 receiving invite from 192.168.1.166:61762 version: 1.10.8-dev git e428be3 2021-11-17 19:23:28Z 64bit call-id: H66EMVsAFFGB4DzX2Fk4IA..
  88. 2021-12-15 20:43:04.127165 96.50% [DEBUG] sofia.c:10556 verifying acl "domains" for ip/port 192.168.1.166:0.
  89. 2021-12-15 20:43:04.127165 96.50% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [received][100]
  90. 2021-12-15 20:43:04.127165 96.50% [DEBUG] sofia.c:7509 Remote SDP:
  91. v=0
  92. o=Z 1639600984077 1 IN IP4 192.168.1.166
  93. s=Z
  94. c=IN IP4 192.168.1.166
  95. t=0 0
  96. m=audio 8000 RTP/AVP 106
  97. a=rtpmap:106 opus/48000/2
  98. a=fmtp:106 minptime=20; useinbandfec=1
  99.  
  100. 2021-12-15 20:43:04.127165 96.50% [DEBUG] sofia.c:7912 (sofia/internal/1000@192.168.1.166) State Change CS_NEW -> CS_INIT
  101. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1000@192.168.1.166) Running State Change CS_INIT (Cur 1 Tot 5)
  102. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/1000@192.168.1.166) State INIT
  103. 2021-12-15 20:43:04.127165 96.50% [DEBUG] mod_sofia.c:97 sofia/internal/1000@192.168.1.166 SOFIA INIT
  104. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:40 sofia/internal/1000@192.168.1.166 Standard INIT
  105. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1000@192.168.1.166) State Change CS_INIT -> CS_ROUTING
  106. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/1000@192.168.1.166) State INIT going to sleep
  107. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1000@192.168.1.166) Running State Change CS_ROUTING (Cur 1 Tot 5)
  108. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_channel.c:2380 (sofia/internal/1000@192.168.1.166) Callstate Change DOWN -> RINGING
  109. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1000@192.168.1.166) State ROUTING
  110. 2021-12-15 20:43:04.127165 96.50% [DEBUG] mod_sofia.c:158 sofia/internal/1000@192.168.1.166 SOFIA ROUTING
  111. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:230 sofia/internal/1000@192.168.1.166 Standard ROUTING
  112. 2021-12-15 20:43:04.127165 96.50% [INFO] mod_dialplan_xml.c:639 Processing 1000 <1000>->98765432100 in context default
  113. send 364 bytes to udp/[192.168.1.166]:61762 at 20:43:04.141376:
  114. ------------------------------------------------------------------------
  115. SIP/2.0 100 Trying
  116. Via: SIP/2.0/UDP 192.168.1.166:61762;branch=z9hG4bK-524287-1---208a27208340423a;rport=61762
  117. From: <sip:1000@192.168.1.166;transport=UDP>;tag=82406d28
  118. To: <sip:98765432100@192.168.1.166>
  119. Call-ID: H66EMVsAFFGB4DzX2Fk4IA..
  120. CSeq: 2 INVITE
  121. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  122. Content-Length: 0
  123.  
  124. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->unloop] continue=false
  125. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  126. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  127. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->tod_example] continue=true
  128. Dialplan: sofia/internal/1000@192.168.1.166 Date/TimeMatch (FAIL) [tod_example] break=on-false
  129. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->holiday_example] continue=true
  130. Dialplan: sofia/internal/1000@192.168.1.166 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  131. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->global-intercept] continue=false
  132. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [global-intercept] destination_number(98765432100) =~ /^886$/ break=on-false
  133. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->group-intercept] continue=false
  134. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [group-intercept] destination_number(98765432100) =~ /^\*8$/ break=on-false
  135. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->intercept-ext] continue=false
  136. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [intercept-ext] destination_number(98765432100) =~ /^\*\*(\d+)$/ break=on-false
  137. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->redial] continue=false
  138. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [redial] destination_number(98765432100) =~ /^(redial|870)$/ break=on-false
  139. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->global] continue=true
  140. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  141. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [global] ${default_password}(nekipassword001) =~ /^1234$/ break=never
  142. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
  143. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  144. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [global] ${switch_r_sdp}(v=0
  145. o=Z 1639600984077 1 IN IP4 192.168.1.166
  146. s=Z
  147. c=IN IP4 192.168.1.166
  148. t=0 0
  149. m=audio 8000 RTP/AVP 106
  150. a=rtpmap:106 opus/48000/2
  151. a=fmtp:106 minptime=20; useinbandfec=1
  152. ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
  153. Dialplan: sofia/internal/1000@192.168.1.166 Absolute Condition [global]
  154. Dialplan: sofia/internal/1000@192.168.1.166 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
  155. Dialplan: sofia/internal/1000@192.168.1.166 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  156. Dialplan: sofia/internal/1000@192.168.1.166 Action hash(insert/${domain_name}-last_dial/global/${uuid})
  157. Dialplan: sofia/internal/1000@192.168.1.166 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  158. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->snom-demo-2] continue=false
  159. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [snom-demo-2] destination_number(98765432100) =~ /^9001$/ break=on-false
  160. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->snom-demo-1] continue=false
  161. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [snom-demo-1] destination_number(98765432100) =~ /^9000$/ break=on-false
  162. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->eavesdrop] continue=false
  163. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [eavesdrop] destination_number(98765432100) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  164. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->eavesdrop] continue=false
  165. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [eavesdrop] destination_number(98765432100) =~ /^779$/ break=on-false
  166. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->call_return] continue=false
  167. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [call_return] destination_number(98765432100) =~ /^\*69$|^869$|^lcr$/ break=on-false
  168. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->del-group] continue=false
  169. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [del-group] destination_number(98765432100) =~ /^80(\d{2})$/ break=on-false
  170. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->add-group] continue=false
  171. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [add-group] destination_number(98765432100) =~ /^81(\d{2})$/ break=on-false
  172. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->call-group-simo] continue=false
  173. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [call-group-simo] destination_number(98765432100) =~ /^82(\d{2})$/ break=on-false
  174. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->call-group-order] continue=false
  175. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [call-group-order] destination_number(98765432100) =~ /^83(\d{2})$/ break=on-false
  176. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->extension-intercom] continue=false
  177. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [extension-intercom] destination_number(98765432100) =~ /^8(10[01][0-9])$/ break=on-false
  178. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->Local_Extension] continue=false
  179. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [Local_Extension] destination_number(98765432100) =~ /^(10[01][0-9])$/ break=on-false
  180. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->Local_Extension_Skinny] continue=false
  181. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [Local_Extension_Skinny] destination_number(98765432100) =~ /^(11[01][0-9])$/ break=on-false
  182. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->group_dial_sales] continue=false
  183. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [group_dial_sales] destination_number(98765432100) =~ /^2000$/ break=on-false
  184. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->group_dial_support] continue=false
  185. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [group_dial_support] destination_number(98765432100) =~ /^2001$/ break=on-false
  186. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->group_dial_billing] continue=false
  187. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [group_dial_billing] destination_number(98765432100) =~ /^2002$/ break=on-false
  188. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->operator] continue=false
  189. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [operator] destination_number(98765432100) =~ /^(operator|0)$/ break=on-false
  190. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->vmain] continue=false
  191. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [vmain] destination_number(98765432100) =~ /^vmain$|^4000$|^\*98$/ break=on-false
  192. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->sip_uri] continue=false
  193. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [sip_uri] destination_number(98765432100) =~ /^sip:(.*)$/ break=on-false
  194. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->nb_conferences] continue=false
  195. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [nb_conferences] destination_number(98765432100) =~ /^(30\d{2})$/ break=on-false
  196. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->wb_conferences] continue=false
  197. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [wb_conferences] destination_number(98765432100) =~ /^(31\d{2})$/ break=on-false
  198. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->uwb_conferences] continue=false
  199. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [uwb_conferences] destination_number(98765432100) =~ /^(32\d{2})$/ break=on-false
  200. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->cdquality_conferences] continue=false
  201. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [cdquality_conferences] destination_number(98765432100) =~ /^(33\d{2})$/ break=on-false
  202. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->cdquality_stereo_conferences] continue=false
  203. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [cdquality_stereo_conferences] destination_number(98765432100) =~ /^(35\d{2}).*?-screen$/ break=on-false
  204. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->conference-canvases] continue=true
  205. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [conference-canvases] destination_number(98765432100) =~ /(35\d{2})-canvas-(\d+)/ break=on-false
  206. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->conf mod] continue=false
  207. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [conf mod] destination_number(98765432100) =~ /^6070-moderator$/ break=on-false
  208. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->cdquality_conferences] continue=false
  209. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [cdquality_conferences] destination_number(98765432100) =~ /^(35\d{2})$/ break=on-false
  210. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->cdquality_conferences_720] continue=false
  211. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [cdquality_conferences_720] destination_number(98765432100) =~ /^(36\d{2})$/ break=on-false
  212. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->cdquality_conferences_480] continue=false
  213. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [cdquality_conferences_480] destination_number(98765432100) =~ /^(37\d{2})$/ break=on-false
  214. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->cdquality_conferences_320] continue=false
  215. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [cdquality_conferences_320] destination_number(98765432100) =~ /^(38\d{2})$/ break=on-false
  216. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->freeswitch_public_conf_via_sip] continue=false
  217. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(98765432100) =~ /^9(888|8888|1616|3232)$/ break=on-false
  218. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->mad_boss_intercom] continue=false
  219. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [mad_boss_intercom] destination_number(98765432100) =~ /^0911$/ break=on-false
  220. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->mad_boss_intercom] continue=false
  221. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [mad_boss_intercom] destination_number(98765432100) =~ /^0912$/ break=on-false
  222. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->mad_boss] continue=false
  223. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [mad_boss] destination_number(98765432100) =~ /^0913$/ break=on-false
  224. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->ivr_demo] continue=false
  225. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [ivr_demo] destination_number(98765432100) =~ /^5000$/ break=on-false
  226. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->dynamic_conference] continue=false
  227. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [dynamic_conference] destination_number(98765432100) =~ /^5001$/ break=on-false
  228. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->rtp_multicast_page] continue=false
  229. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [rtp_multicast_page] destination_number(98765432100) =~ /^pagegroup$|^7243$/ break=on-false
  230. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->park] continue=false
  231. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [park] destination_number(98765432100) =~ /^5900$/ break=on-false
  232. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->unpark] continue=false
  233. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [unpark] destination_number(98765432100) =~ /^5901$/ break=on-false
  234. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->valet_park] continue=false
  235. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [valet_park] destination_number(98765432100) =~ /^(6000)$/ break=on-false
  236. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->valet_park] continue=false
  237. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [valet_park] destination_number(98765432100) =~ /^((?!6000)60\d{2})$/ break=on-false
  238. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->park] continue=false
  239. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
  240. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [park] destination_number(98765432100) =~ /park\+(\d+)/ break=on-false
  241. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->unpark] continue=false
  242. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
  243. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [unpark] destination_number(98765432100) =~ /^parking$/ break=on-false
  244. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->park] continue=false
  245. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
  246. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [park] destination_number(98765432100) =~ /callpark/ break=on-false
  247. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->unpark] continue=false
  248. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
  249. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [unpark] destination_number(98765432100) =~ /pickup/ break=on-false
  250. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->wait] continue=false
  251. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [wait] destination_number(98765432100) =~ /^wait$/ break=on-false
  252. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->fax_receive] continue=false
  253. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [fax_receive] destination_number(98765432100) =~ /^9178$/ break=on-false
  254. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->fax_transmit] continue=false
  255. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [fax_transmit] destination_number(98765432100) =~ /^9179$/ break=on-false
  256. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->ringback_180] continue=false
  257. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [ringback_180] destination_number(98765432100) =~ /^9180$/ break=on-false
  258. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->ringback_183_uk_ring] continue=false
  259. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [ringback_183_uk_ring] destination_number(98765432100) =~ /^9181$/ break=on-false
  260. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->ringback_183_music_ring] continue=false
  261. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [ringback_183_music_ring] destination_number(98765432100) =~ /^9182$/ break=on-false
  262. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->ringback_post_answer_uk_ring] continue=false
  263. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(98765432100) =~ /^9183$/ break=on-false
  264. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->ringback_post_answer_music] continue=false
  265. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [ringback_post_answer_music] destination_number(98765432100) =~ /^9184$/ break=on-false
  266. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->ClueCon] continue=false
  267. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [ClueCon] destination_number(98765432100) =~ /^9191$/ break=on-false
  268. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->show_info] continue=false
  269. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [show_info] destination_number(98765432100) =~ /^9192$/ break=on-false
  270. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->video_record] continue=false
  271. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [video_record] destination_number(98765432100) =~ /^9193$/ break=on-false
  272. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->video_playback] continue=false
  273. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [video_playback] destination_number(98765432100) =~ /^9194$/ break=on-false
  274. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->delay_echo] continue=false
  275. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [delay_echo] destination_number(98765432100) =~ /^9195$/ break=on-false
  276. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->echo] continue=false
  277. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [echo] destination_number(98765432100) =~ /^9196$/ break=on-false
  278. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->milliwatt] continue=false
  279. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [milliwatt] destination_number(98765432100) =~ /^9197$/ break=on-false
  280. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->tone_stream] continue=false
  281. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [tone_stream] destination_number(98765432100) =~ /^9198$/ break=on-false
  282. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->zrtp_enrollement] continue=false
  283. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [zrtp_enrollement] destination_number(98765432100) =~ /^9787$/ break=on-false
  284. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->hold_music] continue=false
  285. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [hold_music] destination_number(98765432100) =~ /^9664$/ break=on-false
  286. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->laugh break] continue=false
  287. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [laugh break] destination_number(98765432100) =~ /^9386$/ break=on-false
  288. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->101] continue=false
  289. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [101] destination_number(98765432100) =~ /^101$/ break=on-false
  290. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->pizza_demo] continue=false
  291. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [pizza_demo] destination_number(98765432100) =~ /^(pizza|74992)$/ break=on-false
  292. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->Talking Clock Time] continue=false
  293. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [Talking Clock Time] destination_number(98765432100) =~ /^9170$/ break=on-false
  294. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->Talking Clock Date] continue=false
  295. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [Talking Clock Date] destination_number(98765432100) =~ /^9171$/ break=on-false
  296. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->Talking Clock Date and Time] continue=false
  297. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [Talking Clock Date and Time] destination_number(98765432100) =~ /^9172$/ break=on-false
  298. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->local.example.com] continue=false
  299. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [local.example.com] ${toll_allow}(domestic,international,local) =~ /local/ break=on-false
  300. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [local.example.com] destination_number(98765432100) =~ /^(\d{7})$/ break=on-false
  301. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->domestic.example.com] continue=false
  302. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [domestic.example.com] ${toll_allow}(domestic,international,local) =~ /domestic/ break=on-false
  303. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [domestic.example.com] destination_number(98765432100) =~ /^(\d{11})$/ break=on-false
  304. Dialplan: sofia/internal/1000@192.168.1.166 Action set(effective_caller_id_number=${outbound_caller_id_number})
  305. Dialplan: sofia/internal/1000@192.168.1.166 Action set(effective_caller_id_name=${outbound_caller_id_name})
  306. Dialplan: sofia/internal/1000@192.168.1.166 Action bridge(sofia/gateway/${default_gateway}/98765432100)
  307. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/1000@192.168.1.166) State Change CS_ROUTING -> CS_EXECUTE
  308. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1000@192.168.1.166) State ROUTING going to sleep
  309. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1000@192.168.1.166) Running State Change CS_EXECUTE (Cur 1 Tot 5)
  310. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/1000@192.168.1.166) State EXECUTE
  311. 2021-12-15 20:43:04.127165 96.50% [DEBUG] mod_sofia.c:213 sofia/internal/1000@192.168.1.166 SOFIA EXECUTE
  312. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:323 sofia/internal/1000@192.168.1.166 Standard EXECUTE
  313. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 hash(insert/192.168.1.166-spymap/1000/38bfa394-ca3e-468f-9a1e-9d048a913edc)
  314. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 hash(insert/192.168.1.166-last_dial/1000/98765432100)
  315. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 hash(insert/192.168.1.166-last_dial/global/38bfa394-ca3e-468f-9a1e-9d048a913edc)
  316. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 export(RFC2822_DATE=Wed, 15 Dec 2021 20:43:04 +0000)
  317. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 15 Dec 2021 20:43:04 +0000]
  318. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 set(effective_caller_id_number=0000000000)
  319. 2021-12-15 20:43:04.127165 96.50% [DEBUG] mod_dptools.c:1685 SET sofia/internal/1000@192.168.1.166 [effective_caller_id_number]=[0000000000]
  320. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 set(effective_caller_id_name=FreeSWITCH)
  321. 2021-12-15 20:43:04.127165 96.50% [DEBUG] mod_dptools.c:1685 SET sofia/internal/1000@192.168.1.166 [effective_caller_id_name]=[FreeSWITCH]
  322. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 bridge(sofia/gateway/tolinphone/98765432100)
  323. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_channel.c:1269 sofia/internal/1000@192.168.1.166 EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 15 Dec 2021 20:43:04 +0000] to event
  324. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  325. 2021-12-15 20:43:04.127165 96.50% [NOTICE] switch_channel.c:1123 New Channel sofia/external/98765432100 [4c849af0-2974-46e8-a7d9-262b2ae0f1b1]
  326. 2021-12-15 20:43:04.127165 96.50% [DEBUG] mod_sofia.c:5121 (sofia/external/98765432100) State Change CS_NEW -> CS_INIT
  327. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:581 (sofia/external/98765432100) Running State Change CS_INIT (Cur 2 Tot 6)
  328. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:624 (sofia/external/98765432100) State INIT
  329. 2021-12-15 20:43:04.127165 96.50% [DEBUG] mod_sofia.c:97 sofia/external/98765432100 SOFIA INIT
  330. 2021-12-15 20:43:04.127165 96.50% [INFO] sofia_glue.c:1651 sofia/external/98765432100 sending invite call-id: (null)
  331. 2021-12-15 20:43:04.127165 96.50% [DEBUG] sofia_glue.c:1654 sofia/external/98765432100 sending invite version: 1.10.8-dev git e428be3 2021-11-17 19:23:28Z 64bit
  332. Local SDP:
  333. v=0
  334. o=FreeSWITCH 1639582954 1639582955 IN IP4 192.168.1.166
  335. s=FreeSWITCH
  336. c=IN IP4 192.168.1.166
  337. t=0 0
  338. m=audio 18030 RTP/AVP 102 101
  339. a=rtpmap:102 opus/48000/2
  340. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  341. a=rtpmap:101 telephone-event/48000
  342. a=fmtp:101 0-15
  343. a=ptime:20
  344. a=sendrecv
  345.  
  346. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:40 sofia/external/98765432100 Standard INIT
  347. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:48 (sofia/external/98765432100) State Change CS_INIT -> CS_ROUTING
  348. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:624 (sofia/external/98765432100) State INIT going to sleep
  349. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:581 (sofia/external/98765432100) Running State Change CS_ROUTING (Cur 2 Tot 6)
  350. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:640 (sofia/external/98765432100) State ROUTING
  351. 2021-12-15 20:43:04.127165 96.50% [DEBUG] mod_sofia.c:158 sofia/external/98765432100 SOFIA ROUTING
  352. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_ivr_originate.c:67 (sofia/external/98765432100) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  353. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:640 (sofia/external/98765432100) State ROUTING going to sleep
  354. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:581 (sofia/external/98765432100) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 6)
  355. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:659 (sofia/external/98765432100) State CONSUME_MEDIA
  356. 2021-12-15 20:43:04.127165 96.50% [DEBUG] switch_core_state_machine.c:659 (sofia/external/98765432100) State CONSUME_MEDIA going to sleep
  357. send 1231 bytes to udp/[192.168.1.166]:5090 at 20:43:04.143964:
  358. ------------------------------------------------------------------------
  359. INVITE sip:98765432100@192.168.1.166:5090 SIP/2.0
  360. Via: SIP/2.0/UDP 192.168.1.166:5080;rport;branch=z9hG4bK5NeN68vU46Q8p
  361. Max-Forwards: 69
  362. From: "FreeSWITCH" <sip:cluecon@192.168.1.166:5090>;tag=39N8pX577t5Dg
  363. To: <sip:98765432100@192.168.1.166:5090>
  364. Call-ID: 7134b16b-d88a-123a-519f-dc1ba1ed361d
  365. CSeq: 45246700 INVITE
  366. Contact: <sip:gw+tolinphone@192.168.1.166:5080;transport=udp;gw=tolinphone>
  367. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  368. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  369. Supported: timer, path, replaces
  370. Allow-Events: talk, hold, conference, refer
  371. Content-Type: application/sdp
  372. Content-Disposition: session
  373. Content-Length: 351
  374. X-FS-Support: update_display,send_info
  375. Remote-Party-ID: "FreeSWITCH" <sip:0000000000@192.168.1.166:5090>;party=calling;screen=yes;privacy=off
  376.  
  377. v=0
  378. o=FreeSWITCH 1639582954 1639582955 IN IP4 192.168.1.166
  379. s=FreeSWITCH
  380. c=IN IP4 192.168.1.166
  381. t=0 0
  382. m=audio 18030 RTP/AVP 102 101
  383. a=rtpmap:102 opus/48000/2
  384. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  385. a=rtpmap:101 telephone-event/48000
  386. a=fmtp:101 0-15
  387. a=ptime:20
  388. 2021-12-15 20:43:04.127165 96.50% [DEBUG] sofia.c:7499 Channel sofia/external/98765432100 entering state [calling][0]
  389. recv 274 bytes from udp/[192.168.1.166]:5090 at 20:43:04.162945:
  390. ------------------------------------------------------------------------
  391. SIP/2.0 100 Trying
  392. Via: SIP/2.0/UDP 192.168.1.166:5080;rport;branch=z9hG4bK5NeN68vU46Q8p
  393. From: "FreeSWITCH" <sip:cluecon@192.168.1.166:5090>;tag=39N8pX577t5Dg
  394. To: sip:98765432100@192.168.1.166:5090
  395. Call-ID: 7134b16b-d88a-123a-519f-dc1ba1ed361d
  396. CSeq: 45246700 INVITE
  397.  
  398. recv 367 bytes from udp/[192.168.1.166]:5090 at 20:43:04.388602:
  399. ------------------------------------------------------------------------
  400. SIP/2.0 180 Ringing
  401. Via: SIP/2.0/UDP 192.168.1.166:5080;rport;branch=z9hG4bK5NeN68vU46Q8p
  402. From: "FreeSWITCH" <sip:cluecon@192.168.1.166:5090>;tag=39N8pX577t5Dg
  403. To: <sip:98765432100@192.168.1.166:5090>;tag=Sp1~o~3
  404. Call-ID: 7134b16b-d88a-123a-519f-dc1ba1ed361d
  405. CSeq: 45246700 INVITE
  406. User-Agent: Linphone/3.12.0 (belle-sip/1.6.3)
  407. Supported: replaces, outbound
  408.  
  409. 2021-12-15 20:43:04.387168 96.30% [DEBUG] sofia.c:7499 Channel sofia/external/98765432100 entering state [proceeding][180]
  410. 2021-12-15 20:43:04.387168 96.30% [NOTICE] sofia.c:7610 Ring-Ready sofia/external/98765432100!
  411. 2021-12-15 20:43:04.387168 96.30% [DEBUG] switch_channel.c:3494 (sofia/external/98765432100) Callstate Change DOWN -> RINGING
  412. 2021-12-15 20:43:04.387168 96.30% [NOTICE] mod_sofia.c:2515 Ring-Ready sofia/internal/1000@192.168.1.166!
  413. send 886 bytes to udp/[192.168.1.166]:61762 at 20:43:04.406283:
  414. ------------------------------------------------------------------------
  415. SIP/2.0 180 Ringing
  416. Via: SIP/2.0/UDP 192.168.1.166:61762;branch=z9hG4bK-524287-1---208a27208340423a;rport=61762
  417. From: <sip:1000@192.168.1.166;transport=UDP>;tag=82406d28
  418. To: <sip:98765432100@192.168.1.166>;tag=QcBKSBmgrr1yN
  419. Call-ID: H66EMVsAFFGB4DzX2Fk4IA..
  420. CSeq: 2 INVITE
  421. Contact: <sip:98765432100@192.168.1.166:5060;transport=udp>
  422. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  423. Accept: application/sdp
  424. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  425. Supported: timer, path, replaces
  426. Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  427. Content-Length: 0
  428. Remote-Party-ID: "Outbound Call" <sip:98765432100@192.168.1.166>;party=calling;privacy=off;screen=no
  429.  
  430. 2021-12-15 20:43:04.387168 96.30% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [early][180]
  431. 2021-12-15 20:43:04.387168 96.30% [NOTICE] switch_ivr_originate.c:565 Ring Ready sofia/internal/1000@192.168.1.166!
  432. recv 838 bytes from udp/[192.168.1.166]:5090 at 20:43:06.336192:
  433. ------------------------------------------------------------------------
  434. SIP/2.0 200 Ok
  435. Via: SIP/2.0/UDP 192.168.1.166:5080;rport;branch=z9hG4bK5NeN68vU46Q8p
  436. From: "FreeSWITCH" <sip:cluecon@192.168.1.166:5090>;tag=39N8pX577t5Dg
  437. To: <sip:98765432100@192.168.1.166:5090>;tag=Sp1~o~3
  438. Call-ID: 7134b16b-d88a-123a-519f-dc1ba1ed361d
  439. CSeq: 45246700 INVITE
  440. User-Agent: Linphone/3.12.0 (belle-sip/1.6.3)
  441. Supported: replaces, outbound
  442. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
  443. Contact: "Li" <sip:9999@192.168.1.166:5090;transport=udp>;+sip.instance="<urn:uuid:b8fee09a-4790-44f6-88d1-69c76cc5c84a>"
  444. Content-Type: application/sdp
  445. Content-Length: 210
  446.  
  447. v=0
  448. o=98765432100 2079 1141 IN IP4 192.168.1.166
  449. s=Talk
  450. c=IN IP4 192.168.1.166
  451. t=0 0
  452. m=audio 7078 RTP/AVP 102 101
  453. a=rtpmap:102 opus/48000/2
  454. a=fmtp:102 useinbandfec=1
  455. a=rtpmap:101 telephone-event/48000
  456. 2021-12-15 20:43:06.327191 96.17% [DEBUG] sofia.c:7499 Channel sofia/external/98765432100 entering state [completing][200]
  457. 2021-12-15 20:43:06.327191 96.17% [DEBUG] sofia.c:7509 Remote SDP:
  458. v=0
  459. o=98765432100 2079 1141 IN IP4 192.168.1.166
  460. s=Talk
  461. c=IN IP4 192.168.1.166
  462. t=0 0
  463. m=audio 7078 RTP/AVP 102 101
  464. a=rtpmap:102 opus/48000/2
  465. a=fmtp:102 useinbandfec=1
  466. a=rtpmap:101 telephone-event/48000
  467.  
  468. send 434 bytes to udp/[192.168.1.166]:5090 at 20:43:06.338654:
  469. ------------------------------------------------------------------------
  470. ACK sip:9999@192.168.1.166:5090;transport=udp SIP/2.0
  471. Via: SIP/2.0/UDP 192.168.1.166:5080;rport;branch=z9hG4bK6y7D83DZ1FeUj
  472. Max-Forwards: 70
  473. From: "FreeSWITCH" <sip:cluecon@192.168.1.166:5090>;tag=39N8pX577t5Dg
  474. To: <sip:98765432100@192.168.1.166:5090>;tag=Sp1~o~3
  475. Call-ID: 7134b16b-d88a-123a-519f-dc1ba1ed361d
  476. CSeq: 45246700 ACK
  477. Contact: <sip:gw+tolinphone@192.168.1.166:5080;transport=udp;gw=tolinphone>
  478. Content-Length: 0
  479.  
  480. 2021-12-15 20:43:06.327191 96.17% [DEBUG] sofia.c:7499 Channel sofia/external/98765432100 entering state [ready][200]
  481. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:2]
  482. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_core_media.c:5707 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match
  483. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_core_media.c:5568 Set telephone-event payload to 101@48000
  484. 2021-12-15 20:43:06.327191 96.17% [DEBUG] mod_opus.c:619 Opus encoder: set bitrate to local settings [72000bps]
  485. 2021-12-15 20:43:06.327191 96.17% [DEBUG] mod_opus.c:619 Opus encoder: set bitrate to local settings [72000bps]
  486. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_core_media.c:3872 Set Codec sofia/external/98765432100 opus/48000 20 ms 960 samples 0 bits 1 channels
  487. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_core_codec.c:111 sofia/external/98765432100 Original read codec set to opus:116
  488. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_core_media.c:5917 Set telephone-event payload to 101@48000
  489. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_core_media.c:5975 sofia/external/98765432100 Set 2833 dtmf send payload to 101 recv payload to 101
  490. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_core_media.c:8779 AUDIO RTP [sofia/external/98765432100] 192.168.1.166 port 18030 -> 192.168.1.166 port 7078 codec: 102 ms: 20
  491. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 960 bytes per 20ms
  492. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_core_media.c:9091 sofia/external/98765432100 Set 2833 dtmf send payload to 101
  493. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_core_media.c:9098 sofia/external/98765432100 Set 2833 dtmf receive payload to 101
  494. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_core_media.c:9121 sofia/external/98765432100 Set rtp dtmf delay to 40
  495. 2021-12-15 20:43:06.327191 96.17% [NOTICE] sofia.c:8683 Channel [sofia/external/98765432100] has been answered
  496. 2021-12-15 20:43:06.327191 96.17% [DEBUG] switch_channel.c:3950 (sofia/external/98765432100) Callstate Change RINGING -> ACTIVE
  497. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:106:48000:20:0:1]/[opus:116:48000:20:0:1]
  498. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:5707 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  499. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:106:48000:20:0:1]/[G722:9:8000:20:64000:1]
  500. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:106:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
  501. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:106:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
  502. 2021-12-15 20:43:06.347208 96.17% [DEBUG] mod_opus.c:619 Opus encoder: set bitrate to local settings [72000bps]
  503. 2021-12-15 20:43:06.347208 96.17% [DEBUG] mod_opus.c:619 Opus encoder: set bitrate to local settings [72000bps]
  504. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:3872 Set Codec sofia/internal/1000@192.168.1.166 opus/48000 20 ms 960 samples 0 bits 1 channels
  505. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_codec.c:111 sofia/internal/1000@192.168.1.166 Original read codec set to opus:116
  506. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:5946 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
  507. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:5975 sofia/internal/1000@192.168.1.166 Set 2833 dtmf send payload to 101 recv payload to 101
  508. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:8779 AUDIO RTP [sofia/internal/1000@192.168.1.166] 192.168.1.166 port 25486 -> 192.168.1.166 port 8000 codec: 106 ms: 20
  509. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 960 bytes per 20ms
  510. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:9091 sofia/internal/1000@192.168.1.166 Set 2833 dtmf send payload to 101
  511. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:9098 sofia/internal/1000@192.168.1.166 Set 2833 dtmf receive payload to 101
  512. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:9121 sofia/internal/1000@192.168.1.166 Set rtp dtmf delay to 40
  513. 2021-12-15 20:43:06.347208 96.17% [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1000@192.168.1.166!
  514. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_channel.c:3622 (sofia/internal/1000@192.168.1.166) Callstate Change RINGING -> EARLY
  515. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_core_media.c:8761 Audio params are unchanged for sofia/internal/1000@192.168.1.166.
  516. 2021-12-15 20:43:06.347208 96.17% [DEBUG] mod_sofia.c:913 Local SDP sofia/internal/1000@192.168.1.166:
  517. v=0
  518. o=FreeSWITCH 1639575500 1639575501 IN IP4 192.168.1.166
  519. s=FreeSWITCH
  520. c=IN IP4 192.168.1.166
  521. t=0 0
  522. m=audio 25486 RTP/AVP 106 101
  523. a=rtpmap:106 opus/48000/2
  524. a=fmtp:106 useinbandfec=1; minptime=20
  525. a=rtpmap:101 telephone-event/8000
  526. a=fmtp:101 0-15
  527. a=ptime:20
  528. a=sendrecv
  529.  
  530. 2021-12-15 20:43:06.347208 96.17% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/internal/1000@192.168.1.166] has been answered
  531. send 1188 bytes to udp/[192.168.1.166]:61762 at 20:43:06.364982:
  532. ------------------------------------------------------------------------
  533. SIP/2.0 200 OK
  534. Via: SIP/2.0/UDP 192.168.1.166:61762;branch=z9hG4bK-524287-1---208a27208340423a;rport=61762
  535. From: <sip:1000@192.168.1.166;transport=UDP>;tag=82406d28
  536. To: <sip:98765432100@192.168.1.166>;tag=QcBKSBmgrr1yN
  537. Call-ID: H66EMVsAFFGB4DzX2Fk4IA..
  538. CSeq: 2 INVITE
  539. Contact: <sip:98765432100@192.168.1.166:5060;transport=udp>
  540. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  541. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  542. Supported: timer, path, replaces
  543. Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  544. Content-Type: application/sdp
  545. Content-Disposition: session
  546. Content-Length: 269
  547. Remote-Party-ID: "Outbound Call" <sip:98765432100@192.168.1.166>;party=calling;privacy=off;screen=no
  548.  
  549. v=0
  550. o=FreeSWITCH 1639575500 1639575501 IN IP4 192.168.1.166
  551. s=FreeSWITCH
  552. c=IN IP4 192.168.1.166
  553. t=0 0
  554. m=audio 25486 RTP/AVP 106 101
  555. a=rtpmap:106 opus/48000/2
  556. a=fmtp:106 useinbandfec=1; minptime=20
  557. a=rtpmap:101 telephone-event/8000
  558. a=fmtp:101 0-15
  559. a=ptime:20
  560. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_channel.c:3950 (sofia/internal/1000@192.168.1.166) Callstate Change EARLY -> ACTIVE
  561. 2021-12-15 20:43:06.347208 96.17% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [completed][200]
  562. 2021-12-15 20:43:06.347208 96.17% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/external/98765432100] Peer UUID: 4c849af0-2974-46e8-a7d9-262b2ae0f1b1
  563. recv 442 bytes from udp/[192.168.1.166]:61762 at 20:43:06.366260:
  564. ------------------------------------------------------------------------
  565. ACK sip:98765432100@192.168.1.166:5060;transport=udp SIP/2.0
  566. Via: SIP/2.0/UDP 192.168.1.166:61762;branch=z9hG4bK-524287-1---1376af489db8a5e8;rport
  567. Max-Forwards: 70
  568. Contact: <sip:1000@192.168.1.166:61762;transport=UDP>
  569. To: <sip:98765432100@192.168.1.166>;tag=QcBKSBmgrr1yN
  570. From: <sip:1000@192.168.1.166;transport=UDP>;tag=82406d28
  571. Call-ID: H66EMVsAFFGB4DzX2Fk4IA..
  572. CSeq: 2 ACK
  573. User-Agent: Z 5.4.12 v2.10.13.2-mod
  574. Content-Length: 0
  575.  
  576. 2021-12-15 20:43:06.347208 96.17% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [ready][200]
  577. 2021-12-15 20:43:06.367182 96.03% [DEBUG] switch_ivr_bridge.c:1793 (sofia/external/98765432100) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  578. 2021-12-15 20:43:06.367182 96.03% [DEBUG] switch_core_state_machine.c:581 (sofia/external/98765432100) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 6)
  579. 2021-12-15 20:43:06.367182 96.03% [DEBUG] switch_core_state_machine.c:650 (sofia/external/98765432100) State EXCHANGE_MEDIA
  580. 2021-12-15 20:43:06.367182 96.03% [DEBUG] mod_sofia.c:671 SOFIA EXCHANGE_MEDIA
  581. 2021-12-15 20:43:06.387198 96.03% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  582. 2021-12-15 20:43:06.387198 96.03% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  583. recv 1196 bytes from udp/[192.168.1.166]:5090 at 20:43:09.265018:
  584. ------------------------------------------------------------------------
  585. INVITE sip:gw+tolinphone@192.168.1.166:5080;transport=udp;gw=tolinphone SIP/2.0
  586. Via: SIP/2.0/UDP 192.168.1.166:5090;branch=z9hG4bK.D9xR9vnCl;rport
  587. From: <sip:98765432100@192.168.1.166>;tag=Sp1~o~3
  588. To: "FreeSWITCH" <sip:cluecon@192.168.1.166>;tag=39N8pX577t5Dg
  589. CSeq: 111 INVITE
  590. Call-ID: 7134b16b-d88a-123a-519f-dc1ba1ed361d
  591. Max-Forwards: 70
  592. Subject: Media change
  593. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
  594. Content-Type: application/sdp
  595. Content-Length: 521
  596. Contact: <sip:192.168.1.166:5090;transport=udp>;+sip.instance="<urn:uuid:b8fee09a-4790-44f6-88d1-69c76cc5c84a>"
  597. User-Agent: Linphone/3.12.0 (belle-sip/1.6.3)
  598.  
  599. v=0
  600. o=98765432100 2079 1142 IN IP4 192.168.1.166
  601. s=Talk
  602. c=IN IP4 192.168.1.166
  603. t=0 0
  604. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
  605. m=audio 7078 RTP/AVP 102 101
  606. a=rtpmap:102 opus/48000/2
  607. a=fmtp:102 useinbandfec=1
  608. a=rtpmap:101 telephone-event/48000
  609. a=rtcp-fb:* trr-int 5000
  610. a=rtcp-fb:* ccm tmmbr
  611. m=video 9078 RTP/AVP 96
  612. a=rtpmap:96 VP8/90000
  613. a=rtcp-fb:* trr-int 5000
  614. a=rtcp-fb:* ccm tmmbr
  615. a=rtcp-fb:96 nack pli
  616. a=rtcp-fb:96 nack sli
  617. a=rtcp-fb:96 ack rpsi
  618. a=rtcp-fb:96 ccm fir
  619. send 377 bytes to udp/[192.168.1.166]:5090 at 20:43:09.280780:
  620. ------------------------------------------------------------------------
  621. SIP/2.0 100 Trying
  622. Via: SIP/2.0/UDP 192.168.1.166:5090;branch=z9hG4bK.D9xR9vnCl;rport=5090
  623. From: <sip:98765432100@192.168.1.166>;tag=Sp1~o~3
  624. To: "FreeSWITCH" <sip:cluecon@192.168.1.166>;tag=39N8pX577t5Dg
  625. Call-ID: 7134b16b-d88a-123a-519f-dc1ba1ed361d
  626. CSeq: 111 INVITE
  627. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  628. Content-Length: 0
  629.  
  630. 2021-12-15 20:43:09.267234 95.77% [DEBUG] sofia.c:7499 Channel sofia/external/98765432100 entering state [received][100]
  631. 2021-12-15 20:43:09.267234 95.77% [DEBUG] sofia.c:7509 Remote SDP:
  632. v=0
  633. o=98765432100 2079 1142 IN IP4 192.168.1.166
  634. s=Talk
  635. c=IN IP4 192.168.1.166
  636. t=0 0
  637. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
  638. m=audio 7078 RTP/AVP 102 101
  639. a=rtpmap:102 opus/48000/2
  640. a=fmtp:102 useinbandfec=1
  641. a=rtpmap:101 telephone-event/48000
  642. a=rtcp-fb:* trr-int 5000
  643. a=rtcp-fb:* ccm tmmbr
  644. m=video 9078 RTP/AVP 96
  645. a=rtpmap:96 VP8/90000
  646. a=rtcp-fb:* trr-int 5000
  647. a=rtcp-fb:* ccm tmmbr
  648. a=rtcp-fb:96 nack pli
  649. a=rtcp-fb:96 nack sli
  650. a=rtcp-fb:96 ack rpsi
  651. a=rtcp-fb:96 ccm fir
  652.  
  653. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:2]
  654. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:5707 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match
  655. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
  656. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:5707 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  657. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:102:48000:20:0:1]/[G722:9:8000:20:64000:1]
  658. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
  659. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
  660. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:5568 Set telephone-event payload to 101@48000
  661. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:5917 Set telephone-event payload to 101@48000
  662. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:5975 sofia/external/98765432100 Set 2833 dtmf send payload to 101 recv payload to 101
  663. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:6260 Video Codec Compare [VP8:96]/[H264:99]
  664. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:6260 Video Codec Compare [VP8:96]/[VP8:99]
  665. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:6292 Video Codec Compare [VP8:96] +++ is saved as a match
  666. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_vpx.c:703 VPX VER:v1.8.1 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:8
  667. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_vpx.c:703 VPX VER:v1.8.1 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:8
  668. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:3677 Set VIDEO Codec sofia/external/98765432100 VP8/90000 0 ms
  669. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:8761 Audio params are unchanged for sofia/external/98765432100.
  670. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_rtp.c:4634 Starting video timer.
  671. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:9613 VIDEO RTP [sofia/external/98765432100] 192.168.1.166:16406->192.168.1.166:9078 codec: 96 ms: 0 [SUCCESS]
  672. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:7738 sofia/external/98765432100 Starting Video thread
  673. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_core_media.c:7604 sofia/external/98765432100 Video thread started. Echo is off
  674. 2021-12-15 20:43:09.267234 95.77% [INFO] switch_core_media.c:9694 Activating VIDEO RTCP PORT 9079 interval 1000 mux -1
  675. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_rtp.c:5017 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 9079
  676. 2021-12-15 20:43:09.267234 95.77% [DEBUG] switch_rtp.c:2776 Setting RTCP remote addr to 192.168.1.166:9079 2
  677. 2021-12-15 20:43:09.267234 95.77% [DEBUG] sofia.c:8454 Processing updated SDP
  678. 2021-12-15 20:43:09.287209 95.77% [INFO] sofia_glue.c:1651 sofia/internal/1000@192.168.1.166 sending invite call-id: (null)
  679. 2021-12-15 20:43:09.287209 95.77% [DEBUG] sofia_glue.c:1654 sofia/internal/1000@192.168.1.166 sending invite version: 1.10.8-dev git e428be3 2021-11-17 19:23:28Z 64bit
  680. Local SDP:
  681. v=0
  682. o=FreeSWITCH 1639575500 1639575503 IN IP4 192.168.1.166
  683. s=FreeSWITCH
  684. c=IN IP4 192.168.1.166
  685. t=0 0
  686. m=audio 25486 RTP/AVP 102 101
  687. a=rtpmap:102 opus/48000/2
  688. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  689. a=rtpmap:101 telephone-event/48000
  690. a=fmtp:101 0-15
  691. a=ptime:20
  692. a=sendrecv
  693. m=video 29564 RTP/AVP 96
  694. b=AS:3072
  695. a=rtpmap:96 VP8/90000
  696. a=sendrecv
  697. a=rtcp-fb:96 ccm fir
  698. a=rtcp-fb:96 ccm tmmbr
  699. a=rtcp-fb:96 nack
  700. a=rtcp-fb:96 nack pli
  701.  
  702. send 1220 bytes to udp/[192.168.1.166]:61762 at 20:43:09.302791:
  703. ------------------------------------------------------------------------
  704. INVITE sip:1000@192.168.1.166:61762;transport=UDP SIP/2.0
  705. Via: SIP/2.0/UDP 192.168.1.166;rport;branch=z9hG4bKHD513Sy27ecjj
  706. Max-Forwards: 70
  707. From: <sip:98765432100@192.168.1.166>;tag=QcBKSBmgrr1yN
  708. To: <sip:1000@192.168.1.166;transport=UDP>;tag=82406d28
  709. Call-ID: H66EMVsAFFGB4DzX2Fk4IA..
  710. CSeq: 45246702 INVITE
  711. Contact: <sip:98765432100@192.168.1.166:5060;transport=udp>
  712. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  713. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  714. Supported: timer, path, replaces
  715. Content-Type: application/sdp
  716. Content-Length: 523
  717. X-FS-Support: update_display,send_info
  718.  
  719. v=0
  720. o=FreeSWITCH 1639575500 1639575503 IN IP4 192.168.1.166
  721. s=FreeSWITCH
  722. c=IN IP4 192.168.1.166
  723. t=0 0
  724. m=audio 25486 RTP/AVP 102 101
  725. a=rtpmap:102 opus/48000/2
  726. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  727. a=rtpmap:101 telephone-event/48000
  728. a=fmtp:101 0-15
  729. a=ptime:20
  730. a=sendrecv
  731. m=video 29564 RTP/AVP 96
  732. b=AS:3072
  733. a=rtpmap:96 VP8/90000
  734. a=sendrecv
  735. a=rtcp-fb:96 ccm fir
  736. a=rtcp-fb:96 ccm tmmbr
  737. a=rtcp-fb:96 nack
  738. a=rtcp-fb:96 nack pli
  739. recv 731 bytes from udp/[192.168.1.166]:61762 at 20:43:09.305121:
  740. ------------------------------------------------------------------------
  741. SIP/2.0 200 OK
  742. Via: SIP/2.0/UDP 192.168.1.166;rport=5060;branch=z9hG4bKHD513Sy27ecjj
  743. Contact: <sip:1000@192.168.1.166:61762;transport=UDP>
  744. To: <sip:1000@192.168.1.166;transport=UDP>;tag=82406d28
  745. From: <sip:98765432100@192.168.1.166>;tag=QcBKSBmgrr1yN
  746. Call-ID: H66EMVsAFFGB4DzX2Fk4IA..
  747. CSeq: 45246702 INVITE
  748. Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  749. Content-Type: application/sdp
  750. User-Agent: Z 5.4.12 v2.10.13.2-mod
  751. Allow-Events: presence, kpml, talk
  752. Content-Length: 207
  753.  
  754. v=0
  755. o=Z 0 4 IN IP4 192.168.1.166
  756. s=Z
  757. c=IN IP4 192.168.1.166
  758. t=0 0
  759. m=audio 8000 RTP/AVP 106
  760. a=rtpmap:106 opus/48000/2
  761. a=fmtp:106 minptime=10; useinbandfec=1
  762. a=sendrecv
  763. m=video 0 RTP/AVP
  764. a=sendrecv
  765. 2021-12-15 20:43:09.307185 95.77% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [calling][0]
  766. 2021-12-15 20:43:09.307185 95.77% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [completing][200]
  767. 2021-12-15 20:43:09.307185 95.77% [DEBUG] sofia.c:7509 Remote SDP:
  768. v=0
  769. o=Z 0 4 IN IP4 192.168.1.166
  770. s=Z
  771. c=IN IP4 192.168.1.166
  772. t=0 0
  773. m=audio 8000 RTP/AVP 106
  774. a=rtpmap:106 opus/48000/2
  775. a=fmtp:106 minptime=10; useinbandfec=1
  776. a=sendrecv
  777. m=video 0 RTP/AVP
  778. a=sendrecv
  779.  
  780. send 330 bytes to udp/[192.168.1.166]:61762 at 20:43:09.322883:
  781. ------------------------------------------------------------------------
  782. ACK sip:1000@192.168.1.166:61762;transport=UDP SIP/2.0
  783. Via: SIP/2.0/UDP 192.168.1.166;rport;branch=z9hG4bKjpyt5mF64Q24D
  784. Max-Forwards: 70
  785. From: <sip:98765432100@192.168.1.166>;tag=QcBKSBmgrr1yN
  786. To: <sip:1000@192.168.1.166;transport=UDP>;tag=82406d28
  787. Call-ID: H66EMVsAFFGB4DzX2Fk4IA..
  788. CSeq: 45246702 ACK
  789. Content-Length: 0
  790.  
  791. 2021-12-15 20:43:09.327201 95.77% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [ready][200]
  792. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:106:48000:20:0:1]/[opus:116:48000:20:0:2]
  793. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:5707 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match
  794. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:5946 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
  795. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:5975 sofia/internal/1000@192.168.1.166 Set 2833 dtmf send payload to 101 recv payload to 101
  796. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:2]
  797. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:5707 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match
  798. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:5568 Set telephone-event payload to 101@48000
  799. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:5917 Set telephone-event payload to 101@48000
  800. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:5975 sofia/external/98765432100 Set 2833 dtmf send payload to 101 recv payload to 101
  801. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:6303 No matches with FTMP, fallback to ignoring FMTP
  802. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:6311 No matches with inherit_codec, fallback to ignoring PT
  803. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:8761 Audio params are unchanged for sofia/external/98765432100.
  804. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:9477 Video params are unchanged for sofia/external/98765432100.
  805. 2021-12-15 20:43:09.327201 95.77% [DEBUG] switch_core_media.c:8761 Audio params are unchanged for sofia/internal/1000@192.168.1.166.
  806. send 941 bytes to udp/[192.168.1.166]:5090 at 20:43:09.360712:
  807. ------------------------------------------------------------------------
  808. SIP/2.0 200 OK
  809. Via: SIP/2.0/UDP 192.168.1.166:5090;branch=z9hG4bK.D9xR9vnCl;rport=5090
  810. From: <sip:98765432100@192.168.1.166>;tag=Sp1~o~3
  811. To: "FreeSWITCH" <sip:cluecon@192.168.1.166>;tag=39N8pX577t5Dg
  812. Call-ID: 7134b16b-d88a-123a-519f-dc1ba1ed361d
  813. CSeq: 111 INVITE
  814. Contact: <sip:gw+tolinphone@192.168.1.166:5080;transport=udp;gw=tolinphone>
  815. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  816. Accept: application/sdp
  817. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  818. Supported: timer, path, replaces
  819. Content-Type: application/sdp
  820. Content-Disposition: session
  821. Content-Length: 279
  822.  
  823. v=0
  824. o=FreeSWITCH 1639582954 1639582956 IN IP4 192.168.1.166
  825. s=FreeSWITCH
  826. c=IN IP4 192.168.1.166
  827. t=0 0
  828. m=audio 18030 RTP/AVP 102 101
  829. a=rtpmap:102 opus/48000/2
  830. a=fmtp:102 useinbandfec=1
  831. a=rtpmap:101 telephone-event/48000
  832. a=fmtp:101 0-15
  833. a=ptime:20
  834. m=video 0 RTP/AVP 19
  835. 2021-12-15 20:43:09.367166 95.50% [DEBUG] sofia.c:7499 Channel sofia/external/98765432100 entering state [completed][200]
  836. recv 390 bytes from udp/[192.168.1.166]:5090 at 20:43:09.388234:
  837. ------------------------------------------------------------------------
  838. ACK sip:gw+tolinphone@192.168.1.166:5080;transport=udp;gw=tolinphone SIP/2.0
  839. Via: SIP/2.0/UDP 192.168.1.166:5090;rport;branch=z9hG4bK.8luI0J6IH
  840. From: <sip:98765432100@192.168.1.166>;tag=Sp1~o~3
  841. To: "FreeSWITCH" <sip:cluecon@192.168.1.166>;tag=39N8pX577t5Dg
  842. CSeq: 111 ACK
  843. Call-ID: 7134b16b-d88a-123a-519f-dc1ba1ed361d
  844. Max-Forwards: 70
  845. User-Agent: Linphone/3.12.0 (belle-sip/1.6.3)
  846.  
  847. 2021-12-15 20:43:09.387185 95.50% [INFO] switch_vpx.c:564 config: vp8
  848. 2021-12-15 20:43:09.387185 95.50% [NOTICE] switch_vpx.c:599 VPX encoder reset (WxH/BW) from 0x0/0 to 352x288/3072
  849. 2021-12-15 20:43:09.387185 95.50% [DEBUG] sofia.c:7499 Channel sofia/external/98765432100 entering state [ready][200]
  850.