From ft, 3 Years ago, written in Plain Text.
- view diff
Embed
  1. freeswitch@test> reloadxml
  2. +OK [Success]
  3.  
  4. 2021-12-15 17:05:58.220777 95.53% [INFO] mod_enum.c:887 ENUM Reloaded
  5. 2021-12-15 17:05:58.220777 95.53% [INFO] switch_time.c:1431 Timezone reloaded 1750 definitions
  6. recv 751 bytes from udp/[192.168.1.166]:52290 at 17:06:21.688122:
  7. ------------------------------------------------------------------------
  8. INVITE sip:1001@192.168.1.166;transport=UDP SIP/2.0
  9. Via: SIP/2.0/UDP 192.168.1.166:52290;branch=z9hG4bK-524287-1---ac65e78e544637bf;rport
  10. Max-Forwards: 70
  11. Contact: <sip:1000@192.168.1.166:52290;transport=UDP>
  12. To: <sip:1001@192.168.1.166>
  13. From: <sip:1000@192.168.1.166;transport=UDP>;tag=db7b3813
  14. Call-ID: XYKmeziVHmYs9BWzEvGA3Q..
  15. CSeq: 1 INVITE
  16. Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  17. Content-Type: application/sdp
  18. User-Agent: Z 5.4.12 v2.10.13.2-mod
  19. Allow-Events: presence, kpml, talk
  20. Content-Length: 188
  21.  
  22. v=0
  23. o=Z 1639587981655 1 IN IP4 192.168.1.166
  24. s=Z
  25. c=IN IP4 192.168.1.166
  26. t=0 0
  27. m=audio 8000 RTP/AVP 106
  28. a=rtpmap:106 opus/48000/2
  29. a=fmtp:106 minptime=20; useinbandfec=1
  30. a=sendrecv
  31. 2021-12-15 17:06:21.680738 96.57% [NOTICE] switch_channel.c:1123 New Channel sofia/internal/1000@192.168.1.166 [447378cc-4519-458d-a3cc-8f947e2c86a1]
  32. 2021-12-15 17:06:21.680738 96.57% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1000@192.168.1.166) Running State Change CS_NEW (Cur 1 Tot 5)
  33. 2021-12-15 17:06:21.680738 96.57% [INFO] sofia.c:10462 sofia/internal/1000@192.168.1.166 receiving invite from 192.168.1.166:52290 version: 1.10.8-dev git e428be3 2021-11-17 19:23:28Z 64bit call-id: XYKmeziVHmYs9BWzEvGA3Q..
  34. 2021-12-15 17:06:21.680738 96.57% [DEBUG] sofia.c:10556 verifying acl "domains" for ip/port 192.168.1.166:0.
  35. 2021-12-15 17:06:21.680738 96.57% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/1000@192.168.1.166) State NEW
  36. send 861 bytes to udp/[192.168.1.166]:52290 at 17:06:21.689131:
  37. ------------------------------------------------------------------------
  38. SIP/2.0 407 Proxy Authentication Required
  39. Via: SIP/2.0/UDP 192.168.1.166:52290;branch=z9hG4bK-524287-1---ac65e78e544637bf;rport=52290
  40. From: <sip:1000@192.168.1.166;transport=UDP>;tag=db7b3813
  41. To: <sip:1001@192.168.1.166>;tag=rvgNme2QcKQrQ
  42. Call-ID: XYKmeziVHmYs9BWzEvGA3Q..
  43. CSeq: 1 INVITE
  44. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  45. Accept: application/sdp
  46. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  47. Supported: timer, path, replaces
  48. Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  49. Proxy-Authenticate: Digest realm="192.168.1.166", nonce="284893a6-2383-4698-8670-47c1a11ea49d", algorithm=MD5, qop="auth"
  50. Content-Length: 0
  51.  
  52. 2021-12-15 17:06:21.680738 96.57% [DEBUG] sofia.c:2419 detaching session 447378cc-4519-458d-a3cc-8f947e2c86a1
  53. recv 331 bytes from udp/[192.168.1.166]:52290 at 17:06:21.689546:
  54. ------------------------------------------------------------------------
  55. ACK sip:1001@192.168.1.166;transport=UDP SIP/2.0
  56. Via: SIP/2.0/UDP 192.168.1.166:52290;branch=z9hG4bK-524287-1---ac65e78e544637bf;rport
  57. Max-Forwards: 70
  58. To: <sip:1001@192.168.1.166>;tag=rvgNme2QcKQrQ
  59. From: <sip:1000@192.168.1.166;transport=UDP>;tag=db7b3813
  60. Call-ID: XYKmeziVHmYs9BWzEvGA3Q..
  61. CSeq: 1 ACK
  62. Content-Length: 0
  63.  
  64. recv 1027 bytes from udp/[192.168.1.166]:52290 at 17:06:21.689685:
  65. ------------------------------------------------------------------------
  66. INVITE sip:1001@192.168.1.166;transport=UDP SIP/2.0
  67. Via: SIP/2.0/UDP 192.168.1.166:52290;branch=z9hG4bK-524287-1---31ab86c205ba1b61;rport
  68. Max-Forwards: 70
  69. Contact: <sip:1000@192.168.1.166:52290;transport=UDP>
  70. To: <sip:1001@192.168.1.166>
  71. From: <sip:1000@192.168.1.166;transport=UDP>;tag=db7b3813
  72. Call-ID: XYKmeziVHmYs9BWzEvGA3Q..
  73. CSeq: 2 INVITE
  74. Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  75. Content-Type: application/sdp
  76. Proxy-Authorization: Digest username="1000",realm="192.168.1.166",nonce="284893a6-2383-4698-8670-47c1a11ea49d",uri="sip:1001@192.168.1.166;transport=UDP",response="918de2ec3937363ada3c4868f2f2b00a",cnonce="c023a2fbaaa0811b225b654726dcf3fd",nc=00000001,qop=auth,algorithm=MD5
  77. User-Agent: Z 5.4.12 v2.10.13.2-mod
  78. Allow-Events: presence, kpml, talk
  79. Content-Length: 188
  80.  
  81. v=0
  82. o=Z 1639587981655 1 IN IP4 192.168.1.166
  83. s=Z
  84. c=IN IP4 192.168.1.166
  85. t=0 0
  86. m=audio 8000 RTP/AVP 106
  87. a=rtpmap:106 opus/48000/2
  88. a=fmtp:106 minptime=20; useinbandfec=1
  89. a=sendrecv
  90. 2021-12-15 17:06:21.680738 96.57% [DEBUG] sofia.c:2532 Re-attaching to session 447378cc-4519-458d-a3cc-8f947e2c86a1
  91. 2021-12-15 17:06:21.700737 96.57% [INFO] sofia.c:10462 sofia/internal/1000@192.168.1.166 receiving invite from 192.168.1.166:52290 version: 1.10.8-dev git e428be3 2021-11-17 19:23:28Z 64bit call-id: XYKmeziVHmYs9BWzEvGA3Q..
  92. 2021-12-15 17:06:21.700737 96.57% [DEBUG] sofia.c:10556 verifying acl "domains" for ip/port 192.168.1.166:0.
  93. 2021-12-15 17:06:21.700737 96.57% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [received][100]
  94. 2021-12-15 17:06:21.700737 96.57% [DEBUG] sofia.c:7509 Remote SDP:
  95. v=0
  96. o=Z 1639587981655 1 IN IP4 192.168.1.166
  97. s=Z
  98. c=IN IP4 192.168.1.166
  99. t=0 0
  100. m=audio 8000 RTP/AVP 106
  101. a=rtpmap:106 opus/48000/2
  102. a=fmtp:106 minptime=20; useinbandfec=1
  103.  
  104. 2021-12-15 17:06:21.700737 96.57% [DEBUG] sofia.c:7912 (sofia/internal/1000@192.168.1.166) State Change CS_NEW -> CS_INIT
  105. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1000@192.168.1.166) Running State Change CS_INIT (Cur 1 Tot 5)
  106. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/1000@192.168.1.166) State INIT
  107. 2021-12-15 17:06:21.700737 96.57% [DEBUG] mod_sofia.c:97 sofia/internal/1000@192.168.1.166 SOFIA INIT
  108. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:40 sofia/internal/1000@192.168.1.166 Standard INIT
  109. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1000@192.168.1.166) State Change CS_INIT -> CS_ROUTING
  110. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/1000@192.168.1.166) State INIT going to sleep
  111. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1000@192.168.1.166) Running State Change CS_ROUTING (Cur 1 Tot 5)
  112. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_channel.c:2380 (sofia/internal/1000@192.168.1.166) Callstate Change DOWN -> RINGING
  113. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1000@192.168.1.166) State ROUTING
  114. 2021-12-15 17:06:21.700737 96.57% [DEBUG] mod_sofia.c:158 sofia/internal/1000@192.168.1.166 SOFIA ROUTING
  115. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:230 sofia/internal/1000@192.168.1.166 Standard ROUTING
  116. 2021-12-15 17:06:21.700737 96.57% [INFO] mod_dialplan_xml.c:639 Processing 1000 <1000>->1001 in context default
  117. send 357 bytes to udp/[192.168.1.166]:52290 at 17:06:21.710398:
  118. ------------------------------------------------------------------------
  119. SIP/2.0 100 Trying
  120. Via: SIP/2.0/UDP 192.168.1.166:52290;branch=z9hG4bK-524287-1---31ab86c205ba1b61;rport=52290
  121. From: <sip:1000@192.168.1.166;transport=UDP>;tag=db7b3813
  122. To: <sip:1001@192.168.1.166>
  123. Call-ID: XYKmeziVHmYs9BWzEvGA3Q..
  124. CSeq: 2 INVITE
  125. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  126. Content-Length: 0
  127.  
  128. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->unloop] continue=false
  129. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  130. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  131. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->tod_example] continue=true
  132. Dialplan: sofia/internal/1000@192.168.1.166 Date/Time Match (PASS) [tod_example] break=on-false
  133. Dialplan: sofia/internal/1000@192.168.1.166 Action set(open=true)
  134. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->holiday_example] continue=true
  135. Dialplan: sofia/internal/1000@192.168.1.166 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  136. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->global-intercept] continue=false
  137. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [global-intercept] destination_number(1001) =~ /^886$/ break=on-false
  138. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->group-intercept] continue=false
  139. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [group-intercept] destination_number(1001) =~ /^\*8$/ break=on-false
  140. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->intercept-ext] continue=false
  141. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [intercept-ext] destination_number(1001) =~ /^\*\*(\d+)$/ break=on-false
  142. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->redial] continue=false
  143. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [redial] destination_number(1001) =~ /^(redial|870)$/ break=on-false
  144. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->global] continue=true
  145. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  146. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [global] ${default_password}(1234) =~ /^1234$/ break=never
  147. Dialplan: sofia/internal/1000@192.168.1.166 Action log(CRIT WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING )
  148. Dialplan: sofia/internal/1000@192.168.1.166 Action log(CRIT Open /usr/local/freeswitch/conf/vars.xml and change the default_password.)
  149. Dialplan: sofia/internal/1000@192.168.1.166 Action log(CRIT Once changed type 'reloadxml' at the console.)
  150. Dialplan: sofia/internal/1000@192.168.1.166 Action log(CRIT WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING )
  151. Dialplan: sofia/internal/1000@192.168.1.166 Action sleep(10000)
  152. 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
  153. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  154. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [global] ${switch_r_sdp}(v=0
  155. o=Z 1639587981655 1 IN IP4 192.168.1.166
  156. s=Z
  157. c=IN IP4 192.168.1.166
  158. t=0 0
  159. m=audio 8000 RTP/AVP 106
  160. a=rtpmap:106 opus/48000/2
  161. a=fmtp:106 minptime=20; useinbandfec=1
  162. ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
  163. Dialplan: sofia/internal/1000@192.168.1.166 Absolute Condition [global]
  164. Dialplan: sofia/internal/1000@192.168.1.166 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
  165. Dialplan: sofia/internal/1000@192.168.1.166 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  166. Dialplan: sofia/internal/1000@192.168.1.166 Action hash(insert/${domain_name}-last_dial/global/${uuid})
  167. Dialplan: sofia/internal/1000@192.168.1.166 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  168. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->snom-demo-2] continue=false
  169. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [snom-demo-2] destination_number(1001) =~ /^9001$/ break=on-false
  170. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->snom-demo-1] continue=false
  171. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [snom-demo-1] destination_number(1001) =~ /^9000$/ break=on-false
  172. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->eavesdrop] continue=false
  173. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [eavesdrop] destination_number(1001) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  174. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->eavesdrop] continue=false
  175. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [eavesdrop] destination_number(1001) =~ /^779$/ break=on-false
  176. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->call_return] continue=false
  177. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [call_return] destination_number(1001) =~ /^\*69$|^869$|^lcr$/ break=on-false
  178. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->del-group] continue=false
  179. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [del-group] destination_number(1001) =~ /^80(\d{2})$/ break=on-false
  180. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->add-group] continue=false
  181. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [add-group] destination_number(1001) =~ /^81(\d{2})$/ break=on-false
  182. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->call-group-simo] continue=false
  183. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [call-group-simo] destination_number(1001) =~ /^82(\d{2})$/ break=on-false
  184. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->call-group-order] continue=false
  185. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [call-group-order] destination_number(1001) =~ /^83(\d{2})$/ break=on-false
  186. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->extension-intercom] continue=false
  187. Dialplan: sofia/internal/1000@192.168.1.166 Regex (FAIL) [extension-intercom] destination_number(1001) =~ /^8(10[01][0-9])$/ break=on-false
  188. Dialplan: sofia/internal/1000@192.168.1.166 parsing [default->Local_Extension] continue=false
  189. Dialplan: sofia/internal/1000@192.168.1.166 Regex (PASS) [Local_Extension] destination_number(1001) =~ /^(10[01][0-9])$/ break=on-false
  190. Dialplan: sofia/internal/1000@192.168.1.166 Action export(dialed_extension=1001)
  191. Dialplan: sofia/internal/1000@192.168.1.166 Action bind_meta_app(1 b s execute_extension::dx XML features)
  192. Dialplan: sofia/internal/1000@192.168.1.166 Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav)
  193. Dialplan: sofia/internal/1000@192.168.1.166 Action bind_meta_app(3 b s execute_extension::cf XML features)
  194. Dialplan: sofia/internal/1000@192.168.1.166 Action bind_meta_app(4 b s execute_extension::att_xfer XML features)
  195. Dialplan: sofia/internal/1000@192.168.1.166 Action set(ringback=${us-ring})
  196. Dialplan: sofia/internal/1000@192.168.1.166 Action set(transfer_ringback=local_stream://moh)
  197. Dialplan: sofia/internal/1000@192.168.1.166 Action set(call_timeout=30)
  198. Dialplan: sofia/internal/1000@192.168.1.166 Action set(hangup_after_bridge=true)
  199. Dialplan: sofia/internal/1000@192.168.1.166 Action set(continue_on_fail=true)
  200. Dialplan: sofia/internal/1000@192.168.1.166 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
  201. Dialplan: sofia/internal/1000@192.168.1.166 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
  202. Dialplan: sofia/internal/1000@192.168.1.166 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)})
  203. Dialplan: sofia/internal/1000@192.168.1.166 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid})
  204. Dialplan: sofia/internal/1000@192.168.1.166 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid})
  205. Dialplan: sofia/internal/1000@192.168.1.166 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})
  206. Dialplan: sofia/internal/1000@192.168.1.166 Action bridge({absolute_codec_string=OPUS}user/${dialed_extension}@${domain_name})
  207. Dialplan: sofia/internal/1000@192.168.1.166 Action answer()
  208. Dialplan: sofia/internal/1000@192.168.1.166 Action sleep(1000)
  209. Dialplan: sofia/internal/1000@192.168.1.166 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension})
  210. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/1000@192.168.1.166) State Change CS_ROUTING -> CS_EXECUTE
  211. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1000@192.168.1.166) State ROUTING going to sleep
  212. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1000@192.168.1.166) Running State Change CS_EXECUTE (Cur 1 Tot 5)
  213. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/1000@192.168.1.166) State EXECUTE
  214. 2021-12-15 17:06:21.700737 96.57% [DEBUG] mod_sofia.c:213 sofia/internal/1000@192.168.1.166 SOFIA EXECUTE
  215. 2021-12-15 17:06:21.700737 96.57% [DEBUG] switch_core_state_machine.c:323 sofia/internal/1000@192.168.1.166 Standard EXECUTE
  216. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 set(open=true)
  217. 2021-12-15 17:06:21.700737 96.57% [DEBUG] mod_dptools.c:1685 SET sofia/internal/1000@192.168.1.166 [open]=[true]
  218. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 log(CRIT WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING )
  219. 2021-12-15 17:06:21.700737 96.57% [CRIT] mod_dptools.c:1879 WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING
  220. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 log(CRIT Open /usr/local/freeswitch/conf/vars.xml and change the default_password.)
  221. 2021-12-15 17:06:21.700737 96.57% [CRIT] mod_dptools.c:1879 Open /usr/local/freeswitch/conf/vars.xml and change the default_password.
  222. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 log(CRIT Once changed type 'reloadxml' at the console.)
  223. 2021-12-15 17:06:21.700737 96.57% [CRIT] mod_dptools.c:1879 Once changed type 'reloadxml' at the console.
  224. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 log(CRIT WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING )
  225. 2021-12-15 17:06:21.700737 96.57% [CRIT] mod_dptools.c:1879 WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING
  226. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 sleep(10000)
  227. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 hash(insert/192.168.1.166-spymap/1000/447378cc-4519-458d-a3cc-8f947e2c86a1)
  228. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 hash(insert/192.168.1.166-last_dial/1000/1001)
  229. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 hash(insert/192.168.1.166-last_dial/global/447378cc-4519-458d-a3cc-8f947e2c86a1)
  230. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 export(RFC2822_DATE=Wed, 15 Dec 2021 17:06:31 +0000)
  231. 2021-12-15 17:06:31.760772 96.40% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 15 Dec 2021 17:06:31 +0000]
  232. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 export(dialed_extension=1001)
  233. 2021-12-15 17:06:31.760772 96.40% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [dialed_extension]=[1001]
  234. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 bind_meta_app(1 b s execute_extension::dx XML features)
  235. 2021-12-15 17:06:31.760772 96.40% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *1 execute_extension::dx XML features
  236. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/1000.2021-12-15-17-06-31.wav)
  237. 2021-12-15 17:06:31.760772 96.40% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/1000.2021-12-15-17-06-31.wav
  238. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 bind_meta_app(3 b s execute_extension::cf XML features)
  239. 2021-12-15 17:06:31.780749 96.40% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *3 execute_extension::cf XML features
  240. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 bind_meta_app(4 b s execute_extension::att_xfer XML features)
  241. 2021-12-15 17:06:31.780749 96.40% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *4 execute_extension::att_xfer XML features
  242. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 set(ringback=%(2000,4000,440,480))
  243. 2021-12-15 17:06:31.780749 96.40% [DEBUG] mod_dptools.c:1685 SET sofia/internal/1000@192.168.1.166 [ringback]=[%(2000,4000,440,480)]
  244. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 set(transfer_ringback=local_stream://moh)
  245. 2021-12-15 17:06:31.780749 96.40% [DEBUG] mod_dptools.c:1685 SET sofia/internal/1000@192.168.1.166 [transfer_ringback]=[local_stream://moh]
  246. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 set(call_timeout=30)
  247. 2021-12-15 17:06:31.780749 96.40% [DEBUG] mod_dptools.c:1685 SET sofia/internal/1000@192.168.1.166 [call_timeout]=[30]
  248. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 set(hangup_after_bridge=true)
  249. 2021-12-15 17:06:31.780749 96.40% [DEBUG] mod_dptools.c:1685 SET sofia/internal/1000@192.168.1.166 [hangup_after_bridge]=[true]
  250. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 set(continue_on_fail=true)
  251. 2021-12-15 17:06:31.780749 96.40% [DEBUG] mod_dptools.c:1685 SET sofia/internal/1000@192.168.1.166 [continue_on_fail]=[true]
  252. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 hash(insert/192.168.1.166-call_return/1001/1000)
  253. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 hash(insert/192.168.1.166-last_dial_ext/1001/447378cc-4519-458d-a3cc-8f947e2c86a1)
  254. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 set(called_party_callgroup=techsupport)
  255. 2021-12-15 17:06:31.780749 96.40% [DEBUG] mod_dptools.c:1685 SET sofia/internal/1000@192.168.1.166 [called_party_callgroup]=[techsupport]
  256. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 hash(insert/192.168.1.166-last_dial_ext/techsupport/447378cc-4519-458d-a3cc-8f947e2c86a1)
  257. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 hash(insert/192.168.1.166-last_dial_ext/global/447378cc-4519-458d-a3cc-8f947e2c86a1)
  258. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 hash(insert/192.168.1.166-last_dial/techsupport/447378cc-4519-458d-a3cc-8f947e2c86a1)
  259. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 bridge({absolute_codec_string=OPUS}user/1001@192.168.1.166)
  260. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_channel.c:1269 sofia/internal/1000@192.168.1.166 EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 15 Dec 2021 17:06:31 +0000] to event
  261. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_channel.c:1269 sofia/internal/1000@192.168.1.166 EXPORTING[export_vars] [dialed_extension]=[1001] to event
  262. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  263. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_channel.c:1269 sofia/internal/1000@192.168.1.166 EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 15 Dec 2021 17:06:31 +0000] to event
  264. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_channel.c:1269 sofia/internal/1000@192.168.1.166 EXPORTING[export_vars] [dialed_extension]=[1001] to event
  265. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  266. 2021-12-15 17:06:31.780749 96.40% [NOTICE] switch_channel.c:1123 New Channel sofia/internal/1001@192.168.1.166:5090 [1712a177-79b4-4d70-9612-c31d28baf345]
  267. 2021-12-15 17:06:31.780749 96.40% [DEBUG] mod_sofia.c:5121 (sofia/internal/1001@192.168.1.166:5090) State Change CS_NEW -> CS_INIT
  268. 2021-12-15 17:06:31.780749 96.40% [NOTICE] switch_ivr_originate.c:3039 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
  269. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@192.168.1.166:5090) Running State Change CS_INIT (Cur 2 Tot 6)
  270. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/1001@192.168.1.166:5090) State INIT
  271. 2021-12-15 17:06:31.780749 96.40% [DEBUG] mod_sofia.c:97 sofia/internal/1001@192.168.1.166:5090 SOFIA INIT
  272. 2021-12-15 17:06:31.780749 96.40% [INFO] sofia_glue.c:1651 sofia/internal/1001@192.168.1.166:5090 sending invite call-id: (null)
  273. 2021-12-15 17:06:31.780749 96.40% [DEBUG] sofia_glue.c:1654 sofia/internal/1001@192.168.1.166:5090 sending invite version: 1.10.8-dev git e428be3 2021-11-17 19:23:28Z 64bit
  274. Local SDP:
  275. v=0
  276. o=FreeSWITCH 1639566591 1639566592 IN IP4 192.168.1.166
  277. s=FreeSWITCH
  278. c=IN IP4 192.168.1.166
  279. t=0 0
  280. m=audio 21400 RTP/AVP 102 101
  281. a=rtpmap:102 opus/48000/2
  282. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  283. a=rtpmap:101 telephone-event/48000
  284. a=fmtp:101 0-15
  285. a=ptime:20
  286. a=sendrecv
  287.  
  288. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@192.168.1.166:5090 Standard INIT
  289. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@192.168.1.166:5090) State Change CS_INIT -> CS_ROUTING
  290. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/1001@192.168.1.166:5090) State INIT going to sleep
  291. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@192.168.1.166:5090) Running State Change CS_ROUTING (Cur 2 Tot 6)
  292. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@192.168.1.166:5090) State ROUTING
  293. 2021-12-15 17:06:31.780749 96.40% [DEBUG] mod_sofia.c:158 sofia/internal/1001@192.168.1.166:5090 SOFIA ROUTING
  294. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1001@192.168.1.166:5090) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  295. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@192.168.1.166:5090) State ROUTING going to sleep
  296. send 1333 bytes to udp/[192.168.1.166]:5090 at 17:06:31.786960:
  297. ------------------------------------------------------------------------
  298. INVITE sip:1001@192.168.1.166:5090;transport=udp SIP/2.0
  299. Via: SIP/2.0/UDP 192.168.1.166;rport;branch=z9hG4bKmrQ8jB9N6g0DN
  300. Max-Forwards: 69
  301. From: "Extension 1000" <sip:1000@192.168.1.166>;tag=te36Q43y643Xe
  302. To: <sip:1001@192.168.1.166:5090;transport=udp>
  303. Call-ID: 3128581e-d86c-123a-3585-dc1ba1ed361d
  304. CSeq: 45240203 INVITE
  305. Contact: <sip:mod_sofia@192.168.1.166:5060>
  306. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  307. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  308. Supported: timer, path, replaces
  309. Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  310. Content-Type: application/sdp
  311. Content-Disposition: session
  312. Content-Length: 341
  313. X-FS-Support: update_display,send_info
  314. Remote-Party-ID: "Extension 1000" <sip:1000@192.168.1.166>;party=calling;screen=yes;privacy=off
  315.  
  316. v=0
  317. o=FreeSWITCH 1639566591 1639566592 IN IP4 192.168.1.166
  318. s=FreeSWITCH
  319. c=IN IP4 192.168.1.166
  320. t=0 0
  321. m=audio 21400 RTP/AVP 102 101
  322. a=rtpmap:102 opus/48000/2
  323. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  324. a=rtpmap:101 telephone-event/48000
  325. a=fmtp:101 0-15
  326. a=ptime:20
  327. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@192.168.1.166:5090) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 6)
  328. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_core_state_machine.c:659 (sofia/internal/1001@192.168.1.166:5090) State CONSUME_MEDIA
  329. 2021-12-15 17:06:31.780749 96.40% [DEBUG] switch_core_state_machine.c:659 (sofia/internal/1001@192.168.1.166:5090) State CONSUME_MEDIA going to sleep
  330. 2021-12-15 17:06:31.780749 96.40% [DEBUG] sofia.c:7499 Channel sofia/internal/1001@192.168.1.166:5090 entering state [calling][0]
  331. recv 274 bytes from udp/[192.168.1.166]:5090 at 17:06:31.797907:
  332. ------------------------------------------------------------------------
  333. SIP/2.0 100 Trying
  334. Via: SIP/2.0/UDP 192.168.1.166;rport;branch=z9hG4bKmrQ8jB9N6g0DN
  335. From: "Extension 1000" <sip:1000@192.168.1.166>;tag=te36Q43y643Xe
  336. To: <sip:1001@192.168.1.166:5090;transport=udp>
  337. Call-ID: 3128581e-d86c-123a-3585-dc1ba1ed361d
  338. CSeq: 45240203 INVITE
  339.  
  340. recv 365 bytes from udp/[192.168.1.166]:5090 at 17:06:32.020371:
  341. ------------------------------------------------------------------------
  342. SIP/2.0 180 Ringing
  343. Via: SIP/2.0/UDP 192.168.1.166;rport;branch=z9hG4bKmrQ8jB9N6g0DN
  344. From: "Extension 1000" <sip:1000@192.168.1.166>;tag=te36Q43y643Xe
  345. To: <sip:1001@192.168.1.166:5090;transport=udp>;tag=wHUTOwc
  346. Call-ID: 3128581e-d86c-123a-3585-dc1ba1ed361d
  347. CSeq: 45240203 INVITE
  348. User-Agent: Linphone/3.12.0 (belle-sip/1.6.3)
  349. Supported: replaces, outbound
  350.  
  351. 2021-12-15 17:06:32.020735 96.40% [DEBUG] sofia.c:7499 Channel sofia/internal/1001@192.168.1.166:5090 entering state [proceeding][180]
  352. 2021-12-15 17:06:32.020735 96.40% [NOTICE] sofia.c:7610 Ring-Ready sofia/internal/1001@192.168.1.166:5090!
  353. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_channel.c:3494 (sofia/internal/1001@192.168.1.166:5090) Callstate Change DOWN -> RINGING
  354. 2021-12-15 17:06:32.020735 96.40% [INFO] switch_ivr_originate.c:1295 Sending early media
  355. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:106:48000:20:0:1]/[opus:116:48000:20:0:1]
  356. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_media.c:5707 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  357. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:106:48000:20:0:1]/[G722:9:8000:20:64000:1]
  358. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:106:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
  359. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:106:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
  360. 2021-12-15 17:06:32.020735 96.40% [DEBUG] mod_opus.c:619 Opus encoder: set bitrate to local settings [72000bps]
  361. 2021-12-15 17:06:32.020735 96.40% [DEBUG] mod_opus.c:619 Opus encoder: set bitrate to local settings [72000bps]
  362. 2021-12-15 17:06:32.020735 96.40% [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
  363. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_codec.c:111 sofia/internal/1000@192.168.1.166 Original read codec set to opus:116
  364. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_media.c:5946 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
  365. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_media.c:5975 sofia/internal/1000@192.168.1.166 Set 2833 dtmf send payload to 101 recv payload to 101
  366. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_media.c:8779 AUDIO RTP [sofia/internal/1000@192.168.1.166] 192.168.1.166 port 20366 -> 192.168.1.166 port 8000 codec: 106 ms: 20
  367. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 960 bytes per 20ms
  368. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_media.c:9091 sofia/internal/1000@192.168.1.166 Set 2833 dtmf send payload to 101
  369. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_media.c:9098 sofia/internal/1000@192.168.1.166 Set 2833 dtmf receive payload to 101
  370. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_media.c:9121 sofia/internal/1000@192.168.1.166 Set rtp dtmf delay to 40
  371. 2021-12-15 17:06:32.020735 96.40% [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1000@192.168.1.166!
  372. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_channel.c:3622 (sofia/internal/1000@192.168.1.166) Callstate Change RINGING -> EARLY
  373. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_media.c:8761 Audio params are unchanged for sofia/internal/1000@192.168.1.166.
  374. 2021-12-15 17:06:32.020735 96.40% [DEBUG] mod_sofia.c:2610 Ring SDP:
  375. v=0
  376. o=FreeSWITCH 1639567626 1639567627 IN IP4 192.168.1.166
  377. s=FreeSWITCH
  378. c=IN IP4 192.168.1.166
  379. t=0 0
  380. m=audio 20366 RTP/AVP 106 101
  381. a=rtpmap:106 opus/48000/2
  382. a=fmtp:106 useinbandfec=1; minptime=20
  383. a=rtpmap:101 telephone-event/8000
  384. a=fmtp:101 0-15
  385. a=ptime:20
  386. a=sendrecv
  387.  
  388. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_ivr_originate.c:1353 Raw Codec Activation Success L16@48000hz 1 channel 20ms
  389. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_core_codec.c:223 sofia/internal/1000@192.168.1.166 Push codec L16:100
  390. 2021-12-15 17:06:32.020735 96.40% [DEBUG] switch_ivr_originate.c:1427 Play Ringback Tone [%(2000,4000,440,480)]
  391. send 1197 bytes to udp/[192.168.1.166]:52290 at 17:06:32.033266:
  392. ------------------------------------------------------------------------
  393. SIP/2.0 183 Session Progress
  394. Via: SIP/2.0/UDP 192.168.1.166:52290;branch=z9hG4bK-524287-1---31ab86c205ba1b61;rport=52290
  395. From: <sip:1000@192.168.1.166;transport=UDP>;tag=db7b3813
  396. To: <sip:1001@192.168.1.166>;tag=S59Dp9jU9UDBK
  397. Call-ID: XYKmeziVHmYs9BWzEvGA3Q..
  398. CSeq: 2 INVITE
  399. Contact: <sip:1001@192.168.1.166:5060;transport=udp>
  400. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  401. Accept: application/sdp
  402. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  403. Supported: timer, path, replaces
  404. Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  405. Content-Type: application/sdp
  406. Content-Disposition: session
  407. Content-Length: 269
  408. Remote-Party-ID: "1001" <sip:1001@192.168.1.166>;party=calling;privacy=off;screen=no
  409.  
  410. v=0
  411. o=FreeSWITCH 1639567626 1639567627 IN IP4 192.168.1.166
  412. s=FreeSWITCH
  413. c=IN IP4 192.168.1.166
  414. t=0 0
  415. m=audio 20366 RTP/AVP 106 101
  416. a=rtpmap:106 opus/48000/2
  417. a=fmtp:106 useinbandfec=1; minptime=20
  418. a=rtpmap:101 telephone-event/8000
  419. a=fmtp:101 0-15
  420. a=ptime:20
  421. 2021-12-15 17:06:32.040736 96.40% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [early][183]
  422. 2021-12-15 17:06:32.300762 96.40% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  423. recv 827 bytes from udp/[192.168.1.166]:5090 at 17:06:33.301220:
  424. ------------------------------------------------------------------------
  425. SIP/2.0 200 Ok
  426. Via: SIP/2.0/UDP 192.168.1.166;rport;branch=z9hG4bKmrQ8jB9N6g0DN
  427. From: "Extension 1000" <sip:1000@192.168.1.166>;tag=te36Q43y643Xe
  428. To: <sip:1001@192.168.1.166:5090;transport=udp>;tag=wHUTOwc
  429. Call-ID: 3128581e-d86c-123a-3585-dc1ba1ed361d
  430. CSeq: 45240203 INVITE
  431. User-Agent: Linphone/3.12.0 (belle-sip/1.6.3)
  432. Supported: replaces, outbound
  433. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
  434. Contact: "Li" <sip:9999@192.168.1.166:5090;transport=udp>;+sip.instance="<urn:uuid:b8fee09a-4790-44f6-88d1-69c76cc5c84a>"
  435. Content-Type: application/sdp
  436. Content-Length: 201
  437.  
  438. v=0
  439. o=9999 1759 48 IN IP4 192.168.1.166
  440. s=Talk
  441. c=IN IP4 192.168.1.166
  442. t=0 0
  443. m=audio 7078 RTP/AVP 102 101
  444. a=rtpmap:102 opus/48000/2
  445. a=fmtp:102 useinbandfec=1
  446. a=rtpmap:101 telephone-event/48000
  447. 2021-12-15 17:06:33.300778 96.23% [DEBUG] sofia.c:7499 Channel sofia/internal/1001@192.168.1.166:5090 entering state [completing][200]
  448. 2021-12-15 17:06:33.300778 96.23% [DEBUG] sofia.c:7509 Remote SDP:
  449. v=0
  450. o=9999 1759 48 IN IP4 192.168.1.166
  451. s=Talk
  452. c=IN IP4 192.168.1.166
  453. t=0 0
  454. m=audio 7078 RTP/AVP 102 101
  455. a=rtpmap:102 opus/48000/2
  456. a=fmtp:102 useinbandfec=1
  457. a=rtpmap:101 telephone-event/48000
  458.  
  459. send 400 bytes to udp/[192.168.1.166]:5090 at 17:06:33.304719:
  460. ------------------------------------------------------------------------
  461. ACK sip:9999@192.168.1.166:5090;transport=udp SIP/2.0
  462. Via: SIP/2.0/UDP 192.168.1.166;rport;branch=z9hG4bKN1g1m6SS3Sp0g
  463. Max-Forwards: 70
  464. From: "Extension 1000" <sip:1000@192.168.1.166>;tag=te36Q43y643Xe
  465. To: <sip:1001@192.168.1.166:5090;transport=udp>;tag=wHUTOwc
  466. Call-ID: 3128581e-d86c-123a-3585-dc1ba1ed361d
  467. CSeq: 45240203 ACK
  468. Contact: <sip:mod_sofia@192.168.1.166:5060>
  469. Content-Length: 0
  470.  
  471. 2021-12-15 17:06:33.300778 96.23% [DEBUG] sofia.c:7499 Channel sofia/internal/1001@192.168.1.166:5090 entering state [ready][200]
  472. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
  473. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_media.c:5707 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  474. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_media.c:5568 Set telephone-event payload to 101@48000
  475. 2021-12-15 17:06:33.300778 96.23% [DEBUG] mod_opus.c:619 Opus encoder: set bitrate to local settings [72000bps]
  476. 2021-12-15 17:06:33.300778 96.23% [DEBUG] mod_opus.c:619 Opus encoder: set bitrate to local settings [72000bps]
  477. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_media.c:3872 Set Codec sofia/internal/1001@192.168.1.166:5090 opus/48000 20 ms 960 samples 0 bits 1 channels
  478. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_codec.c:111 sofia/internal/1001@192.168.1.166:5090 Original read codec set to opus:116
  479. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_media.c:5917 Set telephone-event payload to 101@48000
  480. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_media.c:5975 sofia/internal/1001@192.168.1.166:5090 Set 2833 dtmf send payload to 101 recv payload to 101
  481. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_media.c:8779 AUDIO RTP [sofia/internal/1001@192.168.1.166:5090] 192.168.1.166 port 21400 -> 192.168.1.166 port 7078 codec: 102 ms: 20
  482. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 960 bytes per 20ms
  483. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_media.c:9091 sofia/internal/1001@192.168.1.166:5090 Set 2833 dtmf send payload to 101
  484. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_media.c:9098 sofia/internal/1001@192.168.1.166:5090 Set 2833 dtmf receive payload to 101
  485. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_media.c:9121 sofia/internal/1001@192.168.1.166:5090 Set rtp dtmf delay to 40
  486. 2021-12-15 17:06:33.300778 96.23% [NOTICE] sofia.c:8683 Channel [sofia/internal/1001@192.168.1.166:5090] has been answered
  487. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_channel.c:3950 (sofia/internal/1001@192.168.1.166:5090) Callstate Change RINGING -> ACTIVE
  488. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_codec.c:248 sofia/internal/1000@192.168.1.166 Restore previous codec opus:116.
  489. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_core_media.c:8761 Audio params are unchanged for sofia/internal/1000@192.168.1.166.
  490. 2021-12-15 17:06:33.300778 96.23% [DEBUG] mod_sofia.c:913 Local SDP sofia/internal/1000@192.168.1.166:
  491. v=0
  492. o=FreeSWITCH 1639567626 1639567628 IN IP4 192.168.1.166
  493. s=FreeSWITCH
  494. c=IN IP4 192.168.1.166
  495. t=0 0
  496. m=audio 20366 RTP/AVP 106 101
  497. a=rtpmap:106 opus/48000/2
  498. a=fmtp:106 useinbandfec=1; minptime=20
  499. a=rtpmap:101 telephone-event/8000
  500. a=fmtp:101 0-15
  501. a=ptime:20
  502. a=sendrecv
  503.  
  504. send 1167 bytes to udp/[192.168.1.166]:52290 at 17:06:33.313107:
  505. ------------------------------------------------------------------------
  506. SIP/2.0 200 OK
  507. Via: SIP/2.0/UDP 192.168.1.166:52290;branch=z9hG4bK-524287-1---31ab86c205ba1b61;rport=52290
  508. From: <sip:1000@192.168.1.166;transport=UDP>;tag=db7b3813
  509. To: <sip:1001@192.168.1.166>;tag=S59Dp9jU9UDBK
  510. Call-ID: XYKmeziVHmYs9BWzEvGA3Q..
  511. CSeq: 2 INVITE
  512. Contact: <sip:1001@192.168.1.166:5060;transport=udp>
  513. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  514. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  515. Supported: timer, path, replaces
  516. Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  517. Content-Type: application/sdp
  518. Content-Disposition: session
  519. Content-Length: 269
  520. Remote-Party-ID: "Outbound Call" <sip:1001@192.168.1.166>;party=calling;privacy=off;screen=no
  521.  
  522. v=0
  523. o=FreeSWITCH 1639567626 1639567627 IN IP4 192.168.1.166
  524. s=FreeSWITCH
  525. c=IN IP4 192.168.1.166
  526. t=0 0
  527. m=audio 20366 RTP/AVP 106 101
  528. a=rtpmap:106 opus/48000/2
  529. a=fmtp:106 useinbandfec=1; minptime=20
  530. a=rtpmap:101 telephone-event/8000
  531. a=fmtp:101 0-15
  532. a=ptime:20
  533. 2021-12-15 17:06:33.300778 96.23% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/internal/1000@192.168.1.166] has been answered
  534. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_channel.c:3950 (sofia/internal/1000@192.168.1.166) Callstate Change EARLY -> ACTIVE
  535. recv 428 bytes from udp/[192.168.1.166]:52290 at 17:06:33.314390:
  536. ------------------------------------------------------------------------
  537. ACK sip:1001@192.168.1.166:5060;transport=udp SIP/2.0
  538. Via: SIP/2.0/UDP 192.168.1.166:52290;branch=z9hG4bK-524287-1---dbe10a54f52d6f1b;rport
  539. Max-Forwards: 70
  540. Contact: <sip:1000@192.168.1.166:52290;transport=UDP>
  541. To: <sip:1001@192.168.1.166>;tag=S59Dp9jU9UDBK
  542. From: <sip:1000@192.168.1.166;transport=UDP>;tag=db7b3813
  543. Call-ID: XYKmeziVHmYs9BWzEvGA3Q..
  544. CSeq: 2 ACK
  545. User-Agent: Z 5.4.12 v2.10.13.2-mod
  546. Content-Length: 0
  547.  
  548. 2021-12-15 17:06:33.300778 96.23% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [completed][200]
  549. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/internal/1001@192.168.1.166:5090] Peer UUID: 1712a177-79b4-4d70-9612-c31d28baf345
  550. 2021-12-15 17:06:33.300778 96.23% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [ready][200]
  551. 2021-12-15 17:06:33.300778 96.23% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/internal/1001@192.168.1.166:5090] Peer UUID: 1712a177-79b4-4d70-9612-c31d28baf345
  552. 2021-12-15 17:06:33.320774 96.23% [DEBUG] switch_ivr_bridge.c:1793 (sofia/internal/1001@192.168.1.166:5090) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  553. 2021-12-15 17:06:33.320774 96.23% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@192.168.1.166:5090) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 6)
  554. 2021-12-15 17:06:33.320774 96.23% [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1001@192.168.1.166:5090) State EXCHANGE_MEDIA
  555. 2021-12-15 17:06:33.320774 96.23% [DEBUG] mod_sofia.c:671 SOFIA EXCHANGE_MEDIA
  556. 2021-12-15 17:06:33.360761 96.23% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  557. 2021-12-15 17:06:33.360761 96.23% [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  558. 2021-12-15 17:06:33.360761 96.23% [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
  559. 2021-12-15 17:06:33.360761 96.23% [DEBUG] mod_opus.c:619 Opus encoder: set bitrate to local settings [72000bps]
  560. 2021-12-15 17:06:33.400816 96.23% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  561. 2021-12-15 17:06:33.400816 96.23% [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  562. 2021-12-15 17:06:33.400816 96.23% [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
  563. 2021-12-15 17:06:33.400816 96.23% [DEBUG] mod_opus.c:619 Opus encoder: set bitrate to local settings [72000bps]
  564. recv 849 bytes from udp/[192.168.1.166]:52290 at 17:06:33.755794:
  565. ------------------------------------------------------------------------
  566. REGISTER sip:192.168.1.166;transport=UDP SIP/2.0
  567. Via: SIP/2.0/UDP 192.168.1.166:52290;branch=z9hG4bK-524287-1---fdfd1039f3e65f5d;rport
  568. Max-Forwards: 70
  569. Contact: <sip:1000@192.168.1.166:52290;rinstance=ae9d4de82a2033b7;transport=UDP>
  570. To: <sip:1000@192.168.1.166;transport=UDP>
  571. From: <sip:1000@192.168.1.166;transport=UDP>;tag=98d22a27
  572. Call-ID: Lt6vHw5Hs6EkMiWJdiG20A..
  573. CSeq: 16 REGISTER
  574. Expires: 60
  575. Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  576. User-Agent: Z 5.4.12 v2.10.13.2-mod
  577. Authorization: Digest username="1000",realm="192.168.1.166",nonce="72bed853-18fe-4e23-bfcd-06fdc9d83f6f",uri="sip:192.168.1.166;transport=UDP",response="251b8f8a85c325e7c263fb3ac32a61da",cnonce="390c1f4b821a56d170f767fa17a77f64",nc=00000006,qop=auth,algorithm=MD5
  578. Allow-Events: presence, kpml, talk
  579. Content-Length: 0
  580.  
  581. send 662 bytes to udp/[192.168.1.166]:52290 at 17:06:33.757364:
  582. ------------------------------------------------------------------------
  583. SIP/2.0 200 OK
  584. Via: SIP/2.0/UDP 192.168.1.166:52290;branch=z9hG4bK-524287-1---fdfd1039f3e65f5d;rport=52290
  585. From: <sip:1000@192.168.1.166;transport=UDP>;tag=98d22a27
  586. To: <sip:1000@192.168.1.166;transport=UDP>;tag=UQvZSZm23Dtga
  587. Call-ID: Lt6vHw5Hs6EkMiWJdiG20A..
  588. CSeq: 16 REGISTER
  589. Contact: <sip:1000@192.168.1.166:52290;rinstance=ae9d4de82a2033b7;transport=UDP>;expires=60
  590. Date: Wed, 15 Dec 2021 17:06:33 GMT
  591. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  592. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  593. Supported: timer, path, replaces
  594. Content-Length: 0
  595.  
  596. recv 983 bytes from udp/[192.168.1.166]:5090 at 17:06:36.767092:
  597. ------------------------------------------------------------------------
  598. INVITE sip:mod_sofia@192.168.1.166:5060 SIP/2.0
  599. Via: SIP/2.0/UDP 192.168.1.166:5090;branch=z9hG4bK.n1mo3qZRy;rport
  600. From: <sip:1001@192.168.1.166>;tag=wHUTOwc
  601. To: "Extension 1000" <sip:1000@192.168.1.166>;tag=te36Q43y643Xe
  602. CSeq: 111 INVITE
  603. Call-ID: 3128581e-d86c-123a-3585-dc1ba1ed361d
  604. Max-Forwards: 70
  605. Subject: Call on hold
  606. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
  607. Content-Type: application/sdp
  608. Content-Length: 336
  609. Contact: "Li" <sip:9999@192.168.1.166:5090;transport=udp>;+sip.instance="<urn:uuid:b8fee09a-4790-44f6-88d1-69c76cc5c84a>"
  610. User-Agent: Linphone/3.12.0 (belle-sip/1.6.3)
  611.  
  612. v=0
  613. o=9999 1759 49 IN IP4 192.168.1.166
  614. s=Talk
  615. c=IN IP4 192.168.1.166
  616. t=0 0
  617. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
  618. m=audio 7078 RTP/AVP 102 101
  619. a=rtpmap:102 opus/48000/2
  620. a=fmtp:102 useinbandfec=1
  621. a=rtpmap:101 telephone-event/48000
  622. a=sendonly
  623. a=rtcp-fb:* trr-int 5000
  624. a=rtcp-fb:* ccm tmmbr
  625. send 371 bytes to udp/[192.168.1.166]:5090 at 17:06:36.787143:
  626. ------------------------------------------------------------------------
  627. SIP/2.0 100 Trying
  628. Via: SIP/2.0/UDP 192.168.1.166:5090;branch=z9hG4bK.n1mo3qZRy;rport=5090
  629. From: <sip:1001@192.168.1.166>;tag=wHUTOwc
  630. To: "Extension 1000" <sip:1000@192.168.1.166>;tag=te36Q43y643Xe
  631. Call-ID: 3128581e-d86c-123a-3585-dc1ba1ed361d
  632. CSeq: 111 INVITE
  633. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  634. Content-Length: 0
  635.  
  636. 2021-12-15 17:06:36.780808 95.20% [DEBUG] sofia.c:7499 Channel sofia/internal/1001@192.168.1.166:5090 entering state [received][100]
  637. 2021-12-15 17:06:36.780808 95.20% [DEBUG] sofia.c:7509 Remote SDP:
  638. v=0
  639. o=9999 1759 49 IN IP4 192.168.1.166
  640. s=Talk
  641. c=IN IP4 192.168.1.166
  642. t=0 0
  643. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
  644. m=audio 7078 RTP/AVP 102 101
  645. a=rtpmap:102 opus/48000/2
  646. a=fmtp:102 useinbandfec=1
  647. a=rtpmap:101 telephone-event/48000
  648. a=sendonly
  649. a=rtcp-fb:* trr-int 5000
  650. a=rtcp-fb:* ccm tmmbr
  651.  
  652. 2021-12-15 17:06:36.780808 95.20% [DEBUG] switch_channel.c:1975 (sofia/internal/1001@192.168.1.166:5090) Callstate Change ACTIVE -> HELD
  653. 2021-12-15 17:06:36.920797 95.00% [DEBUG] switch_ivr.c:632 sofia/internal/1000@192.168.1.166 Command Execute [depth=0] playback(local_stream://moh)
  654. EXECUTE [depth=0] sofia/internal/1000@192.168.1.166 playback(local_stream://moh)
  655. 2021-12-15 17:06:36.920797 95.00% [WARNING] mod_local_stream.c:874 Unknown source moh, trying 'default'
  656. 2021-12-15 17:06:36.920797 95.00% [ERR] mod_local_stream.c:882 Unknown source default
  657. 2021-12-15 17:06:37.020803 95.00% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
  658. 2021-12-15 17:06:37.020803 95.00% [DEBUG] switch_core_media.c:5707 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  659. 2021-12-15 17:06:37.020803 95.00% [DEBUG] switch_core_media.c:5568 Set telephone-event payload to 101@48000
  660. 2021-12-15 17:06:37.020803 95.00% [DEBUG] switch_core_media.c:5917 Set telephone-event payload to 101@48000
  661. 2021-12-15 17:06:37.020803 95.00% [DEBUG] switch_core_media.c:5975 sofia/internal/1001@192.168.1.166:5090 Set 2833 dtmf send payload to 101 recv payload to 101
  662. 2021-12-15 17:06:37.040782 95.00% [DEBUG] switch_core_media.c:8761 Audio params are unchanged for sofia/internal/1001@192.168.1.166:5090.
  663. 2021-12-15 17:06:37.040782 95.00% [DEBUG] sofia.c:8454 Processing updated SDP
  664. 2021-12-15 17:06:37.040782 95.00% [INFO] sofia_glue.c:1651 sofia/internal/1000@192.168.1.166 sending invite call-id: (null)
  665. 2021-12-15 17:06:37.040782 95.00% [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 1639567626 1639567630 IN IP4 192.168.1.166
  669. s=FreeSWITCH
  670. c=IN IP4 192.168.1.166
  671. t=0 0
  672. m=audio 20366 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 1046 bytes to udp/[192.168.1.166]:52290 at 17:06:37.051630:
  681. ------------------------------------------------------------------------
  682. INVITE sip:1000@192.168.1.166:52290;transport=UDP SIP/2.0
  683. Via: SIP/2.0/UDP 192.168.1.166;rport;branch=z9hG4bKpaatp1aX02cKc
  684. Max-Forwards: 70
  685. From: <sip:1001@192.168.1.166>;tag=S59Dp9jU9UDBK
  686. To: <sip:1000@192.168.1.166;transport=UDP>;tag=db7b3813
  687. Call-ID: XYKmeziVHmYs9BWzEvGA3Q..
  688. CSeq: 45240206 INVITE
  689. Contact: <sip:1001@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 1639567626 1639567630 IN IP4 192.168.1.166
  699. s=FreeSWITCH
  700. c=IN IP4 192.168.1.166
  701. t=0 0
  702. m=audio 20366 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 693 bytes from udp/[192.168.1.166]:52290 at 17:06:37.053288:
  710. ------------------------------------------------------------------------
  711. SIP/2.0 200 OK
  712. Via: SIP/2.0/UDP 192.168.1.166;rport=5060;branch=z9hG4bKpaatp1aX02cKc
  713. Contact: <sip:1000@192.168.1.166:52290;transport=UDP>
  714. To: <sip:1000@192.168.1.166;transport=UDP>;tag=db7b3813
  715. From: <sip:1001@192.168.1.166>;tag=S59Dp9jU9UDBK
  716. Call-ID: XYKmeziVHmYs9BWzEvGA3Q..
  717. CSeq: 45240206 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: 176
  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
  730. a=rtpmap:106 opus/48000/2
  731. a=fmtp:106 minptime=10; useinbandfec=1
  732. a=inactive
  733. 2021-12-15 17:06:37.060738 95.00% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [calling][0]
  734. 2021-12-15 17:06:37.060738 95.00% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [completing][200]
  735. 2021-12-15 17:06:37.060738 95.00% [DEBUG] sofia.c:7509 Remote SDP:
  736. v=0
  737. o=Z 0 2 IN IP4 192.168.1.166
  738. s=Z
  739. c=IN IP4 192.168.1.166
  740. t=0 0
  741. m=audio 8000 RTP/AVP 106
  742. a=rtpmap:106 opus/48000/2
  743. a=fmtp:106 minptime=10; useinbandfec=1
  744. a=inactive
  745.  
  746. send 323 bytes to udp/[192.168.1.166]:52290 at 17:06:37.071780:
  747. ------------------------------------------------------------------------
  748. ACK sip:1000@192.168.1.166:52290;transport=UDP SIP/2.0
  749. Via: SIP/2.0/UDP 192.168.1.166;rport;branch=z9hG4bKQK3jrvU0XB35Q
  750. Max-Forwards: 70
  751. From: <sip:1001@192.168.1.166>;tag=S59Dp9jU9UDBK
  752. To: <sip:1000@192.168.1.166;transport=UDP>;tag=db7b3813
  753. Call-ID: XYKmeziVHmYs9BWzEvGA3Q..
  754. CSeq: 45240206 ACK
  755. Content-Length: 0
  756.  
  757. 2021-12-15 17:06:37.080794 95.00% [DEBUG] sofia.c:7499 Channel sofia/internal/1000@192.168.1.166 entering state [ready][200]
  758. 2021-12-15 17:06:37.080794 95.00% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:106:48000:20:0:1]/[opus:116:48000:20:0:2]
  759. 2021-12-15 17:06:37.080794 95.00% [DEBUG] switch_core_media.c:5707 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match
  760. 2021-12-15 17:06:37.080794 95.00% [DEBUG] switch_core_media.c:5946 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
  761. 2021-12-15 17:06:37.080794 95.00% [DEBUG] switch_core_media.c:5975 sofia/internal/1000@192.168.1.166 Set 2833 dtmf send payload to 101 recv payload to 101
  762. 2021-12-15 17:06:37.080794 95.00% [DEBUG] switch_core_media.c:5652 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
  763. 2021-12-15 17:06:37.080794 95.00% [DEBUG] switch_core_media.c:5707 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  764. 2021-12-15 17:06:37.080794 95.00% [DEBUG] switch_core_media.c:5568 Set telephone-event payload to 101@48000
  765. 2021-12-15 17:06:37.080794 95.00% [DEBUG] switch_core_media.c:5917 Set telephone-event payload to 101@48000
  766. 2021-12-15 17:06:37.080794 95.00% [DEBUG] switch_core_media.c:5975 sofia/internal/1001@192.168.1.166:5090 Set 2833 dtmf send payload to 101 recv payload to 101
  767. 2021-12-15 17:06:37.080794 95.00% [DEBUG] switch_core_media.c:8761 Audio params are unchanged for sofia/internal/1001@192.168.1.166:5090.
  768. 2021-12-15 17:06:37.080794 95.00% [DEBUG] switch_core_media.c:8761 Audio params are unchanged for sofia/internal/1000@192.168.1.166.
  769. send 913 bytes to udp/[192.168.1.166]:5090 at 17:06:37.102146:
  770. ------------------------------------------------------------------------
  771. SIP/2.0 200 OK
  772. Via: SIP/2.0/UDP 192.168.1.166:5090;branch=z9hG4bK.n1mo3qZRy;rport=5090
  773. From: <sip:1001@192.168.1.166>;tag=wHUTOwc
  774. To: "Extension 1000" <sip:1000@192.168.1.166>;tag=te36Q43y643Xe
  775. Call-ID: 3128581e-d86c-123a-3585-dc1ba1ed361d
  776. CSeq: 111 INVITE
  777. Contact: <sip:mod_sofia@192.168.1.166:5060>
  778. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  779. Accept: application/sdp
  780. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  781. Supported: timer, path, replaces
  782. Content-Type: application/sdp
  783. Content-Disposition: session
  784. Content-Length: 269
  785.  
  786. v=0
  787. o=FreeSWITCH 1639566591 1639566593 IN IP4 192.168.1.166
  788. s=FreeSWITCH
  789. c=IN IP4 192.168.1.166
  790. t=0 0
  791. m=audio 21400 RTP/AVP 102 101
  792. a=rtpmap:102 opus/48000/2
  793. a=fmtp:102 useinbandfec=1
  794. a=rtpmap:101 telephone-event/48000
  795. a=fmtp:101 0-15
  796. a=recvonly
  797. a=ptime:20
  798. recv 352 bytes from udp/[192.168.1.166]:5090 at 17:06:37.114801:
  799. ------------------------------------------------------------------------
  800. ACK sip:mod_sofia@192.168.1.166:5060 SIP/2.0
  801. Via: SIP/2.0/UDP 192.168.1.166:5090;rport;branch=z9hG4bK.WQt3-Z2U3
  802. From: <sip:1001@192.168.1.166>;tag=wHUTOwc
  803. To: "Extension 1000" <sip:1000@192.168.1.166>;tag=te36Q43y643Xe
  804. CSeq: 111 ACK
  805. Call-ID: 3128581e-d86c-123a-3585-dc1ba1ed361d
  806. Max-Forwards: 70
  807. User-Agent: Linphone/3.12.0 (belle-sip/1.6.3)
  808.  
  809. 2021-12-15 17:06:37.120783 95.00% [DEBUG] sofia.c:7499 Channel sofia/internal/1001@192.168.1.166:5090 entering state [completed][200]
  810. 2021-12-15 17:06:37.120783 95.00% [DEBUG] sofia.c:7499 Channel sofia/internal/1001@192.168.1.166:5090 entering state [ready][200]
  811. recv 849 bytes from udp/[192.168.1.166]:52290 at 17:07:27.758245:
  812. ------------------------------------------------------------------------
  813. REGISTER sip:192.168.1.166;transport=UDP SIP/2.0
  814. Via: SIP/2.0/UDP 192.168.1.166:52290;branch=z9hG4bK-524287-1---6abfb15a557d62bf;rport
  815. Max-Forwards: 70
  816. Contact: <sip:1000@192.168.1.166:52290;rinstance=ae9d4de82a2033b7;transport=UDP>
  817. To: <sip:1000@192.168.1.166;transport=UDP>
  818. From: <sip:1000@192.168.1.166;transport=UDP>;tag=98d22a27
  819. Call-ID: Lt6vHw5Hs6EkMiWJdiG20A..
  820. CSeq: 17 REGISTER
  821. Expires: 60
  822. Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  823. User-Agent: Z 5.4.12 v2.10.13.2-mod
  824. Authorization: Digest username="1000",realm="192.168.1.166",nonce="72bed853-18fe-4e23-bfcd-06fdc9d83f6f",uri="sip:192.168.1.166;transport=UDP",response="14b79628ac03c0f9fef4b728013587b8",cnonce="6016194f56c07b8abe65b7f56661143c",nc=00000007,qop=auth,algorithm=MD5
  825. Allow-Events: presence, kpml, talk
  826. Content-Length: 0
  827.  
  828. send 662 bytes to udp/[192.168.1.166]:52290 at 17:07:27.764279:
  829. ------------------------------------------------------------------------
  830. SIP/2.0 200 OK
  831. Via: SIP/2.0/UDP 192.168.1.166:52290;branch=z9hG4bK-524287-1---6abfb15a557d62bf;rport=52290
  832. From: <sip:1000@192.168.1.166;transport=UDP>;tag=98d22a27
  833. To: <sip:1000@192.168.1.166;transport=UDP>;tag=v0NrUt550pg3N
  834. Call-ID: Lt6vHw5Hs6EkMiWJdiG20A..
  835. CSeq: 17 REGISTER
  836. Contact: <sip:1000@192.168.1.166:52290;rinstance=ae9d4de82a2033b7;transport=UDP>;expires=60
  837. Date: Wed, 15 Dec 2021 17:07:27 GMT
  838. User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20211117T192328Z~e428be3f1a~64bit
  839. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  840. Supported: timer, path, replaces
  841. Content-Length: 0
  842.  
  843.