From nevian, 4 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. recv 1527 bytes from tcp/[172.17.2.3]:16112 at 12:27:37.672765:
  2.    ------------------------------------------------------------------------
  3.    INVITE sip:12550@83.y.y.y SIP/2.0
  4.    From: "Unk" <sip:anonymous@anonymous.invalid>;tag=80ac2f3d9c9ee61a3645724fb1600
  5.    To: <sip:12550@83.y.y.y>
  6.    Call-ID: 80ac2f3d9c9ee61a4645724fb1600
  7.    CSeq: 1 INVITE
  8.    Max-Forwards: 71
  9.    Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600
  10.    Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600
  11.    Supported: 100rel,histinfo,join,replaces,sdp-anat,timer
  12.   Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,INFO,PRACK,PUBLISH,UPDATE
  13.    User-Agent: Avaya CM/R016x.03.0.124.0
  14.    Contact: "Unk" <sip:172.17.2.3:6060;transport=tcp>
  15.    Route: <sip:83.y.y.y:6060;transport=tcp;lr;phase=terminating>
  16.    Accept-Language: uk
  17.    Alert-Info: <cid:internal@invalid.unknown.domain>;avaya-cm-alert-type=internal
  18.    History-Info: <sip:12550@83.y.y.y>;index=1
  19.    History-Info: "12550" <sip:12550@83.y.y.y>;index=1.1
  20.    Min-SE: 1200
  21.    P-Asserted-Identity: "Unk" <sip:invalid.unknown.domain>
  22.    Record-Route: <sip:172.17.2.3:6060;transport=tcp;lr>
  23.    Session-Expires: 1200;refresher=uac
  24.    Privacy: id
  25.    Av-Global-Session-ID: 80ac2f3d-9c9e-4601-a264-5724fb160000
  26.    Content-Type: application/sdp
  27.    Content-Length:   262
  28.    
  29.    v=0
  30.    o=- 1475832457 1 IN IP4 172.17.2.3
  31.    s=-
  32.    c=IN IP4 172.17.2.4
  33.    b=AS:64
  34.    t=0 0
  35.    a=avf:avc=n prio=n
  36.    a=csup:avf-v0
  37.    m=audio 3196 RTP/AVP 8 18 101
  38.    a=rtpmap:8 PCMA/8000
  39.    a=rtpmap:18 G729/8000
  40.    a=fmtp:18 annexb=no
  41.    a=rtpmap:101 telephone-event/8000
  42.    a=ptime:20
  43.    ------------------------------------------------------------------------
  44. send 478 bytes to tcp/[172.17.2.3]:16112 at 12:27:37.678935:
  45.    ------------------------------------------------------------------------
  46.    SIP/2.0 100 Trying
  47.    Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600;rport=16112
  48.    Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600
  49.    Record-Route: <sip:172.17.2.3:6060;transport=tcp;lr>
  50.    From: "Unk" <sip:anonymous@anonymous.invalid>;tag=80ac2f3d9c9ee61a3645724fb1600
  51.    To: <sip:12550@83.y.y.y>
  52.    Call-ID: 80ac2f3d9c9ee61a4645724fb1600
  53.    CSeq: 1 INVITE
  54.    User-Agent: ES FS SBC
  55.    Content-Length: 0
  56.    
  57.    ------------------------------------------------------------------------
  58. 2016-10-07 12:27:37.667575 [NOTICE] switch_channel.c:1104 New Channel sofia/external/anonymous@anonymous.invalid [6f9d5c3b-b5a6-4937-9770-e335fe97732a]
  59. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_NEW
  60. 2016-10-07 12:27:37.667575 [DEBUG] sofia.c:9519 sofia/external/anonymous@anonymous.invalid receiving invite from 172.17.2.3:16112 version: 1.7.0 git be13536 2016-07-07 16:55:35Z 64bit
  61. 2016-10-07 12:27:37.667575 [DEBUG] sofia.c:6937 Channel sofia/external/anonymous@anonymous.invalid entering state [received][100]
  62. 2016-10-07 12:27:37.667575 [DEBUG] sofia.c:6947 Remote SDP:
  63. v=0
  64. o=- 1475832457 1 IN IP4 172.17.2.3
  65. s=-
  66. c=IN IP4 172.17.2.4
  67. b=AS:64
  68. t=0 0
  69. a=avf:avc=n prio=n
  70. a=csup:avf-v0
  71. m=audio 3196 RTP/AVP 8 18 101
  72. a=rtpmap:8 PCMA/8000
  73. a=rtpmap:18 G729/8000
  74. a=fmtp:18 annexb=no
  75. a=rtpmap:101 telephone-event/8000
  76. a=ptime:20
  77.  
  78. 2016-10-07 12:27:37.667575 [DEBUG] sofia.c:7315 (sofia/external/anonymous@anonymous.invalid) State Change CS_NEW -> CS_INIT
  79. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:562 (sofia/external/anonymous@anonymous.invalid) State NEW
  80. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_INIT
  81. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:586 (sofia/external/anonymous@anonymous.invalid) State INIT
  82. 2016-10-07 12:27:37.667575 [DEBUG] mod_sofia.c:90 sofia/external/anonymous@anonymous.invalid SOFIA INIT
  83. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:40 sofia/external/anonymous@anonymous.invalid Standard INIT
  84. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:48 (sofia/external/anonymous@anonymous.invalid) State Change CS_INIT -> CS_ROUTING
  85. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:586 (sofia/external/anonymous@anonymous.invalid) State INIT going to sleep
  86. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_ROUTING
  87. 2016-10-07 12:27:37.667575 [DEBUG] switch_channel.c:2249 (sofia/external/anonymous@anonymous.invalid) Callstate Change DOWN -> RINGING
  88. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:602 (sofia/external/anonymous@anonymous.invalid) State ROUTING
  89. 2016-10-07 12:27:37.667575 [DEBUG] mod_sofia.c:143 sofia/external/anonymous@anonymous.invalid SOFIA ROUTING
  90. 2016-10-07 12:27:37.667575 [ERR] mod_xml_radius.c:933 Result of true match: 83.y.y.y == ^8\.8\.8\.8
  91. 2016-10-07 12:27:37.667575 [INFO] mod_xml_radius.c:986 mod_xml_radius:  Accounting Start success
  92. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:236 sofia/external/anonymous@anonymous.invalid Standard ROUTING
  93. 2016-10-07 12:27:37.667575 [INFO] mod_dialplan_xml.c:637 Processing Unk <anonymous>->12550 in context public
  94. Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->common_variables] continue=true
  95. Dialplan: sofia/external/anonymous@anonymous.invalid Absolute Condition [common_variables]
  96. Dialplan: sofia/external/anonymous@anonymous.invalid Action set(default_language=en)
  97. Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->call_debug] continue=true
  98. Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
  99. Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->gateway_inbound] continue=false
  100. Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [gateway_inbound] ${target_context}() =~ /^[a-z]/ break=on-false
  101. Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->from_mts-tascom] continue=false
  102. Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [from_mts-tascom] network_addr(172.17.2.3) =~ /^82\.204\.25[45]/ break=on-false
  103. Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->from_partner] continue=false
  104. Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [from_partner] network_addr(172.17.2.3) =~ /^172\.17\.2\.3$/ break=on-false
  105. Dialplan: sofia/external/anonymous@anonymous.invalid Action set(effective_caller_id_number=$(caller_id_number))
  106. Dialplan: sofia/external/anonymous@anonymous.invalid Action transfer(${destination_number} XML internal)
  107. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:286 (sofia/external/anonymous@anonymous.invalid) State Change CS_ROUTING -> CS_EXECUTE
  108. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:602 (sofia/external/anonymous@anonymous.invalid) State ROUTING going to sleep
  109. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_EXECUTE
  110. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:609 (sofia/external/anonymous@anonymous.invalid) State EXECUTE
  111. 2016-10-07 12:27:37.667575 [DEBUG] mod_sofia.c:198 sofia/external/anonymous@anonymous.invalid SOFIA EXECUTE
  112. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:328 sofia/external/anonymous@anonymous.invalid Standard EXECUTE
  113. EXECUTE sofia/external/anonymous@anonymous.invalid set(default_language=en)
  114. 2016-10-07 12:27:37.667575 [DEBUG] mod_dptools.c:1519 SET sofia/external/anonymous@anonymous.invalid [default_language]=[en]
  115. EXECUTE sofia/external/anonymous@anonymous.invalid set(effective_caller_id_number=$(caller_id_number))
  116. 2016-10-07 12:27:37.667575 [DEBUG] mod_dptools.c:1519 SET sofia/external/anonymous@anonymous.invalid [effective_caller_id_number]=[$(caller_id_number)]
  117. EXECUTE sofia/external/anonymous@anonymous.invalid transfer(12550 XML internal)
  118. 2016-10-07 12:27:37.667575 [DEBUG] switch_ivr.c:2160 (sofia/external/anonymous@anonymous.invalid) State Change CS_EXECUTE -> CS_ROUTING
  119. 2016-10-07 12:27:37.667575 [NOTICE] switch_ivr.c:2167 Transfer sofia/external/anonymous@anonymous.invalid to XML[12550@internal]
  120. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:609 (sofia/external/anonymous@anonymous.invalid) State EXECUTE going to sleep
  121. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_ROUTING
  122. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:602 (sofia/external/anonymous@anonymous.invalid) State ROUTING
  123. 2016-10-07 12:27:37.667575 [DEBUG] mod_sofia.c:143 sofia/external/anonymous@anonymous.invalid SOFIA ROUTING
  124. 2016-10-07 12:27:37.667575 [ERR] mod_xml_radius.c:933 Result of true match: 83.y.y.y == ^8\.8\.8\.8
  125. 2016-10-07 12:27:37.667575 [INFO] mod_xml_radius.c:986 mod_xml_radius:  Accounting Start success
  126. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:236 sofia/external/anonymous@anonymous.invalid Standard ROUTING
  127. 2016-10-07 12:27:37.667575 [INFO] mod_dialplan_xml.c:637 Processing Unk <anonymous>->12550 in context internal
  128. Dialplan: sofia/external/anonymous@anonymous.invalid parsing [internal->common_variables] continue=true
  129. Dialplan: sofia/external/anonymous@anonymous.invalid Absolute Condition [common_variables]
  130. Dialplan: sofia/external/anonymous@anonymous.invalid Action set(default_language=en)
  131. Dialplan: sofia/external/anonymous@anonymous.invalid parsing [internal->call_debug] continue=true
  132. Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
  133. Dialplan: sofia/external/anonymous@anonymous.invalid parsing [internal->mts_tascom] continue=false
  134. Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [mts_tascom] destination_number(12550) =~ /^(17[0-5]\d\d)$/ break=on-false
  135. Dialplan: sofia/external/anonymous@anonymous.invalid parsing [internal->cucm] continue=false
  136. Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [cucm] destination_number(12550) =~ /^17[0-5]\d\d$/ break=on-true
  137. Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [cucm] destination_number(12550) =~ /^(?:1700#)?([1245678]\d{4}$|04\d{2,3}|555)$/ break=on-false
  138. Dialplan: sofia/external/anonymous@anonymous.invalid Action set(continue_on_fail=true)
  139. Dialplan: sofia/external/anonymous@anonymous.invalid Action set(hangup_after_bridge=true)
  140. Dialplan: sofia/external/anonymous@anonymous.invalid Action set(inherit_codec=true)
  141. Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/${distributor(cucm)}/12550)
  142. Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/${distributor(cucm)}/12550)
  143. Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/${distributor(cucm)}/12550)
  144. Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/${distributor(cucm)}/12550)
  145. Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/${distributor(cucm)}/12550)
  146. Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/${distributor(cucm)}/12550)
  147. Dialplan: sofia/external/anonymous@anonymous.invalid Action hangup(${originate_disposition})
  148. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:286 (sofia/external/anonymous@anonymous.invalid) State Change CS_ROUTING -> CS_EXECUTE
  149. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:602 (sofia/external/anonymous@anonymous.invalid) State ROUTING going to sleep
  150. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_EXECUTE
  151. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:609 (sofia/external/anonymous@anonymous.invalid) State EXECUTE
  152. 2016-10-07 12:27:37.667575 [DEBUG] mod_sofia.c:198 sofia/external/anonymous@anonymous.invalid SOFIA EXECUTE
  153. 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:328 sofia/external/anonymous@anonymous.invalid Standard EXECUTE
  154. EXECUTE sofia/external/anonymous@anonymous.invalid set(default_language=en)
  155. 2016-10-07 12:27:37.687565 [DEBUG] mod_dptools.c:1519 SET sofia/external/anonymous@anonymous.invalid [default_language]=[en]
  156. EXECUTE sofia/external/anonymous@anonymous.invalid set(continue_on_fail=true)
  157. 2016-10-07 12:27:37.687565 [DEBUG] mod_dptools.c:1519 SET sofia/external/anonymous@anonymous.invalid [continue_on_fail]=[true]
  158. EXECUTE sofia/external/anonymous@anonymous.invalid set(hangup_after_bridge=true)
  159. 2016-10-07 12:27:37.687565 [DEBUG] mod_dptools.c:1519 SET sofia/external/anonymous@anonymous.invalid [hangup_after_bridge]=[true]
  160. EXECUTE sofia/external/anonymous@anonymous.invalid set(inherit_codec=true)
  161. 2016-10-07 12:27:37.687565 [DEBUG] mod_dptools.c:1519 SET sofia/external/anonymous@anonymous.invalid [inherit_codec]=[true]
  162. EXECUTE sofia/external/anonymous@anonymous.invalid bridge(sofia/gateway/cucm-64/12550)
  163. 2016-10-07 12:27:37.687565 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
  164. 2016-10-07 12:27:37.687565 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/12550 [5e5ddb8b-68c0-4979-a645-1647e79fe948]
  165. 2016-10-07 12:27:37.687565 [DEBUG] mod_sofia.c:4758 (sofia/internal/12550) State Change CS_NEW -> CS_INIT
  166. 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/12550) Running State Change CS_INIT
  167. 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/12550) State INIT
  168. 2016-10-07 12:27:37.687565 [DEBUG] mod_sofia.c:90 sofia/internal/12550 SOFIA INIT
  169. 2016-10-07 12:27:37.687565 [DEBUG] sofia_glue.c:1249 sofia/internal/12550 sending invite version: 1.7.0 git be13536 2016-07-07 16:55:35Z 64bit
  170. Local SDP:
  171. v=0
  172. o=FreeSWITCH 1475814555 1475814556 IN IP4 10.23.154.100
  173. s=FreeSWITCH
  174. c=IN IP4 10.23.154.100
  175. t=0 0
  176. m=audio 17902 RTP/AVP 8 18 101 13
  177. a=rtpmap:8 PCMA/8000
  178. a=rtpmap:18 G729/8000
  179. a=rtpmap:101 telephone-event/8000
  180. a=fmtp:101 0-16
  181. a=rtpmap:13 CN/8000
  182. a=ptime:20
  183. a=sendrecv
  184.  
  185. send 1163 bytes to udp/[10.23.154.64]:5060 at 12:27:37.696131:
  186.    ------------------------------------------------------------------------
  187.    INVITE sip:12550@10.23.154.64 SIP/2.0
  188.    Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bK8HyrgBmDZFKHF
  189.    Max-Forwards: 69
  190.    From: "Unk" <sip:$(caller_id_number)@10.23.154.100>;tag=QgKrpvp4c2vgF
  191.    To: <sip:12550@10.23.154.64>
  192.    Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948
  193.    CSeq: 97580164 INVITE
  194.    Contact: <sip:gw+cucm-64@10.23.154.100:6060;transport=udp;gw=cucm-64>
  195.    User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20160707T165535Z~be13536ac9~64bit
  196.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, PRACK, NOTIFY
  197.    Supported: precondition, 100rel, timer, path, replaces
  198.    Allow-Events: talk, hold, conference, refer
  199.    Content-Type: application/sdp
  200.    Content-Disposition: session
  201.    Content-Length: 268
  202.    X-FS-Support: update_display,send_info
  203.    Remote-Party-ID: "Unk" <sip:$(caller_id_number)@10.23.154.100>;party=calling;screen=yes;privacy=full
  204.    
  205.    v=0
  206.    o=FreeSWITCH 1475814555 1475814556 IN IP4 10.23.154.100
  207.    s=FreeSWITCH
  208.    c=IN IP4 10.23.154.100
  209.    t=0 0
  210.    m=audio 17902 RTP/AVP 8 18 101 13
  211.    a=rtpmap:8 PCMA/8000
  212.    a=rtpmap:18 G729/8000
  213.    a=rtpmap:101 telephone-event/8000
  214.    a=fmtp:101 0-16
  215.    a=rtpmap:13 CN/8000
  216.    a=ptime:20
  217.    ------------------------------------------------------------------------
  218. 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:40 sofia/internal/12550 Standard INIT
  219. 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/12550) State Change CS_INIT -> CS_ROUTING
  220. 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/12550) State INIT going to sleep
  221. 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/12550) Running State Change CS_ROUTING
  222. 2016-10-07 12:27:37.687565 [DEBUG] sofia.c:6937 Channel sofia/internal/12550 entering state [calling][0]
  223. 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/12550) State ROUTING
  224. 2016-10-07 12:27:37.687565 [DEBUG] mod_sofia.c:143 sofia/internal/12550 SOFIA ROUTING
  225. 2016-10-07 12:27:37.687565 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/12550) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  226. 2016-10-07 12:27:37.687565 [ERR] mod_xml_radius.c:933 Result of true match: 10.23.154.64 == ^8\.8\.8\.8
  227. 2016-10-07 12:27:37.687565 [INFO] mod_xml_radius.c:986 mod_xml_radius:  Accounting Start success
  228. 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/12550) State ROUTING going to sleep
  229. 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/12550) Running State Change CS_CONSUME_MEDIA
  230. 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/12550) State CONSUME_MEDIA
  231. 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/12550) State CONSUME_MEDIA going to sleep
  232. recv 364 bytes from udp/[10.23.154.64]:5060 at 12:27:37.702123:
  233.    ------------------------------------------------------------------------
  234.    SIP/2.0 100 Trying
  235.    Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bK8HyrgBmDZFKHF
  236.    From: "Unk" <sip:$(caller_id_number)@10.23.154.100>;tag=QgKrpvp4c2vgF
  237.    To: <sip:12550@10.23.154.64>
  238.    Date: Fri, 07 Oct 2016 09:27:37 GMT
  239.    Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948
  240.    CSeq: 97580164 INVITE
  241.    Allow-Events: presence
  242.    Content-Length: 0
  243.    
  244.    ------------------------------------------------------------------------
  245. recv 765 bytes from udp/[10.23.154.64]:5060 at 12:27:37.707721:
  246.    ------------------------------------------------------------------------
  247.    SIP/2.0 180 Ringing
  248.    Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bK8HyrgBmDZFKHF
  249.    From: "Unk" <sip:$(caller_id_number)@10.23.154.100>;tag=QgKrpvp4c2vgF
  250.    To: <sip:12550@10.23.154.64>;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289
  251.    Date: Fri, 07 Oct 2016 09:27:37 GMT
  252.    Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948
  253.    CSeq: 97580164 INVITE
  254.    Require: 100rel
  255.    RSeq: 2060524221
  256.   Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
  257.    Allow-Events: presence
  258.    Supported: X-cisco-srtp-fallback
  259.    Supported: Geolocation
  260.    P-Asserted-Identity: <sip:12550@10.23.154.64>
  261.    Remote-Party-ID: <sip:12550@10.23.154.64>;party=called;screen=yes;privacy=off
  262.    Contact: <sip:12550@10.23.154.64:5060>
  263.    Content-Length: 0
  264.    
  265.    ------------------------------------------------------------------------
  266. send 716 bytes to udp/[10.23.154.64]:5060 at 12:27:37.707850:
  267.    ------------------------------------------------------------------------
  268.    PRACK sip:12550@10.23.154.64:5060 SIP/2.0
  269.    Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bK9tQHj64gvr93a
  270.    Max-Forwards: 70
  271.    From: "Unk" <sip:$(caller_id_number)@10.23.154.100>;tag=QgKrpvp4c2vgF
  272.    To: <sip:12550@10.23.154.64>;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289
  273.    Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948
  274.    CSeq: 97580165 PRACK
  275.    Contact: <sip:gw+cucm-64@10.23.154.100:6060;transport=udp;gw=cucm-64>
  276.    RAck: 2060524221 97580164 INVITE
  277.    User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20160707T165535Z~be13536ac9~64bit
  278.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, PRACK, NOTIFY
  279.    Supported: precondition, 100rel, timer, path, replaces
  280.    Content-Length: 0
  281.    
  282.    ------------------------------------------------------------------------
  283. 2016-10-07 12:27:37.707566 [DEBUG] sofia.c:6937 Channel sofia/internal/12550 entering state [proceeding][180]
  284. 2016-10-07 12:27:37.707566 [NOTICE] sofia.c:7045 Ring-Ready sofia/internal/12550!
  285. recv 393 bytes from udp/[10.23.154.64]:5060 at 12:27:37.708452:
  286.    ------------------------------------------------------------------------
  287.    SIP/2.0 200 OK
  288.    Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bK9tQHj64gvr93a
  289.    From: "Unk" <sip:$(caller_id_number)@10.23.154.100>;tag=QgKrpvp4c2vgF
  290.    To: <sip:12550@10.23.154.64>;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289
  291.    Date: Fri, 07 Oct 2016 09:27:37 GMT
  292.    Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948
  293.    CSeq: 97580165 PRACK
  294.    Content-Length: 0
  295.    
  296.    ------------------------------------------------------------------------
  297. 2016-10-07 12:27:37.707566 [DEBUG] switch_channel.c:3343 (sofia/internal/12550) Callstate Change DOWN -> RINGING
  298. send 795 bytes to tcp/[172.17.2.3]:16112 at 12:27:37.717309:
  299.    ------------------------------------------------------------------------
  300.    SIP/2.0 180 Ringing
  301.    Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600;rport=16112
  302.    Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600
  303.    Record-Route: <sip:172.17.2.3:6060;transport=tcp;lr>
  304.    From: "Unk" <sip:anonymous@anonymous.invalid>;tag=80ac2f3d9c9ee61a3645724fb1600
  305.    To: <sip:12550@83.y.y.y>;tag=18QFj7m70ae5D
  306.    Call-ID: 80ac2f3d9c9ee61a4645724fb1600
  307.    CSeq: 1 INVITE
  308.    Contact: <sip:12550@83.y.y.y:6060;transport=tcp>
  309.    User-Agent: ES FS SBC
  310.    Accept: application/sdp
  311.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, NOTIFY
  312.    Supported: timer, path, replaces
  313.    Allow-Events: talk, hold, conference, refer
  314.    Content-Length: 0
  315.    P-Asserted-Identity: "Outbound Call" <sip:12550@83.y.y.y>
  316.    
  317.    ------------------------------------------------------------------------
  318. 2016-10-07 12:27:37.707566 [NOTICE] mod_sofia.c:2221 Ring-Ready sofia/external/anonymous@anonymous.invalid!
  319. 2016-10-07 12:27:37.707566 [DEBUG] sofia.c:6937 Channel sofia/external/anonymous@anonymous.invalid entering state [early][180]
  320. 2016-10-07 12:27:37.707566 [NOTICE] switch_ivr_originate.c:525 Ring Ready sofia/external/anonymous@anonymous.invalid!
  321. recv 1104 bytes from udp/[10.23.154.64]:5060 at 12:27:44.650493:
  322.    ------------------------------------------------------------------------
  323.    SIP/2.0 200 OK
  324.    Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bK8HyrgBmDZFKHF
  325.    From: "Unk" <sip:$(caller_id_number)@10.23.154.100>;tag=QgKrpvp4c2vgF
  326.    To: <sip:12550@10.23.154.64>;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289
  327.    Date: Fri, 07 Oct 2016 09:27:37 GMT
  328.    Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948
  329.    CSeq: 97580164 INVITE
  330.   Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
  331.    Allow-Events: presence, kpml
  332.    Supported: replaces
  333.    Server: Cisco-CP7942G/9.3.1
  334.    Supported: X-cisco-srtp-fallback
  335.    Supported: Geolocation
  336.    Session-Expires:  1800;refresher=uas
  337.    Require:  timer
  338.    P-Asserted-Identity: <sip:12550@10.23.154.64>
  339.    Remote-Party-ID: <sip:12550@10.23.154.64>;party=called;screen=yes;privacy=off
  340.    Contact: <sip:12550@10.23.154.64:5060>
  341.    Content-Type: application/sdp
  342.    Content-Length: 235
  343.    
  344.    v=0
  345.    o=CiscoSystemsCCM-SIP 741728 1 IN IP4 10.23.154.64
  346.    s=SIP Call
  347.    c=IN IP4 10.228.8.167
  348.    b=TIAS:64000
  349.    b=AS:64
  350.    t=0 0
  351.    m=audio 21250 RTP/AVP 8 101
  352.    a=rtpmap:8 PCMA/8000
  353.    a=ptime:20
  354.    a=rtpmap:101 telephone-event/8000
  355.    a=fmtp:101 0-15
  356.    ------------------------------------------------------------------------
  357. 2016-10-07 12:27:44.647568 [DEBUG] sofia.c:6937 Channel sofia/internal/12550 entering state [completing][200]
  358. 2016-10-07 12:27:44.647568 [DEBUG] sofia.c:6947 Remote SDP:
  359. v=0
  360. o=CiscoSystemsCCM-SIP 741728 1 IN IP4 10.23.154.64
  361. s=SIP Call
  362. c=IN IP4 10.228.8.167
  363. b=TIAS:64000
  364. b=AS:64
  365. t=0 0
  366. m=audio 21250 RTP/AVP 8 101
  367. a=rtpmap:8 PCMA/8000
  368. a=rtpmap:101 telephone-event/8000
  369. a=fmtp:101 0-15
  370. a=ptime:20
  371.  
  372. send 464 bytes to udp/[10.23.154.64]:5060 at 12:27:44.651358:
  373.    ------------------------------------------------------------------------
  374.    ACK sip:12550@10.23.154.64:5060 SIP/2.0
  375.    Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bKa4gam1NmS1Zpp
  376.    Max-Forwards: 70
  377.    From: "Unk" <sip:$(caller_id_number)@10.23.154.100>;tag=QgKrpvp4c2vgF
  378.    To: <sip:12550@10.23.154.64>;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289
  379.    Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948
  380.    CSeq: 97580164 ACK
  381.    Contact: <sip:gw+cucm-64@10.23.154.100:6060;transport=udp;gw=cucm-64>
  382.    Content-Length: 0
  383.    
  384.    ------------------------------------------------------------------------
  385. 2016-10-07 12:27:44.647568 [DEBUG] sofia.c:6937 Channel sofia/internal/12550 entering state [ready][200]
  386. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4373 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  387. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4428 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  388. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4373 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  389. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4289 Set telephone-event payload to 101@8000
  390. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:3086 Set Codec sofia/internal/12550 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  391. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_codec.c:111 sofia/internal/12550 Original read codec set to PCMA:8
  392. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4642 Set telephone-event payload to 101@8000
  393. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4701 sofia/internal/12550 Set 2833 dtmf send payload to 101 recv payload to 101
  394. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:6508 AUDIO RTP [sofia/internal/12550] 10.23.154.100 port 17902 -> 10.228.8.167 port 21250 codec: 8 ms: 20
  395. 2016-10-07 12:27:44.647568 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 160 bytes per 20ms
  396. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:6807 sofia/internal/12550 Set 2833 dtmf send payload to 101
  397. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:6814 sofia/internal/12550 Set 2833 dtmf receive payload to 101
  398. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:6837 sofia/internal/12550 Set rtp dtmf delay to 40
  399. 2016-10-07 12:27:44.647568 [NOTICE] sofia.c:7969 Channel [sofia/internal/12550] has been answered
  400. 2016-10-07 12:27:44.647568 [DEBUG] switch_channel.c:3770 (sofia/internal/12550) Callstate Change RINGING -> ACTIVE
  401. 2016-10-07 12:27:44.647568 [DEBUG] switch_ivr_originate.c:410 Setting codec string on sofia/external/anonymous@anonymous.invalid to PCMA@8000h@20i
  402. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4373 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  403. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4428 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  404. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4373 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  405. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4289 Set telephone-event payload to 101@8000
  406. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:3086 Set Codec sofia/external/anonymous@anonymous.invalid PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  407. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_codec.c:111 sofia/external/anonymous@anonymous.invalid Original read codec set to PCMA:8
  408. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4642 Set telephone-event payload to 101@8000
  409. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4701 sofia/external/anonymous@anonymous.invalid Set 2833 dtmf send payload to 101 recv payload to 101
  410. 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:6508 AUDIO RTP [sofia/external/anonymous@anonymous.invalid] 83.y.y.y port 30404 -> 172.17.2.4 port 3196 codec: 8 ms: 20
  411. 2016-10-07 12:27:44.647568 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 160 bytes per 20ms
  412. 2016-10-07 12:27:44.667571 [DEBUG] switch_core_media.c:6807 sofia/external/anonymous@anonymous.invalid Set 2833 dtmf send payload to 101
  413. 2016-10-07 12:27:44.667571 [DEBUG] switch_core_media.c:6814 sofia/external/anonymous@anonymous.invalid Set 2833 dtmf receive payload to 101
  414. 2016-10-07 12:27:44.667571 [DEBUG] switch_core_media.c:6837 sofia/external/anonymous@anonymous.invalid Set rtp dtmf delay to 40
  415. 2016-10-07 12:27:44.667571 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/anonymous@anonymous.invalid!
  416. 2016-10-07 12:27:44.667571 [DEBUG] switch_channel.c:3471 (sofia/external/anonymous@anonymous.invalid) Callstate Change RINGING -> EARLY
  417. 2016-10-07 12:27:44.667571 [DEBUG] mod_sofia.c:822 Local SDP sofia/external/anonymous@anonymous.invalid:
  418. v=0
  419. o=FreeSWITCH 1475802060 1475802061 IN IP4 83.y.y.y
  420. s=FreeSWITCH
  421. c=IN IP4 83.y.y.y
  422. t=0 0
  423. m=audio 30404 RTP/AVP 8 101
  424. a=rtpmap:8 PCMA/8000
  425. a=rtpmap:101 telephone-event/8000
  426. a=fmtp:101 0-16
  427. a=ptime:20
  428. a=sendrecv
  429.  
  430. send 1105 bytes to tcp/[172.17.2.3]:16112 at 12:27:44.669363:
  431.    ------------------------------------------------------------------------
  432.    SIP/2.0 200 OK
  433.    Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600;rport=16112
  434.    Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600
  435.    Record-Route: <sip:172.17.2.3:6060;transport=tcp;lr>
  436.    From: "Unk" <sip:anonymous@anonymous.invalid>;tag=80ac2f3d9c9ee61a3645724fb1600
  437.    To: <sip:12550@83.y.y.y>;tag=18QFj7m70ae5D
  438.    Call-ID: 80ac2f3d9c9ee61a4645724fb1600
  439.    CSeq: 1 INVITE
  440.    Contact: <sip:12550@83.y.y.y:6060;transport=tcp>
  441.    User-Agent: ES FS SBC
  442.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, NOTIFY
  443.    Require: timer
  444.    Supported: timer, path, replaces
  445.    Allow-Events: talk, hold, conference, refer
  446.    Session-Expires: 1200;refresher=uac
  447.    Content-Type: application/sdp
  448.    Content-Disposition: session
  449.    Content-Length: 224
  450.    P-Asserted-Identity: "Outbound Call" <sip:12550@83.y.y.y>
  451.    
  452.    v=0
  453.    o=FreeSWITCH 1475802060 1475802061 IN IP4 83.y.y.y
  454.    s=FreeSWITCH
  455.    c=IN IP4 83.y.y.y
  456.    t=0 0
  457.    m=audio 30404 RTP/AVP 8 101
  458.    a=rtpmap:8 PCMA/8000
  459.    a=rtpmap:101 telephone-event/8000
  460.    a=fmtp:101 0-16
  461.    a=ptime:20
  462.    ------------------------------------------------------------------------
  463. 2016-10-07 12:27:44.667571 [DEBUG] sofia.c:6937 Channel sofia/external/anonymous@anonymous.invalid entering state [completed][200]
  464. 2016-10-07 12:27:44.667571 [NOTICE] switch_ivr_originate.c:3549 Channel [sofia/external/anonymous@anonymous.invalid] has been answered
  465. 2016-10-07 12:27:44.667571 [DEBUG] switch_channel.c:3770 (sofia/external/anonymous@anonymous.invalid) Callstate Change EARLY -> ACTIVE
  466. 2016-10-07 12:27:44.667571 [DEBUG] switch_ivr_originate.c:3607 Originate Resulted in Success: [sofia/internal/12550]
  467. 2016-10-07 12:27:44.667571 [DEBUG] switch_ivr_bridge.c:1599 (sofia/internal/12550) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  468. 2016-10-07 12:27:44.667571 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/12550) Running State Change CS_EXCHANGE_MEDIA
  469. 2016-10-07 12:27:44.667571 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/12550) State EXCHANGE_MEDIA
  470. 2016-10-07 12:27:44.667571 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  471. recv 560 bytes from tcp/[172.17.2.3]:16112 at 12:27:44.698085:
  472.    ------------------------------------------------------------------------
  473.    ACK sip:12550@83.y.y.y:6060;transport=tcp SIP/2.0
  474.    From: "Unk" <sip:anonymous@anonymous.invalid>;tag=80ac2f3d9c9ee61a3645724fb1600
  475.    To: <sip:12550@83.y.y.y>;tag=18QFj7m70ae5D
  476.    Call-ID: 80ac2f3d9c9ee61a4645724fb1600
  477.    CSeq: 1 ACK
  478.    Max-Forwards: 70
  479.    Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK060f4419c9ee61b0645724fb1600
  480.    Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK060f4419c9ee61b0645724fb1600
  481.    User-Agent: Avaya CM/R016x.03.0.124.0
  482.    Av-Global-Session-ID: 80ac2f3d-9c9e-4601-a264-5724fb160000
  483.    Content-Length:     0
  484.    
  485.    ------------------------------------------------------------------------
  486. 2016-10-07 12:27:44.687566 [DEBUG] sofia.c:6937 Channel sofia/external/anonymous@anonymous.invalid entering state [ready][200]
  487. 2016-10-07 12:27:44.747567 [DEBUG] switch_rtp.c:6711 Correct audio ip/port confirmed.
  488. 2016-10-07 12:27:44.787573 [DEBUG] switch_rtp.c:6711 Correct audio ip/port confirmed.
  489. recv 558 bytes from tcp/[172.17.2.3]:16112 at 12:28:49.504634:
  490.    ------------------------------------------------------------------------
  491.    BYE sip:12550@83.y.y.y:6060;transport=tcp SIP/2.0
  492.    From: "Unk" <sip:anonymous@anonymous.invalid>;tag=80ac2f3d9c9ee61a3645724fb1600
  493.    To: <sip:12550@83.y.y.y>;tag=18QFj7m70ae5D
  494.    Call-ID: 80ac2f3d9c9ee61a4645724fb1600
  495.    CSeq: 2 BYE
  496.    Max-Forwards: 70
  497.    Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK8001a689c9ee610655724fb1600
  498.    Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK8001a689c9ee610655724fb1600
  499.    User-Agent: Avaya CM/R016x.03.0.124.0
  500.    Av-Global-Session-ID: 80ac2f3d-9c9e-4601-a264-5724fb160000
  501.    Content-Length:     0
  502.    
  503.    ------------------------------------------------------------------------
  504. 2016-10-07 12:28:49.507567 [NOTICE] sofia.c:988 Hangup sofia/external/anonymous@anonymous.invalid [CS_EXECUTE] [NORMAL_CLEARING]
  505. send 538 bytes to tcp/[172.17.2.3]:16112 at 12:28:49.508267:
  506.    ------------------------------------------------------------------------
  507.    SIP/2.0 200 OK
  508.    Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK8001a689c9ee610655724fb1600;rport=16112
  509.    Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK8001a689c9ee610655724fb1600
  510.    From: "Unk" <sip:anonymous@anonymous.invalid>;tag=80ac2f3d9c9ee61a3645724fb1600
  511.    To: <sip:12550@83.y.y.y>;tag=18QFj7m70ae5D
  512.    Call-ID: 80ac2f3d9c9ee61a4645724fb1600
  513.    CSeq: 2 BYE
  514.    User-Agent: ES FS SBC
  515.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, NOTIFY
  516.    Supported: timer, path, replaces
  517.    Content-Length: 0
  518.    
  519.    ------------------------------------------------------------------------
  520. 2016-10-07 12:28:49.507567 [DEBUG] switch_ivr_bridge.c:785 BRIDGE THREAD DONE [sofia/external/anonymous@anonymous.invalid]
  521. 2016-10-07 12:28:49.507567 [DEBUG] switch_ivr_bridge.c:706 sofia/external/anonymous@anonymous.invalid ending bridge by request from write function
  522. 2016-10-07 12:28:49.507567 [DEBUG] switch_ivr_bridge.c:785 BRIDGE THREAD DONE [sofia/internal/12550]
  523. 2016-10-07 12:28:49.507567 [NOTICE] switch_ivr_bridge.c:889 Hangup sofia/internal/12550 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  524. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/12550) State EXCHANGE_MEDIA going to sleep
  525. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/12550) Running State Change CS_HANGUP
  526. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/12550) Callstate Change ACTIVE -> HANGUP
  527. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/12550) State HANGUP
  528. 2016-10-07 12:28:49.507567 [DEBUG] mod_sofia.c:432 sofia/internal/12550 Overriding SIP cause 480 with 200 from the other leg
  529. 2016-10-07 12:28:49.507567 [DEBUG] mod_sofia.c:438 Channel sofia/internal/12550 hanging up, cause: NORMAL_CLEARING
  530. 2016-10-07 12:28:49.507567 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/12550
  531. send 656 bytes to udp/[10.23.154.64]:5060 at 12:28:49.512669:
  532.    ------------------------------------------------------------------------
  533.    BYE sip:12550@10.23.154.64:5060 SIP/2.0
  534.    Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bKBDa3Nv6Qpap9H
  535.    Max-Forwards: 70
  536.    From: "Unk" <sip:$(caller_id_number)@10.23.154.100>;tag=QgKrpvp4c2vgF
  537.    To: <sip:12550@10.23.154.64>;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289
  538.    Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948
  539.    CSeq: 97580166 BYE
  540.    User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20160707T165535Z~be13536ac9~64bit
  541.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, PRACK, NOTIFY
  542.    Supported: precondition, 100rel, timer, path, replaces
  543.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  544.    Content-Length: 0
  545.    
  546.    ------------------------------------------------------------------------
  547. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:60 sofia/internal/12550 Standard HANGUP, cause: NORMAL_CLEARING
  548. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/12550) State HANGUP going to sleep
  549. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/12550) State Change CS_HANGUP -> CS_REPORTING
  550. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/12550) Running State Change CS_REPORTING
  551. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/12550) State REPORTING
  552. 2016-10-07 12:28:49.507567 [ERR] mod_xml_radius.c:933 Result of true match: 10.23.154.64 == ^8\.8\.8\.8
  553. 2016-10-07 12:28:49.507567 [DEBUG] switch_ivr_bridge.c:1697 sofia/internal/12550 skip receive message [UNBRIDGE] (channel is hungup already)
  554. 2016-10-07 12:28:49.507567 [DEBUG] switch_ivr_bridge.c:1700 sofia/external/anonymous@anonymous.invalid skip receive message [UNBRIDGE] (channel is hungup already)
  555. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_session.c:2797 sofia/external/anonymous@anonymous.invalid skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  556. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:609 (sofia/external/anonymous@anonymous.invalid) State EXECUTE going to sleep
  557. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_HANGUP
  558. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:809 (sofia/external/anonymous@anonymous.invalid) Callstate Change ACTIVE -> HANGUP
  559. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:811 (sofia/external/anonymous@anonymous.invalid) State HANGUP
  560. 2016-10-07 12:28:49.507567 [DEBUG] mod_sofia.c:438 Channel sofia/external/anonymous@anonymous.invalid hanging up, cause: NORMAL_CLEARING
  561. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:60 sofia/external/anonymous@anonymous.invalid Standard HANGUP, cause: NORMAL_CLEARING
  562. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:811 (sofia/external/anonymous@anonymous.invalid) State HANGUP going to sleep
  563. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:578 (sofia/external/anonymous@anonymous.invalid) State Change CS_HANGUP -> CS_REPORTING
  564. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_REPORTING
  565. 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:897 (sofia/external/anonymous@anonymous.invalid) State REPORTING
  566. 2016-10-07 12:28:49.507567 [ERR] mod_xml_radius.c:933 Result of true match: 83.y.y.y == ^8\.8\.8\.8
  567. 2016-10-07 12:28:49.507567 [INFO] mod_xml_radius.c:1044 mod_xml_radius:  Accounting Stop success
  568. recv 420 bytes from udp/[10.23.154.64]:5060 at 12:28:49.518178:
  569.    ------------------------------------------------------------------------
  570.    SIP/2.0 200 OK
  571.    Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bKBDa3Nv6Qpap9H
  572.    From: "Unk" <sip:$(caller_id_number)@10.23.154.100>;tag=QgKrpvp4c2vgF
  573.    To: <sip:12550@10.23.154.64>;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289
  574.    Date: Fri, 07 Oct 2016 09:28:49 GMT
  575.    Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948
  576.    Server: Cisco-CP7942G/9.3.1
  577.    CSeq: 97580166 BYE
  578.    Content-Length: 0
  579.    
  580.    ------------------------------------------------------------------------
  581. 2016-10-07 12:28:49.507567 [INFO] mod_xml_radius.c:1044 mod_xml_radius:  Accounting Stop success
  582. 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:174 sofia/internal/12550 Standard REPORTING, cause: NORMAL_CLEARING
  583. 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/12550) State REPORTING going to sleep
  584. 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/12550) State Change CS_REPORTING -> CS_DESTROY
  585. 2016-10-07 12:28:49.567567 [DEBUG] switch_core_session.c:1647 Session 8707 (sofia/internal/12550) Locked, Waiting on external entities
  586. 2016-10-07 12:28:49.567567 [NOTICE] switch_core_session.c:1665 Session 8707 (sofia/internal/12550) Ended
  587. 2016-10-07 12:28:49.567567 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/12550 [CS_DESTROY]
  588. 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/12550) Running State Change CS_DESTROY
  589. 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/12550) State DESTROY
  590. 2016-10-07 12:28:49.567567 [DEBUG] mod_sofia.c:343 sofia/internal/12550 SOFIA DESTROY
  591. 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:181 sofia/internal/12550 Standard DESTROY
  592. 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/12550) State DESTROY going to sleep
  593. 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:174 sofia/external/anonymous@anonymous.invalid Standard REPORTING, cause: NORMAL_CLEARING
  594. 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:897 (sofia/external/anonymous@anonymous.invalid) State REPORTING going to sleep
  595. 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:569 (sofia/external/anonymous@anonymous.invalid) State Change CS_REPORTING -> CS_DESTROY
  596. 2016-10-07 12:28:49.587569 [DEBUG] switch_core_session.c:1647 Session 8706 (sofia/external/anonymous@anonymous.invalid) Locked, Waiting on external entities
  597. 2016-10-07 12:28:49.587569 [NOTICE] switch_core_session.c:1665 Session 8706 (sofia/external/anonymous@anonymous.invalid) Ended
  598. 2016-10-07 12:28:49.587569 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/anonymous@anonymous.invalid [CS_DESTROY]
  599. 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:700 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_DESTROY
  600. 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:710 (sofia/external/anonymous@anonymous.invalid) State DESTROY
  601. 2016-10-07 12:28:49.587569 [DEBUG] mod_sofia.c:343 sofia/external/anonymous@anonymous.invalid SOFIA DESTROY
  602. 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:181 sofia/external/anonymous@anonymous.invalid Standard DESTROY
  603. 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:710 (sofia/external/anonymous@anonymous.invalid) State DESTROY going to sleep
  604.