recv 1527 bytes from tcp/[172.17.2.3]:16112 at 12:27:37.672765: ------------------------------------------------------------------------ INVITE sip:12550@83.y.y.y SIP/2.0 From: "Unk" ;tag=80ac2f3d9c9ee61a3645724fb1600 To: Call-ID: 80ac2f3d9c9ee61a4645724fb1600 CSeq: 1 INVITE Max-Forwards: 71 Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600 Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600 Supported: 100rel,histinfo,join,replaces,sdp-anat,timer Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,INFO,PRACK,PUBLISH,UPDATE User-Agent: Avaya CM/R016x.03.0.124.0 Contact: "Unk" Route: Accept-Language: uk Alert-Info: ;avaya-cm-alert-type=internal History-Info: ;index=1 History-Info: "12550" ;index=1.1 Min-SE: 1200 P-Asserted-Identity: "Unk" Record-Route: Session-Expires: 1200;refresher=uac Privacy: id Av-Global-Session-ID: 80ac2f3d-9c9e-4601-a264-5724fb160000 Content-Type: application/sdp Content-Length: 262 v=0 o=- 1475832457 1 IN IP4 172.17.2.3 s=- c=IN IP4 172.17.2.4 b=AS:64 t=0 0 a=avf:avc=n prio=n a=csup:avf-v0 m=audio 3196 RTP/AVP 8 18 101 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=ptime:20 ------------------------------------------------------------------------ send 478 bytes to tcp/[172.17.2.3]:16112 at 12:27:37.678935: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600;rport=16112 Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600 Record-Route: From: "Unk" ;tag=80ac2f3d9c9ee61a3645724fb1600 To: Call-ID: 80ac2f3d9c9ee61a4645724fb1600 CSeq: 1 INVITE User-Agent: ES FS SBC Content-Length: 0 ------------------------------------------------------------------------ 2016-10-07 12:27:37.667575 [NOTICE] switch_channel.c:1104 New Channel sofia/external/anonymous@anonymous.invalid [6f9d5c3b-b5a6-4937-9770-e335fe97732a] 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_NEW 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 2016-10-07 12:27:37.667575 [DEBUG] sofia.c:6937 Channel sofia/external/anonymous@anonymous.invalid entering state [received][100] 2016-10-07 12:27:37.667575 [DEBUG] sofia.c:6947 Remote SDP: v=0 o=- 1475832457 1 IN IP4 172.17.2.3 s=- c=IN IP4 172.17.2.4 b=AS:64 t=0 0 a=avf:avc=n prio=n a=csup:avf-v0 m=audio 3196 RTP/AVP 8 18 101 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=ptime:20 2016-10-07 12:27:37.667575 [DEBUG] sofia.c:7315 (sofia/external/anonymous@anonymous.invalid) State Change CS_NEW -> CS_INIT 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:562 (sofia/external/anonymous@anonymous.invalid) State NEW 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_INIT 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:586 (sofia/external/anonymous@anonymous.invalid) State INIT 2016-10-07 12:27:37.667575 [DEBUG] mod_sofia.c:90 sofia/external/anonymous@anonymous.invalid SOFIA INIT 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:40 sofia/external/anonymous@anonymous.invalid Standard INIT 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 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:586 (sofia/external/anonymous@anonymous.invalid) State INIT going to sleep 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_ROUTING 2016-10-07 12:27:37.667575 [DEBUG] switch_channel.c:2249 (sofia/external/anonymous@anonymous.invalid) Callstate Change DOWN -> RINGING 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:602 (sofia/external/anonymous@anonymous.invalid) State ROUTING 2016-10-07 12:27:37.667575 [DEBUG] mod_sofia.c:143 sofia/external/anonymous@anonymous.invalid SOFIA ROUTING 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 2016-10-07 12:27:37.667575 [INFO] mod_xml_radius.c:986 mod_xml_radius: Accounting Start success 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:236 sofia/external/anonymous@anonymous.invalid Standard ROUTING 2016-10-07 12:27:37.667575 [INFO] mod_dialplan_xml.c:637 Processing Unk ->12550 in context public Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->common_variables] continue=true Dialplan: sofia/external/anonymous@anonymous.invalid Absolute Condition [common_variables] Dialplan: sofia/external/anonymous@anonymous.invalid Action set(default_language=en) Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->call_debug] continue=true Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->gateway_inbound] continue=false Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [gateway_inbound] ${target_context}() =~ /^[a-z]/ break=on-false Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->from_mts-tascom] continue=false Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [from_mts-tascom] network_addr(172.17.2.3) =~ /^82\.204\.25[45]/ break=on-false Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->from_partner] continue=false Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [from_partner] network_addr(172.17.2.3) =~ /^172\.17\.2\.3$/ break=on-false Dialplan: sofia/external/anonymous@anonymous.invalid Action set(effective_caller_id_number=$(caller_id_number)) Dialplan: sofia/external/anonymous@anonymous.invalid Action transfer(${destination_number} XML internal) 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 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:602 (sofia/external/anonymous@anonymous.invalid) State ROUTING going to sleep 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_EXECUTE 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:609 (sofia/external/anonymous@anonymous.invalid) State EXECUTE 2016-10-07 12:27:37.667575 [DEBUG] mod_sofia.c:198 sofia/external/anonymous@anonymous.invalid SOFIA EXECUTE 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:328 sofia/external/anonymous@anonymous.invalid Standard EXECUTE EXECUTE sofia/external/anonymous@anonymous.invalid set(default_language=en) 2016-10-07 12:27:37.667575 [DEBUG] mod_dptools.c:1519 SET sofia/external/anonymous@anonymous.invalid [default_language]=[en] EXECUTE sofia/external/anonymous@anonymous.invalid set(effective_caller_id_number=$(caller_id_number)) 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)] EXECUTE sofia/external/anonymous@anonymous.invalid transfer(12550 XML internal) 2016-10-07 12:27:37.667575 [DEBUG] switch_ivr.c:2160 (sofia/external/anonymous@anonymous.invalid) State Change CS_EXECUTE -> CS_ROUTING 2016-10-07 12:27:37.667575 [NOTICE] switch_ivr.c:2167 Transfer sofia/external/anonymous@anonymous.invalid to XML[12550@internal] 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:609 (sofia/external/anonymous@anonymous.invalid) State EXECUTE going to sleep 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_ROUTING 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:602 (sofia/external/anonymous@anonymous.invalid) State ROUTING 2016-10-07 12:27:37.667575 [DEBUG] mod_sofia.c:143 sofia/external/anonymous@anonymous.invalid SOFIA ROUTING 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 2016-10-07 12:27:37.667575 [INFO] mod_xml_radius.c:986 mod_xml_radius: Accounting Start success 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:236 sofia/external/anonymous@anonymous.invalid Standard ROUTING 2016-10-07 12:27:37.667575 [INFO] mod_dialplan_xml.c:637 Processing Unk ->12550 in context internal Dialplan: sofia/external/anonymous@anonymous.invalid parsing [internal->common_variables] continue=true Dialplan: sofia/external/anonymous@anonymous.invalid Absolute Condition [common_variables] Dialplan: sofia/external/anonymous@anonymous.invalid Action set(default_language=en) Dialplan: sofia/external/anonymous@anonymous.invalid parsing [internal->call_debug] continue=true Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/external/anonymous@anonymous.invalid parsing [internal->mts_tascom] continue=false Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [mts_tascom] destination_number(12550) =~ /^(17[0-5]\d\d)$/ break=on-false Dialplan: sofia/external/anonymous@anonymous.invalid parsing [internal->cucm] continue=false Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [cucm] destination_number(12550) =~ /^17[0-5]\d\d$/ break=on-true Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [cucm] destination_number(12550) =~ /^(?:1700#)?([1245678]\d{4}$|04\d{2,3}|555)$/ break=on-false Dialplan: sofia/external/anonymous@anonymous.invalid Action set(continue_on_fail=true) Dialplan: sofia/external/anonymous@anonymous.invalid Action set(hangup_after_bridge=true) Dialplan: sofia/external/anonymous@anonymous.invalid Action set(inherit_codec=true) Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/${distributor(cucm)}/12550) Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/${distributor(cucm)}/12550) Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/${distributor(cucm)}/12550) Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/${distributor(cucm)}/12550) Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/${distributor(cucm)}/12550) Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/${distributor(cucm)}/12550) Dialplan: sofia/external/anonymous@anonymous.invalid Action hangup(${originate_disposition}) 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 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:602 (sofia/external/anonymous@anonymous.invalid) State ROUTING going to sleep 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_EXECUTE 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:609 (sofia/external/anonymous@anonymous.invalid) State EXECUTE 2016-10-07 12:27:37.667575 [DEBUG] mod_sofia.c:198 sofia/external/anonymous@anonymous.invalid SOFIA EXECUTE 2016-10-07 12:27:37.667575 [DEBUG] switch_core_state_machine.c:328 sofia/external/anonymous@anonymous.invalid Standard EXECUTE EXECUTE sofia/external/anonymous@anonymous.invalid set(default_language=en) 2016-10-07 12:27:37.687565 [DEBUG] mod_dptools.c:1519 SET sofia/external/anonymous@anonymous.invalid [default_language]=[en] EXECUTE sofia/external/anonymous@anonymous.invalid set(continue_on_fail=true) 2016-10-07 12:27:37.687565 [DEBUG] mod_dptools.c:1519 SET sofia/external/anonymous@anonymous.invalid [continue_on_fail]=[true] EXECUTE sofia/external/anonymous@anonymous.invalid set(hangup_after_bridge=true) 2016-10-07 12:27:37.687565 [DEBUG] mod_dptools.c:1519 SET sofia/external/anonymous@anonymous.invalid [hangup_after_bridge]=[true] EXECUTE sofia/external/anonymous@anonymous.invalid set(inherit_codec=true) 2016-10-07 12:27:37.687565 [DEBUG] mod_dptools.c:1519 SET sofia/external/anonymous@anonymous.invalid [inherit_codec]=[true] EXECUTE sofia/external/anonymous@anonymous.invalid bridge(sofia/gateway/cucm-64/12550) 2016-10-07 12:27:37.687565 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables 2016-10-07 12:27:37.687565 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/12550 [5e5ddb8b-68c0-4979-a645-1647e79fe948] 2016-10-07 12:27:37.687565 [DEBUG] mod_sofia.c:4758 (sofia/internal/12550) State Change CS_NEW -> CS_INIT 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/12550) Running State Change CS_INIT 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/12550) State INIT 2016-10-07 12:27:37.687565 [DEBUG] mod_sofia.c:90 sofia/internal/12550 SOFIA INIT 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 Local SDP: v=0 o=FreeSWITCH 1475814555 1475814556 IN IP4 10.23.154.100 s=FreeSWITCH c=IN IP4 10.23.154.100 t=0 0 m=audio 17902 RTP/AVP 8 18 101 13 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 a=sendrecv send 1163 bytes to udp/[10.23.154.64]:5060 at 12:27:37.696131: ------------------------------------------------------------------------ INVITE sip:12550@10.23.154.64 SIP/2.0 Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bK8HyrgBmDZFKHF Max-Forwards: 69 From: "Unk" ;tag=QgKrpvp4c2vgF To: Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948 CSeq: 97580164 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20160707T165535Z~be13536ac9~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, PRACK, NOTIFY Supported: precondition, 100rel, timer, path, replaces Allow-Events: talk, hold, conference, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 268 X-FS-Support: update_display,send_info Remote-Party-ID: "Unk" ;party=calling;screen=yes;privacy=full v=0 o=FreeSWITCH 1475814555 1475814556 IN IP4 10.23.154.100 s=FreeSWITCH c=IN IP4 10.23.154.100 t=0 0 m=audio 17902 RTP/AVP 8 18 101 13 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 ------------------------------------------------------------------------ 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:40 sofia/internal/12550 Standard INIT 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/12550) State Change CS_INIT -> CS_ROUTING 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/12550) State INIT going to sleep 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/12550) Running State Change CS_ROUTING 2016-10-07 12:27:37.687565 [DEBUG] sofia.c:6937 Channel sofia/internal/12550 entering state [calling][0] 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/12550) State ROUTING 2016-10-07 12:27:37.687565 [DEBUG] mod_sofia.c:143 sofia/internal/12550 SOFIA ROUTING 2016-10-07 12:27:37.687565 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/12550) State Change CS_ROUTING -> CS_CONSUME_MEDIA 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 2016-10-07 12:27:37.687565 [INFO] mod_xml_radius.c:986 mod_xml_radius: Accounting Start success 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/12550) State ROUTING going to sleep 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/12550) Running State Change CS_CONSUME_MEDIA 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/12550) State CONSUME_MEDIA 2016-10-07 12:27:37.687565 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/12550) State CONSUME_MEDIA going to sleep recv 364 bytes from udp/[10.23.154.64]:5060 at 12:27:37.702123: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bK8HyrgBmDZFKHF From: "Unk" ;tag=QgKrpvp4c2vgF To: Date: Fri, 07 Oct 2016 09:27:37 GMT Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948 CSeq: 97580164 INVITE Allow-Events: presence Content-Length: 0 ------------------------------------------------------------------------ recv 765 bytes from udp/[10.23.154.64]:5060 at 12:27:37.707721: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bK8HyrgBmDZFKHF From: "Unk" ;tag=QgKrpvp4c2vgF To: ;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289 Date: Fri, 07 Oct 2016 09:27:37 GMT Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948 CSeq: 97580164 INVITE Require: 100rel RSeq: 2060524221 Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence Supported: X-cisco-srtp-fallback Supported: Geolocation P-Asserted-Identity: Remote-Party-ID: ;party=called;screen=yes;privacy=off Contact: Content-Length: 0 ------------------------------------------------------------------------ send 716 bytes to udp/[10.23.154.64]:5060 at 12:27:37.707850: ------------------------------------------------------------------------ PRACK sip:12550@10.23.154.64:5060 SIP/2.0 Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bK9tQHj64gvr93a Max-Forwards: 70 From: "Unk" ;tag=QgKrpvp4c2vgF To: ;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289 Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948 CSeq: 97580165 PRACK Contact: RAck: 2060524221 97580164 INVITE User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20160707T165535Z~be13536ac9~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, PRACK, NOTIFY Supported: precondition, 100rel, timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2016-10-07 12:27:37.707566 [DEBUG] sofia.c:6937 Channel sofia/internal/12550 entering state [proceeding][180] 2016-10-07 12:27:37.707566 [NOTICE] sofia.c:7045 Ring-Ready sofia/internal/12550! recv 393 bytes from udp/[10.23.154.64]:5060 at 12:27:37.708452: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bK9tQHj64gvr93a From: "Unk" ;tag=QgKrpvp4c2vgF To: ;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289 Date: Fri, 07 Oct 2016 09:27:37 GMT Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948 CSeq: 97580165 PRACK Content-Length: 0 ------------------------------------------------------------------------ 2016-10-07 12:27:37.707566 [DEBUG] switch_channel.c:3343 (sofia/internal/12550) Callstate Change DOWN -> RINGING send 795 bytes to tcp/[172.17.2.3]:16112 at 12:27:37.717309: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600;rport=16112 Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600 Record-Route: From: "Unk" ;tag=80ac2f3d9c9ee61a3645724fb1600 To: ;tag=18QFj7m70ae5D Call-ID: 80ac2f3d9c9ee61a4645724fb1600 CSeq: 1 INVITE Contact: User-Agent: ES FS SBC Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, NOTIFY Supported: timer, path, replaces Allow-Events: talk, hold, conference, refer Content-Length: 0 P-Asserted-Identity: "Outbound Call" ------------------------------------------------------------------------ 2016-10-07 12:27:37.707566 [NOTICE] mod_sofia.c:2221 Ring-Ready sofia/external/anonymous@anonymous.invalid! 2016-10-07 12:27:37.707566 [DEBUG] sofia.c:6937 Channel sofia/external/anonymous@anonymous.invalid entering state [early][180] 2016-10-07 12:27:37.707566 [NOTICE] switch_ivr_originate.c:525 Ring Ready sofia/external/anonymous@anonymous.invalid! recv 1104 bytes from udp/[10.23.154.64]:5060 at 12:27:44.650493: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bK8HyrgBmDZFKHF From: "Unk" ;tag=QgKrpvp4c2vgF To: ;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289 Date: Fri, 07 Oct 2016 09:27:37 GMT Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948 CSeq: 97580164 INVITE Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence, kpml Supported: replaces Server: Cisco-CP7942G/9.3.1 Supported: X-cisco-srtp-fallback Supported: Geolocation Session-Expires: 1800;refresher=uas Require: timer P-Asserted-Identity: Remote-Party-ID: ;party=called;screen=yes;privacy=off Contact: Content-Type: application/sdp Content-Length: 235 v=0 o=CiscoSystemsCCM-SIP 741728 1 IN IP4 10.23.154.64 s=SIP Call c=IN IP4 10.228.8.167 b=TIAS:64000 b=AS:64 t=0 0 m=audio 21250 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ 2016-10-07 12:27:44.647568 [DEBUG] sofia.c:6937 Channel sofia/internal/12550 entering state [completing][200] 2016-10-07 12:27:44.647568 [DEBUG] sofia.c:6947 Remote SDP: v=0 o=CiscoSystemsCCM-SIP 741728 1 IN IP4 10.23.154.64 s=SIP Call c=IN IP4 10.228.8.167 b=TIAS:64000 b=AS:64 t=0 0 m=audio 21250 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 send 464 bytes to udp/[10.23.154.64]:5060 at 12:27:44.651358: ------------------------------------------------------------------------ ACK sip:12550@10.23.154.64:5060 SIP/2.0 Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bKa4gam1NmS1Zpp Max-Forwards: 70 From: "Unk" ;tag=QgKrpvp4c2vgF To: ;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289 Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948 CSeq: 97580164 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2016-10-07 12:27:44.647568 [DEBUG] sofia.c:6937 Channel sofia/internal/12550 entering state [ready][200] 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] 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 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] 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4289 Set telephone-event payload to 101@8000 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 2016-10-07 12:27:44.647568 [DEBUG] switch_core_codec.c:111 sofia/internal/12550 Original read codec set to PCMA:8 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4642 Set telephone-event payload to 101@8000 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 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 2016-10-07 12:27:44.647568 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 160 bytes per 20ms 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:6807 sofia/internal/12550 Set 2833 dtmf send payload to 101 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:6814 sofia/internal/12550 Set 2833 dtmf receive payload to 101 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:6837 sofia/internal/12550 Set rtp dtmf delay to 40 2016-10-07 12:27:44.647568 [NOTICE] sofia.c:7969 Channel [sofia/internal/12550] has been answered 2016-10-07 12:27:44.647568 [DEBUG] switch_channel.c:3770 (sofia/internal/12550) Callstate Change RINGING -> ACTIVE 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 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] 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 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] 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4289 Set telephone-event payload to 101@8000 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 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 2016-10-07 12:27:44.647568 [DEBUG] switch_core_media.c:4642 Set telephone-event payload to 101@8000 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 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 2016-10-07 12:27:44.647568 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 160 bytes per 20ms 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 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 2016-10-07 12:27:44.667571 [DEBUG] switch_core_media.c:6837 sofia/external/anonymous@anonymous.invalid Set rtp dtmf delay to 40 2016-10-07 12:27:44.667571 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/anonymous@anonymous.invalid! 2016-10-07 12:27:44.667571 [DEBUG] switch_channel.c:3471 (sofia/external/anonymous@anonymous.invalid) Callstate Change RINGING -> EARLY 2016-10-07 12:27:44.667571 [DEBUG] mod_sofia.c:822 Local SDP sofia/external/anonymous@anonymous.invalid: v=0 o=FreeSWITCH 1475802060 1475802061 IN IP4 83.y.y.y s=FreeSWITCH c=IN IP4 83.y.y.y t=0 0 m=audio 30404 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv send 1105 bytes to tcp/[172.17.2.3]:16112 at 12:27:44.669363: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600;rport=16112 Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK80ac2f3d9c9ee61a5645724fb1600 Record-Route: From: "Unk" ;tag=80ac2f3d9c9ee61a3645724fb1600 To: ;tag=18QFj7m70ae5D Call-ID: 80ac2f3d9c9ee61a4645724fb1600 CSeq: 1 INVITE Contact: User-Agent: ES FS SBC Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, NOTIFY Require: timer Supported: timer, path, replaces Allow-Events: talk, hold, conference, refer Session-Expires: 1200;refresher=uac Content-Type: application/sdp Content-Disposition: session Content-Length: 224 P-Asserted-Identity: "Outbound Call" v=0 o=FreeSWITCH 1475802060 1475802061 IN IP4 83.y.y.y s=FreeSWITCH c=IN IP4 83.y.y.y t=0 0 m=audio 30404 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2016-10-07 12:27:44.667571 [DEBUG] sofia.c:6937 Channel sofia/external/anonymous@anonymous.invalid entering state [completed][200] 2016-10-07 12:27:44.667571 [NOTICE] switch_ivr_originate.c:3549 Channel [sofia/external/anonymous@anonymous.invalid] has been answered 2016-10-07 12:27:44.667571 [DEBUG] switch_channel.c:3770 (sofia/external/anonymous@anonymous.invalid) Callstate Change EARLY -> ACTIVE 2016-10-07 12:27:44.667571 [DEBUG] switch_ivr_originate.c:3607 Originate Resulted in Success: [sofia/internal/12550] 2016-10-07 12:27:44.667571 [DEBUG] switch_ivr_bridge.c:1599 (sofia/internal/12550) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2016-10-07 12:27:44.667571 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/12550) Running State Change CS_EXCHANGE_MEDIA 2016-10-07 12:27:44.667571 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/12550) State EXCHANGE_MEDIA 2016-10-07 12:27:44.667571 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA recv 560 bytes from tcp/[172.17.2.3]:16112 at 12:27:44.698085: ------------------------------------------------------------------------ ACK sip:12550@83.y.y.y:6060;transport=tcp SIP/2.0 From: "Unk" ;tag=80ac2f3d9c9ee61a3645724fb1600 To: ;tag=18QFj7m70ae5D Call-ID: 80ac2f3d9c9ee61a4645724fb1600 CSeq: 1 ACK Max-Forwards: 70 Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK060f4419c9ee61b0645724fb1600 Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK060f4419c9ee61b0645724fb1600 User-Agent: Avaya CM/R016x.03.0.124.0 Av-Global-Session-ID: 80ac2f3d-9c9e-4601-a264-5724fb160000 Content-Length: 0 ------------------------------------------------------------------------ 2016-10-07 12:27:44.687566 [DEBUG] sofia.c:6937 Channel sofia/external/anonymous@anonymous.invalid entering state [ready][200] 2016-10-07 12:27:44.747567 [DEBUG] switch_rtp.c:6711 Correct audio ip/port confirmed. 2016-10-07 12:27:44.787573 [DEBUG] switch_rtp.c:6711 Correct audio ip/port confirmed. recv 558 bytes from tcp/[172.17.2.3]:16112 at 12:28:49.504634: ------------------------------------------------------------------------ BYE sip:12550@83.y.y.y:6060;transport=tcp SIP/2.0 From: "Unk" ;tag=80ac2f3d9c9ee61a3645724fb1600 To: ;tag=18QFj7m70ae5D Call-ID: 80ac2f3d9c9ee61a4645724fb1600 CSeq: 2 BYE Max-Forwards: 70 Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK8001a689c9ee610655724fb1600 Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK8001a689c9ee610655724fb1600 User-Agent: Avaya CM/R016x.03.0.124.0 Av-Global-Session-ID: 80ac2f3d-9c9e-4601-a264-5724fb160000 Content-Length: 0 ------------------------------------------------------------------------ 2016-10-07 12:28:49.507567 [NOTICE] sofia.c:988 Hangup sofia/external/anonymous@anonymous.invalid [CS_EXECUTE] [NORMAL_CLEARING] send 538 bytes to tcp/[172.17.2.3]:16112 at 12:28:49.508267: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/TCP 172.17.2.3:6060;branch=z9hG4bK8001a689c9ee610655724fb1600;rport=16112 Via: SIP/2.0/TCP 10.0.194.249;branch=z9hG4bK8001a689c9ee610655724fb1600 From: "Unk" ;tag=80ac2f3d9c9ee61a3645724fb1600 To: ;tag=18QFj7m70ae5D Call-ID: 80ac2f3d9c9ee61a4645724fb1600 CSeq: 2 BYE User-Agent: ES FS SBC Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, NOTIFY Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2016-10-07 12:28:49.507567 [DEBUG] switch_ivr_bridge.c:785 BRIDGE THREAD DONE [sofia/external/anonymous@anonymous.invalid] 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 2016-10-07 12:28:49.507567 [DEBUG] switch_ivr_bridge.c:785 BRIDGE THREAD DONE [sofia/internal/12550] 2016-10-07 12:28:49.507567 [NOTICE] switch_ivr_bridge.c:889 Hangup sofia/internal/12550 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/12550) State EXCHANGE_MEDIA going to sleep 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/12550) Running State Change CS_HANGUP 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/12550) Callstate Change ACTIVE -> HANGUP 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/12550) State HANGUP 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 2016-10-07 12:28:49.507567 [DEBUG] mod_sofia.c:438 Channel sofia/internal/12550 hanging up, cause: NORMAL_CLEARING 2016-10-07 12:28:49.507567 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/12550 send 656 bytes to udp/[10.23.154.64]:5060 at 12:28:49.512669: ------------------------------------------------------------------------ BYE sip:12550@10.23.154.64:5060 SIP/2.0 Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bKBDa3Nv6Qpap9H Max-Forwards: 70 From: "Unk" ;tag=QgKrpvp4c2vgF To: ;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289 Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948 CSeq: 97580166 BYE User-Agent: FreeSWITCH-mod_sofia/1.7.0+git~20160707T165535Z~be13536ac9~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, PRACK, NOTIFY Supported: precondition, 100rel, timer, path, replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 ------------------------------------------------------------------------ 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:60 sofia/internal/12550 Standard HANGUP, cause: NORMAL_CLEARING 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/12550) State HANGUP going to sleep 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/12550) State Change CS_HANGUP -> CS_REPORTING 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/12550) Running State Change CS_REPORTING 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/12550) State REPORTING 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 2016-10-07 12:28:49.507567 [DEBUG] switch_ivr_bridge.c:1697 sofia/internal/12550 skip receive message [UNBRIDGE] (channel is hungup already) 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) 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) 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:609 (sofia/external/anonymous@anonymous.invalid) State EXECUTE going to sleep 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_HANGUP 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:809 (sofia/external/anonymous@anonymous.invalid) Callstate Change ACTIVE -> HANGUP 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:811 (sofia/external/anonymous@anonymous.invalid) State HANGUP 2016-10-07 12:28:49.507567 [DEBUG] mod_sofia.c:438 Channel sofia/external/anonymous@anonymous.invalid hanging up, cause: NORMAL_CLEARING 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:60 sofia/external/anonymous@anonymous.invalid Standard HANGUP, cause: NORMAL_CLEARING 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:811 (sofia/external/anonymous@anonymous.invalid) State HANGUP going to sleep 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 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:543 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_REPORTING 2016-10-07 12:28:49.507567 [DEBUG] switch_core_state_machine.c:897 (sofia/external/anonymous@anonymous.invalid) State REPORTING 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 2016-10-07 12:28:49.507567 [INFO] mod_xml_radius.c:1044 mod_xml_radius: Accounting Stop success recv 420 bytes from udp/[10.23.154.64]:5060 at 12:28:49.518178: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 10.23.154.100:6060;rport;branch=z9hG4bKBDa3Nv6Qpap9H From: "Unk" ;tag=QgKrpvp4c2vgF To: ;tag=741728~27154efa-6325-45a2-9e47-67e5d9302ebc-268288289 Date: Fri, 07 Oct 2016 09:28:49 GMT Call-ID: 5e5ddb8b-68c0-4979-a645-1647e79fe948 Server: Cisco-CP7942G/9.3.1 CSeq: 97580166 BYE Content-Length: 0 ------------------------------------------------------------------------ 2016-10-07 12:28:49.507567 [INFO] mod_xml_radius.c:1044 mod_xml_radius: Accounting Stop success 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:174 sofia/internal/12550 Standard REPORTING, cause: NORMAL_CLEARING 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/12550) State REPORTING going to sleep 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/12550) State Change CS_REPORTING -> CS_DESTROY 2016-10-07 12:28:49.567567 [DEBUG] switch_core_session.c:1647 Session 8707 (sofia/internal/12550) Locked, Waiting on external entities 2016-10-07 12:28:49.567567 [NOTICE] switch_core_session.c:1665 Session 8707 (sofia/internal/12550) Ended 2016-10-07 12:28:49.567567 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/12550 [CS_DESTROY] 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/12550) Running State Change CS_DESTROY 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/12550) State DESTROY 2016-10-07 12:28:49.567567 [DEBUG] mod_sofia.c:343 sofia/internal/12550 SOFIA DESTROY 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:181 sofia/internal/12550 Standard DESTROY 2016-10-07 12:28:49.567567 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/12550) State DESTROY going to sleep 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:174 sofia/external/anonymous@anonymous.invalid Standard REPORTING, cause: NORMAL_CLEARING 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:897 (sofia/external/anonymous@anonymous.invalid) State REPORTING going to sleep 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 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 2016-10-07 12:28:49.587569 [NOTICE] switch_core_session.c:1665 Session 8706 (sofia/external/anonymous@anonymous.invalid) Ended 2016-10-07 12:28:49.587569 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/anonymous@anonymous.invalid [CS_DESTROY] 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:700 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_DESTROY 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:710 (sofia/external/anonymous@anonymous.invalid) State DESTROY 2016-10-07 12:28:49.587569 [DEBUG] mod_sofia.c:343 sofia/external/anonymous@anonymous.invalid SOFIA DESTROY 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:181 sofia/external/anonymous@anonymous.invalid Standard DESTROY 2016-10-07 12:28:49.587569 [DEBUG] switch_core_state_machine.c:710 (sofia/external/anonymous@anonymous.invalid) State DESTROY going to sleep