From Putrid Dove, 5 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. recv 796 bytes from udp/[183.82.203.220]:23502 at 12:34:12.381923:
  2.    ------------------------------------------------------------------------
  3.    INVITE sip:1000@159.89.163.195:5020 SIP/2.0
  4.    Via: SIP/2.0/UDP 192.168.1.4:64343;branch=z9hG4bK-524287-1---f7a8f15b6eab5e05;rport
  5.    Max-Forwards: 70
  6.    Contact: <sip:1000@183.82.203.220:23502;rinstance=455085c26772fb89>
  7.    To: <sip:1000@159.89.163.195:5020>
  8.    From: "1000"<sip:1000@159.89.163.195:5020>;tag=e2818c43
  9.    Call-ID: 92627YjA3OTQ1NGE2MmZlZmI1NDNkNzhlNTcyODM4ZjU5MWQ
  10.    CSeq: 1 INVITE
  11.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  12.    Content-Type: application/sdp
  13.    Supported: replaces
  14.    User-Agent: X-Lite release 5.3.2 stamp 92627
  15.    Content-Length: 207
  16.  
  17.    v=0
  18.    o=- 13185394425892273 1 IN IP4 192.168.1.4
  19.    s=X-Lite release 5.3.2 stamp 92627
  20.    c=IN IP4 192.168.1.4
  21.    t=0 0
  22.    m=audio 64120 RTP/AVP 0 101
  23.    a=rtpmap:101 telephone-event/8000
  24.    a=fmtp:101 0-15
  25.    a=sendrecv
  26.    ------------------------------------------------------------------------
  27. 2018-10-30 12:34:12.374828 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1000@159.89.163.195:5020 [b518fc28-5c14-4367-9326-94767a8e0783]
  28. 2018-10-30 12:34:12.374828 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@159.89.163.195:5020) Running State Change CS_NEW (Cur 1 Tot 24)
  29. 2018-10-30 12:34:12.374828 [DEBUG] sofia.c:10080 sofia/internal/1000@159.89.163.195:5020 receiving invite from 183.82.203.220:23502 version: 1.8.2 git a98a958 2018-09-26 17:55:25Z 64bit
  30. 2018-10-30 12:34:12.374828 [DEBUG] sofia.c:10251 IP 183.82.203.220 Rejected by acl "domains". Falling back to Digest auth.
  31. send 907 bytes to udp/[183.82.203.220]:23502 at 12:34:12.382884:
  32.    ------------------------------------------------------------------------
  33.    SIP/2.0 407 Proxy Authentication Required
  34.    Via: SIP/2.0/UDP 192.168.1.4:64343;branch=z9hG4bK-524287-1---f7a8f15b6eab5e05;rport=23502;received=183.82.203.220
  35.    From: "1000"<sip:1000@159.89.163.195:5020>;tag=e2818c43
  36.    To: <sip:1000@159.89.163.195:5020>;tag=FZp4N5p5B3trK
  37.    Call-ID: 92627YjA3OTQ1NGE2MmZlZmI1NDNkNzhlNTcyODM4ZjU5MWQ
  38.    CSeq: 1 INVITE
  39.    User-Agent: FreeSWITCH-mod_sofia/1.8.2+git~20180926T175525Z~a98a958ac3~64bit
  40.    Accept: application/sdp
  41.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  42.    Supported: timer, path, replaces
  43.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  44.    Proxy-Authenticate: Digest realm="159.89.163.195", nonce="3d21ebdb-1f85-4cfa-b808-5ebb75c487ff", algorithm=MD5, qop="auth"
  45.    Content-Length: 0
  46.  
  47.    ------------------------------------------------------------------------
  48. 2018-10-30 12:34:12.374828 [DEBUG] sofia.c:2413 detaching session b518fc28-5c14-4367-9326-94767a8e0783
  49. 2018-10-30 12:34:12.374828 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1000@159.89.163.195:5020) State NEW
  50. recv 349 bytes from udp/[183.82.203.220]:23502 at 12:34:12.410143:
  51.    ------------------------------------------------------------------------
  52.    ACK sip:1000@159.89.163.195:5020 SIP/2.0
  53.    Via: SIP/2.0/UDP 192.168.1.4:64343;branch=z9hG4bK-524287-1---f7a8f15b6eab5e05;rport
  54.    Max-Forwards: 70
  55.    To: <sip:1000@159.89.163.195:5020>;tag=FZp4N5p5B3trK
  56.    From: "1000"<sip:1000@159.89.163.195:5020>;tag=e2818c43
  57.    Call-ID: 92627YjA3OTQ1NGE2MmZlZmI1NDNkNzhlNTcyODM4ZjU5MWQ
  58.    CSeq: 1 ACK
  59.    Content-Length: 0
  60.  
  61.    ------------------------------------------------------------------------
  62. recv 1065 bytes from udp/[183.82.203.220]:23502 at 12:34:12.412418:
  63.    ------------------------------------------------------------------------
  64.    INVITE sip:1000@159.89.163.195:5020 SIP/2.0
  65.    Via: SIP/2.0/UDP 192.168.1.4:64343;branch=z9hG4bK-524287-1---ad06303728d3f229;rport
  66.    Max-Forwards: 70
  67.    Contact: <sip:1000@183.82.203.220:23502;rinstance=455085c26772fb89>
  68.    To: <sip:1000@159.89.163.195:5020>
  69.    From: "1000"<sip:1000@159.89.163.195:5020>;tag=e2818c43
  70.    Call-ID: 92627YjA3OTQ1NGE2MmZlZmI1NDNkNzhlNTcyODM4ZjU5MWQ
  71.    CSeq: 2 INVITE
  72.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  73.    Content-Type: application/sdp
  74.    Proxy-Authorization: Digest username="1000",realm="159.89.163.195",nonce="3d21ebdb-1f85-4cfa-b808-5ebb75c487ff",uri="sip:1000@159.89.163.195:5020",response="51408f634a6b75ba28e86588ed92fb0e",cnonce="b2f918df319453bb78cab0b218f67280",nc=00000001,qop=auth,algorithm=MD5
  75.    Supported: replaces
  76.    User-Agent: X-Lite release 5.3.2 stamp 92627
  77.    Content-Length: 207
  78.  
  79.    v=0
  80.    o=- 13185394425892273 1 IN IP4 192.168.1.4
  81.    s=X-Lite release 5.3.2 stamp 92627
  82.    c=IN IP4 192.168.1.4
  83.    t=0 0
  84.    m=audio 64120 RTP/AVP 0 101
  85.    a=rtpmap:101 telephone-event/8000
  86.    a=fmtp:101 0-15
  87.    a=sendrecv
  88.    ------------------------------------------------------------------------
  89. 2018-10-30 12:34:12.404804 [DEBUG] sofia.c:2522 Re-attaching to session b518fc28-5c14-4367-9326-94767a8e0783
  90. 2018-10-30 12:34:12.414811 [DEBUG] sofia.c:10080 sofia/internal/1000@159.89.163.195:5020 receiving invite from 183.82.203.220:23502 version: 1.8.2 git a98a958 2018-09-26 17:55:25Z 64bit
  91. 2018-10-30 12:34:12.414811 [DEBUG] sofia.c:10251 IP 183.82.203.220 Rejected by acl "domains". Falling back to Digest auth.
  92. 2018-10-30 12:34:12.414811 [DEBUG] sofia.c:11388 Setting NAT mode based on nat.auto
  93. 2018-10-30 12:34:12.414811 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@159.89.163.195:5020 entering state [received][100]
  94. 2018-10-30 12:34:12.414811 [DEBUG] sofia.c:7301 Remote SDP:
  95. v=0
  96. o=- 13185394425892273 1 IN IP4 192.168.1.4
  97. s=X-Lite release 5.3.2 stamp 92627
  98. c=IN IP4 192.168.1.4
  99. t=0 0
  100. m=audio 64120 RTP/AVP 0 101
  101. a=rtpmap:101 telephone-event/8000
  102. a=fmtp:101 0-15
  103.  
  104. 2018-10-30 12:34:12.414811 [DEBUG] sofia.c:7695 (sofia/internal/1000@159.89.163.195:5020) State Change CS_NEW -> CS_INIT
  105. 2018-10-30 12:34:12.414811 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@159.89.163.195:5020) Running State Change CS_INIT (Cur 1 Tot 24)
  106. 2018-10-30 12:34:12.414811 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1000@159.89.163.195:5020) State INIT
  107. 2018-10-30 12:34:12.414811 [DEBUG] mod_sofia.c:93 sofia/internal/1000@159.89.163.195:5020 SOFIA INIT
  108. 2018-10-30 12:34:12.414811 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1000@159.89.163.195:5020 Standard INIT
  109. 2018-10-30 12:34:12.414811 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1000@159.89.163.195:5020) State Change CS_INIT -> CS_ROUTING
  110. 2018-10-30 12:34:12.414811 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1000@159.89.163.195:5020) State INIT going to sleep
  111. 2018-10-30 12:34:12.414811 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@159.89.163.195:5020) Running State Change CS_ROUTING (Cur 1 Tot 24)
  112. 2018-10-30 12:34:12.414811 [DEBUG] switch_channel.c:2249 (sofia/internal/1000@159.89.163.195:5020) Callstate Change DOWN -> RINGING
  113. 2018-10-30 12:34:12.414811 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1000@159.89.163.195:5020) State ROUTING
  114. send 402 bytes to udp/[183.82.203.220]:23502 at 12:34:12.424720:
  115.    ------------------------------------------------------------------------
  116.    SIP/2.0 100 Trying
  117.    Via: SIP/2.0/UDP 192.168.1.4:64343;branch=z9hG4bK-524287-1---ad06303728d3f229;rport=23502;received=183.82.203.220
  118.    From: "1000"<sip:1000@159.89.163.195:5020>;tag=e2818c43
  119.    To: <sip:1000@159.89.163.195:5020>
  120.    Call-ID: 92627YjA3OTQ1NGE2MmZlZmI1NDNkNzhlNTcyODM4ZjU5MWQ
  121.    CSeq: 2 INVITE
  122.    User-Agent: FreeSWITCH-mod_sofia/1.8.2+git~20180926T175525Z~a98a958ac3~64bit
  123.    Content-Length: 0
  124.  
  125.    ------------------------------------------------------------------------
  126. 2018-10-30 12:34:12.424808 [DEBUG] mod_sofia.c:154 sofia/internal/1000@159.89.163.195:5020 SOFIA ROUTING
  127. 2018-10-30 12:34:12.424808 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1000@159.89.163.195:5020 Standard ROUTING
  128. 2018-10-30 12:34:12.424808 [INFO] mod_dialplan_xml.c:637 Processing 1000 <1000>->1000 in context default
  129. Dialplan: sofia/internal/1000@159.89.163.195:5020 parsing [default->ee634e42-e806-4e15-9abe-969d09a3eb33] continue=false
  130. Dialplan: sofia/internal/1000@159.89.163.195:5020 Regex (PASS) [ee634e42-e806-4e15-9abe-969d09a3eb33] destination_number(1000) =~ /^1000$/ break=on-false
  131. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action clear_digit_action(all)
  132. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action export(bind_digit_digit_timeout=200)
  133. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action unset(sip_h_X-Weave-DC)
  134. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action unset(sip_h_X-Loop-Data)
  135. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action unset(sip_h_X-Weave-External)
  136. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action unset(sip_h_X-Weave-Internal)
  137. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action unset(sip_h_X-Weave-Outbound)
  138. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action export(weave_domain=dev-scott)
  139. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action export(w_domain=dev-scott.getweave.io)
  140. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action export(selected_carrier=twilio)
  141. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_log=true)
  142. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_direction=inbound)
  143. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_dialed_number=8017970178)
  144. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_caller_name=Weave )
  145. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_caller_number=7164186158)
  146. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_auto_sms_webhook_url=http://admin-api.client:8000/portal/v1/phones/missedcall)
  147. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_auto_sms_webhook_key=4G7tEQa0CQB8XlXs-JkdiP3AFRBT3pIrG-hm0k1GK4QZMBzMM4)
  148. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action limit(hash dev-scott.getweave.io inbound 10 inbound_exceeded)
  149. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(w_cdr_originator=7164186158)
  150. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(w_cdr_destination=8017970178)
  151. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(w_cdr_direction=inbound)
  152. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(w_call_direction=inbound)
  153. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(RECORD_STEREO=true)
  154. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(media_bug_answer_req=true)
  155. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(recording_follow_transfer=true)
  156. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(record_post_process_exec_app=bgsystem:curl -d '/temp_recordings/dev-scott_2018-10-30_ee634e42-e806-4e15-9abe-969d09a3eb33.mp3' 'http:///pub?topic=CallRecording')
  157. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(execute_on_answer=record_session /temp_recordings/dev-scott_2018-10-30_ee634e42-e806-4e15-9abe-969d09a3eb33.mp3)
  158. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action export(location_id=94472cd8-2b1c-4b44-94fe-9708c2bd739d)
  159. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(weave_lookup_number=${caller_id_number})
  160. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(continue_on_fail=true)
  161. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(hangup_after_bridge=true)
  162. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(hold_music=local_stream://moh)
  163. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(temp_hold_music=local_stream://moh)
  164. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_slug=dev-scott)
  165. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(generated_channel_id_eschmidbauer02_dev-scott=1a9b5231-e815-4aba-900d-ecf1089e848f)
  166. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_sip_id_eschmidbauer02_dev-scott=44ea632a-a36d-4f3e-b2cc-5272065d1d09)
  167. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_sip_name_eschmidbauer02_dev-scott=eschmidbauer02)
  168. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(generated_channel_id_eschmidbauer01_dev-scott=8f00162e-9926-4f19-a078-b88487aa6e58)
  169. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_sip_id_eschmidbauer01_dev-scott=126385ac-5c42-4b20-bc64-0eec19733a42)
  170. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_sip_name_eschmidbauer01_dev-scott=E-VVX400)
  171. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(generated_channel_id_xlite_dev-scott=f60035e5-788e-4acd-9d86-18f19ae75dec)
  172. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_sip_id_xlite_dev-scott=4073da9c-2359-4f2b-ae75-7fc06748ba78)
  173. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_sip_name_xlite_dev-scott=xlite)
  174. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(generated_channel_id_eschmidbauer03_dev-scott=f403f20c-cb2e-40f9-bde5-d1ad9e10616b)
  175. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_sip_id_eschmidbauer03_dev-scott=1fa6d75e-765e-4110-a8e1-13f7df9275c0)
  176. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_sip_name_eschmidbauer03_dev-scott=vvx500)
  177. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(generated_channel_id_testline05_dev-scott=3573a9a4-b04e-4ab1-92e8-dfaa401c1b85)
  178. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_sip_id_testline05_dev-scott=1b112711-e324-484a-bbc5-f17564d26935)
  179. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(cdr_sip_name_testline05_dev-scott=testline05)
  180. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(mobile_app=eschmidbauer01;xlite)
  181. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action set(desk_phone=eschmidbauer02;eschmidbauer03;testline05)
  182. Dialplan: sofia/internal/1000@159.89.163.195:5020 Action bridge({origination_uuid=14a8bd6f-af2e-4b1d-8d18-bd59d863387f}user/1003@${domain_name})
  183. 2018-10-30 12:34:12.424808 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1000@159.89.163.195:5020) State Change CS_ROUTING -> CS_EXECUTE
  184. 2018-10-30 12:34:12.424808 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1000@159.89.163.195:5020) State ROUTING going to sleep
  185. 2018-10-30 12:34:12.424808 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@159.89.163.195:5020) Running State Change CS_EXECUTE (Cur 1 Tot 24)
  186. 2018-10-30 12:34:12.424808 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1000@159.89.163.195:5020) State EXECUTE
  187. 2018-10-30 12:34:12.424808 [DEBUG] mod_sofia.c:209 sofia/internal/1000@159.89.163.195:5020 SOFIA EXECUTE
  188. 2018-10-30 12:34:12.424808 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1000@159.89.163.195:5020 Standard EXECUTE
  189. EXECUTE sofia/internal/1000@159.89.163.195:5020 clear_digit_action(all)
  190. EXECUTE sofia/internal/1000@159.89.163.195:5020 export(bind_digit_digit_timeout=200)
  191. 2018-10-30 12:34:12.424808 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [bind_digit_digit_timeout]=[200]
  192. EXECUTE sofia/internal/1000@159.89.163.195:5020 unset(sip_h_X-Weave-DC)
  193. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1743 UNSET [sip_h_X-Weave-DC]
  194. EXECUTE sofia/internal/1000@159.89.163.195:5020 unset(sip_h_X-Loop-Data)
  195. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1743 UNSET [sip_h_X-Loop-Data]
  196. EXECUTE sofia/internal/1000@159.89.163.195:5020 unset(sip_h_X-Weave-External)
  197. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1743 UNSET [sip_h_X-Weave-External]
  198. EXECUTE sofia/internal/1000@159.89.163.195:5020 unset(sip_h_X-Weave-Internal)
  199. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1743 UNSET [sip_h_X-Weave-Internal]
  200. EXECUTE sofia/internal/1000@159.89.163.195:5020 unset(sip_h_X-Weave-Outbound)
  201. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1743 UNSET [sip_h_X-Weave-Outbound]
  202. EXECUTE sofia/internal/1000@159.89.163.195:5020 export(weave_domain=dev-scott)
  203. 2018-10-30 12:34:12.424808 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [weave_domain]=[dev-scott]
  204. EXECUTE sofia/internal/1000@159.89.163.195:5020 export(w_domain=dev-scott.getweave.io)
  205. 2018-10-30 12:34:12.424808 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [w_domain]=[dev-scott.getweave.io]
  206. EXECUTE sofia/internal/1000@159.89.163.195:5020 export(selected_carrier=twilio)
  207. 2018-10-30 12:34:12.424808 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [selected_carrier]=[twilio]
  208. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_log=true)
  209. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_log]=[true]
  210. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_direction=inbound)
  211. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_direction]=[inbound]
  212. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_dialed_number=8017970178)
  213. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_dialed_number]=[8017970178]
  214. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_caller_name=Weave )
  215. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_caller_name]=[Weave ]
  216. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_caller_number=7164186158)
  217. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_caller_number]=[7164186158]
  218. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_auto_sms_webhook_url=http://admin-api.client:8000/portal/v1/phones/missedcall)
  219. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_auto_sms_webhook_url]=[http://admin-api.client:8000/portal/v1/phones/missedcall]
  220. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_auto_sms_webhook_key=4G7tEQa0CQB8XlXs-JkdiP3AFRBT3pIrG-hm0k1GK4QZMBzMM4)
  221. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_auto_sms_webhook_key]=[4G7tEQa0CQB8XlXs-JkdiP3AFRBT3pIrG-hm0k1GK4QZMBzMM4]
  222. EXECUTE sofia/internal/1000@159.89.163.195:5020 limit(hash dev-scott.getweave.io inbound 10 inbound_exceeded)
  223. 2018-10-30 12:34:12.424808 [DEBUG] switch_limit.c:126 incr called: dev-scott.getweave.io_inbound max:10, interval:0
  224. 2018-10-30 12:34:12.424808 [DEBUG] mod_hash.c:196 Usage for dev-scott.getweave.io_inbound is now 1/10
  225. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(w_cdr_originator=7164186158)
  226. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [w_cdr_originator]=[7164186158]
  227. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(w_cdr_destination=8017970178)
  228. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [w_cdr_destination]=[8017970178]
  229. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(w_cdr_direction=inbound)
  230. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [w_cdr_direction]=[inbound]
  231. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(w_call_direction=inbound)
  232. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [w_call_direction]=[inbound]
  233. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(RECORD_STEREO=true)
  234. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [RECORD_STEREO]=[true]
  235. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(media_bug_answer_req=true)
  236. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [media_bug_answer_req]=[true]
  237. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(recording_follow_transfer=true)
  238. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [recording_follow_transfer]=[true]
  239. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(record_post_process_exec_app=bgsystem:curl -d '/temp_recordings/dev-scott_2018-10-30_ee634e42-e806-4e15-9abe-969d09a3eb33.mp3' 'http:///pub?topic=CallRecording')
  240. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [record_post_process_exec_app]=[bgsystem:curl -d '/temp_recordings/dev-scott_2018-10-30_ee634e42-e806-4e15-9abe-969d09a3eb33.mp3' 'http:///pub?topic=CallRecording']
  241. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(execute_on_answer=record_session /temp_recordings/dev-scott_2018-10-30_ee634e42-e806-4e15-9abe-969d09a3eb33.mp3)
  242. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [execute_on_answer]=[record_session /temp_recordings/dev-scott_2018-10-30_ee634e42-e806-4e15-9abe-969d09a3eb33.mp3]
  243. EXECUTE sofia/internal/1000@159.89.163.195:5020 export(location_id=94472cd8-2b1c-4b44-94fe-9708c2bd739d)
  244. 2018-10-30 12:34:12.424808 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [location_id]=[94472cd8-2b1c-4b44-94fe-9708c2bd739d]
  245. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(weave_lookup_number=1000)
  246. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [weave_lookup_number]=[1000]
  247. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(continue_on_fail=true)
  248. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [continue_on_fail]=[true]
  249. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(hangup_after_bridge=true)
  250. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [hangup_after_bridge]=[true]
  251. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(hold_music=local_stream://moh)
  252. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [hold_music]=[local_stream://moh]
  253. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(temp_hold_music=local_stream://moh)
  254. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [temp_hold_music]=[local_stream://moh]
  255. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_slug=dev-scott)
  256. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_slug]=[dev-scott]
  257. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(generated_channel_id_eschmidbauer02_dev-scott=1a9b5231-e815-4aba-900d-ecf1089e848f)
  258. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [generated_channel_id_eschmidbauer02_dev-scott]=[1a9b5231-e815-4aba-900d-ecf1089e848f]
  259. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_sip_id_eschmidbauer02_dev-scott=44ea632a-a36d-4f3e-b2cc-5272065d1d09)
  260. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_sip_id_eschmidbauer02_dev-scott]=[44ea632a-a36d-4f3e-b2cc-5272065d1d09]
  261. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_sip_name_eschmidbauer02_dev-scott=eschmidbauer02)
  262. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_sip_name_eschmidbauer02_dev-scott]=[eschmidbauer02]
  263. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(generated_channel_id_eschmidbauer01_dev-scott=8f00162e-9926-4f19-a078-b88487aa6e58)
  264. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [generated_channel_id_eschmidbauer01_dev-scott]=[8f00162e-9926-4f19-a078-b88487aa6e58]
  265. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_sip_id_eschmidbauer01_dev-scott=126385ac-5c42-4b20-bc64-0eec19733a42)
  266. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_sip_id_eschmidbauer01_dev-scott]=[126385ac-5c42-4b20-bc64-0eec19733a42]
  267. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_sip_name_eschmidbauer01_dev-scott=E-VVX400)
  268. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_sip_name_eschmidbauer01_dev-scott]=[E-VVX400]
  269. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(generated_channel_id_xlite_dev-scott=f60035e5-788e-4acd-9d86-18f19ae75dec)
  270. 2018-10-30 12:34:12.424808 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [generated_channel_id_xlite_dev-scott]=[f60035e5-788e-4acd-9d86-18f19ae75dec]
  271. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_sip_id_xlite_dev-scott=4073da9c-2359-4f2b-ae75-7fc06748ba78)
  272. 2018-10-30 12:34:12.434805 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_sip_id_xlite_dev-scott]=[4073da9c-2359-4f2b-ae75-7fc06748ba78]
  273. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_sip_name_xlite_dev-scott=xlite)
  274. 2018-10-30 12:34:12.434805 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_sip_name_xlite_dev-scott]=[xlite]
  275. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(generated_channel_id_eschmidbauer03_dev-scott=f403f20c-cb2e-40f9-bde5-d1ad9e10616b)
  276. 2018-10-30 12:34:12.434805 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [generated_channel_id_eschmidbauer03_dev-scott]=[f403f20c-cb2e-40f9-bde5-d1ad9e10616b]
  277. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_sip_id_eschmidbauer03_dev-scott=1fa6d75e-765e-4110-a8e1-13f7df9275c0)
  278. 2018-10-30 12:34:12.434805 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_sip_id_eschmidbauer03_dev-scott]=[1fa6d75e-765e-4110-a8e1-13f7df9275c0]
  279. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_sip_name_eschmidbauer03_dev-scott=vvx500)
  280. 2018-10-30 12:34:12.434805 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_sip_name_eschmidbauer03_dev-scott]=[vvx500]
  281. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(generated_channel_id_testline05_dev-scott=3573a9a4-b04e-4ab1-92e8-dfaa401c1b85)
  282. 2018-10-30 12:34:12.434805 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [generated_channel_id_testline05_dev-scott]=[3573a9a4-b04e-4ab1-92e8-dfaa401c1b85]
  283. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_sip_id_testline05_dev-scott=1b112711-e324-484a-bbc5-f17564d26935)
  284. 2018-10-30 12:34:12.434805 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_sip_id_testline05_dev-scott]=[1b112711-e324-484a-bbc5-f17564d26935]
  285. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(cdr_sip_name_testline05_dev-scott=testline05)
  286. 2018-10-30 12:34:12.434805 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [cdr_sip_name_testline05_dev-scott]=[testline05]
  287. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(mobile_app=eschmidbauer01;xlite)
  288. 2018-10-30 12:34:12.434805 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [mobile_app]=[eschmidbauer01;xlite]
  289. EXECUTE sofia/internal/1000@159.89.163.195:5020 set(desk_phone=eschmidbauer02;eschmidbauer03;testline05)
  290. 2018-10-30 12:34:12.434805 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1000@159.89.163.195:5020 [desk_phone]=[eschmidbauer02;eschmidbauer03;testline05]
  291. EXECUTE sofia/internal/1000@159.89.163.195:5020 bridge({origination_uuid=14a8bd6f-af2e-4b1d-8d18-bd59d863387f}user/1003@159.89.163.195)
  292. 2018-10-30 12:34:12.434805 [DEBUG] switch_channel.c:1250 sofia/internal/1000@159.89.163.195:5020 EXPORTING[export_vars] [bind_digit_digit_timeout]=[200] to event
  293. 2018-10-30 12:34:12.434805 [DEBUG] switch_channel.c:1250 sofia/internal/1000@159.89.163.195:5020 EXPORTING[export_vars] [weave_domain]=[dev-scott] to event
  294. 2018-10-30 12:34:12.434805 [DEBUG] switch_channel.c:1250 sofia/internal/1000@159.89.163.195:5020 EXPORTING[export_vars] [w_domain]=[dev-scott.getweave.io] to event
  295. 2018-10-30 12:34:12.434805 [DEBUG] switch_channel.c:1250 sofia/internal/1000@159.89.163.195:5020 EXPORTING[export_vars] [selected_carrier]=[twilio] to event
  296. 2018-10-30 12:34:12.434805 [DEBUG] switch_channel.c:1250 sofia/internal/1000@159.89.163.195:5020 EXPORTING[export_vars] [location_id]=[94472cd8-2b1c-4b44-94fe-9708c2bd739d] to event
  297. 2018-10-30 12:34:12.434805 [DEBUG] switch_ivr_originate.c:2204 Parsing global variables
  298. 2018-10-30 12:34:12.434805 [DEBUG] switch_channel.c:1250 sofia/internal/1000@159.89.163.195:5020 EXPORTING[export_vars] [bind_digit_digit_timeout]=[200] to event
  299. 2018-10-30 12:34:12.434805 [DEBUG] switch_channel.c:1250 sofia/internal/1000@159.89.163.195:5020 EXPORTING[export_vars] [weave_domain]=[dev-scott] to event
  300. 2018-10-30 12:34:12.434805 [DEBUG] switch_channel.c:1250 sofia/internal/1000@159.89.163.195:5020 EXPORTING[export_vars] [w_domain]=[dev-scott.getweave.io] to event
  301. 2018-10-30 12:34:12.434805 [DEBUG] switch_channel.c:1250 sofia/internal/1000@159.89.163.195:5020 EXPORTING[export_vars] [selected_carrier]=[twilio] to event
  302. 2018-10-30 12:34:12.434805 [DEBUG] switch_channel.c:1250 sofia/internal/1000@159.89.163.195:5020 EXPORTING[export_vars] [location_id]=[94472cd8-2b1c-4b44-94fe-9708c2bd739d] to event
  303. 2018-10-30 12:34:12.434805 [DEBUG] switch_ivr_originate.c:2204 Parsing global variables
  304. 2018-10-30 12:34:12.434805 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1003@183.82.203.220:23585 [14a8bd6f-af2e-4b1d-8d18-bd59d863387f]
  305. 2018-10-30 12:34:12.434805 [DEBUG] mod_sofia.c:5028 (sofia/internal/1003@183.82.203.220:23585) State Change CS_NEW -> CS_INIT
  306. 2018-10-30 12:34:12.434805 [DEBUG] switch_core_session.c:641 sofia/internal/1003@183.82.203.220:23585 set UUID=14a8bd6f-af2e-4b1d-8d18-bd59d863387f
  307. 2018-10-30 12:34:12.434805 [NOTICE] switch_ivr_originate.c:2944 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
  308. 2018-10-30 12:34:12.434805 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@183.82.203.220:23585) Running State Change CS_INIT (Cur 2 Tot 25)
  309. 2018-10-30 12:34:12.434805 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1003@183.82.203.220:23585) State INIT
  310. 2018-10-30 12:34:12.434805 [DEBUG] mod_sofia.c:93 sofia/internal/1003@183.82.203.220:23585 SOFIA INIT
  311. 2018-10-30 12:34:12.434805 [DEBUG] sofia_glue.c:1299 sofia/internal/1003@183.82.203.220:23585 sending invite version: 1.8.2 git a98a958 2018-09-26 17:55:25Z 64bit
  312. Local SDP:
  313. v=0
  314. o=FreeSWITCH 1540898872 1540898873 IN IP4 159.89.163.195
  315. s=FreeSWITCH
  316. c=IN IP4 159.89.163.195
  317. t=0 0
  318. m=audio 21980 RTP/AVP 0 101
  319. a=rtpmap:0 PCMU/8000
  320. a=rtpmap:101 telephone-event/8000
  321. a=fmtp:101 0-16
  322. a=ptime:20
  323. a=sendrecv
  324.  
  325. send 1287 bytes to tcp/[183.82.203.220]:23585 at 12:34:12.440692:
  326.    ------------------------------------------------------------------------
  327.    INVITE sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0 SIP/2.0
  328.    Via: SIP/2.0/TCP 159.89.163.195:5020;branch=z9hG4bKZj8KD3vXjaQ9p
  329.    Max-Forwards: 69
  330.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  331.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>
  332.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  333.    CSeq: 130124362 INVITE
  334.    Contact: <sip:mod_sofia@159.89.163.195:5020;transport=tcp>
  335.    User-Agent: FreeSWITCH-mod_sofia/1.8.2+git~20180926T175525Z~a98a958ac3~64bit
  336.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  337.    Supported: timer, path, replaces
  338.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  339.    Content-Type: application/sdp
  340.    Content-Disposition: session
  341.    Content-Length: 224
  342.    X-FS-Support: update_display,send_info
  343.    Remote-Party-ID: "Extension 1000" <sip:1000@159.89.163.195>;party=calling;screen=yes;privacy=off
  344.  
  345.    v=0
  346.    o=FreeSWITCH 1540898872 1540898873 IN IP4 159.89.163.195
  347.    s=FreeSWITCH
  348.    c=IN IP4 159.89.163.195
  349.    t=0 0
  350.    m=audio 21980 RTP/AVP 0 101
  351.    a=rtpmap:0 PCMU/8000
  352.    a=rtpmap:101 telephone-event/8000
  353.    a=fmtp:101 0-16
  354.    a=ptime:20
  355.    ------------------------------------------------------------------------
  356. 2018-10-30 12:34:12.434805 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1003@183.82.203.220:23585 Standard INIT
  357. 2018-10-30 12:34:12.434805 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1003@183.82.203.220:23585) State Change CS_INIT -> CS_ROUTING
  358. 2018-10-30 12:34:12.434805 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1003@183.82.203.220:23585) State INIT going to sleep
  359. 2018-10-30 12:34:12.434805 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@183.82.203.220:23585) Running State Change CS_ROUTING (Cur 2 Tot 25)
  360. 2018-10-30 12:34:12.434805 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@183.82.203.220:23585 entering state [calling][0]
  361. 2018-10-30 12:34:12.434805 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1003@183.82.203.220:23585) State ROUTING
  362. 2018-10-30 12:34:12.434805 [DEBUG] mod_sofia.c:154 sofia/internal/1003@183.82.203.220:23585 SOFIA ROUTING
  363. 2018-10-30 12:34:12.434805 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1003@183.82.203.220:23585) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  364. 2018-10-30 12:34:12.434805 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1003@183.82.203.220:23585) State ROUTING going to sleep
  365. 2018-10-30 12:34:12.434805 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@183.82.203.220:23585) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 25)
  366. 2018-10-30 12:34:12.434805 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1003@183.82.203.220:23585) State CONSUME_MEDIA
  367. 2018-10-30 12:34:12.434805 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1003@183.82.203.220:23585) State CONSUME_MEDIA going to sleep
  368. recv 324 bytes from tcp/[183.82.203.220]:23585 at 12:34:13.118462:
  369.    ------------------------------------------------------------------------
  370.    SIP/2.0 100 Trying
  371.    Via: SIP/2.0/TCP 159.89.163.195:5020;branch=z9hG4bKZj8KD3vXjaQ9p
  372.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>
  373.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  374.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  375.    CSeq: 130124362 INVITE
  376.    Content-Length: 0
  377.  
  378.    ------------------------------------------------------------------------
  379. recv 529 bytes from tcp/[183.82.203.220]:23585 at 12:34:13.928711:
  380.    ------------------------------------------------------------------------
  381.    SIP/2.0 180 Ringing
  382.    Via: SIP/2.0/TCP 159.89.163.195:5020;branch=z9hG4bKZj8KD3vXjaQ9p
  383.    Contact: <sip:1003@183.82.203.220:23581>
  384.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>;tag=435b9221
  385.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  386.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  387.    CSeq: 130124362 INVITE
  388.   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  389.    User-Agent: Zoiper rv2.8.109
  390.    Allow-Events: presence, kpml, talk
  391.    Content-Length: 0
  392.  
  393.    ------------------------------------------------------------------------
  394. 2018-10-30 12:34:13.924820 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@183.82.203.220:23585 entering state [proceeding][180]
  395. 2018-10-30 12:34:13.924820 [NOTICE] sofia.c:7401 Ring-Ready sofia/internal/1003@183.82.203.220:23585!
  396. 2018-10-30 12:34:13.924820 [DEBUG] switch_channel.c:3354 (sofia/internal/1003@183.82.203.220:23585) Callstate Change DOWN -> RINGING
  397. send 903 bytes to udp/[183.82.203.220]:23502 at 12:34:13.946352:
  398.    ------------------------------------------------------------------------
  399.    SIP/2.0 180 Ringing
  400.    Via: SIP/2.0/UDP 192.168.1.4:64343;branch=z9hG4bK-524287-1---ad06303728d3f229;rport=23502;received=183.82.203.220
  401.    From: "1000"<sip:1000@159.89.163.195:5020>;tag=e2818c43
  402.    To: <sip:1000@159.89.163.195:5020>;tag=g8FXQ0788BHBF
  403.    Call-ID: 92627YjA3OTQ1NGE2MmZlZmI1NDNkNzhlNTcyODM4ZjU5MWQ
  404.    CSeq: 2 INVITE
  405.    Contact: <sip:1000@159.89.163.195:5020;transport=udp>
  406.    User-Agent: FreeSWITCH-mod_sofia/1.8.2+git~20180926T175525Z~a98a958ac3~64bit
  407.    Accept: application/sdp
  408.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  409.    Supported: timer, path, replaces
  410.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  411.    Content-Length: 0
  412.    Remote-Party-ID: "1000" <sip:1000@159.89.163.195>;party=calling;privacy=off;screen=no
  413.  
  414.    ------------------------------------------------------------------------
  415. 2018-10-30 12:34:13.944813 [NOTICE] mod_sofia.c:2419 Ring-Ready sofia/internal/1000@159.89.163.195:5020!
  416. 2018-10-30 12:34:13.944813 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@159.89.163.195:5020 entering state [early][180]
  417. 2018-10-30 12:34:13.944813 [NOTICE] switch_ivr_originate.c:553 Ring Ready sofia/internal/1000@159.89.163.195:5020!
  418. recv 802 bytes from tcp/[183.82.203.220]:23585 at 12:34:16.919063:
  419.    ------------------------------------------------------------------------
  420.    SIP/2.0 200 OK
  421.    Via: SIP/2.0/TCP 159.89.163.195:5020;branch=z9hG4bKZj8KD3vXjaQ9p
  422.    Contact: <sip:1003@183.82.203.220:23581>
  423.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>;tag=435b9221
  424.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  425.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  426.    CSeq: 130124362 INVITE
  427.   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  428.    Content-Type: application/sdp
  429.    User-Agent: Zoiper rv2.8.109
  430.    Allow-Events: presence, kpml, talk
  431.    Content-Length: 245
  432.  
  433.    v=0
  434.    o=Zoiper 0 2 IN IP4 183.82.203.220
  435.    s=Zoiper
  436.    c=IN IP4 183.82.203.220
  437.    t=0 0
  438.    m=audio 24215 RTP/AVP 0 3 8 101
  439.    a=rtpmap:0 PCMU/8000
  440.    a=rtpmap:3 GSM/8000
  441.    a=rtpmap:8 PCMA/8000
  442.    a=rtpmap:101 telephone-event/8000
  443.    a=fmtp:101 0-16
  444.    a=sendrecv
  445.    ------------------------------------------------------------------------
  446. 2018-10-30 12:34:16.914819 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@183.82.203.220:23585 entering state [completing][200]
  447. 2018-10-30 12:34:16.914819 [DEBUG] sofia.c:7301 Remote SDP:
  448. v=0
  449. o=Zoiper 0 2 IN IP4 183.82.203.220
  450. s=Zoiper
  451. c=IN IP4 183.82.203.220
  452. t=0 0
  453. m=audio 24215 RTP/AVP 0 3 8 101
  454. a=rtpmap:0 PCMU/8000
  455. a=rtpmap:3 GSM/8000
  456. a=rtpmap:8 PCMA/8000
  457. a=rtpmap:101 telephone-event/8000
  458. a=fmtp:101 0-16
  459.  
  460. 2018-10-30 12:34:16.914819 [NOTICE] sofia.c:7304 Pre-Answer sofia/internal/1003@183.82.203.220:23585!
  461. 2018-10-30 12:34:16.914819 [DEBUG] switch_channel.c:3482 (sofia/internal/1003@183.82.203.220:23585) Callstate Change RINGING -> EARLY
  462. send 441 bytes to udp/[183.82.203.220]:23581 at 12:34:16.921025:
  463.    ------------------------------------------------------------------------
  464.    ACK sip:1003@183.82.203.220:23581 SIP/2.0
  465.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport;branch=z9hG4bK0U1cFyD1FKDvj
  466.    Max-Forwards: 70
  467.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  468.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>;tag=435b9221
  469.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  470.    CSeq: 130124362 ACK
  471.    Contact: <sip:mod_sofia@159.89.163.195:5020;transport=tcp>
  472.    Content-Length: 0
  473.  
  474.    ------------------------------------------------------------------------
  475. 2018-10-30 12:34:16.914819 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@183.82.203.220:23585 entering state [ready][200]
  476. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  477. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_media.c:5533 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  478. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  479. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  480. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_media.c:5394 Set telephone-event payload to 101@8000
  481. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_media.c:3781 Set Codec sofia/internal/1003@183.82.203.220:23585 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  482. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_codec.c:111 sofia/internal/1003@183.82.203.220:23585 Original read codec set to PCMU:0
  483. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_media.c:5737 Set telephone-event payload to 101@8000
  484. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_media.c:5795 sofia/internal/1003@183.82.203.220:23585 Set 2833 dtmf send payload to 101 recv payload to 101
  485. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_media.c:8511 AUDIO RTP [sofia/internal/1003@183.82.203.220:23585] 159.89.163.195 port 21980 -> 183.82.203.220 port 24215 codec: 0 ms: 20
  486. 2018-10-30 12:34:16.914819 [DEBUG] switch_rtp.c:4300 Starting timer [soft] 160 bytes per 20ms
  487. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_media.c:8815 sofia/internal/1003@183.82.203.220:23585 Set 2833 dtmf send payload to 101
  488. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_media.c:8822 sofia/internal/1003@183.82.203.220:23585 Set 2833 dtmf receive payload to 101
  489. 2018-10-30 12:34:16.914819 [DEBUG] switch_core_media.c:8845 sofia/internal/1003@183.82.203.220:23585 Set rtp dtmf delay to 40
  490. 2018-10-30 12:34:16.914819 [NOTICE] sofia.c:8429 Channel [sofia/internal/1003@183.82.203.220:23585] has been answered
  491. 2018-10-30 12:34:16.924812 [DEBUG] switch_channel.c:3781 (sofia/internal/1003@183.82.203.220:23585) Callstate Change EARLY -> ACTIVE
  492. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  493. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  494. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  495. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:5533 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  496. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  497. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:5394 Set telephone-event payload to 101@8000
  498. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:3781 Set Codec sofia/internal/1000@159.89.163.195:5020 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  499. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_codec.c:111 sofia/internal/1000@159.89.163.195:5020 Original read codec set to PCMU:0
  500. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:5737 Set telephone-event payload to 101@8000
  501. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:5795 sofia/internal/1000@159.89.163.195:5020 Set 2833 dtmf send payload to 101 recv payload to 101
  502. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:8511 AUDIO RTP [sofia/internal/1000@159.89.163.195:5020] 159.89.163.195 port 20662 -> 192.168.1.4 port 64120 codec: 0 ms: 20
  503. 2018-10-30 12:34:16.934820 [DEBUG] switch_rtp.c:4300 Starting timer [soft] 160 bytes per 20ms
  504. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:8815 sofia/internal/1000@159.89.163.195:5020 Set 2833 dtmf send payload to 101
  505. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:8822 sofia/internal/1000@159.89.163.195:5020 Set 2833 dtmf receive payload to 101
  506. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:8845 sofia/internal/1000@159.89.163.195:5020 Set rtp dtmf delay to 40
  507. 2018-10-30 12:34:16.934820 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1000@159.89.163.195:5020!
  508. 2018-10-30 12:34:16.934820 [DEBUG] switch_channel.c:3482 (sofia/internal/1000@159.89.163.195:5020) Callstate Change RINGING -> EARLY
  509. 2018-10-30 12:34:16.934820 [DEBUG] switch_core_media.c:8494 Audio params are unchanged for sofia/internal/1000@159.89.163.195:5020.
  510. 2018-10-30 12:34:16.934820 [DEBUG] mod_sofia.c:881 Local SDP sofia/internal/1000@159.89.163.195:5020:
  511. v=0
  512. o=FreeSWITCH 1540900194 1540900195 IN IP4 159.89.163.195
  513. s=FreeSWITCH
  514. c=IN IP4 159.89.163.195
  515. t=0 0
  516. m=audio 20662 RTP/AVP 0 101
  517. a=rtpmap:0 PCMU/8000
  518. a=rtpmap:101 telephone-event/8000
  519. a=fmtp:101 0-16
  520. a=ptime:20
  521. a=sendrecv
  522.  
  523. send 1205 bytes to udp/[183.82.203.220]:23502 at 12:34:16.940321:
  524.    ------------------------------------------------------------------------
  525.    SIP/2.0 200 OK
  526.    Via: SIP/2.0/UDP 192.168.1.4:64343;branch=z9hG4bK-524287-1---ad06303728d3f229;rport=23502;received=183.82.203.220
  527.    From: "1000"<sip:1000@159.89.163.195:5020>;tag=e2818c43
  528.    To: <sip:1000@159.89.163.195:5020>;tag=g8FXQ0788BHBF
  529.    Call-ID: 92627YjA3OTQ1NGE2MmZlZmI1NDNkNzhlNTcyODM4ZjU5MWQ
  530.    CSeq: 2 INVITE
  531.    Contact: <sip:1000@159.89.163.195:5020;transport=udp>
  532.    User-Agent: FreeSWITCH-mod_sofia/1.8.2+git~20180926T175525Z~a98a958ac3~64bit
  533.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  534.    Supported: timer, path, replaces
  535.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  536.    Session-Expires: 120;refresher=uas
  537.    Content-Type: application/sdp
  538.    Content-Disposition: session
  539.    Content-Length: 224
  540.    Remote-Party-ID: "Outbound Call" <sip:1003@159.89.163.195>;party=calling;privacy=off;screen=no
  541.  
  542.    v=0
  543.    o=FreeSWITCH 1540900194 1540900195 IN IP4 159.89.163.195
  544.    s=FreeSWITCH
  545.    c=IN IP4 159.89.163.195
  546.    t=0 0
  547.    m=audio 20662 RTP/AVP 0 101
  548.    a=rtpmap:0 PCMU/8000
  549.    a=rtpmap:101 telephone-event/8000
  550.    a=fmtp:101 0-16
  551.    a=ptime:20
  552.    ------------------------------------------------------------------------
  553. 2018-10-30 12:34:16.934820 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@159.89.163.195:5020 entering state [completed][200]
  554. 2018-10-30 12:34:16.934820 [NOTICE] switch_ivr_originate.c:3740 Channel [sofia/internal/1000@159.89.163.195:5020] has been answered
  555. EXECUTE sofia/internal/1000@159.89.163.195:5020 record_session(/temp_recordings/dev-scott_2018-10-30_ee634e42-e806-4e15-9abe-969d09a3eb33.mp3)
  556. 2018-10-30 12:34:16.934820 [ERR] switch_core_file.c:301 Invalid file format [mp3] for [/temp_recordings/dev-scott_2018-10-30_ee634e42-e806-4e15-9abe-969d09a3eb33.mp3]!
  557. 2018-10-30 12:34:16.934820 [ERR] switch_ivr_async.c:2737 Error opening /temp_recordings/dev-scott_2018-10-30_ee634e42-e806-4e15-9abe-969d09a3eb33.mp3
  558. 2018-10-30 12:34:16.934820 [DEBUG] switch_channel.c:3781 (sofia/internal/1000@159.89.163.195:5020) Callstate Change EARLY -> ACTIVE
  559. 2018-10-30 12:34:16.934820 [DEBUG] switch_ivr_originate.c:3798 Originate Resulted in Success: [sofia/internal/1003@183.82.203.220:23585]
  560. 2018-10-30 12:34:16.934820 [DEBUG] switch_ivr_originate.c:3798 Originate Resulted in Success: [sofia/internal/1003@183.82.203.220:23585]
  561. 2018-10-30 12:34:16.944808 [DEBUG] switch_ivr_bridge.c:1782 (sofia/internal/1003@183.82.203.220:23585) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  562. 2018-10-30 12:34:16.944808 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@183.82.203.220:23585) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 25)
  563. 2018-10-30 12:34:16.944808 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1003@183.82.203.220:23585) State EXCHANGE_MEDIA
  564. 2018-10-30 12:34:16.944808 [DEBUG] mod_sofia.c:645 SOFIA EXCHANGE_MEDIA
  565. 2018-10-30 12:34:17.014814 [DEBUG] switch_rtp.c:7550 Correct audio ip/port confirmed.
  566. recv 478 bytes from udp/[183.82.203.220]:23502 at 12:34:17.056268:
  567.    ------------------------------------------------------------------------
  568.    ACK sip:1000@159.89.163.195:5020;transport=udp SIP/2.0
  569.    Via: SIP/2.0/UDP 192.168.1.4:64343;branch=z9hG4bK-524287-1---2a69633807c6d734;rport
  570.    Max-Forwards: 70
  571.    Contact: <sip:1000@183.82.203.220:23502;rinstance=455085c26772fb89>
  572.    To: <sip:1000@159.89.163.195:5020>;tag=g8FXQ0788BHBF
  573.    From: "1000"<sip:1000@159.89.163.195:5020>;tag=e2818c43
  574.    Call-ID: 92627YjA3OTQ1NGE2MmZlZmI1NDNkNzhlNTcyODM4ZjU5MWQ
  575.    CSeq: 2 ACK
  576.    User-Agent: X-Lite release 5.3.2 stamp 92627
  577.    Content-Length: 0
  578.  
  579.    ------------------------------------------------------------------------
  580. 2018-10-30 12:34:17.054818 [DEBUG] sofia.c:7291 Channel sofia/internal/1000@159.89.163.195:5020 entering state [ready][200]
  581. 2018-10-30 12:34:17.274823 [INFO] switch_rtp.c:7510 Auto Changing audio port from 192.168.1.4:64120 to 183.82.203.220:24218
  582. recv 802 bytes from tcp/[183.82.203.220]:23585 at 12:34:17.342705:
  583.    ------------------------------------------------------------------------
  584.    SIP/2.0 200 OK
  585.    Via: SIP/2.0/TCP 159.89.163.195:5020;branch=z9hG4bKZj8KD3vXjaQ9p
  586.    Contact: <sip:1003@183.82.203.220:23581>
  587.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>;tag=435b9221
  588.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  589.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  590.    CSeq: 130124362 INVITE
  591.   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  592.    Content-Type: application/sdp
  593.    User-Agent: Zoiper rv2.8.109
  594.    Allow-Events: presence, kpml, talk
  595.    Content-Length: 245
  596.  
  597.    v=0
  598.    o=Zoiper 0 2 IN IP4 183.82.203.220
  599.    s=Zoiper
  600.    c=IN IP4 183.82.203.220
  601.    t=0 0
  602.    m=audio 24215 RTP/AVP 0 3 8 101
  603.    a=rtpmap:0 PCMU/8000
  604.    a=rtpmap:3 GSM/8000
  605.    a=rtpmap:8 PCMA/8000
  606.    a=rtpmap:101 telephone-event/8000
  607.    a=fmtp:101 0-16
  608.    a=sendrecv
  609.    ------------------------------------------------------------------------
  610. send 441 bytes to udp/[183.82.203.220]:23581 at 12:34:17.342834:
  611.    ------------------------------------------------------------------------
  612.    ACK sip:1003@183.82.203.220:23581 SIP/2.0
  613.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport;branch=z9hG4bK0U1cFyD1FKDvj
  614.    Max-Forwards: 70
  615.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  616.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>;tag=435b9221
  617.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  618.    CSeq: 130124362 ACK
  619.    Contact: <sip:mod_sofia@159.89.163.195:5020;transport=tcp>
  620.    Content-Length: 0
  621.  
  622.    ------------------------------------------------------------------------
  623. recv 802 bytes from tcp/[183.82.203.220]:23585 at 12:34:18.346737:
  624.    ------------------------------------------------------------------------
  625.    SIP/2.0 200 OK
  626.    Via: SIP/2.0/TCP 159.89.163.195:5020;branch=z9hG4bKZj8KD3vXjaQ9p
  627.    Contact: <sip:1003@183.82.203.220:23581>
  628.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>;tag=435b9221
  629.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  630.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  631.    CSeq: 130124362 INVITE
  632.   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  633.    Content-Type: application/sdp
  634.    User-Agent: Zoiper rv2.8.109
  635.    Allow-Events: presence, kpml, talk
  636.    Content-Length: 245
  637.  
  638.    v=0
  639.    o=Zoiper 0 2 IN IP4 183.82.203.220
  640.    s=Zoiper
  641.    c=IN IP4 183.82.203.220
  642.    t=0 0
  643.    m=audio 24215 RTP/AVP 0 3 8 101
  644.    a=rtpmap:0 PCMU/8000
  645.    a=rtpmap:3 GSM/8000
  646.    a=rtpmap:8 PCMA/8000
  647.    a=rtpmap:101 telephone-event/8000
  648.    a=fmtp:101 0-16
  649.    a=sendrecv
  650.    ------------------------------------------------------------------------
  651. send 441 bytes to udp/[183.82.203.220]:23581 at 12:34:18.346849:
  652.    ------------------------------------------------------------------------
  653.    ACK sip:1003@183.82.203.220:23581 SIP/2.0
  654.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport;branch=z9hG4bK0U1cFyD1FKDvj
  655.    Max-Forwards: 70
  656.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  657.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>;tag=435b9221
  658.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  659.    CSeq: 130124362 ACK
  660.    Contact: <sip:mod_sofia@159.89.163.195:5020;transport=tcp>
  661.    Content-Length: 0
  662.  
  663.    ------------------------------------------------------------------------
  664. recv 802 bytes from tcp/[183.82.203.220]:23585 at 12:34:20.345601:
  665.    ------------------------------------------------------------------------
  666.    SIP/2.0 200 OK
  667.    Via: SIP/2.0/TCP 159.89.163.195:5020;branch=z9hG4bKZj8KD3vXjaQ9p
  668.    Contact: <sip:1003@183.82.203.220:23581>
  669.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>;tag=435b9221
  670.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  671.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  672.    CSeq: 130124362 INVITE
  673.   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  674.    Content-Type: application/sdp
  675.    User-Agent: Zoiper rv2.8.109
  676.    Allow-Events: presence, kpml, talk
  677.    Content-Length: 245
  678.  
  679.    v=0
  680.    o=Zoiper 0 2 IN IP4 183.82.203.220
  681.    s=Zoiper
  682.    c=IN IP4 183.82.203.220
  683.    t=0 0
  684.    m=audio 24215 RTP/AVP 0 3 8 101
  685.    a=rtpmap:0 PCMU/8000
  686.    a=rtpmap:3 GSM/8000
  687.    a=rtpmap:8 PCMA/8000
  688.    a=rtpmap:101 telephone-event/8000
  689.    a=fmtp:101 0-16
  690.    a=sendrecv
  691.    ------------------------------------------------------------------------
  692. send 441 bytes to udp/[183.82.203.220]:23581 at 12:34:20.345717:
  693.    ------------------------------------------------------------------------
  694.    ACK sip:1003@183.82.203.220:23581 SIP/2.0
  695.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport;branch=z9hG4bK0U1cFyD1FKDvj
  696.    Max-Forwards: 70
  697.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  698.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>;tag=435b9221
  699.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  700.    CSeq: 130124362 ACK
  701.    Contact: <sip:mod_sofia@159.89.163.195:5020;transport=tcp>
  702.    Content-Length: 0
  703.  
  704.    ------------------------------------------------------------------------
  705. recv 802 bytes from tcp/[183.82.203.220]:23585 at 12:34:24.347363:
  706.    ------------------------------------------------------------------------
  707.    SIP/2.0 200 OK
  708.    Via: SIP/2.0/TCP 159.89.163.195:5020;branch=z9hG4bKZj8KD3vXjaQ9p
  709.    Contact: <sip:1003@183.82.203.220:23581>
  710.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>;tag=435b9221
  711.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  712.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  713.    CSeq: 130124362 INVITE
  714.   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  715.    Content-Type: application/sdp
  716.    User-Agent: Zoiper rv2.8.109
  717.    Allow-Events: presence, kpml, talk
  718.    Content-Length: 245
  719.  
  720.    v=0
  721.    o=Zoiper 0 2 IN IP4 183.82.203.220
  722.    s=Zoiper
  723.    c=IN IP4 183.82.203.220
  724.    t=0 0
  725.    m=audio 24215 RTP/AVP 0 3 8 101
  726.    a=rtpmap:0 PCMU/8000
  727.    a=rtpmap:3 GSM/8000
  728.    a=rtpmap:8 PCMA/8000
  729.    a=rtpmap:101 telephone-event/8000
  730.    a=fmtp:101 0-16
  731.    a=sendrecv
  732.    ------------------------------------------------------------------------
  733. send 441 bytes to udp/[183.82.203.220]:23581 at 12:34:24.347512:
  734.    ------------------------------------------------------------------------
  735.    ACK sip:1003@183.82.203.220:23581 SIP/2.0
  736.    Via: SIP/2.0/UDP 159.89.163.195:5020;rport;branch=z9hG4bK0U1cFyD1FKDvj
  737.    Max-Forwards: 70
  738.    From: "Extension 1000" <sip:1000@159.89.163.195>;tag=HH9NSUrc6m7Xa
  739.    To: <sip:1003@183.82.203.220:23585;transport=TCP;rinstance=eff915fadb99c0b0>;tag=435b9221
  740.    Call-ID: db3d7dda-570c-1237-2b97-528373432f88
  741.    CSeq: 130124362 ACK
  742.    Contact: <sip:mod_sofia@159.89.163.195:5020;transport=tcp>
  743.    Content-Length: 0
  744.  
  745.    ------------------------------------------------------------------------
  746. freeswitch@shashi> ...
  747.