FreeSWITCH Pastebin

Re: Re: 通过网关外拨, FS没有发送RTP给外线网关(trace日志)

From lzpong, 7 Years ago, written in FreeSWITCH.
This paste is a reply to Re: 通过网关外拨, FS没有发送RTP(trace日志) from lzpong
- view diff
URL https://pastebin.freeswitch.org/view/0a1e47c5
Embed
Download Paste or View Raw
  1. 192.168.1.101  外线网关
  2. 192.168.1.33   分机网关
  3. 192.168.1.246  FS
  4.  
  5.  
  6. 外线 呼入, 没问题
  7. 分机呼出外线,  FS 就不发rtp 了,  貌似 端口 也没有打开
  8.  
  9.  
  10. 日志:
  11. ==========================================================================================
  12.  
  13. recv 817 bytes from udp/[192.168.1.33]:5060 at 14:45:58.514350:
  14.    ------------------------------------------------------------------------
  15.    INVITE sip:9186******73@127.0.0.1;user=phone SIP/2.0
  16.    Via: SIP/2.0/UDP 192.168.1.33:5060;branch=z9hG4bK1202;rport
  17.    From: "8005" <sip:8005@127.0.0.1;user=phone>;tag=3cd16e0589b326696
  18.    To: <sip:9186******73@127.0.0.1;user=phone>
  19.    Call-ID: 3cd16e0589b39777@192.168.1.33
  20.    CSeq: 2081 INVITE
  21.    Contact: "8005" <sip:8005@192.168.1.33:5060>
  22.    Max-Forwards: 70
  23.    User-Agent: SIPUA
  24.    Expires: 120
  25.    Supported: 100rel
  26.    P-Preferred-Identity: "8005" <sip:8005@127.0.0.1;user=phone>
  27.    Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, REFER, SUBSCRIBE, NOTIFY, MESSAGE, UPDATE, PRACK
  28.    Content-Type: application/sdp
  29.    Content-Length: 206
  30.    
  31.    v=0
  32.    o=8005 20000001 31 IN IP4 192.168.1.33
  33.    s=A call
  34.    c=IN IP4 192.168.1.33
  35.    t=0 0
  36.    m=audio 10000 RTP/AVP 18 0 8
  37.    a=rtpmap:18 G729/8000
  38.    a=rtpmap:0 PCMU/8000
  39.    a=rtpmap:8 PCMA/8000
  40.    a=ptime:20
  41.    a=sendrecv
  42.    ------------------------------------------------------------------------
  43. send 359 bytes to udp/[192.168.1.33]:5060 at 14:45:58.514671:
  44.    ------------------------------------------------------------------------
  45.    SIP/2.0 100 Trying
  46.    Via: SIP/2.0/UDP 192.168.1.33:5060;branch=z9hG4bK1202;rport=5060
  47.    From: "8005" <sip:8005@127.0.0.1;user=phone>;tag=3cd16e0589b326696
  48.    To: <sip:9186******73@127.0.0.1;user=phone>
  49.    Call-ID: 3cd16e0589b39777@192.168.1.33
  50.    CSeq: 2081 INVITE
  51.    User-Agent: FreeSWITCH-mod_sofia/1.5.15b+git~20141220T224621Z~0a0b9262b4~64bit
  52.    Content-Length: 0
  53.    
  54.    ------------------------------------------------------------------------
  55. 2017-04-14 14:45:58.512503 [NOTICE] switch_channel.c:1055 New Channel sofia/internal/8005@127.0.0.1 [044e242e-20de-11e7-ba33-f7c335f7649a]
  56. 2017-04-14 14:45:58.512503 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  57. 2017-04-14 14:45:58.512503 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  58. 2017-04-14 14:45:58.512503 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/8005@127.0.0.1) Running State Change CS_NEW
  59. 2017-04-14 14:45:58.512503 [DEBUG] sofia.c:8834 sofia/internal/8005@127.0.0.1 receiving invite from 192.168.1.33:5060 version: 1.5.15b git 0a0b926 2014-12-20 22:46:21Z 64bit
  60. 2017-04-14 14:45:58.512503 [DEBUG] sofia.c:9001 IP 192.168.1.33 Rejected by acl "domains". Falling back to Digest auth.
  61. 2017-04-14 14:45:58.512503 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/8005@127.0.0.1) State NEW
  62. send 859 bytes to udp/[192.168.1.33]:5060 at 14:45:58.515399:
  63.    ------------------------------------------------------------------------
  64.    SIP/2.0 407 Proxy Authentication Required
  65.    Via: SIP/2.0/UDP 192.168.1.33:5060;branch=z9hG4bK1202;rport=5060
  66.    From: "8005" <sip:8005@127.0.0.1;user=phone>;tag=3cd16e0589b326696
  67.    To: <sip:9186******73@127.0.0.1;user=phone>;tag=1Zmp3ej28685B
  68.    Call-ID: 3cd16e0589b39777@192.168.1.33
  69.    CSeq: 2081 INVITE
  70.    User-Agent: FreeSWITCH-mod_sofia/1.5.15b+git~20141220T224621Z~0a0b9262b4~64bit
  71.    Accept: application/sdp
  72.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  73.    Supported: timer, path, replaces
  74.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  75.    Proxy-Authenticate: Digest realm="127.0.0.1", nonce="044e2e1a-20de-11e7-ba34-f7c335f7649a", algorithm=MD5, qop="auth"
  76.    Content-Length: 0
  77.    
  78.    ------------------------------------------------------------------------
  79. 2017-04-14 14:45:58.512503 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  80. 2017-04-14 14:45:58.512503 [DEBUG] sofia.c:2067 detaching session 044e242e-20de-11e7-ba33-f7c335f7649a
  81. recv 320 bytes from udp/[192.168.1.33]:5060 at 14:45:58.535989:
  82.    ------------------------------------------------------------------------
  83.    ACK sip:9186******73@127.0.0.1;user=phone SIP/2.0
  84.    Via: SIP/2.0/UDP 192.168.1.33:5060;branch=z9hG4bK1202;rport
  85.    From: "8005" <sip:8005@127.0.0.1;user=phone>;tag=3cd16e0589b326696
  86.    To: <sip:9186******73@127.0.0.1;user=phone>;tag=1Zmp3ej28685B
  87.    Call-ID: 3cd16e0589b39777@192.168.1.33
  88.    CSeq: 2081 ACK
  89.    Content-Length: 0
  90.    
  91.    ------------------------------------------------------------------------
  92. recv 1051 bytes from udp/[192.168.1.33]:5060 at 14:45:58.537349:
  93.    ------------------------------------------------------------------------
  94.    INVITE sip:9186******73@127.0.0.1;user=phone SIP/2.0
  95.    Via: SIP/2.0/UDP 192.168.1.33:5060;branch=z9hG4bK19726;rport
  96.    From: "8005" <sip:8005@127.0.0.1;user=phone>;tag=3cd16e0589b326696
  97.    To: <sip:9186******73@127.0.0.1;user=phone>
  98.    Call-ID: 3cd16e0589b39777@192.168.1.33
  99.    CSeq: 2082 INVITE
  100.    Contact: "8005" <sip:8005@192.168.1.33:5060>
  101.    Proxy-Authorization: Digest username="8005", realm="127.0.0.1", nonce="044e2e1a-20de-11e7-ba34-f7c335f7649a", uri="sip:127.0.0.1", response="901c2c5bf4cd4b618c9d52a03ce07108", algorithm=MD5, cnonce="000010cc", qop=auth, nc=00000001
  102.    Max-Forwards: 70
  103.    User-Agent: SIPUA
  104.    Expires: 120
  105.    Supported: 100rel
  106.    P-Preferred-Identity: "8005" <sip:8005@127.0.0.1;user=phone>
  107.    Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, REFER, SUBSCRIBE, NOTIFY, MESSAGE, UPDATE, PRACK
  108.    Content-Type: application/sdp
  109.    Content-Length: 206
  110.    
  111.    v=0
  112.    o=8005 20000001 31 IN IP4 192.168.1.33
  113.    s=A call
  114.    c=IN IP4 192.168.1.33
  115.    t=0 0
  116.    m=audio 10000 RTP/AVP 18 0 8
  117.    a=rtpmap:18 G729/8000
  118.    a=rtpmap:0 PCMU/8000
  119.    a=rtpmap:8 PCMA/8000
  120.    a=ptime:20
  121.    a=sendrecv
  122.    ------------------------------------------------------------------------
  123. send 360 bytes to udp/[192.168.1.33]:5060 at 14:45:58.537639:
  124.    ------------------------------------------------------------------------
  125.    SIP/2.0 100 Trying
  126.    Via: SIP/2.0/UDP 192.168.1.33:5060;branch=z9hG4bK19726;rport=5060
  127.    From: "8005" <sip:8005@127.0.0.1;user=phone>;tag=3cd16e0589b326696
  128.    To: <sip:9186******73@127.0.0.1;user=phone>
  129.    Call-ID: 3cd16e0589b39777@192.168.1.33
  130.    CSeq: 2082 INVITE
  131.    User-Agent: FreeSWITCH-mod_sofia/1.5.15b+git~20141220T224621Z~0a0b9262b4~64bit
  132.    Content-Length: 0
  133.    
  134.    ------------------------------------------------------------------------
  135. 2017-04-14 14:45:58.532512 [DEBUG] sofia.c:2175 Re-attaching to session 044e242e-20de-11e7-ba33-f7c335f7649a
  136. 2017-04-14 14:45:58.532512 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  137. 2017-04-14 14:45:58.532512 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  138. 2017-04-14 14:45:58.552511 [DEBUG] sofia.c:8834 sofia/internal/8005@127.0.0.1 receiving invite from 192.168.1.33:5060 version: 1.5.15b git 0a0b926 2014-12-20 22:46:21Z 64bit
  139. 2017-04-14 14:45:58.552511 [DEBUG] sofia.c:9001 IP 192.168.1.33 Rejected by acl "domains". Falling back to Digest auth.
  140. 2017-04-14 14:45:58.552511 [DEBUG] sofia.c:6614 Channel sofia/internal/8005@127.0.0.1 entering state [received][100]
  141. 2017-04-14 14:45:58.552511 [DEBUG] sofia.c:6624 Remote SDP:
  142. v=0
  143. o=8005 20000001 31 IN IP4 192.168.1.33
  144. s=A call
  145. c=IN IP4 192.168.1.33
  146. t=0 0
  147. m=audio 10000 RTP/AVP 18 0 8
  148. a=rtpmap:18 G729/8000
  149. a=rtpmap:0 PCMU/8000
  150. a=rtpmap:8 PCMA/8000
  151. a=ptime:20
  152.  
  153. 2017-04-14 14:45:58.552511 [DEBUG] sofia.c:6890 (sofia/internal/8005@127.0.0.1) State Change CS_NEW -> CS_INIT
  154. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  155. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/8005@127.0.0.1) Running State Change CS_INIT
  156. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/8005@127.0.0.1) State INIT
  157. 2017-04-14 14:45:58.552511 [DEBUG] mod_sofia.c:87 sofia/internal/8005@127.0.0.1 SOFIA INIT
  158. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:40 sofia/internal/8005@127.0.0.1 Standard INIT
  159. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/8005@127.0.0.1) State Change CS_INIT -> CS_ROUTING
  160. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  161. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/8005@127.0.0.1) State INIT going to sleep
  162. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/8005@127.0.0.1) Running State Change CS_ROUTING
  163. 2017-04-14 14:45:58.552511 [DEBUG] switch_channel.c:2184 (sofia/internal/8005@127.0.0.1) Callstate Change DOWN -> RINGING
  164. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/8005@127.0.0.1) State ROUTING
  165. 2017-04-14 14:45:58.552511 [DEBUG] mod_sofia.c:123 sofia/internal/8005@127.0.0.1 SOFIA ROUTING
  166. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:166 sofia/internal/8005@127.0.0.1 Standard ROUTING
  167. 2017-04-14 14:45:58.552511 [INFO] mod_dialplan_xml.c:635 Processing 8005 <8005>->9186******73 in context default
  168. Dialplan: sofia/internal/8005@127.0.0.1 parsing [default->tod_example] continue=true
  169. Dialplan: sofia/internal/8005@127.0.0.1 Date/Time Match (PASS) [tod_example] break=on-true
  170. Dialplan: sofia/internal/8005@127.0.0.1 Action set(open=true)
  171. Dialplan: sofia/internal/8005@127.0.0.1 parsing [default->holiday_example] continue=true
  172. Dialplan: sofia/internal/8005@127.0.0.1 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  173. Dialplan: sofia/internal/8005@127.0.0.1 parsing [default->Local_Extension] continue=false
  174. Dialplan: sofia/internal/8005@127.0.0.1 Regex (FAIL) [Local_Extension] destination_number(9186******73) =~ /^[18]0\d{2}$/ break=on-false
  175. Dialplan: sofia/internal/8005@127.0.0.1 parsing [default->call_out3] continue=false
  176. Dialplan: sofia/internal/8005@127.0.0.1 Regex (PASS) [call_out3] destination_number(9186******73) =~ /^9(\d+)$/ break=on-false
  177. Dialplan: sofia/internal/8005@127.0.0.1 Action log(INFO ${sip_from_user})
  178. Dialplan: sofia/internal/8005@127.0.0.1 Action bridge({originion_caller_id_name=${outbound_caller_id_number},origination_caller_id_number=${outbound_caller_id_number}}sofia/external/186******73@192.168.1.101)
  179. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/8005@127.0.0.1) State Change CS_ROUTING -> CS_EXECUTE
  180. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  181. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/8005@127.0.0.1) State ROUTING going to sleep
  182. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/8005@127.0.0.1) Running State Change CS_EXECUTE
  183. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/8005@127.0.0.1) State EXECUTE
  184. 2017-04-14 14:45:58.552511 [DEBUG] mod_sofia.c:178 sofia/internal/8005@127.0.0.1 SOFIA EXECUTE
  185. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:258 sofia/internal/8005@127.0.0.1 Standard EXECUTE
  186. EXECUTE sofia/internal/8005@127.0.0.1 set(open=true)
  187. 2017-04-14 14:45:58.552511 [DEBUG] mod_dptools.c:1435 sofia/internal/8005@127.0.0.1 SET [open]=[true]
  188. EXECUTE sofia/internal/8005@127.0.0.1 log(INFO 8005)
  189. 2017-04-14 14:45:58.552511 [INFO] mod_dptools.c:1628 8005
  190. EXECUTE sofia/internal/8005@127.0.0.1 bridge({originion_caller_id_name=0000000000,origination_caller_id_number=0000000000}sofia/external/186******73@192.168.1.101)
  191. 2017-04-14 14:45:58.552511 [DEBUG] switch_ivr_originate.c:2103 Parsing global variables
  192. 2017-04-14 14:45:58.552511 [DEBUG] switch_event.c:1688 Parsing variable [originion_caller_id_name]=[0000000000]
  193. 2017-04-14 14:45:58.552511 [DEBUG] switch_event.c:1688 Parsing variable [origination_caller_id_number]=[0000000000]
  194. 2017-04-14 14:45:58.552511 [NOTICE] switch_channel.c:1055 New Channel sofia/external/186******73@192.168.1.101 [0454d666-20de-11e7-ba3c-f7c335f7649a]
  195. 2017-04-14 14:45:58.552511 [DEBUG] mod_sofia.c:4627 (sofia/external/186******73@192.168.1.101) State Change CS_NEW -> CS_INIT
  196. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_session.c:1388 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  197. 2017-04-14 14:45:58.552511 [DEBUG] mod_sofia.c:4697 [zrtp_passthru] Setting a-leg inherit_codec=true
  198. 2017-04-14 14:45:58.552511 [DEBUG] mod_sofia.c:4700 [zrtp_passthru] Setting b-leg absolute_codec_string='PCMU@8000h@20i@64000b,PCMA@8000h@20i@64000b'
  199. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:472 (sofia/external/186******73@192.168.1.101) Running State Change CS_INIT
  200. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:512 (sofia/external/186******73@192.168.1.101) State INIT
  201. 2017-04-14 14:45:58.552511 [DEBUG] mod_sofia.c:87 sofia/external/186******73@192.168.1.101 SOFIA INIT
  202. 2017-04-14 14:45:58.552511 [DEBUG] sofia_glue.c:1232 sofia/external/186******73@192.168.1.101 sending invite version: 1.5.15b git 0a0b926 2014-12-20 22:46:21Z 64bit
  203. Local SDP:
  204. v=0
  205. o=FreeSWITCH 1492122818 1492122819 IN IP4 192.168.1.246
  206. s=FreeSWITCH
  207. c=IN IP4 192.168.1.246
  208. t=0 0
  209. m=audio 29540 RTP/AVP 0 8 101 13
  210. a=rtpmap:0 PCMU/8000
  211. a=rtpmap:8 PCMA/8000
  212. a=rtpmap:101 telephone-event/8000
  213. a=fmtp:101 0-16
  214. a=ptime:20
  215. a=sendrecv
  216.  
  217. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:40 sofia/external/186******73@192.168.1.101 Standard INIT
  218. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:48 (sofia/external/186******73@192.168.1.101) State Change CS_INIT -> CS_ROUTING
  219. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_session.c:1388 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  220. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:512 (sofia/external/186******73@192.168.1.101) State INIT going to sleep
  221. send 1068 bytes to udp/[192.168.1.101]:5060 at 14:45:58.559681:
  222.    ------------------------------------------------------------------------
  223.    INVITE sip:186******73@192.168.1.101 SIP/2.0
  224.    Via: SIP/2.0/UDP 192.168.1.246:5080;rport;branch=z9hG4bKrSSavg44NcDQN
  225.    Max-Forwards: 69
  226.    From: "Extension 8005" <sip:0000000000@192.168.1.246>;tag=SygvtFjSyD4Hg
  227.    To: <sip:186******73@192.168.1.101>
  228.    Call-ID: dbb3399b-9b80-1235-b997-000bab7a8683
  229.    CSeq: 105740115 INVITE
  230.    Contact: <sip:mod_sofia@192.168.1.246:5080>
  231.    User-Agent: FreeSWITCH-mod_sofia/1.5.15b+git~20141220T224621Z~0a0b9262b4~64bit
  232.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  233.    Supported: timer, path, replaces
  234.    Allow-Events: talk, hold, conference, refer
  235.    Privacy: none
  236.    Content-Type: application/sdp
  237.    Content-Disposition: session
  238.    Content-Length: 249
  239.    X-FS-Support: update_display,send_info
  240.    P-Asserted-Identity: "Extension 8005" <sip:0000000000@192.168.1.246>
  241.    
  242.    v=0
  243.    o=FreeSWITCH 1492122818 1492122819 IN IP4 192.168.1.246
  244.    s=FreeSWITCH
  245.    c=IN IP4 192.168.1.246
  246.    t=0 0
  247.    m=audio 29540 RTP/AVP 0 8 101 13
  248.    a=rtpmap:0 PCMU/8000
  249.    a=rtpmap:8 PCMA/8000
  250.    a=rtpmap:101 telephone-event/8000
  251.    a=fmtp:101 0-16
  252.    a=ptime:20
  253.    ------------------------------------------------------------------------
  254. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  255. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:472 (sofia/external/186******73@192.168.1.101) Running State Change CS_ROUTING
  256. 2017-04-14 14:45:58.552511 [DEBUG] sofia.c:6614 Channel sofia/external/186******73@192.168.1.101 entering state [calling][0]
  257. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:528 (sofia/external/186******73@192.168.1.101) State ROUTING
  258. 2017-04-14 14:45:58.552511 [DEBUG] mod_sofia.c:123 sofia/external/186******73@192.168.1.101 SOFIA ROUTING
  259. 2017-04-14 14:45:58.552511 [DEBUG] switch_ivr_originate.c:67 (sofia/external/186******73@192.168.1.101) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  260. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_session.c:1388 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  261. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:528 (sofia/external/186******73@192.168.1.101) State ROUTING going to sleep
  262. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:472 (sofia/external/186******73@192.168.1.101) Running State Change CS_CONSUME_MEDIA
  263. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:547 (sofia/external/186******73@192.168.1.101) State CONSUME_MEDIA
  264. 2017-04-14 14:45:58.552511 [DEBUG] switch_core_state_machine.c:547 (sofia/external/186******73@192.168.1.101) State CONSUME_MEDIA going to sleep
  265. recv 319 bytes from udp/[192.168.1.101]:5060 at 14:45:58.585924:
  266.    ------------------------------------------------------------------------
  267.    SIP/2.0 100 Trying
  268.    Via: SIP/2.0/UDP 192.168.1.246:5080;rport=5080;branch=z9hG4bKrSSavg44NcDQN
  269.    From: "Extension 8005" <sip:0000000000@192.168.1.246>;tag=SygvtFjSyD4Hg
  270.    To: <sip:186******73@192.168.1.101>
  271.    Call-ID: dbb3399b-9b80-1235-b997-000bab7a8683
  272.    CSeq: 105740115 INVITE
  273.    User-Agent: Gateway
  274.    Content-Length: 0
  275.    
  276.    ------------------------------------------------------------------------
  277. recv 630 bytes from udp/[192.168.1.101]:5060 at 14:46:01.560002:
  278.    ------------------------------------------------------------------------
  279.    SIP/2.0 200 OK
  280.    Via: SIP/2.0/UDP 192.168.1.246:5080;rport=5080;branch=z9hG4bKrSSavg44NcDQN
  281.    From: "Extension 8005" <sip:0000000000@192.168.1.246>;tag=SygvtFjSyD4Hg
  282.    To: <sip:186******73@192.168.1.101>;tag=3271950963
  283.    Call-ID: dbb3399b-9b80-1235-b997-000bab7a8683
  284.    CSeq: 105740115 INVITE
  285.    Contact: <sip:186******73@192.168.1.101:5060>
  286.    User-Agent: Gateway
  287.    Content-Type: application/sdp
  288.    Content-Length:   218
  289.    
  290.    v=0
  291.    o=- 65 66 IN IP4 192.168.1.101
  292.    s=Gateway
  293.    c=IN IP4 192.168.1.101
  294.    t=0 0
  295.    m=audio 50000 RTP/AVP 0 8 101
  296.    a=rtpmap:0 PCMU/8000
  297.    a=rtpmap:8 PCMA/8000
  298.    a=rtpmap:101 telephone-event/8000
  299.    a=fmtp:101 0-15
  300.    a=sendrecv
  301.    ------------------------------------------------------------------------
  302. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  303. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  304. 2017-04-14 14:46:01.552506 [DEBUG] sofia.c:6614 Channel sofia/external/186******73@192.168.1.101 entering state [completing][200]
  305. 2017-04-14 14:46:01.552506 [DEBUG] sofia.c:6624 Remote SDP:
  306. v=0
  307. o=- 65 66 IN IP4 192.168.1.101
  308. s=Gateway
  309. c=IN IP4 192.168.1.101
  310. t=0 0
  311. m=audio 50000 RTP/AVP 0 8 101
  312. a=rtpmap:0 PCMU/8000
  313. a=rtpmap:8 PCMA/8000
  314. a=rtpmap:101 telephone-event/8000
  315. a=fmtp:101 0-15
  316.  
  317. send 396 bytes to udp/[192.168.1.101]:5060 at 14:46:01.560851:
  318.    ------------------------------------------------------------------------
  319.    ACK sip:186******73@192.168.1.101:5060 SIP/2.0
  320.    Via: SIP/2.0/UDP 192.168.1.246:5080;rport;branch=z9hG4bKS2j3XBN8jN39g
  321.    Max-Forwards: 70
  322.    From: "Extension 8005" <sip:0000000000@192.168.1.246>;tag=SygvtFjSyD4Hg
  323.    To: <sip:186******73@192.168.1.101>;tag=3271950963
  324.    Call-ID: dbb3399b-9b80-1235-b997-000bab7a8683
  325.    CSeq: 105740115 ACK
  326.    Contact: <sip:mod_sofia@192.168.1.246:5080>
  327.    Content-Length: 0
  328.    
  329.    ------------------------------------------------------------------------
  330. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  331. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  332. 2017-04-14 14:46:01.552506 [DEBUG] sofia.c:6614 Channel sofia/external/186******73@192.168.1.101 entering state [ready][200]
  333. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3627 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  334. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3682 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  335. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3627 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  336. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3627 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  337. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3627 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  338. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3682 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  339. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3543 Set telephone-event payload to 101
  340. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:2473 Set Codec sofia/external/186******73@192.168.1.101 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  341. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_codec.c:111 sofia/external/186******73@192.168.1.101 Original read codec set to PCMU:0
  342. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3881 Set 2833 dtmf send payload to 101
  343. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:5141 AUDIO RTP [sofia/external/186******73@192.168.1.101] 192.168.1.246 port 29540 -> 192.168.1.101 port 50000 codec: 0 ms: 20
  344. 2017-04-14 14:46:01.552506 [DEBUG] switch_rtp.c:3548 Starting timer [soft] 160 bytes per 20ms
  345. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:5439 Set 2833 dtmf send payload to 101
  346. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:5445 Set 2833 dtmf receive payload to 101
  347. 2017-04-14 14:46:01.552506 [DEBUG] switch_channel.c:3635 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  348. 2017-04-14 14:46:01.552506 [NOTICE] sofia.c:7475 Channel [sofia/external/186******73@192.168.1.101] has been answered
  349. 2017-04-14 14:46:01.552506 [DEBUG] switch_channel.c:3689 (sofia/external/186******73@192.168.1.101) Callstate Change DOWN -> ACTIVE
  350. 2017-04-14 14:46:01.552506 [DEBUG] switch_ivr_originate.c:412 Setting codec string on sofia/internal/8005@127.0.0.1 to PCMU@8000h@20i
  351. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3627 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  352. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3627 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  353. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3682 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  354. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3627 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  355. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:2473 Set Codec sofia/internal/8005@127.0.0.1 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  356. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_codec.c:111 sofia/internal/8005@127.0.0.1 Original read codec set to PCMU:0
  357. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:3903 No 2833 in SDP.  Disable 2833 dtmf and switch to INFO
  358. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_media.c:5141 AUDIO RTP [sofia/internal/8005@127.0.0.1] 192.168.1.246 port 22532 -> 192.168.1.33 port 10000 codec: 0 ms: 20
  359. 2017-04-14 14:46:01.552506 [DEBUG] switch_rtp.c:3548 Starting timer [soft] 160 bytes per 20ms
  360. 2017-04-14 14:46:01.552506 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/8005@127.0.0.1!
  361. 2017-04-14 14:46:01.552506 [DEBUG] switch_channel.c:3399 (sofia/internal/8005@127.0.0.1) Callstate Change RINGING -> EARLY
  362. 2017-04-14 14:46:01.552506 [DEBUG] mod_sofia.c:780 Local SDP sofia/internal/8005@127.0.0.1:
  363. v=0
  364. o=FreeSWITCH 1492129829 1492129830 IN IP4 192.168.1.246
  365. s=FreeSWITCH
  366. c=IN IP4 192.168.1.246
  367. t=0 0
  368. m=audio 22532 RTP/AVP 0
  369. a=rtpmap:0 PCMU/8000
  370. a=ptime:20
  371. a=sendrecv
  372.  
  373. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_session.c:908 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  374. send 1071 bytes to udp/[192.168.1.33]:5060 at 14:46:01.566374:
  375.    ------------------------------------------------------------------------
  376. 2017-04-14 14:46:01.552506 [NOTICE] switch_ivr_originate.c:3522 Channel [sofia/internal/8005@127.0.0.1] has been answered
  377.    SIP/2.0 200 OK
  378.    Via: SIP/2.0/UDP 192.168.1.33:5060;branch=z9hG4bK19726;rport=5060
  379.    From: "8005" <sip:8005@127.0.0.1;user=phone>;tag=3cd16e0589b326696
  380.    To: <sip:9186******73@127.0.0.1;user=phone>;tag=28DF59255FZrQ
  381.    Call-ID: 3cd16e0589b39777@192.168.1.33
  382.    CSeq: 2082 INVITE
  383.    Contact: <sip:9186******73@117.185.49.89:5060;transport=udp>
  384.    User-Agent: FreeSWITCH-mod_sofia/1.5.15b+git~20141220T224621Z~0a0b9262b4~64bit
  385.    Accept: application/sdp
  386.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  387.    Supported: timer, path, replaces
  388.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  389.    Content-Type: application/sdp
  390.    Content-Disposition: session
  391.    Content-Length: 166
  392.    P-Asserted-Identity: "Outbound Call" <sip:186******73@127.0.0.1>
  393.    
  394.    v=0
  395.    o=FreeSWITCH 1492129829 1492129830 IN IP4 192.168.1.246
  396.    s=FreeSWITCH
  397.    c=IN IP4 192.168.1.246
  398.    t=0 0
  399.    m=audio 22532 RTP/AVP 0
  400.    a=rtpmap:0 PCMU/8000
  401.    a=ptime:20
  402.    ------------------------------------------------------------------------
  403. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  404. 2017-04-14 14:46:01.552506 [DEBUG] switch_channel.c:3689 (sofia/internal/8005@127.0.0.1) Callstate Change EARLY -> ACTIVE
  405. 2017-04-14 14:46:01.552506 [DEBUG] sofia.c:6614 Channel sofia/internal/8005@127.0.0.1 entering state [completed][200]
  406. 2017-04-14 14:46:01.552506 [DEBUG] switch_ivr_originate.c:3580 Originate Resulted in Success: [sofia/external/186******73@192.168.1.101]
  407. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_session.c:908 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  408. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_session.c:908 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  409. 2017-04-14 14:46:01.552506 [DEBUG] switch_ivr_bridge.c:1465 (sofia/external/186******73@192.168.1.101) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  410. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_session.c:1388 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  411. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_state_machine.c:472 (sofia/external/186******73@192.168.1.101) Running State Change CS_EXCHANGE_MEDIA
  412. 2017-04-14 14:46:01.552506 [DEBUG] switch_core_state_machine.c:538 (sofia/external/186******73@192.168.1.101) State EXCHANGE_MEDIA
  413. 2017-04-14 14:46:01.552506 [DEBUG] mod_sofia.c:594 SOFIA EXCHANGE_MEDIA
  414. recv 376 bytes from udp/[192.168.1.33]:5060 at 14:46:01.576230:
  415.    ------------------------------------------------------------------------
  416.    ACK sip:9186******73@117.185.49.89:5060;transport=udp SIP/2.0
  417.    Via: SIP/2.0/UDP 192.168.1.33:5060;rport;branch=z9hG4bK27697;rport
  418.    From: "8005" <sip:8005@127.0.0.1;user=phone>;tag=3cd16e0589b326696
  419.    To: <sip:9186******73@127.0.0.1;user=phone>;tag=28DF59255FZrQ
  420.    Call-ID: 3cd16e0589b39777@192.168.1.33
  421.    CSeq: 2082 ACK
  422.    Max-Forwards: 70
  423.    User-Agent: SIPUA
  424.    Content-Length: 0
  425.    
  426.    ------------------------------------------------------------------------
  427. 2017-04-14 14:46:01.572513 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  428. 2017-04-14 14:46:01.572513 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  429. 2017-04-14 14:46:01.572513 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  430. 2017-04-14 14:46:01.592514 [DEBUG] sofia.c:6614 Channel sofia/internal/8005@127.0.0.1 entering state [ready][200]
  431. 2017-04-14 14:46:01.592514 [DEBUG] switch_core_session.c:970 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  432. 2017-04-14 14:46:01.592514 [DEBUG] switch_core_session.c:970 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  433. recv 414 bytes from udp/[192.168.1.101]:5060 at 14:46:06.867652:
  434.    ------------------------------------------------------------------------
  435.    BYE sip:mod_sofia@192.168.1.246:5080 SIP/2.0
  436.    Via: SIP/2.0/UDP 192.168.1.101:5060;branch=z9hG4bK3093601659;rport
  437.    From: <sip:186******73@192.168.1.101>;tag=3271950963
  438.    To: "Extension 8005" <sip:0000000000@192.168.1.246>;tag=SygvtFjSyD4Hg
  439.    Call-ID: dbb3399b-9b80-1235-b997-000bab7a8683
  440.    CSeq: 105740116 BYE
  441.    Contact: <sip:186******73@192.168.1.101:5060>
  442.    Max-Forwards: 70
  443.    User-Agent: Gateway
  444.    Content-Length: 0
  445.    
  446.    ------------------------------------------------------------------------
  447. 2017-04-14 14:46:06.852505 [DEBUG] switch_core_session.c:1053 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  448. 2017-04-14 14:46:06.872505 [NOTICE] sofia.c:952 Hangup sofia/external/186******73@192.168.1.101 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  449. 2017-04-14 14:46:06.872505 [DEBUG] switch_channel.c:3222 Send signal sofia/external/186******73@192.168.1.101 [KILL]
  450. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_session.c:1388 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  451. send 507 bytes to udp/[192.168.1.101]:5060 at 14:46:06.873375:
  452.    ------------------------------------------------------------------------
  453.    SIP/2.0 200 OK
  454.    Via: SIP/2.0/UDP 192.168.1.101:5060;branch=z9hG4bK3093601659;rport=5060
  455.    From: <sip:186******73@192.168.1.101>;tag=3271950963
  456.    To: "Extension 8005" <sip:0000000000@192.168.1.246>;tag=SygvtFjSyD4Hg
  457.    Call-ID: dbb3399b-9b80-1235-b997-000bab7a8683
  458.    CSeq: 105740116 BYE
  459.    User-Agent: FreeSWITCH-mod_sofia/1.5.15b+git~20141220T224621Z~0a0b9262b4~64bit
  460.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  461.    Supported: timer, path, replaces
  462.    Content-Length: 0
  463.    
  464.    ------------------------------------------------------------------------
  465. 2017-04-14 14:46:06.872505 [DEBUG] switch_ivr_bridge.c:660 BRIDGE THREAD DONE [sofia/external/186******73@192.168.1.101]
  466. 2017-04-14 14:46:06.872505 [DEBUG] switch_ivr_bridge.c:690 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  467. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_state_machine.c:538 (sofia/external/186******73@192.168.1.101) State EXCHANGE_MEDIA going to sleep
  468. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_state_machine.c:472 (sofia/external/186******73@192.168.1.101) Running State Change CS_HANGUP
  469. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_state_machine.c:735 (sofia/external/186******73@192.168.1.101) Callstate Change ACTIVE -> HANGUP
  470. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_state_machine.c:737 (sofia/external/186******73@192.168.1.101) State HANGUP
  471. 2017-04-14 14:46:06.872505 [DEBUG] mod_sofia.c:413 Channel sofia/external/186******73@192.168.1.101 hanging up, cause: NORMAL_CLEARING
  472. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_state_machine.c:60 sofia/external/186******73@192.168.1.101 Standard HANGUP, cause: NORMAL_CLEARING
  473. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_state_machine.c:737 (sofia/external/186******73@192.168.1.101) State HANGUP going to sleep
  474. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_state_machine.c:504 (sofia/external/186******73@192.168.1.101) State Change CS_HANGUP -> CS_REPORTING
  475. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_session.c:1388 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  476. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_state_machine.c:472 (sofia/external/186******73@192.168.1.101) Running State Change CS_REPORTING
  477. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_state_machine.c:823 (sofia/external/186******73@192.168.1.101) State REPORTING
  478. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_state_machine.c:104 sofia/external/186******73@192.168.1.101 Standard REPORTING, cause: NORMAL_CLEARING
  479. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_state_machine.c:823 (sofia/external/186******73@192.168.1.101) State REPORTING going to sleep
  480. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_state_machine.c:498 (sofia/external/186******73@192.168.1.101) State Change CS_REPORTING -> CS_DESTROY
  481. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_session.c:1388 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  482. 2017-04-14 14:46:06.872505 [DEBUG] switch_core_session.c:1615 Session 204 (sofia/external/186******73@192.168.1.101) Locked, Waiting on external entities
  483. 2017-04-14 14:46:06.892515 [DEBUG] switch_ivr_bridge.c:660 BRIDGE THREAD DONE [sofia/internal/8005@127.0.0.1]
  484. 2017-04-14 14:46:06.892515 [DEBUG] switch_ivr_bridge.c:690 Send signal sofia/external/186******73@192.168.1.101 [BREAK]
  485. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_session.c:908 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  486. 2017-04-14 14:46:06.892515 [NOTICE] switch_core_session.c:1633 Session 204 (sofia/external/186******73@192.168.1.101) Ended
  487. 2017-04-14 14:46:06.892515 [NOTICE] switch_core_session.c:1637 Close Channel sofia/external/186******73@192.168.1.101 [CS_DESTROY]
  488. 2017-04-14 14:46:06.892515 [NOTICE] switch_core_state_machine.c:315 sofia/internal/8005@127.0.0.1 has executed the last dialplan instruction, hanging up.
  489. 2017-04-14 14:46:06.892515 [NOTICE] switch_core_state_machine.c:317 Hangup sofia/internal/8005@127.0.0.1 [CS_EXECUTE] [NORMAL_CLEARING]
  490. 2017-04-14 14:46:06.892515 [DEBUG] switch_channel.c:3222 Send signal sofia/internal/8005@127.0.0.1 [KILL]
  491. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  492. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/8005@127.0.0.1) State EXECUTE going to sleep
  493. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/8005@127.0.0.1) Running State Change CS_HANGUP
  494. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:735 (sofia/internal/8005@127.0.0.1) Callstate Change ACTIVE -> HANGUP
  495. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:626 (sofia/external/186******73@192.168.1.101) Running State Change CS_DESTROY
  496. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/8005@127.0.0.1) State HANGUP
  497. 2017-04-14 14:46:06.892515 [DEBUG] mod_sofia.c:407 sofia/internal/8005@127.0.0.1 Overriding SIP cause 480 with 200 from the other leg
  498. 2017-04-14 14:46:06.892515 [DEBUG] mod_sofia.c:413 Channel sofia/internal/8005@127.0.0.1 hanging up, cause: NORMAL_CLEARING
  499. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:636 (sofia/external/186******73@192.168.1.101) State DESTROY
  500. 2017-04-14 14:46:06.892515 [DEBUG] mod_sofia.c:323 sofia/external/186******73@192.168.1.101 SOFIA DESTROY
  501. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:111 sofia/external/186******73@192.168.1.101 Standard DESTROY
  502. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:636 (sofia/external/186******73@192.168.1.101) State DESTROY going to sleep
  503. 2017-04-14 14:46:06.892515 [DEBUG] mod_sofia.c:465 Sending BYE to sofia/internal/8005@127.0.0.1
  504. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:60 sofia/internal/8005@127.0.0.1 Standard HANGUP, cause: NORMAL_CLEARING
  505. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/8005@127.0.0.1) State HANGUP going to sleep
  506. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/8005@127.0.0.1) State Change CS_HANGUP -> CS_REPORTING
  507. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  508. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/8005@127.0.0.1) Running State Change CS_REPORTING
  509. send 608 bytes to udp/[192.168.1.33]:5060 at 14:46:06.894485:
  510.    ------------------------------------------------------------------------
  511.    BYE sip:8005@192.168.1.33:5060 SIP/2.0
  512.    Via: SIP/2.0/UDP 117.185.49.89;rport;branch=z9hG4bKmZcQ8DSKtpD0Q
  513.    Max-Forwards: 70
  514.    From: <sip:9186******73@127.0.0.1;user=phone>;tag=28DF59255FZrQ
  515.    To: "8005" <sip:8005@127.0.0.1;user=phone>;tag=3cd16e0589b326696
  516.    Call-ID: 3cd16e0589b39777@192.168.1.33
  517.    CSeq: 105740119 BYE
  518.    User-Agent: FreeSWITCH-mod_sofia/1.5.15b+git~20141220T224621Z~0a0b9262b4~64bit
  519.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  520.    Supported: timer, path, replaces
  521.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  522.    Content-Length: 0
  523.    
  524.    ------------------------------------------------------------------------
  525. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/8005@127.0.0.1) State REPORTING
  526. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:104 sofia/internal/8005@127.0.0.1 Standard REPORTING, cause: NORMAL_CLEARING
  527. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/8005@127.0.0.1) State REPORTING going to sleep
  528. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:498 (sofia/internal/8005@127.0.0.1) State Change CS_REPORTING -> CS_DESTROY
  529. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/8005@127.0.0.1 [BREAK]
  530. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_session.c:1615 Session 203 (sofia/internal/8005@127.0.0.1) Locked, Waiting on external entities
  531. 2017-04-14 14:46:06.892515 [NOTICE] switch_core_session.c:1633 Session 203 (sofia/internal/8005@127.0.0.1) Ended
  532. 2017-04-14 14:46:06.892515 [NOTICE] switch_core_session.c:1637 Close Channel sofia/internal/8005@127.0.0.1 [CS_DESTROY]
  533. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:626 (sofia/internal/8005@127.0.0.1) Running State Change CS_DESTROY
  534. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/8005@127.0.0.1) State DESTROY
  535. 2017-04-14 14:46:06.892515 [DEBUG] mod_sofia.c:323 sofia/internal/8005@127.0.0.1 SOFIA DESTROY
  536. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:111 sofia/internal/8005@127.0.0.1 Standard DESTROY
  537. 2017-04-14 14:46:06.892515 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/8005@127.0.0.1) State DESTROY going to sleep
  538. recv 369 bytes from udp/[192.168.1.33]:5060 at 14:46:06.906296:
  539.    ------------------------------------------------------------------------
  540.    SIP/2.0 200 OK
  541.    Via: SIP/2.0/UDP 117.185.49.89;rport=5060;branch=z9hG4bKmZcQ8DSKtpD0Q;received=192.168.1.246
  542.    From: <sip:9186******73@127.0.0.1;user=phone>;tag=28DF59255FZrQ
  543.    To: "8005" <sip:8005@127.0.0.1;user=phone>;tag=3cd16e0589b326696
  544.    Call-ID: 3cd16e0589b39777@192.168.1.33
  545.    CSeq: 105740119 BYE
  546.    Contact: "8005" <sip:8005@192.168.1.33:5060>
  547.    Content-Length: 0
  548.    
  549.    ------------------------------------------------------------------------
  550.  
  551.  

Reply to "Re: Re: 通过网关外拨, FS没有发送RTP给外线网关(trace日志)"