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